[2021/07/10 11:53:40.062][DEBUG][utils.grpc.ChannelCache:49] Eviction period = 600.0s, max channels = 4096 [2021/07/10 11:53:40.247][DEBUG][asyncio.__init__:59] Using selector: EpollSelector [2021/07/10 11:53:40.654][DEBUG][p2p.p2p_daemon_bindings.control.open_connection:57] DaemonConnector opens connection to /unix/tmp/hivemind-p2pd-B4tnhAq6U1U.sock [2021/07/10 11:53:40.672][DEBUG][p2p.p2p_daemon._ping_daemon:193] Launched p2pd with id = QmTqNtzhfJrChohykAkiZQ8xBJW8995EfQynqAf37BNCak, host multiaddrs = (,) [2021/07/10 11:53:40.673][DEBUG][p2p.p2p_daemon_bindings.control.open_connection:57] DaemonConnector opens connection to /unix/tmp/hivemind-p2pd-B4tnhAq6U1U.sock [2021/07/10 11:53:40.674][DEBUG][p2p.p2p_daemon_bindings.control.listen:108] DaemonConnector starts listening to /unix/tmp/hivemind-p2pclient-B4tnhAq6U1U.sock [2021/07/10 11:53:40.674][DEBUG][p2p.p2p_daemon_bindings.control.open_connection:57] DaemonConnector opens connection to /unix/tmp/hivemind-p2pd-B4tnhAq6U1U.sock [2021/07/10 11:53:40.675][DEBUG][p2p.p2p_daemon_bindings.control.open_connection:57] DaemonConnector opens connection to /unix/tmp/hivemind-p2pd-B4tnhAq6U1U.sock [2021/07/10 11:53:40.676][INFO][root.run_protocol_listener:40] Started peer id=DHTID(0x3db9c894718f96bdd909afcef13a545941e7e1e4) visible_maddrs=[] [2021/07/10 11:53:40.683][DEBUG][asyncio.__init__:59] Using selector: EpollSelector [2021/07/10 11:53:41.101][DEBUG][p2p.p2p_daemon_bindings.control.open_connection:57] DaemonConnector opens connection to /unix/tmp/hivemind-p2pd-vXE2xWbi9bg.sock [2021/07/10 11:53:41.103][DEBUG][p2p.p2p_daemon._ping_daemon:193] Launched p2pd with id = Qma6rFAHFdwecmQKqWtU59LRqGAeXDam1rLc1u5ueNbwXm, host multiaddrs = (,) [2021/07/10 11:53:41.105][DEBUG][p2p.p2p_daemon_bindings.control.open_connection:57] DaemonConnector opens connection to /unix/tmp/hivemind-p2pd-vXE2xWbi9bg.sock [2021/07/10 11:53:41.105][DEBUG][p2p.p2p_daemon_bindings.control.listen:108] DaemonConnector starts listening to /unix/tmp/hivemind-p2pclient-vXE2xWbi9bg.sock [2021/07/10 11:53:41.106][DEBUG][p2p.p2p_daemon_bindings.control.open_connection:57] DaemonConnector opens connection to /unix/tmp/hivemind-p2pd-vXE2xWbi9bg.sock [2021/07/10 11:53:41.106][DEBUG][p2p.p2p_daemon_bindings.control.open_connection:57] DaemonConnector opens connection to /unix/tmp/hivemind-p2pd-vXE2xWbi9bg.sock [2021/07/10 11:53:41.107][INFO][root.run_protocol_listener:40] Started peer id=DHTID(0xa7087d197c0f2758c6bff7256e3cb78c5152c137) visible_maddrs=[] [2021/07/10 11:53:41.108][DEBUG][p2p.p2p_daemon_bindings.control.open_connection:57] DaemonConnector opens connection to /unix/tmp/hivemind-p2pd-vXE2xWbi9bg.sock [2021/07/10 11:53:41.109][DEBUG][p2p.p2p_daemon_bindings.control._handler:83] New incoming stream: [2021/07/10 11:53:41.111][DEBUG][asyncio.__init__:59] Using selector: EpollSelector [2021/07/10 11:53:41.528][DEBUG][p2p.p2p_daemon_bindings.control.open_connection:57] DaemonConnector opens connection to /unix/tmp/hivemind-p2pd-VRUjGSy2BP8.sock [2021/07/10 11:53:41.530][DEBUG][p2p.p2p_daemon._ping_daemon:193] Launched p2pd with id = QmYqP7ECXjtxaTi54eznbk2tgq9D43xpyL8hRYsksRMQ8Q, host multiaddrs = (,) [2021/07/10 11:53:41.531][INFO][root.test_dht_protocol:81] Self id=DHTID(0xc147c315f7abd7fff33ccb0bd7b35cb0215ca616) [2021/07/10 11:53:41.531][DEBUG][p2p.p2p_daemon_bindings.control.open_connection:57] DaemonConnector opens connection to /unix/tmp/hivemind-p2pd-VRUjGSy2BP8.sock [2021/07/10 11:53:41.532][DEBUG][p2p.p2p_daemon_bindings.control._handler:83] New incoming stream: [2021/07/10 11:53:41.534][DEBUG][p2p.p2p_daemon_bindings.control.open_connection:57] DaemonConnector opens connection to /unix/tmp/hivemind-p2pd-VRUjGSy2BP8.sock [2021/07/10 11:53:41.535][DEBUG][p2p.p2p_daemon_bindings.control._handler:83] New incoming stream: [2021/07/10 11:53:41.536][DEBUG][p2p.p2p_daemon_bindings.control.open_connection:57] DaemonConnector opens connection to /unix/tmp/hivemind-p2pd-VRUjGSy2BP8.sock [2021/07/10 11:53:41.536][DEBUG][p2p.p2p_daemon_bindings.control._handler:83] New incoming stream: [2021/07/10 11:53:41.538][DEBUG][p2p.p2p_daemon_bindings.control.open_connection:57] DaemonConnector opens connection to /unix/tmp/hivemind-p2pd-VRUjGSy2BP8.sock [2021/07/10 11:53:41.539][DEBUG][p2p.p2p_daemon_bindings.control._handler:83] New incoming stream: [2021/07/10 11:53:41.541][DEBUG][p2p.p2p_daemon_bindings.control.open_connection:57] DaemonConnector opens connection to /unix/tmp/hivemind-p2pd-VRUjGSy2BP8.sock [2021/07/10 11:53:41.542][ERROR][dht.protocol.call_find:278] DHTProtocol failed to find at fakeid Traceback (most recent call last): File "/home/borzunov/hivemind/hivemind/dht/protocol.py", line 245, in call_find response = await self.get_stub(peer).rpc_find(find_request, timeout=self.wait_timeout) File "/home/borzunov/hivemind/hivemind/utils/auth.py", line 199, in wrapped_rpc response = await method(request, *args, **kwargs) File "/home/borzunov/hivemind/hivemind/p2p/servicer.py", line 72, in caller return await asyncio.wait_for( File "/home/borzunov/anaconda3/lib/python3.8/asyncio/tasks.py", line 483, in wait_for return fut.result() File "/home/borzunov/hivemind/hivemind/p2p/p2p_daemon.py", line 374, in call_protobuf_handler stream_info, reader, writer = await self._client.stream_open(peer_id, (handler_name,)) File "/home/borzunov/hivemind/hivemind/p2p/p2p_daemon_bindings/p2pclient.py", line 76, in stream_open return await self.control.stream_open(peer_id=peer_id, protocols=protocols) File "/home/borzunov/hivemind/hivemind/p2p/p2p_daemon_bindings/control.py", line 185, in stream_open raise_if_failed(resp) File "/home/borzunov/hivemind/hivemind/p2p/p2p_daemon_bindings/utils.py", line 60, in raise_if_failed raise ControlFailure(f"Connect failed. msg={response.error.msg}") hivemind.p2p.p2p_daemon_bindings.utils.ControlFailure: Connect failed. msg=length greater than remaining number of bytes in buffer [2021/07/10 11:53:41.544][DEBUG][p2p.p2p_daemon_bindings.control.open_connection:57] DaemonConnector opens connection to /unix/tmp/hivemind-p2pd-VRUjGSy2BP8.sock [2021/07/10 11:53:41.545][DEBUG][p2p.p2p_daemon_bindings.control._handler:83] New incoming stream: [2021/07/10 11:53:41.546][DEBUG][p2p.p2p_daemon_bindings.control.open_connection:57] DaemonConnector opens connection to /unix/tmp/hivemind-p2pd-VRUjGSy2BP8.sock [2021/07/10 11:53:41.547][DEBUG][p2p.p2p_daemon_bindings.control._handler:83] New incoming stream: [2021/07/10 11:53:41.547][DEBUG][p2p.p2p_daemon_bindings.control.open_connection:57] DaemonConnector opens connection to /unix/tmp/hivemind-p2pd-VRUjGSy2BP8.sock [2021/07/10 11:53:41.548][DEBUG][p2p.p2p_daemon_bindings.control._handler:83] New incoming stream: [2021/07/10 11:53:41.958][DEBUG][p2p.p2p_daemon_bindings.control.open_connection:57] DaemonConnector opens connection to /unix/tmp/hivemind-p2pd-Pswf-De9YNA.sock [2021/07/10 11:53:41.960][DEBUG][p2p.p2p_daemon._ping_daemon:193] Launched p2pd with id = QmfJuUCnS8PFAazbfgiExgpYGDCGnq5pBCQBSFZjVB71mE, host multiaddrs = (,) [2021/07/10 11:53:41.961][DEBUG][p2p.p2p_daemon_bindings.control.open_connection:57] DaemonConnector opens connection to /unix/tmp/hivemind-p2pd-Pswf-De9YNA.sock [2021/07/10 11:53:41.962][DEBUG][p2p.p2p_daemon_bindings.control.listen:108] DaemonConnector starts listening to /unix/tmp/hivemind-p2pclient-Pswf-De9YNA.sock [2021/07/10 11:53:41.963][DEBUG][p2p.p2p_daemon_bindings.control.open_connection:57] DaemonConnector opens connection to /unix/tmp/hivemind-p2pd-Pswf-De9YNA.sock [2021/07/10 11:53:41.964][DEBUG][p2p.p2p_daemon_bindings.control.open_connection:57] DaemonConnector opens connection to /unix/tmp/hivemind-p2pd-Pswf-De9YNA.sock [2021/07/10 11:53:41.965][INFO][root.test_dht_protocol:81] Self id=DHTID(0x493ce245bb79ab0900ad291134df41125dfcf217) [2021/07/10 11:53:41.966][DEBUG][p2p.p2p_daemon_bindings.control.open_connection:57] DaemonConnector opens connection to /unix/tmp/hivemind-p2pd-Pswf-De9YNA.sock [2021/07/10 11:53:41.967][DEBUG][p2p.p2p_daemon_bindings.control._handler:83] New incoming stream: [2021/07/10 11:53:41.968][DEBUG][p2p.p2p_daemon_bindings.control.open_connection:57] DaemonConnector opens connection to /unix/tmp/hivemind-p2pd-Pswf-De9YNA.sock [2021/07/10 11:53:41.969][DEBUG][p2p.p2p_daemon_bindings.control._handler:83] New incoming stream: [2021/07/10 11:53:41.970][DEBUG][p2p.p2p_daemon_bindings.control.open_connection:57] DaemonConnector opens connection to /unix/tmp/hivemind-p2pd-Pswf-De9YNA.sock [2021/07/10 11:53:41.971][DEBUG][p2p.p2p_daemon_bindings.control._handler:83] New incoming stream: [2021/07/10 11:53:41.973][DEBUG][p2p.p2p_daemon_bindings.control.open_connection:57] DaemonConnector opens connection to /unix/tmp/hivemind-p2pd-Pswf-De9YNA.sock [2021/07/10 11:53:41.974][DEBUG][p2p.p2p_daemon_bindings.control._handler:83] New incoming stream: [2021/07/10 11:53:41.975][DEBUG][p2p.p2p_daemon_bindings.control.open_connection:57] DaemonConnector opens connection to /unix/tmp/hivemind-p2pd-Pswf-De9YNA.sock [2021/07/10 11:53:41.975][ERROR][dht.protocol.call_find:278] DHTProtocol failed to find at fakeid Traceback (most recent call last): File "/home/borzunov/hivemind/hivemind/dht/protocol.py", line 245, in call_find response = await self.get_stub(peer).rpc_find(find_request, timeout=self.wait_timeout) File "/home/borzunov/hivemind/hivemind/utils/auth.py", line 199, in wrapped_rpc response = await method(request, *args, **kwargs) File "/home/borzunov/hivemind/hivemind/p2p/servicer.py", line 72, in caller return await asyncio.wait_for( File "/home/borzunov/anaconda3/lib/python3.8/asyncio/tasks.py", line 483, in wait_for return fut.result() File "/home/borzunov/hivemind/hivemind/p2p/p2p_daemon.py", line 374, in call_protobuf_handler stream_info, reader, writer = await self._client.stream_open(peer_id, (handler_name,)) File "/home/borzunov/hivemind/hivemind/p2p/p2p_daemon_bindings/p2pclient.py", line 76, in stream_open return await self.control.stream_open(peer_id=peer_id, protocols=protocols) File "/home/borzunov/hivemind/hivemind/p2p/p2p_daemon_bindings/control.py", line 185, in stream_open raise_if_failed(resp) File "/home/borzunov/hivemind/hivemind/p2p/p2p_daemon_bindings/utils.py", line 60, in raise_if_failed raise ControlFailure(f"Connect failed. msg={response.error.msg}") hivemind.p2p.p2p_daemon_bindings.utils.ControlFailure: Connect failed. msg=length greater than remaining number of bytes in buffer [2021/07/10 11:53:41.976][DEBUG][p2p.p2p_daemon_bindings.control.open_connection:57] DaemonConnector opens connection to /unix/tmp/hivemind-p2pd-Pswf-De9YNA.sock [2021/07/10 11:53:41.977][DEBUG][p2p.p2p_daemon_bindings.control._handler:83] New incoming stream: [2021/07/10 11:53:41.978][DEBUG][p2p.p2p_daemon_bindings.control.open_connection:57] DaemonConnector opens connection to /unix/tmp/hivemind-p2pd-Pswf-De9YNA.sock [2021/07/10 11:53:41.978][DEBUG][p2p.p2p_daemon_bindings.control._handler:83] New incoming stream: [2021/07/10 11:53:41.979][DEBUG][p2p.p2p_daemon_bindings.control.open_connection:57] DaemonConnector opens connection to /unix/tmp/hivemind-p2pd-Pswf-De9YNA.sock [2021/07/10 11:53:41.980][DEBUG][p2p.p2p_daemon_bindings.control._handler:83] New incoming stream: 2021-07-10T11:53:41.981+0300 ERROR p2pd error accepting connection {"error": "accept unix /tmp/hivemind-p2pd-Pswf-De9YNA.sock: use of closed network connection"} [2021/07/10 11:53:41.985][DEBUG][p2p.p2p_daemon._terminate:401] Terminated p2pd with id = QmfJuUCnS8PFAazbfgiExgpYGDCGnq5pBCQBSFZjVB71mE 2021-07-10T11:53:41.985+0300 ERROR p2pd error accepting connection {"error": "accept unix /tmp/hivemind-p2pd-B4tnhAq6U1U.sock: use of closed network connection"} [2021/07/10 11:53:41.988][DEBUG][p2p.p2p_daemon._terminate:401] Terminated p2pd with id = Qma6rFAHFdwecmQKqWtU59LRqGAeXDam1rLc1u5ueNbwXm [2021/07/10 11:53:41.988][DEBUG][p2p.p2p_daemon._terminate:401] Terminated p2pd with id = Qma6rFAHFdwecmQKqWtU59LRqGAeXDam1rLc1u5ueNbwXm [2021/07/10 11:53:41.989][INFO][root.shutdown:49] Finished peer id=DHTID(0xa7087d197c0f2758c6bff7256e3cb78c5152c137) maddrs=[] [2021/07/10 11:53:41.989][DEBUG][p2p.p2p_daemon._terminate:401] Terminated p2pd with id = QmTqNtzhfJrChohykAkiZQ8xBJW8995EfQynqAf37BNCak [2021/07/10 11:53:41.989][DEBUG][p2p.p2p_daemon._terminate:401] Terminated p2pd with id = QmTqNtzhfJrChohykAkiZQ8xBJW8995EfQynqAf37BNCak [2021/07/10 11:53:41.990][INFO][root.shutdown:49] Finished peer id=DHTID(0x3db9c894718f96bdd909afcef13a545941e7e1e4) maddrs=[] [2021/07/10 11:53:41.990][INFO][root.shutdown:49] Finished peer id=DHTID(0x3db9c894718f96bdd909afcef13a545941e7e1e4) maddrs=[] [2021/07/10 11:53:42.033][DEBUG][p2p.p2p_daemon._terminate:401] Terminated p2pd with id = QmYqP7ECXjtxaTi54eznbk2tgq9D43xpyL8hRYsksRMQ8Q