[Pidgin] #6514: Recv (ssl)(66): <stream:error>Connection is closing</stream:error></stream:stream>

Pidgin trac at pidgin.im
Wed Aug 6 10:57:28 EDT 2008


#6514: Recv (ssl)(66): <stream:error>Connection is
closing</stream:error></stream:stream>
--------------------------+-------------------------------------------------
  Reporter:  AndrewTrick  |       Owner:  deryni      
      Type:  defect       |      Status:  new         
  Priority:  minor        |   Milestone:              
 Component:  XMPP         |     Version:  2.4.3       
Resolution:               |    Keywords:  stream error
   Pending:  1            |  
--------------------------+-------------------------------------------------
Changes (by deryni):

  * pending:  0 => 1

Old description:

> Connection always drops 7 seconds after connecting, then reconnects again
> 5 seconds later, and continues connecting and reconnecting indefinitely.
> The same jabber account/server had no problems before I upgraded to
> fedora 9, pidgin 2.4.3-1.fc9.
>
> protocol: XMPP
> Force old port 5223 SSL: YES
> Connect port: 5223
> Proxy: Gnome proxy setting (seems to have the same problem with no proxy)
>
> Sorry I don't know how to easily get a backtrace for something that
> doesn't crash.
> Here's a big chunk of the debug log:
>
> (16:18:40) jabber: Sending (ssl): <iq type='get' id='purplec950d17b'
> to='...'><query xmlns='http://jabber.org/protocol/disco#info'
> node='http://pidgin.im/caps#2.4.3'/></iq>
> (16:18:40) blist: Updating buddy status for ... (XMPP)
> (16:18:40) nautilus: saved blist online
> (16:18:40) jabber: Recv (ssl)(210): <presence from='.../JM_2.7.6.6'
> to='andrew.trick at jabber.hp.com'><x from='... at jabber.hp.com/JM_2.7.6.6'
> stamp='20080805T23:11:02' xmlns='jabber:x:delay'/></presence>
> (16:18:40) blist: Updating buddy status for ... at jabber.hp.com (XMPP)
> (16:18:40) nautilus: saved blist online
> (16:18:40) jabber: Recv (ssl)(208): <presence
> from='... at jabber.hp.com/JabberMessenger'
> to='andrew.trick at jabber.hp.com'><x
> from='... at jabber.hp.com/JabberMessenger' stamp='20080805T12:26:57'
> xmlns='jabber:x:delay'/></presence>
> (16:18:40) blist: Updating buddy status for ... at jabber.hp.com (XMPP)
> (16:18:40) nautilus: saved blist online
> (16:18:40) jabber: Recv (ssl)(198): <presence
> from='... at jabber.hp.com/JM_2.7.6.6' to='andrew.trick at jabber.hp.com'><x
> from='... at jabber.hp.com/JM_2.7.6.6' stamp='20080805T23:10:32'
> xmlns='jabber:x:delay'/></presence>
> (16:18:40) blist: Updating buddy status for ... at jabber.hp.com (XMPP)
> (16:18:40) nautilus: saved blist online
> (16:18:40) jabber: Recv (ssl)(210): <presence
> from='... at jabber.hp.com/JabberMessenger'
> to='andrew.trick at jabber.hp.com'><x
> from='... at jabber.hp.com/JabberMessenger' stamp='20080805T20:56:26'
> xmlns='jabber:x:delay'/></presence>
> (16:18:41) util: Writing file xmpp-caps.xml to directory
> /home/atrick/.purple
> (16:18:41) util: Writing file /home/atrick/.purple/xmpp-caps.xml
> (16:18:41) jabber: Sending (ssl): <iq type='get' id='purplec950d17c'
> to='... at jabber.hp.com/Pidgin'><query
> xmlns='http://jabber.org/protocol/disco#info'
> node='http://pidgin.im/caps#moodn'/></iq>
> (16:18:41) jabber: Sending (ssl): <iq type='get' id='purplec950d17d'
> to='... at jabber.hp.com/Pidgin'><query
> xmlns='http://jabber.org/protocol/disco#info'
> node='http://pidgin.im/caps#nickn'/></iq>
> (16:18:41) jabber: Sending (ssl): <iq type='get' id='purplec950d17e'
> to='... at jabber.hp.com/Pidgin'><query
> xmlns='http://jabber.org/protocol/disco#info'
> node='http://pidgin.im/caps#tunen'/></iq>
> (16:18:41) jabber: Sending (ssl): <iq type='get' id='purplec950d17f'
> to='... at jabber.hp.com/Pidgin'><query
> xmlns='http://jabber.org/protocol/disco#info'
> node='http://pidgin.im/caps#avatar'/></iq>
> (16:18:41) jabber: Recv (ssl)(273): <iq from='... at jabber.hp.com/Pidgin'
> id='purplec950d17c' to='andrew.trick at jabber.hp.com/Gaim'
> type='result'><query node='http://pidgin.im/caps#moodn'
> xmlns='http://jabber.org/protocol/disco#info'><feature
> var='http://jabber.org/protocol/mood+notify'/></query></iq>
> (16:18:41) util: Writing file xmpp-caps.xml to directory
> /home/atrick/.purple
> (16:18:41) util: Writing file /home/atrick/.purple/xmpp-caps.xml
> (16:18:41) jabber: Recv (ssl)(273): <iq from='... at jabber.hp.com/Pidgin'
> id='purplec950d17d' to='andrew.trick at jabber.hp.com/Gaim'
> type='result'><query node='http://pidgin.im/caps#nickn'
> xmlns='http://jabber.org/protocol/disco#info'><feature
> var='http://jabber.org/protocol/nick+notify'/></query></iq>
> (16:18:41) util: Writing file xmpp-caps.xml to directory
> /home/atrick/.purple
> (16:18:41) util: Writing file /home/atrick/.purple/xmpp-caps.xml
> (16:18:41) jabber: Recv (ssl)(273): <iq from='... at jabber.hp.com/Pidgin'
> id='purplec950d17e' to='andrew.trick at jabber.hp.com/Gaim'
> type='result'><query node='http://pidgin.im/caps#tunen'
> xmlns='http://jabber.org/protocol/disco#info'><feature
> var='http://jabber.org/protocol/tune+notify'/></query></iq>
> (16:18:41) util: Writing file xmpp-caps.xml to directory
> /home/atrick/.purple
> (16:18:41) util: Writing file /home/atrick/.purple/xmpp-caps.xml
> (16:18:41) util: Writing file xmpp-caps.xml to directory
> /home/atrick/.purple
> (16:18:41) util: Writing file /home/atrick/.purple/xmpp-caps.xml
> (16:18:44) util: Writing file prefs.xml to directory /home/atrick/.purple
> (16:18:44) util: Writing file /home/atrick/.purple/prefs.xml
> (16:18:44) util: Writing file accounts.xml to directory
> /home/atrick/.purple
> (16:18:44) util: Writing file /home/atrick/.purple/accounts.xml
> (16:18:44) util: Writing file blist.xml to directory /home/atrick/.purple
> (16:18:44) util: Writing file /home/atrick/.purple/blist.xml
> (16:19:13) jabber: Recv (ssl)(66): <stream:error>Connection is
> closing</stream:error></stream:stream>
> (16:19:13) account: Disconnecting account 0x8face58
> (16:19:13) connection: Disconnecting connection 0x95bd960
> (16:19:13) connection: Deactivating keepalive.
> (16:19:13) connection: Destroying connection 0x95bd960
> (16:19:18) util: Writing file accounts.xml to directory
> /home/atrick/.purple
> (16:19:18) util: Writing file /home/atrick/.purple/accounts.xml
> (16:19:18) util: Writing file blist.xml to directory /home/atrick/.purple
> (16:19:18) util: Writing file /home/atrick/.purple/blist.xml
> (16:19:49) autorecon: do_signon called
> (16:19:49) autorecon: calling purple_account_connect
> (16:19:49) account: Connecting to account andrew.trick at jabber.hp.com/Gaim
> (16:19:49) connection: Connecting. gc = 0x965a670
> (16:19:49) dns: DNS query for 'jabber.hp.com' queued
> (16:19:49) autorecon: done calling purple_account_connect
> (16:19:49) dns: Created new DNS child 9704, there are now 1 children.
> (16:19:49) dns: Successfully sent DNS request to child 9704
> (16:19:49) dns: Got response for 'jabber.hp.com'
> (16:19:49) dnsquery: IP resolved for jabber.hp.com
> (16:19:49) proxy: Attempting connection to 16.236.16.79
> (16:19:49) proxy: Connecting to jabber.hp.com:5223 with no proxy
> (16:19:49) proxy: Connection in progress
> (16:19:49) proxy: Connected to jabber.hp.com:5223.
> (16:19:50) jabber: Sending (ssl): <?xml version='1.0' ?>
> (16:19:50) jabber: Sending (ssl): <stream:stream to='jabber.hp.com'
> xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams'
> version='1.0'>
> (16:19:50) jabber: Recv (ssl)(126): <stream:stream from='jabber.hp.com'
> xmlns='jabber:client' id='1FFF282D03E46D'
> xmlns:stream='http://etherx.jabber.org/streams'>....

New description:

 Connection always drops 7 seconds after connecting, then reconnects again
 5 seconds later, and continues connecting and reconnecting indefinitely.
 The same jabber account/server had no problems before I upgraded to fedora
 9, pidgin 2.4.3-1.fc9.

 protocol: XMPP
 Force old port 5223 SSL: YES
 Connect port: 5223
 Proxy: Gnome proxy setting (seems to have the same problem with no proxy)

 Sorry I don't know how to easily get a backtrace for something that
 doesn't crash.
 Here's a big chunk of the debug log:

 {{{
 (16:18:40) jabber: Sending (ssl): <iq type='get' id='purplec950d17b'
 to='...'><query xmlns='http://jabber.org/protocol/disco#info'
 node='http://pidgin.im/caps#2.4.3'/></iq>
 (16:18:40) blist: Updating buddy status for ... (XMPP)
 (16:18:40) nautilus: saved blist online
 (16:18:40) jabber: Recv (ssl)(210): <presence from='.../JM_2.7.6.6'
 to='andrew.trick at jabber.hp.com'><x from='... at jabber.hp.com/JM_2.7.6.6'
 stamp='20080805T23:11:02' xmlns='jabber:x:delay'/></presence>
 (16:18:40) blist: Updating buddy status for ... at jabber.hp.com (XMPP)
 (16:18:40) nautilus: saved blist online
 (16:18:40) jabber: Recv (ssl)(208): <presence
 from='... at jabber.hp.com/JabberMessenger'
 to='andrew.trick at jabber.hp.com'><x
 from='... at jabber.hp.com/JabberMessenger' stamp='20080805T12:26:57'
 xmlns='jabber:x:delay'/></presence>
 (16:18:40) blist: Updating buddy status for ... at jabber.hp.com (XMPP)
 (16:18:40) nautilus: saved blist online
 (16:18:40) jabber: Recv (ssl)(198): <presence
 from='... at jabber.hp.com/JM_2.7.6.6' to='andrew.trick at jabber.hp.com'><x
 from='... at jabber.hp.com/JM_2.7.6.6' stamp='20080805T23:10:32'
 xmlns='jabber:x:delay'/></presence>
 (16:18:40) blist: Updating buddy status for ... at jabber.hp.com (XMPP)
 (16:18:40) nautilus: saved blist online
 (16:18:40) jabber: Recv (ssl)(210): <presence
 from='... at jabber.hp.com/JabberMessenger'
 to='andrew.trick at jabber.hp.com'><x
 from='... at jabber.hp.com/JabberMessenger' stamp='20080805T20:56:26'
 xmlns='jabber:x:delay'/></presence>
 (16:18:41) util: Writing file xmpp-caps.xml to directory
 /home/atrick/.purple
 (16:18:41) util: Writing file /home/atrick/.purple/xmpp-caps.xml
 (16:18:41) jabber: Sending (ssl): <iq type='get' id='purplec950d17c'
 to='... at jabber.hp.com/Pidgin'><query
 xmlns='http://jabber.org/protocol/disco#info'
 node='http://pidgin.im/caps#moodn'/></iq>
 (16:18:41) jabber: Sending (ssl): <iq type='get' id='purplec950d17d'
 to='... at jabber.hp.com/Pidgin'><query
 xmlns='http://jabber.org/protocol/disco#info'
 node='http://pidgin.im/caps#nickn'/></iq>
 (16:18:41) jabber: Sending (ssl): <iq type='get' id='purplec950d17e'
 to='... at jabber.hp.com/Pidgin'><query
 xmlns='http://jabber.org/protocol/disco#info'
 node='http://pidgin.im/caps#tunen'/></iq>
 (16:18:41) jabber: Sending (ssl): <iq type='get' id='purplec950d17f'
 to='... at jabber.hp.com/Pidgin'><query
 xmlns='http://jabber.org/protocol/disco#info'
 node='http://pidgin.im/caps#avatar'/></iq>
 (16:18:41) jabber: Recv (ssl)(273): <iq from='... at jabber.hp.com/Pidgin'
 id='purplec950d17c' to='andrew.trick at jabber.hp.com/Gaim'
 type='result'><query node='http://pidgin.im/caps#moodn'
 xmlns='http://jabber.org/protocol/disco#info'><feature
 var='http://jabber.org/protocol/mood+notify'/></query></iq>
 (16:18:41) util: Writing file xmpp-caps.xml to directory
 /home/atrick/.purple
 (16:18:41) util: Writing file /home/atrick/.purple/xmpp-caps.xml
 (16:18:41) jabber: Recv (ssl)(273): <iq from='... at jabber.hp.com/Pidgin'
 id='purplec950d17d' to='andrew.trick at jabber.hp.com/Gaim'
 type='result'><query node='http://pidgin.im/caps#nickn'
 xmlns='http://jabber.org/protocol/disco#info'><feature
 var='http://jabber.org/protocol/nick+notify'/></query></iq>
 (16:18:41) util: Writing file xmpp-caps.xml to directory
 /home/atrick/.purple
 (16:18:41) util: Writing file /home/atrick/.purple/xmpp-caps.xml
 (16:18:41) jabber: Recv (ssl)(273): <iq from='... at jabber.hp.com/Pidgin'
 id='purplec950d17e' to='andrew.trick at jabber.hp.com/Gaim'
 type='result'><query node='http://pidgin.im/caps#tunen'
 xmlns='http://jabber.org/protocol/disco#info'><feature
 var='http://jabber.org/protocol/tune+notify'/></query></iq>
 (16:18:41) util: Writing file xmpp-caps.xml to directory
 /home/atrick/.purple
 (16:18:41) util: Writing file /home/atrick/.purple/xmpp-caps.xml
 (16:18:41) util: Writing file xmpp-caps.xml to directory
 /home/atrick/.purple
 (16:18:41) util: Writing file /home/atrick/.purple/xmpp-caps.xml
 (16:18:44) util: Writing file prefs.xml to directory /home/atrick/.purple
 (16:18:44) util: Writing file /home/atrick/.purple/prefs.xml
 (16:18:44) util: Writing file accounts.xml to directory
 /home/atrick/.purple
 (16:18:44) util: Writing file /home/atrick/.purple/accounts.xml
 (16:18:44) util: Writing file blist.xml to directory /home/atrick/.purple
 (16:18:44) util: Writing file /home/atrick/.purple/blist.xml
 (16:19:13) jabber: Recv (ssl)(66): <stream:error>Connection is
 closing</stream:error></stream:stream>
 (16:19:13) account: Disconnecting account 0x8face58
 (16:19:13) connection: Disconnecting connection 0x95bd960
 (16:19:13) connection: Deactivating keepalive.
 (16:19:13) connection: Destroying connection 0x95bd960
 (16:19:18) util: Writing file accounts.xml to directory
 /home/atrick/.purple
 (16:19:18) util: Writing file /home/atrick/.purple/accounts.xml
 (16:19:18) util: Writing file blist.xml to directory /home/atrick/.purple
 (16:19:18) util: Writing file /home/atrick/.purple/blist.xml
 (16:19:49) autorecon: do_signon called
 (16:19:49) autorecon: calling purple_account_connect
 (16:19:49) account: Connecting to account andrew.trick at jabber.hp.com/Gaim
 (16:19:49) connection: Connecting. gc = 0x965a670
 (16:19:49) dns: DNS query for 'jabber.hp.com' queued
 (16:19:49) autorecon: done calling purple_account_connect
 (16:19:49) dns: Created new DNS child 9704, there are now 1 children.
 (16:19:49) dns: Successfully sent DNS request to child 9704
 (16:19:49) dns: Got response for 'jabber.hp.com'
 (16:19:49) dnsquery: IP resolved for jabber.hp.com
 (16:19:49) proxy: Attempting connection to 16.236.16.79
 (16:19:49) proxy: Connecting to jabber.hp.com:5223 with no proxy
 (16:19:49) proxy: Connection in progress
 (16:19:49) proxy: Connected to jabber.hp.com:5223.
 (16:19:50) jabber: Sending (ssl): <?xml version='1.0' ?>
 (16:19:50) jabber: Sending (ssl): <stream:stream to='jabber.hp.com'
 xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams'
 version='1.0'>
 (16:19:50) jabber: Recv (ssl)(126): <stream:stream from='jabber.hp.com'
 xmlns='jabber:client' id='1FFF282D03E46D'
 xmlns:stream='http://etherx.jabber.org/streams'>....
 }}}

Comment:

 There is unfortunately not much that can be gained from that debug log
 other than the fact that your server is forcibly terminating your
 connection. It is literally sending us a closing stream stanza (and then
 likely closing the connection).

 You can try asking your server administrators to look in the server logs
 and see if anything shows up as a reason for your being disconnected, but
 without more information the best I can guess is that the server doesn't
 like our query about avatars. (We send four disco#info queries one each
 for mood, tune, nick, and avatar and receive three responses, one for
 mood, nick, and tune but not one for avatar. So either you are being
 disconnected before the avatar request comes back or the server doesn't
 like the avatar request and is kicking you off because of it.)

-- 
Ticket URL: <http://developer.pidgin.im/ticket/6514#comment:1>
Pidgin <http://pidgin.im>
Pidgin


More information about the Tracker mailing list