Created
January 15, 2020 14:35
-
-
Save veox/0bb8786935404071c59d2d1f48768173 to your computer and use it in GitHub Desktop.
Trinity Goerli sync against geth: Mismatch between block and imported block on 1 fields: - header.state_root
This file contains bidirectional Unicode text that may be interpreted or compiled differently than what appears below. To review, open the file in an editor that reveals hidden Unicode characters.
Learn more about bidirectional Unicode characters
| INFO 2020-01-15 16:29:51,287 trinity | |
| ______ _ _ __ | |
| /_ __/____(_)___ (_) /___ __ | |
| / / / ___/ / __ \/ / __/ / / / | |
| / / / / / / / / / / /_/ /_/ / | |
| /_/ /_/ /_/_/ /_/_/\__/\__, / | |
| /____/ | |
| INFO 2020-01-15 16:29:51,287 trinity Started main process (pid=82575) | |
| INFO 2020-01-15 16:29:51,288 trinity Trinity/v0.1.0a34/linux/cpython3.7.5 | |
| INFO 2020-01-15 16:29:51,288 trinity Trinity DEBUG log file is created at /home/veox/.local/share/trinity/goerli/logs-eth1/trinity.log | |
| INFO 2020-01-15 16:29:51,357 trinity Started DB server process (pid=82586) | |
| DEBUG 2020-01-15 16:29:52,894 root Logging initialized for file /home/veox/.local/share/trinity/goerli/ipcs-eth1/logging.ipc: PID=82586 | |
| DEBUG 2020-01-15 16:29:52,964 DBManager Starting <trinity.db.manager.DBManager object at 0x7f79264bbdd0> server over IPC socket: /home/veox/.local/share/trinity/goerli/ipcs-eth1/db.ipc | |
| INFO 2020-01-15 16:29:52,972 ComponentManager Starting components: JSON-RPC API/Network Database/Discovery/Request Server/Sync / PeerPool/TxComponent | |
| INFO 2020-01-15 16:29:53,057 ComponentManager Components started | |
| DEBUG 2020-01-15 16:29:54,689 root Logging initialized for file /home/veox/.local/share/trinity/goerli/ipcs-eth1/logging.ipc: PID=82599 | |
| DEBUG 2020-01-15 16:29:54,690 Endpoint Endpoint[bjson-rpc-api]: running | |
| DEBUG 2020-01-15 16:29:54,690 Endpoint Endpoint[bjson-rpc-api]: server started | |
| DEBUG 2020-01-15 16:29:54,692 RemoteEndpoint RemoteEndpoint connection established: bjson-rpc-api <-> main | |
| DEBUG 2020-01-15 16:29:54,695 root Logging initialized for file /home/veox/.local/share/trinity/goerli/ipcs-eth1/logging.ipc: PID=82604 | |
| DEBUG 2020-01-15 16:29:54,696 Manager <Manager service=<trinity.extensibility.event_bus.TrioEventBusService object at 0x7f4506e5c7d0> started=True running=True cancelled=False stopping=False finished=False did_error=False>: _handle_cancelled waiting for cancellation | |
| DEBUG 2020-01-15 16:29:54,697 TrioEndpoint Endpoint[discovery]: starting | |
| DEBUG 2020-01-15 16:29:54,697 TrioEndpoint Endpoint[discovery]: starting new connection channel | |
| DEBUG 2020-01-15 16:29:54,698 TrioEndpoint Endpoint[discovery]: server starting | |
| DEBUG 2020-01-15 16:29:54,707 DBClient Opened connection to /home/veox/.local/share/trinity/goerli/ipcs-eth1/db.ipc: <socket.socket fd=17, family=AddressFamily.AF_UNIX, type=SocketKind.SOCK_STREAM, proto=0> | |
| DEBUG 2020-01-15 16:29:54,708 DBManager Server accepted connection: '' | |
| DEBUG 2020-01-15 16:29:54,708 DBManager <trinity.db.manager.DBManager object at 0x7f79264bbdd0>: starting client handler for <socket.socket fd=14, family=AddressFamily.AF_UNIX, type=SocketKind.SOCK_STREAM, proto=0, laddr=/home/veox/.local/share/trinity/goerli/ipcs-eth1/db.ipc> | |
| DEBUG 2020-01-15 16:29:54,709 Manager New root task run[daemon=False] added to DAG | |
| DEBUG 2020-01-15 16:29:54,709 Manager <Manager service=<trinity.rpc.ipc.IPCServer object at 0x7f9e03586950> started=True running=True cancelled=False stopping=False finished=False did_error=False>: _handle_cancelled waiting for cancellation | |
| DEBUG 2020-01-15 16:29:54,709 Manager <Manager service=<trinity.rpc.ipc.IPCServer object at 0x7f9e03586950> started=True running=True cancelled=False stopping=False finished=False did_error=False>: _handle_stopping waiting for stopping | |
| DEBUG 2020-01-15 16:29:54,710 Manager running task 'run[daemon=False]' | |
| INFO 2020-01-15 16:29:54,710 IPCServer IPC started at: /home/veox/.local/share/trinity/goerli/ipcs-eth1/jsonrpc.ipc | |
| DEBUG 2020-01-15 16:29:54,751 Manager running task '_auto_connect_new_announced_endpoints[daemon=True]' | |
| DEBUG 2020-01-15 16:29:54,751 DBClient Opened connection to /home/veox/.local/share/trinity/goerli/ipcs-eth1/db.ipc: <socket.socket fd=12, family=AddressFamily.AF_UNIX, type=SocketKind.SOCK_STREAM, proto=0> | |
| DEBUG 2020-01-15 16:29:54,752 DBManager Server accepted connection: '' | |
| DEBUG 2020-01-15 16:29:54,752 DBManager <trinity.db.manager.DBManager object at 0x7f79264bbdd0>: starting client handler for <socket.socket fd=15, family=AddressFamily.AF_UNIX, type=SocketKind.SOCK_STREAM, proto=0, laddr=/home/veox/.local/share/trinity/goerli/ipcs-eth1/db.ipc> | |
| DEBUG 2020-01-15 16:29:54,752 Manager <Manager service=<p2p.discovery.StaticDiscoveryService object at 0x7f4506e13a10> started=True running=True cancelled=False stopping=False finished=False did_error=False>: _handle_cancelled waiting for cancellation | |
| DEBUG 2020-01-15 16:29:54,753 Manager running task 'handle_get_peer_candidates_requests[daemon=True]' | |
| DEBUG 2020-01-15 16:29:54,753 Manager running task 'handle_get_random_bootnode_requests[daemon=True]' | |
| DEBUG 2020-01-15 16:29:54,755 Connection Opened connection to /home/veox/.local/share/trinity/goerli/ipcs-eth1/main.ipc: <trio.SocketStream object at 0x7f4506e13750> | |
| DEBUG 2020-01-15 16:29:54,756 RemoteEndpoint RemoteEndpoint connection established: discovery <-> main | |
| DEBUG 2020-01-15 16:29:54,758 AsyncioEventBusService EventBus Endpoint bjson-rpc-api connecting to other Endpoints: discovery | |
| DEBUG 2020-01-15 16:29:54,759 TrioEndpoint Endpoint[discovery]: starting client handler for <trio.SocketStream object at 0x7f4506dfee10> | |
| DEBUG 2020-01-15 16:29:54,759 RemoteEndpoint RemoteEndpoint connection established: bjson-rpc-api <-> discovery | |
| DEBUG 2020-01-15 16:29:54,759 RemoteEndpoint RemoteEndpoint connection established: discovery <-> bjson-rpc-api | |
| DEBUG 2020-01-15 16:29:54,759 root Logging initialized for file /home/veox/.local/share/trinity/goerli/ipcs-eth1/logging.ipc: PID=82602 | |
| DEBUG 2020-01-15 16:29:54,759 Endpoint Endpoint[brequest-server]: running | |
| DEBUG 2020-01-15 16:29:54,760 Endpoint Endpoint[brequest-server]: server started | |
| DEBUG 2020-01-15 16:29:54,761 RemoteEndpoint RemoteEndpoint connection established: brequest-server <-> main | |
| DEBUG 2020-01-15 16:29:54,762 DBClient Opened connection to /home/veox/.local/share/trinity/goerli/ipcs-eth1/db.ipc: <socket.socket fd=17, family=AddressFamily.AF_UNIX, type=SocketKind.SOCK_STREAM, proto=0> | |
| DEBUG 2020-01-15 16:29:54,762 DBManager Server accepted connection: '' | |
| DEBUG 2020-01-15 16:29:54,762 Manager New root task run[daemon=False] added to DAG | |
| DEBUG 2020-01-15 16:29:54,762 AsyncioEventBusService EventBus Endpoint bjson-rpc-api connecting to other Endpoints: brequest-server | |
| DEBUG 2020-01-15 16:29:54,762 Manager <Manager service=<trinity.protocol.eth.servers.ETHRequestServer object at 0x7fb3c8fa2b90> started=True running=True cancelled=False stopping=False finished=False did_error=False>: _handle_cancelled waiting for cancellation | |
| DEBUG 2020-01-15 16:29:54,762 DBManager <trinity.db.manager.DBManager object at 0x7f79264bbdd0>: starting client handler for <socket.socket fd=16, family=AddressFamily.AF_UNIX, type=SocketKind.SOCK_STREAM, proto=0, laddr=/home/veox/.local/share/trinity/goerli/ipcs-eth1/db.ipc> | |
| DEBUG 2020-01-15 16:29:54,762 TrioEventBusService EventBus Endpoint discovery connecting to other Endpoints: brequest-server | |
| DEBUG 2020-01-15 16:29:54,762 Manager <Manager service=<trinity.protocol.eth.servers.ETHRequestServer object at 0x7fb3c8fa2b90> started=True running=True cancelled=False stopping=False finished=False did_error=False>: _handle_stopping waiting for stopping | |
| DEBUG 2020-01-15 16:29:54,763 Manager running task 'run[daemon=False]' | |
| DEBUG 2020-01-15 16:29:54,763 Manager running task 'handle_stream[daemon=True]' | |
| DEBUG 2020-01-15 16:29:54,763 Manager running task 'handle_stream[daemon=True]' | |
| DEBUG 2020-01-15 16:29:54,763 RemoteEndpoint RemoteEndpoint connection established: bjson-rpc-api <-> brequest-server | |
| DEBUG 2020-01-15 16:29:54,763 Manager running task 'handle_stream[daemon=True]' | |
| DEBUG 2020-01-15 16:29:54,763 Manager running task 'handle_stream[daemon=True]' | |
| DEBUG 2020-01-15 16:29:54,763 RemoteEndpoint RemoteEndpoint connection established: brequest-server <-> bjson-rpc-api | |
| DEBUG 2020-01-15 16:29:54,765 Connection Opened connection to /home/veox/.local/share/trinity/goerli/ipcs-eth1/brequest-server.ipc: <trio.SocketStream object at 0x7f4506e2dc90> | |
| DEBUG 2020-01-15 16:29:54,765 RemoteEndpoint RemoteEndpoint connection established: brequest-server <-> discovery | |
| DEBUG 2020-01-15 16:29:54,766 RemoteEndpoint RemoteEndpoint connection established: discovery <-> brequest-server | |
| DEBUG 2020-01-15 16:29:54,772 root Logging initialized for file /home/veox/.local/share/trinity/goerli/ipcs-eth1/logging.ipc: PID=82606 | |
| DEBUG 2020-01-15 16:29:54,772 Endpoint Endpoint[btxcomponent]: running | |
| DEBUG 2020-01-15 16:29:54,772 root Logging initialized for file /home/veox/.local/share/trinity/goerli/ipcs-eth1/logging.ipc: PID=82600 | |
| DEBUG 2020-01-15 16:29:54,773 Endpoint Endpoint[btxcomponent]: server started | |
| DEBUG 2020-01-15 16:29:54,773 Endpoint Endpoint[network-db]: running | |
| DEBUG 2020-01-15 16:29:54,773 Endpoint Endpoint[network-db]: server started | |
| DEBUG 2020-01-15 16:29:54,775 RemoteEndpoint RemoteEndpoint connection established: network-db <-> main | |
| DEBUG 2020-01-15 16:29:54,775 root Logging initialized for file /home/veox/.local/share/trinity/goerli/ipcs-eth1/logging.ipc: PID=82605 | |
| DEBUG 2020-01-15 16:29:54,776 Endpoint Endpoint[networking]: running | |
| DEBUG 2020-01-15 16:29:54,776 Endpoint Endpoint[networking]: server started | |
| DEBUG 2020-01-15 16:29:54,775 RemoteEndpoint RemoteEndpoint connection established: btxcomponent <-> main | |
| DEBUG 2020-01-15 16:29:54,777 DBClient Opened connection to /home/veox/.local/share/trinity/goerli/ipcs-eth1/db.ipc: <socket.socket fd=17, family=AddressFamily.AF_UNIX, type=SocketKind.SOCK_STREAM, proto=0> | |
| DEBUG 2020-01-15 16:29:54,777 DBManager Server accepted connection: '' | |
| DEBUG 2020-01-15 16:29:54,778 RemoteEndpoint RemoteEndpoint connection established: networking <-> main | |
| DEBUG 2020-01-15 16:29:54,777 DBManager <trinity.db.manager.DBManager object at 0x7f79264bbdd0>: starting client handler for <socket.socket fd=17, family=AddressFamily.AF_UNIX, type=SocketKind.SOCK_STREAM, proto=0, laddr=/home/veox/.local/share/trinity/goerli/ipcs-eth1/db.ipc> | |
| DEBUG 2020-01-15 16:29:54,778 AsyncioEventBusService EventBus Endpoint brequest-server connecting to other Endpoints: btxcomponent | |
| DEBUG 2020-01-15 16:29:54,778 AsyncioEventBusService EventBus Endpoint bjson-rpc-api connecting to other Endpoints: btxcomponent | |
| DEBUG 2020-01-15 16:29:54,778 TrioEventBusService EventBus Endpoint discovery connecting to other Endpoints: btxcomponent | |
| DEBUG 2020-01-15 16:29:54,780 DBClient Opened connection to /home/veox/.local/share/trinity/goerli/ipcs-eth1/db.ipc: <socket.socket fd=17, family=AddressFamily.AF_UNIX, type=SocketKind.SOCK_STREAM, proto=0> | |
| DEBUG 2020-01-15 16:29:54,780 DBManager Server accepted connection: '' | |
| DEBUG 2020-01-15 16:29:54,781 DBManager <trinity.db.manager.DBManager object at 0x7f79264bbdd0>: starting client handler for <socket.socket fd=18, family=AddressFamily.AF_UNIX, type=SocketKind.SOCK_STREAM, proto=0, laddr=/home/veox/.local/share/trinity/goerli/ipcs-eth1/db.ipc> | |
| DEBUG 2020-01-15 16:29:54,782 Connection Opened connection to /home/veox/.local/share/trinity/goerli/ipcs-eth1/btxcomponent.ipc: <trio.SocketStream object at 0x7f4506e13710> | |
| DEBUG 2020-01-15 16:29:54,790 ConnectionTrackerServer Running ConnectionTrackerServer | |
| DEBUG 2020-01-15 16:29:54,791 PeerDBServer Running PeerDBServer | |
| DEBUG 2020-01-15 16:29:54,791 Manager New root task run[daemon=False] added to DAG | |
| DEBUG 2020-01-15 16:29:54,792 Manager <Manager service=<trinity.components.builtin.tx_pool.pool.TxPool object at 0x7f8db5e7b790> started=True running=True cancelled=False stopping=False finished=False did_error=False>: _handle_cancelled waiting for cancellation | |
| DEBUG 2020-01-15 16:29:54,792 Manager <Manager service=<trinity.components.builtin.tx_pool.pool.TxPool object at 0x7f8db5e7b790> started=True running=True cancelled=False stopping=False finished=False did_error=False>: _handle_stopping waiting for stopping | |
| DEBUG 2020-01-15 16:29:54,792 Manager running task 'run[daemon=False]' | |
| INFO 2020-01-15 16:29:54,792 TxPool Running Tx Pool | |
| DEBUG 2020-01-15 16:29:54,792 Manager running task '_process_transactions[daemon=True]' | |
| DEBUG 2020-01-15 16:29:54,793 AsyncioEventBusService EventBus Endpoint network-db connecting to other Endpoints: networking | |
| DEBUG 2020-01-15 16:29:54,793 RemoteEndpoint RemoteEndpoint connection established: btxcomponent <-> brequest-server | |
| DEBUG 2020-01-15 16:29:54,793 RemoteEndpoint RemoteEndpoint connection established: btxcomponent <-> bjson-rpc-api | |
| DEBUG 2020-01-15 16:29:54,793 RemoteEndpoint RemoteEndpoint connection established: btxcomponent <-> discovery | |
| DEBUG 2020-01-15 16:29:54,794 RemoteEndpoint RemoteEndpoint connection established: brequest-server <-> btxcomponent | |
| DEBUG 2020-01-15 16:29:54,794 RemoteEndpoint RemoteEndpoint connection established: bjson-rpc-api <-> btxcomponent | |
| DEBUG 2020-01-15 16:29:54,794 AsyncioEventBusService EventBus Endpoint btxcomponent connecting to other Endpoints: network-db | |
| DEBUG 2020-01-15 16:29:54,794 RemoteEndpoint RemoteEndpoint connection established: discovery <-> btxcomponent | |
| DEBUG 2020-01-15 16:29:54,794 RemoteEndpoint RemoteEndpoint connection established: btxcomponent <-> network-db | |
| DEBUG 2020-01-15 16:29:54,794 AsyncioEventBusService EventBus Endpoint brequest-server connecting to other Endpoints: network-db | |
| DEBUG 2020-01-15 16:29:54,794 RemoteEndpoint RemoteEndpoint connection established: network-db <-> btxcomponent | |
| DEBUG 2020-01-15 16:29:54,794 AsyncioEventBusService EventBus Endpoint bjson-rpc-api connecting to other Endpoints: network-db | |
| DEBUG 2020-01-15 16:29:54,795 RemoteEndpoint RemoteEndpoint connection established: network-db <-> brequest-server | |
| DEBUG 2020-01-15 16:29:54,795 RemoteEndpoint RemoteEndpoint connection established: brequest-server <-> network-db | |
| DEBUG 2020-01-15 16:29:54,795 RemoteEndpoint RemoteEndpoint connection established: bjson-rpc-api <-> network-db | |
| DEBUG 2020-01-15 16:29:54,795 RemoteEndpoint RemoteEndpoint connection established: network-db <-> bjson-rpc-api | |
| DEBUG 2020-01-15 16:29:54,795 TrioEventBusService EventBus Endpoint discovery connecting to other Endpoints: network-db | |
| INFO 2020-01-15 16:29:54,795 FullServer Running server... | |
| DEBUG 2020-01-15 16:29:54,796 AsyncioEventBusService EventBus Endpoint brequest-server connecting to other Endpoints: networking | |
| DEBUG 2020-01-15 16:29:54,796 AsyncioEventBusService EventBus Endpoint bjson-rpc-api connecting to other Endpoints: networking | |
| DEBUG 2020-01-15 16:29:54,796 ETHPeerPoolEventServer Running ETHPeerPoolEventServer | |
| DEBUG 2020-01-15 16:29:54,797 AsyncioEventBusService EventBus Endpoint btxcomponent connecting to other Endpoints: networking | |
| DEBUG 2020-01-15 16:29:54,799 Connection Opened connection to /home/veox/.local/share/trinity/goerli/ipcs-eth1/network-db.ipc: <trio.SocketStream object at 0x7f4506e3d150> | |
| DEBUG 2020-01-15 16:29:54,800 RemoteEndpoint RemoteEndpoint connection established: network-db <-> discovery | |
| DEBUG 2020-01-15 16:29:54,800 RemoteEndpoint RemoteEndpoint connection established: discovery <-> network-db | |
| DEBUG 2020-01-15 16:29:54,802 TrioEventBusService EventBus Endpoint discovery connecting to other Endpoints: networking | |
| INFO 2020-01-15 16:29:54,802 FullServer enode://8d504879f91eb9f27ddd1754f632b6e8e727d9b45605a31a5c4c4f806d34480412da23ce181fc7a33906af019930ae6a4273402de9e2940eaad8f9b078353095@0.0.0.0:33333 | |
| INFO 2020-01-15 16:29:54,802 FullServer network: 5 | |
| INFO 2020-01-15 16:29:54,802 FullServer peers: max_peers=1 | |
| DEBUG 2020-01-15 16:29:54,803 RemoteEndpoint RemoteEndpoint connection established: networking <-> network-db | |
| DEBUG 2020-01-15 16:29:54,803 RemoteEndpoint RemoteEndpoint connection established: networking <-> brequest-server | |
| DEBUG 2020-01-15 16:29:54,803 RemoteEndpoint RemoteEndpoint connection established: network-db <-> networking | |
| DEBUG 2020-01-15 16:29:54,804 RemoteEndpoint RemoteEndpoint connection established: networking <-> bjson-rpc-api | |
| INFO 2020-01-15 16:29:54,804 ETHPeerPool Connected peers: 0 inbound, 0 outbound | |
| DEBUG 2020-01-15 16:29:54,804 ETHPeerPool Peer subscribers: 2, longest queue: EventBusEth1PeerTracker(0) | |
| DEBUG 2020-01-15 16:29:54,804 ETHPeerPool == Peer details == | |
| DEBUG 2020-01-15 16:29:54,804 ETHPeerPool == End peer details == | |
| DEBUG 2020-01-15 16:29:54,804 RemoteEndpoint RemoteEndpoint connection established: brequest-server <-> networking | |
| DEBUG 2020-01-15 16:29:54,804 RemoteEndpoint RemoteEndpoint connection established: bjson-rpc-api <-> networking | |
| DEBUG 2020-01-15 16:29:54,804 RemoteEndpoint RemoteEndpoint connection established: networking <-> btxcomponent | |
| DEBUG 2020-01-15 16:29:54,804 Connection Opened connection to /home/veox/.local/share/trinity/goerli/ipcs-eth1/networking.ipc: <trio.SocketStream object at 0x7f4505dde510> | |
| DEBUG 2020-01-15 16:29:54,805 RemoteEndpoint RemoteEndpoint connection established: btxcomponent <-> networking | |
| DEBUG 2020-01-15 16:29:54,805 RemoteEndpoint RemoteEndpoint connection established: networking <-> discovery | |
| DEBUG 2020-01-15 16:29:54,806 RemoteEndpoint RemoteEndpoint connection established: discovery <-> networking | |
| INFO 2020-01-15 16:29:54,806 FullChainSyncer Starting regular sync; current head: <BlockHeader #226322 4a1ea5b9> | |
| DEBUG 2020-01-15 16:29:54,812 RegularChainBodySyncer Regular sync waiting for first header to arrive | |
| DEBUG 2020-01-15 16:29:59,815 HeaderMeatSyncer Header Skeleton Gaps: active=0 queued=0 max=50 | |
| DEBUG 2020-01-15 16:30:02,618 FullServer Could not complete handshake with ('94.176.237.140', 34914): Failed to decrypt both EIP8 handshake: Failed to verify tag and non-EIP8 handshake: wrong ecies header | |
| DEBUG 2020-01-15 16:30:04,816 HeaderMeatSyncer Header Skeleton Gaps: active=0 queued=0 max=50 | |
| DEBUG 2020-01-15 16:30:09,819 HeaderMeatSyncer Header Skeleton Gaps: active=0 queued=0 max=50 | |
| DEBUG 2020-01-15 16:30:14,818 HeaderMeatSyncer Header Skeleton Gaps: active=0 queued=0 max=50 | |
| DEBUG 2020-01-15 16:30:19,822 HeaderMeatSyncer Header Skeleton Gaps: active=0 queued=0 max=50 | |
| DEBUG 2020-01-15 16:30:24,823 HeaderMeatSyncer Header Skeleton Gaps: active=0 queued=0 max=50 | |
| DEBUG 2020-01-15 16:30:25,600 Transport Receiving auth handshake from Address(176.223.135.35:udp:53476|tcp:53476) | |
| DEBUG 2020-01-15 16:30:29,829 HeaderMeatSyncer Header Skeleton Gaps: active=0 queued=0 max=50 | |
| DEBUG 2020-01-15 16:30:34,830 HeaderMeatSyncer Header Skeleton Gaps: active=0 queued=0 max=50 | |
| DEBUG 2020-01-15 16:30:37,658 FullServer Could not complete handshake with ('94.176.237.140', 35212): Failed to decrypt both EIP8 handshake: Failed to verify tag and non-EIP8 handshake: wrong ecies header | |
| DEBUG 2020-01-15 16:30:39,836 HeaderMeatSyncer Header Skeleton Gaps: active=0 queued=0 max=50 | |
| DEBUG 2020-01-15 16:30:44,836 HeaderMeatSyncer Header Skeleton Gaps: active=0 queued=0 max=50 | |
| DEBUG 2020-01-15 16:30:49,842 HeaderMeatSyncer Header Skeleton Gaps: active=0 queued=0 max=50 | |
| INFO 2020-01-15 16:30:54,815 ETHPeerPool Connected peers: 0 inbound, 0 outbound | |
| DEBUG 2020-01-15 16:30:54,815 ETHPeerPool Peer subscribers: 5, longest queue: EventBusEth1PeerTracker(0) | |
| DEBUG 2020-01-15 16:30:54,815 ETHPeerPool == Peer details == | |
| DEBUG 2020-01-15 16:30:54,816 ETHPeerPool == End peer details == | |
| DEBUG 2020-01-15 16:30:54,843 HeaderMeatSyncer Header Skeleton Gaps: active=0 queued=0 max=50 | |
| DEBUG 2020-01-15 16:30:59,845 HeaderMeatSyncer Header Skeleton Gaps: active=0 queued=0 max=50 | |
| DEBUG 2020-01-15 16:31:00,660 Transport Receiving auth handshake from Address(176.223.135.35:udp:53744|tcp:53744) | |
| DEBUG 2020-01-15 16:31:00,683 Manager New root task run[daemon=False] added to DAG | |
| DEBUG 2020-01-15 16:31:00,684 Manager <Manager service=<ResponseCandidateStream(Connection-<Session <Node(0x59d62a@176.223.135.35)> 6d789a13-0446-4f4c-b0e0-cc3d2d9456b4>, <class 'trinity.protocol.eth.commands.BlockBodies'>)> started=True running=True cancelled=False stopping=False finished=False did_error=False>: _handle_cancelled waiting for cancellation | |
| DEBUG 2020-01-15 16:31:00,685 Manager <Manager service=<ResponseCandidateStream(Connection-<Session <Node(0x59d62a@176.223.135.35)> 6d789a13-0446-4f4c-b0e0-cc3d2d9456b4>, <class 'trinity.protocol.eth.commands.BlockBodies'>)> started=True running=True cancelled=False stopping=False finished=False did_error=False>: _handle_stopping waiting for stopping | |
| DEBUG 2020-01-15 16:31:00,685 Manager running task 'run[daemon=False]' | |
| DEBUG 2020-01-15 16:31:00,685 ResponseCandidateStream Launching <ResponseCandidateStream(Connection-<Session <Node(0x59d62a@176.223.135.35)> 6d789a13-0446-4f4c-b0e0-cc3d2d9456b4>, <class 'trinity.protocol.eth.commands.BlockBodies'>)> | |
| DEBUG 2020-01-15 16:31:00,686 Manager New root task run[daemon=False] added to DAG | |
| DEBUG 2020-01-15 16:31:00,686 Manager <Manager service=<ResponseCandidateStream(Connection-<Session <Node(0x59d62a@176.223.135.35)> 6d789a13-0446-4f4c-b0e0-cc3d2d9456b4>, <class 'trinity.protocol.eth.commands.BlockHeaders'>)> started=True running=True cancelled=False stopping=False finished=False did_error=False>: _handle_cancelled waiting for cancellation | |
| DEBUG 2020-01-15 16:31:00,686 Manager <Manager service=<ResponseCandidateStream(Connection-<Session <Node(0x59d62a@176.223.135.35)> 6d789a13-0446-4f4c-b0e0-cc3d2d9456b4>, <class 'trinity.protocol.eth.commands.BlockHeaders'>)> started=True running=True cancelled=False stopping=False finished=False did_error=False>: _handle_stopping waiting for stopping | |
| DEBUG 2020-01-15 16:31:00,687 Manager running task 'run[daemon=False]' | |
| DEBUG 2020-01-15 16:31:00,687 ResponseCandidateStream Launching <ResponseCandidateStream(Connection-<Session <Node(0x59d62a@176.223.135.35)> 6d789a13-0446-4f4c-b0e0-cc3d2d9456b4>, <class 'trinity.protocol.eth.commands.BlockHeaders'>)> | |
| DEBUG 2020-01-15 16:31:00,687 Manager New root task run[daemon=False] added to DAG | |
| DEBUG 2020-01-15 16:31:00,687 Manager <Manager service=<ResponseCandidateStream(Connection-<Session <Node(0x59d62a@176.223.135.35)> 6d789a13-0446-4f4c-b0e0-cc3d2d9456b4>, <class 'trinity.protocol.eth.commands.NodeData'>)> started=True running=True cancelled=False stopping=False finished=False did_error=False>: _handle_cancelled waiting for cancellation | |
| DEBUG 2020-01-15 16:31:00,687 Manager <Manager service=<ResponseCandidateStream(Connection-<Session <Node(0x59d62a@176.223.135.35)> 6d789a13-0446-4f4c-b0e0-cc3d2d9456b4>, <class 'trinity.protocol.eth.commands.NodeData'>)> started=True running=True cancelled=False stopping=False finished=False did_error=False>: _handle_stopping waiting for stopping | |
| DEBUG 2020-01-15 16:31:00,688 Manager running task 'run[daemon=False]' | |
| DEBUG 2020-01-15 16:31:00,688 ResponseCandidateStream Launching <ResponseCandidateStream(Connection-<Session <Node(0x59d62a@176.223.135.35)> 6d789a13-0446-4f4c-b0e0-cc3d2d9456b4>, <class 'trinity.protocol.eth.commands.NodeData'>)> | |
| DEBUG 2020-01-15 16:31:00,688 Manager New root task run[daemon=False] added to DAG | |
| DEBUG 2020-01-15 16:31:00,688 Manager <Manager service=<ResponseCandidateStream(Connection-<Session <Node(0x59d62a@176.223.135.35)> 6d789a13-0446-4f4c-b0e0-cc3d2d9456b4>, <class 'trinity.protocol.eth.commands.Receipts'>)> started=True running=True cancelled=False stopping=False finished=False did_error=False>: _handle_cancelled waiting for cancellation | |
| DEBUG 2020-01-15 16:31:00,688 Manager <Manager service=<ResponseCandidateStream(Connection-<Session <Node(0x59d62a@176.223.135.35)> 6d789a13-0446-4f4c-b0e0-cc3d2d9456b4>, <class 'trinity.protocol.eth.commands.Receipts'>)> started=True running=True cancelled=False stopping=False finished=False did_error=False>: _handle_stopping waiting for stopping | |
| DEBUG 2020-01-15 16:31:00,689 Manager running task 'run[daemon=False]' | |
| DEBUG 2020-01-15 16:31:00,689 ResponseCandidateStream Launching <ResponseCandidateStream(Connection-<Session <Node(0x59d62a@176.223.135.35)> 6d789a13-0446-4f4c-b0e0-cc3d2d9456b4>, <class 'trinity.protocol.eth.commands.Receipts'>)> | |
| DEBUG 2020-01-15 16:31:00,689 DAOCheckBootManager <trinity.protocol.common.boot.DAOCheckBootManager object at 0x7f059068d7d0> had nothing left to do, ceasing operation... | |
| DEBUG 2020-01-15 16:31:00,690 DAOCheckBootManager <trinity.protocol.common.boot.DAOCheckBootManager object at 0x7f059068d7d0> halted cleanly | |
| INFO 2020-01-15 16:31:00,694 ETHPeerPool Adding ETHPeer <Session <Node(0x59d62a@176.223.135.35)> 6d789a13-0446-4f4c-b0e0-cc3d2d9456b4> to pool | |
| DEBUG 2020-01-15 16:31:00,696 PeerTracker tracking inbound peer connection: ETHPeer <Session <Node(0x59d62a@176.223.135.35)> 6d789a13-0446-4f4c-b0e0-cc3d2d9456b4> | |
| DEBUG 2020-01-15 16:31:00,702 Manager running task '_quiet_handle_msg[daemon=False]' | |
| DEBUG 2020-01-15 16:31:00,702 PeerRequestHandler ETHProxyPeer <Session <Node(0x59d62a@176.223.135.35)> 6d789a13-0446-4f4c-b0e0-cc3d2d9456b4> requested headers: BlockHeadersQuery(block_number_or_hash=1736703, max_headers=1, skip=0, reverse=False) | |
| DEBUG 2020-01-15 16:31:00,704 PeerRequestHandler Peer requested header number 1736703 that is unavailable, stopping search. | |
| DEBUG 2020-01-15 16:31:00,705 Manager task '_quiet_handle_msg[daemon=False]' finished. | |
| DEBUG 2020-01-15 16:31:00,705 ETHHeaderChainSyncer ETHPeer <Session <Node(0x59d62a@176.223.135.35)> 6d789a13-0446-4f4c-b0e0-cc3d2d9456b4> announced Head TD 3025443, which is higher than ours (382579), starting sync | |
| DEBUG 2020-01-15 16:31:00,705 Manager running task '_handle_tx[daemon=False]' | |
| DEBUG 2020-01-15 16:31:00,705 Manager task '_handle_tx[daemon=False]' finished. | |
| DEBUG 2020-01-15 16:31:00,711 SkeletonSyncer Requsting chain of headers from ETHPeer <Session <Node(0x59d62a@176.223.135.35)> 6d789a13-0446-4f4c-b0e0-cc3d2d9456b4> starting at #191322 | |
| DEBUG 2020-01-15 16:31:00,711 ETHProxyPeer Starting Proxy Peer ETHProxyPeer <Session <Node(0x59d62a@176.223.135.35)> 6d789a13-0446-4f4c-b0e0-cc3d2d9456b4> | |
| DEBUG 2020-01-15 16:31:00,993 SkeletonSyncer Requsting chain of headers from ETHPeer <Session <Node(0x59d62a@176.223.135.35)> 6d789a13-0446-4f4c-b0e0-cc3d2d9456b4> starting at #226267 | |
| DEBUG 2020-01-15 16:31:01,071 SkeletonSyncer During header sync launch, skipping over (56) already stored headers 226267-226322: <BlockHeader #226267 965a7d5c>..<BlockHeader #226322 4a1ea5b9> | |
| DEBUG 2020-01-15 16:31:01,080 SkeletonSyncer Requsting chain of headers from ETHPeer <Session <Node(0x59d62a@176.223.135.35)> 6d789a13-0446-4f4c-b0e0-cc3d2d9456b4> starting at #226651 | |
| DEBUG 2020-01-15 16:31:01,081 ETHHeaderChainSyncer Skeleton syncer asserts that parent (4a1e..3674) of the first header (<BlockHeader #226323 e36c00fb>) is already present | |
| DEBUG 2020-01-15 16:31:01,090 RegularChainBodySyncer Regular sync first header arrived | |
| DEBUG 2020-01-15 16:31:01,094 RegularChainBodySyncer Requesting block bodies for 9 headers from ETHPeer <Session <Node(0x59d62a@176.223.135.35)> 6d789a13-0446-4f4c-b0e0-cc3d2d9456b4> | |
| DEBUG 2020-01-15 16:31:01,125 SkeletonSyncer Requsting chain of headers from ETHPeer <Session <Node(0x59d62a@176.223.135.35)> 6d789a13-0446-4f4c-b0e0-cc3d2d9456b4> starting at #226652 | |
| DEBUG 2020-01-15 16:31:01,164 RegularChainBodySyncer Got block bodies for 9/9 headers from ETHPeer <Session <Node(0x59d62a@176.223.135.35)> 6d789a13-0446-4f4c-b0e0-cc3d2d9456b4>, from BlockHeader(parent_hash=b'J\x1e\xa5\xb9`\xd25\xff!\x9d\xe3h\xda\x0f\xb2\xfa\xee-\xef5\x17\xadY\x0e5\\O\xf2]\x8d6t', uncles_hash=b'\x1d\xccM\xe8\xde\xc7]z\xab\x85\xb5g\xb6\xcc\xd4\x1a\xd3\x12E\x1b\x94\x8at\x13\xf0\xa1B\xfd@\xd4\x93G', coinbase=b'\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00', state_root=b'\x05\xb3<\xb4\xb3sr\xb6d\xc0\xa3\xdb|\x1a\xef\xf40Hh\xa9yg7"\xc8Ao\x99\x18UX\x08', transaction_root=b'\xc1\x9ay\x0c\x99_\xc0\x0ez\x16\x9a\xbc\xbe\x1f\x80Wz(\x1br\x1d\xa0\xcb\xec99\xfb\xecc{\xc0B', receipt_root=b'S\x84\xd1\x02\xb75\x13\xbfMa\xcd\xb7I\xc0o\xd0\x0c\xe6\x1d\xebi\x13\xae\xc9[^l\x85\xd3 \x85\x1a', bloom=0, difficulty=2, block_number=226323, gas_limit=8000000, gas_used=346098, timestamp=1552386731, extra_data=b'\xde\x83\x02\x04\x00\x8fParity-Ethereum\x861.32.0\x82li\x00\x97T\x89\xff>\xefk"f\xf7S:\xab\xfa\x0b\x89T\x88m\xe22 =\xe7sp\x84\xf9_\xed\xf5\xb8\x0b\x8e\xa3\xc1q\xbc\xfa\x05\x13q\x16<\xdf\x904\x08t\x1c\x17\x02\xf0Z\xc80\x19\'\xf6\x8d\t\x1c\x90B\x01', mix_hash=b'\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00', nonce=b'\x00\x00\x00\x00\x00\x00\x00\x00')..BlockHeader(parent_hash=b'\xfb\n[\x05\x1f\xd0\x02\xa8.~\x1a\xf9\xaa\x84\xa8g\x0b\x0ci\xdf\xfb\x11L \x8c\x8eQK\x83\xe7\xd2\x83', uncles_hash=b'\x1d\xccM\xe8\xde\xc7]z\xab\x85\xb5g\xb6\xcc\xd4\x1a\xd3\x12E\x1b\x94\x8at\x13\xf0\xa1B\xfd@\xd4\x93G', coinbase=b'\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00', state_root=b'I~\x1a4\xc9\xf0\x01\xee<\xdb\x9b\xdd\x82\xd9p\xc2\xbc\xd0\x18X/\xdd\x9fR\xa3\x83\xca\xffqU,\xbf', transaction_root=b'V\x8fK\xd37\x84h\x17\xdbM\xd3\xc3\x01\xa7}\x93\x83\xef\x9fB\xe1\xec\xaf\x99\x1f\xcac\xb1Pe\x9aA', receipt_root=b"q.1'i6\x91\xac\x94\x0e\x8fc\xd6\x86k\xfd5zn\xeaf\r\xef\x8a\xe4(\x92{\xab\xcd\xe7\xfe", bloom=13366000438973476717068376854987641316721647255870289708683845803496403164071558880460210236396448739650683072133609383579731178401290226152575197940180801197334872293675574622413530702784450158573144263320982997277304431188607524713721265968335485226022257041022470832092370253487135155614148790531556494632726292158629604797497298412831722283425648721810057656693910462192115156774779044686112795961725137286184156766013868193581439704297594028553315594364510710074993233563641107516787750918041949279885393094204169219329231646749462877218020714610976515750422347182357559509751894068166656, difficulty=2, block_number=226448, gas_limit=8000000, gas_used=33496, timestamp=1552388606, extra_data=b'\xd8\x83\x01\x08\x17\x84geth\x88go1.10.4\x85linux\x00\x00\x00\x00\x00\x00\x00\xda\x9d\x16Yz\x16\xe0l8\xed\xa5\xc0\x90\xd83\x0b\x90^\x1a\x02j3Y\xbdV~\t2\x13\xaar\xcaB\xa3c6R\xb23\x8d\xcd\xa0d\xd2<\x05\xb4\xce7\x04\xf3l*\xf3~JT\xb2\xd5\x069y\x94\xfd\x01', mix_hash=b'\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00', nonce=b'\x00\x00\x00\x00\x00\x00\x00\x00') | |
| INFO 2020-01-15 16:31:01,171 RegularChainBodySyncer Waited 0.1s for <BlockHeader #226323 e36c00fb> body | |
| DEBUG 2020-01-15 16:31:01,171 RegularChainBodySyncer Requesting block bodies for 1 headers from ETHPeer <Session <Node(0x59d62a@176.223.135.35)> 6d789a13-0446-4f4c-b0e0-cc3d2d9456b4> | |
| DEBUG 2020-01-15 16:31:01,363 SkeletonSyncer Requsting chain of headers from ETHPeer <Session <Node(0x59d62a@176.223.135.35)> 6d789a13-0446-4f4c-b0e0-cc3d2d9456b4> starting at #240233 | |
| DEBUG 2020-01-15 16:31:01,398 RegularChainBodySyncer Got block bodies for 1/1 headers from ETHPeer <Session <Node(0x59d62a@176.223.135.35)> 6d789a13-0446-4f4c-b0e0-cc3d2d9456b4>, from BlockHeader(parent_hash=b'Y\x17\xdd\x0f*L\x96DR\xdem\x05\xeeu\x05V\x85\x14\xd4\x0b\x82\x9b\x80\x9fJ\x9d\xa6f\x10\xd0\x861', uncles_hash=b'\x1d\xccM\xe8\xde\xc7]z\xab\x85\xb5g\xb6\xcc\xd4\x1a\xd3\x12E\x1b\x94\x8at\x13\xf0\xa1B\xfd@\xd4\x93G', coinbase=b'\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00', state_root=b'\xa7\xa0\xccS\xa6B\xd8\x13\x1c\xd5\x98Y_\xea\xd4\x02N\xf7\xf4`o\xaa\x7f\x04dB\x8b\x80\x1e\xe19\x98', transaction_root=b'\x16\x12\xe9#\x14I\xcb\xb6F\x9f\xba\x13\x9f/2\xfb\xber\xa0\x90\xbd\x82Y\x95\xcd\xd8bi\x15Y\xc4\x04', receipt_root=b'J[\xaf\x7f\xbc\xa5\xb2\xcd\xfc\xb0\x1c\x06|\xe0\xe0\xec\xb8f\xe3j$ua\xd8\x10\xd6\xba\xe0\xc9r\x1c"', bloom=0, difficulty=1, block_number=226456, gas_limit=8000000, gas_used=55678, timestamp=1552388726, extra_data=b"\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00]\x15\xf96^\xe0\x04'\x04\xf5\xf3gX\xbd\xa8\x8a\xec(O\xab\t4\xa3\xcf\xda\xac*K{H\xdf\xa3cr\xd4\x17\xac\xa9\x9e\xa0\xb2G[c\x0f\x8ev\x19Z}t=\xcb\xc8\xb5?V\xe1\xed\xb2\x9fQx^\x01", mix_hash=b'\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00', nonce=b'\x00\x00\x00\x00\x00\x00\x00\x00')..BlockHeader(parent_hash=b'Y\x17\xdd\x0f*L\x96DR\xdem\x05\xeeu\x05V\x85\x14\xd4\x0b\x82\x9b\x80\x9fJ\x9d\xa6f\x10\xd0\x861', uncles_hash=b'\x1d\xccM\xe8\xde\xc7]z\xab\x85\xb5g\xb6\xcc\xd4\x1a\xd3\x12E\x1b\x94\x8at\x13\xf0\xa1B\xfd@\xd4\x93G', coinbase=b'\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00', state_root=b'\xa7\xa0\xccS\xa6B\xd8\x13\x1c\xd5\x98Y_\xea\xd4\x02N\xf7\xf4`o\xaa\x7f\x04dB\x8b\x80\x1e\xe19\x98', transaction_root=b'\x16\x12\xe9#\x14I\xcb\xb6F\x9f\xba\x13\x9f/2\xfb\xber\xa0\x90\xbd\x82Y\x95\xcd\xd8bi\x15Y\xc4\x04', receipt_root=b'J[\xaf\x7f\xbc\xa5\xb2\xcd\xfc\xb0\x1c\x06|\xe0\xe0\xec\xb8f\xe3j$ua\xd8\x10\xd6\xba\xe0\xc9r\x1c"', bloom=0, difficulty=1, block_number=226456, gas_limit=8000000, gas_used=55678, timestamp=1552388726, extra_data=b"\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00]\x15\xf96^\xe0\x04'\x04\xf5\xf3gX\xbd\xa8\x8a\xec(O\xab\t4\xa3\xcf\xda\xac*K{H\xdf\xa3cr\xd4\x17\xac\xa9\x9e\xa0\xb2G[c\x0f\x8ev\x19Z}t=\xcb\xc8\xb5?V\xe1\xed\xb2\x9fQx^\x01", mix_hash=b'\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00', nonce=b'\x00\x00\x00\x00\x00\x00\x00\x00') | |
| DEBUG 2020-01-15 16:31:01,452 PetersburgVM No block reward given to b'\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00' | |
| DEBUG 2020-01-15 16:31:01,462 SkeletonSyncer Got new header bones from ETHPeer <Session <Node(0x59d62a@176.223.135.35)> 6d789a13-0446-4f4c-b0e0-cc3d2d9456b4>: <BlockHeader #226651 e2e7eddf>-<BlockHeader #263706 8eb15db9> | |
| DEBUG 2020-01-15 16:31:01,464 HeaderMeatSyncer Requesting 1 headers from ETHPeer <Session <Node(0x59d62a@176.223.135.35)> 6d789a13-0446-4f4c-b0e0-cc3d2d9456b4> | |
| DEBUG 2020-01-15 16:31:01,474 HeaderMeatSyncer Requesting 192 headers from ETHPeer <Session <Node(0x59d62a@176.223.135.35)> 6d789a13-0446-4f4c-b0e0-cc3d2d9456b4> | |
| DEBUG 2020-01-15 16:31:01,558 RegularChainBodySyncer <coroutine object RegularChainBodySyncer._import_ready_blocks at 0x7f0592f97d40> finished while <trinity.sync.full.chain.RegularChainBodySyncer object at 0x7f0592f88150> is still running, terminating as well | |
| DEBUG 2020-01-15 16:31:01,559 RegularChainBodySyncer Cancelling service: <trinity.sync.full.chain.RegularChainBodySyncer object at 0x7f0592f88150> | |
| [1m[33m WARNING 2020-01-15 16:31:01,559 RegularChainBodySyncer Task <coroutine object RegularChainBodySyncer._import_ready_blocks at 0x7f05926eaa70> finished unexpectedly: ValidationError('Mismatch between block and imported block on 1 fields:\n - header.state_root:\n (actual) : b"X.\\x0e,\\xc2xA\\xed\\xb4Y\\x15w\\x93\\xa3\\xc1\\xe1./\\x15\\xbf\\x11\'\\x12\\xd6\\xa8\\x85q\\xdf\\xb2\\xd9\\x1a0"\n (expected): b\'\\x05\\xb3<\\xb4\\xb3sr\\xb6d\\xc0\\xa3\\xdb|\\x1a\\xef\\xf40Hh\\xa9yg7"\\xc8Ao\\x99\\x18UX\\x08\'')[0m | |
| DEBUG 2020-01-15 16:31:01,559 RegularChainBodySyncer Task failure traceback | |
| Traceback (most recent call last): | |
| File "/home/veox/src/trinity/p2p/service.py", line 158, in _run_task_wrapper | |
| await awaitable | |
| File "/home/veox/src/trinity/p2p/service.py", line 179, in _run_daemon_task_wrapper | |
| await awaitable | |
| File "/home/veox/src/trinity/trinity/sync/full/chain.py", line 1137, in _import_ready_blocks | |
| await self._import_block(block) | |
| File "/home/veox/src/trinity/trinity/sync/full/chain.py", line 1142, in _import_block | |
| self._block_importer.import_block(block) | |
| File "/home/veox/src/trinity/p2p/cancellable.py", line 20, in wait | |
| return await self.wait_first(awaitable, token=token, timeout=timeout) | |
| File "/home/veox/src/trinity/p2p/cancellable.py", line 43, in wait_first | |
| return await token_chain.cancellable_wait(*awaitables, timeout=timeout) | |
| File "/home/veox/src/trinity/.virtualenv/trinity/lib/python3.7/site-packages/cancel_token/token.py", line 178, in cancellable_wait | |
| return done.pop().result() | |
| File "/home/veox/src/trinity/trinity/sync/common/chain.py", line 297, in import_block | |
| return await self._chain.coro_import_block(block, perform_validation=True) | |
| File "/home/veox/src/trinity/trinity/_utils/async_dispatch.py", line 25, in wrapper | |
| *args | |
| File "/usr/lib64/python3.7/concurrent/futures/thread.py", line 57, in run | |
| result = self.fn(*self.args, **self.kwargs) | |
| File "/home/veox/src/trinity/.virtualenv/trinity/lib/python3.7/site-packages/eth/chains/base.py", line 473, in import_block | |
| validate_imported_block_unchanged(imported_block, block) | |
| File "cytoolz/functoolz.pyx", line 250, in cytoolz.functoolz.curry.__call__ | |
| File "/home/veox/src/trinity/.virtualenv/trinity/lib/python3.7/site-packages/eth/_utils/rlp.py", line 89, in validate_rlp_equal | |
| raise ValidationError(error_message) | |
| eth_utils.exceptions.ValidationError: Mismatch between block and imported block on 1 fields: | |
| - header.state_root: | |
| (actual) : b"X.\x0e,\xc2xA\xed\xb4Y\x15w\x93\xa3\xc1\xe1./\x15\xbf\x11'\x12\xd6\xa8\x85q\xdf\xb2\xd9\x1a0" | |
| (expected): b'\x05\xb3<\xb4\xb3sr\xb6d\xc0\xa3\xdb|\x1a\xef\xf40Hh\xa9yg7"\xc8Ao\x99\x18UX\x08' | |
| DEBUG 2020-01-15 16:31:01,563 RegularChainBodySyncer <trinity.sync.full.chain.RegularChainBodySyncer object at 0x7f0592f88150> finished: Cancellation requested by RegularChainBodySyncer:RegularChainSyncer:FullChainSyncer:FullNode token | |
| DEBUG 2020-01-15 16:31:01,564 RegularChainBodySyncer <trinity.sync.full.chain.RegularChainBodySyncer object at 0x7f0592f88150>: Waiting for tasks (4): [<Task pending coro=<RegularChainBodySyncer._preview_ready_blocks() running at /home/veox/src/trinity/p2p/service.py:158> wait_for=<Future pending cb=[<TaskWakeupMethWrapper object at 0x7f0590369f50>()]>>, <Task pending coro=<RegularChainBodySyncer._display_stats() running at /home/veox/src/trinity/p2p/service.py:158> wait_for=<Future pending cb=[<TaskWakeupMethWrapper object at 0x7f0592730fd0>()]>>, <Task pending coro=<BaseBodyChainSyncer._assign_body_download_to_peers() running at /home/veox/src/trinity/p2p/service.py:158> wait_for=<Future pending cb=[<TaskWakeupMethWrapper object at 0x7f05707c4090>()]>>, <Task pending coro=<RegularChainBodySyncer._launch_prerequisite_tasks() running at /home/veox/src/trinity/p2p/service.py:158> wait_for=<Future pending cb=[<TaskWakeupMethWrapper object at 0x7f0592730f10>()]>>] | |
| DEBUG 2020-01-15 16:31:01,565 RegularChainBodySyncer All tasks finished | |
| DEBUG 2020-01-15 16:31:01,565 RegularChainBodySyncer <trinity.sync.full.chain.RegularChainBodySyncer object at 0x7f0592f88150> halted cleanly | |
| DEBUG 2020-01-15 16:31:01,565 RegularChainSyncer <trinity.sync.full.chain.RegularChainBodySyncer object at 0x7f0592f88150> finished while <trinity.sync.full.chain.RegularChainSyncer object at 0x7f059581c450> is still running, terminating as well | |
| DEBUG 2020-01-15 16:31:01,565 RegularChainSyncer Cancelling service: <trinity.sync.full.chain.RegularChainSyncer object at 0x7f059581c450> | |
| DEBUG 2020-01-15 16:31:01,566 RegularChainSyncer <trinity.sync.full.chain.RegularChainSyncer object at 0x7f059581c450> cancelled, cleaning up... | |
| DEBUG 2020-01-15 16:31:01,566 RegularChainSyncer <trinity.sync.full.chain.RegularChainSyncer object at 0x7f059581c450> waiting for child services: [<trinity.sync.full.chain.RegularChainBodySyncer object at 0x7f0592f88150>, <trinity.protocol.eth.sync.ETHHeaderChainSyncer object at 0x7f0592f72f50>] | |
| DEBUG 2020-01-15 16:31:01,567 SkeletonSyncer <trinity.sync.common.headers.SkeletonSyncer object at 0x7f0592711dd0> finished: Cancellation requested by RegularChainSyncer:FullChainSyncer:FullNode token | |
| DEBUG 2020-01-15 16:31:01,567 SkeletonSyncer <trinity.sync.common.headers.SkeletonSyncer object at 0x7f0592711dd0>: Waiting for tasks (1): [<Task pending coro=<SkeletonSyncer._display_stats() running at /home/veox/src/trinity/p2p/service.py:158> wait_for=<Future pending cb=[<TaskWakeupMethWrapper object at 0x7f05906194d0>()]>>] | |
| DEBUG 2020-01-15 16:31:01,567 ETHHeaderChainSyncer <trinity.protocol.eth.sync.ETHHeaderChainSyncer object at 0x7f0592f72f50> finished: Cancellation requested by RegularChainSyncer:FullChainSyncer:FullNode token | |
| DEBUG 2020-01-15 16:31:01,567 ETHHeaderChainSyncer <trinity.protocol.eth.sync.ETHHeaderChainSyncer object at 0x7f0592f72f50> waiting for child services: [<trinity.sync.common.headers.SkeletonSyncer object at 0x7f0592711dd0>, <trinity.sync.common.headers.HeaderMeatSyncer object at 0x7f0592f827d0>, <trinity.protocol.eth.monitors.ETHChainTipMonitor object at 0x7f0592f82250>] | |
| DEBUG 2020-01-15 16:31:01,568 ETHChainTipMonitor <trinity.protocol.eth.monitors.ETHChainTipMonitor object at 0x7f0592f82250> finished: Cancellation requested by ETHHeaderChainSyncer:RegularChainSyncer:FullChainSyncer:FullNode token | |
| DEBUG 2020-01-15 16:31:01,568 ETHChainTipMonitor <trinity.protocol.eth.monitors.ETHChainTipMonitor object at 0x7f0592f82250>: Waiting for tasks (1): [<Task pending coro=<BaseChainTipMonitor._handle_msg_loop() running at /home/veox/src/trinity/p2p/service.py:158> wait_for=<Task cancelled coro=<Queue.get() done, defined at /usr/lib64/python3.7/asyncio/queues.py:150>>>] | |
| DEBUG 2020-01-15 16:31:01,568 HeaderMeatSyncer <trinity.sync.common.headers.HeaderMeatSyncer object at 0x7f0592f827d0> finished: Cancellation requested by ETHHeaderChainSyncer:RegularChainSyncer:FullChainSyncer:FullNode token | |
| DEBUG 2020-01-15 16:31:01,569 HeaderMeatSyncer <trinity.sync.common.headers.HeaderMeatSyncer object at 0x7f0592f827d0>: Waiting for tasks (2): [<Task pending coro=<HeaderMeatSyncer._display_stats() running at /home/veox/src/trinity/p2p/service.py:158> wait_for=<Task cancelled coro=<sleep() done, defined at /usr/lib64/python3.7/asyncio/tasks.py:582>>>, <Task pending coro=<HeaderMeatSyncer._run_fetch_segment() running at /home/veox/src/trinity/p2p/service.py:158> wait_for=<Future pending cb=[<TaskWakeupMethWrapper object at 0x7f057067cc50>()]>>] | |
| DEBUG 2020-01-15 16:31:01,569 SkeletonSyncer All tasks finished | |
| DEBUG 2020-01-15 16:31:01,569 SkeletonSyncer <trinity.sync.common.headers.SkeletonSyncer object at 0x7f0592711dd0> halted cleanly | |
| DEBUG 2020-01-15 16:31:01,569 ETHChainTipMonitor All tasks finished | |
| DEBUG 2020-01-15 16:31:01,569 ETHChainTipMonitor <trinity.protocol.eth.monitors.ETHChainTipMonitor object at 0x7f0592f82250> halted cleanly | |
| DEBUG 2020-01-15 16:31:01,570 HeaderMeatSyncer Service cancellation while fetching segment, dropping ETHPeer <Session <Node(0x59d62a@176.223.135.35)> 6d789a13-0446-4f4c-b0e0-cc3d2d9456b4> from queue | |
| Traceback (most recent call last): | |
| File "/home/veox/src/trinity/trinity/sync/common/headers.py", line 668, in _run_fetch_segment | |
| completed_headers = await peer.wait(self._fetch_segment(peer, parent_header, length)) | |
| File "/home/veox/src/trinity/p2p/cancellable.py", line 20, in wait | |
| return await self.wait_first(awaitable, token=token, timeout=timeout) | |
| File "/home/veox/src/trinity/p2p/cancellable.py", line 43, in wait_first | |
| return await token_chain.cancellable_wait(*awaitables, timeout=timeout) | |
| File "/home/veox/src/trinity/.virtualenv/trinity/lib/python3.7/site-packages/cancel_token/token.py", line 178, in cancellable_wait | |
| return done.pop().result() | |
| File "/home/veox/src/trinity/trinity/sync/common/headers.py", line 743, in _fetch_segment | |
| SEAL_CHECK_RANDOM_SAMPLE_RATE, | |
| File "/home/veox/src/trinity/p2p/cancellable.py", line 20, in wait | |
| return await self.wait_first(awaitable, token=token, timeout=timeout) | |
| File "/home/veox/src/trinity/p2p/cancellable.py", line 43, in wait_first | |
| return await token_chain.cancellable_wait(*awaitables, timeout=timeout) | |
| File "/home/veox/src/trinity/.virtualenv/trinity/lib/python3.7/site-packages/cancel_token/token.py", line 176, in cancellable_wait | |
| "Cancellation requested by {} token".format(self.triggered_token) | |
| cancel_token.exceptions.OperationCancelled: Cancellation requested by HeaderMeatSyncer:ETHHeaderChainSyncer:RegularChainSyncer:FullChainSyncer:FullNode token | |
| DEBUG 2020-01-15 16:31:01,571 HeaderMeatSyncer All tasks finished | |
| DEBUG 2020-01-15 16:31:01,571 HeaderMeatSyncer <trinity.sync.common.headers.HeaderMeatSyncer object at 0x7f0592f827d0> halted cleanly | |
| DEBUG 2020-01-15 16:31:01,571 ETHHeaderChainSyncer All child services finished | |
| DEBUG 2020-01-15 16:31:01,571 ETHHeaderChainSyncer <trinity.protocol.eth.sync.ETHHeaderChainSyncer object at 0x7f0592f72f50> halted cleanly | |
| DEBUG 2020-01-15 16:31:01,572 RegularChainSyncer All child services finished | |
| DEBUG 2020-01-15 16:31:01,572 RegularChainSyncer <trinity.sync.full.chain.RegularChainSyncer object at 0x7f059581c450> halted cleanly | |
| DEBUG 2020-01-15 16:31:01,572 FullChainSyncer <trinity.sync.full.service.FullChainSyncer object at 0x7f05957ef410> had nothing left to do, ceasing operation... | |
| DEBUG 2020-01-15 16:31:01,572 FullChainSyncer <trinity.sync.full.service.FullChainSyncer object at 0x7f05957ef410> halted cleanly | |
| [1m[31m ERROR 2020-01-15 16:31:01,572 Sync Sync ended unexpectedly. Shutting down trinity[0m | |
| INFO 2020-01-15 16:31:01,573 ComponentManager Stopping components | |
| DEBUG 2020-01-15 16:31:01,574 Manager <Manager service=<trinity.components.builtin.tx_pool.pool.TxPool object at 0x7f8db5e7b790> started=True running=False cancelled=False stopping=True finished=False did_error=False> stopping | |
| DEBUG 2020-01-15 16:31:01,574 DBManager <trinity.db.manager.DBManager object at 0x7f79264bbdd0>: closing client connection: Connection closed | |
| INFO 2020-01-15 16:31:01,574 TxPool Stopping Tx Pool... | |
| DEBUG 2020-01-15 16:31:01,574 AsyncioEventBusService Cancelling service: <trinity.extensibility.event_bus.AsyncioEventBusService object at 0x7f8dbfd28f10> | |
| DEBUG 2020-01-15 16:31:01,574 Manager <Manager service=<trinity.components.builtin.tx_pool.pool.TxPool object at 0x7f8db5e7b790> started=True running=False cancelled=False stopping=True finished=False did_error=False>: _handle_stopping triggering system task cancellations | |
| [1m[31m ERROR 2020-01-15 16:31:01,628 asyncio Task was destroyed but it is pending! | |
| task: <Task pending coro=<AsyncioIsolatedComponent._do_run() running at /home/veox/src/trinity/trinity/extensibility/asyncio.py:74> wait_for=<Future pending cb=[<TaskWakeupMethWrapper object at 0x7f8db5e7bd10>()]>>[0m | |
| DEBUG 2020-01-15 16:31:01,766 FullNode Cancelling service: <trinity.nodes.full.FullNode object at 0x7f059586cdd0> | |
| DEBUG 2020-01-15 16:31:01,767 FullNode <trinity.nodes.full.FullNode object at 0x7f059586cdd0> finished: Cancellation requested by FullNode token | |
| DEBUG 2020-01-15 16:31:01,767 FullNode <trinity.nodes.full.FullNode object at 0x7f059586cdd0> waiting for child services: [<trinity.server.FullServer object at 0x7f059584ac10>, <trinity.protocol.eth.peer.ETHPeerPoolEventServer object at 0x7f059582ae90>] | |
| DEBUG 2020-01-15 16:31:01,767 DBManager <trinity.db.manager.DBManager object at 0x7f79264bbdd0>: closing client connection: Connection closed | |
| DEBUG 2020-01-15 16:31:01,769 FullServer <trinity.server.FullServer object at 0x7f059584ac10> cancelled, cleaning up... | |
| DEBUG 2020-01-15 16:31:01,770 FullServer <trinity.server.FullServer object at 0x7f059584ac10> waiting for child services: [<trinity.protocol.eth.peer.ETHPeerPool object at 0x7f059584a510>] | |
| DEBUG 2020-01-15 16:31:01,771 ETHPeerPoolEventServer <trinity.protocol.eth.peer.ETHPeerPoolEventServer object at 0x7f059582ae90> finished: Cancellation requested by FullNode token | |
| DEBUG 2020-01-15 16:31:01,772 ETHPeerPoolEventServer <trinity.protocol.eth.peer.ETHPeerPoolEventServer object at 0x7f059582ae90>: Waiting for tasks (13): [<Task pending coro=<PeerPoolEventServer.handle_request_stream() running at /home/veox/src/trinity/p2p/service.py:158> wait_for=<Task cancelled coro=<<async_generator_asend without __name__>()>>>, <Task pending coro=<PeerPoolEventServer.handle_connect_to_node_requests() running at /home/veox/src/trinity/p2p/service.py:158> wait_for=<Task cancelled coro=<<async_generator_asend without __name__>()>>>, <Task pending coro=<PeerPoolEventServer.handle_stream() running at /home/veox/src/trinity/p2p/service.py:158> wait_for=<Task cancelled coro=<<async_generator_asend without __name__>()>>>, <Task pending coro=<PeerPoolEventServer.handle_stream() running at /home/veox/src/trinity/p2p/service.py:158> wait_for=<Task cancelled coro=<<async_generator_asend without __name__>()>>>, <Task pending coro=<PeerPoolEventServer.handle_stream() running at /home/veox/src/trinity/p2p/service.py:158> wait_for=<Task cancelled coro=<<async_generator_asend without __name__>()>>>, <Task pending coro=<PeerPoolEventServer.handle_request_stream() running at /home/veox/src/trinity/p2p/service.py:158> wait_for=<Task cancelled coro=<<async_generator_asend without __name__>()>>>, <Task pending coro=<PeerPoolEventServer.handle_stream() running at /home/veox/src/trinity/p2p/service.py:158> wait_for=<Task cancelled coro=<<async_generator_asend without __name__>()>>>, <Task pending coro=<PeerPoolEventServer.handle_native_peer_messages() running at /home/veox/src/trinity/p2p/service.py:158> wait_for=<Task cancelled coro=<Queue.get() done, defined at /usr/lib64/python3.7/asyncio/queues.py:150>>>, <Task pending coro=<PeerPoolEventServer.handle_request_stream() running at /home/veox/src/trinity/p2p/service.py:158> wait_for=<Task cancelled coro=<<async_generator_asend without __name__>()>>>, <Task pending coro=<PeerPoolEventServer.handle_stream() running at /home/veox/src/trinity/p2p/service.py:158> wait_for=<Task cancelled coro=<<async_generator_asend without __name__>()>>>, <Task pending coro=<PeerPoolEventServer.handle_peer_count_requests() running at /home/veox/src/trinity/p2p/service.py:158> wait_for=<Task cancelled coro=<<async_generator_asend without __name__>()>>>, <Task pending coro=<PeerPoolEventServer.handle_get_connected_peers_requests() running at /home/veox/src/trinity/p2p/service.py:158> wait_for=<Task cancelled coro=<<async_generator_asend without __name__>()>>>, <Task pending coro=<PeerPoolEventServer.handle_request_stream() running at /home/veox/src/trinity/p2p/service.py:158> wait_for=<Task cancelled coro=<<async_generator_asend without __name__>()>>>] | |
| DEBUG 2020-01-15 16:31:01,772 Connection Failed to send SubscriptionsUpdated(subscriptions={<class 'trinity.events.AvailableEndpointsUpdated'>}, response_expected=False). Handler closed. | |
| DEBUG 2020-01-15 16:31:01,773 ETHPeerPool <trinity.protocol.eth.peer.ETHPeerPool object at 0x7f059584a510> cancelled, cleaning up... | |
| DEBUG 2020-01-15 16:31:01,774 ETHPeerPool <trinity.protocol.eth.peer.ETHPeerPool object at 0x7f059584a510> waiting for child services: [<Connection Session(<Node(0x59d62ab6e3b70be2625da680c54fc082b5ad313840abc18ec0feddc4bcb5704318cf5dceb70ac6350b0ebbb9f851e87ec2bdca243d41dc1136c5325de1ad61d0@176.223.135.35:53744)> UUID('6d789a13-0446-4f4c-b0e0-cc3d2d9456b4')) <Multiplexer[p2p:5,eth:63]> dial_out=False>, ETHPeer Session(<Node(0x59d62ab6e3b70be2625da680c54fc082b5ad313840abc18ec0feddc4bcb5704318cf5dceb70ac6350b0ebbb9f851e87ec2bdca243d41dc1136c5325de1ad61d0@176.223.135.35:53744)> UUID('6d789a13-0446-4f4c-b0e0-cc3d2d9456b4'))] | |
| DEBUG 2020-01-15 16:31:01,774 ETHPeerPoolEventServer All tasks finished | |
| DEBUG 2020-01-15 16:31:01,774 ETHPeerPoolEventServer <trinity.protocol.eth.peer.ETHPeerPoolEventServer object at 0x7f059582ae90> halted cleanly | |
| DEBUG 2020-01-15 16:31:01,776 Connection Connection-<Session <Node(0x59d62a@176.223.135.35)> 6d789a13-0446-4f4c-b0e0-cc3d2d9456b4> finished: Cancellation requested by FullServer:FullNode token | |
| DEBUG 2020-01-15 16:31:01,776 Connection Connection-<Session <Node(0x59d62a@176.223.135.35)> 6d789a13-0446-4f4c-b0e0-cc3d2d9456b4>: Waiting for tasks (1): [<Task pending coro=<Connection._feed_protocol_handlers() running at /home/veox/src/trinity/p2p/service.py:158> wait_for=<Task cancelled coro=<<async_generator_asend without __name__>()>>>] | |
| DEBUG 2020-01-15 16:31:01,776 FullServer Could not complete handshake with ('176.223.135.35', 53476): Lost connection to <Node(0x59d62a@176.223.135.35)> | |
| DEBUG 2020-01-15 16:31:01,776 Connection All tasks finished | |
| DEBUG 2020-01-15 16:31:01,777 Connection Connection-<Session <Node(0x59d62a@176.223.135.35)> 6d789a13-0446-4f4c-b0e0-cc3d2d9456b4> halted cleanly | |
| DEBUG 2020-01-15 16:31:01,777 Manager <Manager service=<ResponseCandidateStream(Connection-<Session <Node(0x59d62a@176.223.135.35)> 6d789a13-0446-4f4c-b0e0-cc3d2d9456b4>, <class 'trinity.protocol.eth.commands.Receipts'>)> started=True running=True cancelled=True stopping=False finished=False did_error=False>: _handle_cancelled triggering task cancellation | |
| DEBUG 2020-01-15 16:31:01,777 Manager <Manager service=<ResponseCandidateStream(Connection-<Session <Node(0x59d62a@176.223.135.35)> 6d789a13-0446-4f4c-b0e0-cc3d2d9456b4>, <class 'trinity.protocol.eth.commands.Receipts'>)> started=True running=False cancelled=True stopping=True finished=False did_error=False> stopping | |
| DEBUG 2020-01-15 16:31:01,778 Manager <Manager service=<ResponseCandidateStream(Connection-<Session <Node(0x59d62a@176.223.135.35)> 6d789a13-0446-4f4c-b0e0-cc3d2d9456b4>, <class 'trinity.protocol.eth.commands.Receipts'>)> started=True running=False cancelled=True stopping=True finished=False did_error=False>: _handle_stopping triggering system task cancellations | |
| DEBUG 2020-01-15 16:31:01,778 Manager <Manager service=<ResponseCandidateStream(Connection-<Session <Node(0x59d62a@176.223.135.35)> 6d789a13-0446-4f4c-b0e0-cc3d2d9456b4>, <class 'trinity.protocol.eth.commands.Receipts'>)> started=True running=False cancelled=True stopping=False finished=True did_error=False> finished | |
| DEBUG 2020-01-15 16:31:01,778 Manager <Manager service=<ResponseCandidateStream(Connection-<Session <Node(0x59d62a@176.223.135.35)> 6d789a13-0446-4f4c-b0e0-cc3d2d9456b4>, <class 'trinity.protocol.eth.commands.NodeData'>)> started=True running=True cancelled=True stopping=False finished=False did_error=False>: _handle_cancelled triggering task cancellation | |
| DEBUG 2020-01-15 16:31:01,778 Manager <Manager service=<ResponseCandidateStream(Connection-<Session <Node(0x59d62a@176.223.135.35)> 6d789a13-0446-4f4c-b0e0-cc3d2d9456b4>, <class 'trinity.protocol.eth.commands.NodeData'>)> started=True running=False cancelled=True stopping=True finished=False did_error=False> stopping | |
| DEBUG 2020-01-15 16:31:01,778 Manager <Manager service=<ResponseCandidateStream(Connection-<Session <Node(0x59d62a@176.223.135.35)> 6d789a13-0446-4f4c-b0e0-cc3d2d9456b4>, <class 'trinity.protocol.eth.commands.NodeData'>)> started=True running=False cancelled=True stopping=True finished=False did_error=False>: _handle_stopping triggering system task cancellations | |
| DEBUG 2020-01-15 16:31:01,778 Manager <Manager service=<ResponseCandidateStream(Connection-<Session <Node(0x59d62a@176.223.135.35)> 6d789a13-0446-4f4c-b0e0-cc3d2d9456b4>, <class 'trinity.protocol.eth.commands.NodeData'>)> started=True running=False cancelled=True stopping=False finished=True did_error=False> finished | |
| DEBUG 2020-01-15 16:31:01,779 Manager <Manager service=<ResponseCandidateStream(Connection-<Session <Node(0x59d62a@176.223.135.35)> 6d789a13-0446-4f4c-b0e0-cc3d2d9456b4>, <class 'trinity.protocol.eth.commands.BlockHeaders'>)> started=True running=True cancelled=True stopping=False finished=False did_error=False>: _handle_cancelled triggering task cancellation | |
| DEBUG 2020-01-15 16:31:01,779 Manager <Manager service=<ResponseCandidateStream(Connection-<Session <Node(0x59d62a@176.223.135.35)> 6d789a13-0446-4f4c-b0e0-cc3d2d9456b4>, <class 'trinity.protocol.eth.commands.BlockHeaders'>)> started=True running=False cancelled=True stopping=True finished=False did_error=False> stopping | |
| DEBUG 2020-01-15 16:31:01,779 Manager <Manager service=<ResponseCandidateStream(Connection-<Session <Node(0x59d62a@176.223.135.35)> 6d789a13-0446-4f4c-b0e0-cc3d2d9456b4>, <class 'trinity.protocol.eth.commands.BlockHeaders'>)> started=True running=False cancelled=True stopping=True finished=False did_error=False>: _handle_stopping triggering system task cancellations | |
| DEBUG 2020-01-15 16:31:01,779 Manager <Manager service=<ResponseCandidateStream(Connection-<Session <Node(0x59d62a@176.223.135.35)> 6d789a13-0446-4f4c-b0e0-cc3d2d9456b4>, <class 'trinity.protocol.eth.commands.BlockHeaders'>)> started=True running=False cancelled=True stopping=False finished=True did_error=False> finished | |
| DEBUG 2020-01-15 16:31:01,779 Manager <Manager service=<ResponseCandidateStream(Connection-<Session <Node(0x59d62a@176.223.135.35)> 6d789a13-0446-4f4c-b0e0-cc3d2d9456b4>, <class 'trinity.protocol.eth.commands.BlockBodies'>)> started=True running=True cancelled=True stopping=False finished=False did_error=False>: _handle_cancelled triggering task cancellation | |
| DEBUG 2020-01-15 16:31:01,780 Manager <Manager service=<ResponseCandidateStream(Connection-<Session <Node(0x59d62a@176.223.135.35)> 6d789a13-0446-4f4c-b0e0-cc3d2d9456b4>, <class 'trinity.protocol.eth.commands.BlockBodies'>)> started=True running=False cancelled=True stopping=True finished=False did_error=False> stopping | |
| DEBUG 2020-01-15 16:31:01,780 Manager <Manager service=<ResponseCandidateStream(Connection-<Session <Node(0x59d62a@176.223.135.35)> 6d789a13-0446-4f4c-b0e0-cc3d2d9456b4>, <class 'trinity.protocol.eth.commands.BlockBodies'>)> started=True running=False cancelled=True stopping=True finished=False did_error=False>: _handle_stopping triggering system task cancellations | |
| DEBUG 2020-01-15 16:31:01,780 Manager <Manager service=<ResponseCandidateStream(Connection-<Session <Node(0x59d62a@176.223.135.35)> 6d789a13-0446-4f4c-b0e0-cc3d2d9456b4>, <class 'trinity.protocol.eth.commands.BlockBodies'>)> started=True running=False cancelled=True stopping=False finished=True did_error=False> finished | |
| DEBUG 2020-01-15 16:31:01,780 ETHPeer ETHPeer <Session <Node(0x59d62a@176.223.135.35)> 6d789a13-0446-4f4c-b0e0-cc3d2d9456b4> finished: Cancellation requested by Connection:multiplexer[<Node(0x59d62a@176.223.135.35)>]:FullServer:FullNode token | |
| DEBUG 2020-01-15 16:31:01,780 ETHPeer ETHPeer <Session <Node(0x59d62a@176.223.135.35)> 6d789a13-0446-4f4c-b0e0-cc3d2d9456b4> waiting for child services: [<trinity.protocol.common.boot.DAOCheckBootManager object at 0x7f059068d7d0>] | |
| DEBUG 2020-01-15 16:31:01,780 ETHPeer All child services finished | |
| DEBUG 2020-01-15 16:31:01,781 ETHPeerPool Removing ETHPeer <Session <Node(0x59d62a@176.223.135.35)> 6d789a13-0446-4f4c-b0e0-cc3d2d9456b4> from pool: local_reason=None remote_reason=None | |
| DEBUG 2020-01-15 16:31:01,781 PeerTracker Not tracking disconnecting peer ETHPeer <Session <Node(0x59d62a@176.223.135.35)> 6d789a13-0446-4f4c-b0e0-cc3d2d9456b4>[inbound] missing disconnect reason | |
| DEBUG 2020-01-15 16:31:01,781 ETHPeer ETHPeer <Session <Node(0x59d62a@176.223.135.35)> 6d789a13-0446-4f4c-b0e0-cc3d2d9456b4> halted cleanly | |
| DEBUG 2020-01-15 16:31:01,781 ETHPeerPool All child services finished | |
| INFO 2020-01-15 16:31:01,781 ETHPeerPool Stopping all peers ... | |
| DEBUG 2020-01-15 16:31:01,781 ETHPeerPool <trinity.protocol.eth.peer.ETHPeerPool object at 0x7f059584a510> halted cleanly | |
| DEBUG 2020-01-15 16:31:01,781 FullServer All child services finished | |
| INFO 2020-01-15 16:31:01,781 FullServer Closing server... | |
| DEBUG 2020-01-15 16:31:01,781 FullServer <trinity.server.FullServer object at 0x7f059584ac10> halted cleanly | |
| DEBUG 2020-01-15 16:31:01,781 FullNode All child services finished | |
| DEBUG 2020-01-15 16:31:01,782 FullNode <trinity.nodes.full.FullNode object at 0x7f059586cdd0> halted cleanly | |
| DEBUG 2020-01-15 16:31:03,961 Manager <Manager service=<trinity.protocol.eth.servers.ETHRequestServer object at 0x7fb3c8fa2b90> started=True running=False cancelled=False stopping=True finished=False did_error=False> stopping | |
| DEBUG 2020-01-15 16:31:03,961 DBManager <trinity.db.manager.DBManager object at 0x7f79264bbdd0>: closing client connection: Connection closed | |
| DEBUG 2020-01-15 16:31:03,961 AsyncioEventBusService Cancelling service: <trinity.extensibility.event_bus.AsyncioEventBusService object at 0x7fb3c8feb450> | |
| DEBUG 2020-01-15 16:31:03,962 Manager <Manager service=<trinity.protocol.eth.servers.ETHRequestServer object at 0x7fb3c8fa2b90> started=True running=False cancelled=False stopping=True finished=False did_error=False>: _handle_stopping triggering system task cancellations | |
| DEBUG 2020-01-15 16:31:03,963 Connection Failed to send SubscriptionsUpdated(subscriptions={<class 'trinity.protocol.eth.events.GetNodeDataEvent'>, <class 'trinity.protocol.eth.events.GetBlockBodiesEvent'>, <class 'trinity.protocol.eth.events.GetBlockHeadersEvent'>, <class 'trinity.protocol.eth.events.GetReceiptsEvent'>}, response_expected=False). Handler closed. | |
| DEBUG 2020-01-15 16:31:03,964 Endpoint Endpoint[brequest-server]: server stopped | |
| DEBUG 2020-01-15 16:31:03,964 Endpoint Endpoint[brequest-server]: stopped | |
| DEBUG 2020-01-15 16:31:03,964 AsyncioEventBusService <trinity.extensibility.event_bus.AsyncioEventBusService object at 0x7fb3c8feb450> finished: Cancellation requested by AsyncioEventBusService token | |
| DEBUG 2020-01-15 16:31:03,965 AsyncioEventBusService <trinity.extensibility.event_bus.AsyncioEventBusService object at 0x7fb3c8feb450> halted cleanly | |
| [1m[31m ERROR 2020-01-15 16:31:06,016 asyncio Task was destroyed but it is pending! | |
| task: <Task pending coro=<AsyncioIsolatedComponent._do_run() running at /home/veox/src/trinity/trinity/extensibility/asyncio.py:74> wait_for=<Future pending cb=[<TaskWakeupMethWrapper object at 0x7fb3c8fa2f50>()]>>[0m | |
| INFO 2020-01-15 16:31:06,140 TrioIsolatedComponent Sent SIGINT to process 82604, waiting 10 seconds for it to terminate | |
| DEBUG 2020-01-15 16:31:06,141 Manager <Manager service=<p2p.discovery.StaticDiscoveryService object at 0x7f4506e13a10> started=True running=False cancelled=False stopping=True finished=False did_error=False> stopping | |
| DEBUG 2020-01-15 16:31:06,142 DBManager <trinity.db.manager.DBManager object at 0x7f79264bbdd0>: closing client connection: Connection closed | |
| DEBUG 2020-01-15 16:31:06,141 Manager <Manager service=<p2p.discovery.StaticDiscoveryService object at 0x7f4506e13a10> started=True running=False cancelled=False stopping=False finished=True did_error=False> finished | |
| DEBUG 2020-01-15 16:31:06,142 Manager <Manager service=<trinity.extensibility.event_bus.TrioEventBusService object at 0x7f4506e5c7d0> started=True running=True cancelled=True stopping=False finished=False did_error=False>: _handle_cancelled triggering task cancellation | |
| DEBUG 2020-01-15 16:31:06,143 TrioEndpoint Endpoint[discovery]: server stopped | |
| DEBUG 2020-01-15 16:31:06,146 TrioEndpoint Endpoint[discovery]: server finished | |
| DEBUG 2020-01-15 16:31:06,148 Manager <Manager service=<trinity.extensibility.event_bus.TrioEventBusService object at 0x7f4506e5c7d0> started=True running=True cancelled=True stopping=False finished=False did_error=False>: _handle_run exited cleanly, waiting for full stop... | |
| DEBUG 2020-01-15 16:31:06,148 Manager <Manager service=<trinity.extensibility.event_bus.TrioEventBusService object at 0x7f4506e5c7d0> started=True running=False cancelled=True stopping=True finished=False did_error=False> stopping | |
| DEBUG 2020-01-15 16:31:06,149 Manager <Manager service=<trinity.extensibility.event_bus.TrioEventBusService object at 0x7f4506e5c7d0> started=True running=False cancelled=True stopping=False finished=True did_error=False> finished | |
| INFO 2020-01-15 16:31:06,325 TrioIsolatedComponent Process 82604 has already terminated | |
| INFO 2020-01-15 16:31:06,325 TrioIsolatedComponent Process 82604 has already terminated | |
| DEBUG 2020-01-15 16:31:06,326 PeerDBServer Cancelling service: <trinity.components.builtin.network_db.eth1_peer_db.server.PeerDBServer object at 0x7f6cf7618110> | |
| DEBUG 2020-01-15 16:31:06,327 PeerDBServer <trinity.components.builtin.network_db.eth1_peer_db.server.PeerDBServer object at 0x7f6cf7618110> cancelled, cleaning up... | |
| DEBUG 2020-01-15 16:31:06,327 PeerDBServer <trinity.components.builtin.network_db.eth1_peer_db.server.PeerDBServer object at 0x7f6cf7618110>: Waiting for tasks (2): [<Task pending coro=<PeerDBServer.handle_track_peer_event() running at /home/veox/src/trinity/p2p/service.py:158> wait_for=<Future pending cb=[<TaskWakeupMethWrapper object at 0x7f6cf7419190>()]>>, <Task pending coro=<PeerDBServer.handle_get_peer_candidates_request() running at /home/veox/src/trinity/p2p/service.py:158> wait_for=<Future pending cb=[<TaskWakeupMethWrapper object at 0x7f6cf7430090>()]>>] | |
| DEBUG 2020-01-15 16:31:06,328 ConnectionTrackerServer Cancelling service: <trinity.components.builtin.network_db.connection.server.ConnectionTrackerServer object at 0x7f6cf76a0c50> | |
| DEBUG 2020-01-15 16:31:06,328 ConnectionTrackerServer <trinity.components.builtin.network_db.connection.server.ConnectionTrackerServer object at 0x7f6cf76a0c50> finished: Cancellation requested by ConnectionTrackerServer token | |
| DEBUG 2020-01-15 16:31:06,329 ConnectionTrackerServer <trinity.components.builtin.network_db.connection.server.ConnectionTrackerServer object at 0x7f6cf76a0c50>: Waiting for tasks (2): [<Task pending coro=<ConnectionTrackerServer.handle_should_connect_to_requests() running at /home/veox/src/trinity/p2p/service.py:158> wait_for=<Future pending cb=[<TaskWakeupMethWrapper object at 0x7f6cf75aea50>()]>>, <Task pending coro=<ConnectionTrackerServer.handle_blacklist_command() running at /home/veox/src/trinity/p2p/service.py:158> wait_for=<Future pending cb=[<TaskWakeupMethWrapper object at 0x7f6cf75aec90>()]>>] | |
| DEBUG 2020-01-15 16:31:06,329 Connection Failed to send SubscriptionsUpdated(subscriptions={<class 'trinity.events.AvailableEndpointsUpdated'>}, response_expected=False). Handler closed. | |
| DEBUG 2020-01-15 16:31:06,330 Connection Failed to send SubscriptionsUpdated(subscriptions={<class 'trinity.events.AvailableEndpointsUpdated'>}, response_expected=False). Handler closed. | |
| DEBUG 2020-01-15 16:31:06,330 Connection Failed to send SubscriptionsUpdated(subscriptions={<class 'trinity.events.AvailableEndpointsUpdated'>}, response_expected=False). Handler closed. | |
| DEBUG 2020-01-15 16:31:06,330 Connection Failed to send SubscriptionsUpdated(subscriptions={<class 'trinity.events.AvailableEndpointsUpdated'>}, response_expected=False). Handler closed. | |
| DEBUG 2020-01-15 16:31:06,330 ConnectionTrackerServer All tasks finished | |
| DEBUG 2020-01-15 16:31:06,330 ConnectionTrackerServer <trinity.components.builtin.network_db.connection.server.ConnectionTrackerServer object at 0x7f6cf76a0c50> halted cleanly | |
| DEBUG 2020-01-15 16:31:06,331 ConnectionTrackerServer <trinity.components.builtin.network_db.connection.server.ConnectionTrackerServer object at 0x7f6cf76a0c50> finished cleanly | |
| DEBUG 2020-01-15 16:31:06,331 AsyncioEventBusService Cancelling service: <trinity.extensibility.event_bus.AsyncioEventBusService object at 0x7f6cf7688390> | |
| DEBUG 2020-01-15 16:31:06,332 Connection Failed to send SubscriptionsUpdated(subscriptions=set(), response_expected=False). Handler closed. | |
| DEBUG 2020-01-15 16:31:06,332 Connection Failed to send SubscriptionsUpdated(subscriptions=set(), response_expected=False). Handler closed. | |
| DEBUG 2020-01-15 16:31:06,332 Connection Failed to send SubscriptionsUpdated(subscriptions=set(), response_expected=False). Handler closed. | |
| DEBUG 2020-01-15 16:31:06,332 Connection Failed to send SubscriptionsUpdated(subscriptions=set(), response_expected=False). Handler closed. | |
| DEBUG 2020-01-15 16:31:06,333 Endpoint Endpoint[network-db]: server stopped | |
| DEBUG 2020-01-15 16:31:06,333 Endpoint Endpoint[network-db]: stopped | |
| DEBUG 2020-01-15 16:31:06,333 AsyncioEventBusService <trinity.extensibility.event_bus.AsyncioEventBusService object at 0x7f6cf7688390> finished: Cancellation requested by AsyncioEventBusService token | |
| DEBUG 2020-01-15 16:31:06,334 AsyncioEventBusService <trinity.extensibility.event_bus.AsyncioEventBusService object at 0x7f6cf7688390> halted cleanly | |
| DEBUG 2020-01-15 16:31:06,334 AsyncioEventBusService <trinity.extensibility.event_bus.AsyncioEventBusService object at 0x7f6cf7688390> finished cleanly | |
| DEBUG 2020-01-15 16:31:06,508 Manager <Manager service=<trinity.rpc.ipc.IPCServer object at 0x7f9e03586950> started=True running=True cancelled=True stopping=False finished=False did_error=False>: _handle_cancelled triggering task cancellation | |
| DEBUG 2020-01-15 16:31:06,508 Manager <Manager service=<trinity.rpc.ipc.IPCServer object at 0x7f9e03586950> started=True running=False cancelled=True stopping=True finished=False did_error=False> stopping | |
| DEBUG 2020-01-15 16:31:06,508 Manager <Manager service=<trinity.rpc.ipc.IPCServer object at 0x7f9e03586950> started=True running=False cancelled=True stopping=True finished=False did_error=False>: _handle_stopping triggering system task cancellations | |
| DEBUG 2020-01-15 16:31:06,508 Manager <Manager service=<trinity.rpc.ipc.IPCServer object at 0x7f9e03586950> started=True running=False cancelled=True stopping=False finished=True did_error=False> finished | |
| DEBUG 2020-01-15 16:31:08,686 DBManager <trinity.db.manager.DBManager object at 0x7f79264bbdd0>: closing client connection: Connection closed | |
| INFO 2020-01-15 16:31:08,686 ComponentManager Components stopped. | |
| [1m[31m ERROR 2020-01-15 16:31:08,688 root Error during trinity run: DaemonTaskExit('Daemon task _handle_shutdown_request exited')[0m | |
| INFO 2020-01-15 16:31:08,689 root Shutting down Trinity (Sync ended unexpectedly) | |
| INFO 2020-01-15 16:31:10,698 root Sent SIGINT to process 82586, waiting 10 seconds for it to terminate | |
| DEBUG 2020-01-15 16:31:10,699 DBManager Server stopping: [Errno 22] Invalid argument | |
| INFO 2020-01-15 16:31:10,877 root Process 82586 has already terminated | |
| INFO 2020-01-15 16:31:10,877 root Process 82586 has already terminated | |
| INFO 2020-01-15 16:31:10,877 root DB process (pid=82586) terminated | |
| [1m[33m WARNING 2020-01-15 16:31:10,878 root Removed dangling IPC socket file /home/veox/.local/share/trinity/goerli/ipcs-eth1/bjson-rpc-api.ipc[0m | |
| [1m[33m WARNING 2020-01-15 16:31:10,879 root Removed dangling IPC socket file /home/veox/.local/share/trinity/goerli/ipcs-eth1/btxcomponent.ipc[0m | |
| [1m[33m WARNING 2020-01-15 16:31:10,879 root Removed dangling IPC socket file /home/veox/.local/share/trinity/goerli/ipcs-eth1/logging.ipc[0m | |
| [1m[33m WARNING 2020-01-15 16:31:10,880 root Removed dangling IPC socket file /home/veox/.local/share/trinity/goerli/ipcs-eth1/networking.ipc[0m |
Sign up for free
to join this conversation on GitHub.
Already have an account?
Sign in to comment