• Julien Muchembled's avatar
    storage: fix assertion failure in case of connection reset with a client node · 652f1f0d
    Julien Muchembled authored
    Here is what happened after simulating a network failure between a client and
    a storage:
    
    C8
    
    DEBUG   recv failed for <SSLSocketConnectorIPv6 at 0x7f8198027f90 fileno 17 ('xxxx:xxxx:120:cd8::90a1', 53970), opened to ('xxxx:xxxx:60:4c2c::25c3', 39085)>: ECONNRESET (Connection reset by peer)
    DEBUG   connection closed for <MTClientConnection(uuid=S2, address=[xxxx:xxxx:60:4c2c::25c3]:39085, handler=StorageEventHandler, closed, client) at 7f81939a0950>
    DEBUG   connection started for <MTClientConnection(uuid=S2, address=[xxxx:xxxx:60:4c2c::25c3]:39085, handler=StorageEventHandler, fd=17, on_close=onConnectionClosed, connecting, client) at 7f8192eb17d0>
    PACKET  #0x0000 RequestIdentification          > S2 ([xxxx:xxxx:60:4c2c::25c3]:39085)        | (<EnumItem CLIENT (2)>, -536870904, None, '...', [], 1535555463.455761)
    DEBUG   SSL handshake done for <SSLSocketConnectorIPv6 at 0x7f8192eb1850 fileno 17 ('xxxx:xxxx:120:cd8::90a1', 54014), opened to ('xxxx:xxxx:60:4c2c::25c3', 39085)>: ECDHE-RSA-AES256-GCM-SHA384 256
    DEBUG   connection completed for <MTClientConnection(uuid=S2, address=[xxxx:xxxx:60:4c2c::25c3]:39085, handler=StorageEventHandler, fd=17, on_close=onConnectionClosed, client) at 7f8192eb17d0> (from xxxx:xxxx:120:cd8::90a1:54014)
    DEBUG   <SSLSocketConnectorIPv6 at 0x7f8192eb1850 fileno 17 ('xxxx:xxxx:120:cd8::90a1', 54014), opened to ('xxxx:xxxx:60:4c2c::25c3', 39085)> closed in recv
    DEBUG   connection closed for <MTClientConnection(uuid=S2, address=[xxxx:xxxx:60:4c2c::25c3]:39085, handler=StorageEventHandler, closed, client) at 7f8192eb17d0>
    ERROR   Connection to <StorageNode(uuid=S2, address=[xxxx:xxxx:60:4c2c::25c3]:39085, state=RUNNING, connection=None, not identified) at 7f81a8874690> failed
    
    S2
    
    DEBUG   accepted a connection from xxxx:xxxx:120:cd8::90a1:54014
    DEBUG   SSL handshake done for <SSLSocketConnectorIPv6 at 0x7f657144a910 fileno 22 ('xxxx:xxxx:60:4c2c::25c3', 39085), opened from ('xxxx:xxxx:120:cd8::90a1', 54014)>: ECDHE-RSA-AES256-GCM-SHA384 256
    DEBUG   connection completed for <ServerConnection(uuid=None, address=[xxxx:xxxx:120:cd8::90a1]:54014, handler=IdentificationHandler, fd=22, server) at 7f657144a090> (from xxxx:xxxx:60:4c2c::25c3:39085)
    PACKET  #0x0000 RequestIdentification          < None ([xxxx:xxxx:120:cd8::90a1]:54014)         | (<EnumItem CLIENT (2)>, -536870904, None, '...', [], 1535555463.455761)
    DEBUG   connection closed for <ServerConnection(uuid=None, address=[xxxx:xxxx:120:cd8::90a1]:54014, handler=IdentificationHandler, closed, server) at 7f657144a090>
    WARNING A connection was lost during identification
    ERROR   Pre-mortem data:
    ERROR   Traceback (most recent call last):
    ERROR     File "neo/storage/app.py", line 194, in run
    ERROR       self._run()
    ERROR     File "neo/storage/app.py", line 225, in _run
    ERROR       self.doOperation()
    ERROR     File "neo/storage/app.py", line 310, in doOperation
    ERROR       poll()
    ERROR     File "neo/storage/app.py", line 134, in _poll
    ERROR       self.em.poll(1)
    ERROR     File "neo/lib/event.py", line 160, in poll
    ERROR       to_process.process()
    ERROR     File "neo/lib/connection.py", line 499, in process
    ERROR       self._handlers.handle(self, self._queue.pop(0))
    ERROR     File "neo/lib/connection.py", line 85, in handle
    ERROR       self._handle(connection, packet)
    ERROR     File "neo/lib/connection.py", line 100, in _handle
    ERROR       pending[0][1].packetReceived(connection, packet)
    ERROR     File "neo/lib/handler.py", line 123, in packetReceived
    ERROR       self.dispatch(*args)
    ERROR     File "neo/lib/handler.py", line 72, in dispatch
    ERROR       method(conn, *args, **kw)
    ERROR     File "neo/storage/handlers/identification.py", line 56, in requestIdentification
    ERROR       assert not node.isConnected(), node
    ERROR   AssertionError: <ClientNode(uuid=C8, state=RUNNING, connection=<ServerConnection(uuid=C8, address=[xxxx:xxxx:120:cd8::90a1]:53970, handler=ClientOperationHandler, fd=18, on_close=onConnectionClosed, server) at 7f657147d7d0>) at 7f65714d6cd0>
    652f1f0d
identification.py 2.97 KB