Skip to content

Instantly share code, notes, and snippets.

@adilimroz

adilimroz/gist:9ed6f6377ab681d2750f84c566321b06

Created November 6, 2020 23:51
Show Gist options
  • Save adilimroz/9ed6f6377ab681d2750f84c566321b06 to your computer and use it in GitHub Desktop.
Save adilimroz/9ed6f6377ab681d2750f84c566321b06 to your computer and use it in GitHub Desktop.
Download ZIP
Raw
gistfile1.txt
github.com/smartcontractkit/libocr/networking/dht-router.DHTRouter.startAnnounceInBackground.func1
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/networking/dht-router/router.go:267
github.com/smartcontractkit/libocr/subprocesses.(*Subprocesses).Go.func1
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/subprocesses/subprocesses.go:29
2020-11-06T23:46:12Z [DEBUG] Received new head #21955398 (0x14f0346) services/head_tracker.go:335 blockHash=0x3575d367641b3b05e1d45562d567346b833c0ae3c32841f01d7027cfadf30b38 blockHeight=21955398
2020-11-06T23:46:12Z [DEBUG] HeadTracker initiating callbacks services/head_tracker.go:498 chainLength=50 headNum=21955398 numCallbacks=6
2020-11-06T23:46:12Z [DEBUG] HeadTracker: finished callback 5 in 9.2µs services/head_tracker.go:520 blockNumber=21955398 callbackIdx=5 callbackType=*chainlink.headTrackableCallback id=head_tracker time=0.0000092
2020-11-06T23:46:12Z [DEBUG] HeadTracker: finished callback 3 in 11.5µs services/head_tracker.go:520 blockNumber=21955398 callbackIdx=3 callbackType=*chainlink.pendingConnectionResumer id=head_tracker time=0.0000115
2020-11-06T23:46:12Z [DEBUG] BalanceMonitor: signalling balance worker services/balance_monitor.go:68
2020-11-06T23:46:12Z [DEBUG] HeadTracker: finished callback 2 in 7.2µs services/head_tracker.go:520 blockNumber=21955398 callbackIdx=2 callbackType=*services.jobSubscriber id=head_tracker time=0.0000072
2020-11-06T23:46:12Z [DEBUG] Resuming all runs pending next block services/run_manager.go:247 currentBlockHeight=21955398
2020-11-06T23:46:12Z [DEBUG] eth.Client#BlockByNumber(...) eth/client.go:270 number=21955395
2020-11-06T23:46:12Z [DEBUG] HeadTracker: finished callback 4 in 265.6µs services/head_tracker.go:520 blockNumber=21955398 callbackIdx=4 callbackType=*services.balanceMonitor id=head_tracker time=0.0002656
2020-11-06T23:46:12Z [DEBUG] eth.Client#BalanceAt(...) eth/client.go:301 account=0xdEE5bcB87dD8b429E0e7d86ed2C2b43aC1F9EFbf blockNumber=<nil>
2020-11-06T23:46:12Z [DEBUG] EthConfirmer: finished CheckForReceipts bulletprooftxmanager/eth_confirmer.go:92 headNum=21955398 id=eth_confirmer time=0.0060197
2020-11-06T23:46:12Z [DEBUG] EthConfirmer: finished BumpGasWhereNecessary bulletprooftxmanager/eth_confirmer.go:103 headNum=21955398 id=eth_confirmer time=0.01113
2020-11-06T23:46:12Z [DEBUG] EthConfirmer: finished EnsureConfirmedTransactionsInLongestChain bulletprooftxmanager/eth_confirmer.go:107 headNum=21955398 id=eth_confirmer time=0.009321
2020-11-06T23:46:12Z [DEBUG] HeadTracker: finished callback 1 in 34.4992ms services/head_tracker.go:520 blockNumber=21955398 callbackIdx=1 callbackType=*bulletprooftxmanager.ethConfirmer id=head_tracker time=0.0344992
2020-11-06T23:46:12Z [DEBUG] GasUpdater: skipping empty block: 21955395 services/gas_updater.go:121 blockNumber=21955395
2020-11-06T23:46:12Z [DEBUG] HeadTracker: finished callback 0 in 259.3468ms services/head_tracker.go:520 blockNumber=21955398 callbackIdx=0 callbackType=*services.gasUpdater id=head_tracker time=0.2593468
2020-11-06T23:46:12Z [DEBUG] HeadTracker finished processing head 21955398 in 272.7677ms services/head_tracker.go:330 blockNumber=21955398 id=head_tracker time=0.2727677
2020-11-06T23:46:19Z [DEBUG] DHT: Put value loghelper/logger_with_context.go:37 F=1 configDigest=017363cc8a250ae71b30107df7c0bdfd extendedLogging=true id=DHT key=/peerinfo/12D3KooWLFP41jHMfKXVyyFttspPXVhmVTQCrWrXqQDppMgHfdXm listenAddr=/ip4/0.0.0.0/tcp/6690 listenIP=0.0.0.0 listenPort=6690 peerID=12D3KooWLFP41jHMfKXVyyFttspPXVhmVTQCrWrXqQDppMgHfdXm protocolID=/cl_peer_discovery_dht/017363cc8a250ae71b30107df7c0bdfd/kad/1.0.0 value={"addrs":["/ip4/172.19.0.8/tcp/6690","/ip4/127.0.0.1/tcp/6690"],"pk":"CAESIJr9Y5O2Pl09P00uoOoiF9nfkYhi3ZrL1sL3z3zfugvY","sig":"otIBq2mRk6IQntKu7sOq++8RiwLd6PWYRzbE+SeeP1SzvUzvajnY3WvftuKiD1Is57HlktvGrv6qipRqOEb7AA==","timestamp":1604706379}
2020-11-06T23:46:19Z [ERROR] DHT: Error publishing address loghelper/logger_with_context.go:49 F=1 configDigest=017363cc8a250ae71b30107df7c0bdfd err=could publish address: failed to find any peer in table extendedLogging=true id=DHT listenAddr=/ip4/0.0.0.0/tcp/6690 listenIP=0.0.0.0 listenPort=6690 peerID=12D3KooWLFP41jHMfKXVyyFttspPXVhmVTQCrWrXqQDppMgHfdXm protocolID=/cl_peer_discovery_dht/017363cc8a250ae71b30107df7c0bdfd/kad/1.0.0 retryIn=10 stacktrace=github.com/smartcontractkit/libocr/offchainreporting/loghelper.LoggerWithContext.Error
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/offchainreporting/loghelper/logger_with_context.go:49
github.com/smartcontractkit/libocr/offchainreporting/loghelper.LoggerWithContext.Error
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/offchainreporting/loghelper/logger_with_context.go:49
github.com/smartcontractkit/libocr/offchainreporting/loghelper.LoggerWithContext.Error
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/offchainreporting/loghelper/logger_with_context.go:49
github.com/smartcontractkit/libocr/networking/dht-router.DHTRouter.startAnnounceInBackground.func1
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/networking/dht-router/router.go:267
github.com/smartcontractkit/libocr/subprocesses.(*Subprocesses).Go.func1
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/subprocesses/subprocesses.go:29
2020-11-06T23:46:21Z [DEBUG] Received new head #21955399 (0x14f0347) services/head_tracker.go:335 blockHash=0x44478ac720d88c2c8b334ab4f4e758ab585623696829cad3ade99186541821ac blockHeight=21955399
2020-11-06T23:46:21Z [DEBUG] HeadTracker initiating callbacks services/head_tracker.go:498 chainLength=50 headNum=21955399 numCallbacks=6
2020-11-06T23:46:21Z [DEBUG] HeadTracker: finished callback 5 in 8.2µs services/head_tracker.go:520 blockNumber=21955399 callbackIdx=5 callbackType=*chainlink.headTrackableCallback id=head_tracker time=0.0000082
2020-11-06T23:46:21Z [DEBUG] eth.Client#BlockByNumber(...) eth/client.go:270 number=21955396
2020-11-06T23:46:21Z [DEBUG] HeadTracker: finished callback 2 in 9.1µs services/head_tracker.go:520 blockNumber=21955399 callbackIdx=2 callbackType=*services.jobSubscriber id=head_tracker time=0.0000091
2020-11-06T23:46:21Z [DEBUG] Resuming all runs pending next block services/run_manager.go:247 currentBlockHeight=21955399
2020-11-06T23:46:21Z [DEBUG] HeadTracker: finished callback 3 in 8.2µs services/head_tracker.go:520 blockNumber=21955399 callbackIdx=3 callbackType=*chainlink.pendingConnectionResumer id=head_tracker time=0.0000082
2020-11-06T23:46:21Z [DEBUG] BalanceMonitor: signalling balance worker services/balance_monitor.go:68
2020-11-06T23:46:21Z [DEBUG] HeadTracker: finished callback 4 in 88.4µs services/head_tracker.go:520 blockNumber=21955399 callbackIdx=4 callbackType=*services.balanceMonitor id=head_tracker time=0.0000884
2020-11-06T23:46:21Z [DEBUG] eth.Client#BalanceAt(...) eth/client.go:301 account=0xdEE5bcB87dD8b429E0e7d86ed2C2b43aC1F9EFbf blockNumber=<nil>
2020-11-06T23:46:21Z [DEBUG] EthConfirmer: finished CheckForReceipts bulletprooftxmanager/eth_confirmer.go:92 headNum=21955399 id=eth_confirmer time=0.0012384
2020-11-06T23:46:21Z [DEBUG] EthConfirmer: finished BumpGasWhereNecessary bulletprooftxmanager/eth_confirmer.go:103 headNum=21955399 id=eth_confirmer time=0.0056958
2020-11-06T23:46:21Z [DEBUG] EthConfirmer: finished EnsureConfirmedTransactionsInLongestChain bulletprooftxmanager/eth_confirmer.go:107 headNum=21955399 id=eth_confirmer time=0.0061029
2020-11-06T23:46:21Z [DEBUG] HeadTracker: finished callback 1 in 19.3774ms services/head_tracker.go:520 blockNumber=21955399 callbackIdx=1 callbackType=*bulletprooftxmanager.ethConfirmer id=head_tracker time=0.0193774
2020-11-06T23:46:21Z [DEBUG] GasUpdater: skipping empty block: 21955396 services/gas_updater.go:121 blockNumber=21955396
2020-11-06T23:46:21Z [DEBUG] HeadTracker: finished callback 0 in 260.6371ms services/head_tracker.go:520 blockNumber=21955399 callbackIdx=0 callbackType=*services.gasUpdater id=head_tracker time=0.2606371
2020-11-06T23:46:21Z [DEBUG] HeadTracker finished processing head 21955399 in 271.8163ms services/head_tracker.go:330 blockNumber=21955399 id=head_tracker time=0.2718163
2020-11-06T23:46:24Z [DEBUG] Received new head #21955400 (0x14f0348) services/head_tracker.go:335 blockHash=0xb641e112e0a24593da14e82c912ccf3dbd65f98c9469336130327968c662fb5a blockHeight=21955400
2020-11-06T23:46:24Z [DEBUG] HeadTracker initiating callbacks services/head_tracker.go:498 chainLength=50 headNum=21955400 numCallbacks=6
2020-11-06T23:46:24Z [DEBUG] HeadTracker: finished callback 5 in 11.9µs services/head_tracker.go:520 blockNumber=21955400 callbackIdx=5 callbackType=*chainlink.headTrackableCallback id=head_tracker time=0.0000119
2020-11-06T23:46:24Z [DEBUG] HeadTracker: finished callback 2 in 28.3µs services/head_tracker.go:520 blockNumber=21955400 callbackIdx=2 callbackType=*services.jobSubscriber id=head_tracker time=0.0000283
2020-11-06T23:46:24Z [DEBUG] HeadTracker: finished callback 3 in 10.1µs services/head_tracker.go:520 blockNumber=21955400 callbackIdx=3 callbackType=*chainlink.pendingConnectionResumer id=head_tracker time=0.0000101
2020-11-06T23:46:24Z [DEBUG] BalanceMonitor: signalling balance worker services/balance_monitor.go:68
2020-11-06T23:46:24Z [DEBUG] Resuming all runs pending next block services/run_manager.go:247 currentBlockHeight=21955400
2020-11-06T23:46:24Z [DEBUG] HeadTracker: finished callback 4 in 550.3µs services/head_tracker.go:520 blockNumber=21955400 callbackIdx=4 callbackType=*services.balanceMonitor id=head_tracker time=0.0005503
2020-11-06T23:46:24Z [DEBUG] eth.Client#BlockByNumber(...) eth/client.go:270 number=21955397
2020-11-06T23:46:24Z [DEBUG] eth.Client#BalanceAt(...) eth/client.go:301 account=0xdEE5bcB87dD8b429E0e7d86ed2C2b43aC1F9EFbf blockNumber=<nil>
2020-11-06T23:46:24Z [DEBUG] EthConfirmer: finished CheckForReceipts bulletprooftxmanager/eth_confirmer.go:92 headNum=21955400 id=eth_confirmer time=0.0048494
2020-11-06T23:46:24Z [DEBUG] EthConfirmer: finished BumpGasWhereNecessary bulletprooftxmanager/eth_confirmer.go:103 headNum=21955400 id=eth_confirmer time=0.0130417
2020-11-06T23:46:24Z [DEBUG] EthConfirmer: finished EnsureConfirmedTransactionsInLongestChain bulletprooftxmanager/eth_confirmer.go:107 headNum=21955400 id=eth_confirmer time=0.005649
2020-11-06T23:46:24Z [DEBUG] HeadTracker: finished callback 1 in 34.8138ms services/head_tracker.go:520 blockNumber=21955400 callbackIdx=1 callbackType=*bulletprooftxmanager.ethConfirmer id=head_tracker time=0.0348138
2020-11-06T23:46:25Z [DEBUG] GasUpdater: waiting for blocks: 13/24 services/gas_updater.go:118 inHistory=13 required=24
2020-11-06T23:46:25Z [DEBUG] HeadTracker: finished callback 0 in 263.7495ms services/head_tracker.go:520 blockNumber=21955400 callbackIdx=0 callbackType=*services.gasUpdater id=head_tracker time=0.2637495
2020-11-06T23:46:25Z [DEBUG] HeadTracker finished processing head 21955400 in 287.5124ms services/head_tracker.go:330 blockNumber=21955400 id=head_tracker time=0.2875124
2020-11-06T23:46:28Z [DEBUG] Received new head #21955401 (0x14f0349) services/head_tracker.go:335 blockHash=0xf0a57931a1deecbc6e6eca8665b690742cdb932588fb4251d65cd0a4836f4f43 blockHeight=21955401
2020-11-06T23:46:28Z [DEBUG] HeadTracker initiating callbacks services/head_tracker.go:498 chainLength=50 headNum=21955401 numCallbacks=6
2020-11-06T23:46:28Z [DEBUG] HeadTracker: finished callback 5 in 9.5µs services/head_tracker.go:520 blockNumber=21955401 callbackIdx=5 callbackType=*chainlink.headTrackableCallback id=head_tracker time=0.0000095
2020-11-06T23:46:28Z [DEBUG] HeadTracker: finished callback 2 in 10.3µs services/head_tracker.go:520 blockNumber=21955401 callbackIdx=2 callbackType=*services.jobSubscriber id=head_tracker time=0.0000103
2020-11-06T23:46:28Z [DEBUG] HeadTracker: finished callback 3 in 7.6µs services/head_tracker.go:520 blockNumber=21955401 callbackIdx=3 callbackType=*chainlink.pendingConnectionResumer id=head_tracker time=0.0000076
2020-11-06T23:46:28Z [DEBUG] BalanceMonitor: signalling balance worker services/balance_monitor.go:68
2020-11-06T23:46:28Z [DEBUG] HeadTracker: finished callback 4 in 65.9µs services/head_tracker.go:520 blockNumber=21955401 callbackIdx=4 callbackType=*services.balanceMonitor id=head_tracker time=0.0000659
2020-11-06T23:46:28Z [DEBUG] eth.Client#BlockByNumber(...) eth/client.go:270 number=21955398
2020-11-06T23:46:28Z [DEBUG] Resuming all runs pending next block services/run_manager.go:247 currentBlockHeight=21955401
2020-11-06T23:46:28Z [DEBUG] eth.Client#BalanceAt(...) eth/client.go:301 account=0xdEE5bcB87dD8b429E0e7d86ed2C2b43aC1F9EFbf blockNumber=<nil>
2020-11-06T23:46:28Z [DEBUG] EthConfirmer: finished CheckForReceipts bulletprooftxmanager/eth_confirmer.go:92 headNum=21955401 id=eth_confirmer time=0.0008422
2020-11-06T23:46:28Z [DEBUG] EthConfirmer: finished BumpGasWhereNecessary bulletprooftxmanager/eth_confirmer.go:103 headNum=21955401 id=eth_confirmer time=0.0040291
2020-11-06T23:46:28Z [DEBUG] EthConfirmer: finished EnsureConfirmedTransactionsInLongestChain bulletprooftxmanager/eth_confirmer.go:107 headNum=21955401 id=eth_confirmer time=0.0057073
2020-11-06T23:46:28Z [DEBUG] HeadTracker: finished callback 1 in 23.6963ms services/head_tracker.go:520 blockNumber=21955401 callbackIdx=1 callbackType=*bulletprooftxmanager.ethConfirmer id=head_tracker time=0.0236963
2020-11-06T23:46:28Z [DEBUG] GasUpdater: skipping empty block: 21955398 services/gas_updater.go:121 blockNumber=21955398
2020-11-06T23:46:28Z [DEBUG] HeadTracker: finished callback 0 in 253.5512ms services/head_tracker.go:520 blockNumber=21955401 callbackIdx=0 callbackType=*services.gasUpdater id=head_tracker time=0.2535512
2020-11-06T23:46:28Z [DEBUG] HeadTracker finished processing head 21955401 in 266.8104ms services/head_tracker.go:330 blockNumber=21955401 id=head_tracker time=0.2668104
2020-11-06T23:46:29Z [DEBUG] DHT: Put value loghelper/logger_with_context.go:37 F=1 configDigest=017363cc8a250ae71b30107df7c0bdfd extendedLogging=true id=DHT key=/peerinfo/12D3KooWLFP41jHMfKXVyyFttspPXVhmVTQCrWrXqQDppMgHfdXm listenAddr=/ip4/0.0.0.0/tcp/6690 listenIP=0.0.0.0 listenPort=6690 peerID=12D3KooWLFP41jHMfKXVyyFttspPXVhmVTQCrWrXqQDppMgHfdXm protocolID=/cl_peer_discovery_dht/017363cc8a250ae71b30107df7c0bdfd/kad/1.0.0 value={"addrs":["/ip4/172.19.0.8/tcp/6690","/ip4/127.0.0.1/tcp/6690"],"pk":"CAESIJr9Y5O2Pl09P00uoOoiF9nfkYhi3ZrL1sL3z3zfugvY","sig":"7xPBZlBlBrPgV7WEkn74pw6GwzE4TCnEAb2NNSseacZqq2VctmydG2TCtjzP2yyu+YEqsikvk5O65I2xwJpUCw==","timestamp":1604706389}
2020-11-06T23:46:29Z [ERROR] DHT: Error publishing address loghelper/logger_with_context.go:49 F=1 configDigest=017363cc8a250ae71b30107df7c0bdfd err=could publish address: failed to find any peer in table extendedLogging=true id=DHT listenAddr=/ip4/0.0.0.0/tcp/6690 listenIP=0.0.0.0 listenPort=6690 peerID=12D3KooWLFP41jHMfKXVyyFttspPXVhmVTQCrWrXqQDppMgHfdXm protocolID=/cl_peer_discovery_dht/017363cc8a250ae71b30107df7c0bdfd/kad/1.0.0 retryIn=10 stacktrace=github.com/smartcontractkit/libocr/offchainreporting/loghelper.LoggerWithContext.Error
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/offchainreporting/loghelper/logger_with_context.go:49
github.com/smartcontractkit/libocr/offchainreporting/loghelper.LoggerWithContext.Error
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/offchainreporting/loghelper/logger_with_context.go:49
github.com/smartcontractkit/libocr/offchainreporting/loghelper.LoggerWithContext.Error
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/offchainreporting/loghelper/logger_with_context.go:49
github.com/smartcontractkit/libocr/networking/dht-router.DHTRouter.startAnnounceInBackground.func1
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/networking/dht-router/router.go:267
github.com/smartcontractkit/libocr/subprocesses.(*Subprocesses).Go.func1
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/subprocesses/subprocesses.go:29
2020-11-06T23:46:32Z [DEBUG] Received new head #21955402 (0x14f034a) services/head_tracker.go:335 blockHash=0xa9abcc4eb86f29cac49f9119456428d6f9fee181f5e81f59c9e4da573926208a blockHeight=21955402
2020-11-06T23:46:33Z [DEBUG] HeadTracker initiating callbacks services/head_tracker.go:498 chainLength=50 headNum=21955402 numCallbacks=6
2020-11-06T23:46:33Z [DEBUG] HeadTracker: finished callback 5 in 11.2µs services/head_tracker.go:520 blockNumber=21955402 callbackIdx=5 callbackType=*chainlink.headTrackableCallback id=head_tracker time=0.0000112
2020-11-06T23:46:33Z [DEBUG] HeadTracker: finished callback 3 in 11.7µs services/head_tracker.go:520 blockNumber=21955402 callbackIdx=3 callbackType=*chainlink.pendingConnectionResumer id=head_tracker time=0.0000117
2020-11-06T23:46:33Z [DEBUG] HeadTracker: finished callback 2 in 17.4µs services/head_tracker.go:520 blockNumber=21955402 callbackIdx=2 callbackType=*services.jobSubscriber id=head_tracker time=0.0000174
2020-11-06T23:46:33Z [DEBUG] Resuming all runs pending next block services/run_manager.go:247 currentBlockHeight=21955402
2020-11-06T23:46:33Z [DEBUG] eth.Client#BlockByNumber(...) eth/client.go:270 number=21955399
2020-11-06T23:46:33Z [DEBUG] BalanceMonitor: signalling balance worker services/balance_monitor.go:68
2020-11-06T23:46:33Z [DEBUG] HeadTracker: finished callback 4 in 1.4225ms services/head_tracker.go:520 blockNumber=21955402 callbackIdx=4 callbackType=*services.balanceMonitor id=head_tracker time=0.0014225
2020-11-06T23:46:33Z [DEBUG] eth.Client#BalanceAt(...) eth/client.go:301 account=0xdEE5bcB87dD8b429E0e7d86ed2C2b43aC1F9EFbf blockNumber=<nil>
2020-11-06T23:46:33Z [DEBUG] EthConfirmer: finished CheckForReceipts bulletprooftxmanager/eth_confirmer.go:92 headNum=21955402 id=eth_confirmer time=0.0024478
2020-11-06T23:46:33Z [DEBUG] EthConfirmer: finished BumpGasWhereNecessary bulletprooftxmanager/eth_confirmer.go:103 headNum=21955402 id=eth_confirmer time=0.0090441
2020-11-06T23:46:33Z [DEBUG] EthConfirmer: finished EnsureConfirmedTransactionsInLongestChain bulletprooftxmanager/eth_confirmer.go:107 headNum=21955402 id=eth_confirmer time=0.0058264
2020-11-06T23:46:33Z [DEBUG] HeadTracker: finished callback 1 in 49.8056ms services/head_tracker.go:520 blockNumber=21955402 callbackIdx=1 callbackType=*bulletprooftxmanager.ethConfirmer id=head_tracker time=0.0498056
2020-11-06T23:46:33Z [DEBUG] GasUpdater: waiting for blocks: 14/24 services/gas_updater.go:118 inHistory=14 required=24
2020-11-06T23:46:33Z [DEBUG] HeadTracker: finished callback 0 in 270.2698ms services/head_tracker.go:520 blockNumber=21955402 callbackIdx=0 callbackType=*services.gasUpdater id=head_tracker time=0.2702698
2020-11-06T23:46:33Z [DEBUG] HeadTracker finished processing head 21955402 in 289.0769ms services/head_tracker.go:330 blockNumber=21955402 id=head_tracker time=0.2890769
2020-11-06T23:46:39Z [DEBUG] DHT: Put value loghelper/logger_with_context.go:37 F=1 configDigest=017363cc8a250ae71b30107df7c0bdfd extendedLogging=true id=DHT key=/peerinfo/12D3KooWLFP41jHMfKXVyyFttspPXVhmVTQCrWrXqQDppMgHfdXm listenAddr=/ip4/0.0.0.0/tcp/6690 listenIP=0.0.0.0 listenPort=6690 peerID=12D3KooWLFP41jHMfKXVyyFttspPXVhmVTQCrWrXqQDppMgHfdXm protocolID=/cl_peer_discovery_dht/017363cc8a250ae71b30107df7c0bdfd/kad/1.0.0 value={"addrs":["/ip4/172.19.0.8/tcp/6690","/ip4/127.0.0.1/tcp/6690"],"pk":"CAESIJr9Y5O2Pl09P00uoOoiF9nfkYhi3ZrL1sL3z3zfugvY","sig":"WeWRYTTZ2uzg9Bl8afm6HAe0o8EjXKOb/LH/Ke/eClMJ+SzvgTiqegnR7B7cRHZlr4zRomRu8QqQd0k8j7olAA==","timestamp":1604706399}
2020-11-06T23:46:39Z [ERROR] DHT: Error publishing address loghelper/logger_with_context.go:49 F=1 configDigest=017363cc8a250ae71b30107df7c0bdfd err=could publish address: failed to find any peer in table extendedLogging=true id=DHT listenAddr=/ip4/0.0.0.0/tcp/6690 listenIP=0.0.0.0 listenPort=6690 peerID=12D3KooWLFP41jHMfKXVyyFttspPXVhmVTQCrWrXqQDppMgHfdXm protocolID=/cl_peer_discovery_dht/017363cc8a250ae71b30107df7c0bdfd/kad/1.0.0 retryIn=10 stacktrace=github.com/smartcontractkit/libocr/offchainreporting/loghelper.LoggerWithContext.Error
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/offchainreporting/loghelper/logger_with_context.go:49
github.com/smartcontractkit/libocr/offchainreporting/loghelper.LoggerWithContext.Error
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/offchainreporting/loghelper/logger_with_context.go:49
github.com/smartcontractkit/libocr/offchainreporting/loghelper.LoggerWithContext.Error
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/offchainreporting/loghelper/logger_with_context.go:49
github.com/smartcontractkit/libocr/networking/dht-router.DHTRouter.startAnnounceInBackground.func1
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/networking/dht-router/router.go:267
github.com/smartcontractkit/libocr/subprocesses.(*Subprocesses).Go.func1
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/subprocesses/subprocesses.go:29
2020-11-06T23:46:41Z [DEBUG] Received new head #21955403 (0x14f034b) services/head_tracker.go:335 blockHash=0xe769dae11b9a03e90553c0c407e86f9641d40095a5a98f083b630de69f91a534 blockHeight=21955403
2020-11-06T23:46:41Z [DEBUG] HeadTracker initiating callbacks services/head_tracker.go:498 chainLength=50 headNum=21955403 numCallbacks=6
2020-11-06T23:46:41Z [DEBUG] HeadTracker: finished callback 2 in 39.4µs services/head_tracker.go:520 blockNumber=21955403 callbackIdx=2 callbackType=*services.jobSubscriber id=head_tracker time=0.0000394
2020-11-06T23:46:41Z [DEBUG] HeadTracker: finished callback 3 in 10.2µs services/head_tracker.go:520 blockNumber=21955403 callbackIdx=3 callbackType=*chainlink.pendingConnectionResumer id=head_tracker time=0.0000102
2020-11-06T23:46:41Z [DEBUG] BalanceMonitor: signalling balance worker services/balance_monitor.go:68
2020-11-06T23:46:41Z [DEBUG] HeadTracker: finished callback 4 in 1.5187ms services/head_tracker.go:520 blockNumber=21955403 callbackIdx=4 callbackType=*services.balanceMonitor id=head_tracker time=0.0015187
2020-11-06T23:46:41Z [DEBUG] eth.Client#BlockByNumber(...) eth/client.go:270 number=21955400
2020-11-06T23:46:41Z [DEBUG] Resuming all runs pending next block services/run_manager.go:247 currentBlockHeight=21955403
2020-11-06T23:46:41Z [DEBUG] HeadTracker: finished callback 5 in 1.1765ms services/head_tracker.go:520 blockNumber=21955403 callbackIdx=5 callbackType=*chainlink.headTrackableCallback id=head_tracker time=0.0011765
2020-11-06T23:46:41Z [DEBUG] eth.Client#BalanceAt(...) eth/client.go:301 account=0xdEE5bcB87dD8b429E0e7d86ed2C2b43aC1F9EFbf blockNumber=<nil>
2020-11-06T23:46:41Z [DEBUG] EthConfirmer: finished CheckForReceipts bulletprooftxmanager/eth_confirmer.go:92 headNum=21955403 id=eth_confirmer time=0.0013052
2020-11-06T23:46:41Z [DEBUG] EthConfirmer: finished BumpGasWhereNecessary bulletprooftxmanager/eth_confirmer.go:103 headNum=21955403 id=eth_confirmer time=0.0095848
2020-11-06T23:46:41Z [DEBUG] EthConfirmer: finished EnsureConfirmedTransactionsInLongestChain bulletprooftxmanager/eth_confirmer.go:107 headNum=21955403 id=eth_confirmer time=0.0112024
2020-11-06T23:46:41Z [DEBUG] HeadTracker: finished callback 1 in 56.1595ms services/head_tracker.go:520 blockNumber=21955403 callbackIdx=1 callbackType=*bulletprooftxmanager.ethConfirmer id=head_tracker time=0.0561595
2020-11-06T23:46:41Z [DEBUG] GasUpdater: skipping empty block: 21955400 services/gas_updater.go:121 blockNumber=21955400
2020-11-06T23:46:41Z [DEBUG] HeadTracker: finished callback 0 in 335.8652ms services/head_tracker.go:520 blockNumber=21955403 callbackIdx=0 callbackType=*services.gasUpdater id=head_tracker time=0.3358652
2020-11-06T23:46:41Z [DEBUG] HeadTracker finished processing head 21955403 in 355.2061ms services/head_tracker.go:330 blockNumber=21955403 id=head_tracker time=0.3552061
2020-11-06T23:46:44Z [DEBUG] Received new head #21955404 (0x14f034c) services/head_tracker.go:335 blockHash=0xa7db11063045ede9e2a67cf600e7cca1fbe67210dbc3105b8473d4cc0d8ad360 blockHeight=21955404
2020-11-06T23:46:45Z [DEBUG] HeadTracker initiating callbacks services/head_tracker.go:498 chainLength=50 headNum=21955404 numCallbacks=6
2020-11-06T23:46:45Z [DEBUG] HeadTracker: finished callback 5 in 10.5µs services/head_tracker.go:520 blockNumber=21955404 callbackIdx=5 callbackType=*chainlink.headTrackableCallback id=head_tracker time=0.0000105
2020-11-06T23:46:45Z [DEBUG] HeadTracker: finished callback 2 in 23.7µs services/head_tracker.go:520 blockNumber=21955404 callbackIdx=2 callbackType=*services.jobSubscriber id=head_tracker time=0.0000237
2020-11-06T23:46:45Z [DEBUG] HeadTracker: finished callback 3 in 85.9µs services/head_tracker.go:520 blockNumber=21955404 callbackIdx=3 callbackType=*chainlink.pendingConnectionResumer id=head_tracker time=0.0000859
2020-11-06T23:46:45Z [DEBUG] BalanceMonitor: signalling balance worker services/balance_monitor.go:68
2020-11-06T23:46:45Z [DEBUG] HeadTracker: finished callback 4 in 162µs services/head_tracker.go:520 blockNumber=21955404 callbackIdx=4 callbackType=*services.balanceMonitor id=head_tracker time=0.000162
2020-11-06T23:46:45Z [DEBUG] eth.Client#BlockByNumber(...) eth/client.go:270 number=21955401
2020-11-06T23:46:45Z [DEBUG] Resuming all runs pending next block services/run_manager.go:247 currentBlockHeight=21955404
2020-11-06T23:46:45Z [DEBUG] eth.Client#BalanceAt(...) eth/client.go:301 account=0xdEE5bcB87dD8b429E0e7d86ed2C2b43aC1F9EFbf blockNumber=<nil>
2020-11-06T23:46:45Z [DEBUG] EthConfirmer: finished CheckForReceipts bulletprooftxmanager/eth_confirmer.go:92 headNum=21955404 id=eth_confirmer time=0.0016301
2020-11-06T23:46:45Z [DEBUG] EthConfirmer: finished BumpGasWhereNecessary bulletprooftxmanager/eth_confirmer.go:103 headNum=21955404 id=eth_confirmer time=0.0104131
2020-11-06T23:46:45Z [DEBUG] EthConfirmer: finished EnsureConfirmedTransactionsInLongestChain bulletprooftxmanager/eth_confirmer.go:107 headNum=21955404 id=eth_confirmer time=0.0069903
2020-11-06T23:46:45Z [DEBUG] HeadTracker: finished callback 1 in 25.6718ms services/head_tracker.go:520 blockNumber=21955404 callbackIdx=1 callbackType=*bulletprooftxmanager.ethConfirmer id=head_tracker time=0.0256718
2020-11-06T23:46:45Z [DEBUG] GasUpdater: waiting for blocks: 15/24 services/gas_updater.go:118 inHistory=15 required=24
2020-11-06T23:46:45Z [DEBUG] HeadTracker: finished callback 0 in 258.2567ms services/head_tracker.go:520 blockNumber=21955404 callbackIdx=0 callbackType=*services.gasUpdater id=head_tracker time=0.2582567
2020-11-06T23:46:45Z [DEBUG] HeadTracker finished processing head 21955404 in 289.9806ms services/head_tracker.go:330 blockNumber=21955404 id=head_tracker time=0.2899806
2020-11-06T23:46:48Z [DEBUG] Received new head #21955405 (0x14f034d) services/head_tracker.go:335 blockHash=0xd06d7bdd908b597e5ebd36b4c7f0fb3ca84ea586fa9930890f6e691ff29b6c9d blockHeight=21955405
2020-11-06T23:46:48Z [DEBUG] HeadTracker initiating callbacks services/head_tracker.go:498 chainLength=50 headNum=21955405 numCallbacks=6
2020-11-06T23:46:48Z [DEBUG] HeadTracker: finished callback 3 in 10.9µs services/head_tracker.go:520 blockNumber=21955405 callbackIdx=3 callbackType=*chainlink.pendingConnectionResumer id=head_tracker time=0.0000109
2020-11-06T23:46:48Z [DEBUG] Resuming all runs pending next block services/run_manager.go:247 currentBlockHeight=21955405
2020-11-06T23:46:48Z [DEBUG] HeadTracker: finished callback 2 in 19.9µs services/head_tracker.go:520 blockNumber=21955405 callbackIdx=2 callbackType=*services.jobSubscriber id=head_tracker time=0.0000199
2020-11-06T23:46:48Z [DEBUG] BalanceMonitor: signalling balance worker services/balance_monitor.go:68
2020-11-06T23:46:48Z [DEBUG] eth.Client#BlockByNumber(...) eth/client.go:270 number=21955402
2020-11-06T23:46:48Z [DEBUG] HeadTracker: finished callback 4 in 295µs services/head_tracker.go:520 blockNumber=21955405 callbackIdx=4 callbackType=*services.balanceMonitor id=head_tracker time=0.000295
2020-11-06T23:46:48Z [DEBUG] HeadTracker: finished callback 5 in 66.9µs services/head_tracker.go:520 blockNumber=21955405 callbackIdx=5 callbackType=*chainlink.headTrackableCallback id=head_tracker time=0.0000669
2020-11-06T23:46:48Z [DEBUG] eth.Client#BalanceAt(...) eth/client.go:301 account=0xdEE5bcB87dD8b429E0e7d86ed2C2b43aC1F9EFbf blockNumber=<nil>
2020-11-06T23:46:48Z [DEBUG] EthConfirmer: finished CheckForReceipts bulletprooftxmanager/eth_confirmer.go:92 headNum=21955405 id=eth_confirmer time=0.0012315
2020-11-06T23:46:48Z [DEBUG] EthConfirmer: finished BumpGasWhereNecessary bulletprooftxmanager/eth_confirmer.go:103 headNum=21955405 id=eth_confirmer time=0.0070062
2020-11-06T23:46:48Z [DEBUG] EthConfirmer: finished EnsureConfirmedTransactionsInLongestChain bulletprooftxmanager/eth_confirmer.go:107 headNum=21955405 id=eth_confirmer time=0.0069675
2020-11-06T23:46:48Z [DEBUG] HeadTracker: finished callback 1 in 31.4832ms services/head_tracker.go:520 blockNumber=21955405 callbackIdx=1 callbackType=*bulletprooftxmanager.ethConfirmer id=head_tracker time=0.0314832
2020-11-06T23:46:49Z [DEBUG] GasUpdater: skipping empty block: 21955402 services/gas_updater.go:121 blockNumber=21955402
2020-11-06T23:46:49Z [DEBUG] HeadTracker: finished callback 0 in 293.4634ms services/head_tracker.go:520 blockNumber=21955405 callbackIdx=0 callbackType=*services.gasUpdater id=head_tracker time=0.2934634
2020-11-06T23:46:49Z [DEBUG] HeadTracker finished processing head 21955405 in 308.9989ms services/head_tracker.go:330 blockNumber=21955405 id=head_tracker time=0.3089989
2020-11-06T23:46:49Z [DEBUG] DHT: Put value loghelper/logger_with_context.go:37 F=1 configDigest=017363cc8a250ae71b30107df7c0bdfd extendedLogging=true id=DHT key=/peerinfo/12D3KooWLFP41jHMfKXVyyFttspPXVhmVTQCrWrXqQDppMgHfdXm listenAddr=/ip4/0.0.0.0/tcp/6690 listenIP=0.0.0.0 listenPort=6690 peerID=12D3KooWLFP41jHMfKXVyyFttspPXVhmVTQCrWrXqQDppMgHfdXm protocolID=/cl_peer_discovery_dht/017363cc8a250ae71b30107df7c0bdfd/kad/1.0.0 value={"addrs":["/ip4/172.19.0.8/tcp/6690","/ip4/127.0.0.1/tcp/6690"],"pk":"CAESIJr9Y5O2Pl09P00uoOoiF9nfkYhi3ZrL1sL3z3zfugvY","sig":"QhYjacPC8rmlEMTtJlRTtlJTRBhAFyDhL9fyCKPddlqojsrzlJi17VgAcOCDYKWrZ/ZTa1wwfXx+cBD5y+rlBw==","timestamp":1604706409}
2020-11-06T23:46:49Z [ERROR] DHT: Error publishing address loghelper/logger_with_context.go:49 F=1 configDigest=017363cc8a250ae71b30107df7c0bdfd err=could publish address: failed to find any peer in table extendedLogging=true id=DHT listenAddr=/ip4/0.0.0.0/tcp/6690 listenIP=0.0.0.0 listenPort=6690 peerID=12D3KooWLFP41jHMfKXVyyFttspPXVhmVTQCrWrXqQDppMgHfdXm protocolID=/cl_peer_discovery_dht/017363cc8a250ae71b30107df7c0bdfd/kad/1.0.0 retryIn=10 stacktrace=github.com/smartcontractkit/libocr/offchainreporting/loghelper.LoggerWithContext.Error
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/offchainreporting/loghelper/logger_with_context.go:49
github.com/smartcontractkit/libocr/offchainreporting/loghelper.LoggerWithContext.Error
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/offchainreporting/loghelper/logger_with_context.go:49
github.com/smartcontractkit/libocr/offchainreporting/loghelper.LoggerWithContext.Error
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/offchainreporting/loghelper/logger_with_context.go:49
github.com/smartcontractkit/libocr/networking/dht-router.DHTRouter.startAnnounceInBackground.func1
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/networking/dht-router/router.go:267
github.com/smartcontractkit/libocr/subprocesses.(*Subprocesses).Go.func1
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/subprocesses/subprocesses.go:29
2020-11-06T23:46:52Z [DEBUG] Received new head #21955406 (0x14f034e) services/head_tracker.go:335 blockHash=0x16ee008050a3bd4f4bf858fc002caf238440ced1bb6c9b06ec9283252cc90492 blockHeight=21955406
2020-11-06T23:46:52Z [DEBUG] HeadTracker initiating callbacks services/head_tracker.go:498 chainLength=50 headNum=21955406 numCallbacks=6
2020-11-06T23:46:52Z [DEBUG] HeadTracker: finished callback 5 in 60.8µs services/head_tracker.go:520 blockNumber=21955406 callbackIdx=5 callbackType=*chainlink.headTrackableCallback id=head_tracker time=0.0000608
2020-11-06T23:46:52Z [DEBUG] HeadTracker: finished callback 3 in 8.6µs services/head_tracker.go:520 blockNumber=21955406 callbackIdx=3 callbackType=*chainlink.pendingConnectionResumer id=head_tracker time=0.0000086
2020-11-06T23:46:52Z [DEBUG] BalanceMonitor: signalling balance worker services/balance_monitor.go:68
2020-11-06T23:46:52Z [DEBUG] eth.Client#BlockByNumber(...) eth/client.go:270 number=21955403
2020-11-06T23:46:52Z [DEBUG] Resuming all runs pending next block services/run_manager.go:247 currentBlockHeight=21955406
2020-11-06T23:46:52Z [DEBUG] HeadTracker: finished callback 4 in 394.6µs services/head_tracker.go:520 blockNumber=21955406 callbackIdx=4 callbackType=*services.balanceMonitor id=head_tracker time=0.0003946
2020-11-06T23:46:52Z [DEBUG] HeadTracker: finished callback 2 in 6.8µs services/head_tracker.go:520 blockNumber=21955406 callbackIdx=2 callbackType=*services.jobSubscriber id=head_tracker time=0.0000068
2020-11-06T23:46:52Z [DEBUG] eth.Client#BalanceAt(...) eth/client.go:301 account=0xdEE5bcB87dD8b429E0e7d86ed2C2b43aC1F9EFbf blockNumber=<nil>
2020-11-06T23:46:52Z [DEBUG] EthConfirmer: finished CheckForReceipts bulletprooftxmanager/eth_confirmer.go:92 headNum=21955406 id=eth_confirmer time=0.0007172
2020-11-06T23:46:52Z [DEBUG] EthConfirmer: finished BumpGasWhereNecessary bulletprooftxmanager/eth_confirmer.go:103 headNum=21955406 id=eth_confirmer time=0.003972
2020-11-06T23:46:52Z [DEBUG] EthConfirmer: finished EnsureConfirmedTransactionsInLongestChain bulletprooftxmanager/eth_confirmer.go:107 headNum=21955406 id=eth_confirmer time=0.0043785
2020-11-06T23:46:52Z [DEBUG] HeadTracker: finished callback 1 in 19.738ms services/head_tracker.go:520 blockNumber=21955406 callbackIdx=1 callbackType=*bulletprooftxmanager.ethConfirmer id=head_tracker time=0.019738
2020-11-06T23:46:52Z [DEBUG] GasUpdater: waiting for blocks: 16/24 services/gas_updater.go:118 inHistory=16 required=24
2020-11-06T23:46:52Z [DEBUG] HeadTracker: finished callback 0 in 252.412ms services/head_tracker.go:520 blockNumber=21955406 callbackIdx=0 callbackType=*services.gasUpdater id=head_tracker time=0.252412
2020-11-06T23:46:52Z [DEBUG] HeadTracker finished processing head 21955406 in 263.1677ms services/head_tracker.go:330 blockNumber=21955406 id=head_tracker time=0.2631677
2020-11-06T23:46:59Z [DEBUG] DHT: Put value loghelper/logger_with_context.go:37 F=1 configDigest=017363cc8a250ae71b30107df7c0bdfd extendedLogging=true id=DHT key=/peerinfo/12D3KooWLFP41jHMfKXVyyFttspPXVhmVTQCrWrXqQDppMgHfdXm listenAddr=/ip4/0.0.0.0/tcp/6690 listenIP=0.0.0.0 listenPort=6690 peerID=12D3KooWLFP41jHMfKXVyyFttspPXVhmVTQCrWrXqQDppMgHfdXm protocolID=/cl_peer_discovery_dht/017363cc8a250ae71b30107df7c0bdfd/kad/1.0.0 value={"addrs":["/ip4/172.19.0.8/tcp/6690","/ip4/127.0.0.1/tcp/6690"],"pk":"CAESIJr9Y5O2Pl09P00uoOoiF9nfkYhi3ZrL1sL3z3zfugvY","sig":"HIEIpP3neZXWW0Vq/ZauLyeV5/X/J2tIcOeYUnqytC5nZVzEKymaWOps5VDrUbJRcEsitdy/igDXDWmXt0CTDw==","timestamp":1604706419}
2020-11-06T23:46:59Z [ERROR] DHT: Error publishing address loghelper/logger_with_context.go:49 F=1 configDigest=017363cc8a250ae71b30107df7c0bdfd err=could publish address: failed to find any peer in table extendedLogging=true id=DHT listenAddr=/ip4/0.0.0.0/tcp/6690 listenIP=0.0.0.0 listenPort=6690 peerID=12D3KooWLFP41jHMfKXVyyFttspPXVhmVTQCrWrXqQDppMgHfdXm protocolID=/cl_peer_discovery_dht/017363cc8a250ae71b30107df7c0bdfd/kad/1.0.0 retryIn=10 stacktrace=github.com/smartcontractkit/libocr/offchainreporting/loghelper.LoggerWithContext.Error
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/offchainreporting/loghelper/logger_with_context.go:49
github.com/smartcontractkit/libocr/offchainreporting/loghelper.LoggerWithContext.Error
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/offchainreporting/loghelper/logger_with_context.go:49
github.com/smartcontractkit/libocr/offchainreporting/loghelper.LoggerWithContext.Error
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/offchainreporting/loghelper/logger_with_context.go:49
github.com/smartcontractkit/libocr/networking/dht-router.DHTRouter.startAnnounceInBackground.func1
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/networking/dht-router/router.go:267
github.com/smartcontractkit/libocr/subprocesses.(*Subprocesses).Go.func1
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/subprocesses/subprocesses.go:29
2020-11-06T23:47:00Z [DEBUG] eth.Client#HeaderByNumber(...) eth/client.go:277 number=<nil>
2020-11-06T23:47:01Z [DEBUG] Received new head #21955407 (0x14f034f) services/head_tracker.go:335 blockHash=0x6319503fcc95efd824c20f68980dfcc78c3fcbdefe2eba424d723018b320bf2f blockHeight=21955407
2020-11-06T23:47:01Z [DEBUG] HeadTracker initiating callbacks services/head_tracker.go:498 chainLength=50 headNum=21955407 numCallbacks=6
2020-11-06T23:47:01Z [DEBUG] HeadTracker: finished callback 5 in 8.7µs services/head_tracker.go:520 blockNumber=21955407 callbackIdx=5 callbackType=*chainlink.headTrackableCallback id=head_tracker time=0.0000087
2020-11-06T23:47:01Z [DEBUG] HeadTracker: finished callback 3 in 7.4µs services/head_tracker.go:520 blockNumber=21955407 callbackIdx=3 callbackType=*chainlink.pendingConnectionResumer id=head_tracker time=0.0000074
2020-11-06T23:47:01Z [DEBUG] Resuming all runs pending next block services/run_manager.go:247 currentBlockHeight=21955407
2020-11-06T23:47:01Z [DEBUG] HeadTracker: finished callback 2 in 10.8µs services/head_tracker.go:520 blockNumber=21955407 callbackIdx=2 callbackType=*services.jobSubscriber id=head_tracker time=0.0000108
2020-11-06T23:47:01Z [DEBUG] BalanceMonitor: signalling balance worker services/balance_monitor.go:68
2020-11-06T23:47:01Z [DEBUG] HeadTracker: finished callback 4 in 531.5µs services/head_tracker.go:520 blockNumber=21955407 callbackIdx=4 callbackType=*services.balanceMonitor id=head_tracker time=0.0005315
2020-11-06T23:47:01Z [DEBUG] eth.Client#BlockByNumber(...) eth/client.go:270 number=21955404
2020-11-06T23:47:01Z [DEBUG] EthConfirmer: finished CheckForReceipts bulletprooftxmanager/eth_confirmer.go:92 headNum=21955407 id=eth_confirmer time=0.0013637
2020-11-06T23:47:01Z [DEBUG] EthConfirmer: finished BumpGasWhereNecessary bulletprooftxmanager/eth_confirmer.go:103 headNum=21955407 id=eth_confirmer time=0.0058669
2020-11-06T23:47:01Z [DEBUG] eth.Client#BalanceAt(...) eth/client.go:301 account=0xdEE5bcB87dD8b429E0e7d86ed2C2b43aC1F9EFbf blockNumber=<nil>
2020-11-06T23:47:01Z [DEBUG] EthConfirmer: finished EnsureConfirmedTransactionsInLongestChain bulletprooftxmanager/eth_confirmer.go:107 headNum=21955407 id=eth_confirmer time=0.0038875
2020-11-06T23:47:01Z [DEBUG] HeadTracker: finished callback 1 in 15.3726ms services/head_tracker.go:520 blockNumber=21955407 callbackIdx=1 callbackType=*bulletprooftxmanager.ethConfirmer id=head_tracker time=0.0153726
2020-11-06T23:47:01Z [DEBUG] GasUpdater: skipping empty block: 21955404 services/gas_updater.go:121 blockNumber=21955404
2020-11-06T23:47:01Z [DEBUG] HeadTracker: finished callback 0 in 417.133ms services/head_tracker.go:520 blockNumber=21955407 callbackIdx=0 callbackType=*services.gasUpdater id=head_tracker time=0.417133
2020-11-06T23:47:01Z [DEBUG] HeadTracker finished processing head 21955407 in 427.8312ms services/head_tracker.go:330 blockNumber=21955407 id=head_tracker time=0.4278312
2020-11-06T23:47:01Z [DEBUG] TrackConfig: checking latestConfigDetails managed/track_config.go:48
2020-11-06T23:47:04Z [DEBUG] Received new head #21955408 (0x14f0350) services/head_tracker.go:335 blockHash=0x6385f71e47e7134f89db49b6d2118d7a0c8fc29c20c19bf802ba025c4d4ee45f blockHeight=21955408
2020-11-06T23:47:04Z [DEBUG] HeadTracker initiating callbacks services/head_tracker.go:498 chainLength=50 headNum=21955408 numCallbacks=6
2020-11-06T23:47:04Z [DEBUG] HeadTracker: finished callback 5 in 9µs services/head_tracker.go:520 blockNumber=21955408 callbackIdx=5 callbackType=*chainlink.headTrackableCallback id=head_tracker time=0.000009
2020-11-06T23:47:04Z [DEBUG] HeadTracker: finished callback 2 in 11µs services/head_tracker.go:520 blockNumber=21955408 callbackIdx=2 callbackType=*services.jobSubscriber id=head_tracker time=0.000011
2020-11-06T23:47:04Z [DEBUG] Resuming all runs pending next block services/run_manager.go:247 currentBlockHeight=21955408
2020-11-06T23:47:04Z [DEBUG] eth.Client#BlockByNumber(...) eth/client.go:270 number=21955405
2020-11-06T23:47:04Z [DEBUG] HeadTracker: finished callback 3 in 10.8µs services/head_tracker.go:520 blockNumber=21955408 callbackIdx=3 callbackType=*chainlink.pendingConnectionResumer id=head_tracker time=0.0000108
2020-11-06T23:47:04Z [DEBUG] BalanceMonitor: signalling balance worker services/balance_monitor.go:68
2020-11-06T23:47:04Z [DEBUG] HeadTracker: finished callback 4 in 526.5µs services/head_tracker.go:520 blockNumber=21955408 callbackIdx=4 callbackType=*services.balanceMonitor id=head_tracker time=0.0005265
2020-11-06T23:47:04Z [DEBUG] eth.Client#BalanceAt(...) eth/client.go:301 account=0xdEE5bcB87dD8b429E0e7d86ed2C2b43aC1F9EFbf blockNumber=<nil>
2020-11-06T23:47:04Z [DEBUG] EthConfirmer: finished CheckForReceipts bulletprooftxmanager/eth_confirmer.go:92 headNum=21955408 id=eth_confirmer time=0.0011655
2020-11-06T23:47:04Z [DEBUG] EthConfirmer: finished BumpGasWhereNecessary bulletprooftxmanager/eth_confirmer.go:103 headNum=21955408 id=eth_confirmer time=0.0080424
2020-11-06T23:47:04Z [DEBUG] EthConfirmer: finished EnsureConfirmedTransactionsInLongestChain bulletprooftxmanager/eth_confirmer.go:107 headNum=21955408 id=eth_confirmer time=0.0069421
2020-11-06T23:47:04Z [DEBUG] HeadTracker: finished callback 1 in 26.8341ms services/head_tracker.go:520 blockNumber=21955408 callbackIdx=1 callbackType=*bulletprooftxmanager.ethConfirmer id=head_tracker time=0.0268341
2020-11-06T23:47:04Z [DEBUG] GasUpdater: waiting for blocks: 17/24 services/gas_updater.go:118 inHistory=17 required=24
2020-11-06T23:47:04Z [DEBUG] HeadTracker: finished callback 0 in 275.842ms services/head_tracker.go:520 blockNumber=21955408 callbackIdx=0 callbackType=*services.gasUpdater id=head_tracker time=0.275842
2020-11-06T23:47:04Z [DEBUG] HeadTracker finished processing head 21955408 in 289.7457ms services/head_tracker.go:330 blockNumber=21955408 id=head_tracker time=0.2897457
2020-11-06T23:47:08Z [DEBUG] Received new head #21955409 (0x14f0351) services/head_tracker.go:335 blockHash=0x7d8c824b645f9c75f58c76a57cf1aefaaac95a9a87e5f5dd0a99c5f1ec10b42b blockHeight=21955409
2020-11-06T23:47:08Z [DEBUG] HeadTracker initiating callbacks services/head_tracker.go:498 chainLength=50 headNum=21955409 numCallbacks=6
2020-11-06T23:47:08Z [DEBUG] HeadTracker: finished callback 5 in 9.6µs services/head_tracker.go:520 blockNumber=21955409 callbackIdx=5 callbackType=*chainlink.headTrackableCallback id=head_tracker time=0.0000096
2020-11-06T23:47:08Z [DEBUG] eth.Client#BlockByNumber(...) eth/client.go:270 number=21955406
2020-11-06T23:47:08Z [DEBUG] HeadTracker: finished callback 2 in 11.1µs services/head_tracker.go:520 blockNumber=21955409 callbackIdx=2 callbackType=*services.jobSubscriber id=head_tracker time=0.0000111
2020-11-06T23:47:08Z [DEBUG] Resuming all runs pending next block services/run_manager.go:247 currentBlockHeight=21955409
2020-11-06T23:47:08Z [DEBUG] HeadTracker: finished callback 3 in 8.2µs services/head_tracker.go:520 blockNumber=21955409 callbackIdx=3 callbackType=*chainlink.pendingConnectionResumer id=head_tracker time=0.0000082
2020-11-06T23:47:08Z [DEBUG] BalanceMonitor: signalling balance worker services/balance_monitor.go:68
2020-11-06T23:47:08Z [DEBUG] HeadTracker: finished callback 4 in 7.3194ms services/head_tracker.go:520 blockNumber=21955409 callbackIdx=4 callbackType=*services.balanceMonitor id=head_tracker time=0.0073194
2020-11-06T23:47:08Z [DEBUG] EthConfirmer: finished CheckForReceipts bulletprooftxmanager/eth_confirmer.go:92 headNum=21955409 id=eth_confirmer time=0.0018978
2020-11-06T23:47:08Z [DEBUG] EthConfirmer: finished BumpGasWhereNecessary bulletprooftxmanager/eth_confirmer.go:103 headNum=21955409 id=eth_confirmer time=0.0082996
2020-11-06T23:47:08Z [DEBUG] EthConfirmer: finished EnsureConfirmedTransactionsInLongestChain bulletprooftxmanager/eth_confirmer.go:107 headNum=21955409 id=eth_confirmer time=0.0040215
2020-11-06T23:47:08Z [DEBUG] HeadTracker: finished callback 1 in 26.3435ms services/head_tracker.go:520 blockNumber=21955409 callbackIdx=1 callbackType=*bulletprooftxmanager.ethConfirmer id=head_tracker time=0.0263435
2020-11-06T23:47:08Z [DEBUG] eth.Client#BalanceAt(...) eth/client.go:301 account=0xdEE5bcB87dD8b429E0e7d86ed2C2b43aC1F9EFbf blockNumber=<nil>
2020-11-06T23:47:09Z [DEBUG] GasUpdater: waiting for blocks: 18/24 services/gas_updater.go:118 inHistory=18 required=24
2020-11-06T23:47:09Z [DEBUG] HeadTracker: finished callback 0 in 286.7495ms services/head_tracker.go:520 blockNumber=21955409 callbackIdx=0 callbackType=*services.gasUpdater id=head_tracker time=0.2867495
2020-11-06T23:47:09Z [DEBUG] HeadTracker finished processing head 21955409 in 306.1137ms services/head_tracker.go:330 blockNumber=21955409 id=head_tracker time=0.3061137
2020-11-06T23:47:09Z [DEBUG] DHT: Put value loghelper/logger_with_context.go:37 F=1 configDigest=017363cc8a250ae71b30107df7c0bdfd extendedLogging=true id=DHT key=/peerinfo/12D3KooWLFP41jHMfKXVyyFttspPXVhmVTQCrWrXqQDppMgHfdXm listenAddr=/ip4/0.0.0.0/tcp/6690 listenIP=0.0.0.0 listenPort=6690 peerID=12D3KooWLFP41jHMfKXVyyFttspPXVhmVTQCrWrXqQDppMgHfdXm protocolID=/cl_peer_discovery_dht/017363cc8a250ae71b30107df7c0bdfd/kad/1.0.0 value={"addrs":["/ip4/172.19.0.8/tcp/6690","/ip4/127.0.0.1/tcp/6690"],"pk":"CAESIJr9Y5O2Pl09P00uoOoiF9nfkYhi3ZrL1sL3z3zfugvY","sig":"+R47SQlgC8ihDwmPA6WNQvbUKXuqSf5ncTG2mgmeyUs5SoXm2DJ8CWd4hh5veJBaLU1bPO34a/p+UIIRrqJUAA==","timestamp":1604706429}
2020-11-06T23:47:09Z [ERROR] DHT: Error publishing address loghelper/logger_with_context.go:49 F=1 configDigest=017363cc8a250ae71b30107df7c0bdfd err=could publish address: failed to find any peer in table extendedLogging=true id=DHT listenAddr=/ip4/0.0.0.0/tcp/6690 listenIP=0.0.0.0 listenPort=6690 peerID=12D3KooWLFP41jHMfKXVyyFttspPXVhmVTQCrWrXqQDppMgHfdXm protocolID=/cl_peer_discovery_dht/017363cc8a250ae71b30107df7c0bdfd/kad/1.0.0 retryIn=10 stacktrace=github.com/smartcontractkit/libocr/offchainreporting/loghelper.LoggerWithContext.Error
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/offchainreporting/loghelper/logger_with_context.go:49
github.com/smartcontractkit/libocr/offchainreporting/loghelper.LoggerWithContext.Error
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/offchainreporting/loghelper/logger_with_context.go:49
github.com/smartcontractkit/libocr/offchainreporting/loghelper.LoggerWithContext.Error
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/offchainreporting/loghelper/logger_with_context.go:49
github.com/smartcontractkit/libocr/networking/dht-router.DHTRouter.startAnnounceInBackground.func1
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/networking/dht-router/router.go:267
github.com/smartcontractkit/libocr/subprocesses.(*Subprocesses).Go.func1
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/subprocesses/subprocesses.go:29
2020-11-06T23:47:12Z [DEBUG] Received new head #21955410 (0x14f0352) services/head_tracker.go:335 blockHash=0xac89ca7e5d5cf31e77090b16ad5e3364bb60fe60eeb63d730dcc395e6e34ff4c blockHeight=21955410
2020-11-06T23:47:12Z [DEBUG] HeadTracker initiating callbacks services/head_tracker.go:498 chainLength=50 headNum=21955410 numCallbacks=6
2020-11-06T23:47:12Z [DEBUG] HeadTracker: finished callback 5 in 9.2µs services/head_tracker.go:520 blockNumber=21955410 callbackIdx=5 callbackType=*chainlink.headTrackableCallback id=head_tracker time=0.0000092
2020-11-06T23:47:12Z [DEBUG] HeadTracker: finished callback 2 in 9.7µs services/head_tracker.go:520 blockNumber=21955410 callbackIdx=2 callbackType=*services.jobSubscriber id=head_tracker time=0.0000097
2020-11-06T23:47:12Z [DEBUG] Resuming all runs pending next block services/run_manager.go:247 currentBlockHeight=21955410
2020-11-06T23:47:12Z [DEBUG] eth.Client#BlockByNumber(...) eth/client.go:270 number=21955407
2020-11-06T23:47:12Z [DEBUG] BalanceMonitor: signalling balance worker services/balance_monitor.go:68
2020-11-06T23:47:12Z [DEBUG] HeadTracker: finished callback 4 in 7.3631ms services/head_tracker.go:520 blockNumber=21955410 callbackIdx=4 callbackType=*services.balanceMonitor id=head_tracker time=0.0073631
2020-11-06T23:47:12Z [DEBUG] HeadTracker: finished callback 3 in 14µs services/head_tracker.go:520 blockNumber=21955410 callbackIdx=3 callbackType=*chainlink.pendingConnectionResumer id=head_tracker time=0.000014
2020-11-06T23:47:12Z [DEBUG] EthConfirmer: finished CheckForReceipts bulletprooftxmanager/eth_confirmer.go:92 headNum=21955410 id=eth_confirmer time=0.0009016
2020-11-06T23:47:12Z [DEBUG] EthConfirmer: finished BumpGasWhereNecessary bulletprooftxmanager/eth_confirmer.go:103 headNum=21955410 id=eth_confirmer time=0.0061726
2020-11-06T23:47:12Z [DEBUG] EthConfirmer: finished EnsureConfirmedTransactionsInLongestChain bulletprooftxmanager/eth_confirmer.go:107 headNum=21955410 id=eth_confirmer time=0.004757
2020-11-06T23:47:12Z [DEBUG] HeadTracker: finished callback 1 in 22.4557ms services/head_tracker.go:520 blockNumber=21955410 callbackIdx=1 callbackType=*bulletprooftxmanager.ethConfirmer id=head_tracker time=0.0224557
2020-11-06T23:47:12Z [DEBUG] eth.Client#BalanceAt(...) eth/client.go:301 account=0xdEE5bcB87dD8b429E0e7d86ed2C2b43aC1F9EFbf blockNumber=<nil>
2020-11-06T23:47:12Z [DEBUG] GasUpdater: skipping empty block: 21955407 services/gas_updater.go:121 blockNumber=21955407
2020-11-06T23:47:12Z [DEBUG] HeadTracker: finished callback 0 in 258.4597ms services/head_tracker.go:520 blockNumber=21955410 callbackIdx=0 callbackType=*services.gasUpdater id=head_tracker time=0.2584597
2020-11-06T23:47:12Z [DEBUG] HeadTracker finished processing head 21955410 in 272.8669ms services/head_tracker.go:330 blockNumber=21955410 id=head_tracker time=0.2728669
2020-11-06T23:47:19Z [DEBUG] DHT: Put value loghelper/logger_with_context.go:37 F=1 configDigest=017363cc8a250ae71b30107df7c0bdfd extendedLogging=true id=DHT key=/peerinfo/12D3KooWLFP41jHMfKXVyyFttspPXVhmVTQCrWrXqQDppMgHfdXm listenAddr=/ip4/0.0.0.0/tcp/6690 listenIP=0.0.0.0 listenPort=6690 peerID=12D3KooWLFP41jHMfKXVyyFttspPXVhmVTQCrWrXqQDppMgHfdXm protocolID=/cl_peer_discovery_dht/017363cc8a250ae71b30107df7c0bdfd/kad/1.0.0 value={"addrs":["/ip4/172.19.0.8/tcp/6690","/ip4/127.0.0.1/tcp/6690"],"pk":"CAESIJr9Y5O2Pl09P00uoOoiF9nfkYhi3ZrL1sL3z3zfugvY","sig":"dGrpSAow5K88qsTZIpFXZQqNx1tpL1mADWTbPcqjURT1P8IAzuEBMkUZez3zCoz1VeSnrkAw/YESmmpei5SVCA==","timestamp":1604706439}
2020-11-06T23:47:19Z [ERROR] DHT: Error publishing address loghelper/logger_with_context.go:49 F=1 configDigest=017363cc8a250ae71b30107df7c0bdfd err=could publish address: failed to find any peer in table extendedLogging=true id=DHT listenAddr=/ip4/0.0.0.0/tcp/6690 listenIP=0.0.0.0 listenPort=6690 peerID=12D3KooWLFP41jHMfKXVyyFttspPXVhmVTQCrWrXqQDppMgHfdXm protocolID=/cl_peer_discovery_dht/017363cc8a250ae71b30107df7c0bdfd/kad/1.0.0 retryIn=10 stacktrace=github.com/smartcontractkit/libocr/offchainreporting/loghelper.LoggerWithContext.Error
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/offchainreporting/loghelper/logger_with_context.go:49
github.com/smartcontractkit/libocr/offchainreporting/loghelper.LoggerWithContext.Error
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/offchainreporting/loghelper/logger_with_context.go:49
github.com/smartcontractkit/libocr/offchainreporting/loghelper.LoggerWithContext.Error
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/offchainreporting/loghelper/logger_with_context.go:49
github.com/smartcontractkit/libocr/networking/dht-router.DHTRouter.startAnnounceInBackground.func1
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/networking/dht-router/router.go:267
github.com/smartcontractkit/libocr/subprocesses.(*Subprocesses).Go.func1
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/subprocesses/subprocesses.go:29
2020-11-06T23:47:22Z [DEBUG] Received new head #21955411 (0x14f0353) services/head_tracker.go:335 blockHash=0x73a35af5ff17b9567626c334e31a71388b65af01dff70246d61fe64906751a81 blockHeight=21955411
2020-11-06T23:47:22Z [DEBUG] HeadTracker initiating callbacks services/head_tracker.go:498 chainLength=50 headNum=21955411 numCallbacks=6
2020-11-06T23:47:22Z [DEBUG] HeadTracker: finished callback 5 in 8.2µs services/head_tracker.go:520 blockNumber=21955411 callbackIdx=5 callbackType=*chainlink.headTrackableCallback id=head_tracker time=0.0000082
2020-11-06T23:47:22Z [DEBUG] HeadTracker: finished callback 2 in 8.5µs services/head_tracker.go:520 blockNumber=21955411 callbackIdx=2 callbackType=*services.jobSubscriber id=head_tracker time=0.0000085
2020-11-06T23:47:22Z [DEBUG] Resuming all runs pending next block services/run_manager.go:247 currentBlockHeight=21955411
2020-11-06T23:47:22Z [DEBUG] eth.Client#BlockByNumber(...) eth/client.go:270 number=21955408
2020-11-06T23:47:22Z [DEBUG] BalanceMonitor: signalling balance worker services/balance_monitor.go:68
2020-11-06T23:47:22Z [DEBUG] HeadTracker: finished callback 4 in 722.4µs services/head_tracker.go:520 blockNumber=21955411 callbackIdx=4 callbackType=*services.balanceMonitor id=head_tracker time=0.0007224
2020-11-06T23:47:22Z [DEBUG] HeadTracker: finished callback 3 in 6.8µs services/head_tracker.go:520 blockNumber=21955411 callbackIdx=3 callbackType=*chainlink.pendingConnectionResumer id=head_tracker time=0.0000068
2020-11-06T23:47:22Z [DEBUG] eth.Client#BalanceAt(...) eth/client.go:301 account=0xdEE5bcB87dD8b429E0e7d86ed2C2b43aC1F9EFbf blockNumber=<nil>
2020-11-06T23:47:22Z [DEBUG] EthConfirmer: finished CheckForReceipts bulletprooftxmanager/eth_confirmer.go:92 headNum=21955411 id=eth_confirmer time=0.000832
2020-11-06T23:47:22Z [DEBUG] EthConfirmer: finished BumpGasWhereNecessary bulletprooftxmanager/eth_confirmer.go:103 headNum=21955411 id=eth_confirmer time=0.007795
2020-11-06T23:47:22Z [DEBUG] EthConfirmer: finished EnsureConfirmedTransactionsInLongestChain bulletprooftxmanager/eth_confirmer.go:107 headNum=21955411 id=eth_confirmer time=0.0039952
2020-11-06T23:47:22Z [DEBUG] HeadTracker: finished callback 1 in 33.9672ms services/head_tracker.go:520 blockNumber=21955411 callbackIdx=1 callbackType=*bulletprooftxmanager.ethConfirmer id=head_tracker time=0.0339672
2020-11-06T23:47:23Z [DEBUG] GasUpdater: skipping empty block: 21955408 services/gas_updater.go:121 blockNumber=21955408
2020-11-06T23:47:23Z [DEBUG] HeadTracker: finished callback 0 in 280.1554ms services/head_tracker.go:520 blockNumber=21955411 callbackIdx=0 callbackType=*services.gasUpdater id=head_tracker time=0.2801554
2020-11-06T23:47:23Z [DEBUG] HeadTracker finished processing head 21955411 in 292.3505ms services/head_tracker.go:330 blockNumber=21955411 id=head_tracker time=0.2923505
2020-11-06T23:47:27Z [DEBUG] Received new head #21955412 (0x14f0354) services/head_tracker.go:335 blockHash=0xb0817439455acccf4d43b5bdf9162a2cd14be6ce29bd23b3c58b9a8f2eb76622 blockHeight=21955412
2020-11-06T23:47:27Z [DEBUG] HeadTracker initiating callbacks services/head_tracker.go:498 chainLength=50 headNum=21955412 numCallbacks=6
2020-11-06T23:47:27Z [DEBUG] HeadTracker: finished callback 5 in 9.5µs services/head_tracker.go:520 blockNumber=21955412 callbackIdx=5 callbackType=*chainlink.headTrackableCallback id=head_tracker time=0.0000095
2020-11-06T23:47:27Z [DEBUG] HeadTracker: finished callback 3 in 9.5µs services/head_tracker.go:520 blockNumber=21955412 callbackIdx=3 callbackType=*chainlink.pendingConnectionResumer id=head_tracker time=0.0000095
2020-11-06T23:47:27Z [DEBUG] HeadTracker: finished callback 2 in 9.8µs services/head_tracker.go:520 blockNumber=21955412 callbackIdx=2 callbackType=*services.jobSubscriber id=head_tracker time=0.0000098
2020-11-06T23:47:27Z [DEBUG] BalanceMonitor: signalling balance worker services/balance_monitor.go:68
2020-11-06T23:47:27Z [DEBUG] HeadTracker: finished callback 4 in 382.9µs services/head_tracker.go:520 blockNumber=21955412 callbackIdx=4 callbackType=*services.balanceMonitor id=head_tracker time=0.0003829
2020-11-06T23:47:27Z [DEBUG] Resuming all runs pending next block services/run_manager.go:247 currentBlockHeight=21955412
2020-11-06T23:47:27Z [DEBUG] eth.Client#BlockByNumber(...) eth/client.go:270 number=21955409
2020-11-06T23:47:27Z [DEBUG] eth.Client#BalanceAt(...) eth/client.go:301 account=0xdEE5bcB87dD8b429E0e7d86ed2C2b43aC1F9EFbf blockNumber=<nil>
2020-11-06T23:47:27Z [DEBUG] EthConfirmer: finished CheckForReceipts bulletprooftxmanager/eth_confirmer.go:92 headNum=21955412 id=eth_confirmer time=0.0009001
2020-11-06T23:47:27Z [DEBUG] EthConfirmer: finished BumpGasWhereNecessary bulletprooftxmanager/eth_confirmer.go:103 headNum=21955412 id=eth_confirmer time=0.0086039
2020-11-06T23:47:27Z [DEBUG] EthConfirmer: finished EnsureConfirmedTransactionsInLongestChain bulletprooftxmanager/eth_confirmer.go:107 headNum=21955412 id=eth_confirmer time=0.0033066
2020-11-06T23:47:27Z [DEBUG] HeadTracker: finished callback 1 in 42.5983ms services/head_tracker.go:520 blockNumber=21955412 callbackIdx=1 callbackType=*bulletprooftxmanager.ethConfirmer id=head_tracker time=0.0425983
2020-11-06T23:47:28Z [DEBUG] GasUpdater: waiting for blocks: 19/24 services/gas_updater.go:118 inHistory=19 required=24
2020-11-06T23:47:28Z [DEBUG] HeadTracker: finished callback 0 in 304.2519ms services/head_tracker.go:520 blockNumber=21955412 callbackIdx=0 callbackType=*services.gasUpdater id=head_tracker time=0.3042519
2020-11-06T23:47:28Z [DEBUG] HeadTracker finished processing head 21955412 in 322.5255ms services/head_tracker.go:330 blockNumber=21955412 id=head_tracker time=0.3225255
2020-11-06T23:47:29Z [DEBUG] DHT: Put value loghelper/logger_with_context.go:37 F=1 configDigest=017363cc8a250ae71b30107df7c0bdfd extendedLogging=true id=DHT key=/peerinfo/12D3KooWLFP41jHMfKXVyyFttspPXVhmVTQCrWrXqQDppMgHfdXm listenAddr=/ip4/0.0.0.0/tcp/6690 listenIP=0.0.0.0 listenPort=6690 peerID=12D3KooWLFP41jHMfKXVyyFttspPXVhmVTQCrWrXqQDppMgHfdXm protocolID=/cl_peer_discovery_dht/017363cc8a250ae71b30107df7c0bdfd/kad/1.0.0 value={"addrs":["/ip4/172.19.0.8/tcp/6690","/ip4/127.0.0.1/tcp/6690"],"pk":"CAESIJr9Y5O2Pl09P00uoOoiF9nfkYhi3ZrL1sL3z3zfugvY","sig":"PpHzmmQNSnXwwKDbQGT25Nw9+VxgYnFHzuz25L845dRRgP4FYwIB+BwhHWXgViqDp9XiLAkgADGyDsO64y8+Dg==","timestamp":1604706449}
2020-11-06T23:47:29Z [ERROR] DHT: Error publishing address loghelper/logger_with_context.go:49 F=1 configDigest=017363cc8a250ae71b30107df7c0bdfd err=could publish address: failed to find any peer in table extendedLogging=true id=DHT listenAddr=/ip4/0.0.0.0/tcp/6690 listenIP=0.0.0.0 listenPort=6690 peerID=12D3KooWLFP41jHMfKXVyyFttspPXVhmVTQCrWrXqQDppMgHfdXm protocolID=/cl_peer_discovery_dht/017363cc8a250ae71b30107df7c0bdfd/kad/1.0.0 retryIn=10 stacktrace=github.com/smartcontractkit/libocr/offchainreporting/loghelper.LoggerWithContext.Error
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/offchainreporting/loghelper/logger_with_context.go:49
github.com/smartcontractkit/libocr/offchainreporting/loghelper.LoggerWithContext.Error
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/offchainreporting/loghelper/logger_with_context.go:49
github.com/smartcontractkit/libocr/offchainreporting/loghelper.LoggerWithContext.Error
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/offchainreporting/loghelper/logger_with_context.go:49
github.com/smartcontractkit/libocr/networking/dht-router.DHTRouter.startAnnounceInBackground.func1
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/networking/dht-router/router.go:267
github.com/smartcontractkit/libocr/subprocesses.(*Subprocesses).Go.func1
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/subprocesses/subprocesses.go:29
2020-11-06T23:47:32Z [DEBUG] Received new head #21955413 (0x14f0355) services/head_tracker.go:335 blockHash=0x7f8a1bcad0226e3a9e1a4666358c1ad71a70c420bd5ea80e810fb2f21fa36160 blockHeight=21955413
2020-11-06T23:47:32Z [DEBUG] HeadTracker initiating callbacks services/head_tracker.go:498 chainLength=50 headNum=21955413 numCallbacks=6
2020-11-06T23:47:32Z [DEBUG] HeadTracker: finished callback 5 in 15.5µs services/head_tracker.go:520 blockNumber=21955413 callbackIdx=5 callbackType=*chainlink.headTrackableCallback id=head_tracker time=0.0000155
2020-11-06T23:47:32Z [DEBUG] HeadTracker: finished callback 2 in 61.9µs services/head_tracker.go:520 blockNumber=21955413 callbackIdx=2 callbackType=*services.jobSubscriber id=head_tracker time=0.0000619
2020-11-06T23:47:32Z [DEBUG] BalanceMonitor: signalling balance worker services/balance_monitor.go:68
2020-11-06T23:47:32Z [DEBUG] HeadTracker: finished callback 4 in 148.9µs services/head_tracker.go:520 blockNumber=21955413 callbackIdx=4 callbackType=*services.balanceMonitor id=head_tracker time=0.0001489
2020-11-06T23:47:32Z [DEBUG] eth.Client#BlockByNumber(...) eth/client.go:270 number=21955410
2020-11-06T23:47:32Z [DEBUG] Resuming all runs pending next block services/run_manager.go:247 currentBlockHeight=21955413
2020-11-06T23:47:32Z [DEBUG] HeadTracker: finished callback 3 in 9.4µs services/head_tracker.go:520 blockNumber=21955413 callbackIdx=3 callbackType=*chainlink.pendingConnectionResumer id=head_tracker time=0.0000094
2020-11-06T23:47:32Z [DEBUG] eth.Client#BalanceAt(...) eth/client.go:301 account=0xdEE5bcB87dD8b429E0e7d86ed2C2b43aC1F9EFbf blockNumber=<nil>
2020-11-06T23:47:32Z [DEBUG] EthConfirmer: finished CheckForReceipts bulletprooftxmanager/eth_confirmer.go:92 headNum=21955413 id=eth_confirmer time=0.0027799
2020-11-06T23:47:32Z [DEBUG] EthConfirmer: finished BumpGasWhereNecessary bulletprooftxmanager/eth_confirmer.go:103 headNum=21955413 id=eth_confirmer time=0.0058855
2020-11-06T23:47:32Z [DEBUG] EthConfirmer: finished EnsureConfirmedTransactionsInLongestChain bulletprooftxmanager/eth_confirmer.go:107 headNum=21955413 id=eth_confirmer time=0.006658
2020-11-06T23:47:32Z [DEBUG] HeadTracker: finished callback 1 in 43.7012ms services/head_tracker.go:520 blockNumber=21955413 callbackIdx=1 callbackType=*bulletprooftxmanager.ethConfirmer id=head_tracker time=0.0437012
2020-11-06T23:47:32Z [DEBUG] GasUpdater: waiting for blocks: 20/24 services/gas_updater.go:118 inHistory=20 required=24
2020-11-06T23:47:32Z [DEBUG] HeadTracker: finished callback 0 in 253.2193ms services/head_tracker.go:520 blockNumber=21955413 callbackIdx=0 callbackType=*services.gasUpdater id=head_tracker time=0.2532193
2020-11-06T23:47:32Z [DEBUG] HeadTracker finished processing head 21955413 in 271.5406ms services/head_tracker.go:330 blockNumber=21955413 id=head_tracker time=0.2715406
2020-11-06T23:47:39Z [DEBUG] DHT: Put value loghelper/logger_with_context.go:37 F=1 configDigest=017363cc8a250ae71b30107df7c0bdfd extendedLogging=true id=DHT key=/peerinfo/12D3KooWLFP41jHMfKXVyyFttspPXVhmVTQCrWrXqQDppMgHfdXm listenAddr=/ip4/0.0.0.0/tcp/6690 listenIP=0.0.0.0 listenPort=6690 peerID=12D3KooWLFP41jHMfKXVyyFttspPXVhmVTQCrWrXqQDppMgHfdXm protocolID=/cl_peer_discovery_dht/017363cc8a250ae71b30107df7c0bdfd/kad/1.0.0 value={"addrs":["/ip4/172.19.0.8/tcp/6690","/ip4/127.0.0.1/tcp/6690"],"pk":"CAESIJr9Y5O2Pl09P00uoOoiF9nfkYhi3ZrL1sL3z3zfugvY","sig":"8aIdCbsWZF04fiImVEVfIUpmEIHwU4SY00inCffoLOv0ZdyqBrUwavua+1sl7uf94FT89Q2fozydiuvp3U4mDQ==","timestamp":1604706459}
2020-11-06T23:47:39Z [ERROR] DHT: Error publishing address loghelper/logger_with_context.go:49 F=1 configDigest=017363cc8a250ae71b30107df7c0bdfd err=could publish address: failed to find any peer in table extendedLogging=true id=DHT listenAddr=/ip4/0.0.0.0/tcp/6690 listenIP=0.0.0.0 listenPort=6690 peerID=12D3KooWLFP41jHMfKXVyyFttspPXVhmVTQCrWrXqQDppMgHfdXm protocolID=/cl_peer_discovery_dht/017363cc8a250ae71b30107df7c0bdfd/kad/1.0.0 retryIn=10 stacktrace=github.com/smartcontractkit/libocr/offchainreporting/loghelper.LoggerWithContext.Error
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/offchainreporting/loghelper/logger_with_context.go:49
github.com/smartcontractkit/libocr/offchainreporting/loghelper.LoggerWithContext.Error
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/offchainreporting/loghelper/logger_with_context.go:49
github.com/smartcontractkit/libocr/offchainreporting/loghelper.LoggerWithContext.Error
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/offchainreporting/loghelper/logger_with_context.go:49
github.com/smartcontractkit/libocr/networking/dht-router.DHTRouter.startAnnounceInBackground.func1
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/networking/dht-router/router.go:267
github.com/smartcontractkit/libocr/subprocesses.(*Subprocesses).Go.func1
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/subprocesses/subprocesses.go:29
2020-11-06T23:47:40Z [DEBUG] Received new head #21955414 (0x14f0356) services/head_tracker.go:335 blockHash=0xcff0a70ddd33f1fd11011c8e6fdedb343f474c43f3417cdbaa64265a3f0fa7aa blockHeight=21955414
2020-11-06T23:47:40Z [DEBUG] HeadTracker initiating callbacks services/head_tracker.go:498 chainLength=50 headNum=21955414 numCallbacks=6
2020-11-06T23:47:40Z [DEBUG] HeadTracker: finished callback 5 in 9.6µs services/head_tracker.go:520 blockNumber=21955414 callbackIdx=5 callbackType=*chainlink.headTrackableCallback id=head_tracker time=0.0000096
2020-11-06T23:47:40Z [DEBUG] eth.Client#BlockByNumber(...) eth/client.go:270 number=21955411
2020-11-06T23:47:40Z [DEBUG] HeadTracker: finished callback 3 in 8.1µs services/head_tracker.go:520 blockNumber=21955414 callbackIdx=3 callbackType=*chainlink.pendingConnectionResumer id=head_tracker time=0.0000081
2020-11-06T23:47:40Z [DEBUG] BalanceMonitor: signalling balance worker services/balance_monitor.go:68
2020-11-06T23:47:40Z [DEBUG] HeadTracker: finished callback 4 in 578.6µs services/head_tracker.go:520 blockNumber=21955414 callbackIdx=4 callbackType=*services.balanceMonitor id=head_tracker time=0.0005786
2020-11-06T23:47:40Z [DEBUG] HeadTracker: finished callback 2 in 29.1µs services/head_tracker.go:520 blockNumber=21955414 callbackIdx=2 callbackType=*services.jobSubscriber id=head_tracker time=0.0000291
2020-11-06T23:47:40Z [DEBUG] Resuming all runs pending next block services/run_manager.go:247 currentBlockHeight=21955414
2020-11-06T23:47:40Z [DEBUG] eth.Client#BalanceAt(...) eth/client.go:301 account=0xdEE5bcB87dD8b429E0e7d86ed2C2b43aC1F9EFbf blockNumber=<nil>
2020-11-06T23:47:40Z [DEBUG] EthConfirmer: finished CheckForReceipts bulletprooftxmanager/eth_confirmer.go:92 headNum=21955414 id=eth_confirmer time=0.0017034
2020-11-06T23:47:40Z [DEBUG] EthConfirmer: finished BumpGasWhereNecessary bulletprooftxmanager/eth_confirmer.go:103 headNum=21955414 id=eth_confirmer time=0.0190642
2020-11-06T23:47:40Z [DEBUG] EthConfirmer: finished EnsureConfirmedTransactionsInLongestChain bulletprooftxmanager/eth_confirmer.go:107 headNum=21955414 id=eth_confirmer time=0.007566
2020-11-06T23:47:40Z [DEBUG] HeadTracker: finished callback 1 in 35.4809ms services/head_tracker.go:520 blockNumber=21955414 callbackIdx=1 callbackType=*bulletprooftxmanager.ethConfirmer id=head_tracker time=0.0354809
2020-11-06T23:47:41Z [DEBUG] GasUpdater: waiting for blocks: 21/24 services/gas_updater.go:118 inHistory=21 required=24
2020-11-06T23:47:41Z [DEBUG] HeadTracker: finished callback 0 in 502.0688ms services/head_tracker.go:520 blockNumber=21955414 callbackIdx=0 callbackType=*services.gasUpdater id=head_tracker time=0.5020688
2020-11-06T23:47:41Z [DEBUG] HeadTracker finished processing head 21955414 in 513.5196ms services/head_tracker.go:330 blockNumber=21955414 id=head_tracker time=0.5135196
2020-11-06T23:47:44Z [DEBUG] Received new head #21955415 (0x14f0357) services/head_tracker.go:335 blockHash=0x69de9567b9956d24ad1887f4da2c9f6ce4ea4c2873e8633cbbb6cf2aecb29320 blockHeight=21955415
2020-11-06T23:47:44Z [DEBUG] HeadTracker initiating callbacks services/head_tracker.go:498 chainLength=50 headNum=21955415 numCallbacks=6
2020-11-06T23:47:44Z [DEBUG] HeadTracker: finished callback 5 in 7.5µs services/head_tracker.go:520 blockNumber=21955415 callbackIdx=5 callbackType=*chainlink.headTrackableCallback id=head_tracker time=0.0000075
2020-11-06T23:47:44Z [DEBUG] eth.Client#BlockByNumber(...) eth/client.go:270 number=21955412
2020-11-06T23:47:44Z [DEBUG] HeadTracker: finished callback 2 in 7.7µs services/head_tracker.go:520 blockNumber=21955415 callbackIdx=2 callbackType=*services.jobSubscriber id=head_tracker time=0.0000077
2020-11-06T23:47:44Z [DEBUG] Resuming all runs pending next block services/run_manager.go:247 currentBlockHeight=21955415
2020-11-06T23:47:44Z [DEBUG] BalanceMonitor: signalling balance worker services/balance_monitor.go:68
2020-11-06T23:47:44Z [DEBUG] HeadTracker: finished callback 4 in 450.7µs services/head_tracker.go:520 blockNumber=21955415 callbackIdx=4 callbackType=*services.balanceMonitor id=head_tracker time=0.0004507
2020-11-06T23:47:44Z [DEBUG] HeadTracker: finished callback 3 in 10.6µs services/head_tracker.go:520 blockNumber=21955415 callbackIdx=3 callbackType=*chainlink.pendingConnectionResumer id=head_tracker time=0.0000106
2020-11-06T23:47:44Z [DEBUG] eth.Client#BalanceAt(...) eth/client.go:301 account=0xdEE5bcB87dD8b429E0e7d86ed2C2b43aC1F9EFbf blockNumber=<nil>
2020-11-06T23:47:44Z [DEBUG] EthConfirmer: finished CheckForReceipts bulletprooftxmanager/eth_confirmer.go:92 headNum=21955415 id=eth_confirmer time=0.000757
2020-11-06T23:47:44Z [DEBUG] EthConfirmer: finished BumpGasWhereNecessary bulletprooftxmanager/eth_confirmer.go:103 headNum=21955415 id=eth_confirmer time=0.0096609
2020-11-06T23:47:44Z [DEBUG] EthConfirmer: finished EnsureConfirmedTransactionsInLongestChain bulletprooftxmanager/eth_confirmer.go:107 headNum=21955415 id=eth_confirmer time=0.0052911
2020-11-06T23:47:44Z [DEBUG] HeadTracker: finished callback 1 in 40.7475ms services/head_tracker.go:520 blockNumber=21955415 callbackIdx=1 callbackType=*bulletprooftxmanager.ethConfirmer id=head_tracker time=0.0407475
2020-11-06T23:47:45Z [DEBUG] GasUpdater: skipping empty block: 21955412 services/gas_updater.go:121 blockNumber=21955412
2020-11-06T23:47:45Z [DEBUG] HeadTracker: finished callback 0 in 295.7474ms services/head_tracker.go:520 blockNumber=21955415 callbackIdx=0 callbackType=*services.gasUpdater id=head_tracker time=0.2957474
2020-11-06T23:47:45Z [DEBUG] HeadTracker finished processing head 21955415 in 308.289ms services/head_tracker.go:330 blockNumber=21955415 id=head_tracker time=0.308289
2020-11-06T23:47:48Z [DEBUG] Received new head #21955416 (0x14f0358) services/head_tracker.go:335 blockHash=0x52f16ffcffaff63d866276568cab4b86c512c96899196e7b203363649c9fb8fc blockHeight=21955416
2020-11-06T23:47:48Z [DEBUG] HeadTracker initiating callbacks services/head_tracker.go:498 chainLength=50 headNum=21955416 numCallbacks=6
2020-11-06T23:47:48Z [DEBUG] HeadTracker: finished callback 5 in 8.1µs services/head_tracker.go:520 blockNumber=21955416 callbackIdx=5 callbackType=*chainlink.headTrackableCallback id=head_tracker time=0.0000081
2020-11-06T23:47:48Z [DEBUG] HeadTracker: finished callback 3 in 8.3µs services/head_tracker.go:520 blockNumber=21955416 callbackIdx=3 callbackType=*chainlink.pendingConnectionResumer id=head_tracker time=0.0000083
2020-11-06T23:47:48Z [DEBUG] BalanceMonitor: signalling balance worker services/balance_monitor.go:68
2020-11-06T23:47:48Z [DEBUG] HeadTracker: finished callback 4 in 60.9µs services/head_tracker.go:520 blockNumber=21955416 callbackIdx=4 callbackType=*services.balanceMonitor id=head_tracker time=0.0000609
2020-11-06T23:47:48Z [DEBUG] eth.Client#BlockByNumber(...) eth/client.go:270 number=21955413
2020-11-06T23:47:48Z [DEBUG] eth.Client#BalanceAt(...) eth/client.go:301 account=0xdEE5bcB87dD8b429E0e7d86ed2C2b43aC1F9EFbf blockNumber=<nil>
2020-11-06T23:47:48Z [DEBUG] HeadTracker: finished callback 2 in 12.1µs services/head_tracker.go:520 blockNumber=21955416 callbackIdx=2 callbackType=*services.jobSubscriber id=head_tracker time=0.0000121
2020-11-06T23:47:48Z [DEBUG] Resuming all runs pending next block services/run_manager.go:247 currentBlockHeight=21955416
2020-11-06T23:47:48Z [DEBUG] EthConfirmer: finished CheckForReceipts bulletprooftxmanager/eth_confirmer.go:92 headNum=21955416 id=eth_confirmer time=0.0010547
2020-11-06T23:47:48Z [DEBUG] EthConfirmer: finished BumpGasWhereNecessary bulletprooftxmanager/eth_confirmer.go:103 headNum=21955416 id=eth_confirmer time=0.0125738
2020-11-06T23:47:48Z [DEBUG] EthConfirmer: finished EnsureConfirmedTransactionsInLongestChain bulletprooftxmanager/eth_confirmer.go:107 headNum=21955416 id=eth_confirmer time=0.0046479
2020-11-06T23:47:48Z [DEBUG] HeadTracker: finished callback 1 in 23.7162ms services/head_tracker.go:520 blockNumber=21955416 callbackIdx=1 callbackType=*bulletprooftxmanager.ethConfirmer id=head_tracker time=0.0237162
2020-11-06T23:47:49Z [DEBUG] GasUpdater: skipping empty block: 21955413 services/gas_updater.go:121 blockNumber=21955413
2020-11-06T23:47:49Z [DEBUG] HeadTracker: finished callback 0 in 300.6564ms services/head_tracker.go:520 blockNumber=21955416 callbackIdx=0 callbackType=*services.gasUpdater id=head_tracker time=0.3006564
2020-11-06T23:47:49Z [DEBUG] HeadTracker finished processing head 21955416 in 311.5317ms services/head_tracker.go:330 blockNumber=21955416 id=head_tracker time=0.3115317
2020-11-06T23:47:49Z [DEBUG] DHT: Put value loghelper/logger_with_context.go:37 F=1 configDigest=017363cc8a250ae71b30107df7c0bdfd extendedLogging=true id=DHT key=/peerinfo/12D3KooWLFP41jHMfKXVyyFttspPXVhmVTQCrWrXqQDppMgHfdXm listenAddr=/ip4/0.0.0.0/tcp/6690 listenIP=0.0.0.0 listenPort=6690 peerID=12D3KooWLFP41jHMfKXVyyFttspPXVhmVTQCrWrXqQDppMgHfdXm protocolID=/cl_peer_discovery_dht/017363cc8a250ae71b30107df7c0bdfd/kad/1.0.0 value={"addrs":["/ip4/172.19.0.8/tcp/6690","/ip4/127.0.0.1/tcp/6690"],"pk":"CAESIJr9Y5O2Pl09P00uoOoiF9nfkYhi3ZrL1sL3z3zfugvY","sig":"AWaUtnG+HGG3vtOKvB4xzLqlZ3Wy7Qwe1VtVqT8nl/ojfJKmPxHozF1SZDvXPYbGEdKmP30cqfzm5sD3ndBHAg==","timestamp":1604706469}
2020-11-06T23:47:49Z [ERROR] DHT: Error publishing address loghelper/logger_with_context.go:49 F=1 configDigest=017363cc8a250ae71b30107df7c0bdfd err=could publish address: failed to find any peer in table extendedLogging=true id=DHT listenAddr=/ip4/0.0.0.0/tcp/6690 listenIP=0.0.0.0 listenPort=6690 peerID=12D3KooWLFP41jHMfKXVyyFttspPXVhmVTQCrWrXqQDppMgHfdXm protocolID=/cl_peer_discovery_dht/017363cc8a250ae71b30107df7c0bdfd/kad/1.0.0 retryIn=10 stacktrace=github.com/smartcontractkit/libocr/offchainreporting/loghelper.LoggerWithContext.Error
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/offchainreporting/loghelper/logger_with_context.go:49
github.com/smartcontractkit/libocr/offchainreporting/loghelper.LoggerWithContext.Error
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/offchainreporting/loghelper/logger_with_context.go:49
github.com/smartcontractkit/libocr/offchainreporting/loghelper.LoggerWithContext.Error
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/offchainreporting/loghelper/logger_with_context.go:49
github.com/smartcontractkit/libocr/networking/dht-router.DHTRouter.startAnnounceInBackground.func1
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/networking/dht-router/router.go:267
github.com/smartcontractkit/libocr/subprocesses.(*Subprocesses).Go.func1
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/subprocesses/subprocesses.go:29
2020-11-06T23:47:52Z [DEBUG] Received new head #21955417 (0x14f0359) services/head_tracker.go:335 blockHash=0x73e8bc911f8a109f12342c4e627e4197440c3e8967c9af14900ec2030ccce187 blockHeight=21955417
2020-11-06T23:47:52Z [DEBUG] HeadTracker initiating callbacks services/head_tracker.go:498 chainLength=50 headNum=21955417 numCallbacks=6
2020-11-06T23:47:52Z [DEBUG] HeadTracker: finished callback 5 in 8.2µs services/head_tracker.go:520 blockNumber=21955417 callbackIdx=5 callbackType=*chainlink.headTrackableCallback id=head_tracker time=0.0000082
2020-11-06T23:47:52Z [DEBUG] eth.Client#BlockByNumber(...) eth/client.go:270 number=21955414
2020-11-06T23:47:52Z [DEBUG] HeadTracker: finished callback 3 in 7.6µs services/head_tracker.go:520 blockNumber=21955417 callbackIdx=3 callbackType=*chainlink.pendingConnectionResumer id=head_tracker time=0.0000076
2020-11-06T23:47:52Z [DEBUG] HeadTracker: finished callback 2 in 9.4µs services/head_tracker.go:520 blockNumber=21955417 callbackIdx=2 callbackType=*services.jobSubscriber id=head_tracker time=0.0000094
2020-11-06T23:47:52Z [DEBUG] Resuming all runs pending next block services/run_manager.go:247 currentBlockHeight=21955417
2020-11-06T23:47:52Z [DEBUG] BalanceMonitor: signalling balance worker services/balance_monitor.go:68
2020-11-06T23:47:52Z [DEBUG] HeadTracker: finished callback 4 in 104.5µs services/head_tracker.go:520 blockNumber=21955417 callbackIdx=4 callbackType=*services.balanceMonitor id=head_tracker time=0.0001045
2020-11-06T23:47:52Z [DEBUG] eth.Client#BalanceAt(...) eth/client.go:301 account=0xdEE5bcB87dD8b429E0e7d86ed2C2b43aC1F9EFbf blockNumber=<nil>
2020-11-06T23:47:52Z [DEBUG] EthConfirmer: finished CheckForReceipts bulletprooftxmanager/eth_confirmer.go:92 headNum=21955417 id=eth_confirmer time=0.0010303
2020-11-06T23:47:52Z [DEBUG] EthConfirmer: finished BumpGasWhereNecessary bulletprooftxmanager/eth_confirmer.go:103 headNum=21955417 id=eth_confirmer time=0.0085926
2020-11-06T23:47:52Z [DEBUG] EthConfirmer: finished EnsureConfirmedTransactionsInLongestChain bulletprooftxmanager/eth_confirmer.go:107 headNum=21955417 id=eth_confirmer time=0.0072052
2020-11-06T23:47:52Z [DEBUG] HeadTracker: finished callback 1 in 21.3265ms services/head_tracker.go:520 blockNumber=21955417 callbackIdx=1 callbackType=*bulletprooftxmanager.ethConfirmer id=head_tracker time=0.0213265
2020-11-06T23:47:52Z [DEBUG] GasUpdater: skipping empty block: 21955414 services/gas_updater.go:121 blockNumber=21955414
2020-11-06T23:47:52Z [DEBUG] HeadTracker: finished callback 0 in 252.9986ms services/head_tracker.go:520 blockNumber=21955417 callbackIdx=0 callbackType=*services.gasUpdater id=head_tracker time=0.2529986
2020-11-06T23:47:52Z [DEBUG] HeadTracker finished processing head 21955417 in 265.0825ms services/head_tracker.go:330 blockNumber=21955417 id=head_tracker time=0.2650825
2020-11-06T23:47:59Z [DEBUG] DHT: Put value loghelper/logger_with_context.go:37 F=1 configDigest=017363cc8a250ae71b30107df7c0bdfd extendedLogging=true id=DHT key=/peerinfo/12D3KooWLFP41jHMfKXVyyFttspPXVhmVTQCrWrXqQDppMgHfdXm listenAddr=/ip4/0.0.0.0/tcp/6690 listenIP=0.0.0.0 listenPort=6690 peerID=12D3KooWLFP41jHMfKXVyyFttspPXVhmVTQCrWrXqQDppMgHfdXm protocolID=/cl_peer_discovery_dht/017363cc8a250ae71b30107df7c0bdfd/kad/1.0.0 value={"addrs":["/ip4/172.19.0.8/tcp/6690","/ip4/127.0.0.1/tcp/6690"],"pk":"CAESIJr9Y5O2Pl09P00uoOoiF9nfkYhi3ZrL1sL3z3zfugvY","sig":"0n6BIqDBrQCHg0A1+RIi1zda0uaV9h43u1XTGsqz0DKroILzneWYYTVJSwFNhEhgpdjmk28N+sPKxRObl2hJDw==","timestamp":1604706479}
2020-11-06T23:47:59Z [ERROR] DHT: Error publishing address loghelper/logger_with_context.go:49 F=1 configDigest=017363cc8a250ae71b30107df7c0bdfd err=could publish address: failed to find any peer in table extendedLogging=true id=DHT listenAddr=/ip4/0.0.0.0/tcp/6690 listenIP=0.0.0.0 listenPort=6690 peerID=12D3KooWLFP41jHMfKXVyyFttspPXVhmVTQCrWrXqQDppMgHfdXm protocolID=/cl_peer_discovery_dht/017363cc8a250ae71b30107df7c0bdfd/kad/1.0.0 retryIn=10 stacktrace=github.com/smartcontractkit/libocr/offchainreporting/loghelper.LoggerWithContext.Error
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/offchainreporting/loghelper/logger_with_context.go:49
github.com/smartcontractkit/libocr/offchainreporting/loghelper.LoggerWithContext.Error
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/offchainreporting/loghelper/logger_with_context.go:49
github.com/smartcontractkit/libocr/offchainreporting/loghelper.LoggerWithContext.Error
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/offchainreporting/loghelper/logger_with_context.go:49
github.com/smartcontractkit/libocr/networking/dht-router.DHTRouter.startAnnounceInBackground.func1
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/networking/dht-router/router.go:267
github.com/smartcontractkit/libocr/subprocesses.(*Subprocesses).Go.func1
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/subprocesses/subprocesses.go:29
2020-11-06T23:48:01Z [DEBUG] Received new head #21955418 (0x14f035a) services/head_tracker.go:335 blockHash=0x19f5e825a0debe1f593381dfcf4e3a1ecb2197e2228ce3d3b3877c27e5a1613e blockHeight=21955418
2020-11-06T23:48:01Z [DEBUG] HeadTracker initiating callbacks services/head_tracker.go:498 chainLength=50 headNum=21955418 numCallbacks=6
2020-11-06T23:48:01Z [DEBUG] HeadTracker: finished callback 5 in 14.2µs services/head_tracker.go:520 blockNumber=21955418 callbackIdx=5 callbackType=*chainlink.headTrackableCallback id=head_tracker time=0.0000142
2020-11-06T23:48:01Z [DEBUG] HeadTracker: finished callback 3 in 10.1µs services/head_tracker.go:520 blockNumber=21955418 callbackIdx=3 callbackType=*chainlink.pendingConnectionResumer id=head_tracker time=0.0000101
2020-11-06T23:48:01Z [DEBUG] HeadTracker: finished callback 2 in 11.9µs services/head_tracker.go:520 blockNumber=21955418 callbackIdx=2 callbackType=*services.jobSubscriber id=head_tracker time=0.0000119
2020-11-06T23:48:01Z [DEBUG] Resuming all runs pending next block services/run_manager.go:247 currentBlockHeight=21955418
2020-11-06T23:48:01Z [DEBUG] eth.Client#BlockByNumber(...) eth/client.go:270 number=21955415
2020-11-06T23:48:01Z [DEBUG] BalanceMonitor: signalling balance worker services/balance_monitor.go:68
2020-11-06T23:48:01Z [DEBUG] HeadTracker: finished callback 4 in 1.7173ms services/head_tracker.go:520 blockNumber=21955418 callbackIdx=4 callbackType=*services.balanceMonitor id=head_tracker time=0.0017173
2020-11-06T23:48:01Z [DEBUG] eth.Client#BalanceAt(...) eth/client.go:301 account=0xdEE5bcB87dD8b429E0e7d86ed2C2b43aC1F9EFbf blockNumber=<nil>
2020-11-06T23:48:01Z [DEBUG] EthConfirmer: finished CheckForReceipts bulletprooftxmanager/eth_confirmer.go:92 headNum=21955418 id=eth_confirmer time=0.0008616
2020-11-06T23:48:01Z [DEBUG] EthConfirmer: finished BumpGasWhereNecessary bulletprooftxmanager/eth_confirmer.go:103 headNum=21955418 id=eth_confirmer time=0.0051557
2020-11-06T23:48:01Z [DEBUG] EthConfirmer: finished EnsureConfirmedTransactionsInLongestChain bulletprooftxmanager/eth_confirmer.go:107 headNum=21955418 id=eth_confirmer time=0.0048258
2020-11-06T23:48:01Z [DEBUG] HeadTracker: finished callback 1 in 26.0395ms services/head_tracker.go:520 blockNumber=21955418 callbackIdx=1 callbackType=*bulletprooftxmanager.ethConfirmer id=head_tracker time=0.0260395
2020-11-06T23:48:01Z [DEBUG] eth.Client#HeaderByNumber(...) eth/client.go:277 number=<nil>
2020-11-06T23:48:01Z [DEBUG] GasUpdater: skipping empty block: 21955415 services/gas_updater.go:121 blockNumber=21955415
2020-11-06T23:48:01Z [DEBUG] HeadTracker: finished callback 0 in 253.9179ms services/head_tracker.go:520 blockNumber=21955418 callbackIdx=0 callbackType=*services.gasUpdater id=head_tracker time=0.2539179
2020-11-06T23:48:01Z [DEBUG] HeadTracker finished processing head 21955418 in 281.0562ms services/head_tracker.go:330 blockNumber=21955418 id=head_tracker time=0.2810562
2020-11-06T23:48:02Z [DEBUG] TrackConfig: checking latestConfigDetails managed/track_config.go:48
2020-11-06T23:48:04Z [DEBUG] Received new head #21955419 (0x14f035b) services/head_tracker.go:335 blockHash=0x384955a3f29590118a2cb2642cdcdc8c0107efa9c9d0c81a4d6a0df4adb44996 blockHeight=21955419
2020-11-06T23:48:04Z [DEBUG] HeadTracker initiating callbacks services/head_tracker.go:498 chainLength=50 headNum=21955419 numCallbacks=6
2020-11-06T23:48:04Z [DEBUG] HeadTracker: finished callback 5 in 7.1µs services/head_tracker.go:520 blockNumber=21955419 callbackIdx=5 callbackType=*chainlink.headTrackableCallback id=head_tracker time=0.0000071
2020-11-06T23:48:04Z [DEBUG] HeadTracker: finished callback 3 in 8.5µs services/head_tracker.go:520 blockNumber=21955419 callbackIdx=3 callbackType=*chainlink.pendingConnectionResumer id=head_tracker time=0.0000085
2020-11-06T23:48:04Z [DEBUG] HeadTracker: finished callback 2 in 11.8µs services/head_tracker.go:520 blockNumber=21955419 callbackIdx=2 callbackType=*services.jobSubscriber id=head_tracker time=0.0000118
2020-11-06T23:48:04Z [DEBUG] BalanceMonitor: signalling balance worker services/balance_monitor.go:68
2020-11-06T23:48:04Z [DEBUG] HeadTracker: finished callback 4 in 286.8µs services/head_tracker.go:520 blockNumber=21955419 callbackIdx=4 callbackType=*services.balanceMonitor id=head_tracker time=0.0002868
2020-11-06T23:48:04Z [DEBUG] Resuming all runs pending next block services/run_manager.go:247 currentBlockHeight=21955419
2020-11-06T23:48:04Z [DEBUG] eth.Client#BlockByNumber(...) eth/client.go:270 number=21955416
2020-11-06T23:48:04Z [DEBUG] eth.Client#BalanceAt(...) eth/client.go:301 account=0xdEE5bcB87dD8b429E0e7d86ed2C2b43aC1F9EFbf blockNumber=<nil>
2020-11-06T23:48:04Z [DEBUG] EthConfirmer: finished CheckForReceipts bulletprooftxmanager/eth_confirmer.go:92 headNum=21955419 id=eth_confirmer time=0.0025553
2020-11-06T23:48:04Z [DEBUG] EthConfirmer: finished BumpGasWhereNecessary bulletprooftxmanager/eth_confirmer.go:103 headNum=21955419 id=eth_confirmer time=0.0064519
2020-11-06T23:48:04Z [DEBUG] EthConfirmer: finished EnsureConfirmedTransactionsInLongestChain bulletprooftxmanager/eth_confirmer.go:107 headNum=21955419 id=eth_confirmer time=0.0046985
2020-11-06T23:48:04Z [DEBUG] HeadTracker: finished callback 1 in 23.0504ms services/head_tracker.go:520 blockNumber=21955419 callbackIdx=1 callbackType=*bulletprooftxmanager.ethConfirmer id=head_tracker time=0.0230504
2020-11-06T23:48:04Z [DEBUG] GasUpdater: skipping empty block: 21955416 services/gas_updater.go:121 blockNumber=21955416
2020-11-06T23:48:04Z [DEBUG] HeadTracker: finished callback 0 in 251.4668ms services/head_tracker.go:520 blockNumber=21955419 callbackIdx=0 callbackType=*services.gasUpdater id=head_tracker time=0.2514668
2020-11-06T23:48:04Z [DEBUG] HeadTracker finished processing head 21955419 in 265.9109ms services/head_tracker.go:330 blockNumber=21955419 id=head_tracker time=0.2659109
2020-11-06T23:48:08Z [DEBUG] Received new head #21955420 (0x14f035c) services/head_tracker.go:335 blockHash=0x8c998df3cfc9363ff521b80f3e6424b9c20676559c6dc6277d2c62b0bd8a025e blockHeight=21955420
2020-11-06T23:48:08Z [DEBUG] HeadTracker initiating callbacks services/head_tracker.go:498 chainLength=50 headNum=21955420 numCallbacks=6
2020-11-06T23:48:08Z [DEBUG] HeadTracker: finished callback 5 in 9µs services/head_tracker.go:520 blockNumber=21955420 callbackIdx=5 callbackType=*chainlink.headTrackableCallback id=head_tracker time=0.000009
2020-11-06T23:48:08Z [DEBUG] eth.Client#BlockByNumber(...) eth/client.go:270 number=21955417
2020-11-06T23:48:08Z [DEBUG] BalanceMonitor: signalling balance worker services/balance_monitor.go:68
2020-11-06T23:48:08Z [DEBUG] HeadTracker: finished callback 4 in 117.7µs services/head_tracker.go:520 blockNumber=21955420 callbackIdx=4 callbackType=*services.balanceMonitor id=head_tracker time=0.0001177
2020-11-06T23:48:08Z [DEBUG] HeadTracker: finished callback 3 in 7µs services/head_tracker.go:520 blockNumber=21955420 callbackIdx=3 callbackType=*chainlink.pendingConnectionResumer id=head_tracker time=0.000007
2020-11-06T23:48:08Z [DEBUG] HeadTracker: finished callback 2 in 9.2µs services/head_tracker.go:520 blockNumber=21955420 callbackIdx=2 callbackType=*services.jobSubscriber id=head_tracker time=0.0000092
2020-11-06T23:48:08Z [DEBUG] Resuming all runs pending next block services/run_manager.go:247 currentBlockHeight=21955420
2020-11-06T23:48:08Z [DEBUG] eth.Client#BalanceAt(...) eth/client.go:301 account=0xdEE5bcB87dD8b429E0e7d86ed2C2b43aC1F9EFbf blockNumber=<nil>
2020-11-06T23:48:08Z [DEBUG] EthConfirmer: finished CheckForReceipts bulletprooftxmanager/eth_confirmer.go:92 headNum=21955420 id=eth_confirmer time=0.0010041
2020-11-06T23:48:08Z [DEBUG] EthConfirmer: finished BumpGasWhereNecessary bulletprooftxmanager/eth_confirmer.go:103 headNum=21955420 id=eth_confirmer time=0.0056902
2020-11-06T23:48:08Z [DEBUG] EthConfirmer: finished EnsureConfirmedTransactionsInLongestChain bulletprooftxmanager/eth_confirmer.go:107 headNum=21955420 id=eth_confirmer time=0.0044627
2020-11-06T23:48:08Z [DEBUG] HeadTracker: finished callback 1 in 30.5071ms services/head_tracker.go:520 blockNumber=21955420 callbackIdx=1 callbackType=*bulletprooftxmanager.ethConfirmer id=head_tracker time=0.0305071
2020-11-06T23:48:08Z [DEBUG] GasUpdater: waiting for blocks: 22/24 services/gas_updater.go:118 inHistory=22 required=24
2020-11-06T23:48:08Z [DEBUG] HeadTracker: finished callback 0 in 254.5717ms services/head_tracker.go:520 blockNumber=21955420 callbackIdx=0 callbackType=*services.gasUpdater id=head_tracker time=0.2545717
2020-11-06T23:48:08Z [DEBUG] HeadTracker finished processing head 21955420 in 269.4732ms services/head_tracker.go:330 blockNumber=21955420 id=head_tracker time=0.2694732
2020-11-06T23:48:09Z [DEBUG] DHT: Put value loghelper/logger_with_context.go:37 F=1 configDigest=017363cc8a250ae71b30107df7c0bdfd extendedLogging=true id=DHT key=/peerinfo/12D3KooWLFP41jHMfKXVyyFttspPXVhmVTQCrWrXqQDppMgHfdXm listenAddr=/ip4/0.0.0.0/tcp/6690 listenIP=0.0.0.0 listenPort=6690 peerID=12D3KooWLFP41jHMfKXVyyFttspPXVhmVTQCrWrXqQDppMgHfdXm protocolID=/cl_peer_discovery_dht/017363cc8a250ae71b30107df7c0bdfd/kad/1.0.0 value={"addrs":["/ip4/172.19.0.8/tcp/6690","/ip4/127.0.0.1/tcp/6690"],"pk":"CAESIJr9Y5O2Pl09P00uoOoiF9nfkYhi3ZrL1sL3z3zfugvY","sig":"pR7SWsW4U8bstqHOdwUvNKsIkEbCgS/ZhZzKvZiB3C+PyHfGt85LjOjsT4OsIxiAWl9GwT84J5YI2wf2nf1jCw==","timestamp":1604706489}
2020-11-06T23:48:09Z [ERROR] DHT: Error publishing address loghelper/logger_with_context.go:49 F=1 configDigest=017363cc8a250ae71b30107df7c0bdfd err=could publish address: failed to find any peer in table extendedLogging=true id=DHT listenAddr=/ip4/0.0.0.0/tcp/6690 listenIP=0.0.0.0 listenPort=6690 peerID=12D3KooWLFP41jHMfKXVyyFttspPXVhmVTQCrWrXqQDppMgHfdXm protocolID=/cl_peer_discovery_dht/017363cc8a250ae71b30107df7c0bdfd/kad/1.0.0 retryIn=10 stacktrace=github.com/smartcontractkit/libocr/offchainreporting/loghelper.LoggerWithContext.Error
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/offchainreporting/loghelper/logger_with_context.go:49
github.com/smartcontractkit/libocr/offchainreporting/loghelper.LoggerWithContext.Error
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/offchainreporting/loghelper/logger_with_context.go:49
github.com/smartcontractkit/libocr/offchainreporting/loghelper.LoggerWithContext.Error
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/offchainreporting/loghelper/logger_with_context.go:49
github.com/smartcontractkit/libocr/networking/dht-router.DHTRouter.startAnnounceInBackground.func1
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/networking/dht-router/router.go:267
github.com/smartcontractkit/libocr/subprocesses.(*Subprocesses).Go.func1
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/subprocesses/subprocesses.go:29
2020-11-06T23:48:12Z [DEBUG] Received new head #21955421 (0x14f035d) services/head_tracker.go:335 blockHash=0x05a3098ab1274cbf7839d11df152117755b35f7977d88d2255e2434fedf6f1bb blockHeight=21955421
2020-11-06T23:48:12Z [DEBUG] HeadTracker initiating callbacks services/head_tracker.go:498 chainLength=50 headNum=21955421 numCallbacks=6
2020-11-06T23:48:12Z [DEBUG] HeadTracker: finished callback 3 in 9.1µs services/head_tracker.go:520 blockNumber=21955421 callbackIdx=3 callbackType=*chainlink.pendingConnectionResumer id=head_tracker time=0.0000091
2020-11-06T23:48:12Z [DEBUG] BalanceMonitor: signalling balance worker services/balance_monitor.go:68
2020-11-06T23:48:12Z [DEBUG] HeadTracker: finished callback 4 in 107.6µs services/head_tracker.go:520 blockNumber=21955421 callbackIdx=4 callbackType=*services.balanceMonitor id=head_tracker time=0.0001076
2020-11-06T23:48:12Z [DEBUG] Resuming all runs pending next block services/run_manager.go:247 currentBlockHeight=21955421
2020-11-06T23:48:12Z [DEBUG] eth.Client#BlockByNumber(...) eth/client.go:270 number=21955418
2020-11-06T23:48:12Z [DEBUG] HeadTracker: finished callback 5 in 8.4µs services/head_tracker.go:520 blockNumber=21955421 callbackIdx=5 callbackType=*chainlink.headTrackableCallback id=head_tracker time=0.0000084
2020-11-06T23:48:12Z [DEBUG] HeadTracker: finished callback 2 in 88.2µs services/head_tracker.go:520 blockNumber=21955421 callbackIdx=2 callbackType=*services.jobSubscriber id=head_tracker time=0.0000882
2020-11-06T23:48:12Z [DEBUG] eth.Client#BalanceAt(...) eth/client.go:301 account=0xdEE5bcB87dD8b429E0e7d86ed2C2b43aC1F9EFbf blockNumber=<nil>
2020-11-06T23:48:12Z [DEBUG] EthConfirmer: finished CheckForReceipts bulletprooftxmanager/eth_confirmer.go:92 headNum=21955421 id=eth_confirmer time=0.0016897
2020-11-06T23:48:12Z [DEBUG] EthConfirmer: finished BumpGasWhereNecessary bulletprooftxmanager/eth_confirmer.go:103 headNum=21955421 id=eth_confirmer time=0.006602
2020-11-06T23:48:12Z [DEBUG] EthConfirmer: finished EnsureConfirmedTransactionsInLongestChain bulletprooftxmanager/eth_confirmer.go:107 headNum=21955421 id=eth_confirmer time=0.0039698
2020-11-06T23:48:12Z [DEBUG] HeadTracker: finished callback 1 in 39.1841ms services/head_tracker.go:520 blockNumber=21955421 callbackIdx=1 callbackType=*bulletprooftxmanager.ethConfirmer id=head_tracker time=0.0391841
2020-11-06T23:48:13Z [DEBUG] GasUpdater: skipping empty block: 21955418 services/gas_updater.go:121 blockNumber=21955418
2020-11-06T23:48:13Z [DEBUG] HeadTracker: finished callback 0 in 299.1745ms services/head_tracker.go:520 blockNumber=21955421 callbackIdx=0 callbackType=*services.gasUpdater id=head_tracker time=0.2991745
2020-11-06T23:48:13Z [DEBUG] HeadTracker finished processing head 21955421 in 313.2202ms services/head_tracker.go:330 blockNumber=21955421 id=head_tracker time=0.3132202
2020-11-06T23:48:19Z [DEBUG] DHT: Put value loghelper/logger_with_context.go:37 F=1 configDigest=017363cc8a250ae71b30107df7c0bdfd extendedLogging=true id=DHT key=/peerinfo/12D3KooWLFP41jHMfKXVyyFttspPXVhmVTQCrWrXqQDppMgHfdXm listenAddr=/ip4/0.0.0.0/tcp/6690 listenIP=0.0.0.0 listenPort=6690 peerID=12D3KooWLFP41jHMfKXVyyFttspPXVhmVTQCrWrXqQDppMgHfdXm protocolID=/cl_peer_discovery_dht/017363cc8a250ae71b30107df7c0bdfd/kad/1.0.0 value={"addrs":["/ip4/172.19.0.8/tcp/6690","/ip4/127.0.0.1/tcp/6690"],"pk":"CAESIJr9Y5O2Pl09P00uoOoiF9nfkYhi3ZrL1sL3z3zfugvY","sig":"xzso5FWjvrlJ58NjwSUYzr3tlCzShSV6UgYqEUjr6zyvXuCaIOPcuTU/8tQMH30TY07HDAkTe1wLKDS6MzWbDg==","timestamp":1604706499}
2020-11-06T23:48:19Z [ERROR] DHT: Error publishing address loghelper/logger_with_context.go:49 F=1 configDigest=017363cc8a250ae71b30107df7c0bdfd err=could publish address: failed to find any peer in table extendedLogging=true id=DHT listenAddr=/ip4/0.0.0.0/tcp/6690 listenIP=0.0.0.0 listenPort=6690 peerID=12D3KooWLFP41jHMfKXVyyFttspPXVhmVTQCrWrXqQDppMgHfdXm protocolID=/cl_peer_discovery_dht/017363cc8a250ae71b30107df7c0bdfd/kad/1.0.0 retryIn=10 stacktrace=github.com/smartcontractkit/libocr/offchainreporting/loghelper.LoggerWithContext.Error
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/offchainreporting/loghelper/logger_with_context.go:49
github.com/smartcontractkit/libocr/offchainreporting/loghelper.LoggerWithContext.Error
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/offchainreporting/loghelper/logger_with_context.go:49
github.com/smartcontractkit/libocr/offchainreporting/loghelper.LoggerWithContext.Error
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/offchainreporting/loghelper/logger_with_context.go:49
github.com/smartcontractkit/libocr/networking/dht-router.DHTRouter.startAnnounceInBackground.func1
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/networking/dht-router/router.go:267
github.com/smartcontractkit/libocr/subprocesses.(*Subprocesses).Go.func1
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/subprocesses/subprocesses.go:29
2020-11-06T23:48:21Z [DEBUG] Received new head #21955422 (0x14f035e) services/head_tracker.go:335 blockHash=0x211ff7ee5f97db6a9ba63ba454ace1e83b6c01f0d7c8fffd9402c3726363532f blockHeight=21955422
2020-11-06T23:48:21Z [DEBUG] HeadTracker initiating callbacks services/head_tracker.go:498 chainLength=50 headNum=21955422 numCallbacks=6
2020-11-06T23:48:21Z [DEBUG] HeadTracker: finished callback 2 in 6.9µs services/head_tracker.go:520 blockNumber=21955422 callbackIdx=2 callbackType=*services.jobSubscriber id=head_tracker time=0.0000069
2020-11-06T23:48:21Z [DEBUG] HeadTracker: finished callback 3 in 6.8µs services/head_tracker.go:520 blockNumber=21955422 callbackIdx=3 callbackType=*chainlink.pendingConnectionResumer id=head_tracker time=0.0000068
2020-11-06T23:48:21Z [DEBUG] BalanceMonitor: signalling balance worker services/balance_monitor.go:68
2020-11-06T23:48:21Z [DEBUG] HeadTracker: finished callback 5 in 8µs services/head_tracker.go:520 blockNumber=21955422 callbackIdx=5 callbackType=*chainlink.headTrackableCallback id=head_tracker time=0.000008
2020-11-06T23:48:21Z [DEBUG] HeadTracker: finished callback 4 in 331.6µs services/head_tracker.go:520 blockNumber=21955422 callbackIdx=4 callbackType=*services.balanceMonitor id=head_tracker time=0.0003316
2020-11-06T23:48:21Z [DEBUG] Resuming all runs pending next block services/run_manager.go:247 currentBlockHeight=21955422
2020-11-06T23:48:21Z [DEBUG] eth.Client#BlockByNumber(...) eth/client.go:270 number=21955419
2020-11-06T23:48:21Z [DEBUG] eth.Client#BalanceAt(...) eth/client.go:301 account=0xdEE5bcB87dD8b429E0e7d86ed2C2b43aC1F9EFbf blockNumber=<nil>
2020-11-06T23:48:21Z [DEBUG] EthConfirmer: finished CheckForReceipts bulletprooftxmanager/eth_confirmer.go:92 headNum=21955422 id=eth_confirmer time=0.0006694
2020-11-06T23:48:21Z [DEBUG] EthConfirmer: finished BumpGasWhereNecessary bulletprooftxmanager/eth_confirmer.go:103 headNum=21955422 id=eth_confirmer time=0.0044867
2020-11-06T23:48:21Z [DEBUG] EthConfirmer: finished EnsureConfirmedTransactionsInLongestChain bulletprooftxmanager/eth_confirmer.go:107 headNum=21955422 id=eth_confirmer time=0.0033931
2020-11-06T23:48:21Z [DEBUG] HeadTracker: finished callback 1 in 13.5033ms services/head_tracker.go:520 blockNumber=21955422 callbackIdx=1 callbackType=*bulletprooftxmanager.ethConfirmer id=head_tracker time=0.0135033
2020-11-06T23:48:21Z [DEBUG] GasUpdater: skipping empty block: 21955419 services/gas_updater.go:121 blockNumber=21955419
2020-11-06T23:48:21Z [DEBUG] HeadTracker: finished callback 0 in 256.731ms services/head_tracker.go:520 blockNumber=21955422 callbackIdx=0 callbackType=*services.gasUpdater id=head_tracker time=0.256731
2020-11-06T23:48:21Z [DEBUG] HeadTracker finished processing head 21955422 in 267.9233ms services/head_tracker.go:330 blockNumber=21955422 id=head_tracker time=0.2679233
2020-11-06T23:48:24Z [DEBUG] Received new head #21955423 (0x14f035f) services/head_tracker.go:335 blockHash=0xa06bf8b8f965caee1f1437a3e31e821085bf49cf8d52e3d9df866262864237bd blockHeight=21955423
2020-11-06T23:48:24Z [DEBUG] HeadTracker initiating callbacks services/head_tracker.go:498 chainLength=50 headNum=21955423 numCallbacks=6
2020-11-06T23:48:24Z [DEBUG] HeadTracker: finished callback 5 in 7.3µs services/head_tracker.go:520 blockNumber=21955423 callbackIdx=5 callbackType=*chainlink.headTrackableCallback id=head_tracker time=0.0000073
2020-11-06T23:48:24Z [DEBUG] eth.Client#BlockByNumber(...) eth/client.go:270 number=21955420
2020-11-06T23:48:24Z [DEBUG] HeadTracker: finished callback 3 in 11.4µs services/head_tracker.go:520 blockNumber=21955423 callbackIdx=3 callbackType=*chainlink.pendingConnectionResumer id=head_tracker time=0.0000114
2020-11-06T23:48:24Z [DEBUG] HeadTracker: finished callback 2 in 24.5µs services/head_tracker.go:520 blockNumber=21955423 callbackIdx=2 callbackType=*services.jobSubscriber id=head_tracker time=0.0000245
2020-11-06T23:48:24Z [DEBUG] Resuming all runs pending next block services/run_manager.go:247 currentBlockHeight=21955423
2020-11-06T23:48:24Z [DEBUG] BalanceMonitor: signalling balance worker services/balance_monitor.go:68
2020-11-06T23:48:24Z [DEBUG] HeadTracker: finished callback 4 in 604.3µs services/head_tracker.go:520 blockNumber=21955423 callbackIdx=4 callbackType=*services.balanceMonitor id=head_tracker time=0.0006043
2020-11-06T23:48:24Z [DEBUG] eth.Client#BalanceAt(...) eth/client.go:301 account=0xdEE5bcB87dD8b429E0e7d86ed2C2b43aC1F9EFbf blockNumber=<nil>
2020-11-06T23:48:24Z [DEBUG] EthConfirmer: finished CheckForReceipts bulletprooftxmanager/eth_confirmer.go:92 headNum=21955423 id=eth_confirmer time=0.0013946
2020-11-06T23:48:24Z [DEBUG] EthConfirmer: finished BumpGasWhereNecessary bulletprooftxmanager/eth_confirmer.go:103 headNum=21955423 id=eth_confirmer time=0.0094499
2020-11-06T23:48:24Z [DEBUG] EthConfirmer: finished EnsureConfirmedTransactionsInLongestChain bulletprooftxmanager/eth_confirmer.go:107 headNum=21955423 id=eth_confirmer time=0.0079011
2020-11-06T23:48:24Z [DEBUG] HeadTracker: finished callback 1 in 39.9136ms services/head_tracker.go:520 blockNumber=21955423 callbackIdx=1 callbackType=*bulletprooftxmanager.ethConfirmer id=head_tracker time=0.0399136
2020-11-06T23:48:24Z [DEBUG] GasUpdater: waiting for blocks: 23/24 services/gas_updater.go:118 inHistory=23 required=24
2020-11-06T23:48:24Z [DEBUG] HeadTracker: finished callback 0 in 257.2016ms services/head_tracker.go:520 blockNumber=21955423 callbackIdx=0 callbackType=*services.gasUpdater id=head_tracker time=0.2572016
2020-11-06T23:48:24Z [DEBUG] HeadTracker finished processing head 21955423 in 268.7252ms services/head_tracker.go:330 blockNumber=21955423 id=head_tracker time=0.2687252
2020-11-06T23:48:28Z [DEBUG] Received new head #21955424 (0x14f0360) services/head_tracker.go:335 blockHash=0xa872c723c83366b01f425236ac4c29b55c74141d70ef143ae39749a383341775 blockHeight=21955424
2020-11-06T23:48:28Z [DEBUG] HeadTracker initiating callbacks services/head_tracker.go:498 chainLength=50 headNum=21955424 numCallbacks=6
2020-11-06T23:48:28Z [DEBUG] HeadTracker: finished callback 5 in 9.6µs services/head_tracker.go:520 blockNumber=21955424 callbackIdx=5 callbackType=*chainlink.headTrackableCallback id=head_tracker time=0.0000096
2020-11-06T23:48:28Z [DEBUG] Resuming all runs pending next block services/run_manager.go:247 currentBlockHeight=21955424
2020-11-06T23:48:28Z [DEBUG] HeadTracker: finished callback 2 in 11.6µs services/head_tracker.go:520 blockNumber=21955424 callbackIdx=2 callbackType=*services.jobSubscriber id=head_tracker time=0.0000116
2020-11-06T23:48:28Z [DEBUG] HeadTracker: finished callback 3 in 10.3µs services/head_tracker.go:520 blockNumber=21955424 callbackIdx=3 callbackType=*chainlink.pendingConnectionResumer id=head_tracker time=0.0000103
2020-11-06T23:48:28Z [DEBUG] eth.Client#BlockByNumber(...) eth/client.go:270 number=21955421
2020-11-06T23:48:28Z [DEBUG] BalanceMonitor: signalling balance worker services/balance_monitor.go:68
2020-11-06T23:48:28Z [DEBUG] HeadTracker: finished callback 4 in 777.6µs services/head_tracker.go:520 blockNumber=21955424 callbackIdx=4 callbackType=*services.balanceMonitor id=head_tracker time=0.0007776
2020-11-06T23:48:28Z [DEBUG] eth.Client#BalanceAt(...) eth/client.go:301 account=0xdEE5bcB87dD8b429E0e7d86ed2C2b43aC1F9EFbf blockNumber=<nil>
2020-11-06T23:48:28Z [DEBUG] EthConfirmer: finished CheckForReceipts bulletprooftxmanager/eth_confirmer.go:92 headNum=21955424 id=eth_confirmer time=0.0010133
2020-11-06T23:48:28Z [DEBUG] EthConfirmer: finished BumpGasWhereNecessary bulletprooftxmanager/eth_confirmer.go:103 headNum=21955424 id=eth_confirmer time=0.0082
2020-11-06T23:48:28Z [DEBUG] EthConfirmer: finished EnsureConfirmedTransactionsInLongestChain bulletprooftxmanager/eth_confirmer.go:107 headNum=21955424 id=eth_confirmer time=0.0043906
2020-11-06T23:48:28Z [DEBUG] HeadTracker: finished callback 1 in 35.2974ms services/head_tracker.go:520 blockNumber=21955424 callbackIdx=1 callbackType=*bulletprooftxmanager.ethConfirmer id=head_tracker time=0.0352974
2020-11-06T23:48:28Z [DEBUG] GasUpdater: skipping empty block: 21955421 services/gas_updater.go:121 blockNumber=21955421
2020-11-06T23:48:28Z [DEBUG] HeadTracker: finished callback 0 in 308.2585ms services/head_tracker.go:520 blockNumber=21955424 callbackIdx=0 callbackType=*services.gasUpdater id=head_tracker time=0.3082585
2020-11-06T23:48:28Z [DEBUG] HeadTracker finished processing head 21955424 in 317.7638ms services/head_tracker.go:330 blockNumber=21955424 id=head_tracker time=0.3177638
2020-11-06T23:48:29Z [DEBUG] DHT: Put value loghelper/logger_with_context.go:37 F=1 configDigest=017363cc8a250ae71b30107df7c0bdfd extendedLogging=true id=DHT key=/peerinfo/12D3KooWLFP41jHMfKXVyyFttspPXVhmVTQCrWrXqQDppMgHfdXm listenAddr=/ip4/0.0.0.0/tcp/6690 listenIP=0.0.0.0 listenPort=6690 peerID=12D3KooWLFP41jHMfKXVyyFttspPXVhmVTQCrWrXqQDppMgHfdXm protocolID=/cl_peer_discovery_dht/017363cc8a250ae71b30107df7c0bdfd/kad/1.0.0 value={"addrs":["/ip4/172.19.0.8/tcp/6690","/ip4/127.0.0.1/tcp/6690"],"pk":"CAESIJr9Y5O2Pl09P00uoOoiF9nfkYhi3ZrL1sL3z3zfugvY","sig":"6S76gP5/rQrkirpjR6bGP2elkwti2/zBiiThegQVBHL9tW0ULkPw0T2vBt42SkZpWbIAaO3axYgfzBtYLrxQCw==","timestamp":1604706509}
2020-11-06T23:48:29Z [ERROR] DHT: Error publishing address loghelper/logger_with_context.go:49 F=1 configDigest=017363cc8a250ae71b30107df7c0bdfd err=could publish address: failed to find any peer in table extendedLogging=true id=DHT listenAddr=/ip4/0.0.0.0/tcp/6690 listenIP=0.0.0.0 listenPort=6690 peerID=12D3KooWLFP41jHMfKXVyyFttspPXVhmVTQCrWrXqQDppMgHfdXm protocolID=/cl_peer_discovery_dht/017363cc8a250ae71b30107df7c0bdfd/kad/1.0.0 retryIn=10 stacktrace=github.com/smartcontractkit/libocr/offchainreporting/loghelper.LoggerWithContext.Error
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/offchainreporting/loghelper/logger_with_context.go:49
github.com/smartcontractkit/libocr/offchainreporting/loghelper.LoggerWithContext.Error
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/offchainreporting/loghelper/logger_with_context.go:49
github.com/smartcontractkit/libocr/offchainreporting/loghelper.LoggerWithContext.Error
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/offchainreporting/loghelper/logger_with_context.go:49
github.com/smartcontractkit/libocr/networking/dht-router.DHTRouter.startAnnounceInBackground.func1
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/networking/dht-router/router.go:267
github.com/smartcontractkit/libocr/subprocesses.(*Subprocesses).Go.func1
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/subprocesses/subprocesses.go:29
2020-11-06T23:48:32Z [DEBUG] Received new head #21955425 (0x14f0361) services/head_tracker.go:335 blockHash=0x2a263dfc68135f1f8e4433d8d7cf8f451cc405f497f29753799c48a4a0068872 blockHeight=21955425
2020-11-06T23:48:32Z [DEBUG] HeadTracker initiating callbacks services/head_tracker.go:498 chainLength=50 headNum=21955425 numCallbacks=6
2020-11-06T23:48:32Z [DEBUG] HeadTracker: finished callback 5 in 8.4µs services/head_tracker.go:520 blockNumber=21955425 callbackIdx=5 callbackType=*chainlink.headTrackableCallback id=head_tracker time=0.0000084
2020-11-06T23:48:32Z [DEBUG] HeadTracker: finished callback 2 in 13.2µs services/head_tracker.go:520 blockNumber=21955425 callbackIdx=2 callbackType=*services.jobSubscriber id=head_tracker time=0.0000132
2020-11-06T23:48:32Z [DEBUG] eth.Client#BlockByNumber(...) eth/client.go:270 number=21955422
2020-11-06T23:48:32Z [DEBUG] Resuming all runs pending next block services/run_manager.go:247 currentBlockHeight=21955425
2020-11-06T23:48:32Z [DEBUG] BalanceMonitor: signalling balance worker services/balance_monitor.go:68
2020-11-06T23:48:32Z [DEBUG] HeadTracker: finished callback 3 in 7.5µs services/head_tracker.go:520 blockNumber=21955425 callbackIdx=3 callbackType=*chainlink.pendingConnectionResumer id=head_tracker time=0.0000075
2020-11-06T23:48:32Z [DEBUG] HeadTracker: finished callback 4 in 450.6µs services/head_tracker.go:520 blockNumber=21955425 callbackIdx=4 callbackType=*services.balanceMonitor id=head_tracker time=0.0004506
2020-11-06T23:48:32Z [DEBUG] eth.Client#BalanceAt(...) eth/client.go:301 account=0xdEE5bcB87dD8b429E0e7d86ed2C2b43aC1F9EFbf blockNumber=<nil>
2020-11-06T23:48:32Z [DEBUG] EthConfirmer: finished CheckForReceipts bulletprooftxmanager/eth_confirmer.go:92 headNum=21955425 id=eth_confirmer time=0.0007605
2020-11-06T23:48:32Z [DEBUG] EthConfirmer: finished BumpGasWhereNecessary bulletprooftxmanager/eth_confirmer.go:103 headNum=21955425 id=eth_confirmer time=0.0066239
2020-11-06T23:48:32Z [DEBUG] EthConfirmer: finished EnsureConfirmedTransactionsInLongestChain bulletprooftxmanager/eth_confirmer.go:107 headNum=21955425 id=eth_confirmer time=0.0038212
2020-11-06T23:48:32Z [DEBUG] HeadTracker: finished callback 1 in 16.1904ms services/head_tracker.go:520 blockNumber=21955425 callbackIdx=1 callbackType=*bulletprooftxmanager.ethConfirmer id=head_tracker time=0.0161904
2020-11-06T23:48:32Z [DEBUG] GasUpdater: skipping empty block: 21955422 services/gas_updater.go:121 blockNumber=21955422
2020-11-06T23:48:32Z [DEBUG] HeadTracker: finished callback 0 in 267.1697ms services/head_tracker.go:520 blockNumber=21955425 callbackIdx=0 callbackType=*services.gasUpdater id=head_tracker time=0.2671697
2020-11-06T23:48:32Z [DEBUG] HeadTracker finished processing head 21955425 in 279.0414ms services/head_tracker.go:330 blockNumber=21955425 id=head_tracker time=0.2790414
2020-11-06T23:48:39Z [DEBUG] DHT: Put value loghelper/logger_with_context.go:37 F=1 configDigest=017363cc8a250ae71b30107df7c0bdfd extendedLogging=true id=DHT key=/peerinfo/12D3KooWLFP41jHMfKXVyyFttspPXVhmVTQCrWrXqQDppMgHfdXm listenAddr=/ip4/0.0.0.0/tcp/6690 listenIP=0.0.0.0 listenPort=6690 peerID=12D3KooWLFP41jHMfKXVyyFttspPXVhmVTQCrWrXqQDppMgHfdXm protocolID=/cl_peer_discovery_dht/017363cc8a250ae71b30107df7c0bdfd/kad/1.0.0 value={"addrs":["/ip4/172.19.0.8/tcp/6690","/ip4/127.0.0.1/tcp/6690"],"pk":"CAESIJr9Y5O2Pl09P00uoOoiF9nfkYhi3ZrL1sL3z3zfugvY","sig":"4I+GJxMSWgkcsxqWyqwekV8wkysRu3NKYzdhM+lWeKr6RXv+TMo/smGOy37DnTsyKulN+BDpW4nL312Dsk/IBA==","timestamp":1604706519}
2020-11-06T23:48:39Z [ERROR] DHT: Error publishing address loghelper/logger_with_context.go:49 F=1 configDigest=017363cc8a250ae71b30107df7c0bdfd err=could publish address: failed to find any peer in table extendedLogging=true id=DHT listenAddr=/ip4/0.0.0.0/tcp/6690 listenIP=0.0.0.0 listenPort=6690 peerID=12D3KooWLFP41jHMfKXVyyFttspPXVhmVTQCrWrXqQDppMgHfdXm protocolID=/cl_peer_discovery_dht/017363cc8a250ae71b30107df7c0bdfd/kad/1.0.0 retryIn=10 stacktrace=github.com/smartcontractkit/libocr/offchainreporting/loghelper.LoggerWithContext.Error
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/offchainreporting/loghelper/logger_with_context.go:49
github.com/smartcontractkit/libocr/offchainreporting/loghelper.LoggerWithContext.Error
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/offchainreporting/loghelper/logger_with_context.go:49
github.com/smartcontractkit/libocr/offchainreporting/loghelper.LoggerWithContext.Error
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/offchainreporting/loghelper/logger_with_context.go:49
github.com/smartcontractkit/libocr/networking/dht-router.DHTRouter.startAnnounceInBackground.func1
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/networking/dht-router/router.go:267
github.com/smartcontractkit/libocr/subprocesses.(*Subprocesses).Go.func1
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/subprocesses/subprocesses.go:29
2020-11-06T23:48:41Z [DEBUG] Received new head #21955426 (0x14f0362) services/head_tracker.go:335 blockHash=0x727a2a3a6eee2073babd070808447ce6fa953edb284689535093b48fad71b713 blockHeight=21955426
2020-11-06T23:48:41Z [DEBUG] HeadTracker initiating callbacks services/head_tracker.go:498 chainLength=50 headNum=21955426 numCallbacks=6
2020-11-06T23:48:41Z [DEBUG] HeadTracker: finished callback 5 in 8.6µs services/head_tracker.go:520 blockNumber=21955426 callbackIdx=5 callbackType=*chainlink.headTrackableCallback id=head_tracker time=0.0000086
2020-11-06T23:48:41Z [DEBUG] eth.Client#BlockByNumber(...) eth/client.go:270 number=21955423
2020-11-06T23:48:41Z [DEBUG] HeadTracker: finished callback 3 in 10.5µs services/head_tracker.go:520 blockNumber=21955426 callbackIdx=3 callbackType=*chainlink.pendingConnectionResumer id=head_tracker time=0.0000105
2020-11-06T23:48:41Z [DEBUG] BalanceMonitor: signalling balance worker services/balance_monitor.go:68
2020-11-06T23:48:41Z [DEBUG] Resuming all runs pending next block services/run_manager.go:247 currentBlockHeight=21955426
2020-11-06T23:48:41Z [DEBUG] HeadTracker: finished callback 4 in 77.7µs services/head_tracker.go:520 blockNumber=21955426 callbackIdx=4 callbackType=*services.balanceMonitor id=head_tracker time=0.0000777
2020-11-06T23:48:41Z [DEBUG] HeadTracker: finished callback 2 in 33µs services/head_tracker.go:520 blockNumber=21955426 callbackIdx=2 callbackType=*services.jobSubscriber id=head_tracker time=0.000033
2020-11-06T23:48:41Z [DEBUG] eth.Client#BalanceAt(...) eth/client.go:301 account=0xdEE5bcB87dD8b429E0e7d86ed2C2b43aC1F9EFbf blockNumber=<nil>
2020-11-06T23:48:41Z [DEBUG] EthConfirmer: finished CheckForReceipts bulletprooftxmanager/eth_confirmer.go:92 headNum=21955426 id=eth_confirmer time=0.0014102
2020-11-06T23:48:41Z [DEBUG] EthConfirmer: finished BumpGasWhereNecessary bulletprooftxmanager/eth_confirmer.go:103 headNum=21955426 id=eth_confirmer time=0.0084231
2020-11-06T23:48:41Z [DEBUG] EthConfirmer: finished EnsureConfirmedTransactionsInLongestChain bulletprooftxmanager/eth_confirmer.go:107 headNum=21955426 id=eth_confirmer time=0.0047495
2020-11-06T23:48:41Z [DEBUG] HeadTracker: finished callback 1 in 21.2177ms services/head_tracker.go:520 blockNumber=21955426 callbackIdx=1 callbackType=*bulletprooftxmanager.ethConfirmer id=head_tracker time=0.0212177
2020-11-06T23:48:41Z [DEBUG] GasUpdater: skipping empty block: 21955423 services/gas_updater.go:121 blockNumber=21955423
2020-11-06T23:48:41Z [DEBUG] HeadTracker: finished callback 0 in 262.0107ms services/head_tracker.go:520 blockNumber=21955426 callbackIdx=0 callbackType=*services.gasUpdater id=head_tracker time=0.2620107
2020-11-06T23:48:41Z [DEBUG] HeadTracker finished processing head 21955426 in 272.7444ms services/head_tracker.go:330 blockNumber=21955426 id=head_tracker time=0.2727444
2020-11-06T23:48:44Z [DEBUG] Received new head #21955427 (0x14f0363) services/head_tracker.go:335 blockHash=0xf8e6521b7f06df6bce60fda940d69fe7de6cec15e48b4ce29a882a8da8d22d5a blockHeight=21955427
2020-11-06T23:48:44Z [DEBUG] HeadTracker initiating callbacks services/head_tracker.go:498 chainLength=50 headNum=21955427 numCallbacks=6
2020-11-06T23:48:44Z [DEBUG] HeadTracker: finished callback 2 in 27.8µs services/head_tracker.go:520 blockNumber=21955427 callbackIdx=2 callbackType=*services.jobSubscriber id=head_tracker time=0.0000278
2020-11-06T23:48:44Z [DEBUG] HeadTracker: finished callback 5 in 25.6µs services/head_tracker.go:520 blockNumber=21955427 callbackIdx=5 callbackType=*chainlink.headTrackableCallback id=head_tracker time=0.0000256
2020-11-06T23:48:44Z [DEBUG] eth.Client#BlockByNumber(...) eth/client.go:270 number=21955424
2020-11-06T23:48:44Z [DEBUG] BalanceMonitor: signalling balance worker services/balance_monitor.go:68
2020-11-06T23:48:44Z [DEBUG] HeadTracker: finished callback 4 in 1.147ms services/head_tracker.go:520 blockNumber=21955427 callbackIdx=4 callbackType=*services.balanceMonitor id=head_tracker time=0.001147
2020-11-06T23:48:44Z [DEBUG] Resuming all runs pending next block services/run_manager.go:247 currentBlockHeight=21955427
2020-11-06T23:48:44Z [DEBUG] HeadTracker: finished callback 3 in 12.5µs services/head_tracker.go:520 blockNumber=21955427 callbackIdx=3 callbackType=*chainlink.pendingConnectionResumer id=head_tracker time=0.0000125
2020-11-06T23:48:44Z [DEBUG] eth.Client#BalanceAt(...) eth/client.go:301 account=0xdEE5bcB87dD8b429E0e7d86ed2C2b43aC1F9EFbf blockNumber=<nil>
2020-11-06T23:48:44Z [DEBUG] EthConfirmer: finished CheckForReceipts bulletprooftxmanager/eth_confirmer.go:92 headNum=21955427 id=eth_confirmer time=0.0010693
2020-11-06T23:48:44Z [DEBUG] EthConfirmer: finished BumpGasWhereNecessary bulletprooftxmanager/eth_confirmer.go:103 headNum=21955427 id=eth_confirmer time=0.011417
2020-11-06T23:48:44Z [DEBUG] EthConfirmer: finished EnsureConfirmedTransactionsInLongestChain bulletprooftxmanager/eth_confirmer.go:107 headNum=21955427 id=eth_confirmer time=0.0102772
2020-11-06T23:48:44Z [DEBUG] HeadTracker: finished callback 1 in 72.9593ms services/head_tracker.go:520 blockNumber=21955427 callbackIdx=1 callbackType=*bulletprooftxmanager.ethConfirmer id=head_tracker time=0.0729593
2020-11-06T23:48:44Z [DEBUG] GasUpdater: waiting for blocks: 24/24 services/gas_updater.go:118 inHistory=24 required=24
2020-11-06T23:48:44Z [DEBUG] HeadTracker: finished callback 0 in 281.4832ms services/head_tracker.go:520 blockNumber=21955427 callbackIdx=0 callbackType=*services.gasUpdater id=head_tracker time=0.2814832
2020-11-06T23:48:44Z [DEBUG] HeadTracker finished processing head 21955427 in 312.891ms services/head_tracker.go:330 blockNumber=21955427 id=head_tracker time=0.312891
2020-11-06T23:48:48Z [DEBUG] Received new head #21955428 (0x14f0364) services/head_tracker.go:335 blockHash=0xde1527a60873d372f6b2179438eba891bc36b8767d3d478d70922fa25845816d blockHeight=21955428
2020-11-06T23:48:48Z [DEBUG] HeadTracker initiating callbacks services/head_tracker.go:498 chainLength=50 headNum=21955428 numCallbacks=6
2020-11-06T23:48:48Z [DEBUG] HeadTracker: finished callback 5 in 10.8µs services/head_tracker.go:520 blockNumber=21955428 callbackIdx=5 callbackType=*chainlink.headTrackableCallback id=head_tracker time=0.0000108
2020-11-06T23:48:48Z [DEBUG] HeadTracker: finished callback 3 in 15.5µs services/head_tracker.go:520 blockNumber=21955428 callbackIdx=3 callbackType=*chainlink.pendingConnectionResumer id=head_tracker time=0.0000155
2020-11-06T23:48:48Z [DEBUG] BalanceMonitor: signalling balance worker services/balance_monitor.go:68
2020-11-06T23:48:48Z [DEBUG] HeadTracker: finished callback 4 in 155.8µs services/head_tracker.go:520 blockNumber=21955428 callbackIdx=4 callbackType=*services.balanceMonitor id=head_tracker time=0.0001558
2020-11-06T23:48:48Z [DEBUG] eth.Client#BlockByNumber(...) eth/client.go:270 number=21955425
2020-11-06T23:48:48Z [DEBUG] Resuming all runs pending next block services/run_manager.go:247 currentBlockHeight=21955428
2020-11-06T23:48:48Z [DEBUG] eth.Client#BalanceAt(...) eth/client.go:301 account=0xdEE5bcB87dD8b429E0e7d86ed2C2b43aC1F9EFbf blockNumber=<nil>
2020-11-06T23:48:48Z [DEBUG] HeadTracker: finished callback 2 in 41.7µs services/head_tracker.go:520 blockNumber=21955428 callbackIdx=2 callbackType=*services.jobSubscriber id=head_tracker time=0.0000417
2020-11-06T23:48:48Z [DEBUG] EthConfirmer: finished CheckForReceipts bulletprooftxmanager/eth_confirmer.go:92 headNum=21955428 id=eth_confirmer time=0.001302
2020-11-06T23:48:48Z [DEBUG] EthConfirmer: finished BumpGasWhereNecessary bulletprooftxmanager/eth_confirmer.go:103 headNum=21955428 id=eth_confirmer time=0.0142522
2020-11-06T23:48:48Z [DEBUG] EthConfirmer: finished EnsureConfirmedTransactionsInLongestChain bulletprooftxmanager/eth_confirmer.go:107 headNum=21955428 id=eth_confirmer time=0.0080839
2020-11-06T23:48:48Z [DEBUG] HeadTracker: finished callback 1 in 38.8159ms services/head_tracker.go:520 blockNumber=21955428 callbackIdx=1 callbackType=*bulletprooftxmanager.ethConfirmer id=head_tracker time=0.0388159
2020-11-06T23:48:48Z [DEBUG] GasUpdater: setting new default gas price: 1.30 Gwei services/gas_updater.go:147 gasPriceGWei=1.30 gasPriceWei=1300000000
2020-11-06T23:48:48Z [DEBUG] HeadTracker: finished callback 0 in 271.1763ms services/head_tracker.go:520 blockNumber=21955428 callbackIdx=0 callbackType=*services.gasUpdater id=head_tracker time=0.2711763
2020-11-06T23:48:48Z [DEBUG] HeadTracker finished processing head 21955428 in 297.8579ms services/head_tracker.go:330 blockNumber=21955428 id=head_tracker time=0.2978579
2020-11-06T23:48:49Z [DEBUG] DHT: Put value loghelper/logger_with_context.go:37 F=1 configDigest=017363cc8a250ae71b30107df7c0bdfd extendedLogging=true id=DHT key=/peerinfo/12D3KooWLFP41jHMfKXVyyFttspPXVhmVTQCrWrXqQDppMgHfdXm listenAddr=/ip4/0.0.0.0/tcp/6690 listenIP=0.0.0.0 listenPort=6690 peerID=12D3KooWLFP41jHMfKXVyyFttspPXVhmVTQCrWrXqQDppMgHfdXm protocolID=/cl_peer_discovery_dht/017363cc8a250ae71b30107df7c0bdfd/kad/1.0.0 value={"addrs":["/ip4/172.19.0.8/tcp/6690","/ip4/127.0.0.1/tcp/6690"],"pk":"CAESIJr9Y5O2Pl09P00uoOoiF9nfkYhi3ZrL1sL3z3zfugvY","sig":"Yv2vPKVLa4u3UbqG1/tZqcIN0C9ZErh3k+JdWYqOSBf4VvlzKy8pfRtGv84jsfGZPeUnhZQabMF04ojqSZYqBA==","timestamp":1604706529}
2020-11-06T23:48:49Z [ERROR] DHT: Error publishing address loghelper/logger_with_context.go:49 F=1 configDigest=017363cc8a250ae71b30107df7c0bdfd err=could publish address: failed to find any peer in table extendedLogging=true id=DHT listenAddr=/ip4/0.0.0.0/tcp/6690 listenIP=0.0.0.0 listenPort=6690 peerID=12D3KooWLFP41jHMfKXVyyFttspPXVhmVTQCrWrXqQDppMgHfdXm protocolID=/cl_peer_discovery_dht/017363cc8a250ae71b30107df7c0bdfd/kad/1.0.0 retryIn=10 stacktrace=github.com/smartcontractkit/libocr/offchainreporting/loghelper.LoggerWithContext.Error
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/offchainreporting/loghelper/logger_with_context.go:49
github.com/smartcontractkit/libocr/offchainreporting/loghelper.LoggerWithContext.Error
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/offchainreporting/loghelper/logger_with_context.go:49
github.com/smartcontractkit/libocr/offchainreporting/loghelper.LoggerWithContext.Error
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/offchainreporting/loghelper/logger_with_context.go:49
github.com/smartcontractkit/libocr/networking/dht-router.DHTRouter.startAnnounceInBackground.func1
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/networking/dht-router/router.go:267
github.com/smartcontractkit/libocr/subprocesses.(*Subprocesses).Go.func1
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/subprocesses/subprocesses.go:29
2020-11-06T23:48:53Z [DEBUG] Received new head #21955429 (0x14f0365) services/head_tracker.go:335 blockHash=0x62965370bb2e7aa80e3e7373cbc7f8484ffa4626fdfeb1d7310f5246c93105f6 blockHeight=21955429
2020-11-06T23:48:53Z [DEBUG] HeadTracker initiating callbacks services/head_tracker.go:498 chainLength=50 headNum=21955429 numCallbacks=6
2020-11-06T23:48:53Z [DEBUG] HeadTracker: finished callback 5 in 7.8µs services/head_tracker.go:520 blockNumber=21955429 callbackIdx=5 callbackType=*chainlink.headTrackableCallback id=head_tracker time=0.0000078
2020-11-06T23:48:53Z [DEBUG] HeadTracker: finished callback 3 in 7.8µs services/head_tracker.go:520 blockNumber=21955429 callbackIdx=3 callbackType=*chainlink.pendingConnectionResumer id=head_tracker time=0.0000078
2020-11-06T23:48:53Z [DEBUG] Resuming all runs pending next block services/run_manager.go:247 currentBlockHeight=21955429
2020-11-06T23:48:53Z [DEBUG] eth.Client#BlockByNumber(...) eth/client.go:270 number=21955426
2020-11-06T23:48:53Z [DEBUG] HeadTracker: finished callback 2 in 6.8µs services/head_tracker.go:520 blockNumber=21955429 callbackIdx=2 callbackType=*services.jobSubscriber id=head_tracker time=0.0000068
2020-11-06T23:48:53Z [DEBUG] BalanceMonitor: signalling balance worker services/balance_monitor.go:68
2020-11-06T23:48:53Z [DEBUG] HeadTracker: finished callback 4 in 1.1573ms services/head_tracker.go:520 blockNumber=21955429 callbackIdx=4 callbackType=*services.balanceMonitor id=head_tracker time=0.0011573
2020-11-06T23:48:53Z [DEBUG] EthConfirmer: finished CheckForReceipts bulletprooftxmanager/eth_confirmer.go:92 headNum=21955429 id=eth_confirmer time=0.0007768
2020-11-06T23:48:53Z [DEBUG] EthConfirmer: finished BumpGasWhereNecessary bulletprooftxmanager/eth_confirmer.go:103 headNum=21955429 id=eth_confirmer time=0.0049943
2020-11-06T23:48:53Z [DEBUG] eth.Client#BalanceAt(...) eth/client.go:301 account=0xdEE5bcB87dD8b429E0e7d86ed2C2b43aC1F9EFbf blockNumber=<nil>
2020-11-06T23:48:53Z [DEBUG] EthConfirmer: finished EnsureConfirmedTransactionsInLongestChain bulletprooftxmanager/eth_confirmer.go:107 headNum=21955429 id=eth_confirmer time=0.0053103
2020-11-06T23:48:53Z [DEBUG] HeadTracker: finished callback 1 in 15.6223ms services/head_tracker.go:520 blockNumber=21955429 callbackIdx=1 callbackType=*bulletprooftxmanager.ethConfirmer id=head_tracker time=0.0156223
2020-11-06T23:48:53Z [DEBUG] GasUpdater: setting new default gas price: 1.30 Gwei services/gas_updater.go:147 gasPriceGWei=1.30 gasPriceWei=1300000000
2020-11-06T23:48:53Z [DEBUG] HeadTracker: finished callback 0 in 261.8152ms services/head_tracker.go:520 blockNumber=21955429 callbackIdx=0 callbackType=*services.gasUpdater id=head_tracker time=0.2618152
2020-11-06T23:48:53Z [DEBUG] HeadTracker finished processing head 21955429 in 270.5155ms services/head_tracker.go:330 blockNumber=21955429 id=head_tracker time=0.2705155
2020-11-06T23:48:59Z [DEBUG] DHT: Put value loghelper/logger_with_context.go:37 F=1 configDigest=017363cc8a250ae71b30107df7c0bdfd extendedLogging=true id=DHT key=/peerinfo/12D3KooWLFP41jHMfKXVyyFttspPXVhmVTQCrWrXqQDppMgHfdXm listenAddr=/ip4/0.0.0.0/tcp/6690 listenIP=0.0.0.0 listenPort=6690 peerID=12D3KooWLFP41jHMfKXVyyFttspPXVhmVTQCrWrXqQDppMgHfdXm protocolID=/cl_peer_discovery_dht/017363cc8a250ae71b30107df7c0bdfd/kad/1.0.0 value={"addrs":["/ip4/172.19.0.8/tcp/6690","/ip4/127.0.0.1/tcp/6690"],"pk":"CAESIJr9Y5O2Pl09P00uoOoiF9nfkYhi3ZrL1sL3z3zfugvY","sig":"i+gsyLFLGJxG7I6CbBI0mXhMihg6+a7oieM0Js4ESAWXlDewsKcD/aGrrY5SNMX6O81jEQFuB9g6DKyiMvSfDQ==","timestamp":1604706539}
2020-11-06T23:48:59Z [ERROR] DHT: Error publishing address loghelper/logger_with_context.go:49 F=1 configDigest=017363cc8a250ae71b30107df7c0bdfd err=could publish address: failed to find any peer in table extendedLogging=true id=DHT listenAddr=/ip4/0.0.0.0/tcp/6690 listenIP=0.0.0.0 listenPort=6690 peerID=12D3KooWLFP41jHMfKXVyyFttspPXVhmVTQCrWrXqQDppMgHfdXm protocolID=/cl_peer_discovery_dht/017363cc8a250ae71b30107df7c0bdfd/kad/1.0.0 retryIn=10 stacktrace=github.com/smartcontractkit/libocr/offchainreporting/loghelper.LoggerWithContext.Error
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/offchainreporting/loghelper/logger_with_context.go:49
github.com/smartcontractkit/libocr/offchainreporting/loghelper.LoggerWithContext.Error
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/offchainreporting/loghelper/logger_with_context.go:49
github.com/smartcontractkit/libocr/offchainreporting/loghelper.LoggerWithContext.Error
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/offchainreporting/loghelper/logger_with_context.go:49
github.com/smartcontractkit/libocr/networking/dht-router.DHTRouter.startAnnounceInBackground.func1
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/networking/dht-router/router.go:267
github.com/smartcontractkit/libocr/subprocesses.(*Subprocesses).Go.func1
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/subprocesses/subprocesses.go:29
2020-11-06T23:49:01Z [DEBUG] eth.Client#HeaderByNumber(...) eth/client.go:277 number=<nil>
2020-11-06T23:49:02Z [DEBUG] Received new head #21955430 (0x14f0366) services/head_tracker.go:335 blockHash=0x0c48ba3d182202f67c16105e4cb28435d6013d35918ef25eac6a8547f4758ac7 blockHeight=21955430
2020-11-06T23:49:02Z [DEBUG] HeadTracker initiating callbacks services/head_tracker.go:498 chainLength=50 headNum=21955430 numCallbacks=6
2020-11-06T23:49:02Z [DEBUG] HeadTracker: finished callback 5 in 11.4µs services/head_tracker.go:520 blockNumber=21955430 callbackIdx=5 callbackType=*chainlink.headTrackableCallback id=head_tracker time=0.0000114
2020-11-06T23:49:02Z [DEBUG] eth.Client#BlockByNumber(...) eth/client.go:270 number=21955427
2020-11-06T23:49:02Z [DEBUG] BalanceMonitor: signalling balance worker services/balance_monitor.go:68
2020-11-06T23:49:02Z [DEBUG] HeadTracker: finished callback 4 in 410.6µs services/head_tracker.go:520 blockNumber=21955430 callbackIdx=4 callbackType=*services.balanceMonitor id=head_tracker time=0.0004106
2020-11-06T23:49:02Z [DEBUG] HeadTracker: finished callback 2 in 19.3µs services/head_tracker.go:520 blockNumber=21955430 callbackIdx=2 callbackType=*services.jobSubscriber id=head_tracker time=0.0000193
2020-11-06T23:49:02Z [DEBUG] Resuming all runs pending next block services/run_manager.go:247 currentBlockHeight=21955430
2020-11-06T23:49:02Z [DEBUG] HeadTracker: finished callback 3 in 8.7µs services/head_tracker.go:520 blockNumber=21955430 callbackIdx=3 callbackType=*chainlink.pendingConnectionResumer id=head_tracker time=0.0000087
2020-11-06T23:49:02Z [DEBUG] eth.Client#BalanceAt(...) eth/client.go:301 account=0xdEE5bcB87dD8b429E0e7d86ed2C2b43aC1F9EFbf blockNumber=<nil>
2020-11-06T23:49:02Z [DEBUG] EthConfirmer: finished CheckForReceipts bulletprooftxmanager/eth_confirmer.go:92 headNum=21955430 id=eth_confirmer time=0.0049873
2020-11-06T23:49:02Z [DEBUG] EthConfirmer: finished BumpGasWhereNecessary bulletprooftxmanager/eth_confirmer.go:103 headNum=21955430 id=eth_confirmer time=0.0185073
2020-11-06T23:49:02Z [DEBUG] EthConfirmer: finished EnsureConfirmedTransactionsInLongestChain bulletprooftxmanager/eth_confirmer.go:107 headNum=21955430 id=eth_confirmer time=0.0090514
2020-11-06T23:49:02Z [DEBUG] HeadTracker: finished callback 1 in 43.1277ms services/head_tracker.go:520 blockNumber=21955430 callbackIdx=1 callbackType=*bulletprooftxmanager.ethConfirmer id=head_tracker time=0.0431277
2020-11-06T23:49:02Z [DEBUG] GasUpdater: skipping empty block: 21955427 services/gas_updater.go:121 blockNumber=21955427
2020-11-06T23:49:02Z [DEBUG] HeadTracker: finished callback 0 in 256.5003ms services/head_tracker.go:520 blockNumber=21955430 callbackIdx=0 callbackType=*services.gasUpdater id=head_tracker time=0.2565003
2020-11-06T23:49:02Z [DEBUG] HeadTracker finished processing head 21955430 in 268.7909ms services/head_tracker.go:330 blockNumber=21955430 id=head_tracker time=0.2687909
2020-11-06T23:49:02Z [DEBUG] TrackConfig: checking latestConfigDetails managed/track_config.go:48
2020-11-06T23:49:04Z [DEBUG] Received new head #21955431 (0x14f0367) services/head_tracker.go:335 blockHash=0x8888f1fa8f6845860eced8e3b0076d825bfcf7bcd55a784cb00b2a26c937218c blockHeight=21955431
2020-11-06T23:49:04Z [DEBUG] HeadTracker initiating callbacks services/head_tracker.go:498 chainLength=50 headNum=21955431 numCallbacks=6
2020-11-06T23:49:04Z [DEBUG] HeadTracker: finished callback 5 in 31.9µs services/head_tracker.go:520 blockNumber=21955431 callbackIdx=5 callbackType=*chainlink.headTrackableCallback id=head_tracker time=0.0000319
2020-11-06T23:49:04Z [DEBUG] HeadTracker: finished callback 3 in 10.5µs services/head_tracker.go:520 blockNumber=21955431 callbackIdx=3 callbackType=*chainlink.pendingConnectionResumer id=head_tracker time=0.0000105
2020-11-06T23:49:04Z [DEBUG] BalanceMonitor: signalling balance worker services/balance_monitor.go:68
2020-11-06T23:49:04Z [DEBUG] HeadTracker: finished callback 4 in 389.8µs services/head_tracker.go:520 blockNumber=21955431 callbackIdx=4 callbackType=*services.balanceMonitor id=head_tracker time=0.0003898
2020-11-06T23:49:04Z [DEBUG] HeadTracker: finished callback 2 in 247.9µs services/head_tracker.go:520 blockNumber=21955431 callbackIdx=2 callbackType=*services.jobSubscriber id=head_tracker time=0.0002479
2020-11-06T23:49:04Z [DEBUG] Resuming all runs pending next block services/run_manager.go:247 currentBlockHeight=21955431
2020-11-06T23:49:04Z [DEBUG] eth.Client#BalanceAt(...) eth/client.go:301 account=0xdEE5bcB87dD8b429E0e7d86ed2C2b43aC1F9EFbf blockNumber=<nil>
2020-11-06T23:49:04Z [DEBUG] eth.Client#BlockByNumber(...) eth/client.go:270 number=21955428
2020-11-06T23:49:04Z [DEBUG] EthConfirmer: finished CheckForReceipts bulletprooftxmanager/eth_confirmer.go:92 headNum=21955431 id=eth_confirmer time=0.0011222
2020-11-06T23:49:04Z [DEBUG] EthConfirmer: finished BumpGasWhereNecessary bulletprooftxmanager/eth_confirmer.go:103 headNum=21955431 id=eth_confirmer time=0.0054312
2020-11-06T23:49:04Z [DEBUG] EthConfirmer: finished EnsureConfirmedTransactionsInLongestChain bulletprooftxmanager/eth_confirmer.go:107 headNum=21955431 id=eth_confirmer time=0.0047059
2020-11-06T23:49:04Z [DEBUG] HeadTracker: finished callback 1 in 33.8891ms services/head_tracker.go:520 blockNumber=21955431 callbackIdx=1 callbackType=*bulletprooftxmanager.ethConfirmer id=head_tracker time=0.0338891
2020-11-06T23:49:05Z [DEBUG] GasUpdater: skipping empty block: 21955428 services/gas_updater.go:121 blockNumber=21955428
2020-11-06T23:49:05Z [DEBUG] HeadTracker: finished callback 0 in 293.1983ms services/head_tracker.go:520 blockNumber=21955431 callbackIdx=0 callbackType=*services.gasUpdater id=head_tracker time=0.2931983
2020-11-06T23:49:05Z [DEBUG] HeadTracker finished processing head 21955431 in 309.944ms services/head_tracker.go:330 blockNumber=21955431 id=head_tracker time=0.309944
2020-11-06T23:49:08Z [DEBUG] Received new head #21955432 (0x14f0368) services/head_tracker.go:335 blockHash=0xa4d0b57bfdcda42306b939058c5e0819b72b8003a930c3d02d330d97f0e258e4 blockHeight=21955432
2020-11-06T23:49:08Z [DEBUG] HeadTracker initiating callbacks services/head_tracker.go:498 chainLength=50 headNum=21955432 numCallbacks=6
2020-11-06T23:49:08Z [DEBUG] HeadTracker: finished callback 5 in 8.6µs services/head_tracker.go:520 blockNumber=21955432 callbackIdx=5 callbackType=*chainlink.headTrackableCallback id=head_tracker time=0.0000086
2020-11-06T23:49:08Z [DEBUG] BalanceMonitor: signalling balance worker services/balance_monitor.go:68
2020-11-06T23:49:08Z [DEBUG] Resuming all runs pending next block services/run_manager.go:247 currentBlockHeight=21955432
2020-11-06T23:49:08Z [DEBUG] HeadTracker: finished callback 3 in 7.9µs services/head_tracker.go:520 blockNumber=21955432 callbackIdx=3 callbackType=*chainlink.pendingConnectionResumer id=head_tracker time=0.0000079
2020-11-06T23:49:08Z [DEBUG] HeadTracker: finished callback 4 in 247.7µs services/head_tracker.go:520 blockNumber=21955432 callbackIdx=4 callbackType=*services.balanceMonitor id=head_tracker time=0.0002477
2020-11-06T23:49:08Z [DEBUG] eth.Client#BlockByNumber(...) eth/client.go:270 number=21955429
2020-11-06T23:49:08Z [DEBUG] HeadTracker: finished callback 2 in 16.1µs services/head_tracker.go:520 blockNumber=21955432 callbackIdx=2 callbackType=*services.jobSubscriber id=head_tracker time=0.0000161
2020-11-06T23:49:08Z [DEBUG] eth.Client#BalanceAt(...) eth/client.go:301 account=0xdEE5bcB87dD8b429E0e7d86ed2C2b43aC1F9EFbf blockNumber=<nil>
2020-11-06T23:49:08Z [DEBUG] EthConfirmer: finished CheckForReceipts bulletprooftxmanager/eth_confirmer.go:92 headNum=21955432 id=eth_confirmer time=0.0018416
2020-11-06T23:49:08Z [DEBUG] EthConfirmer: finished BumpGasWhereNecessary bulletprooftxmanager/eth_confirmer.go:103 headNum=21955432 id=eth_confirmer time=0.0126325
2020-11-06T23:49:08Z [DEBUG] EthConfirmer: finished EnsureConfirmedTransactionsInLongestChain bulletprooftxmanager/eth_confirmer.go:107 headNum=21955432 id=eth_confirmer time=0.0049114
2020-11-06T23:49:08Z [DEBUG] HeadTracker: finished callback 1 in 35.1207ms services/head_tracker.go:520 blockNumber=21955432 callbackIdx=1 callbackType=*bulletprooftxmanager.ethConfirmer id=head_tracker time=0.0351207
2020-11-06T23:49:08Z [DEBUG] GasUpdater: skipping empty block: 21955429 services/gas_updater.go:121 blockNumber=21955429
2020-11-06T23:49:08Z [DEBUG] HeadTracker: finished callback 0 in 258.5393ms services/head_tracker.go:520 blockNumber=21955432 callbackIdx=0 callbackType=*services.gasUpdater id=head_tracker time=0.2585393
2020-11-06T23:49:08Z [DEBUG] HeadTracker finished processing head 21955432 in 276.4824ms services/head_tracker.go:330 blockNumber=21955432 id=head_tracker time=0.2764824
2020-11-06T23:49:09Z [DEBUG] DHT: Put value loghelper/logger_with_context.go:37 F=1 configDigest=017363cc8a250ae71b30107df7c0bdfd extendedLogging=true id=DHT key=/peerinfo/12D3KooWLFP41jHMfKXVyyFttspPXVhmVTQCrWrXqQDppMgHfdXm listenAddr=/ip4/0.0.0.0/tcp/6690 listenIP=0.0.0.0 listenPort=6690 peerID=12D3KooWLFP41jHMfKXVyyFttspPXVhmVTQCrWrXqQDppMgHfdXm protocolID=/cl_peer_discovery_dht/017363cc8a250ae71b30107df7c0bdfd/kad/1.0.0 value={"addrs":["/ip4/172.19.0.8/tcp/6690","/ip4/127.0.0.1/tcp/6690"],"pk":"CAESIJr9Y5O2Pl09P00uoOoiF9nfkYhi3ZrL1sL3z3zfugvY","sig":"kHfD3EKQ2UBNPVVEWJjh8DhwAC48/v8M8aRXnYrciiSmi3/OtiSR9YtUB7CL5tajDXmI6InR02e/yAvTgkYNCA==","timestamp":1604706549}
2020-11-06T23:49:09Z [ERROR] DHT: Error publishing address loghelper/logger_with_context.go:49 F=1 configDigest=017363cc8a250ae71b30107df7c0bdfd err=could publish address: failed to find any peer in table extendedLogging=true id=DHT listenAddr=/ip4/0.0.0.0/tcp/6690 listenIP=0.0.0.0 listenPort=6690 peerID=12D3KooWLFP41jHMfKXVyyFttspPXVhmVTQCrWrXqQDppMgHfdXm protocolID=/cl_peer_discovery_dht/017363cc8a250ae71b30107df7c0bdfd/kad/1.0.0 retryIn=10 stacktrace=github.com/smartcontractkit/libocr/offchainreporting/loghelper.LoggerWithContext.Error
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/offchainreporting/loghelper/logger_with_context.go:49
github.com/smartcontractkit/libocr/offchainreporting/loghelper.LoggerWithContext.Error
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/offchainreporting/loghelper/logger_with_context.go:49
github.com/smartcontractkit/libocr/offchainreporting/loghelper.LoggerWithContext.Error
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/offchainreporting/loghelper/logger_with_context.go:49
github.com/smartcontractkit/libocr/networking/dht-router.DHTRouter.startAnnounceInBackground.func1
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/networking/dht-router/router.go:267
github.com/smartcontractkit/libocr/subprocesses.(*Subprocesses).Go.func1
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/subprocesses/subprocesses.go:29
2020-11-06T23:49:12Z [DEBUG] Received new head #21955433 (0x14f0369) services/head_tracker.go:335 blockHash=0x98019e189854b80e13ff66fd29b6990572938b86f9852358b9c7aacc2ba254e9 blockHeight=21955433
2020-11-06T23:49:12Z [DEBUG] HeadTracker initiating callbacks services/head_tracker.go:498 chainLength=50 headNum=21955433 numCallbacks=6
2020-11-06T23:49:12Z [DEBUG] HeadTracker: finished callback 5 in 9.1µs services/head_tracker.go:520 blockNumber=21955433 callbackIdx=5 callbackType=*chainlink.headTrackableCallback id=head_tracker time=0.0000091
2020-11-06T23:49:12Z [DEBUG] HeadTracker: finished callback 3 in 8.6µs services/head_tracker.go:520 blockNumber=21955433 callbackIdx=3 callbackType=*chainlink.pendingConnectionResumer id=head_tracker time=0.0000086
2020-11-06T23:49:12Z [DEBUG] BalanceMonitor: signalling balance worker services/balance_monitor.go:68
2020-11-06T23:49:12Z [DEBUG] eth.Client#BlockByNumber(...) eth/client.go:270 number=21955430
2020-11-06T23:49:12Z [DEBUG] HeadTracker: finished callback 4 in 144µs services/head_tracker.go:520 blockNumber=21955433 callbackIdx=4 callbackType=*services.balanceMonitor id=head_tracker time=0.000144
2020-11-06T23:49:12Z [DEBUG] HeadTracker: finished callback 2 in 122.3µs services/head_tracker.go:520 blockNumber=21955433 callbackIdx=2 callbackType=*services.jobSubscriber id=head_tracker time=0.0001223
2020-11-06T23:49:12Z [DEBUG] Resuming all runs pending next block services/run_manager.go:247 currentBlockHeight=21955433
2020-11-06T23:49:12Z [DEBUG] eth.Client#BalanceAt(...) eth/client.go:301 account=0xdEE5bcB87dD8b429E0e7d86ed2C2b43aC1F9EFbf blockNumber=<nil>
2020-11-06T23:49:12Z [DEBUG] EthConfirmer: finished CheckForReceipts bulletprooftxmanager/eth_confirmer.go:92 headNum=21955433 id=eth_confirmer time=0.0054262
2020-11-06T23:49:12Z [DEBUG] EthConfirmer: finished BumpGasWhereNecessary bulletprooftxmanager/eth_confirmer.go:103 headNum=21955433 id=eth_confirmer time=0.0078381
2020-11-06T23:49:12Z [DEBUG] EthConfirmer: finished EnsureConfirmedTransactionsInLongestChain bulletprooftxmanager/eth_confirmer.go:107 headNum=21955433 id=eth_confirmer time=0.0064799
2020-11-06T23:49:12Z [DEBUG] HeadTracker: finished callback 1 in 30.687ms services/head_tracker.go:520 blockNumber=21955433 callbackIdx=1 callbackType=*bulletprooftxmanager.ethConfirmer id=head_tracker time=0.030687
2020-11-06T23:49:12Z [DEBUG] GasUpdater: skipping empty block: 21955430 services/gas_updater.go:121 blockNumber=21955430
2020-11-06T23:49:12Z [DEBUG] HeadTracker: finished callback 0 in 255.685ms services/head_tracker.go:520 blockNumber=21955433 callbackIdx=0 callbackType=*services.gasUpdater id=head_tracker time=0.255685
2020-11-06T23:49:12Z [DEBUG] HeadTracker finished processing head 21955433 in 266.3639ms services/head_tracker.go:330 blockNumber=21955433 id=head_tracker time=0.2663639
2020-11-06T23:49:19Z [DEBUG] DHT: Put value loghelper/logger_with_context.go:37 F=1 configDigest=017363cc8a250ae71b30107df7c0bdfd extendedLogging=true id=DHT key=/peerinfo/12D3KooWLFP41jHMfKXVyyFttspPXVhmVTQCrWrXqQDppMgHfdXm listenAddr=/ip4/0.0.0.0/tcp/6690 listenIP=0.0.0.0 listenPort=6690 peerID=12D3KooWLFP41jHMfKXVyyFttspPXVhmVTQCrWrXqQDppMgHfdXm protocolID=/cl_peer_discovery_dht/017363cc8a250ae71b30107df7c0bdfd/kad/1.0.0 value={"addrs":["/ip4/172.19.0.8/tcp/6690","/ip4/127.0.0.1/tcp/6690"],"pk":"CAESIJr9Y5O2Pl09P00uoOoiF9nfkYhi3ZrL1sL3z3zfugvY","sig":"HP+EGWmpa/5GPc8F3mcH9Z1YrmsbtiiukmML3V7LVffWeDlejEVW5Js8kgyOTohiSyQAHUT9OsIWvoNISt2OAA==","timestamp":1604706559}
2020-11-06T23:49:19Z [ERROR] DHT: Error publishing address loghelper/logger_with_context.go:49 F=1 configDigest=017363cc8a250ae71b30107df7c0bdfd err=could publish address: failed to find any peer in table extendedLogging=true id=DHT listenAddr=/ip4/0.0.0.0/tcp/6690 listenIP=0.0.0.0 listenPort=6690 peerID=12D3KooWLFP41jHMfKXVyyFttspPXVhmVTQCrWrXqQDppMgHfdXm protocolID=/cl_peer_discovery_dht/017363cc8a250ae71b30107df7c0bdfd/kad/1.0.0 retryIn=10 stacktrace=github.com/smartcontractkit/libocr/offchainreporting/loghelper.LoggerWithContext.Error
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/offchainreporting/loghelper/logger_with_context.go:49
github.com/smartcontractkit/libocr/offchainreporting/loghelper.LoggerWithContext.Error
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/offchainreporting/loghelper/logger_with_context.go:49
github.com/smartcontractkit/libocr/offchainreporting/loghelper.LoggerWithContext.Error
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/offchainreporting/loghelper/logger_with_context.go:49
github.com/smartcontractkit/libocr/networking/dht-router.DHTRouter.startAnnounceInBackground.func1
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/networking/dht-router/router.go:267
github.com/smartcontractkit/libocr/subprocesses.(*Subprocesses).Go.func1
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/subprocesses/subprocesses.go:29
2020-11-06T23:49:21Z [DEBUG] Received new head #21955434 (0x14f036a) services/head_tracker.go:335 blockHash=0xcf4d6ceaedbbc294c8f4b64da59a7532365e908317890ff2291dd3a4f55dfbb0 blockHeight=21955434
2020-11-06T23:49:21Z [DEBUG] HeadTracker initiating callbacks services/head_tracker.go:498 chainLength=50 headNum=21955434 numCallbacks=6
2020-11-06T23:49:21Z [DEBUG] HeadTracker: finished callback 5 in 6.8µs services/head_tracker.go:520 blockNumber=21955434 callbackIdx=5 callbackType=*chainlink.headTrackableCallback id=head_tracker time=0.0000068
2020-11-06T23:49:21Z [DEBUG] eth.Client#BlockByNumber(...) eth/client.go:270 number=21955431
2020-11-06T23:49:21Z [DEBUG] HeadTracker: finished callback 2 in 11.4µs services/head_tracker.go:520 blockNumber=21955434 callbackIdx=2 callbackType=*services.jobSubscriber id=head_tracker time=0.0000114
2020-11-06T23:49:21Z [DEBUG] BalanceMonitor: signalling balance worker services/balance_monitor.go:68
2020-11-06T23:49:21Z [DEBUG] HeadTracker: finished callback 3 in 6.4µs services/head_tracker.go:520 blockNumber=21955434 callbackIdx=3 callbackType=*chainlink.pendingConnectionResumer id=head_tracker time=0.0000064
2020-11-06T23:49:21Z [DEBUG] Resuming all runs pending next block services/run_manager.go:247 currentBlockHeight=21955434
2020-11-06T23:49:21Z [DEBUG] HeadTracker: finished callback 4 in 150.3µs services/head_tracker.go:520 blockNumber=21955434 callbackIdx=4 callbackType=*services.balanceMonitor id=head_tracker time=0.0001503
2020-11-06T23:49:21Z [DEBUG] eth.Client#BalanceAt(...) eth/client.go:301 account=0xdEE5bcB87dD8b429E0e7d86ed2C2b43aC1F9EFbf blockNumber=<nil>
2020-11-06T23:49:21Z [DEBUG] EthConfirmer: finished CheckForReceipts bulletprooftxmanager/eth_confirmer.go:92 headNum=21955434 id=eth_confirmer time=0.001497
2020-11-06T23:49:21Z [DEBUG] EthConfirmer: finished BumpGasWhereNecessary bulletprooftxmanager/eth_confirmer.go:103 headNum=21955434 id=eth_confirmer time=0.0059018
2020-11-06T23:49:21Z [DEBUG] EthConfirmer: finished EnsureConfirmedTransactionsInLongestChain bulletprooftxmanager/eth_confirmer.go:107 headNum=21955434 id=eth_confirmer time=0.0044167
2020-11-06T23:49:21Z [DEBUG] HeadTracker: finished callback 1 in 29.159ms services/head_tracker.go:520 blockNumber=21955434 callbackIdx=1 callbackType=*bulletprooftxmanager.ethConfirmer id=head_tracker time=0.029159
2020-11-06T23:49:21Z [DEBUG] GasUpdater: skipping empty block: 21955431 services/gas_updater.go:121 blockNumber=21955431
2020-11-06T23:49:21Z [DEBUG] HeadTracker: finished callback 0 in 252.7248ms services/head_tracker.go:520 blockNumber=21955434 callbackIdx=0 callbackType=*services.gasUpdater id=head_tracker time=0.2527248
2020-11-06T23:49:21Z [DEBUG] HeadTracker finished processing head 21955434 in 267.3674ms services/head_tracker.go:330 blockNumber=21955434 id=head_tracker time=0.2673674
2020-11-06T23:49:24Z [DEBUG] Received new head #21955435 (0x14f036b) services/head_tracker.go:335 blockHash=0xb502b27ae1af31235481f7dfe50b126d70cdf53f88410d5f8d4e08c451c2c012 blockHeight=21955435
2020-11-06T23:49:24Z [DEBUG] HeadTracker initiating callbacks services/head_tracker.go:498 chainLength=50 headNum=21955435 numCallbacks=6
2020-11-06T23:49:24Z [DEBUG] HeadTracker: finished callback 5 in 8.4µs services/head_tracker.go:520 blockNumber=21955435 callbackIdx=5 callbackType=*chainlink.headTrackableCallback id=head_tracker time=0.0000084
2020-11-06T23:49:24Z [DEBUG] HeadTracker: finished callback 3 in 8.2µs services/head_tracker.go:520 blockNumber=21955435 callbackIdx=3 callbackType=*chainlink.pendingConnectionResumer id=head_tracker time=0.0000082
2020-11-06T23:49:24Z [DEBUG] BalanceMonitor: signalling balance worker services/balance_monitor.go:68
2020-11-06T23:49:24Z [DEBUG] eth.Client#BlockByNumber(...) eth/client.go:270 number=21955432
2020-11-06T23:49:24Z [DEBUG] HeadTracker: finished callback 4 in 282.1µs services/head_tracker.go:520 blockNumber=21955435 callbackIdx=4 callbackType=*services.balanceMonitor id=head_tracker time=0.0002821
2020-11-06T23:49:24Z [DEBUG] HeadTracker: finished callback 2 in 47.1µs services/head_tracker.go:520 blockNumber=21955435 callbackIdx=2 callbackType=*services.jobSubscriber id=head_tracker time=0.0000471
2020-11-06T23:49:24Z [DEBUG] EthConfirmer: finished CheckForReceipts bulletprooftxmanager/eth_confirmer.go:92 headNum=21955435 id=eth_confirmer time=0.001094
2020-11-06T23:49:24Z [DEBUG] Resuming all runs pending next block services/run_manager.go:247 currentBlockHeight=21955435
2020-11-06T23:49:24Z [DEBUG] eth.Client#BalanceAt(...) eth/client.go:301 account=0xdEE5bcB87dD8b429E0e7d86ed2C2b43aC1F9EFbf blockNumber=<nil>
2020-11-06T23:49:24Z [DEBUG] EthConfirmer: finished BumpGasWhereNecessary bulletprooftxmanager/eth_confirmer.go:103 headNum=21955435 id=eth_confirmer time=0.0132198
2020-11-06T23:49:24Z [DEBUG] EthConfirmer: finished EnsureConfirmedTransactionsInLongestChain bulletprooftxmanager/eth_confirmer.go:107 headNum=21955435 id=eth_confirmer time=0.0053173
2020-11-06T23:49:24Z [DEBUG] HeadTracker: finished callback 1 in 26.2224ms services/head_tracker.go:520 blockNumber=21955435 callbackIdx=1 callbackType=*bulletprooftxmanager.ethConfirmer id=head_tracker time=0.0262224
2020-11-06T23:49:24Z [DEBUG] GasUpdater: setting new default gas price: 1.30 Gwei services/gas_updater.go:147 gasPriceGWei=1.30 gasPriceWei=1300000000
2020-11-06T23:49:24Z [DEBUG] HeadTracker: finished callback 0 in 310.5086ms services/head_tracker.go:520 blockNumber=21955435 callbackIdx=0 callbackType=*services.gasUpdater id=head_tracker time=0.3105086
2020-11-06T23:49:24Z [DEBUG] HeadTracker finished processing head 21955435 in 321.9443ms services/head_tracker.go:330 blockNumber=21955435 id=head_tracker time=0.3219443
2020-11-06T23:49:28Z [DEBUG] Received new head #21955436 (0x14f036c) services/head_tracker.go:335 blockHash=0x6d9e5e2f2074906ed742cec4a9967d0240eaaf25eb8996dc8b4a6efe77216f74 blockHeight=21955436
2020-11-06T23:49:28Z [DEBUG] HeadTracker initiating callbacks services/head_tracker.go:498 chainLength=50 headNum=21955436 numCallbacks=6
2020-11-06T23:49:28Z [DEBUG] HeadTracker: finished callback 5 in 10.9µs services/head_tracker.go:520 blockNumber=21955436 callbackIdx=5 callbackType=*chainlink.headTrackableCallback id=head_tracker time=0.0000109
2020-11-06T23:49:28Z [DEBUG] HeadTracker: finished callback 3 in 103.7µs services/head_tracker.go:520 blockNumber=21955436 callbackIdx=3 callbackType=*chainlink.pendingConnectionResumer id=head_tracker time=0.0001037
2020-11-06T23:49:28Z [DEBUG] BalanceMonitor: signalling balance worker services/balance_monitor.go:68
2020-11-06T23:49:28Z [DEBUG] HeadTracker: finished callback 4 in 271.2µs services/head_tracker.go:520 blockNumber=21955436 callbackIdx=4 callbackType=*services.balanceMonitor id=head_tracker time=0.0002712
2020-11-06T23:49:28Z [DEBUG] HeadTracker: finished callback 2 in 11µs services/head_tracker.go:520 blockNumber=21955436 callbackIdx=2 callbackType=*services.jobSubscriber id=head_tracker time=0.000011
2020-11-06T23:49:28Z [DEBUG] eth.Client#BlockByNumber(...) eth/client.go:270 number=21955433
2020-11-06T23:49:28Z [DEBUG] Resuming all runs pending next block services/run_manager.go:247 currentBlockHeight=21955436
2020-11-06T23:49:28Z [DEBUG] eth.Client#BalanceAt(...) eth/client.go:301 account=0xdEE5bcB87dD8b429E0e7d86ed2C2b43aC1F9EFbf blockNumber=<nil>
2020-11-06T23:49:28Z [DEBUG] EthConfirmer: finished CheckForReceipts bulletprooftxmanager/eth_confirmer.go:92 headNum=21955436 id=eth_confirmer time=0.0014453
2020-11-06T23:49:28Z [DEBUG] EthConfirmer: finished BumpGasWhereNecessary bulletprooftxmanager/eth_confirmer.go:103 headNum=21955436 id=eth_confirmer time=0.0100335
2020-11-06T23:49:28Z [DEBUG] EthConfirmer: finished EnsureConfirmedTransactionsInLongestChain bulletprooftxmanager/eth_confirmer.go:107 headNum=21955436 id=eth_confirmer time=0.00751
2020-11-06T23:49:28Z [DEBUG] HeadTracker: finished callback 1 in 28.4907ms services/head_tracker.go:520 blockNumber=21955436 callbackIdx=1 callbackType=*bulletprooftxmanager.ethConfirmer id=head_tracker time=0.0284907
2020-11-06T23:49:29Z [DEBUG] GasUpdater: skipping empty block: 21955433 services/gas_updater.go:121 blockNumber=21955433
2020-11-06T23:49:29Z [DEBUG] HeadTracker: finished callback 0 in 260.1219ms services/head_tracker.go:520 blockNumber=21955436 callbackIdx=0 callbackType=*services.gasUpdater id=head_tracker time=0.2601219
2020-11-06T23:49:29Z [DEBUG] HeadTracker finished processing head 21955436 in 288.8761ms services/head_tracker.go:330 blockNumber=21955436 id=head_tracker time=0.2888761
2020-11-06T23:49:29Z [DEBUG] DHT: Put value loghelper/logger_with_context.go:37 F=1 configDigest=017363cc8a250ae71b30107df7c0bdfd extendedLogging=true id=DHT key=/peerinfo/12D3KooWLFP41jHMfKXVyyFttspPXVhmVTQCrWrXqQDppMgHfdXm listenAddr=/ip4/0.0.0.0/tcp/6690 listenIP=0.0.0.0 listenPort=6690 peerID=12D3KooWLFP41jHMfKXVyyFttspPXVhmVTQCrWrXqQDppMgHfdXm protocolID=/cl_peer_discovery_dht/017363cc8a250ae71b30107df7c0bdfd/kad/1.0.0 value={"addrs":["/ip4/172.19.0.8/tcp/6690","/ip4/127.0.0.1/tcp/6690"],"pk":"CAESIJr9Y5O2Pl09P00uoOoiF9nfkYhi3ZrL1sL3z3zfugvY","sig":"pHqFbVxpsdqpuQXLcj++vNs/pgxZ77F/uXF1TP9M8kdiciDEtf0UpuXE8UcGVeGHwTVrcLGR6DNBZqfkLKjbAw==","timestamp":1604706569}
2020-11-06T23:49:29Z [ERROR] DHT: Error publishing address loghelper/logger_with_context.go:49 F=1 configDigest=017363cc8a250ae71b30107df7c0bdfd err=could publish address: failed to find any peer in table extendedLogging=true id=DHT listenAddr=/ip4/0.0.0.0/tcp/6690 listenIP=0.0.0.0 listenPort=6690 peerID=12D3KooWLFP41jHMfKXVyyFttspPXVhmVTQCrWrXqQDppMgHfdXm protocolID=/cl_peer_discovery_dht/017363cc8a250ae71b30107df7c0bdfd/kad/1.0.0 retryIn=10 stacktrace=github.com/smartcontractkit/libocr/offchainreporting/loghelper.LoggerWithContext.Error
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/offchainreporting/loghelper/logger_with_context.go:49
github.com/smartcontractkit/libocr/offchainreporting/loghelper.LoggerWithContext.Error
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/offchainreporting/loghelper/logger_with_context.go:49
github.com/smartcontractkit/libocr/offchainreporting/loghelper.LoggerWithContext.Error
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/offchainreporting/loghelper/logger_with_context.go:49
github.com/smartcontractkit/libocr/networking/dht-router.DHTRouter.startAnnounceInBackground.func1
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/networking/dht-router/router.go:267
github.com/smartcontractkit/libocr/subprocesses.(*Subprocesses).Go.func1
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/subprocesses/subprocesses.go:29
2020-11-06T23:49:32Z [DEBUG] Received new head #21955437 (0x14f036d) services/head_tracker.go:335 blockHash=0x1ee8bffa01139fe60f48e509d5cd48c730970d46215514a74a5de5b99a72c622 blockHeight=21955437
2020-11-06T23:49:32Z [DEBUG] HeadTracker initiating callbacks services/head_tracker.go:498 chainLength=50 headNum=21955437 numCallbacks=6
2020-11-06T23:49:32Z [DEBUG] HeadTracker: finished callback 5 in 9.1µs services/head_tracker.go:520 blockNumber=21955437 callbackIdx=5 callbackType=*chainlink.headTrackableCallback id=head_tracker time=0.0000091
2020-11-06T23:49:32Z [DEBUG] BalanceMonitor: signalling balance worker services/balance_monitor.go:68
2020-11-06T23:49:32Z [DEBUG] HeadTracker: finished callback 3 in 80.8µs services/head_tracker.go:520 blockNumber=21955437 callbackIdx=3 callbackType=*chainlink.pendingConnectionResumer id=head_tracker time=0.0000808
2020-11-06T23:49:32Z [DEBUG] HeadTracker: finished callback 4 in 488µs services/head_tracker.go:520 blockNumber=21955437 callbackIdx=4 callbackType=*services.balanceMonitor id=head_tracker time=0.000488
2020-11-06T23:49:32Z [DEBUG] eth.Client#BlockByNumber(...) eth/client.go:270 number=21955434
2020-11-06T23:49:32Z [DEBUG] HeadTracker: finished callback 2 in 2.8634ms services/head_tracker.go:520 blockNumber=21955437 callbackIdx=2 callbackType=*services.jobSubscriber id=head_tracker time=0.0028634
2020-11-06T23:49:32Z [DEBUG] Resuming all runs pending next block services/run_manager.go:247 currentBlockHeight=21955437
2020-11-06T23:49:32Z [DEBUG] eth.Client#BalanceAt(...) eth/client.go:301 account=0xdEE5bcB87dD8b429E0e7d86ed2C2b43aC1F9EFbf blockNumber=<nil>
2020-11-06T23:49:32Z [DEBUG] EthConfirmer: finished CheckForReceipts bulletprooftxmanager/eth_confirmer.go:92 headNum=21955437 id=eth_confirmer time=0.0025464
2020-11-06T23:49:33Z [DEBUG] EthConfirmer: finished BumpGasWhereNecessary bulletprooftxmanager/eth_confirmer.go:103 headNum=21955437 id=eth_confirmer time=0.0110753
2020-11-06T23:49:33Z [DEBUG] EthConfirmer: finished EnsureConfirmedTransactionsInLongestChain bulletprooftxmanager/eth_confirmer.go:107 headNum=21955437 id=eth_confirmer time=0.0085859
2020-11-06T23:49:33Z [DEBUG] HeadTracker: finished callback 1 in 57.8894ms services/head_tracker.go:520 blockNumber=21955437 callbackIdx=1 callbackType=*bulletprooftxmanager.ethConfirmer id=head_tracker time=0.0578894
2020-11-06T23:49:33Z [DEBUG] GasUpdater: skipping empty block: 21955434 services/gas_updater.go:121 blockNumber=21955434
2020-11-06T23:49:33Z [DEBUG] HeadTracker: finished callback 0 in 333.3275ms services/head_tracker.go:520 blockNumber=21955437 callbackIdx=0 callbackType=*services.gasUpdater id=head_tracker time=0.3333275
2020-11-06T23:49:33Z [DEBUG] HeadTracker finished processing head 21955437 in 354.0537ms services/head_tracker.go:330 blockNumber=21955437 id=head_tracker time=0.3540537
2020-11-06T23:49:39Z [DEBUG] DHT: Put value loghelper/logger_with_context.go:37 F=1 configDigest=017363cc8a250ae71b30107df7c0bdfd extendedLogging=true id=DHT key=/peerinfo/12D3KooWLFP41jHMfKXVyyFttspPXVhmVTQCrWrXqQDppMgHfdXm listenAddr=/ip4/0.0.0.0/tcp/6690 listenIP=0.0.0.0 listenPort=6690 peerID=12D3KooWLFP41jHMfKXVyyFttspPXVhmVTQCrWrXqQDppMgHfdXm protocolID=/cl_peer_discovery_dht/017363cc8a250ae71b30107df7c0bdfd/kad/1.0.0 value={"addrs":["/ip4/172.19.0.8/tcp/6690","/ip4/127.0.0.1/tcp/6690"],"pk":"CAESIJr9Y5O2Pl09P00uoOoiF9nfkYhi3ZrL1sL3z3zfugvY","sig":"3LdbY/cmhiIwN2+yk3AT7lth6lCOwdVQaM+ZTnKfL1yXADvSxTjZaqMCbkkpjk0GCMc4JIgJ0oYsrlrG8cPABg==","timestamp":1604706579}
2020-11-06T23:49:39Z [ERROR] DHT: Error publishing address loghelper/logger_with_context.go:49 F=1 configDigest=017363cc8a250ae71b30107df7c0bdfd err=could publish address: failed to find any peer in table extendedLogging=true id=DHT listenAddr=/ip4/0.0.0.0/tcp/6690 listenIP=0.0.0.0 listenPort=6690 peerID=12D3KooWLFP41jHMfKXVyyFttspPXVhmVTQCrWrXqQDppMgHfdXm protocolID=/cl_peer_discovery_dht/017363cc8a250ae71b30107df7c0bdfd/kad/1.0.0 retryIn=10 stacktrace=github.com/smartcontractkit/libocr/offchainreporting/loghelper.LoggerWithContext.Error
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/offchainreporting/loghelper/logger_with_context.go:49
github.com/smartcontractkit/libocr/offchainreporting/loghelper.LoggerWithContext.Error
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/offchainreporting/loghelper/logger_with_context.go:49
github.com/smartcontractkit/libocr/offchainreporting/loghelper.LoggerWithContext.Error
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/offchainreporting/loghelper/logger_with_context.go:49
github.com/smartcontractkit/libocr/networking/dht-router.DHTRouter.startAnnounceInBackground.func1
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/networking/dht-router/router.go:267
github.com/smartcontractkit/libocr/subprocesses.(*Subprocesses).Go.func1
/go/pkg/mod/github.com/smartcontractkit/libocr@v0.0.0-20201104141745-a805eb2bc4fc/subprocesses/subprocesses.go:29
2020-11-06T23:49:41Z [DEBUG] Received new head #21955438 (0x14f036e) services/head_tracker.go:335 blockHash=0x1abbbcf2e955d0bd9a08735c130810d7ece6404f5f0c8d0293447897e80cd2c4 blockHeight=21955438
2020-11-06T23:49:41Z [DEBUG] HeadTracker initiating callbacks services/head_tracker.go:498 chainLength=50 headNum=21955438 numCallbacks=6
2020-11-06T23:49:41Z [DEBUG] HeadTracker: finished callback 5 in 16.1µs services/head_tracker.go:520 blockNumber=21955438 callbackIdx=5 callbackType=*chainlink.headTrackableCallback id=head_tracker time=0.0000161
2020-11-06T23:49:41Z [DEBUG] HeadTracker: finished callback 3 in 8.3µs services/head_tracker.go:520 blockNumber=21955438 callbackIdx=3 callbackType=*chainlink.pendingConnectionResumer id=head_tracker time=0.0000083
2020-11-06T23:49:41Z [DEBUG] BalanceMonitor: signalling balance worker services/balance_monitor.go:68
2020-11-06T23:49:41Z [DEBUG] HeadTracker: finished callback 4 in 485.8µs services/head_tracker.go:520 blockNumber=21955438 callbackIdx=4 callbackType=*services.balanceMonitor id=head_tracker time=0.0004858
2020-11-06T23:49:41Z [DEBUG] eth.Client#BlockByNumber(...) eth/client.go:270 number=21955435
2020-11-06T23:49:41Z [DEBUG] HeadTracker: finished callback 2 in 1.562ms services/head_tracker.go:520 blockNumber=21955438 callbackIdx=2 callbackType=*services.jobSubscriber id=head_tracker time=0.001562
2020-11-06T23:49:41Z [DEBUG] Resuming all runs pending next block services/run_manager.go:247 currentBlockHeight=21955438
2020-11-06T23:49:41Z [DEBUG] eth.Client#BalanceAt(...) eth/client.go:301 account=0xdEE5bcB87dD8b429E0e7d86ed2C2b43aC1F9EFbf blockNumber=<nil>
2020-11-06T23:49:41Z [DEBUG] EthConfirmer: finished CheckForReceipts bulletprooftxmanager/eth_confirmer.go:92 headNum=21955438 id=eth_confirmer time=0.0016756
2020-11-06T23:49:41Z [DEBUG] EthConfirmer: finished BumpGasWhereNecessary bulletprooftxmanager/eth_confirmer.go:103 headNum=21955438 id=eth_confirmer time=0.0117836
2020-11-06T23:49:41Z [DEBUG] EthConfirmer: finished EnsureConfirmedTransactionsInLongestChain bulletprooftxmanager/eth_confirmer.go:107 headNum=21955438 id=eth_confirmer time=0.0145855
2020-11-06T23:49:41Z [DEBUG] HeadTracker: finished callback 1 in 45.2449ms services/head_tracker.go:520 blockNumber=21955438 callbackIdx=1 callbackType=*bulletprooftxmanager.ethConfirmer id=head_tracker time=0.0452449
2020-11-06T23:49:41Z [DEBUG] GasUpdater: skipping empty block: 21955435 services/gas_updater.go:121 blockNumber=21955435
2020-11-06T23:49:41Z [DEBUG] HeadTracker: finished callback 0 in 266.3982ms services/head_tracker.go:520 blockNumber=21955438 callbackIdx=0 callbackType=*services.gasUpdater id=head_tracker time=0.2663982
2020-11-06T23:49:41Z [DEBUG] HeadTracker finished processing head 21955438 in 282.9614ms services/head_tracker.go:330 blockNumber=21955438 id=head_tracker time=0.2829614
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment