Improve logging when remote peer is unknown

[seunlanlege]

This is a draft PR simply to enable investigation of #10781

@nathPay is it possible for your to test this branch with your Kubernetes set up?

[parity-cla-bot]

It looks like @seunlanlege signed our Contributor License Agreement. 👍

Many thanks,

Parity Technologies CLA Bot

[dvdplm]

Why is this a draft, seems like a good first step.

[nathPay]

@seunlanlege I've built the docker image and run it uing kubernetes and it seems that it does not change anythings.

[dvdplm]

@nathPay can you report back your logs with this branch please?

[nathPay]

@seunlanlege Here part of log with potential errors:

...
2019-07-01 11:54:41 UTC  TRACE network  connection deregister; token=0
2019-07-01 11:54:41 UTC IO Worker #2 TRACE network  5: Read 32 of 32 bytes
2019-07-01 11:54:41 UTC IO Worker #2 TRACE network  Expect to read 208 bytes
2019-07-01 11:54:41 UTC IO Worker #2 TRACE network  5: Read 64 of 208 bytes
2019-07-01 11:54:41 UTC IO Worker #2 TRACE network  5: Read 128 of 208 bytes
2019-07-01 11:54:41 UTC IO Worker #2 TRACE network  5: Read 208 of 208 bytes
2019-07-01 11:54:41 UTC IO Worker #2 TRACE network  Expect to read 32 bytes
2019-07-01 11:54:41 UTC IO Worker #2 DEBUG network  Hello: Parity-Ethereum/v2.5.1-beta-adabd81-20190514/x86_64-linux-gnu/rustc1.34.1 v5 0x3155…6481 [SessionCapabilityInfo { protocol: [101, 116, 104], version: 63, packet_count: 17, id_offset: 16 }, SessionCapabilityInfo { protocol: [112, 97, 114], version: 3, packet_count: 24, id_offset: 33 }, SessionCapabilityInfo { protocol: [112, 105, 112], version: 1, packet_count: 9, id_offset: 57 }]
2019-07-01 11:54:41 UTC IO Worker #2 TRACE network  compressed 1 to 3
2019-07-01 11:54:41 UTC IO Worker #2 TRACE network  5: Sending 64 bytes
2019-07-01 11:54:41 UTC  TRACE network  connection reregister; token=Token(5)
2019-07-01 11:54:41 UTC IO Worker #0 TRACE network  5: Wrote 64 bytes
2019-07-01 11:54:41 UTC IO Worker #2 TRACE network  compressed 329 to 290
2019-07-01 11:54:41 UTC IO Worker #2 TRACE network  5: Sending 352 bytes
2019-07-01 11:54:41 UTC  TRACE network  connection reregister; token=Token(5)
2019-07-01 11:54:41 UTC  TRACE network  connection reregister; token=Token(5)
2019-07-01 11:54:41 UTC IO Worker #2 TRACE network  compressed 109 to 54
2019-07-01 11:54:41 UTC IO Worker #3 TRACE network  Hup: 5
2019-07-01 11:54:41 UTC IO Worker #3 TRACE network  Connection closed: 5
2019-07-01 11:54:41 UTC IO Worker #2 TRACE network  5: Sending 112 bytes
2019-07-01 11:54:41 UTC  TRACE network  connection reregister; token=Token(5)
2019-07-01 11:54:41 UTC IO Worker #0 TRACE network  Hup: 5
2019-07-01 11:54:41 UTC  TRACE network  connection deregister; token=5
2019-07-01 11:54:41 UTC IO Worker #0 TRACE network  Connection closed: 5
2019-07-01 11:54:41 UTC IO Worker #0 TRACE network  Hup: 2
2019-07-01 11:54:41 UTC IO Worker #0 TRACE network  Connection closed: 2
2019-07-01 11:54:41 UTC  TRACE network  connection deregister; token=2
2019-07-01 11:54:41 UTC IO Worker #0 TRACE network  Hup: 9
2019-07-01 11:54:41 UTC IO Worker #0 TRACE network  Connection closed: 9
2019-07-01 11:54:41 UTC  TRACE network  connection deregister; token=9
2019-07-01 11:54:42 UTC IO Worker #3 TRACE network  Connection timeout: 15

...

2019-07-01 11:54:43 UTC IO Worker #2 TRACE network  Connection closed: 18
2019-07-01 11:54:43 UTC  TRACE network  connection deregister; token=18
2019-07-01 11:54:43 UTC IO Worker #1 TRACE network  0: Read 32 of 32 bytes
2019-07-01 11:54:43 UTC IO Worker #1 TRACE network  Expect to read 208 bytes
2019-07-01 11:54:43 UTC IO Worker #1 TRACE network  0: Read 64 of 208 bytes
2019-07-01 11:54:43 UTC IO Worker #1 TRACE network  0: Read 128 of 208 bytes
2019-07-01 11:54:43 UTC IO Worker #1 TRACE network  0: Read 208 of 208 bytes
2019-07-01 11:54:43 UTC IO Worker #1 TRACE network  Expect to read 32 bytes
2019-07-01 11:54:43 UTC IO Worker #1 DEBUG network  Hello: Parity-Ethereum/v2.4.6-stable-94164e1ca-20190514/x86_64-linux-musl/rustc1.31.1 v5 0xd8b1…62b8 [SessionCapabilityInfo { protocol: [101, 116, 104], version: 63, packet_count: 17, id_offset: 16 }, SessionCapabilityInfo { protocol: [112, 97, 114], version: 3, packet_count: 24, id_offset: 33 }, SessionCapabilityInfo { protocol: [112, 105, 112], version: 1, packet_count: 9, id_offset: 57 }]
2019-07-01 11:54:43 UTC IO Worker #1 TRACE network  compressed 1 to 3
2019-07-01 11:54:43 UTC IO Worker #1 TRACE network  0: Sending 64 bytes
2019-07-01 11:54:43 UTC  TRACE network  connection reregister; token=Token(0)
2019-07-01 11:54:43 UTC IO Worker #0 TRACE network  0: Wrote 64 bytes
2019-07-01 11:54:43 UTC IO Worker #1 TRACE network  compressed 329 to 290
2019-07-01 11:54:43 UTC IO Worker #1 TRACE network  0: Sending 352 bytes
2019-07-01 11:54:43 UTC  TRACE network  connection reregister; token=Token(0)
2019-07-01 11:54:43 UTC  TRACE network  connection reregister; token=Token(0)
2019-07-01 11:54:43 UTC IO Worker #1 TRACE network  compressed 109 to 54
2019-07-01 11:54:43 UTC IO Worker #1 TRACE network  0: Sending 112 bytes
2019-07-01 11:54:43 UTC IO Worker #2 TRACE network  0: Wrote 352 bytes
2019-07-01 11:54:43 UTC  TRACE network  connection reregister; token=Token(0)
2019-07-01 11:54:43 UTC  TRACE network  connection reregister; token=Token(0)
2019-07-01 11:54:43 UTC IO Worker #0 TRACE network  0: Wrote 112 bytes
2019-07-01 11:54:43 UTC  TRACE network  connection reregister; token=Token(0)
2019-07-01 11:54:43 UTC IO Worker #1 TRACE network  Hup: 0
2019-07-01 11:54:43 UTC IO Worker #1 TRACE network  Connection closed: 0
2019-07-01 11:54:43 UTC  TRACE network  connection deregister; token=0
2019-07-01 11:54:43 UTC IO Worker #2 TRACE network  9: Wrote 307 bytes
2019-07-01 11:54:43 UTC  TRACE network  connection reregister; token=Token(9)
2019-07-01 11:54:43 UTC IO Worker #0 TRACE network  2: Read 64 of 210 bytes
2019-07-01 11:54:43 UTC IO Worker #0 TRACE network  2: Read 128 of 210 bytes
2019-07-01 11:54:43 UTC IO Worker #0 TRACE network  2: Read 210 of 210 bytes
2019-07-01 11:54:43 UTC IO Worker #0 TRACE network  Received handshake ack from "134.209.192.170:30303"
2019-07-01 11:54:43 UTC IO Worker #0 TRACE network  Expect to read 32 bytes
2019-07-01 11:54:43 UTC IO Worker #0 TRACE network  2: Sending 240 bytes
2019-07-01 11:54:43 UTC  TRACE network  connection reregister; token=Token(2)
2019-07-01 11:54:43 UTC  TRACE network  connection reregister; token=Token(2)
2019-07-01 11:54:43 UTC IO Worker #1 TRACE network  2: Wrote 240 bytes
2019-07-01 11:54:43 UTC  TRACE network  connection reregister; token=Token(2)
2019-07-01 11:54:43 UTC IO Worker #2 TRACE network  5: Read 64 of 210 bytes
2019-07-01 11:54:43 UTC IO Worker #2 TRACE network  5: Read 128 of 210 bytes
2019-07-01 11:54:43 UTC IO Worker #2 TRACE network  5: Read 210 of 210 bytes
2019-07-01 11:54:43 UTC IO Worker #2 TRACE network  Received handshake ack from "18.202.221.230:30303"
2019-07-01 11:54:43 UTC IO Worker #1 TRACE network  Hup: 5
2019-07-01 11:54:43 UTC IO Worker #1 TRACE network  Connection closed: 5
2019-07-01 11:54:43 UTC IO Worker #2 TRACE network  Expect to read 32 bytes
2019-07-01 11:54:43 UTC IO Worker #2 TRACE network  5: Sending 240 bytes
2019-07-01 11:54:43 UTC IO Worker #0 TRACE network  5: Read 32 of 32 bytes
2019-07-01 11:54:43 UTC IO Worker #0 TRACE network  Expect to read 32 bytes
2019-07-01 11:54:43 UTC IO Worker #0 TRACE network  5: Read 32 of 32 bytes
2019-07-01 11:54:43 UTC IO Worker #0 TRACE network  Expect to read 32 bytes
2019-07-01 11:54:43 UTC IO Worker #0 TRACE network  Session read error: 5:Some(0xb02a0eb711ed1e5c2b5fa7212f0b33b30c403d3b58979d40ce836f9255490c2106172482dd8cbdf129c3ae6be6965ff5e4bf117680a60078ad55e98507e9e01e) (Ok(V4(18.202.221.230:30303))) Disconnect(TooManyPeers)
...