[Pidgin] #12289: DIGEST-MD5 isn't being attempted over BOSH (was: Server does not use any supported authentication method)
Pidgin
trac at pidgin.im
Thu Jul 8 03:01:29 EDT 2010
#12289: DIGEST-MD5 isn't being attempted over BOSH
-----------------------+----------------------------------------------------
Reporter: euixiwmqr | Owner: darkrain42
Type: defect | Status: new
Milestone: | Component: XMPP
Version: 2.7.1 | Resolution:
Keywords: |
-----------------------+----------------------------------------------------
Changes (by darkrain42):
* cc: darkrain42 (removed)
* owner: deryni => darkrain42
Comment:
With BOSH
{{{
(16:10:33) account: Connecting to account test at chat.d19.us/test.
(16:10:33) connection: Connecting. gc = 05155280
(16:10:33) dnsquery: Performing DNS lookup for chat.d19.us
(16:10:33) dnsquery: IP resolved for chat.d19.us
(16:10:33) proxy: Attempting connection to 184.106.199.163
(16:10:33) proxy: Connecting to chat.d19.us:5280 with no proxy
(16:10:33) proxy: Connection in progress
(16:10:33) proxy: Connecting to chat.d19.us:5280.
(16:10:33) proxy: Connected to chat.d19.us:5280.
(16:10:33) jabber: SendBOSH Boot (229): <body content='text/xml;
charset=utf-8' secure='true' to='chat.d19.us' xml:lang='en'
xmpp:version='1.0' ver='1.6' xmlns:xmpp='urn:xmpp:xbosh'
rid='1577657185499189' wait='60' hold='1'
xmlns='http://jabber.org/protocol/httpbind'/>
(16:10:33) jabber: bosh server closed the connection
(16:10:33) dnsquery: Performing DNS lookup for chat.d19.us
(16:10:33) dnsquery: Performing DNS lookup for chat.d19.us
(16:10:33) jabber: RecvBOSH (567): <body authid='97b64b1a-8f2a-
45a1-a979-30e33a687f91' xmpp:version='1.0'
xmlns:stream='http://etherx.jabber.org/streams'
xmlns:xmpp='urn:xmpp:xbosh' inactivity='60' requests='2' polling='5'
secure='true' hold='1' from='chat.d19.us' ver='1.6' sid='97b64b1a-8f2a-
45a1-a979-30e33a687f91' maxpause='120'
xmlns='http://jabber.org/protocol/httpbind'><stream:features><mechanisms
xmlns='urn:ietf:params:xml:ns:xmpp-sasl'><mechanism>SCRAM-
SHA-1</mechanism><mechanism>DIGEST-MD5</mechanism></mechanisms><auth
xmlns='http://jabber.org/features/iq-auth'/></stream:features></body>
(16:10:33) jabber: BOSH connection manager version 1.6
(16:10:33) sasl: Mechs found: SCRAM-SHA-1 DIGEST-MD5
(16:10:33) sasl: No worthy mechs found
(16:10:33) connection: Connection error on 05155280 (reason: 3
description: Server does not use any supported authentication method)
(16:10:33) jabber: BOSH: Sending an empty request
(16:10:33) account: Disconnecting account test at chat.d19.us/test (01BCF120)
(16:10:33) connection: Disconnecting connection 05155280
(16:10:33) jabber: Unable to find a ready BOSH connection. Ignoring send
of type 0x01.
(16:10:33) connection: Destroying connection 05155280
(16:10:33) dnsquery: IP resolved for chat.d19.us
(16:10:33) dnsquery: IP resolved for chat.d19.us
(16:10:34) util: Writing file prefs.xml to directory
C:\Users\Steve\AppData\Roaming\.purple
(16:10:34) util: Writing file
C:\Users\Steve\AppData\Roaming\.purple\prefs.xml
(16:10:39) util: Writing file accounts.xml to directory
C:\Users\Steve\AppData\Roaming\.purple
(16:10:39) util: Writing file
C:\Users\Steve\AppData\Roaming\.purple\accounts.xml
}}}
Without BOSH:
{{{
(16:23:31) account: Disconnecting account test at chat.d19.us/test (01BCF120)
(16:23:31) connection: Disconnecting connection 052A04D0
(16:23:31) connection: Deactivating keepalive.
(16:23:31) jabber: Sending (ssl) (test at chat.d19.us/test): </stream:stream>
(16:23:31) connection: Destroying connection 052A04D0
(16:23:32) account: Connecting to account test at chat.d19.us/test.
(16:23:32) connection: Connecting. gc = 052A04D0
(16:23:32) dnssrv: querying SRV record for chat.d19.us: _xmpp-
client._tcp.chat.d19.us
(16:23:32) dnssrv: Couldn't look up SRV record. DNS name does not exist.
(9003).
(16:23:32) dnsquery: Performing DNS lookup for chat.d19.us
(16:23:32) dnsquery: IP resolved for chat.d19.us
(16:23:32) proxy: Attempting connection to 184.106.199.163
(16:23:32) proxy: Connecting to chat.d19.us:5222 with no proxy
(16:23:32) proxy: Connection in progress
(16:23:32) proxy: Connecting to chat.d19.us:5222.
(16:23:32) proxy: Connected to chat.d19.us:5222.
(16:23:32) jabber: Sending (test at chat.d19.us/test): <?xml version='1.0' ?>
(16:23:32) jabber: Sending (test at chat.d19.us/test): <stream:stream
to='chat.d19.us' xmlns='jabber:client'
xmlns:stream='http://etherx.jabber.org/streams' version='1.0'>
(16:23:33) jabber: Recv (464): <?xml version='1.0'?><stream:stream
xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id
='15eaf2aa-42fd-474f-a021-2ba0355efd74' from='chat.d19.us' version='1.0'
xml:lang='en'><stream:features><mechanisms xmlns='urn:ietf:params:xml:ns
:xmpp-sasl'><mechanism>SCRAM-SHA-1</mechanism><mechanism>DIGEST-
MD5</mechanism></mechanisms><auth xmlns='http://jabber.org/features/iq-
auth'/><starttls xmlns='urn:ietf:params:xml:ns:xmpp-
tls'/></stream:features>
(16:23:33) jabber: Sending (test at chat.d19.us/test): <starttls
xmlns='urn:ietf:params:xml:ns:xmpp-tls'/>
(16:23:33) jabber: Recv (50): <proceed xmlns='urn:ietf:params:xml:ns:xmpp-
tls'/>
(16:23:33) nss: subject=CN=Example
certificate,OU="http://prosody.im/doc/advanced_ssl_tls#certificates",O=Prosody
IM,ST=Jabberland,C=GB issuer=CN=Example
certificate,OU="http://prosody.im/doc/advanced_ssl_tls#certificates",O=Prosody
IM,ST=Jabberland,C=GB
(16:23:33) certificate/x509/tls_cached: Starting verify for chat.d19.us
(16:23:33) certificate/x509/tls_cached: Checking for cached cert...
(16:23:33) certificate/x509/tls_cached: ...Found cached cert
(16:23:33) nss/x509: Loading certificate from
C:\Users\Steve\AppData\Roaming\.purple\certificates\x509\tls_peers\chat.d19.us
(16:23:33) certificate/x509/tls_cached: Peer cert matched cached
(16:23:33) nss/x509: Exporting certificate to
C:\Users\Steve\AppData\Roaming\.purple\certificates\x509\tls_peers\chat.d19.us
(16:23:33) util: Writing file
C:\Users\Steve\AppData\Roaming\.purple\certificates\x509\tls_peers\chat.d19.us
(16:23:33) certificate: Successfully verified certificate for chat.d19.us
(16:23:33) jabber: Sending (ssl) (test at chat.d19.us/test): <stream:stream
to='chat.d19.us' xmlns='jabber:client'
xmlns:stream='http://etherx.jabber.org/streams' version='1.0'>
(16:23:34) jabber: Recv (ssl)(441): <?xml version='1.0'?><stream:stream
xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id
='62f729ea-9ab3-4dda-bbe4-14bea87c0f63' from='chat.d19.us' version='1.0'
xml:lang='en'><stream:features><mechanisms xmlns='urn:ietf:params:xml:ns
:xmpp-sasl'><mechanism>PLAIN</mechanism><mechanism>SCRAM-
SHA-1</mechanism><mechanism>DIGEST-MD5</mechanism></mechanisms><auth
xmlns='http://jabber.org/features/iq-auth'/></stream:features>
(16:23:34) sasl: Mechs found: PLAIN SCRAM-SHA-1 DIGEST-MD5
(16:23:34) sasl: No worthy mechs found
(16:23:36) sasl: Mechs found: PLAIN SCRAM-SHA-1 DIGEST-MD5
(16:23:36) sasl: DIGEST-MD5 client step 1
(16:23:36) jabber: Sending (ssl) (test at chat.d19.us/test): <auth
xmlns='urn:ietf:params:xml:ns:xmpp-sasl' mechanism='DIGEST-MD5'>password
removed</auth>
(16:23:36) jabber: Recv (ssl)(208): <challenge
xmlns='urn:ietf:params:xml:ns:xmpp-
sasl'>cmVhbG09ImNoYXQuZDE5LnVzIixub25jZT0iODVkMzY5OTMtYzNhYy00NmQ5LWJiMzMtOGIwOWExODUyZjYyIixxb3A9ImF1dGgiLGNoYXJzZXQ9dXRmLTgsYWxnb3JpdGhtPW1kNS1zZXNz</challenge>
(16:23:36) sasl: DIGEST-MD5 client step 2
(16:23:36) jabber: Sending (ssl) (test at chat.d19.us/test): <response
xmlns='urn:ietf:params:xml:ns:xmpp-
sasl'>dXNlcm5hbWU9InRlc3QiLHJlYWxtPSJjaGF0LmQxOS51cyIsbm9uY2U9Ijg1ZDM2OTkzLWMzYWMtNDZkOS1iYjMzLThiMDlhMTg1MmY2MiIsY25vbmNlPSJBWFVqSWt0WWExdytHbDg3T25sZkRVd3hYazFKTGhNY05tWkNmbUFzRlZRPSIsbmM9MDAwMDAwMDEscW9wPWF1dGgsZGlnZXN0LXVyaT0ieG1wcC9jaGF0LmQxOS51cyIscmVzcG9uc2U9ZGJmYmNkN2IzNDcwNjhhYzU0YmI2ZTRhOWEwMzE0NGIsY2hhcnNldD11dGYtOA==</response>
(16:23:36) network: found network 'localdomain'
(16:23:36) network: Received Network Change Notification. Current network
count is 1, previous count was 1.
(16:23:36) util: Writing file accounts.xml to directory
C:\Users\Steve\AppData\Roaming\.purple
(16:23:37) util: Writing file
C:\Users\Steve\AppData\Roaming\.purple\accounts.xml
(16:23:37) jabber: Recv (ssl)(120): <challenge
xmlns='urn:ietf:params:xml:ns:xmpp-
sasl'>cnNwYXV0aD0yM2FmYjNlYmJlZmM0OWI4MjEwOTI3MDAzNmUyZTUwZQ==</challenge>
(16:23:37) sasl: DIGEST-MD5 client step 3
(16:23:37) jabber: Sending (ssl) (test at chat.d19.us/test): <response
xmlns='urn:ietf:params:xml:ns:xmpp-sasl'/>
(16:23:37) jabber: Recv (ssl)(60): <success xmlns='urn:ietf:params:xml:ns
:xmpp-sasl'></success>
(16:23:37) jabber: Sending (ssl) (test at chat.d19.us/test): <stream:stream
to='chat.d19.us' xmlns='jabber:client'
xmlns:stream='http://etherx.jabber.org/streams' version='1.0'>
(16:23:37) jabber: Recv (ssl)(427): <?xml version='1.0'?><stream:stream
xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams'
id='1d66bfee-5376-4778-8239-076cd406f57e' from='chat.d19.us' version='1.0'
xml:lang='en'><stream:features><ver
xmlns='urn:xmpp:features:rosterver'><optional/></ver><bind
xmlns='urn:ietf:params:xml:ns:xmpp-bind'><required/></bind><session
xmlns='urn:ietf:params:xml:ns:xmpp-
session'><optional/></session></stream:features>
(16:23:37) jabber: Sending (ssl) (test at chat.d19.us/test): <iq type='set'
id='purpleec223b1e'><bind xmlns='urn:ietf:params:xml:ns:xmpp-
bind'><resource>test</resource></bind></iq>
(16:23:37) jabber: Recv (ssl)(129): <iq id='purpleec223b1e'
type='result'><bind xmlns='urn:ietf:params:xml:ns:xmpp-
bind'><jid>test at chat.d19.us/test</jid></bind></iq>
(16:23:37) jabber: Sending (ssl) (test at chat.d19.us/test): <iq type='set'
id='purpleec223b1f'><session xmlns='urn:ietf:params:xml:ns:xmpp-
session'/></iq>
(16:23:38) jabber: Recv (ssl)(66): <iq id='purpleec223b1f' type='result'
to='test at chat.d19.us/test'/>
(16:23:38) jabber: Sending (ssl) (test at chat.d19.us/test): <iq type='get'
id='purpleec223b20' to='chat.d19.us'><query
xmlns='http://jabber.org/protocol/disco#items'/></iq>
(16:23:38) jabber: Sending (ssl) (test at chat.d19.us/test): <iq type='get'
id='purpleec223b21' to='chat.d19.us'><query
xmlns='http://jabber.org/protocol/disco#info'/></iq>
(16:23:38) jabber: Recv (ssl)(144): <iq id='purpleec223b20' type='result'
to='test at chat.d19.us/test' from='chat.d19.us'><query
xmlns='http://jabber.org/protocol/disco#items'/></iq>
(16:23:38) jabber: Recv (ssl)(769): <iq id='purpleec223b21' type='result'
to='test at chat.d19.us/test' from='chat.d19.us'><query
xmlns='http://jabber.org/protocol/disco#info'><identity type='im'
name='Prosody' category='server'/><identity type='pep' name='Prosody'
category='pubsub'/><feature var='jabber:iq:register'/><feature var='vcard-
temp'/><feature var='jabber:iq:roster'/><feature
var='urn:xmpp:time'/><feature var='jabber:iq:time'/><feature
var='jabber:iq:private'/><feature var='urn:xmpp:ping'/><feature
var='http://jabber.org/protocol/disco#info'/><feature
var='http://jabber.org/protocol/disco#items'/><feature
var='msgoffline'/><feature var='jabber:iq:version'/><feature
var='jabber:iq:auth'/><feature
var='http://jabber.org/protocol/pubsub#publish'/><feature
var='jabber:iq:last'/></query></iq>
(16:23:38) dnssrv: querying SRV record for chat.d19.us:
_stun._udp.chat.d19.us
(16:23:38) jabber: Sending (ssl) (test at chat.d19.us/test): <iq type='get'
id='purpleec223b22'><vCard xmlns='vcard-temp'/></iq>
(16:23:38) jabber: Sending (ssl) (test at chat.d19.us/test): <iq type='get'
id='purpleec223b23'><query xmlns='jabber:iq:roster'/></iq>
(16:23:38) jabber: Sending (ssl) (test at chat.d19.us/test): <iq type='get'
id='purpleec223b24' to='proxy.eu.jabber.org'><query
xmlns='http://jabber.org/protocol/bytestreams'/></iq>
(16:23:38) dnssrv: Couldn't look up SRV record. DNS name does not exist.
(9003).
(16:23:38) jabber: got 0 SRV responses for STUN.
(16:23:38) jabber: Recv (ssl)(159): <iq id='purpleec223b22' type='error'
to='test at chat.d19.us/test'><error type='cancel'><item-not-found
xmlns='urn:ietf:params:xml:ns:xmpp-stanzas'/></error></iq>
(16:23:38) jabber: Server returned error while retrieving vCard
(16:23:38) jabber: Recv (ssl)(111): <iq id='purpleec223b23' type='result'
to='test at chat.d19.us/test'><query ver='1' xmlns='jabber:iq:roster'/></iq>
(16:23:38) jabber: Sending (ssl) (test at chat.d19.us/test):
<presence><status>test</status><priority>1</priority><c
xmlns='http://jabber.org/protocol/caps' node='http://pidgin.im/'
hash='sha-1' ver='I22W7CegORwdbnu0ZiQwGpxr0Go='/><x xmlns='vcard-
temp:x:update'><photo/></x></presence>
(16:23:38) jabber: Sending (ssl) (test at chat.d19.us/test): <iq type='set'
id='purpleec223b25'><pubsub
xmlns='http://jabber.org/protocol/pubsub'><publish
node='http://jabber.org/protocol/tune'><item><tune
xmlns='http://jabber.org/protocol/tune'/></item></publish></pubsub></iq>
(16:23:38) jabber: jabber_actions: have pep: YES
(16:23:38) connection: Activating keepalive.
(16:23:39) jabber: Recv (ssl)(232): <iq id='purpleec223b24' type='result'
to='test at chat.d19.us/test' from='proxy.eu.jabber.org'><query
xmlns='http://jabber.org/protocol/bytestreams'><streamhost
jid='proxy.eu.jabber.org' port='7777' host='91.121.109.155'/></query></iq>
(16:23:39) jabber: Discovered bytestream proxy server:
jid='proxy.eu.jabber.org' host='91.121.109.155' port='7777' zeroconf=''
(16:23:39) jabber: Recv (ssl)(253): <presence
from='test at chat.d19.us/test'><status>test</status><priority>1</priority><c
hash='sha-1' ver='I22W7CegORwdbnu0ZiQwGpxr0Go=' node='http://pidgin.im/'
xmlns='http://jabber.org/protocol/caps'/><x xmlns='vcard-
temp:x:update'><photo/></x></presence>
(16:23:39) jabber: Recv (ssl)(329): <iq id='purpleec223b25' type='result'
to='test at chat.d19.us/test'/><message type='headline'
to='test at chat.d19.us/test' from='test at chat.d19.us'><event
xmlns='http://jabber.org/protocol/pubsub#event'><items
node='http://jabber.org/protocol/tune'><item><tune
xmlns='http://jabber.org/protocol/tune'/></item></items></event></message>
(16:23:39) jabber: Unhandled IQ with id purpleec223b25
}}}
--
Ticket URL: <http://developer.pidgin.im/ticket/12289#comment:1>
Pidgin <http://pidgin.im>
Pidgin
More information about the Tracker
mailing list