Skip to content

Instantly share code, notes, and snippets.

@cburgdorf

cburgdorf/with_more_logs

Created February 27, 2019 16:25
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 cburgdorf/f7477bc0ab29e9a7ea4fd1504b5bdd7c to your computer and use it in GitHub Desktop.
Save cburgdorf/f7477bc0ab29e9a7ea4fd1504b5bdd7c to your computer and use it in GitHub Desktop.
Download ZIP
Raw
with_more_logs
DEBUG 02-27 14:19:42 FastChainBodySyncer Got receipts for 194/194 headers from ETHPeer <Node(0xae4f@83.79.101.201)>, with 62 trivial headers
DEBUG 02-27 14:19:42 FastChainBodySyncer Requesting receipts for 164 headers from ETHPeer <Node(0xae4f@83.79.101.201)>
DEBUG 02-27 14:19:42 ETHPeer Disconnecting from remote peer <Node(0xa7e1@47.95.224.244)>; reason: useless_peer
DEBUG 02-27 14:19:42 FullServer Could not complete handshake: ETHPeer <Node(0xa7e1@47.95.224.244)> network (42) does not match ours (1), disconnecting
DEBUG 02-27 14:19:42 ETHPeerPool Could not complete handshake with <Node(0x354a324d270bac2f728d128387da9f35ed0ff833ff8429faf0fc585884c275d201bd22177c8511983da7a8dde3aab41c8c1fe8785a45007304bcd88b24a1870f@95.179.228.6:21212)>: TooManyPeersFailure('ETHPeer <Node(0x354a@95.179.228.6)> disconnected from us before handshake',)
DEBUG 02-27 14:19:42 SQLitePeerInfo <Node(0x354a@95.179.228.6)> will not be retried until 2019-02-27 14:22:42.553772 because TooManyPeersFailure
DEBUG 02-27 14:19:42 FullServer Receiving handshake from Address(140.110.96.81:udp:52172|tcp:52172)
DEBUG 02-27 14:19:42 ETHPeer Disconnecting from remote peer <Node(0x14aa@140.110.96.81)>; reason: too_many_peers
DEBUG 02-27 14:19:42 FullServer Receiving handshake from Address(193.37.212.144:udp:35196|tcp:35196)
DEBUG 02-27 14:19:42 ETHPeer Disconnecting from remote peer <Node(0x87b7@193.37.212.144)>; reason: too_many_peers
DEBUG 02-27 14:19:42 FullServer Receiving handshake from Address(52.28.132.195:udp:17063|tcp:17063)
DEBUG 02-27 14:19:42 ETHPeer Disconnecting from remote peer <Node(0xf483@52.28.132.195)>; reason: too_many_peers
DEBUG 02-27 14:19:43 HeaderMeatSyncer Requesting 192 headers from ETHPeer <Node(0x3942@52.56.62.122)>
DEBUG 02-27 14:19:43 DiscoveryProtocol Ignoring find_node request from unknown node <Node(0x51df@40.126.233.153)>
DEBUG 02-27 14:19:43 FullServer Receiving handshake from Address(219.87.131.5:udp:48994|tcp:48994)
DEBUG 02-27 14:19:43 ETHPeer Disconnecting from remote peer <Node(0xc42d@219.87.131.5)>; reason: too_many_peers
DEBUG 02-27 14:19:43 FullServer Receiving handshake from Address(150.140.159.245:udp:48388|tcp:48388)
DEBUG 02-27 14:19:43 ETHPeer Disconnecting from remote peer <Node(0x2497@150.140.159.245)>; reason: too_many_peers
DEBUG 02-27 14:19:43 FullServer Receiving handshake from Address(202.101.58.90:udp:41706|tcp:41706)
DEBUG 02-27 14:19:43 ETHPeer Disconnecting from remote peer <Node(0x981d@202.101.58.90)>; reason: too_many_peers
DEBUG 02-27 14:19:44 HeaderMeatSyncer Header Skeleton Gaps: active=3 queued=50 max=50
DEBUG 02-27 14:19:44 FastChainBodySyncer (in progress, queued, max size) of bodies, receipts: [(0, 0, 1024), (512, 2048, 2048)]
INFO 02-27 14:19:44 FastChainBodySyncer blks=177 txs=1547 bps=10 tps=79 elapsed=5.0 head=#2162847 4870..355f age=2y5m4w
DEBUG 02-27 14:19:44 SkeletonSyncer Skeleton header queue is 512/1536 full
DEBUG 02-27 14:19:44 HeaderMeatSyncer Requesting 192 headers from ETHPeer <Node(0xae4f@83.79.101.201)>
DEBUG 02-27 14:19:45 FullServer Receiving handshake from Address(54.93.105.176:udp:54088|tcp:54088)
DEBUG 02-27 14:19:45 DiscoveryProtocol Ignoring find_node request from unknown node <Node(0x568b@45.32.193.224)>
DEBUG 02-27 14:19:45 ETHPeer Disconnecting from remote peer <Node(0xcbb0@54.93.105.176)>; reason: too_many_peers
DEBUG 02-27 14:19:46 protocol client > Frame(fin=True, opcode=1, data=b'{"emit": ["node-ping", {"clientTime": 1551277186218, "id": "snake charmers"}]}', rsv1=False, rsv2=False, rsv3=False)
DEBUG 02-27 14:19:46 protocol client < Frame(fin=True, opcode=1, data=b'{"emit":["node-pong",{"clientTime":1551277186218,"serverTime":1551277186301}]}', rsv1=False, rsv2=False, rsv3=False)
DEBUG 02-27 14:19:46 protocol client > Frame(fin=True, opcode=1, data=b'{"emit": ["latency", {"latency": 43, "id": "snake charmers"}]}', rsv1=False, rsv2=False, rsv3=False)
WARNING 02-27 14:19:47 EthstatsService Timeout: PeerPool did not answer PeerCountRequest
DEBUG 02-27 14:19:47 protocol client > Frame(fin=True, opcode=1, data=b'{"emit": ["stats", {"stats": {"active": true, "uptime": 100, "peers": 0}, "id": "snake charmers"}]}', rsv1=False, rsv2=False, rsv3=False)
DEBUG 02-27 14:19:47 protocol client > Frame(fin=True, opcode=1, data=b'{"emit": ["block", {"block": {"number": 2162847, "hash": "0x4870f75183a92b9060237fb3e8e4aaa4471f8287148e271eb6a2bbdcf4d6355f", "difficulty": 67136930857890, "totalDifficulty": 53894140034325532197, "transactions": [], "uncles": []}, "id": "snake charmers"}]}', rsv1=False, rsv2=False, rsv3=False)
DEBUG 02-27 14:19:47 DiscoveryProtocol Ignoring find_node request from unknown node <Node(0x94d4@161.47.50.192)>
DEBUG 02-27 14:19:48 DiscoveryProtocol Ignoring find_node request from unknown node <Node(0x946d@35.244.111.53)>
DEBUG 02-27 14:19:48 DiscoveryProtocol Ignoring find_node request from unknown node <Node(0x69eb@3.80.6.107)>
DEBUG 02-27 14:19:49 DiscoveryProtocol Ignoring find_node request from unknown node <Node(0x5249@159.89.175.225)>
DEBUG 02-27 14:19:50 DiscoveryProtocol Ignoring find_node request from unknown node <Node(0xafbf@39.105.154.225)>
DEBUG 02-27 14:19:52 DiscoveryProtocol Ignoring find_node request from unknown node <Node(0x946d@35.244.111.53)>
DEBUG 02-27 14:19:56 DiscoveryProtocol Ignoring find_node request from unknown node <Node(0x73d4@125.141.139.200)>
DEBUG 02-27 14:19:56 DiscoveryProtocol Ignoring find_node request from unknown node <Node(0xb309@121.43.179.239)>
DEBUG 02-27 14:19:57 protocol client > Frame(fin=True, opcode=1, data=b'{"emit": ["node-ping", {"clientTime": 1551277197230, "id": "snake charmers"}]}', rsv1=False, rsv2=False, rsv3=False)
DEBUG 02-27 14:19:57 protocol client < Frame(fin=True, opcode=1, data=b'{"emit":["node-pong",{"clientTime":1551277197230,"serverTime":1551277197275}]}', rsv1=False, rsv2=False, rsv3=False)
DEBUG 02-27 14:19:57 protocol client > Frame(fin=True, opcode=1, data=b'{"emit": ["latency", {"latency": 24, "id": "snake charmers"}]}', rsv1=False, rsv2=False, rsv3=False)
WARNING 02-27 14:19:58 EthstatsService Timeout: PeerPool did not answer PeerCountRequest
DEBUG 02-27 14:19:58 protocol client > Frame(fin=True, opcode=1, data=b'{"emit": ["stats", {"stats": {"active": true, "uptime": 100, "peers": 0}, "id": "snake charmers"}]}', rsv1=False, rsv2=False, rsv3=False)
DEBUG 02-27 14:19:58 protocol client > Frame(fin=True, opcode=1, data=b'{"emit": ["block", {"block": {"number": 2162847, "hash": "0x4870f75183a92b9060237fb3e8e4aaa4471f8287148e271eb6a2bbdcf4d6355f", "difficulty": 67136930857890, "totalDifficulty": 53894140034325532197, "transactions": [], "uncles": []}, "id": "snake charmers"}]}', rsv1=False, rsv2=False, rsv3=False)
DEBUG 02-27 14:19:58 DiscoveryProtocol Ignoring find_node request from unknown node <Node(0xc361@212.32.246.39)>
DEBUG 02-27 14:20:00 DiscoveryProtocol Ignoring find_node request from unknown node <Node(0x8de8@34.253.179.170)>
DEBUG 02-27 14:20:04 HeaderMeatSyncer Header Skeleton Gaps: active=3 queued=49 max=50
DEBUG 02-27 14:20:04 FastChainBodySyncer (in progress, queued, max size) of bodies, receipts: [(0, 0, 1024), (512, 2048, 2048)]
INFO 02-27 14:20:04 FastChainBodySyncer blks=0 txs=0 bps=10 tps=75 elapsed=20.7 head=#2162847 4870..355f age=2y5m4w
DEBUG 02-27 14:20:04 SkeletonSyncer Skeleton header queue is 511/1536 full
DEBUG 02-27 14:20:04 HeaderMeatSyncer Requesting 192 headers from ETHPeer <Node(0xae4f@83.79.101.201)>
DEBUG 02-27 14:20:04 HeaderMeatSyncer Requesting 192 headers from ETHPeer <Node(0x3942@52.56.62.122)>
DEBUG 02-27 14:20:04 FullServer Receiving handshake from Address(45.32.193.224:udp:38368|tcp:38368)
DEBUG 02-27 14:20:04 FullServer Receiving handshake from Address(167.114.169.69:udp:53830|tcp:53830)
DEBUG 02-27 14:20:04 FullServer Receiving handshake from Address(35.242.175.154:udp:52102|tcp:52102)
DEBUG 02-27 14:20:04 FullServer Receiving handshake from Address(94.130.33.58:udp:49582|tcp:49582)
DEBUG 02-27 14:20:04 FullServer Receiving handshake from Address(159.89.175.225:udp:55254|tcp:55254)
DEBUG 02-27 14:20:04 FullServer Receiving handshake from Address(114.35.52.215:udp:7659|tcp:7659)
DEBUG 02-27 14:20:05 FullServer Receiving handshake from Address(13.59.54.254:udp:40956|tcp:40956)
DEBUG 02-27 14:20:05 FullServer Receiving handshake from Address(159.89.26.82:udp:33680|tcp:33680)
DEBUG 02-27 14:20:05 FullServer Receiving handshake from Address(178.128.61.150:udp:56104|tcp:56104)
DEBUG 02-27 14:20:05 FullServer Receiving handshake from Address(62.96.79.50:udp:53177|tcp:53177)
DEBUG 02-27 14:20:05 FullServer Receiving handshake from Address(52.5.65.209:udp:65303|tcp:65303)
DEBUG 02-27 14:20:05 FullServer Receiving handshake from Address(125.141.139.200:udp:58202|tcp:58202)
DEBUG 02-27 14:20:05 FullServer Receiving handshake from Address(13.231.161.112:udp:40200|tcp:40200)
DEBUG 02-27 14:20:05 FullServer Receiving handshake from Address(39.105.154.225:udp:49478|tcp:49478)
DEBUG 02-27 14:20:05 FullServer Receiving handshake from Address(72.28.149.162:udp:35164|tcp:35164)
DEBUG 02-27 14:20:05 ETHPeerPool Could not complete handshake with <Node(0xa610a3aed4d934c67071bd1c0eecdb5e9c4d89880ba2af196190340a39c318096d9ea1d002cc577ffa6e5d8625d60e9deab6b7fed2381a2bf7bcde9d53f48aa7@3.121.177.26:21000)>: PeerConnectionLost("ConnectionResetError(104, 'Connection reset by peer')",)
DEBUG 02-27 14:20:05 SQLitePeerInfo skipping <Node(0x6552@35.188.195.124)>, it failed because "WrongNetworkFailure" and is not usable until 2019-02-28 13:46:58
DEBUG 02-27 14:20:05 SQLitePeerInfo skipping <Node(0x1a92@35.176.17.250)>, it failed because "WrongNetworkFailure" and is not usable until 2019-02-28 18:31:50
DEBUG 02-27 14:20:05 ETHPeer Disconnecting from remote peer <Node(0xc3ff@94.130.33.58)>; reason: too_many_peers
DEBUG 02-27 14:20:05 ETHPeer Disconnecting from remote peer <Node(0xe636@114.35.52.215)>; reason: too_many_peers
DEBUG 02-27 14:20:05 ETHPeer Disconnecting from remote peer <Node(0x9495@62.96.79.50)>; reason: too_many_peers
DEBUG 02-27 14:20:05 ETHPeer Disconnecting from remote peer <Node(0x8e2c@72.28.149.162)>; reason: too_many_peers
DEBUG 02-27 14:20:05 ETHPeer Disconnecting from remote peer <Node(0x568b@45.32.193.224)>; reason: too_many_peers
DEBUG 02-27 14:20:05 ETHPeer Disconnecting from remote peer <Node(0x1991@167.114.169.69)>; reason: too_many_peers
DEBUG 02-27 14:20:05 ETHPeer Disconnecting from remote peer <Node(0x579f@35.242.175.154)>; reason: too_many_peers
DEBUG 02-27 14:20:05 ETHPeer Disconnecting from remote peer <Node(0x5249@159.89.175.225)>; reason: too_many_peers
DEBUG 02-27 14:20:05 ETHPeer Disconnecting from remote peer <Node(0x3d9c@13.59.54.254)>; reason: too_many_peers
DEBUG 02-27 14:20:05 ETHPeer Disconnecting from remote peer <Node(0x9fb5@159.89.26.82)>; reason: too_many_peers
DEBUG 02-27 14:20:05 ETHPeer Disconnecting from remote peer <Node(0xd268@178.128.61.150)>; reason: too_many_peers
DEBUG 02-27 14:20:05 ETHPeer Disconnecting from remote peer <Node(0xb5bd@52.5.65.209)>; reason: too_many_peers
DEBUG 02-27 14:20:05 ETHPeer Disconnecting from remote peer <Node(0x73d4@125.141.139.200)>; reason: too_many_peers
DEBUG 02-27 14:20:05 ETHPeer Disconnecting from remote peer <Node(0x9a16@13.231.161.112)>; reason: too_many_peers
DEBUG 02-27 14:20:05 ETHPeer Disconnecting from remote peer <Node(0xafbf@39.105.154.225)>; reason: too_many_peers
DEBUG 02-27 14:20:05 ETHPeerPool Could not complete handshake with <Node(0x0ba5f316997df8be8a0d90caca388349a78db19b5c3f2a6da057e9aed31f59130e6fc5941c3afd27326da5ab682bd0932ad435057580c5ecd050ce96ba2fd19c@159.69.65.190:21213)>: TooManyPeersFailure('ETHPeer <Node(0x0ba5@159.69.65.190)> disconnected from us before handshake',)
DEBUG 02-27 14:20:05 SQLitePeerInfo <Node(0x0ba5@159.69.65.190)> will not be retried until 2019-02-27 14:21:05.482022 because TooManyPeersFailure
DEBUG 02-27 14:20:05 DiscoveryProtocol Ignoring find_node request from unknown node <Node(0x9dc5@124.205.145.236)>
DEBUG 02-27 14:20:06 FullServer Receiving handshake from Address(35.195.61.125:udp:43166|tcp:43166)
DEBUG 02-27 14:20:06 ETHPeer Disconnecting from remote peer <Node(0xae64@35.195.61.125)>; reason: too_many_peers
DEBUG 02-27 14:20:06 HeaderMeatSyncer Requesting 192 headers from ETHPeer <Node(0x3942@52.56.62.122)>
DEBUG 02-27 14:20:06 FastChainBodySyncer Got receipts for 164/164 headers from ETHPeer <Node(0xae4f@83.79.101.201)>, with 92 trivial headers
DEBUG 02-27 14:20:06 FastChainBodySyncer Requesting receipts for 157 headers from ETHPeer <Node(0xae4f@83.79.101.201)>
DEBUG 02-27 14:20:07 HeaderMeatSyncer Requesting 192 headers from ETHPeer <Node(0x3942@52.56.62.122)>
DEBUG 02-27 14:20:07 DiscoveryProtocol starting lookup; initial neighbours: [<Node(0xbaa353e8dd211ea87b8a4371c585ec1692af7fbf3dfad6de2f3d5fdacab9902e945289550cac9dd83c8dda5fea99c5a43a3a67805578339c3aed27d60b79b54f@66.147.236.60:30303)>, <Node(0x63614239d0fa8755e064027855dda6ea446cb9b1356a8400eae271e4ba333fa2254ba24337ad30e0c6fafa29d84cd4a3b80e31b7319258c9bd04b4dbfcd0bb86@47.100.64.140:33333)>, <Node(0x275c8fc474b30f89507c28e2aef77283346b73784702b38d9f090bb8ba0a82169f354ca0ae62fbef81fe98c7e354fa51b939823f9210e53ff1cbba360e2eb272@47.75.57.4:34344)>, <Node(0x03e2114773c8054691954ddc2cb63f3001137e2e127afb81e64d41500d7f5bd55072ced2216b1b37061bc4ac113a0b0c51834b7eb894dddf9d0dfd2b217eee2d@116.25.43.189:3647)>, <Node(0x8d48a8bb88412927ebdb460372a9705387bc10fd5726cb043632be8f97637202f3587a6d6d31859b95f485d56d6ff3dadf98748307505566717ab1ba41cdd50b@45.43.30.2:38003)>, <Node(0xe05473efef5b907485e0b188830272be2e5591e815e37c9fd77008c29909169beb5610041e20cd2734a1826e46ecb5c04c63eebbbe3f55fbad772da9b1db17ff@95.216.34.99:10964)>, <Node(0xbf8f9b3d79db2a48ed2fb489f2a2a79fd3fec5f7959c203b1150c056d1413e84422cf62e9ed1804408b5de4bee2209c7b33aca3c7e7b91a16f7ead35bfbaab3f@104.36.250.11:6397)>, <Node(0xf62e3bd6c91042d296a9894993a2220b5cd11ecb32eb33f84204c6890f25c197bd5f2002c4759d1784e771273c6489f67ffa8e93ecac72abb0e64f580c83356f@192.34.63.106:30303)>, <Node(0xf99f521fb31742b55281a2e8e6175f89187c8647a53c143c1c5b0a73cb7512221c3d8ff017a3fc5ead1ef588d95c89c246fdc898bb51cc211459cbbbfe3e601d@119.53.210.112:30445)>, <Node(0x60ad4f2988a6ec4ea260cc16b57209e1ba5dc55f6d274c93d00b31a46592a3416126c8b2b3724fe1c71c1ce277a2bea8a314603ccb7f360642bb3a4a52b12723@47.244.115.106:30303)>, <Node(0x6b1c5f930f9dfeb31c08732ac56fc0cfdf6bda2ac47211499e838284fa1ce0e94561f5df4694d551367dadb7215541657a612fb987c3239c5f853a18816fe1c1@47.98.57.183:30303)>, <Node(0x31b5c3471dece62e009a7d92c58a5a21309bad1d538b5fd08b94f60dbc1a6bbd8f4af61351bb9bf357ec5b3ea1b546309a2aca2cd3f4aedc33798f09dd1bbb20@173.255.253.153:51057)>, <Node(0xc2584c8cbff561cf2f377f17a26fec58476ad391c9ae11d69dd1ed9d93bac4c982b9b19a42fda082a17c6b86d7fa8afcaa0a5ec22e109bf55470350b534949a8@108.61.72.29:21212)>, <Node(0xa85edad93f58e3739af35cbabe084fbc36e2985f0387bfd6d37d9a39df4e9401dab8e06fcd910e9048dafd7fa30017dd1b47f4676c18f941e571524be342f0e0@158.69.113.211:30305)>, <Node(0x8302a0c6ee2ec543736b6d6b8539f4ab685437aa2df54c5884e0cdd1258d8fe58322af9e7b3b3d93c3b7cbdb157250ef251d7e9c1dd4b25bacf316cc207d952f@144.202.106.53:30305)>, <Node(0x40e4c8ac706231472811df6100fc56d12f0d790f8ce1582ad4f99df9fa71c79333c9ccd1fbbd3654cc8fe088a75aac34956d81374c002866c7b7948ee6c41ed9@47.245.13.69:30313)>]
DEBUG 02-27 14:20:07 FastChainBodySyncer Got receipts for 189/189 headers from ETHPeer <Node(0x3942@52.56.62.122)>, with 67 trivial headers
DEBUG 02-27 14:20:08 protocol client > Frame(fin=True, opcode=1, data=b'{"emit": ["node-ping", {"clientTime": 1551277208236, "id": "snake charmers"}]}', rsv1=False, rsv2=False, rsv3=False)
DEBUG 02-27 14:20:08 protocol client < Frame(fin=True, opcode=1, data=b'{"emit":["node-pong",{"clientTime":1551277208236,"serverTime":1551277208282}]}', rsv1=False, rsv2=False, rsv3=False)
DEBUG 02-27 14:20:08 protocol client > Frame(fin=True, opcode=1, data=b'{"emit": ["latency", {"latency": 25, "id": "snake charmers"}]}', rsv1=False, rsv2=False, rsv3=False)
WARNING 02-27 14:20:09 EthstatsService Timeout: PeerPool did not answer PeerCountRequest
DEBUG 02-27 14:20:09 protocol client > Frame(fin=True, opcode=1, data=b'{"emit": ["stats", {"stats": {"active": true, "uptime": 100, "peers": 0}, "id": "snake charmers"}]}', rsv1=False, rsv2=False, rsv3=False)
DEBUG 02-27 14:20:09 protocol client > Frame(fin=True, opcode=1, data=b'{"emit": ["block", {"block": {"number": 2162847, "hash": "0x4870f75183a92b9060237fb3e8e4aaa4471f8287148e271eb6a2bbdcf4d6355f", "difficulty": 67136930857890, "totalDifficulty": 53894140034325532197, "transactions": [], "uncles": []}, "id": "snake charmers"}]}', rsv1=False, rsv2=False, rsv3=False)
DEBUG 02-27 14:20:11 DiscoveryProtocol Ignoring find_node request from unknown node <Node(0x946d@35.244.111.53)>
DEBUG 02-27 14:20:11 DiscoveryProtocol Ignoring find_node request from unknown node <Node(0xf181@18.218.83.99)>
DEBUG 02-27 14:20:14 DiscoveryProtocol got no candidates from <Node(0x275c@47.75.57.4)>, returning
DEBUG 02-27 14:20:14 DiscoveryProtocol got no candidates from <Node(0xbaa3@66.147.236.60)>, returning
DEBUG 02-27 14:20:14 DiscoveryProtocol Ignoring find_node request from unknown node <Node(0x974c@13.229.240.12)>
DEBUG 02-27 14:20:14 DiscoveryProtocol bonding failed, didn't receive v4 pong from <Node(0x2ef1@47.92.139.81)>
DEBUG 02-27 14:20:14 DiscoveryProtocol bonding failed, didn't receive v4 pong from <Node(0xb1a9@120.79.49.225)>
DEBUG 02-27 14:20:15 DiscoveryProtocol Ignoring find_node request from unknown node <Node(0x9c62@18.210.18.88)>
DEBUG 02-27 14:20:15 DiscoveryProtocol Ignoring find_node request from unknown node <Node(0xb35f@54.175.255.215)>
DEBUG 02-27 14:20:15 DiscoveryProtocol Ignoring find_node request from unknown node <Node(0xecd9@218.19.145.16)>
DEBUG 02-27 14:20:15 DiscoveryProtocol Ignoring find_node request from unknown node <Node(0x68b3@35.172.159.245)>
DEBUG 02-27 14:20:17 DiscoveryProtocol Ignoring find_node request from unknown node <Node(0x2319@18.217.82.197)>
DEBUG 02-27 14:20:19 protocol client > Frame(fin=True, opcode=1, data=b'{"emit": ["node-ping", {"clientTime": 1551277219250, "id": "snake charmers"}]}', rsv1=False, rsv2=False, rsv3=False)
DEBUG 02-27 14:20:19 protocol client < Frame(fin=True, opcode=1, data=b'{"emit":["node-pong",{"clientTime":1551277219250,"serverTime":1551277219296}]}', rsv1=False, rsv2=False, rsv3=False)
DEBUG 02-27 14:20:19 protocol client > Frame(fin=True, opcode=1, data=b'{"emit": ["latency", {"latency": 24, "id": "snake charmers"}]}', rsv1=False, rsv2=False, rsv3=False)
DEBUG 02-27 14:20:20 DiscoveryProtocol Ignoring find_node request from unknown node <Node(0x14d0@104.248.182.118)>
WARNING 02-27 14:20:20 EthstatsService Timeout: PeerPool did not answer PeerCountRequest
DEBUG 02-27 14:20:20 protocol client > Frame(fin=True, opcode=1, data=b'{"emit": ["stats", {"stats": {"active": true, "uptime": 100, "peers": 0}, "id": "snake charmers"}]}', rsv1=False, rsv2=False, rsv3=False)
DEBUG 02-27 14:20:20 protocol client > Frame(fin=True, opcode=1, data=b'{"emit": ["block", {"block": {"number": 2162847, "hash": "0x4870f75183a92b9060237fb3e8e4aaa4471f8287148e271eb6a2bbdcf4d6355f", "difficulty": 67136930857890, "totalDifficulty": 53894140034325532197, "transactions": [], "uncles": []}, "id": "snake charmers"}]}', rsv1=False, rsv2=False, rsv3=False)
DEBUG 02-27 14:20:21 HeaderMeatSyncer Header Skeleton Gaps: active=3 queued=50 max=50
DEBUG 02-27 14:20:21 FastChainBodySyncer (in progress, queued, max size) of bodies, receipts: [(0, 0, 1024), (256, 2048, 2048)]
INFO 02-27 14:20:21 FastChainBodySyncer blks=0 txs=0 bps=9 tps=72 elapsed=17.0 head=#2162847 4870..355f age=2y5m4w
DEBUG 02-27 14:20:21 SkeletonSyncer Skeleton header queue is 507/1536 full
DEBUG 02-27 14:20:21 FullServer Receiving handshake from Address(113.57.95.13:udp:60970|tcp:60970)
DEBUG 02-27 14:20:21 FullServer Receiving handshake from Address(124.205.145.236:udp:58354|tcp:58354)
DEBUG 02-27 14:20:21 FullServer Receiving handshake from Address(212.32.246.39:udp:46680|tcp:46680)
DEBUG 02-27 14:20:21 FullServer Receiving handshake from Address(46.105.132.13:udp:38096|tcp:38096)
DEBUG 02-27 14:20:21 FullServer Receiving handshake from Address(13.125.153.167:udp:60770|tcp:60770)
DEBUG 02-27 14:20:21 FullServer Receiving handshake from Address(35.244.111.53:udp:4612|tcp:4612)
DEBUG 02-27 14:20:21 FullServer Receiving handshake from Address(113.74.126.241:udp:2467|tcp:2467)
DEBUG 02-27 14:20:21 FullServer Receiving handshake from Address(202.94.70.51:udp:62256|tcp:62256)
DEBUG 02-27 14:20:22 FullServer Receiving handshake from Address(39.174.145.236:udp:30321|tcp:30321)
DEBUG 02-27 14:20:22 FullServer Receiving handshake from Address(18.218.83.99:udp:55052|tcp:55052)
DEBUG 02-27 14:20:22 FullServer Receiving handshake from Address(13.229.240.12:udp:40144|tcp:40144)
DEBUG 02-27 14:20:22 FullServer Receiving handshake from Address(213.61.105.52:udp:60900|tcp:60900)
DEBUG 02-27 14:20:22 FullServer Receiving handshake from Address(219.87.131.5:udp:49563|tcp:49563)
DEBUG 02-27 14:20:22 FullServer Receiving handshake from Address(218.19.145.16:udp:6839|tcp:6839)
DEBUG 02-27 14:20:22 ETHPeer Disconnecting from remote peer <Node(0x0183@159.69.65.42)>; reason: useless_peer
DEBUG 02-27 14:20:22 FastChainBodySyncer Requesting receipts for 154 headers from ETHPeer <Node(0x3942@52.56.62.122)>
DEBUG 02-27 14:20:22 ETHPeer Disconnecting from remote peer <Node(0xdcec@113.57.95.13)>; reason: too_many_peers
DEBUG 02-27 14:20:22 ETHPeer Disconnecting from remote peer <Node(0xb1a7@113.74.126.241)>; reason: too_many_peers
DEBUG 02-27 14:20:22 ETHPeer Disconnecting from remote peer <Node(0x494a@202.94.70.51)>; reason: too_many_peers
DEBUG 02-27 14:20:22 ETHPeer Disconnecting from remote peer <Node(0x5812@213.61.105.52)>; reason: too_many_peers
DEBUG 02-27 14:20:22 ETHPeer Disconnecting from remote peer <Node(0xc42d@219.87.131.5)>; reason: too_many_peers
DEBUG 02-27 14:20:22 ETHPeerPool Could not complete handshake with <Node(0x0183b5680ce58640c70c8e8cc11ffd933ad391d1be84bbe4bc5f538e727580637de6ab6c4c0ab36d557f8882256e3382bd800ee41bb7d04632502f29968b3db0@159.69.65.42:21228)>: HandshakeFailure("No matching capabilities between us ([('eth', 63)]) and <Node(0x0183@159.69.65.42)> ((('etz', 62), ('etz', 63), ('etz', 64))), disconnecting",)
DEBUG 02-27 14:20:22 SQLitePeerInfo <Node(0x0183@159.69.65.42)> will not be retried until 2019-02-27 14:20:32.219643 because HandshakeFailure
DEBUG 02-27 14:20:22 DiscoveryProtocol Ignoring find_node request from unknown node <Node(0x14aa@140.110.96.81)>
DEBUG 02-27 14:20:22 ETHPeer Disconnecting from remote peer <Node(0x9dc5@124.205.145.236)>; reason: too_many_peers
DEBUG 02-27 14:20:22 ETHPeer Disconnecting from remote peer <Node(0xc361@212.32.246.39)>; reason: too_many_peers
DEBUG 02-27 14:20:22 ETHPeer Disconnecting from remote peer <Node(0x688c@46.105.132.13)>; reason: too_many_peers
DEBUG 02-27 14:20:22 ETHPeer Disconnecting from remote peer <Node(0x0515@13.125.153.167)>; reason: too_many_peers
DEBUG 02-27 14:20:22 ETHPeer Disconnecting from remote peer <Node(0x946d@35.244.111.53)>; reason: too_many_peers
DEBUG 02-27 14:20:22 ETHPeer Disconnecting from remote peer <Node(0x3e34@39.174.145.236)>; reason: too_many_peers
DEBUG 02-27 14:20:22 ETHPeer Disconnecting from remote peer <Node(0xf181@18.218.83.99)>; reason: too_many_peers
DEBUG 02-27 14:20:22 ETHPeer Disconnecting from remote peer <Node(0x974c@13.229.240.12)>; reason: too_many_peers
DEBUG 02-27 14:20:22 ETHPeer Disconnecting from remote peer <Node(0xecd9@218.19.145.16)>; reason: too_many_peers
DEBUG 02-27 14:20:22 DiscoveryProtocol got no candidates from <Node(0x03e2@116.25.43.189)>, returning
DEBUG 02-27 14:20:22 DiscoveryProtocol got no candidates from <Node(0x8d48@45.43.30.2)>, returning
DEBUG 02-27 14:20:23 DiscoveryProtocol Ignoring find_node request from unknown node <Node(0xf269@221.176.33.43)>
DEBUG 02-27 14:20:24 DiscoveryProtocol Ignoring find_node request from unknown node <Node(0x68c4@18.130.240.247)>
DEBUG 02-27 14:20:26 DiscoveryProtocol Ignoring find_node request from unknown node <Node(0x38a1@35.173.156.149)>
DEBUG 02-27 14:20:27 DiscoveryProtocol Ignoring find_node request from unknown node <Node(0x1900@94.130.160.216)>
DEBUG 02-27 14:20:28 DiscoveryProtocol Ignoring find_node request from unknown node <Node(0x80fd@46.229.218.226)>
DEBUG 02-27 14:20:30 DiscoveryProtocol got no candidates from <Node(0xf99f@119.53.210.112)>, returning
DEBUG 02-27 14:20:30 DiscoveryProtocol got no candidates from <Node(0xf62e@192.34.63.106)>, returning
DEBUG 02-27 14:20:30 DiscoveryProtocol got no candidates from <Node(0xbf8f@104.36.250.11)>, returning
DEBUG 02-27 14:20:30 protocol client > Frame(fin=True, opcode=1, data=b'{"emit": ["node-ping", {"clientTime": 1551277230262, "id": "snake charmers"}]}', rsv1=False, rsv2=False, rsv3=False)
DEBUG 02-27 14:20:30 protocol client < Frame(fin=True, opcode=1, data=b'{"emit":["node-pong",{"clientTime":1551277230262,"serverTime":1551277230303}]}', rsv1=False, rsv2=False, rsv3=False)
DEBUG 02-27 14:20:30 protocol client > Frame(fin=True, opcode=1, data=b'{"emit": ["latency", {"latency": 22, "id": "snake charmers"}]}', rsv1=False, rsv2=False, rsv3=False)
WARNING 02-27 14:20:31 EthstatsService Timeout: PeerPool did not answer PeerCountRequest
DEBUG 02-27 14:20:31 protocol client > Frame(fin=True, opcode=1, data=b'{"emit": ["stats", {"stats": {"active": true, "uptime": 100, "peers": 0}, "id": "snake charmers"}]}', rsv1=False, rsv2=False, rsv3=False)
DEBUG 02-27 14:20:31 protocol client > Frame(fin=True, opcode=1, data=b'{"emit": ["block", {"block": {"number": 2162851, "hash": "0xa2609c21228d6bbe4d96bf287b4e30be73a68c5ec1064c49c578865b536149a5", "difficulty": 67202512371030, "totalDifficulty": 53894408811574159121, "transactions": [], "uncles": []}, "id": "snake charmers"}]}', rsv1=False, rsv2=False, rsv3=False)
DEBUG 02-27 14:20:33 DiscoveryProtocol Ignoring find_node request from unknown node <Node(0x4f7a@198.211.114.59)>
DEBUG 02-27 14:20:35 DiscoveryProtocol Ignoring find_node request from unknown node <Node(0x9972@23.23.210.82)>
DEBUG 02-27 14:20:35 DiscoveryProtocol Ignoring find_node request from unknown node <Node(0xa621@31.200.231.26)>
DEBUG 02-27 14:20:36 DiscoveryProtocol Ignoring find_node request from unknown node <Node(0x946d@35.244.111.53)>
DEBUG 02-27 14:20:36 DiscoveryProtocol Ignoring find_node request from unknown node <Node(0x839c@13.230.14.157)>
DEBUG 02-27 14:20:37 DiscoveryProtocol got no candidates from <Node(0x6b1c@47.98.57.183)>, returning
DEBUG 02-27 14:20:37 HeaderMeatSyncer Header Skeleton Gaps: active=3 queued=49 max=50
DEBUG 02-27 14:20:37 FastChainBodySyncer (in progress, queued, max size) of bodies, receipts: [(0, 0, 1024), (512, 2048, 2048)]
INFO 02-27 14:20:37 FastChainBodySyncer blks=4 txs=30 bps=9 tps=68 elapsed=15.5 head=#2162851 a260..49a5 age=2y5m4w
DEBUG 02-27 14:20:37 SkeletonSyncer Skeleton header queue is 507/1536 full
DEBUG 02-27 14:20:37 HeaderMeatSyncer Requesting 192 headers from ETHPeer <Node(0xae4f@83.79.101.201)>
DEBUG 02-27 14:20:37 HeaderMeatSyncer Requesting 192 headers from ETHPeer <Node(0x3942@52.56.62.122)>
DEBUG 02-27 14:20:37 FullServer Receiving handshake from Address(18.217.82.197:udp:57236|tcp:57236)
DEBUG 02-27 14:20:37 FullServer Receiving handshake from Address(140.110.96.81:udp:52488|tcp:52488)
DEBUG 02-27 14:20:37 FullServer Receiving handshake from Address(147.92.132.152:udp:59052|tcp:59052)
DEBUG 02-27 14:20:37 FullServer Receiving handshake from Address(124.205.145.235:udp:15611|tcp:15611)
DEBUG 02-27 14:20:37 FullServer Receiving handshake from Address(58.69.2.254:udp:51833|tcp:51833)
DEBUG 02-27 14:20:37 FullServer Receiving handshake from Address(167.114.169.69:udp:54774|tcp:54774)
DEBUG 02-27 14:20:37 FullServer Receiving handshake from Address(35.173.156.149:udp:55604|tcp:55604)
DEBUG 02-27 14:20:37 FullServer Receiving handshake from Address(18.222.37.213:udp:4591|tcp:4591)
DEBUG 02-27 14:20:37 FullServer Receiving handshake from Address(124.155.55.165:udp:50216|tcp:50216)
DEBUG 02-27 14:20:37 FullServer Receiving handshake from Address(54.190.108.204:udp:48618|tcp:48618)
DEBUG 02-27 14:20:37 FullServer Receiving handshake from Address(221.176.33.43:udp:20431|tcp:20431)
DEBUG 02-27 14:20:37 FullServer Receiving handshake from Address(34.244.3.36:udp:56022|tcp:56022)
DEBUG 02-27 14:20:37 FullServer Receiving handshake from Address(92.63.23.126:udp:55819|tcp:55819)
DEBUG 02-27 14:20:37 FullServer Receiving handshake from Address(3.94.148.1:udp:43586|tcp:43586)
DEBUG 02-27 14:20:37 FullServer Receiving handshake from Address(46.229.218.226:udp:31260|tcp:31260)
DEBUG 02-27 14:20:37 FullServer Receiving handshake from Address(3.94.148.1:udp:43650|tcp:43650)
DEBUG 02-27 14:20:37 FullServer Receiving handshake from Address(18.203.7.233:udp:54620|tcp:54620)
DEBUG 02-27 14:20:37 FullServer Receiving handshake from Address(92.246.140.111:udp:34628|tcp:34628)
DEBUG 02-27 14:20:37 FullServer Receiving handshake from Address(31.200.231.26:udp:40028|tcp:40028)
DEBUG 02-27 14:20:37 ETHPeer Finished P2P handshake with <Node(0x3454@54.85.39.131)>, using sub-protocol (eth, 63)
DEBUG 02-27 14:20:37 DiscoveryProtocol bonding failed, didn't receive v4 pong from <Node(0x0adf@37.187.21.152)>
DEBUG 02-27 14:20:37 ETHPeer Disconnecting from remote peer <Node(0xd7a3@147.92.132.152)>; reason: too_many_peers
DEBUG 02-27 14:20:37 ETHPeer Disconnecting from remote peer <Node(0x5167@58.69.2.254)>; reason: too_many_peers
DEBUG 02-27 14:20:37 ETHPeer Disconnecting from remote peer <Node(0xae0e@18.222.37.213)>; reason: too_many_peers
DEBUG 02-27 14:20:37 ETHPeer Disconnecting from remote peer <Node(0xbef2@124.155.55.165)>; reason: too_many_peers
DEBUG 02-27 14:20:37 ETHPeer Disconnecting from remote peer <Node(0xbb05@54.190.108.204)>; reason: too_many_peers
DEBUG 02-27 14:20:37 ETHPeer Disconnecting from remote peer <Node(0x7509@92.63.23.126)>; reason: too_many_peers
DEBUG 02-27 14:20:37 ETHPeer Disconnecting from remote peer <Node(0xcd59@3.94.148.1)>; reason: too_many_peers
DEBUG 02-27 14:20:37 ETHPeer Disconnecting from remote peer <Node(0xcd59@3.94.148.1)>; reason: too_many_peers
DEBUG 02-27 14:20:37 ETHPeer Disconnecting from remote peer <Node(0xb512@18.203.7.233)>; reason: too_many_peers
DEBUG 02-27 14:20:37 ETHPeer Disconnecting from remote peer <Node(0x61ef@92.246.140.111)>; reason: too_many_peers
DEBUG 02-27 14:20:37 ETHPeer Disconnecting from remote peer <Node(0x2319@18.217.82.197)>; reason: too_many_peers
DEBUG 02-27 14:20:37 ETHPeer Disconnecting from remote peer <Node(0x14aa@140.110.96.81)>; reason: too_many_peers
DEBUG 02-27 14:20:37 ETHPeer Disconnecting from remote peer <Node(0x743b@124.205.145.235)>; reason: too_many_peers
DEBUG 02-27 14:20:37 ETHPeer Disconnecting from remote peer <Node(0x1991@167.114.169.69)>; reason: too_many_peers
DEBUG 02-27 14:20:37 ETHPeer Disconnecting from remote peer <Node(0x38a1@35.173.156.149)>; reason: too_many_peers
DEBUG 02-27 14:20:37 ETHPeer Disconnecting from remote peer <Node(0xf269@221.176.33.43)>; reason: too_many_peers
DEBUG 02-27 14:20:37 ETHPeer Disconnecting from remote peer <Node(0x579f@34.244.3.36)>; reason: too_many_peers
DEBUG 02-27 14:20:37 ETHPeer Disconnecting from remote peer <Node(0x80fd@46.229.218.226)>; reason: too_many_peers
DEBUG 02-27 14:20:37 ETHPeer Disconnecting from remote peer <Node(0xa621@31.200.231.26)>; reason: too_many_peers
DEBUG 02-27 14:20:37 FullServer Receiving handshake from Address(13.230.14.157:udp:27836|tcp:27836)
DEBUG 02-27 14:20:38 ETHPeer Disconnecting from remote peer <Node(0x839c@13.230.14.157)>; reason: too_many_peers
DEBUG 02-27 14:20:38 ETHPeerPool Could not complete handshake with <Node(0x34546aeb2a8c5307c6fef26a70f98392b7eed8a0abb8ae1619119d68d576e9bb0aeb80f633cdcabd713dedaf632feea52bfa6589e411bc2855dff91784fa4140@54.85.39.131:30303)>: TooManyPeersFailure('ETHPeer <Node(0x3454@54.85.39.131)> disconnected from us before handshake',)
DEBUG 02-27 14:20:38 SQLitePeerInfo <Node(0x3454@54.85.39.131)> will not be retried until 2019-02-27 14:22:38.239875 because TooManyPeersFailure
DEBUG 02-27 14:20:38 FullServer Receiving handshake from Address(47.89.13.79:udp:43708|tcp:43708)
DEBUG 02-27 14:20:38 FullServer Receiving handshake from Address(31.40.25.232:udp:59627|tcp:59627)
DEBUG 02-27 14:20:38 ETHPeer Disconnecting from remote peer <Node(0x71e8@31.40.25.232)>; reason: too_many_peers
DEBUG 02-27 14:20:38 ETHPeer Disconnecting from remote peer <Node(0x948e@47.89.13.79)>; reason: too_many_peers
DEBUG 02-27 14:20:38 FullServer Receiving handshake from Address(54.154.31.83:udp:52144|tcp:52144)
DEBUG 02-27 14:20:38 ETHPeer Disconnecting from remote peer <Node(0xdb7b@54.154.31.83)>; reason: too_many_peers
DEBUG 02-27 14:20:38 ETHPeer Disconnecting from remote peer <Node(0x10ab@159.69.69.123)>; reason: useless_peer
DEBUG 02-27 14:20:38 ETHPeerPool Could not complete handshake with <Node(0x10ab696b57a717b701fb3e71b8727c33939e1008c5cae4519a544be10478a28343989ed2ca4f2c6336d385b0ebbf358237aedc459148ae6acc30b596a31ee5c5@159.69.69.123:21223)>: HandshakeFailure("No matching capabilities between us ([('eth', 63)]) and <Node(0x10ab@159.69.69.123)> ((('etz', 62), ('etz', 63), ('etz', 64))), disconnecting",)
DEBUG 02-27 14:20:38 SQLitePeerInfo <Node(0x10ab@159.69.69.123)> will not be retried until 2019-02-27 14:20:58.686651 because HandshakeFailure
DEBUG 02-27 14:20:38 SQLitePeerInfo skipping <Node(0x4d6f@54.37.131.186)>, it failed because "WrongNetworkFailure" and is not usable until 2019-02-27 15:29:26
DEBUG 02-27 14:20:38 FullServer Receiving handshake from Address(23.23.210.82:udp:42974|tcp:42974)
DEBUG 02-27 14:20:38 ETHPeer Disconnecting from remote peer <Node(0x9972@23.23.210.82)>; reason: too_many_peers
DEBUG 02-27 14:20:39 ETHPeer Disconnecting from remote peer <Node(0xa543@159.69.65.40)>; reason: useless_peer
DEBUG 02-27 14:20:39 ETHPeerPool Could not complete handshake with <Node(0xa5437b58dd9379130dcdbbfe109d5f2e4eb4cb3c6c4404b6d588cceb4daac4a42719596ace09f63942289f55367d54c47badbfbb7da89c2e396215b2c5ff1fc2@159.69.65.40:21219)>: HandshakeFailure("No matching capabilities between us ([('eth', 63)]) and <Node(0xa543@159.69.65.40)> ((('etz', 63), ('etz', 64))), disconnecting",)
DEBUG 02-27 14:20:39 SQLitePeerInfo <Node(0xa543@159.69.65.40)> will not be retried until 2019-02-27 14:21:19.015255 because HandshakeFailure
INFO 02-27 14:20:39 ETHPeerPool Connected peers: 2 inbound, 0 outbound
INFO 02-27 14:20:39 ETHPeerPool Peer subscribers: 4, longest queue: ETHChainTipMonitor(0)
DEBUG 02-27 14:20:39 ETHPeerPool == Peer details ==
DEBUG 02-27 14:20:39 ETHPeerPool ETHPeer <Node(0xae4f@83.79.101.201)>: uptime=0:00:15:18, received_msgs=1090, most_received=Transactions (cmd_id=18)(917)
DEBUG 02-27 14:20:39 ETHPeerPool BlockBodies: None
DEBUG 02-27 14:20:39 ETHPeerPool BlockHeaders: msgs=70 items=12290 rtt=5.27/17.91/4.71 ips=84.88111 timeouts=5 quality=91
DEBUG 02-27 14:20:39 ETHPeerPool NodeData: None
DEBUG 02-27 14:20:39 ETHPeerPool Receipts: msgs=18 items=30120 rtt=13.43/17.19/6.16 ips=3753.03334 timeouts=3 quality=49
DEBUG 02-27 14:20:39 ETHPeerPool ETHPeer <Node(0x3942@52.56.62.122)>: uptime=0:00:00:58, received_msgs=16, most_received=BlockHeaders (cmd_id=20)(6)
DEBUG 02-27 14:20:39 ETHPeerPool BlockBodies: None
DEBUG 02-27 14:20:39 ETHPeerPool BlockHeaders: msgs=6 items=962 rtt=15.74/14.45/5.65 ips=29.09886 timeouts=0 quality=26
DEBUG 02-27 14:20:39 ETHPeerPool NodeData: None
DEBUG 02-27 14:20:39 ETHPeerPool Receipts: msgs=2 items=4486 rtt=19.01/15.46/8.39 ips=47.88695 timeouts=0 quality=9
DEBUG 02-27 14:20:39 ETHPeerPool == End peer details ==
DEBUG 02-27 14:20:39 ETHPeer Disconnecting from remote peer <Node(0x341e@95.216.33.9)>; reason: useless_peer
DEBUG 02-27 14:20:39 ETHPeerPool Could not complete handshake with <Node(0x341e33ae62dbb645faa69b89b26a38236577818813c97367ba0b46122138b154332bf706c1e9e58669694f5bde18c7f32293bb262323af4fb59ec0ad1049c93f@95.216.33.9:21223)>: HandshakeFailure("No matching capabilities between us ([('eth', 63)]) and <Node(0x341e@95.216.33.9)> ((('etz', 63), ('etz', 64))), disconnecting",)
DEBUG 02-27 14:20:39 SQLitePeerInfo <Node(0x341e@95.216.33.9)> will not be retried until 2019-02-27 14:20:49.442887 because HandshakeFailure
DEBUG 02-27 14:20:39 DiscoveryProtocol Ignoring find_node request from unknown node <Node(0x6a15@142.93.41.130)>
DEBUG 02-27 14:20:39 HeaderMeatSyncer Requesting 192 headers from ETHPeer <Node(0x3942@52.56.62.122)>
DEBUG 02-27 14:20:39 ETHPeer Finished P2P handshake with <Node(0x0c99@18.204.243.95)>, using sub-protocol (eth, 63)
DEBUG 02-27 14:20:39 ETHPeerPool Could not complete handshake with <Node(0x0c99abac224ffe12dcc879134376cf09563ec158353e34658764e6ae5ad11e33f65734f37bead7f2af242c6cb3580c00f1343fe45df2bd9cf649b87c7f2b7b1a@18.204.243.95:30303)>: PeerConnectionLost("IncompleteReadError('0 bytes read on a total of 32 expected bytes',)",)
DEBUG 02-27 14:20:39 ETHPeer Disconnecting from remote peer <Node(0x14f2@3.8.131.178)>; reason: useless_peer
DEBUG 02-27 14:20:39 ETHPeerPool Could not complete handshake with <Node(0x14f2a4e39998a282636f1ee1e8e4ed29bd2b1285f9dad01837ac1453ea43d648dd97ea73adb301965b66e980a1617eace836dba926c1f932bcaf566fe7a74889@3.8.131.178:32303)>: HandshakeFailure("No matching capabilities between us ([('eth', 63)]) and <Node(0x14f2@3.8.131.178)> ((('wolk', 66),)), disconnecting",)
DEBUG 02-27 14:20:39 SQLitePeerInfo <Node(0x14f2@3.8.131.178)> will not be retried until 2019-02-27 14:20:59.917272 because HandshakeFailure
DEBUG 02-27 14:20:40 FullServer Receiving handshake from Address(168.62.42.224:udp:54879|tcp:54879)
DEBUG 02-27 14:20:40 ETHPeer Disconnecting from remote peer <Node(0x2b2e@168.62.42.224)>; reason: too_many_peers
DEBUG 02-27 14:20:40 HeaderMeatSyncer Requesting 192 headers from ETHPeer <Node(0x3942@52.56.62.122)>
DEBUG 02-27 14:20:41 protocol client > Frame(fin=True, opcode=1, data=b'{"emit": ["node-ping", {"clientTime": 1551277241274, "id": "snake charmers"}]}', rsv1=False, rsv2=False, rsv3=False)
DEBUG 02-27 14:20:41 protocol client > Frame(fin=True, opcode=1, data=b'{"emit": ["stats", {"stats": {"active": true, "uptime": 100, "peers": 2}, "id": "snake charmers"}]}', rsv1=False, rsv2=False, rsv3=False)
DEBUG 02-27 14:20:41 protocol client > Frame(fin=True, opcode=1, data=b'{"emit": ["block", {"block": {"number": 2163133, "hash": "0x13d2a1abd3ec6334781eddb1ca5c406578c44f156729bcad7354ab7083cabeea", "difficulty": 67263776463855, "totalDifficulty": 53913326530423837584, "transactions": [], "uncles": []}, "id": "snake charmers"}]}', rsv1=False, rsv2=False, rsv3=False)
DEBUG 02-27 14:20:41 protocol client < Frame(fin=True, opcode=1, data=b'{"emit":["node-pong",{"clientTime":1551277241274,"serverTime":1551277241309}]}', rsv1=False, rsv2=False, rsv3=False)
DEBUG 02-27 14:20:41 protocol client > Frame(fin=True, opcode=1, data=b'{"emit": ["latency", {"latency": 19, "id": "snake charmers"}]}', rsv1=False, rsv2=False, rsv3=False)
DEBUG 02-27 14:20:41 DiscoveryService Node discovery lookup already in progress, not running another
DEBUG 02-27 14:20:42 FastChainBodySyncer Got receipts for 157/157 headers from ETHPeer <Node(0xae4f@83.79.101.201)>, with 99 trivial headers
DEBUG 02-27 14:20:45 DiscoveryProtocol Ignoring find_node request from unknown node <Node(0xfaea@193.112.186.234)>
DEBUG 02-27 14:20:45 DiscoveryProtocol lookup finished for target 0xea766e51b7bf21e18c077e51e2e3d2bcb6e85a13d768940115280afe1c4413f5; closest neighbours: [<Node(0xbaa353e8dd211ea87b8a4371c585ec1692af7fbf3dfad6de2f3d5fdacab9902e945289550cac9dd83c8dda5fea99c5a43a3a67805578339c3aed27d60b79b54f@66.147.236.60:30303)>, <Node(0x63614239d0fa8755e064027855dda6ea446cb9b1356a8400eae271e4ba333fa2254ba24337ad30e0c6fafa29d84cd4a3b80e31b7319258c9bd04b4dbfcd0bb86@47.100.64.140:33333)>, <Node(0x275c8fc474b30f89507c28e2aef77283346b73784702b38d9f090bb8ba0a82169f354ca0ae62fbef81fe98c7e354fa51b939823f9210e53ff1cbba360e2eb272@47.75.57.4:34344)>, <Node(0x03e2114773c8054691954ddc2cb63f3001137e2e127afb81e64d41500d7f5bd55072ced2216b1b37061bc4ac113a0b0c51834b7eb894dddf9d0dfd2b217eee2d@116.25.43.189:3647)>, <Node(0x8d48a8bb88412927ebdb460372a9705387bc10fd5726cb043632be8f97637202f3587a6d6d31859b95f485d56d6ff3dadf98748307505566717ab1ba41cdd50b@45.43.30.2:38003)>, <Node(0x8d48a8bb88412927ebdb460372a9705387bc10fd5726cb043632be8f97637202f3587a6d6d31859b95f485d56d6ff3dadf98748307505566717ab1ba41cdd50b@45.43.30.2:38003)>, <Node(0xe05473efef5b907485e0b188830272be2e5591e815e37c9fd77008c29909169beb5610041e20cd2734a1826e46ecb5c04c63eebbbe3f55fbad772da9b1db17ff@95.216.34.99:10964)>, <Node(0xbf8f9b3d79db2a48ed2fb489f2a2a79fd3fec5f7959c203b1150c056d1413e84422cf62e9ed1804408b5de4bee2209c7b33aca3c7e7b91a16f7ead35bfbaab3f@104.36.250.11:6397)>, <Node(0xf62e3bd6c91042d296a9894993a2220b5cd11ecb32eb33f84204c6890f25c197bd5f2002c4759d1784e771273c6489f67ffa8e93ecac72abb0e64f580c83356f@192.34.63.106:30303)>, <Node(0xf99f521fb31742b55281a2e8e6175f89187c8647a53c143c1c5b0a73cb7512221c3d8ff017a3fc5ead1ef588d95c89c246fdc898bb51cc211459cbbbfe3e601d@119.53.210.112:30445)>, <Node(0x60ad4f2988a6ec4ea260cc16b57209e1ba5dc55f6d274c93d00b31a46592a3416126c8b2b3724fe1c71c1ce277a2bea8a314603ccb7f360642bb3a4a52b12723@47.244.115.106:30303)>, <Node(0x60ad4f2988a6ec4ea260cc16b57209e1ba5dc55f6d274c93d00b31a46592a3416126c8b2b3724fe1c71c1ce277a2bea8a314603ccb7f360642bb3a4a52b12723@47.244.115.106:30303)>, <Node(0x6b1c5f930f9dfeb31c08732ac56fc0cfdf6bda2ac47211499e838284fa1ce0e94561f5df4694d551367dadb7215541657a612fb987c3239c5f853a18816fe1c1@47.98.57.183:30303)>, <Node(0x31b5c3471dece62e009a7d92c58a5a21309bad1d538b5fd08b94f60dbc1a6bbd8f4af61351bb9bf357ec5b3ea1b546309a2aca2cd3f4aedc33798f09dd1bbb20@173.255.253.153:51057)>, <Node(0xc2584c8cbff561cf2f377f17a26fec58476ad391c9ae11d69dd1ed9d93bac4c982b9b19a42fda082a17c6b86d7fa8afcaa0a5ec22e109bf55470350b534949a8@108.61.72.29:21212)>, <Node(0xa85edad93f58e3739af35cbabe084fbc36e2985f0387bfd6d37d9a39df4e9401dab8e06fcd910e9048dafd7fa30017dd1b47f4676c18f941e571524be342f0e0@158.69.113.211:30305)>]
DEBUG 02-27 14:20:45 DiscoveryProtocol Ignoring find_node request from unknown node <Node(0x6a53@50.225.198.67)>
DEBUG 02-27 14:20:47 DiscoveryProtocol Ignoring find_node request from unknown node <Node(0x3281@119.53.210.108)>
DEBUG 02-27 14:20:48 HeaderMeatSyncer Header Skeleton Gaps: active=3 queued=50 max=50
DEBUG 02-27 14:20:48 FastChainBodySyncer (in progress, queued, max size) of bodies, receipts: [(0, 0, 1024), (256, 2048, 2048)]
INFO 02-27 14:20:48 FastChainBodySyncer blks=370 txs=4449 bps=10 tps=84 elapsed=11.2 head=#2163221 ce48..1b6d age=2y5m4w
DEBUG 02-27 14:20:48 SkeletonSyncer Skeleton header queue is 503/1536 full
DEBUG 02-27 14:20:48 FullServer Receiving handshake from Address(45.32.249.174:udp:48992|tcp:48992)
DEBUG 02-27 14:20:48 FullServer Receiving handshake from Address(139.99.8.232:udp:47732|tcp:47732)
DEBUG 02-27 14:20:48 FullServer Receiving handshake from Address(37.48.117.195:udp:45530|tcp:45530)
DEBUG 02-27 14:20:48 FullServer Receiving handshake from Address(98.167.58.103:udp:33356|tcp:33356)
DEBUG 02-27 14:20:48 FullServer Receiving handshake from Address(34.213.209.132:udp:32126|tcp:32126)
DEBUG 02-27 14:20:48 FullServer Receiving handshake from Address(120.77.102.101:udp:34668|tcp:34668)
DEBUG 02-27 14:20:48 FullServer Receiving handshake from Address(113.74.126.241:udp:3154|tcp:3154)
DEBUG 02-27 14:20:48 FastChainBodySyncer Requesting receipts for 164 headers from ETHPeer <Node(0xae4f@83.79.101.201)>
DEBUG 02-27 14:20:48 ETHPeer Disconnecting from remote peer <Node(0x16a0@139.99.8.232)>; reason: too_many_peers
DEBUG 02-27 14:20:48 ETHPeer Disconnecting from remote peer <Node(0xa15e@98.167.58.103)>; reason: too_many_peers
DEBUG 02-27 14:20:48 ETHPeer Disconnecting from remote peer <Node(0x54ad@34.213.209.132)>; reason: too_many_peers
DEBUG 02-27 14:20:48 ETHPeer Disconnecting from remote peer <Node(0xb1a7@113.74.126.241)>; reason: too_many_peers
DEBUG 02-27 14:20:48 ETHPeerPool Could not complete handshake with <Node(0x9452f0f284518dc90d3fd690587fa8263ecd8cd6d8e4a352ab84f2fb2b2d9c15ad7a97fd368db39b049bf4cdc3fe9a2b24f4017463cfdbfe314ecb6ee368d3b5@34.73.32.242:30303)>: TooManyPeersFailure('ETHPeer <Node(0x9452@34.73.32.242)> disconnected from us before handshake',)
DEBUG 02-27 14:20:48 SQLitePeerInfo <Node(0x9452@34.73.32.242)> will not be retried until 2019-02-27 14:21:48.856773 because TooManyPeersFailure
DEBUG 02-27 14:20:48 ETHPeer Disconnecting from remote peer <Node(0xc693@45.32.249.174)>; reason: too_many_peers
DEBUG 02-27 14:20:48 ETHPeer Disconnecting from remote peer <Node(0xb6df@37.48.117.195)>; reason: too_many_peers
DEBUG 02-27 14:20:49 ETHPeer Disconnecting from remote peer <Node(0xebcb@120.77.102.101)>; reason: too_many_peers
DEBUG 02-27 14:20:49 HeaderMeatSyncer Requesting 192 headers from ETHPeer <Node(0x3942@52.56.62.122)>
DEBUG 02-27 14:20:49 ETHPeerPool Could not complete handshake with <Node(0xb13e25fc850a2978437faf7b2b431f302cef362b1b4b2b4a69fb2ba36e688dca9a3a459be940f2ed2a321a22ad47548f692cceb3de27fd7c5f9a93d8163af6da@23.239.13.138:30303)>: TooManyPeersFailure('ETHPeer <Node(0xb13e@23.239.13.138)> disconnected from us before handshake',)
DEBUG 02-27 14:20:49 SQLitePeerInfo <Node(0xb13e@23.239.13.138)> will not be retried until 2019-02-27 14:25:49.173773 because TooManyPeersFailure
DEBUG 02-27 14:20:49 FastChainBodySyncer Got receipts for 154/154 headers from ETHPeer <Node(0x3942@52.56.62.122)>, with 102 trivial headers
DEBUG 02-27 14:20:50 DiscoveryProtocol Ignoring find_node request from unknown node <Node(0x14aa@140.110.96.81)>
DEBUG 02-27 14:20:51 protocol client > Frame(fin=True, opcode=1, data=b'{"emit": ["node-ping", {"clientTime": 1551277251286, "id": "snake charmers"}]}', rsv1=False, rsv2=False, rsv3=False)
DEBUG 02-27 14:20:51 protocol client < Frame(fin=True, opcode=1, data=b'{"emit":["node-pong",{"clientTime":1551277251286,"serverTime":1551277251328}]}', rsv1=False, rsv2=False, rsv3=False)
DEBUG 02-27 14:20:51 protocol client > Frame(fin=True, opcode=1, data=b'{"emit": ["latency", {"latency": 22, "id": "snake charmers"}]}', rsv1=False, rsv2=False, rsv3=False)
DEBUG 02-27 14:20:51 DiscoveryProtocol Ignoring find_node request from unknown node <Node(0x38a1@35.173.156.149)>
DEBUG 02-27 14:20:52 DiscoveryProtocol Ignoring find_node request from unknown node <Node(0x946d@35.244.111.53)>
WARNING 02-27 14:20:52 EthstatsService Timeout: PeerPool did not answer PeerCountRequest
DEBUG 02-27 14:20:52 protocol client > Frame(fin=True, opcode=1, data=b'{"emit": ["stats", {"stats": {"active": true, "uptime": 100, "peers": 0}, "id": "snake charmers"}]}', rsv1=False, rsv2=False, rsv3=False)
DEBUG 02-27 14:20:52 protocol client > Frame(fin=True, opcode=1, data=b'{"emit": ["block", {"block": {"number": 2163278, "hash": "0x9aab1e1795cc6a218fe64cd0b1655de42a7d2a131f1d36c20b489166239f7803", "difficulty": 66966982694936, "totalDifficulty": 53923077224169251877, "transactions": [], "uncles": []}, "id": "snake charmers"}]}', rsv1=False, rsv2=False, rsv3=False)
DEBUG 02-27 14:20:54 HeaderMeatSyncer Header Skeleton Gaps: active=3 queued=50 max=50
DEBUG 02-27 14:20:54 FastChainBodySyncer (in progress, queued, max size) of bodies, receipts: [(0, 0, 1024), (256, 2048, 2048)]
INFO 02-27 14:20:54 FastChainBodySyncer blks=56 txs=359 bps=10 tps=83 elapsed=6.2 head=#2163277 6c9e..12f5 age=2y5m4w
DEBUG 02-27 14:20:54 SkeletonSyncer Skeleton header queue is 502/1536 full
DEBUG 02-27 14:20:54 FullServer Receiving handshake from Address(18.217.180.94:udp:43922|tcp:43922)
DEBUG 02-27 14:20:54 FullServer Receiving handshake from Address(219.87.131.5:udp:33713|tcp:33713)
DEBUG 02-27 14:20:54 FullServer Receiving handshake from Address(119.53.210.108:udp:14623|tcp:14623)
DEBUG 02-27 14:20:54 FullServer Receiving handshake from Address(35.244.111.53:udp:4807|tcp:4807)
DEBUG 02-27 14:20:54 FastChainBodySyncer Requesting receipts for 183 headers from ETHPeer <Node(0x3942@52.56.62.122)>
DEBUG 02-27 14:20:54 ETHPeer Disconnecting from remote peer <Node(0xc42d@219.87.131.5)>; reason: too_many_peers
DEBUG 02-27 14:20:54 ETHPeerPool Could not complete handshake with <Node(0x158f8aab45f6d19c6cbf4a089c2670541a8da11978a2f90dbf6a502a4a3bab80d288afdbeb7ec0ef6d92de563767f3b1ea9e8e334ca711e9f8e2df5a0385e8e6@13.75.154.138:30303)>: TooManyPeersFailure('ETHPeer <Node(0x158f@13.75.154.138)> disconnected from us before handshake',)
DEBUG 02-27 14:20:54 SQLitePeerInfo <Node(0x158f@13.75.154.138)> will not be retried until 2019-02-27 14:36:54.982272 because TooManyPeersFailure
DEBUG 02-27 14:20:55 ETHPeer Disconnecting from remote peer <Node(0x271c@18.217.180.94)>; reason: too_many_peers
DEBUG 02-27 14:20:55 ETHPeer Disconnecting from remote peer <Node(0x3281@119.53.210.108)>; reason: too_many_peers
DEBUG 02-27 14:20:55 ETHPeer Disconnecting from remote peer <Node(0x946d@35.244.111.53)>; reason: too_many_peers
DEBUG 02-27 14:20:55 HeaderMeatSyncer Requesting 192 headers from ETHPeer <Node(0x3942@52.56.62.122)>
DEBUG 02-27 14:20:55 DiscoveryProtocol Ignoring find_node request from unknown node <Node(0x72a7@85.214.239.222)>
DEBUG 02-27 14:20:56 DiscoveryProtocol Ignoring find_node request from unknown node <Node(0xd8b0@50.254.113.129)>
DEBUG 02-27 14:20:58 DiscoveryProtocol Ignoring find_node request from unknown node <Node(0x960c@54.68.164.172)>
DEBUG 02-27 14:20:59 DiscoveryProtocol Ignoring find_node request from unknown node <Node(0x266f@52.64.206.186)>
DEBUG 02-27 14:20:59 DiscoveryProtocol Ignoring find_node request from unknown node <Node(0x5c06@46.101.128.49)>
DEBUG 02-27 14:21:02 protocol client > Frame(fin=True, opcode=1, data=b'{"emit": ["node-ping", {"clientTime": 1551277262300, "id": "snake charmers"}]}', rsv1=False, rsv2=False, rsv3=False)
DEBUG 02-27 14:21:02 protocol client < Frame(fin=True, opcode=1, data=b'{"emit":["node-pong",{"clientTime":1551277262300,"serverTime":1551277262340}]}', rsv1=False, rsv2=False, rsv3=False)
DEBUG 02-27 14:21:02 protocol client > Frame(fin=True, opcode=1, data=b'{"emit": ["latency", {"latency": 22, "id": "snake charmers"}]}', rsv1=False, rsv2=False, rsv3=False)
WARNING 02-27 14:21:03 EthstatsService Timeout: PeerPool did not answer PeerCountRequest
DEBUG 02-27 14:21:03 protocol client > Frame(fin=True, opcode=1, data=b'{"emit": ["stats", {"stats": {"active": true, "uptime": 100, "peers": 0}, "id": "snake charmers"}]}', rsv1=False, rsv2=False, rsv3=False)
DEBUG 02-27 14:21:03 protocol client > Frame(fin=True, opcode=1, data=b'{"emit": ["block", {"block": {"number": 2163284, "hash": "0x6711af92f0cd9b6162a2756924395077e7f67932b70e38b05fae8f9f8680aed5", "difficulty": 67097876559011, "totalDifficulty": 53923479549511501787, "transactions": [], "uncles": []}, "id": "snake charmers"}]}', rsv1=False, rsv2=False, rsv3=False)
DEBUG 02-27 14:21:08 DiscoveryProtocol Ignoring find_node request from unknown node <Node(0x26a9@185.122.64.200)>
DEBUG 02-27 14:21:10 DiscoveryProtocol Ignoring find_node request from unknown node <Node(0x946d@35.244.111.53)>
DEBUG 02-27 14:21:12 FastChainBodySyncer Timed out requesting receipts for 164 headers from ETHPeer <Node(0xae4f@83.79.101.201)>
DEBUG 02-27 14:21:12 ETHPeerPool Could not complete handshake with <Node(0x4275754d62248e517d57b27352e9f804f40b71125a629d0ffda9d05b06361ffd07b95fd26059e619174f0e4f9283d4e529bc934af85f180256a11e6e95ab09e1@94.177.225.141:30305)>: UnreachablePeer("Can't reach <Node(0x4275754d62248e517d57b27352e9f804f40b71125a629d0ffda9d05b06361ffd07b95fd26059e619174f0e4f9283d4e529bc934af85f180256a11e6e95ab09e1@94.177.225.141:30305)>",)
DEBUG 02-27 14:21:12 SQLitePeerInfo skipping <Node(0xc5df@51.75.26.226)>, it failed because "WrongNetworkFailure" and is not usable until 2019-03-01 12:30:55
DEBUG 02-27 14:21:12 HeaderMeatSyncer Header Skeleton Gaps: active=3 queued=49 max=50
DEBUG 02-27 14:21:12 FastChainBodySyncer (in progress, queued, max size) of bodies, receipts: [(0, 0, 1024), (512, 2048, 2048)]
INFO 02-27 14:21:12 FastChainBodySyncer blks=7 txs=20 bps=9 tps=79 elapsed=17.8 head=#2163284 6711..aed5 age=2y5m4w
DEBUG 02-27 14:21:12 SkeletonSyncer Skeleton header queue is 501/1536 full
DEBUG 02-27 14:21:12 HeaderMeatSyncer Requesting 192 headers from ETHPeer <Node(0xae4f@83.79.101.201)>
DEBUG 02-27 14:21:12 FullServer Receiving handshake from Address(13.125.153.167:udp:60938|tcp:60938)
DEBUG 02-27 14:21:12 FullServer Receiving handshake from Address(34.214.111.193:udp:33316|tcp:33316)
DEBUG 02-27 14:21:12 FullServer Receiving handshake from Address(51.140.203.239:udp:56370|tcp:56370)
DEBUG 02-27 14:21:12 FullServer Receiving handshake from Address(52.64.206.186:udp:48220|tcp:48220)
DEBUG 02-27 14:21:12 FullServer Receiving handshake from Address(54.68.164.172:udp:41162|tcp:41162)
DEBUG 02-27 14:21:12 FullServer Receiving handshake from Address(85.214.239.222:udp:56650|tcp:56650)
DEBUG 02-27 14:21:12 FullServer Receiving handshake from Address(50.254.113.129:udp:14344|tcp:14344)
DEBUG 02-27 14:21:12 FullServer Receiving handshake from Address(54.175.255.215:udp:20086|tcp:20086)
DEBUG 02-27 14:21:12 FullServer Receiving handshake from Address(52.163.189.150:udp:54212|tcp:54212)
DEBUG 02-27 14:21:12 FullServer Receiving handshake from Address(52.197.124.192:udp:33202|tcp:33202)
DEBUG 02-27 14:21:12 FullServer Receiving handshake from Address(13.81.59.190:udp:43528|tcp:43528)
DEBUG 02-27 14:21:12 FullServer Receiving handshake from Address(167.114.169.69:udp:55708|tcp:55708)
DEBUG 02-27 14:21:12 FullServer Receiving handshake from Address(40.80.148.194:udp:34310|tcp:34310)
DEBUG 02-27 14:21:12 FullServer Receiving handshake from Address(47.104.133.102:udp:51418|tcp:51418)
DEBUG 02-27 14:21:12 FullServer Receiving handshake from Address(46.101.128.49:udp:41672|tcp:41672)
DEBUG 02-27 14:21:12 FullServer Receiving handshake from Address(213.205.77.34:udp:21565|tcp:21565)
DEBUG 02-27 14:21:12 FullServer Receiving handshake from Address(185.122.64.200:udp:59058|tcp:59058)
DEBUG 02-27 14:21:12 ETHPeer Disconnecting from remote peer <Node(0xc8b6@34.214.111.193)>; reason: too_many_peers
DEBUG 02-27 14:21:12 ETHPeer Disconnecting from remote peer <Node(0xb35f@54.175.255.215)>; reason: too_many_peers
DEBUG 02-27 14:21:12 FullServer Failed to decrypt handshake: Auth msg too short: 0
DEBUG 02-27 14:21:13 ETHPeer Disconnecting from remote peer <Node(0x0996@213.205.77.34)>; reason: too_many_peers
DEBUG 02-27 14:21:13 ETHPeer Disconnecting from remote peer <Node(0x0515@13.125.153.167)>; reason: too_many_peers
DEBUG 02-27 14:21:13 ETHPeer Disconnecting from remote peer <Node(0xac6b@51.140.203.239)>; reason: too_many_peers
DEBUG 02-27 14:21:13 protocol client > Frame(fin=True, opcode=1, data=b'{"emit": ["node-ping", {"clientTime": 1551277273308, "id": "snake charmers"}]}', rsv1=False, rsv2=False, rsv3=False)
DEBUG 02-27 14:21:13 ETHPeer Disconnecting from remote peer <Node(0x266f@52.64.206.186)>; reason: too_many_peers
DEBUG 02-27 14:21:13 ETHPeer Disconnecting from remote peer <Node(0x960c@54.68.164.172)>; reason: too_many_peers
DEBUG 02-27 14:21:13 ETHPeer Disconnecting from remote peer <Node(0x72a7@85.214.239.222)>; reason: too_many_peers
DEBUG 02-27 14:21:13 ETHPeer Disconnecting from remote peer <Node(0xd8b0@50.254.113.129)>; reason: too_many_peers
DEBUG 02-27 14:21:13 ETHPeer Disconnecting from remote peer <Node(0x3d9c@52.163.189.150)>; reason: too_many_peers
DEBUG 02-27 14:21:13 ETHPeer Disconnecting from remote peer <Node(0xeaca@13.81.59.190)>; reason: too_many_peers
DEBUG 02-27 14:21:13 ETHPeer Disconnecting from remote peer <Node(0x1991@167.114.169.69)>; reason: too_many_peers
DEBUG 02-27 14:21:13 ETHPeer Disconnecting from remote peer <Node(0xeaca@40.80.148.194)>; reason: too_many_peers
DEBUG 02-27 14:21:13 ETHPeer Disconnecting from remote peer <Node(0x9a29@47.104.133.102)>; reason: too_many_peers
DEBUG 02-27 14:21:13 ETHPeer Disconnecting from remote peer <Node(0x5c06@46.101.128.49)>; reason: too_many_peers
DEBUG 02-27 14:21:13 ETHPeer Disconnecting from remote peer <Node(0x26a9@185.122.64.200)>; reason: too_many_peers
DEBUG 02-27 14:21:13 FastChainBodySyncer Requesting receipts for 217 headers from ETHPeer <Node(0xae4f@83.79.101.201)>
DEBUG 02-27 14:21:13 protocol client < Frame(fin=True, opcode=1, data=b'{"emit":["node-pong",{"clientTime":1551277273308,"serverTime":1551277273454}]}', rsv1=False, rsv2=False, rsv3=False)
DEBUG 02-27 14:21:13 protocol client > Frame(fin=True, opcode=1, data=b'{"emit": ["latency", {"latency": 75, "id": "snake charmers"}]}', rsv1=False, rsv2=False, rsv3=False)
WARNING 02-27 14:21:14 EthstatsService Timeout: PeerPool did not answer PeerCountRequest
DEBUG 02-27 14:21:14 protocol client > Frame(fin=True, opcode=1, data=b'{"emit": ["stats", {"stats": {"active": true, "uptime": 100, "peers": 0}, "id": "snake charmers"}]}', rsv1=False, rsv2=False, rsv3=False)
DEBUG 02-27 14:21:14 protocol client > Frame(fin=True, opcode=1, data=b'{"emit": ["block", {"block": {"number": 2163287, "hash": "0x4b41836ab6ae01f18c05673b76729edacad46792d9f0a22ea4f40f96600f75ed", "difficulty": 67196214037709, "totalDifficulty": 53923681039784133132, "transactions": [], "uncles": []}, "id": "snake charmers"}]}', rsv1=False, rsv2=False, rsv3=False)
DEBUG 02-27 14:21:16 DiscoveryProtocol Ignoring find_node request from unknown node <Node(0xb4d4@47.74.181.236)>
DEBUG 02-27 14:21:17 DiscoveryProtocol Ignoring find_node request from unknown node <Node(0x4b90@178.128.185.242)>
DEBUG 02-27 14:21:18 DiscoveryProtocol Ignoring find_node request from unknown node <Node(0xe0a5@72.182.90.50)>
DEBUG 02-27 14:21:18 HeaderMeatSyncer Timed out requesting 192 headers from ETHPeer <Node(0x3942@52.56.62.122)>
DEBUG 02-27 14:21:18 FullServer Receiving handshake from Address(193.112.186.234:udp:42964|tcp:42964)
DEBUG 02-27 14:21:18 HeaderMeatSyncer Pausing ETHPeer <Node(0x3942@52.56.62.122)> for 15.0s, for sending 0 headers
DEBUG 02-27 14:21:18 HeaderMeatSyncer Header Skeleton Gaps: active=2 queued=50 max=50
DEBUG 02-27 14:21:18 FastChainBodySyncer (in progress, queued, max size) of bodies, receipts: [(0, 0, 1024), (512, 1989, 2048)]
INFO 02-27 14:21:18 FastChainBodySyncer blks=3 txs=6 bps=9 tps=75 elapsed=6.4 head=#2163287 4b41..75ed age=2y5m4w
DEBUG 02-27 14:21:18 SkeletonSyncer Skeleton header queue is 500/1536 full
DEBUG 02-27 14:21:18 FastChainBodySyncer <coroutine object FastChainBodySyncer._launch_prerequisite_tasks at 0x7fc89118b780> finished while <trinity.sync.full.chain.FastChainBodySyncer object at 0x7fc89116a2e8> is still running, terminating as well
DEBUG 02-27 14:21:18 FastChainBodySyncer Cancelling <trinity.sync.full.chain.FastChainBodySyncer object at 0x7fc89116a2e8>
WARNING 02-27 14:21:18 FastChainBodySyncer Task <coroutine object FastChainBodySyncer._launch_prerequisite_tasks at 0x7fc8911807d8> finished unexpectedly: No header with hash 0x9eb6dffe10dd377825d14687db7279f648c4bf4f9552d625a4d4adc6f19434b5 found
DEBUG 02-27 14:21:18 FastChainBodySyncer Task failure traceback
trinity._utils.mp.RemoteTraceback:
"""
Traceback (most recent call last):
File "/home/ubuntu/trinity/venv/lib/python3.6/site-packages/eth/db/header.py", line 167, in _get_block_header_by_hash
header_rlp = db[block_hash]
File "/home/ubuntu/trinity/venv/lib/python3.6/site-packages/eth/db/backends/level.py", line 54, in __getitem__
raise KeyError(key)
KeyError: b'\x9e\xb6\xdf\xfe\x10\xdd7x%\xd1F\x87\xdbry\xf6H\xc4\xbfO\x95R\xd6%\xa4\xd4\xad\xc6\xf1\x944\xb5'
During handling of the above exception, another exception occurred:
Traceback (most recent call last):
File "/home/ubuntu/trinity/trinity/_utils/mp.py", line 68, in wrapper
return attr(*args, **kwargs)
File "/home/ubuntu/trinity/venv/lib/python3.6/site-packages/eth/db/header.py", line 156, in get_block_header_by_hash
return self._get_block_header_by_hash(self.db, block_hash)
File "/home/ubuntu/trinity/venv/lib/python3.6/site-packages/eth/db/header.py", line 170, in _get_block_header_by_hash
encode_hex(block_hash)))
eth.exceptions.HeaderNotFound: No header with hash 0x9eb6dffe10dd377825d14687db7279f648c4bf4f9552d625a4d4adc6f19434b5 found
"""
The above exception was the direct cause of the following exception:
Traceback (most recent call last):
File "/home/ubuntu/trinity/p2p/service.py", line 139, in _run_task_wrapper
await awaitable
File "/home/ubuntu/trinity/p2p/service.py", line 158, in _run_daemon_task_wrapper
await awaitable
File "/home/ubuntu/trinity/trinity/sync/full/chain.py", line 467, in _launch_prerequisite_tasks
self.db.coro_get_block_header_by_hash(headers[0].parent_hash)
File "/home/ubuntu/trinity/p2p/cancellable.py", line 20, in wait
return await self.wait_first(awaitable, token=token, timeout=timeout)
File "/home/ubuntu/trinity/p2p/cancellable.py", line 42, in wait_first
return await token_chain.cancellable_wait(*awaitables, timeout=timeout)
File "/home/ubuntu/trinity/venv/lib/python3.6/site-packages/cancel_token/token.py", line 152, in cancellable_wait
return done.pop().result()
File "/home/ubuntu/trinity/trinity/_utils/mp.py", line 33, in method
args,
File "/usr/lib/python3.6/concurrent/futures/thread.py", line 56, in run
result = self.fn(*self.args, **self.kwargs)
File "/usr/lib/python3.6/multiprocessing/managers.py", line 772, in _callmethod
raise convert_to_error(kind, result)
eth.exceptions.HeaderNotFound: No header with hash 0x9eb6dffe10dd377825d14687db7279f648c4bf4f9552d625a4d4adc6f19434b5 found
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment