Account remains disconnected if during reconnect timeout the event "No reply received for keepalive ping" occured
- Gajim git master b548b90a
- python-nbxmpp git master 8a87c2be8016
Log:
12/23/2018 14:45:14 (I) nbxmpp.transports_nb: read_timeout called, state == CONNECTED
12/23/2018 14:45:14 (I) nbxmpp.transports_nb: Plugging fd 40, W:True, R:True
12/23/2018 14:45:14 (I) nbxmpp.idlequeue: read timeout set for fd 40 on 175 seconds
12/23/2018 14:45:14 (I) nbxmpp.idlequeue: read timeout removed for fd 40
12/23/2018 14:45:14 (I) nbxmpp.transports_nb: pollout called, state == CONNECTED
12/23/2018 14:45:14 (I) nbxmpp.transports_nb: Plugging fd 40, W:False, R:True
12/23/2018 14:45:14 (I) nbxmpp.client_nb: raising event from transport: :::::DATA SENT::::
_____________
_____________
12/23/2018 14:45:19 (I) nbxmpp.transports_nb: pollin called, state == CONNECTED
12/23/2018 14:45:19 (I) nbxmpp.transports_nb: _do_receive, caught SSL error, got None:
Traceback (most recent call last):
File "/home/andrey/progs/python-nbxmpp.git/nbxmpp/tls_nb.py", line 167, in recv
retval = self.sslobj.recv(bufsize)
File "/usr/lib/python3/dist-packages/OpenSSL/SSL.py", line 1783, in recv
self._raise_ssl_error(self._ssl, result)
File "/usr/lib/python3/dist-packages/OpenSSL/SSL.py", line 1632, in _raise_ssl_error
raise SysCallError(-1, "Unexpected EOF")
OpenSSL.SSL.SysCallError: (-1, 'Unexpected EOF')
During handling of the above exception, another exception occurred:
Traceback (most recent call last):
File "/home/andrey/progs/python-nbxmpp.git/nbxmpp/transports_nb.py", line 613, in _do_receive
received = self._recv(RECV_BUFSIZE)
File "/home/andrey/progs/python-nbxmpp.git/nbxmpp/tls_nb.py", line 175, in recv
raise SSLWrapper.Error(self.sock or self.sslobj, e)
nbxmpp.tls_nb.SSLWrapper.Error: <class 'nbxmpp.tls_nb.SSLWrapper.Error'> for 5.1.76.122:5222: [Errno: -1] ((-1, 'Unexpected EOF')), Caused by <class 'OpenSSL.SSL.SysCallError'>((-1, 'Unexpected EOF'))
12/23/2018 14:45:19 (I) nbxmpp.transports_nb: Disconnected by remote server: #-1, (-1, 'Unexpected EOF')
12/23/2018 14:45:19 (I) nbxmpp.client_nb: Disconnecting NBClient:
12/23/2018 14:45:19 (D) nbxmpp.client_nb: Client disconnected..
12/23/2018 14:45:19 (D) nbxmpp.client_nb: Calling disconnect handler <bound method Connection._on_disconnect of <gajim.common.connection.Connection object at 0x7f8f3b8ce860>>
12/23/2018 14:45:19 (I) gajim.c.connection: Disconnect trashserver.net, reconnect: True
12/23/2018 14:45:19 (I) gajim.c.connection: Set state disconnected
12/23/2018 14:45:19 (I) gajim.c.connection: Reconnect to trashserver.net in 15s
12/23/2018 14:45:20 (I) nbxmpp.idlequeue: read timeout removed for fd 40
12/23/2018 14:45:21 (I) nbxmpp.transports_nb: pollin called, state == CONNECTED
12/23/2018 14:45:21 (I) nbxmpp.idlequeue: read timeout removed for fd 39
12/23/2018 14:45:21 (I) nbxmpp.idlequeue: read timeout set for fd 39 on 175 seconds
12/23/2018 14:45:21 (I) nbxmpp.idlequeue: read timeout set for fd 39 on 180 seconds with function <bound method NonBlockingTransport.read_timeout2 of <nbxmpp.transports_nb.NonBlockingTCP object at 0x7f8f29462b00>>
12/23/2018 14:45:21 (I) nbxmpp.client_nb: raising event from transport: :::::DATA RECEIVED::::
_____________
<message to='MYJID@jabber.ccc.de/gajim.P0UTV5AE' from='prosody@conference.prosody.im/Corin' type='groupchat'><active xmlns='http://jabber.org/protocol/chatstates'/><no-store xmlns='urn:xmpp:hints'/><no-permanent-store xmlns='urn:xmpp:hints'/></message>
_____________
12/23/2018 14:45:21 (D) nbxmpp.smacks: IN, message
12/23/2018 14:45:21 (I) nbxmpp.transports_nb: pollin called, state == CONNECTED
12/23/2018 14:45:21 (I) nbxmpp.idlequeue: read timeout removed for fd 39
12/23/2018 14:45:21 (I) nbxmpp.idlequeue: read timeout set for fd 39 on 175 seconds
12/23/2018 14:45:21 (I) nbxmpp.idlequeue: read timeout set for fd 39 on 180 seconds with function <bound method NonBlockingTransport.read_timeout2 of <nbxmpp.transports_nb.NonBlockingTCP object at 0x7f8f29462b00>>
12/23/2018 14:45:21 (I) nbxmpp.client_nb: raising event from transport: :::::DATA RECEIVED::::
_____________
<r xmlns='urn:xmpp:sm:3'/>
_____________
12/23/2018 14:45:21 (D) nbxmpp.smacks: Send ack, h: 11580
12/23/2018 14:45:21 (I) nbxmpp.transports_nb: Plugging fd 39, W:False, R:True
12/23/2018 14:45:21 (I) nbxmpp.client_nb: raising event from transport: :::::DATA SENT::::
_____________
<a xmlns="urn:xmpp:sm:3" h="11580" />
_____________
12/23/2018 14:45:21 (I) nbxmpp.transports_nb: Plugging fd 39, W:True, R:True
12/23/2018 14:45:21 (I) nbxmpp.transports_nb: pollout called, state == CONNECTED
12/23/2018 14:45:21 (W) nbxmpp.transports_nb: calling send on empty buffer and queue
12/23/2018 14:45:21 (I) nbxmpp.transports_nb: Plugging fd 39, W:False, R:True
12/23/2018 14:45:24 (I) nbxmpp.transports_nb: pollin called, state == CONNECTED
12/23/2018 14:45:24 (I) nbxmpp.idlequeue: read timeout removed for fd 39
12/23/2018 14:45:24 (I) nbxmpp.idlequeue: read timeout set for fd 39 on 175 seconds
12/23/2018 14:45:24 (I) nbxmpp.idlequeue: read timeout set for fd 39 on 180 seconds with function <bound method NonBlockingTransport.read_timeout2 of <nbxmpp.transports_nb.NonBlockingTCP object at 0x7f8f29462b00>>
12/23/2018 14:45:24 (I) nbxmpp.client_nb: raising event from transport: :::::DATA RECEIVED::::
_____________
<presence xml:lang='en' to='MYJID@jabber.ccc.de/gajim.P0UTV5AE' from='chatsecure@conference.magicbroccoli.de/zak'><c xmlns='http://jabber.org/protocol/caps' ver='/xego4vdht8Z+u1EXSJRZWP9v2g=' node='http://conversations.im' hash='sha-1'/><x xmlns='vcard-temp:x:update'><photo/></x><x xmlns='http://jabber.org/protocol/muc#user'><item role='participant' affiliation='none'/></x></presence>
_____________
12/23/2018 14:45:24 (D) nbxmpp.smacks: IN, presence
12/23/2018 14:45:24 (D) gajim.avatar: Draw Groupchat Avatar: zak None
12/23/2018 14:45:24 (I) nbxmpp.transports_nb: pollin called, state == CONNECTED
12/23/2018 14:45:24 (I) nbxmpp.idlequeue: read timeout removed for fd 39
12/23/2018 14:45:24 (I) nbxmpp.idlequeue: read timeout set for fd 39 on 175 seconds
12/23/2018 14:45:24 (I) nbxmpp.idlequeue: read timeout set for fd 39 on 180 seconds with function <bound method NonBlockingTransport.read_timeout2 of <nbxmpp.transports_nb.NonBlockingTCP object at 0x7f8f29462b00>>
12/23/2018 14:45:24 (I) nbxmpp.client_nb: raising event from transport: :::::DATA RECEIVED::::
_____________
<r xmlns='urn:xmpp:sm:3'/>
_____________
12/23/2018 14:45:24 (D) nbxmpp.smacks: Send ack, h: 11581
12/23/2018 14:45:24 (I) nbxmpp.transports_nb: Plugging fd 39, W:False, R:True
12/23/2018 14:45:24 (I) nbxmpp.client_nb: raising event from transport: :::::DATA SENT::::
_____________
<a xmlns="urn:xmpp:sm:3" h="11581" />
_____________
12/23/2018 14:45:24 (I) nbxmpp.transports_nb: Plugging fd 39, W:True, R:True
12/23/2018 14:45:24 (I) nbxmpp.transports_nb: pollout called, state == CONNECTED
12/23/2018 14:45:24 (W) nbxmpp.transports_nb: calling send on empty buffer and queue
12/23/2018 14:45:24 (I) nbxmpp.transports_nb: Plugging fd 39, W:False, R:True
12/23/2018 14:45:24 (I) nbxmpp.transports_nb: pollin called, state == CONNECTED
12/23/2018 14:45:24 (I) nbxmpp.idlequeue: read timeout removed for fd 39
12/23/2018 14:45:24 (I) nbxmpp.idlequeue: read timeout set for fd 39 on 175 seconds
12/23/2018 14:45:24 (I) nbxmpp.idlequeue: read timeout set for fd 39 on 180 seconds with function <bound method NonBlockingTransport.read_timeout2 of <nbxmpp.transports_nb.NonBlockingTCP object at 0x7f8f29462b00>>
12/23/2018 14:45:24 (I) nbxmpp.client_nb: raising event from transport: :::::DATA RECEIVED::::
_____________
<message to='MYJID@jabber.ccc.de/gajim.P0UTV5AE' from='prosody@conference.prosody.im/Corin' type='groupchat'><composing xmlns='http://jabber.org/protocol/chatstates'/><no-store xmlns='urn:xmpp:hints'/><no-permanent-store xmlns='urn:xmpp:hints'/></message>
_____________
12/23/2018 14:45:24 (D) nbxmpp.smacks: IN, message
12/23/2018 14:45:24 (I) nbxmpp.transports_nb: pollin called, state == CONNECTED
12/23/2018 14:45:24 (I) nbxmpp.idlequeue: read timeout removed for fd 39
12/23/2018 14:45:24 (I) nbxmpp.idlequeue: read timeout set for fd 39 on 175 seconds
12/23/2018 14:45:24 (I) nbxmpp.idlequeue: read timeout set for fd 39 on 180 seconds with function <bound method NonBlockingTransport.read_timeout2 of <nbxmpp.transports_nb.NonBlockingTCP object at 0x7f8f29462b00>>
12/23/2018 14:45:24 (I) nbxmpp.client_nb: raising event from transport: :::::DATA RECEIVED::::
_____________
<r xmlns='urn:xmpp:sm:3'/>
_____________
12/23/2018 14:45:24 (D) nbxmpp.smacks: Send ack, h: 11582
12/23/2018 14:45:24 (I) nbxmpp.transports_nb: Plugging fd 39, W:False, R:True
12/23/2018 14:45:24 (I) nbxmpp.client_nb: raising event from transport: :::::DATA SENT::::
_____________
<a xmlns="urn:xmpp:sm:3" h="11582" />
_____________
12/23/2018 14:45:24 (I) nbxmpp.transports_nb: Plugging fd 39, W:True, R:True
12/23/2018 14:45:24 (I) nbxmpp.transports_nb: pollout called, state == CONNECTED
12/23/2018 14:45:24 (W) nbxmpp.transports_nb: calling send on empty buffer and queue
12/23/2018 14:45:24 (I) nbxmpp.transports_nb: Plugging fd 39, W:False, R:True
12/23/2018 14:45:24 (W) gajim.c.m.ping: No reply received for keepalive ping. Reconnecting...
12/23/2018 14:45:24 (I) gajim.c.connection: Set state disconnected
[ no fd 40 and no trashserver.net anymore ]