Skip to content

Instantly share code, notes, and snippets.

@veox

veox/trinity.log

Created January 15, 2020 14:35
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save veox/0bb8786935404071c59d2d1f48768173 to your computer and use it in GitHub Desktop.
Save veox/0bb8786935404071c59d2d1f48768173 to your computer and use it in GitHub Desktop.
Download ZIP
Trinity Goerli sync against geth: Mismatch between block and imported block on 1 fields: - header.state_root
Raw
trinity.log
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>
 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\'')
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
 ERROR 2020-01-15 16:31:01,572 Sync Sync ended unexpectedly. Shutting down trinity
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
 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>()]>>
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
 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>()]>>
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.
 ERROR 2020-01-15 16:31:08,688 root Error during trinity run: DaemonTaskExit('Daemon task _handle_shutdown_request exited')
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
 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
 WARNING 2020-01-15 16:31:10,879 root Removed dangling IPC socket file /home/veox/.local/share/trinity/goerli/ipcs-eth1/btxcomponent.ipc
 WARNING 2020-01-15 16:31:10,879 root Removed dangling IPC socket file /home/veox/.local/share/trinity/goerli/ipcs-eth1/logging.ipc
 WARNING 2020-01-15 16:31:10,880 root Removed dangling IPC socket file /home/veox/.local/share/trinity/goerli/ipcs-eth1/networking.ipc
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment