Skip to content
This repository has been archived by the owner on Nov 15, 2023. It is now read-only.

Polkadot service stops listening on port 30333 when reaching some threshold of load. #856

Closed
Mitchell-Grimes opened this issue Feb 19, 2020 · 7 comments
Labels
I3-bug Fails to follow expected behavior.

Comments

@Mitchell-Grimes
Copy link

System:

  • Ubuntu 18.04.4 LTS
  • 4 vCPUs
  • 15 GB of RAM

Setup:

This is a secure validator setup. We have 4 sentries that are same spec as well as 2 validators (primary/secondary). The configuration that we are using for the systemd service includes these flags:


[Unit]
Description=Polkadot service
After=network.target
StartLimitIntervalSec=0
[Service]
Type=simple
Restart=on-failure
RestartSec=10
User=polkadot
ExecStart={{ polkadot_bin_loc }} \
      --base-path {{ polkadot_base_path }} \
      --pruning archive \
      --chain={{ polkadot_network }} \
      --sentry \
      --reserved-nodes /ip4/{{ entry['ip'] }}/tcp/{{ polkadot_port }}/p2p/{{ entry['peerid'] }} \
      --reserved-nodes /ip4/{{ entry['ip'] }}/tcp/{{ polkadot_port }}/p2p/{{ entry['peerid'] }} \
      --reserved-nodes /ip4/{{ entry['ip'] }}/tcp/{{ polkadot_port }}/p2p/{{ entry['peerid'] }} \
      --reserved-nodes /ip4/{{ entry['ip'] }}/tcp/{{ polkadot_port }}/p2p/{{ entry['peerid'] }} \
      --reserved-nodes /ip4/{{ entry['ip'] }}/tcp/{{ polkadot_port }}/p2p/{{ entry['peerid'] }} \
      --log imonline=trace,lsc_offchain=trace \
      --no-mdns
[Install]
WantedBy=multi-user.target

Additional information about the setup:

When we first observed this behavior, it was brought to our attention from an external service called Pingdom. It is used to verify our sentries are available to the public (checks every 1 minute). In using this service, if the host is unreachable (via a specified port such as 30333) from various servers around the world, we will get an alert. Since the process stops listening on port 30333 fairly consistently after upgrading v7.20, we have implemented monit to check and restart the service automatically (this checks every 2 minutes) in such a scenario.

Behavior of the problem:

The process stops listening on port 30333. With the logs and screenshots of metrics, the most recent example is around 18:20 UTC. The spikes on CPU/Disk Utilization is after the restart of the service. The service itself is syncing with the chain and communicating with our validator. We monitor the connected peers across all our nodes. Particularly from the validator perspective. When we receive alerts from our external service, we are still connected (from a sentry to validator and from a validator to sentry) when looking at the connected peers using the RPC call.

This is still a concern to us as it is unclear on what causes this exactly. If the process begins to fail on port 30333 does that mean it can stop on other ports? How does this affect the network if others observe this behavior and sentries are not reachable? This or more or less extreme scenarios, but is a concern.

Had we not had an external service such as pingdom to check the lib p2p port, this would've have gone unnoticed as it appears the process still is able to have outbound connections which allows it to stay in sync with the chain. This may or may not affect the overall health of the network if only half the connections for sentries are properly communicating.

Logs:

There is no indication of anything that is the cause of the problem within the logs. There is however been an introduction of an error in the paste when p2p had been previously update (7.17) which was later removed in version 7.18. The error message is as follows: ERROR yamux::connection 671ba303: socket error: decode error: i/o error: I/O error: Connection reset by peer (os error 104)

Raw logs:


Feb 19, 2020 @ 13:18:19.000 | 2020-02-19 18:18:19.218 main-tokio- INFO substrate  Idle (48 peers), best: #1110532 (0x5132…808a), finalized #1110529 (0x4620…4875), ⬇ 146.9kiB/s ⬆ 129.9kiB/s

  | Feb 19, 2020 @ 13:18:24.000 | 2020-02-19 18:18:24.229 main-tokio- INFO substrate  Idle (48 peers), best: #1110532 (0x5132…808a), finalized #1110530 (0xa822…47ed), ⬇ 122.4kiB/s ⬆ 106.9kiB/s

  | Feb 19, 2020 @ 13:18:24.000 | 2020-02-19 18:18:24.404 main-tokio- INFO substrate  Imported #1110533 (0xef63…4e2f)

  | Feb 19, 2020 @ 13:18:29.000 | 2020-02-19 18:18:29.240 main-tokio- INFO substrate  Idle (48 peers), best: #1110533 (0xef63…4e2f), finalized #1110530 (0xa822…47ed), ⬇ 91.6kiB/s ⬆ 71.4kiB/s

  | Feb 19, 2020 @ 13:18:30.000 | 2020-02-19 18:18:30.741 main-tokio- INFO substrate  Imported #1110534 (0xb169…5779)

  | Feb 19, 2020 @ 13:18:34.000 | 2020-02-19 18:18:34.250 main-tokio- INFO substrate  Idle (48 peers), best: #1110534 (0xb169…5779), finalized #1110531 (0xeef0…13fb), ⬇ 166.0kiB/s ⬆ 137.1kiB/s

  | Feb 19, 2020 @ 13:18:35.000 | 2020-02-19 18:18:35.551 main-tokio- ERROR yamux::connection  671ba303: socket error: decode error: i/o error: I/O error: Connection reset by peer (os error 104)

  | Feb 19, 2020 @ 13:18:36.000 | 2020-02-19 18:18:36.993 main-tokio- INFO sub-libp2p  Discovered new external address for our node: /ip4/10.156.1.215/tcp/30333/p2p/QmQ9SZL7BVYCR6gJ31geMq57XmdjFWT7dE93NT1GTaXBQS

  | Feb 19, 2020 @ 13:18:36.000 | 2020-02-19 18:18:36.670 main-tokio- INFO substrate  Imported #1110535 (0x4a3a…acfe)

  | Feb 19, 2020 @ 13:18:38.000 | 2020-02-19 18:18:38.408 main-tokio- INFO substrate  Imported #1110535 (0xbb29…6608)

  | Feb 19, 2020 @ 13:18:39.000 | 2020-02-19 18:18:39.262 main-tokio- INFO substrate  Idle (48 peers), best: #1110535 (0x4a3a…acfe), finalized #1110532 (0x5132…808a), ⬇ 107.8kiB/s ⬆ 108.9kiB/s

  | Feb 19, 2020 @ 13:18:43.000 | 2020-02-19 18:18:43.360 main-tokio- INFO substrate  Imported #1110536 (0x5e13…c310)

  | Feb 19, 2020 @ 13:18:44.000 | 2020-02-19 18:18:44.274 main-tokio- INFO substrate  Idle (48 peers), best: #1110536 (0x5e13…c310), finalized #1110533 (0xef63…4e2f), ⬇ 107.8kiB/s ⬆ 112.5kiB/s

  | Feb 19, 2020 @ 13:18:48.000 | 2020-02-19 18:18:48.976 main-tokio- INFO sub-libp2p  Discovered new external address for our node: /ip4/10.156.1.218/tcp/30333/p2p/QmQ9SZL7BVYCR6gJ31geMq57XmdjFWT7dE93NT1GTaXBQS

  | Feb 19, 2020 @ 13:18:48.000 | 2020-02-19 18:18:48.861 main-tokio- INFO substrate  Imported #1110537 (0xea1b…ed2f)

  | Feb 19, 2020 @ 13:18:48.000 | 2020-02-19 18:18:48.943 main-tokio- INFO substrate  Imported #1110537 (0x7774…729e)

  | Feb 19, 2020 @ 13:18:49.000 | 2020-02-19 18:18:49.285 main-tokio- INFO substrate  Idle (48 peers), best: #1110537 (0xea1b…ed2f), finalized #1110534 (0xb169…5779), ⬇ 130.6kiB/s ⬆ 151.8kiB/s

  | Feb 19, 2020 @ 13:18:54.000 | 2020-02-19 18:18:54.298 main-tokio- INFO substrate  Idle (48 peers), best: #1110537 (0xea1b…ed2f), finalized #1110535 (0x4a3a…acfe), ⬇ 141.3kiB/s ⬆ 110.5kiB/s

  | Feb 19, 2020 @ 13:18:55.000 | 2020-02-19 18:18:55.902 main-tokio- INFO substrate  Imported #1110538 (0x746f…a570)

  | Feb 19, 2020 @ 13:18:59.000 | 2020-02-19 18:18:59.308 main-tokio- INFO substrate  Idle (48 peers), best: #1110538 (0x746f…a570), finalized #1110535 (0x4a3a…acfe), ⬇ 104.2kiB/s ⬆ 82.8kiB/s

  | Feb 19, 2020 @ 13:19:00.000 | 2020-02-19 18:19:00.616 main-tokio- INFO substrate  Imported #1110539 (0x9901…1347)

  | Feb 19, 2020 @ 13:19:04.000 | 2020-02-19 18:19:04.319 main-tokio- INFO substrate  Idle (48 peers), best: #1110539 (0x9901…1347), finalized #1110536 (0x5e13…c310), ⬇ 106.4kiB/s ⬆ 136.6kiB/s

  | Feb 19, 2020 @ 13:19:06.000 | 2020-02-19 18:19:06.383 main-tokio- INFO substrate  Imported #1110540 (0x406e…a5bc)

  | Feb 19, 2020 @ 13:19:09.000 | 2020-02-19 18:19:09.331 main-tokio- INFO substrate  Idle (48 peers), best: #1110540 (0x406e…a5bc), finalized #1110538 (0x746f…a570), ⬇ 114.6kiB/s ⬆ 96.5kiB/s

  | Feb 19, 2020 @ 13:19:12.000 | 2020-02-19 18:19:12.923 main-tokio- INFO substrate  Imported #1110541 (0xe474…b6c7)

  | Feb 19, 2020 @ 13:19:14.000 | 2020-02-19 18:19:14.342 main-tokio- INFO substrate  Idle (48 peers), best: #1110541 (0xe474…b6c7), finalized #1110538 (0x746f…a570), ⬇ 144.2kiB/s ⬆ 107.4kiB/s

  | Feb 19, 2020 @ 13:19:19.000 | 2020-02-19 18:19:19.354 main-tokio- INFO substrate  Idle (48 peers), best: #1110541 (0xe474…b6c7), finalized #1110539 (0x9901…1347), ⬇ 152.3kiB/s ⬆ 107.0kiB/s

  | Feb 19, 2020 @ 13:19:19.000 | 2020-02-19 18:19:19.802 main-tokio- INFO substrate  Imported #1110542 (0x4faf…6f4c)

  | Feb 19, 2020 @ 13:19:25.000 | 2020-02-19 18:19:25.803 main INFO polkadot_cli::command  This chain is not in any way

  | Feb 19, 2020 @ 13:19:25.000 | 2020-02-19 18:19:25.803 main INFO polkadot_cli::command    version 0.7.20-37381589-x86_64-linux-gnu

  | Feb 19, 2020 @ 13:19:25.000 | 2020-02-19 18:19:25.803 main INFO polkadot_cli::command    by Parity Team <admin@parity.io>, 2017-2020

  | Feb 19, 2020 @ 13:19:25.000 | 2020-02-19 18:19:25.803 main INFO polkadot_cli::command  Chain specification: Kusama CC3

  | Feb 19, 2020 @ 13:19:25.000 | 2020-02-19 18:19:25.803 main INFO polkadot_cli::command  Node name: long-sen-1

  | Feb 19, 2020 @ 13:19:25.000 | 2020-02-19 18:19:25.803 main INFO polkadot_cli::command  Roles: SENTRY

  | Feb 19, 2020 @ 13:19:25.000 | 2020-02-19 18:19:25.803 main INFO polkadot_cli::command  Native runtime: kusama-1045:2(parity-kusama-0)

  | Feb 19, 2020 @ 13:19:25.000 | 2020-02-19 18:19:25.803 main INFO polkadot_cli::command  ----------------------------

  | Feb 19, 2020 @ 13:19:25.000 | 2020-02-19 18:19:25.803 main INFO polkadot_cli::command        endorsed by the

  | Feb 19, 2020 @ 13:19:25.000 | 2020-02-19 18:19:25.803 main INFO polkadot_cli::command       KUSAMA FOUNDATION

  | Feb 19, 2020 @ 13:19:25.000 | 2020-02-19 18:19:25.803 main INFO polkadot_cli::command  ----------------------------

  | Feb 19, 2020 @ 13:19:25.000 | 2020-02-19 18:19:25.802 main INFO polkadot_cli::command  Parity Polkadot

  | Feb 19, 2020 @ 13:19:52.000 | 2020-02-19 18:19:52.724 main-tokio- INFO sub-libp2p  Discovered new external address for our node: /ip4/10.0.1.134/tcp/30333/p2p/QmQ9SZL7BVYCR6gJ31geMq57XmdjFWT7dE93NT1GTaXBQS

  | Feb 19, 2020 @ 13:19:52.000 | 2020-02-19 18:19:52.189 main INFO sc_service::builder  Highest known block at #1110542

  | Feb 19, 2020 @ 13:19:52.000 | 2020-02-19 18:19:52.189 main INFO sub-libp2p  Local node identity is: QmQ9SZL7BVYCR6gJ31geMq57XmdjFWT7dE93NT1GTaXBQS

  | Feb 19, 2020 @ 13:19:52.000 | 2020-02-19 18:19:52.786 main-tokio- INFO sub-libp2p  Discovered new external address for our node: /ip4/10.255.0.5/tcp/30333/p2p/QmQ9SZL7BVYCR6gJ31geMq57XmdjFWT7dE93NT1GTaXBQS

  | Feb 19, 2020 @ 13:19:52.000 | 2020-02-19 18:19:52.212 main-tokio- INFO grafana_data_source::server  Grafana data source server started at 127.0.0.1:9955

  | Feb 19, 2020 @ 13:19:52.000 | 2020-02-19 18:19:52.763 main-tokio- INFO sub-libp2p  Discovered new external address for our node: /ip4/34.89.83.218/tcp/30333/p2p/QmQ9SZL7BVYCR6gJ31geMq57XmdjFWT7dE93NT1GTaXBQS

  | Feb 19, 2020 @ 13:19:53.000 | 2020-02-19 18:19:53.565 main-tokio- INFO sub-libp2p  Discovered new external address for our node: /ip4/10.0.0.164/tcp/30333/p2p/QmQ9SZL7BVYCR6gJ31geMq57XmdjFWT7dE93NT1GTaXBQS

  | Feb 19, 2020 @ 13:19:54.000 | 2020-02-19 18:19:54.853 main-tokio- INFO substrate  Imported #1110548 (0xd626…34fd)

  | Feb 19, 2020 @ 13:19:57.000 | 2020-02-19 18:19:57.211 main-tokio- INFO substrate  Idle (14 peers), best: #1110548 (0xd626…34fd), finalized #1110545 (0xfda9…7b4b), ⬇ 19.8kiB/s ⬆ 9.6kiB/s

  | Feb 19, 2020 @ 13:20:00.000 | 2020-02-19 18:20:00.678 main-tokio- INFO substrate  Imported #1110549 (0x6fff…5d2e)

  | Feb 19, 2020 @ 13:20:02.000 | 2020-02-19 18:20:02.212 main-tokio- INFO substrate  Idle (15 peers), best: #1110549 (0x6fff…5d2e), finalized #1110546 (0xe23a…b93a), ⬇ 77.2kiB/s ⬆ 133.8kiB/s

  | Feb 19, 2020 @ 13:20:06.000 | 2020-02-19 18:20:06.712 main-tokio- INFO substrate  Imported #1110550 (0x0b75…3475)

  | Feb 19, 2020 @ 13:20:07.000 | 2020-02-19 18:20:07.213 main-tokio- INFO substrate  Idle (16 peers), best: #1110550 (0x0b75…3475), finalized #1110547 (0x41d1…9ebe), ⬇ 55.5kiB/s ⬆ 85.1kiB/s

  | Feb 19, 2020 @ 13:20:12.000 | 2020-02-19 18:20:12.214 main-tokio- INFO substrate  Idle (16 peers), best: #1110550 (0x0b75…3475), finalized #1110547 (0x41d1…9ebe), ⬇ 23.5kiB/s ⬆ 74.2kiB/s

  | Feb 19, 2020 @ 13:20:12.000 | 2020-02-19 18:20:12.434 main-tokio- INFO substrate  Imported #1110551 (0xae29…888f)

  | Feb 19, 2020 @ 13:20:17.000 | 2020-02-19 18:20:17.214 main-tokio- INFO substrate  Idle (16 peers), best: #1110551 (0xae29…888f), finalized #1110548 (0xd626…34fd), ⬇ 42.0kiB/s ⬆ 81.9kiB/s

  | Feb 19, 2020 @ 13:20:18.000 | 2020-02-19 18:20:18.754 main-tokio- INFO substrate  Imported #1110552 (0x80c0…4fe3)

Screenshot of metrics:

The spikes on CPU/Disk Utilization is after the restart of the service.
sentry01
sentry01_memory

Observation:

By the looks of it, it appears that once a certain number of connections are met (which is around 2k TCP connections) the process is exceeding it's limitation in some way. Either file descriptors or something else, however, logs don't suggest that it's a problem and is unaware. This was not a problem until the recent update to v7.20 for polkadot. We upgraded from v7.18. Here's some information about our default constraints for the process:


root@sentry-node01:# cat /proc/8817/limits
Limit                     Soft Limit           Hard Limit           Units
Max cpu time              unlimited            unlimited            seconds
Max file size             unlimited            unlimited            bytes
Max data size             unlimited            unlimited            bytes
Max stack size            8388608              unlimited            bytes
Max core file size        0                    unlimited            bytes
Max resident set          unlimited            unlimited            bytes
Max processes             60010                60010                processes
Max open files            4096                 4096                 files
Max locked memory         16777216             16777216             bytes
Max address space         unlimited            unlimited            bytes
Max file locks            unlimited            unlimited            locks
Max pending signals       60010                60010                signals
Max msgqueue size         819200               819200               bytes
Max nice priority         0                    0
Max realtime priority     0                    0
Max realtime timeout      unlimited            unlimited            us

@rphmeier rphmeier added I3-bug Fails to follow expected behavior. Z0-unconfirmed labels Feb 19, 2020
@Mitchell-Grimes
Copy link
Author

Updated logs that happened on another sentry. This time, WARN status logs at the time of the port stopping for the service. Message: WARN libp2p_kad::behaviour Locating closest peers for replication failed:

We received the alert at Feb 20, 2020 @ 18:10:00.000 UTC for when the service stopped listening on port 30333. (13:10:00.000 EST)

This is not a new message as we've been seeing this since the beginning of February. However, this latest failure for listening on this port happened with around 1,000 TCP connections.

Screenshots and evidence

sentry02_connections

WARN message:

Feb 20, 2020 @ 13:09:42.000 | 2020-02-20 18:09:42.372 main-tokio- INFO substrate  Imported #1124485 (0x93df…cb3c)

  | Feb 20, 2020 @ 13:09:45.000 | 2020-02-20 18:09:45.705 main-tokio- INFO substrate  Idle (51 peers), best: #1124485 (0x93df…cb3c), finalized #1124482 (0x475a…6961), ⬇ 98.3kiB/s ⬆ 188.8kiB/s

  | Feb 20, 2020 @ 13:09:48.000 | 2020-02-20 18:09:48.035 main-tokio- WARN libp2p_kad::behaviour  Locating closest peers for replication failed: Err(Timeout { key: Key(b"\x12 \xfa\xf4\x19\xc9[\x05d\xf4\xd9\x96\xc8\xa5&`[\x99\xe4'Wo~[S\x82-9:\x9e\xe5\xd3eF"), num_results: 0, quorum: 20 })

  | Feb 20, 2020 @ 13:09:48.000 | 2020-02-20 18:09:48.841 main-tokio- INFO substrate  Imported #1124486 (0x6bfa…666c)

  | Feb 20, 2020 @ 13:09:48.000 | 2020-02-20 18:09:48.035 main-tokio- WARN libp2p_kad::behaviour  Locating closest peers for replication failed: Err(Timeout { key: Key(b"\x12 \x15^\xf9\x11\xaec\x05\x9c\xb5\xc4\xae \r< \xb0\xd0B\x03UJ\x80\x13\xe7\xab\x81\x11D\x92\xeaG\x11"), num_results: 0, quorum: 20 })

  | Feb 20, 2020 @ 13:09:48.000 | 2020-02-20 18:09:48.035 main-tokio- WARN libp2p_kad::behaviour  Locating closest peers for replication failed: Err(Timeout { key: Key(b"\x12 7\x9e\x9b[.,\xe5S\xc3SV1 ~l\xae\xd5\xd1\xd0\xe8\xd8\xadU\0\x94oD\n\x03<\xcd\xec"), num_results: 0, quorum: 20 })

  | Feb 20, 2020 @ 13:09:48.000 | 2020-02-20 18:09:48.035 main-tokio- WARN libp2p_kad::behaviour  Locating closest peers for replication failed: Err(Timeout { key: Key(b"\x12 $\x1e#rr\xa9\x15\x18\xc9'\xb6\x0c\xf6\x1e\xb7\x99\x8c\xd0a\x9c\xdcZ3\x88\xd0\x0f_\xc02\xb6\x80\x07"), num_results: 0, quorum: 20 })

  | Feb 20, 2020 @ 13:09:50.000 | 2020-02-20 18:09:50.716 main-tokio- INFO substrate  Idle (51 peers), best: #1124486 (0x6bfa…666c), finalized #1124483 (0x80b0…e2c9), ⬇ 90.2kiB/s ⬆ 172.0kiB/s

  | Feb 20, 2020 @ 13:09:54.000 | 2020-02-20 18:09:54.576 main-tokio- INFO substrate  Imported #1124487 (0x3da1…e61a)

  | Feb 20, 2020 @ 13:09:55.000 | 2020-02-20 18:09:55.726 main-tokio- INFO substrate  Idle (50 peers), best: #1124487 (0x3da1…e61a), finalized #1124484 (0x6ccb…a727), ⬇ 90.8kiB/s ⬆ 131.5kiB/s

  | Feb 20, 2020 @ 13:10:00.000 | 2020-02-20 18:10:00.737 main-tokio- INFO substrate  Idle (50 peers), best: #1124488 (0xc1d6…1571), finalized #1124485 (0x93df…cb3c), ⬇ 102.7kiB/s ⬆ 163.9kiB/s

  | Feb 20, 2020 @ 13:10:00.000 | 2020-02-20 18:10:00.420 main-tokio- INFO substrate  Imported #1124488 (0xc1d6…1571)


Raw Logs:

Feb 20, 2020 @ 13:07:54.000 | 2020-02-20 18:07:54.349 main-tokio- INFO substrate  Imported #1124467 (0x1b6c…cab0)

  | Feb 20, 2020 @ 13:07:55.000 | 2020-02-20 18:07:55.471 main-tokio- INFO substrate  Idle (51 peers), best: #1124467 (0x1b6c…cab0), finalized #1124464 (0x8b87…cacf), ⬇ 83.5kiB/s ⬆ 168.7kiB/s

  | Feb 20, 2020 @ 13:08:00.000 | 2020-02-20 18:08:00.483 main-tokio- INFO substrate  Idle (51 peers), best: #1124468 (0x88ae…497a), finalized #1124465 (0xbfb3…5168), ⬇ 112.3kiB/s ⬆ 231.3kiB/s

  | Feb 20, 2020 @ 13:08:00.000 | 2020-02-20 18:08:00.382 main-tokio- INFO substrate  Imported #1124468 (0x88ae…497a)

  | Feb 20, 2020 @ 13:08:05.000 | 2020-02-20 18:08:05.492 main-tokio- INFO substrate  Idle (51 peers), best: #1124468 (0x88ae…497a), finalized #1124465 (0xbfb3…5168), ⬇ 88.7kiB/s ⬆ 189.2kiB/s

  | Feb 20, 2020 @ 13:08:06.000 | 2020-02-20 18:08:06.368 main-tokio- INFO substrate  Imported #1124469 (0x523f…00c3)

  | Feb 20, 2020 @ 13:08:07.000 | 2020-02-20 18:08:07.589 main-tokio- INFO substrate  Imported #1124469 (0x2547…aa03)

  | Feb 20, 2020 @ 13:08:10.000 | 2020-02-20 18:08:10.503 main-tokio- INFO substrate  Idle (49 peers), best: #1124469 (0x523f…00c3), finalized #1124466 (0xfe72…88bb), ⬇ 81.5kiB/s ⬆ 205.4kiB/s

  | Feb 20, 2020 @ 13:08:12.000 | 2020-02-20 18:08:12.607 main-tokio- INFO substrate  Imported #1124470 (0x28b3…cfd0)

  | Feb 20, 2020 @ 13:08:13.000 | 2020-02-20 18:08:13.422 main-tokio- INFO sub-libp2p  Discovered new external address for our node: /ip4/10.156.1.217/tcp/30333/p2p/Qmf6hguiVL9AY6SkiLBusQuNuygm4DVkxR6JrU9nKcbWMY

  | Feb 20, 2020 @ 13:08:15.000 | 2020-02-20 18:08:15.514 main-tokio- INFO substrate  Idle (49 peers), best: #1124470 (0x28b3…cfd0), finalized #1124468 (0x88ae…497a), ⬇ 115.1kiB/s ⬆ 106.2kiB/s

  | Feb 20, 2020 @ 13:08:18.000 | 2020-02-20 18:08:18.818 main-tokio- INFO substrate  Imported #1124471 (0x889e…8f8f)

  | Feb 20, 2020 @ 13:08:20.000 | 2020-02-20 18:08:20.523 main-tokio- INFO substrate  Idle (50 peers), best: #1124471 (0x889e…8f8f), finalized #1124468 (0x88ae…497a), ⬇ 91.1kiB/s ⬆ 203.1kiB/s

  | Feb 20, 2020 @ 13:08:24.000 | 2020-02-20 18:08:24.624 main-tokio- INFO substrate  Imported #1124472 (0x8d30…5edf)

  | Feb 20, 2020 @ 13:08:25.000 | 2020-02-20 18:08:25.533 main-tokio- INFO substrate  Idle (51 peers), best: #1124472 (0x8d30…5edf), finalized #1124469 (0x523f…00c3), ⬇ 114.9kiB/s ⬆ 203.9kiB/s

  | Feb 20, 2020 @ 13:08:30.000 | 2020-02-20 18:08:30.735 main-tokio- INFO substrate  Imported #1124473 (0xedba…7499)

  | Feb 20, 2020 @ 13:08:30.000 | 2020-02-20 18:08:30.542 main-tokio- INFO substrate  Idle (50 peers), best: #1124472 (0x8d30…5edf), finalized #1124470 (0x28b3…cfd0), ⬇ 66.6kiB/s ⬆ 190.0kiB/s

  | Feb 20, 2020 @ 13:08:35.000 | 2020-02-20 18:08:35.552 main-tokio- INFO substrate  Idle (51 peers), best: #1124473 (0xedba…7499), finalized #1124470 (0x28b3…cfd0), ⬇ 100.3kiB/s ⬆ 147.7kiB/s

  | Feb 20, 2020 @ 13:08:39.000 | 2020-02-20 18:08:39.513 main-tokio- INFO substrate  Imported #1124474 (0x5aac…1499)

  | Feb 20, 2020 @ 13:08:40.000 | 2020-02-20 18:08:40.564 main-tokio- INFO substrate  Idle (52 peers), best: #1124474 (0x5aac…1499), finalized #1124471 (0x889e…8f8f), ⬇ 90.5kiB/s ⬆ 183.7kiB/s

  | Feb 20, 2020 @ 13:08:42.000 | 2020-02-20 18:08:42.313 main-tokio- INFO substrate  Imported #1124475 (0x9af3…ffdc)

  | Feb 20, 2020 @ 13:08:45.000 | 2020-02-20 18:08:45.574 main-tokio- INFO substrate  Idle (51 peers), best: #1124475 (0x9af3…ffdc), finalized #1124473 (0xedba…7499), ⬇ 102.5kiB/s ⬆ 151.8kiB/s

  | Feb 20, 2020 @ 13:08:48.000 | 2020-02-20 18:08:48.510 main-tokio- INFO sub-libp2p  Discovered new external address for our node: /ip4/10.255.0.3/tcp/30333/p2p/Qmf6hguiVL9AY6SkiLBusQuNuygm4DVkxR6JrU9nKcbWMY

  | Feb 20, 2020 @ 13:08:49.000 | 2020-02-20 18:08:49.366 main-tokio- INFO substrate  Imported #1124476 (0xcc3a…b13a)

  | Feb 20, 2020 @ 13:08:50.000 | 2020-02-20 18:08:50.584 main-tokio- INFO substrate  Idle (52 peers), best: #1124476 (0xcc3a…b13a), finalized #1124473 (0xedba…7499), ⬇ 121.8kiB/s ⬆ 184.2kiB/s

  | Feb 20, 2020 @ 13:08:54.000 | 2020-02-20 18:08:54.396 main-tokio- INFO substrate  Imported #1124477 (0x3e93…40b6)

  | Feb 20, 2020 @ 13:08:55.000 | 2020-02-20 18:08:55.596 main-tokio- INFO substrate  Idle (51 peers), best: #1124477 (0x3e93…40b6), finalized #1124474 (0x5aac…1499), ⬇ 94.8kiB/s ⬆ 169.7kiB/s

  | Feb 20, 2020 @ 13:09:00.000 | 2020-02-20 18:09:00.607 main-tokio- INFO substrate  Idle (51 peers), best: #1124478 (0xf9c9…09b4), finalized #1124475 (0x9af3…ffdc), ⬇ 100.1kiB/s ⬆ 195.9kiB/s

  | Feb 20, 2020 @ 13:09:00.000 | 2020-02-20 18:09:00.411 main-tokio- INFO substrate  Imported #1124478 (0xf9c9…09b4)

  | Feb 20, 2020 @ 13:09:05.000 | 2020-02-20 18:09:05.617 main-tokio- INFO substrate  Idle (51 peers), best: #1124478 (0xf9c9…09b4), finalized #1124475 (0x9af3…ffdc), ⬇ 73.8kiB/s ⬆ 146.8kiB/s

  | Feb 20, 2020 @ 13:09:07.000 | 2020-02-20 18:09:07.371 main-tokio- INFO substrate  Imported #1124479 (0xa86b…86c0)

  | Feb 20, 2020 @ 13:09:10.000 | 2020-02-20 18:09:10.630 main-tokio- INFO substrate  Idle (51 peers), best: #1124479 (0xa86b…86c0), finalized #1124477 (0x3e93…40b6), ⬇ 102.9kiB/s ⬆ 136.5kiB/s

  | Feb 20, 2020 @ 13:09:12.000 | 2020-02-20 18:09:12.931 main-tokio- INFO substrate  Imported #1124480 (0x4437…9ef5)

  | Feb 20, 2020 @ 13:09:15.000 | 2020-02-20 18:09:15.639 main-tokio- INFO substrate  Idle (53 peers), best: #1124480 (0x4437…9ef5), finalized #1124477 (0x3e93…40b6), ⬇ 136.3kiB/s ⬆ 184.5kiB/s

  | Feb 20, 2020 @ 13:09:18.000 | 2020-02-20 18:09:18.581 main-tokio- INFO substrate  Imported #1124481 (0x3a2a…09cc)

  | Feb 20, 2020 @ 13:09:19.000 | 2020-02-20 18:09:19.737 main-tokio- INFO substrate  Imported #1124481 (0xd5dd…35fa)

  | Feb 20, 2020 @ 13:09:19.000 | 2020-02-20 18:09:19.737 main-tokio- INFO sc_cli::informant  Reorg from #1124481,0x3a2a…09cc to #1124481,0xd5dd…35fa, common ancestor #1124480,0x4437…9ef5

  | Feb 20, 2020 @ 13:09:20.000 | 2020-02-20 18:09:20.651 main-tokio- INFO substrate  Idle (51 peers), best: #1124481 (0xd5dd…35fa), finalized #1124478 (0xf9c9…09b4), ⬇ 146.0kiB/s ⬆ 182.7kiB/s

  | Feb 20, 2020 @ 13:09:24.000 | 2020-02-20 18:09:24.437 main-tokio- INFO substrate  Imported #1124482 (0x59e9…c9ec)

  | Feb 20, 2020 @ 13:09:25.000 | 2020-02-20 18:09:25.630 main-tokio- INFO substrate  Imported #1124482 (0x475a…6961)

  | Feb 20, 2020 @ 13:09:25.000 | 2020-02-20 18:09:25.629 main-tokio- INFO sc_cli::informant  Reorg from #1124482,0x59e9…c9ec to #1124482,0x475a…6961, common ancestor #1124481,0xd5dd…35fa

  | Feb 20, 2020 @ 13:09:25.000 | 2020-02-20 18:09:25.664 main-tokio- INFO substrate  Idle (51 peers), best: #1124482 (0x475a…6961), finalized #1124479 (0xa86b…86c0), ⬇ 83.5kiB/s ⬆ 142.1kiB/s

  | Feb 20, 2020 @ 13:09:30.000 | 2020-02-20 18:09:30.674 main-tokio- INFO substrate  Idle (51 peers), best: #1124483 (0x80b0…e2c9), finalized #1124480 (0x4437…9ef5), ⬇ 92.4kiB/s ⬆ 205.4kiB/s

  | Feb 20, 2020 @ 13:09:30.000 | 2020-02-20 18:09:30.590 main-tokio- INFO substrate  Imported #1124483 (0x80b0…e2c9)

  | Feb 20, 2020 @ 13:09:35.000 | 2020-02-20 18:09:35.684 main-tokio- INFO substrate  Idle (51 peers), best: #1124483 (0x80b0…e2c9), finalized #1124480 (0x4437…9ef5), ⬇ 65.5kiB/s ⬆ 107.7kiB/s

  | Feb 20, 2020 @ 13:09:36.000 | 2020-02-20 18:09:36.304 main-tokio- INFO substrate  Imported #1124484 (0x6ccb…a727)

  | Feb 20, 2020 @ 13:09:40.000 | 2020-02-20 18:09:40.695 main-tokio- INFO substrate  Idle (51 peers), best: #1124484 (0x6ccb…a727), finalized #1124482 (0x475a…6961), ⬇ 105.4kiB/s ⬆ 139.3kiB/s

  | Feb 20, 2020 @ 13:09:42.000 | 2020-02-20 18:09:42.372 main-tokio- INFO substrate  Imported #1124485 (0x93df…cb3c)

  | Feb 20, 2020 @ 13:09:45.000 | 2020-02-20 18:09:45.705 main-tokio- INFO substrate  Idle (51 peers), best: #1124485 (0x93df…cb3c), finalized #1124482 (0x475a…6961), ⬇ 98.3kiB/s ⬆ 188.8kiB/s

  | Feb 20, 2020 @ 13:09:48.000 | 2020-02-20 18:09:48.035 main-tokio- WARN libp2p_kad::behaviour  Locating closest peers for replication failed: Err(Timeout { key: Key(b"\x12 \xfa\xf4\x19\xc9[\x05d\xf4\xd9\x96\xc8\xa5&`[\x99\xe4'Wo~[S\x82-9:\x9e\xe5\xd3eF"), num_results: 0, quorum: 20 })

  | Feb 20, 2020 @ 13:09:48.000 | 2020-02-20 18:09:48.841 main-tokio- INFO substrate  Imported #1124486 (0x6bfa…666c)

  | Feb 20, 2020 @ 13:09:48.000 | 2020-02-20 18:09:48.035 main-tokio- WARN libp2p_kad::behaviour  Locating closest peers for replication failed: Err(Timeout { key: Key(b"\x12 \x15^\xf9\x11\xaec\x05\x9c\xb5\xc4\xae \r< \xb0\xd0B\x03UJ\x80\x13\xe7\xab\x81\x11D\x92\xeaG\x11"), num_results: 0, quorum: 20 })

  | Feb 20, 2020 @ 13:09:48.000 | 2020-02-20 18:09:48.035 main-tokio- WARN libp2p_kad::behaviour  Locating closest peers for replication failed: Err(Timeout { key: Key(b"\x12 7\x9e\x9b[.,\xe5S\xc3SV1 ~l\xae\xd5\xd1\xd0\xe8\xd8\xadU\0\x94oD\n\x03<\xcd\xec"), num_results: 0, quorum: 20 })

  | Feb 20, 2020 @ 13:09:48.000 | 2020-02-20 18:09:48.035 main-tokio- WARN libp2p_kad::behaviour  Locating closest peers for replication failed: Err(Timeout { key: Key(b"\x12 $\x1e#rr\xa9\x15\x18\xc9'\xb6\x0c\xf6\x1e\xb7\x99\x8c\xd0a\x9c\xdcZ3\x88\xd0\x0f_\xc02\xb6\x80\x07"), num_results: 0, quorum: 20 })

  | Feb 20, 2020 @ 13:09:50.000 | 2020-02-20 18:09:50.716 main-tokio- INFO substrate  Idle (51 peers), best: #1124486 (0x6bfa…666c), finalized #1124483 (0x80b0…e2c9), ⬇ 90.2kiB/s ⬆ 172.0kiB/s

  | Feb 20, 2020 @ 13:09:54.000 | 2020-02-20 18:09:54.576 main-tokio- INFO substrate  Imported #1124487 (0x3da1…e61a)

  | Feb 20, 2020 @ 13:09:55.000 | 2020-02-20 18:09:55.726 main-tokio- INFO substrate  Idle (50 peers), best: #1124487 (0x3da1…e61a), finalized #1124484 (0x6ccb…a727), ⬇ 90.8kiB/s ⬆ 131.5kiB/s

  | Feb 20, 2020 @ 13:10:00.000 | 2020-02-20 18:10:00.737 main-tokio- INFO substrate  Idle (50 peers), best: #1124488 (0xc1d6…1571), finalized #1124485 (0x93df…cb3c), ⬇ 102.7kiB/s ⬆ 163.9kiB/s

  | Feb 20, 2020 @ 13:10:00.000 | 2020-02-20 18:10:00.420 main-tokio- INFO substrate  Imported #1124488 (0xc1d6…1571)

  | Feb 20, 2020 @ 13:10:05.000 | 2020-02-20 18:10:05.752 main-tokio- INFO substrate  Idle (52 peers), best: #1124488 (0xc1d6…1571), finalized #1124485 (0x93df…cb3c), ⬇ 62.3kiB/s ⬆ 85.2kiB/s

  | Feb 20, 2020 @ 13:10:06.000 | 2020-02-20 18:10:06.310 main-tokio- INFO substrate  Imported #1124489 (0x73ca…d7b4)

  | Feb 20, 2020 @ 13:10:06.000 | 2020-02-20 18:10:06.844 main-tokio- INFO substrate  Imported #1124489 (0x20e4…5313)

  | Feb 20, 2020 @ 13:10:06.000 | 2020-02-20 18:10:06.844 main-tokio- INFO sc_cli::informant  Reorg from #1124489,0x73ca…d7b4 to #1124489,0x20e4…5313, common ancestor #1124488,0xc1d6…1571

  | Feb 20, 2020 @ 13:10:10.000 | 2020-02-20 18:10:10.762 main-tokio- INFO substrate  Idle (51 peers), best: #1124489 (0x20e4…5313), finalized #1124487 (0x3da1…e61a), ⬇ 98.5kiB/s ⬆ 223.2kiB/s

  | Feb 20, 2020 @ 13:10:12.000 | 2020-02-20 18:10:12.863 main-tokio- INFO substrate  Imported #1124490 (0x0e59…b6d2)

  | Feb 20, 2020 @ 13:10:15.000 | 2020-02-20 18:10:15.772 main-tokio- INFO substrate  Idle (51 peers), best: #1124490 (0x0e59…b6d2), finalized #1124487 (0x3da1…e61a), ⬇ 103.3kiB/s ⬆ 214.5kiB/s

  | Feb 20, 2020 @ 13:10:18.000 | 2020-02-20 18:10:18.302 main-tokio- INFO substrate  Imported #1124491 (0x1073…d577)

  | Feb 20, 2020 @ 13:10:20.000 | 2020-02-20 18:10:20.784 main-tokio- INFO substrate  Idle (52 peers), best: #1124491 (0x1073…d577), finalized #1124488 (0xc1d6…1571), ⬇ 110.8kiB/s ⬆ 147.2kiB/s

  | Feb 20, 2020 @ 13:10:24.000 | 2020-02-20 18:10:24.975 main-tokio- INFO substrate  Imported #1124492 (0xd814…f07e)

  | Feb 20, 2020 @ 13:10:25.000 | 2020-02-20 18:10:25.795 main-tokio- INFO substrate  Idle (52 peers), best: #1124492 (0xd814…f07e), finalized #1124489 (0x20e4…5313), ⬇ 113.0kiB/s ⬆ 143.3kiB/s

  | Feb 20, 2020 @ 13:10:30.000 | 2020-02-20 18:10:30.805 main-tokio- INFO substrate  Idle (51 peers), best: #1124492 (0xd814…f07e), finalized #1124490 (0x0e59…b6d2), ⬇ 115.8kiB/s ⬆ 161.9kiB/s

  | Feb 20, 2020 @ 13:10:31.000 | 2020-02-20 18:10:31.169 main-tokio- INFO substrate  Imported #1124493 (0x2855…7a36)

  | Feb 20, 2020 @ 13:10:35.000 | 2020-02-20 18:10:35.815 main-tokio- INFO substrate  Idle (51 peers), best: #1124493 (0x2855…7a36), finalized #1124491 (0x1073…d577), ⬇ 64.1kiB/s ⬆ 114.1kiB/s

  | Feb 20, 2020 @ 13:10:37.000 | 2020-02-20 18:10:37.305 main-tokio- INFO substrate  Imported #1124494 (0x68d2…5e8a)

  | Feb 20, 2020 @ 13:10:41.000 | 2020-02-20 18:10:41.285 main INFO polkadot_cli::command    by Parity Team <admin@parity.io>, 2017-2020

  | Feb 20, 2020 @ 13:10:41.000 | 2020-02-20 18:10:41.285 main INFO polkadot_cli::command  Chain specification: Kusama CC3

  | Feb 20, 2020 @ 13:10:41.000 | 2020-02-20 18:10:41.285 main INFO polkadot_cli::command  Node name: belg-sen-1

  | Feb 20, 2020 @ 13:10:41.000 | 2020-02-20 18:10:41.285 main INFO polkadot_cli::command  This chain is not in any way

  | Feb 20, 2020 @ 13:10:41.000 | 2020-02-20 18:10:41.285 main INFO polkadot_cli::command        endorsed by the

  | Feb 20, 2020 @ 13:10:41.000 | 2020-02-20 18:10:41.285 main INFO polkadot_cli::command  Parity Polkadot

  | Feb 20, 2020 @ 13:10:41.000 | 2020-02-20 18:10:41.285 main INFO polkadot_cli::command    version 0.7.20-37381589-x86_64-linux-gnu

  | Feb 20, 2020 @ 13:10:41.000 | 2020-02-20 18:10:41.285 main INFO polkadot_cli::command  Roles: SENTRY

  | Feb 20, 2020 @ 13:10:41.000 | 2020-02-20 18:10:41.285 main INFO polkadot_cli::command  Native runtime: kusama-1045:2(parity-kusama-0)

  | Feb 20, 2020 @ 13:10:41.000 | 2020-02-20 18:10:41.285 main INFO polkadot_cli::command  ----------------------------

  | Feb 20, 2020 @ 13:10:41.000 | 2020-02-20 18:10:41.285 main INFO polkadot_cli::command       KUSAMA FOUNDATION

  | Feb 20, 2020 @ 13:10:41.000 | 2020-02-20 18:10:41.285 main INFO polkadot_cli::command  ----------------------------

  | Feb 20, 2020 @ 13:10:54.000 | 2020-02-20 18:10:54.470 main INFO sc_service::builder  Highest known block at #1124494

  | Feb 20, 2020 @ 13:10:54.000 | 2020-02-20 18:10:54.493 main-tokio- INFO grafana_data_source::server  Grafana data source server started at 127.0.0.1:9955

  | Feb 20, 2020 @ 13:10:54.000 | 2020-02-20 18:10:54.471 main INFO sub-libp2p  Local node identity is: Qmf6hguiVL9AY6SkiLBusQuNuygm4DVkxR6JrU9nKcbWMY

  | Feb 20, 2020 @ 13:10:55.000 | 2020-02-20 18:10:55.039 main-tokio- INFO sub-libp2p  Discovered new external address for our node: /ip4/35.195.11.69/tcp/30333/p2p/Qmf6hguiVL9AY6SkiLBusQuNuygm4DVkxR6JrU9nKcbWMY

  | Feb 20, 2020 @ 13:10:55.000 | 2020-02-20 18:10:55.089 main-tokio- INFO sub-libp2p  Discovered new external address for our node: /ip4/10.0.1.134/tcp/30333/p2p/Qmf6hguiVL9AY6SkiLBusQuNuygm4DVkxR6JrU9nKcbWMY

  | Feb 20, 2020 @ 13:10:55.000 | 2020-02-20 18:10:55.010 main-tokio- INFO sub-libp2p  Discovered new external address for our node: /ip4/10.255.0.3/tcp/30333/p2p/Qmf6hguiVL9AY6SkiLBusQuNuygm4DVkxR6JrU9nKcbWMY

  | Feb 20, 2020 @ 13:10:55.000 | 2020-02-20 18:10:55.934 main-tokio- INFO sub-libp2p  Discovered new external address for our node: /ip4/10.0.1.136/tcp/30333/p2p/Qmf6hguiVL9AY6SkiLBusQuNuygm4DVkxR6JrU9nKcbWMY

  | Feb 20, 2020 @ 13:10:57.000 | 2020-02-20 18:10:57.353 main-tokio- INFO substrate  Imported #1124497 (0x984a…71be)

  | Feb 20, 2020 @ 13:10:59.000 | 2020-02-20 18:10:59.493 main-tokio- INFO substrate  Idle (15 peers), best: #1124497 (0x984a…71be), finalized #1124494 (0x68d2…5e8a), ⬇ 113.4kiB/s ⬆ 82.9kiB/s

  | Feb 20, 2020 @ 13:11:00.000 | 2020-02-20 18:11:00.836 main-tokio- INFO substrate  Imported #1124498 (0xb3f2…8cda)

  | Feb 20, 2020 @ 13:11:04.000 | 2020-02-20 18:11:04.494 main-tokio- INFO substrate  Idle (16 peers), best: #1124498 (0xb3f2…8cda), finalized #1124495 (0xd328…b4ec), ⬇ 65.5kiB/s ⬆ 94.3kiB/s

  | Feb 20, 2020 @ 13:11:07.000 | 2020-02-20 18:11:07.907 main-tokio- INFO substrate  Imported #1124499 (0x6d2d…3e6c)

  | Feb 20, 2020 @ 13:11:07.000 | 2020-02-20 18:11:07.183 main-tokio- INFO substrate  Imported #1124499 (0x532d…c71d)

  | Feb 20, 2020 @ 13:11:07.000 | 2020-02-20 18:11:07.051 main-tokio- INFO substrate  Imported #1124499 (0x7152…5a02)

  | Feb 20, 2020 @ 13:11:09.000 | 2020-02-20 18:11:09.495 main-tokio- INFO substrate  Idle (16 peers), best: #1124499 (0x7152…5a02), finalized #1124496 (0x20ef…b180), ⬇ 34.0kiB/s ⬆ 102.3kiB/s

  | Feb 20, 2020 @ 13:11:14.000 | 2020-02-20 18:11:14.496 main-tokio- INFO substrate  Idle (17 peers), best: #1124499 (0x7152…5a02), finalized #1124497 (0x984a…71be), ⬇ 61.9kiB/s ⬆ 143.5kiB/s

  | Feb 20, 2020 @ 13:11:15.000 | 2020-02-20 18:11:15.644 main-tokio- INFO substrate  Imported #1124500 (0xb2bd…ddac)

  | Feb 20, 2020 @ 13:11:18.000 | 2020-02-20 18:11:18.829 main-tokio- INFO substrate  Imported #1124501 (0x2e3e…302b)

  | Feb 20, 2020 @ 13:11:19.000 | 2020-02-20 18:11:19.497 main-tokio- INFO substrate  Idle (18 peers), best: #1124501 (0x2e3e…302b), finalized #1124497 (0x984a…71be), ⬇ 53.4kiB/s ⬆ 65.2kiB/s

  | Feb 20, 2020 @ 13:11:24.000 | 2020-02-20 18:11:24.699 main-tokio- INFO substrate  Imported #1124502 (0xb47e…a2b0)

  | Feb 20, 2020 @ 13:11:24.000 | 2020-02-20 18:11:24.498 main-tokio- INFO substrate  Idle (19 peers), best: #1124501 (0x2e3e…302b), finalized #1124499 (0x7152…5a02), ⬇ 58.0kiB/s ⬆ 121.4kiB/s

  | Feb 20, 2020 @ 13:11:25.000 | 2020-02-20 18:11:25.444 main-tokio- INFO sc_cli::informant  Reorg from #1124502,0xb47e…a2b0 to #1124502,0xfbd1…c44c, common ancestor #1124501,0x2e3e…302b

  | Feb 20, 2020 @ 13:11:25.000 | 2020-02-20 18:11:25.444 main-tokio- INFO substrate  Imported #1124502 (0xfbd1…c44c)

  | Feb 20, 2020 @ 13:11:29.000 | 2020-02-20 18:11:29.499 main-tokio- INFO substrate  Idle (19 peers), best: #1124502 (0xfbd1…c44c), finalized #1124499 (0x7152…5a02), ⬇ 44.0kiB/s ⬆ 101.6kiB/s

  | Feb 20, 2020 @ 13:11:30.000 | 2020-02-20 18:11:30.332 main-tokio- INFO substrate  Imported #1124503 (0x5dde…85e2)

  | Feb 20, 2020 @ 13:11:30.000 | 2020-02-20 18:11:30.593 main-tokio- INFO substrate  Imported #1124503 (0x3b61…4317)

  | Feb 20, 2020 @ 13:11:30.000 | 2020-02-20 18:11:30.593 main-tokio- INFO sc_cli::informant  Reorg from #1124503,0x5dde…85e2 to #1124503,0x3b61…4317, common ancestor #1124502,0xfbd1…c44c

  | Feb 20, 2020 @ 13:11:34.000 | 2020-02-20 18:11:34.500 main-tokio- INFO substrate  Idle (20 peers), best: #1124503 (0x3b61…4317), finalized #1124500 (0xb2bd…ddac), ⬇ 72.5kiB/s ⬆ 144.6kiB/s

  | Feb 20, 2020 @ 13:11:34.000 | 2020-02-20 18:11:34.345 main-tokio- INFO sub-libp2p  Discovered new external address for our node: /ip4/10.10.10.1/tcp/30333/p2p/Qmf6hguiVL9AY6SkiLBusQuNuygm4DVkxR6JrU9nKcbWMY

  | Feb 20, 2020 @ 13:11:36.000 | 2020-02-20 18:11:36.853 main-tokio- INFO substrate  Imported #1124504 (0x711c…fecf)

  | Feb 20, 2020 @ 13:11:39.000 | 2020-02-20 18:11:39.501 main-tokio- INFO substrate  Idle (25 peers), best: #1124504 (0x711c…fecf), finalized #1124501 (0x2e3e…302b), ⬇ 66.1kiB/s ⬆ 111.5kiB/s

  | Feb 20, 2020 @ 13:11:43.000 | 2020-02-20 18:11:43.078 main-tokio- INFO substrate  Imported #1124505 (0x6723…06a2)

  | Feb 20, 2020 @ 13:11:44.000 | 2020-02-20 18:11:44.503 main-tokio- INFO substrate  Idle (25 peers), best: #1124505 (0x6723…06a2), finalized #1124502 (0xfbd1…c44c), ⬇ 112.7kiB/s ⬆ 141.0kiB/s

  | Feb 20, 2020 @ 13:11:45.000 | 2020-02-20 18:11:45.360 main-tokio- INFO substrate  Imported #1124505 (0x1400…3707)

  | Feb 20, 2020 @ 13:11:48.000 | 2020-02-20 18:11:48.675 main-tokio- INFO substrate  Imported #1124506 (0xf9a9…1bea)

  | Feb 20, 2020 @ 13:11:49.000 | 2020-02-20 18:11:49.504 main-tokio- INFO substrate  Idle (24 peers), best: #1124506 (0xf9a9…1bea), finalized #1124503 (0x3b61…4317), ⬇ 60.1kiB/s ⬆ 112.1kiB/s

  | Feb 20, 2020 @ 13:11:54.000 | 2020-02-20 18:11:54.401 main-tokio- INFO substrate  Imported #1124507 (0x3145…8ca3)

  | Feb 20, 2020 @ 13:11:54.000 | 2020-02-20 18:11:54.505 main-tokio- INFO substrate  Idle (26 peers), best: #1124507 (0x3145…8ca3), finalized #1124504 (0x711c…fecf), ⬇ 75.5kiB/s ⬆ 125.5kiB/s

@arkpar
Copy link
Member

arkpar commented Feb 20, 2020

TCP connections are reaching 2k+ still @tomaka

@tomaka
Copy link
Contributor

tomaka commented Feb 21, 2020

The few fixes that we made are not in Polkadot v0.7.20 (paritytech/substrate#4828 paritytech/substrate#4830 paritytech/substrate#4889)
Especially paritytech/substrate#4830 would be very useful here.

@Mitchell-Grimes
Copy link
Author

For some control testing, I was configuring the Max open files for the process. So by default it's 4096.

The testing that I did was reduce the file descriptor count from 4096 to 1000. Then from 1000 to 500. I wanted to see if this would increase the occurrence of the issue.

Based off some of this testing it raises the question of whether or not we should increase process limits for file descriptors or not?

It is interesting to note it appears that under normal conditions, the total number of TCP connections remain steady, but the file descriptor count continues to go up, so it's not always a 1:1.

Below are some logs on standard testing with screenshots.

Default configuration


The number of file_descriptors is: 3231
Mon Feb 24 20:06:08 UTC 2020
The number of file_descriptors is: 3229
Mon Feb 24 20:06:10 UTC 2020
The number of file_descriptors is: 3222
Mon Feb 24 20:06:11 UTC 2020
The number of file_descriptors is: 3224
Mon Feb 24 20:06:12 UTC 2020
The number of file_descriptors is: 3221
Mon Feb 24 20:06:14 UTC 2020
The number of file_descriptors is: 3226
Mon Feb 24 20:06:15 UTC 2020
The number of file_descriptors is: 3230
Mon Feb 24 20:06:16 UTC 2020
The number of file_descriptors is: 3228
Mon Feb 24 20:06:18 UTC 2020
The number of file_descriptors is: 3225
Mon Feb 24 20:06:19 UTC 2020
The number of file_descriptors is: 3222
Mon Feb 24 20:06:20 UTC 2020
The number of file_descriptors is: 3224
Mon Feb 24 20:06:22 UTC 2020
The number of file_descriptors is: 3224
Mon Feb 24 20:06:23 UTC 2020
The number of file_descriptors is: 3232
|
|
|
| FAST FORWARD
|
|
|
The number of file_descriptors is: 4082
Tue Feb 25 07:20:24 UTC 2020
The number of file_descriptors is: 4077
Tue Feb 25 07:20:26 UTC 2020
The number of file_descriptors is: 4076
Tue Feb 25 07:20:27 UTC 2020
The number of file_descriptors is: 4074
Tue Feb 25 07:20:28 UTC 2020
The number of file_descriptors is: 4071
Tue Feb 25 07:20:30 UTC 2020
The number of file_descriptors is: 4063
Tue Feb 25 07:20:31 UTC 2020
The number of file_descriptors is: 4058
Tue Feb 25 07:20:32 UTC 2020
The number of file_descriptors is: 4056
Tue Feb 25 07:20:34 UTC 2020
The number of file_descriptors is: 4055
Tue Feb 25 07:20:35 UTC 2020
The number of file_descriptors is: 4051
Tue Feb 25 07:20:37 UTC 2020
The number of file_descriptors is: 4049
Tue Feb 25 07:20:38 UTC 2020
The number of file_descriptors is: 4048
Tue Feb 25 07:20:39 UTC 2020
The number of file_descriptors is: 4047
Tue Feb 25 07:20:41 UTC 2020
The number of file_descriptors is: 4044
Tue Feb 25 07:20:42 UTC 2020
The number of file_descriptors is: 4042
Tue Feb 25 07:20:43 UTC 2020
The number of file_descriptors is: 4042
Tue Feb 25 07:20:45 UTC 2020
The number of file_descriptors is: 4041
Tue Feb 25 07:20:46 UTC 2020
The number of file_descriptors is: 4040
Tue Feb 25 07:20:47 UTC 2020
The number of file_descriptors is: 4038
Tue Feb 25 07:20:49 UTC 2020
The number of file_descriptors is: 4038
Tue Feb 25 07:20:50 UTC 2020
The number of file_descriptors is: 4037
Tue Feb 25 07:20:52 UTC 2020
The number of file_descriptors is: 4036
Tue Feb 25 07:20:53 UTC 2020
The number of file_descriptors is: 4035
Tue Feb 25 07:20:54 UTC 2020
The number of file_descriptors is: 4009
Tue Feb 25 07:20:56 UTC 2020
The number of file_descriptors is: 0
Tue Feb 25 07:20:57 UTC 2020  -------- Process ID that was being monitored no longer has a count since service was auto restarted. It stopped listening on the port.

sentry01_control

Test case configuration using LimitNOFILE=500


The number of file_descriptors is: 184
Mon Feb 24 18:38:38 UTC 2020
The number of file_descriptors is: 181
Mon Feb 24 18:38:40 UTC 2020
The number of file_descriptors is: 180
Mon Feb 24 18:38:41 UTC 2020
The number of file_descriptors is: 178
Mon Feb 24 18:38:43 UTC 2020
The number of file_descriptors is: 183
Mon Feb 24 18:38:44 UTC 2020
The number of file_descriptors is: 185
Mon Feb 24 18:38:46 UTC 2020
The number of file_descriptors is: 187
Mon Feb 24 18:38:47 UTC 2020
The number of file_descriptors is: 189
Mon Feb 24 18:38:49 UTC 2020
The number of file_descriptors is: 188
Mon Feb 24 18:38:51 UTC 2020
The number of file_descriptors is: 192
Mon Feb 24 18:38:52 UTC 2020
The number of file_descriptors is: 195
Mon Feb 24 18:38:54 UTC 2020
The number of file_descriptors is: 197
Mon Feb 24 18:38:55 UTC 2020
The number of file_descriptors is: 197
Mon Feb 24 18:38:57 UTC 2020
The number of file_descriptors is: 192
Mon Feb 24 18:38:58 UTC 2020
The number of file_descriptors is: 189
Mon Feb 24 18:39:00 UTC 2020
The number of file_descriptors is: 189
Mon Feb 24 18:39:02 UTC 2020
The number of file_descriptors is: 189
Mon Feb 24 18:39:03 UTC 2020
The number of file_descriptors is: 184
Mon Feb 24 18:39:05 UTC 2020
The number of file_descriptors is: 187
Mon Feb 24 18:39:06 UTC 2020
The number of file_descriptors is: 185
Mon Feb 24 18:39:08 UTC 2020
The number of file_descriptors is: 185
Mon Feb 24 18:39:09 UTC 2020
The number of file_descriptors is: 185
Mon Feb 24 18:39:11 UTC 2020
The number of file_descriptors is: 185
Mon Feb 24 18:39:13 UTC 2020
The number of file_descriptors is: 185
Mon Feb 24 18:39:14 UTC 2020
The number of file_descriptors is: 188
Mon Feb 24 18:39:16 UTC 2020
The number of file_descriptors is: 188
Mon Feb 24 18:39:17 UTC 2020
The number of file_descriptors is: 188
Mon Feb 24 18:39:19 UTC 2020
The number of file_descriptors is: 188
Mon Feb 24 18:39:20 UTC 2020
The number of file_descriptors is: 186
Mon Feb 24 18:39:22 UTC 2020
The number of file_descriptors is: 185
Mon Feb 24 18:39:23 UTC 2020
The number of file_descriptors is: 185
Mon Feb 24 18:39:25 UTC 2020
The number of file_descriptors is: 183
Mon Feb 24 18:39:27 UTC 2020
The number of file_descriptors is: 185
Mon Feb 24 18:39:28 UTC 2020
The number of file_descriptors is: 186
Mon Feb 24 18:39:30 UTC 2020
The number of file_descriptors is: 186
Mon Feb 24 18:39:31 UTC 2020
The number of file_descriptors is: 188
Mon Feb 24 18:39:33 UTC 2020
The number of file_descriptors is: 190
Mon Feb 24 18:39:34 UTC 2020
The number of file_descriptors is: 189
Mon Feb 24 18:39:36 UTC 2020
The number of file_descriptors is: 189
Mon Feb 24 18:39:37 UTC 2020
The number of file_descriptors is: 188
Mon Feb 24 18:39:39 UTC 2020
The number of file_descriptors is: 187
Mon Feb 24 18:39:41 UTC 2020
The number of file_descriptors is: 185
Mon Feb 24 18:39:42 UTC 2020
The number of file_descriptors is: 184
Mon Feb 24 18:39:44 UTC 2020
The number of file_descriptors is: 183
Mon Feb 24 18:39:45 UTC 2020
The number of file_descriptors is: 184
Mon Feb 24 18:39:47 UTC 2020
The number of file_descriptors is: 185
Mon Feb 24 18:39:49 UTC 2020
The number of file_descriptors is: 186
Mon Feb 24 18:39:50 UTC 2020
The number of file_descriptors is: 181
Mon Feb 24 18:39:52 UTC 2020
The number of file_descriptors is: 182
Mon Feb 24 18:39:53 UTC 2020
The number of file_descriptors is: 182
Mon Feb 24 18:39:55 UTC 2020
The number of file_descriptors is: 183
Mon Feb 24 18:39:56 UTC 2020
The number of file_descriptors is: 182
Mon Feb 24 18:39:58 UTC 2020
The number of file_descriptors is: 180
Mon Feb 24 18:40:00 UTC 2020
The number of file_descriptors is: 183
Mon Feb 24 18:40:01 UTC 2020
The number of file_descriptors is: 184
Mon Feb 24 18:40:03 UTC 2020
The number of file_descriptors is: 185
Mon Feb 24 18:40:04 UTC 2020
The number of file_descriptors is: 183
Mon Feb 24 18:40:06 UTC 2020
The number of file_descriptors is: 183
Mon Feb 24 18:40:07 UTC 2020
The number of file_descriptors is: 185
Mon Feb 24 18:40:09 UTC 2020
The number of file_descriptors is: 185
Mon Feb 24 18:40:10 UTC 2020
The number of file_descriptors is: 184
Mon Feb 24 18:40:12 UTC 2020
The number of file_descriptors is: 185
Mon Feb 24 18:40:14 UTC 2020
The number of file_descriptors is: 186
Mon Feb 24 18:40:15 UTC 2020
The number of file_descriptors is: 187
Mon Feb 24 18:40:17 UTC 2020
The number of file_descriptors is: 185
Mon Feb 24 18:40:18 UTC 2020
The number of file_descriptors is: 186
Mon Feb 24 18:40:20 UTC 2020
The number of file_descriptors is: 187
Mon Feb 24 18:40:21 UTC 2020
The number of file_descriptors is: 189
Mon Feb 24 18:40:23 UTC 2020
The number of file_descriptors is: 189
Mon Feb 24 18:40:24 UTC 2020
The number of file_descriptors is: 189
Mon Feb 24 18:40:26 UTC 2020
The number of file_descriptors is: 186
Mon Feb 24 18:40:27 UTC 2020
|
|
|
| FAST FORWARD
|
|
|
The number of file_descriptors is: 411
Tue Feb 25 03:12:13 UTC 2020
The number of file_descriptors is: 411
Tue Feb 25 03:12:15 UTC 2020
The number of file_descriptors is: 411
Tue Feb 25 03:12:16 UTC 2020
The number of file_descriptors is: 411
Tue Feb 25 03:12:18 UTC 2020
The number of file_descriptors is: 411
Tue Feb 25 03:12:19 UTC 2020
The number of file_descriptors is: 411
Tue Feb 25 03:12:21 UTC 2020
The number of file_descriptors is: 411
Tue Feb 25 03:12:22 UTC 2020
The number of file_descriptors is: 411
Tue Feb 25 03:12:24 UTC 2020
The number of file_descriptors is: 411
Tue Feb 25 03:12:25 UTC 2020
The number of file_descriptors is: 411
Tue Feb 25 03:12:27 UTC 2020
The number of file_descriptors is: 411
Tue Feb 25 03:12:29 UTC 2020
The number of file_descriptors is: 411
Tue Feb 25 03:12:30 UTC 2020
The number of file_descriptors is: 411
Tue Feb 25 03:12:32 UTC 2020
The number of file_descriptors is: 411
Tue Feb 25 03:12:33 UTC 2020
The number of file_descriptors is: 411
Tue Feb 25 03:12:35 UTC 2020
The number of file_descriptors is: 411
Tue Feb 25 03:12:36 UTC 2020
The number of file_descriptors is: 411
Tue Feb 25 03:12:38 UTC 2020
The number of file_descriptors is: 411
Tue Feb 25 03:12:39 UTC 2020
The number of file_descriptors is: 411
Tue Feb 25 03:12:41 UTC 2020
The number of file_descriptors is: 411
Tue Feb 25 03:12:42 UTC 2020
The number of file_descriptors is: 411
Tue Feb 25 03:12:44 UTC 2020
The number of file_descriptors is: 411
Tue Feb 25 03:12:46 UTC 2020
The number of file_descriptors is: 411
Tue Feb 25 03:12:47 UTC 2020
The number of file_descriptors is: 411
Tue Feb 25 03:12:49 UTC 2020
The number of file_descriptors is: 0
Tue Feb 25 03:12:50 UTC 2020


sentry02_test

@tomaka
Copy link
Contributor

tomaka commented Feb 25, 2020

Ah thanks, so it is indeed related to the number of file descriptors.

If that's the case, then libp2p/rust-libp2p#1427 (which is available in Polkadot's master branch but not released yet) should fix it.

We're also trying to fix the fact that so many TCP connections are being held open all the time.

@Mitchell-Grimes
Copy link
Author

Looking forward to it. Thanks for the information @tomaka

@tomaka
Copy link
Contributor

tomaka commented Apr 23, 2020

This should normally be fixed (by libp2p/rust-libp2p#1427)
Please reopen if it happens again.

Sign up for free to subscribe to this conversation on GitHub. Already have an account? Sign in.
Labels
I3-bug Fails to follow expected behavior.
Projects
None yet
Development

No branches or pull requests

4 participants