[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