Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

geth in light mode hanging with 100% CPU #20464

Closed
c0deright opened this issue Dec 17, 2019 · 59 comments
Closed

geth in light mode hanging with 100% CPU #20464

c0deright opened this issue Dec 17, 2019 · 59 comments
Assignees

Comments

@c0deright
Copy link

c0deright commented Dec 17, 2019

System information

Geth
Version: 1.9.9-stable
Git Commit: 017449971e1e9e220efcd97d3313a0e27f47003b
Architecture: amd64
Protocol Versions: [64 63]
Go Version: go1.13.4
Operating System: linux
GOPATH=
GOROOT=/build/ethereum-XCZsIR/.go

OS: Ubuntu Xenial 16.04 LTS
System: AWS ec2 t3.small (2GB RAM, 2 Cores)

Expected behaviour

Geth using little CPU and syncing blocks

Actual behaviour

Geth using 100% CPU and not syncing blocks, geth being stuck at block 9120454.

Steps to reproduce the behaviour

Run geth for 11 days:

% ps f -o user,pid,nice,%cpu,%mem,cputime,etime,tty8,command ax
USER     PID  NI %CPU %MEM     TIME     ELAPSED TTY      COMMAND
geth   28935   0  4.1 43.3 10:58:35 10-23:27:34 ?        /usr/bin/geth --cache 512 --syncmode light --rpc --rpcaddr 0.0.0.0 --rpcapi eth,net,web3 --maxpeers 60 --verbosity 3 --rpcvhosts=censored

RPC

geth attach is not working, rpc is.
This is a script I run to see block height between different hosts:

% check_eth
Host            Peers     Blocks  Delta
---------------------------------------
geth-node          12    9120454  (ref)
parity-node-1      10    9120605   +151
parity-node-2      13    9120605   +151
parity-node-3      13    9120605   +151

Logs

journalctl -u geth geth.log

Cacti

cacti1

cacti2

AWS Metrics

aws_metrics

Moment CPU went up

cpu

Logfile from around that time:

-- Logs begin at Mon 2019-12-16 01:36:06 CET, end at Tue 2019-12-17 13:58:33 CET. --
Dec 17 01:30:05 hostname geth[28935]: INFO [12-17|01:30:05.196] Imported new block headers               count=1  elapsed=5.126ms   number=9118082 hash=9f511b…baab4c
Dec 17 01:30:07 hostname geth[28935]: INFO [12-17|01:30:07.842] Imported new block headers               count=1  elapsed=5.329ms   number=9118083 hash=0ad9c2…2d87b0
Dec 17 01:30:15 hostname geth[28935]: INFO [12-17|01:30:15.142] Imported new block headers               count=1  elapsed=5.295ms   number=9118084 hash=fab27f…8edc54
Dec 17 01:30:19 hostname geth[28935]: INFO [12-17|01:30:19.840] Imported new block headers               count=1  elapsed=5.024ms   number=9118085 hash=9a793d…68c615
Dec 17 01:30:42 hostname geth[28935]: INFO [12-17|01:30:42.047] Imported new block headers               count=1  elapsed=5.009ms   number=9118086 hash=57844c…92006d
Dec 17 01:30:45 hostname geth[28935]: INFO [12-17|01:30:45.816] Imported new block headers               count=1  elapsed=5.507ms   number=9118087 hash=96b264…873521
Dec 17 01:30:46 hostname geth[28935]: INFO [12-17|01:30:46.318] Imported new block headers               count=1  elapsed=5.774ms   number=9118087 hash=acc872…7d2fb7
Dec 17 01:31:52 hostname geth[28935]: INFO [12-17|01:31:52.223] Imported new block headers               count=1  elapsed=5.345ms   number=9118088 hash=d80e32…a21ebd age=1m7s
Dec 17 01:31:59 hostname geth[28935]: INFO [12-17|01:31:59.911] Imported new block headers               count=1  elapsed=5.184ms   number=9118089 hash=572c8b…5ae682
Dec 17 01:32:01 hostname geth[28935]: INFO [12-17|01:32:01.546] Imported new block headers               count=1  elapsed=5.536ms   number=9118090 hash=f5834c…76c18c
Dec 17 01:33:18 hostname geth[28935]: INFO [12-17|01:33:18.615] Imported new block headers               count=1  elapsed=5.103ms   number=9118091 hash=d6142c…ab834e age=1m17s
Dec 17 01:34:04 hostname geth[28935]: INFO [12-17|01:34:04.879] Imported new block headers               count=1  elapsed=5.409ms   number=9118092 hash=a544cc…d5bd4e
Dec 17 01:34:49 hostname geth[28935]: INFO [12-17|01:34:49.786] Imported new block headers               count=1  elapsed=5.333ms   number=9118093 hash=15e569…82aea7
Dec 17 01:34:55 hostname geth[28935]: INFO [12-17|01:34:55.415] Imported new block headers               count=1  elapsed=5.071ms   number=9118094 hash=1dc207…7d5622
Dec 17 01:35:04 hostname geth[28935]: INFO [12-17|01:35:04.220] Imported new block headers               count=1  elapsed=5.575ms   number=9118095 hash=dd5ee9…432a98
Dec 17 01:35:08 hostname geth[28935]: INFO [12-17|01:35:08.453] Imported new block headers               count=1  elapsed=5.970ms   number=9118096 hash=362b83…d3f735
Dec 17 01:35:09 hostname geth[28935]: INFO [12-17|01:35:09.717] Imported new block headers               count=1  elapsed=5.154ms   number=9118097 hash=c91ef3…25367f
Dec 17 01:35:48 hostname geth[28935]: INFO [12-17|01:35:48.754] Imported new block headers               count=1  elapsed=5.100ms   number=9118098 hash=c025df…f69a98
Dec 17 01:35:55 hostname geth[28935]: INFO [12-17|01:35:55.093] Imported new block headers               count=1  elapsed=5.351ms   number=9118099 hash=eaf699…9445c8
Dec 17 01:36:27 hostname geth[28935]: INFO [12-17|01:36:27.303] Imported new block headers               count=1  elapsed=5.066ms   number=9118100 hash=9d3f80…537b7b
Dec 17 01:36:32 hostname geth[28935]: INFO [12-17|01:36:32.318] Imported new block headers               count=1  elapsed=5.066ms   number=9118101 hash=832f21…0a6f41
Dec 17 01:36:36 hostname geth[28935]: INFO [12-17|01:36:36.712] Imported new block headers               count=1  elapsed=5.200ms   number=9118102 hash=9aa9f2…f51d41
Dec 17 01:36:37 hostname geth[28935]: INFO [12-17|01:36:37.693] Imported new block headers               count=1  elapsed=5.176ms   number=9118103 hash=d02a61…6a15d7
Dec 17 01:37:05 hostname geth[28935]: INFO [12-17|01:37:05.233] Imported new block headers               count=1  elapsed=5.192ms   number=9118104 hash=53a323…9c67bf
Dec 17 01:37:08 hostname geth[28935]: INFO [12-17|01:37:08.907] Imported new block headers               count=1  elapsed=5.024ms   number=9118105 hash=9465f3…b01196
Dec 17 01:37:56 hostname geth[28935]: INFO [12-17|01:37:56.544] Imported new block headers               count=1  elapsed=10.360ms  number=9118106 hash=0cdefc…f27cc2
Dec 17 01:38:05 hostname geth[28935]: INFO [12-17|01:38:05.698] Imported new block headers               count=1  elapsed=5.569ms   number=9118107 hash=a320db…8a7c0f
Dec 17 01:38:17 hostname geth[28935]: INFO [12-17|01:38:17.967] Imported new block headers               count=1  elapsed=5.654ms   number=9118108 hash=4b6cce…7184d4
Dec 17 01:38:28 hostname geth[28935]: INFO [12-17|01:38:28.784] Imported new block headers               count=1  elapsed=5.084ms   number=9118109 hash=136b40…bb1a55
Dec 17 01:38:36 hostname geth[28935]: INFO [12-17|01:38:36.851] Imported new block headers               count=1  elapsed=5.252ms   number=9118110 hash=dac1dd…b73abb
Dec 17 01:38:47 hostname geth[28935]: INFO [12-17|01:38:47.466] Imported new block headers               count=1  elapsed=5.031ms   number=9118111 hash=3f078b…fa7055
Dec 17 01:39:26 hostname geth[28935]: INFO [12-17|01:39:26.742] Imported new block headers               count=1  elapsed=5.067ms   number=9118112 hash=6fedae…5c7a39
Dec 17 01:39:28 hostname geth[28935]: INFO [12-17|01:39:28.789] Imported new block headers               count=1  elapsed=5.157ms   number=9118113 hash=789fcb…45fc75
Dec 17 01:39:53 hostname geth[28935]: INFO [12-17|01:39:53.851] Imported new block headers               count=1  elapsed=5.066ms   number=9118114 hash=78942a…a86723
Dec 17 01:40:14 hostname geth[28935]: INFO [12-17|01:40:14.480] Imported new block headers               count=1  elapsed=5.581ms   number=9118115 hash=8857a1…f42218
Dec 17 01:40:36 hostname geth[28935]: INFO [12-17|01:40:36.410] Imported new block headers               count=1  elapsed=5.014ms   number=9118116 hash=258c84…ca16ef
Dec 17 01:40:43 hostname geth[28935]: INFO [12-17|01:40:43.311] Imported new block headers               count=1  elapsed=5.168ms   number=9118117 hash=c8f594…21ee22
Dec 17 01:41:00 hostname geth[28935]: INFO [12-17|01:41:00.485] Imported new block headers               count=1  elapsed=5.300ms   number=9118118 hash=dd7fb3…8dcb3a
Dec 17 01:41:37 hostname geth[28935]: INFO [12-17|01:41:37.246] Imported new block headers               count=1  elapsed=4.828ms   number=9118119 hash=e7ef38…21b9ac
Dec 17 01:42:39 hostname geth[28935]: INFO [12-17|01:42:39.869] Imported new block headers               count=1  elapsed=5.616ms   number=9118120 hash=f300ac…e7c8c0 age=1m2s
Dec 17 01:42:43 hostname geth[28935]: INFO [12-17|01:42:43.246] Imported new block headers               count=1  elapsed=5.128ms   number=9118121 hash=cae891…a0855a
Dec 17 01:42:51 hostname geth[28935]: INFO [12-17|01:42:51.846] Imported new block headers               count=1  elapsed=5.284ms   number=9118122 hash=c5fea0…115cd1
Dec 17 01:42:57 hostname geth[28935]: INFO [12-17|01:42:57.155] Imported new block headers               count=1  elapsed=5.471ms   number=9118123 hash=35751d…55b8b0
Dec 17 01:43:03 hostname geth[28935]: INFO [12-17|01:43:03.933] Imported new block headers               count=1  elapsed=5.260ms   number=9118124 hash=9b40da…958dba
Dec 17 01:43:06 hostname geth[28935]: INFO [12-17|01:43:06.742] Imported new block headers               count=1  elapsed=5.207ms   number=9118125 hash=a110e3…57af90
Dec 17 01:43:28 hostname geth[28935]: INFO [12-17|01:43:28.577] Imported new block headers               count=1  elapsed=5.385ms   number=9118126 hash=a75d97…3f58f4
Dec 17 01:43:52 hostname geth[28935]: INFO [12-17|01:43:52.515] Imported new block headers               count=1  elapsed=5.085ms   number=9118127 hash=254f69…e705d7
Dec 17 01:44:23 hostname geth[28935]: INFO [12-17|01:44:23.611] Imported new block headers               count=1  elapsed=5.056ms   number=9118128 hash=c6217a…4ea1db
Dec 17 01:45:00 hostname geth[28935]: INFO [12-17|01:45:00.027] Imported new block headers               count=1  elapsed=5.424ms   number=9118129 hash=aaec47…3df414
Dec 17 01:45:02 hostname geth[28935]: INFO [12-17|01:45:02.498] Imported new block headers               count=1  elapsed=4.999ms   number=9118130 hash=d8cc1a…48d25d
Dec 17 01:45:03 hostname geth[28935]: INFO [12-17|01:45:03.576] Imported new block headers               count=1  elapsed=5.422ms   number=9118131 hash=cc289b…38ec2f
Dec 17 01:45:05 hostname geth[28935]: INFO [12-17|01:45:05.767] Imported new block headers               count=1  elapsed=5.248ms   number=9118132 hash=991dc4…b25046
Dec 17 01:45:38 hostname geth[28935]: INFO [12-17|01:45:38.450] Imported new block headers               count=1  elapsed=5.254ms   number=9118133 hash=f2610c…1a3610
Dec 17 01:46:03 hostname geth[28935]: INFO [12-17|01:46:03.655] Imported new block headers               count=1  elapsed=5.380ms   number=9118122 hash=e0ba7e…88e81f age=3m20s
Dec 17 01:46:04 hostname geth[28935]: INFO [12-17|01:46:04.159] Imported new block headers               count=2  elapsed=6.386ms   number=9118134 hash=557055…183683
Dec 17 01:46:07 hostname geth[28935]: INFO [12-17|01:46:07.555] Imported new block headers               count=1  elapsed=5.806ms   number=9118135 hash=c9b1c7…2e1ad9
Dec 17 01:46:15 hostname geth[28935]: INFO [12-17|01:46:15.917] Imported new block headers               count=1  elapsed=5.218ms   number=9118136 hash=a54ebd…ac9968
Dec 17 01:46:37 hostname geth[28935]: INFO [12-17|01:46:37.716] Imported new block headers               count=1  elapsed=5.250ms   number=9118137 hash=1e9448…6d87bc
Dec 17 01:46:50 hostname geth[28935]: INFO [12-17|01:46:50.924] Imported new block headers               count=1  elapsed=5.637ms   number=9118138 hash=972741…2352ab
Dec 17 01:47:15 hostname geth[28935]: INFO [12-17|01:47:15.852] Imported new block headers               count=1  elapsed=21.450ms  number=9118139 hash=e6b7b9…5b15bb
Dec 17 01:47:24 hostname geth[28935]: INFO [12-17|01:47:24.701] Imported new block headers               count=1  elapsed=5.068ms   number=9118140 hash=38b989…d1b5ba
Dec 17 01:47:34 hostname geth[28935]: INFO [12-17|01:47:34.705] Imported new block headers               count=1  elapsed=5.195ms   number=9118141 hash=5010a6…0d6d73
Dec 17 01:47:35 hostname geth[28935]: INFO [12-17|01:47:35.909] Imported new block headers               count=1  elapsed=5.351ms   number=9118142 hash=c1f584…7c140e
Dec 17 01:47:59 hostname geth[28935]: INFO [12-17|01:47:59.008] Imported new block headers               count=1  elapsed=5.570ms   number=9118143 hash=c1581c…bee5e4
Dec 17 01:48:06 hostname geth[28935]: INFO [12-17|01:48:06.459] Imported new block headers               count=1  elapsed=5.285ms   number=9118144 hash=417b31…ce9960
Dec 17 01:48:07 hostname geth[28935]: INFO [12-17|01:48:07.483] Imported new block headers               count=1  elapsed=5.339ms   number=9118145 hash=571e62…18dd2f
Dec 17 01:48:20 hostname geth[28935]: INFO [12-17|01:48:20.952] Imported new block headers               count=1  elapsed=5.326ms   number=9118146 hash=d9aafa…c79817
Dec 17 01:48:28 hostname geth[28935]: INFO [12-17|01:48:28.953] Imported new block headers               count=1  elapsed=5.408ms   number=9118147 hash=68c90f…360088
Dec 17 01:48:52 hostname geth[28935]: INFO [12-17|01:48:52.003] Imported new block headers               count=1  elapsed=8.056ms   number=9118148 hash=400d94…752aba
Dec 17 01:48:57 hostname geth[28935]: INFO [12-17|01:48:57.368] Imported new block headers               count=1  elapsed=4.991ms   number=9118149 hash=e62d38…1a976b
Dec 17 01:49:44 hostname geth[28935]: INFO [12-17|01:49:44.192] Imported new block headers               count=1  elapsed=5.208ms   number=9118150 hash=aa833b…ea93c7
Dec 17 01:49:47 hostname geth[28935]: INFO [12-17|01:49:47.700] Imported new block headers               count=1  elapsed=5.049ms   number=9118151 hash=5d3b34…35d78a
Dec 17 01:49:58 hostname geth[28935]: INFO [12-17|01:49:58.592] Imported new block headers               count=1  elapsed=5.457ms   number=9118152 hash=0100c7…ece888
Dec 17 01:50:18 hostname geth[28935]: INFO [12-17|01:50:18.323] Imported new block headers               count=1  elapsed=5.232ms   number=9118153 hash=38ec34…5b77ee
Dec 17 01:50:51 hostname geth[28935]: INFO [12-17|01:50:51.940] Imported new block headers               count=1  elapsed=5.500ms   number=9118154 hash=a56501…f18f8d
Dec 17 01:50:53 hostname geth[28935]: INFO [12-17|01:50:53.555] Imported new block headers               count=2  elapsed=5.726ms   number=9118155 hash=6df14a…29371b
Dec 17 01:50:54 hostname geth[28935]: INFO [12-17|01:50:54.036] Imported new block headers               count=1  elapsed=5.288ms   number=9118156 hash=db5694…7c4523
Dec 17 01:51:03 hostname geth[28935]: INFO [12-17|01:51:03.611] Imported new block headers               count=1  elapsed=5.810ms   number=9118157 hash=746736…28b1fd
Dec 17 01:51:27 hostname geth[28935]: INFO [12-17|01:51:27.902] Imported new block headers               count=1  elapsed=5.413ms   number=9118158 hash=b880c4…ff2c09
Dec 17 01:51:30 hostname geth[28935]: INFO [12-17|01:51:30.541] Imported new block headers               count=1  elapsed=5.077ms   number=9118159 hash=8d8305…1297e4
Dec 17 01:51:51 hostname geth[28935]: INFO [12-17|01:51:51.625] Imported new block headers               count=1  elapsed=5.628ms   number=9118160 hash=aa82da…196053
Dec 17 01:52:37 hostname geth[28935]: INFO [12-17|01:52:37.316] Imported new block headers               count=1  elapsed=5.392ms   number=9118161 hash=c73b9f…10261c
Dec 17 01:53:08 hostname geth[28935]: INFO [12-17|01:53:08.371] Imported new block headers               count=1  elapsed=5.143ms   number=9118162 hash=3b357c…b5d33e
Dec 17 01:53:10 hostname geth[28935]: INFO [12-17|01:53:10.727] Imported new block headers               count=1  elapsed=5.201ms   number=9118163 hash=81763d…e4f240
Dec 17 01:53:12 hostname geth[28935]: INFO [12-17|01:53:12.967] Imported new block headers               count=1  elapsed=5.415ms   number=9118164 hash=5775aa…714241
Dec 17 01:53:15 hostname geth[28935]: INFO [12-17|01:53:15.513] Imported new block headers               count=1  elapsed=5.147ms   number=9118165 hash=83b1e4…084a9e
Dec 17 01:53:31 hostname geth[28935]: INFO [12-17|01:53:31.170] Imported new block headers               count=1  elapsed=5.158ms   number=9118166 hash=ed1a88…90f9de
Dec 17 01:53:41 hostname geth[28935]: INFO [12-17|01:53:41.862] Imported new block headers               count=1  elapsed=4.903ms   number=9118167 hash=4dc163…f86fdb
Dec 17 01:53:43 hostname geth[28935]: INFO [12-17|01:53:43.364] Imported new block headers               count=1  elapsed=5.362ms   number=9118168 hash=77d43b…eb7f6a
Dec 17 01:54:06 hostname geth[28935]: INFO [12-17|01:54:06.342] Imported new block headers               count=1  elapsed=5.152ms   number=9118169 hash=56a51f…f12549
Dec 17 01:54:42 hostname geth[28935]: INFO [12-17|01:54:42.264] Imported new block headers               count=1  elapsed=5.172ms   number=9118170 hash=c3bb19…904a0e
Dec 17 01:55:05 hostname geth[28935]: INFO [12-17|01:55:05.310] Imported new block headers               count=1  elapsed=5.543ms   number=9118171 hash=f8364f…3e646e
Dec 17 01:55:19 hostname geth[28935]: INFO [12-17|01:55:19.170] Imported new block headers               count=1  elapsed=5.147ms   number=9118172 hash=53d13d…51d422
Dec 17 01:55:20 hostname geth[28935]: INFO [12-17|01:55:20.580] Imported new block headers               count=1  elapsed=5.298ms   number=9118173 hash=a8ba21…0719d4
Dec 17 01:55:26 hostname geth[28935]: INFO [12-17|01:55:26.824] Imported new block headers               count=1  elapsed=5.178ms   number=9118174 hash=2bc425…f1deb7
Dec 17 01:55:57 hostname geth[28935]: INFO [12-17|01:55:57.626] Imported new block headers               count=1  elapsed=5.035ms   number=9118175 hash=6026b3…0bf73c
Dec 17 01:56:14 hostname geth[28935]: INFO [12-17|01:56:14.502] Imported new block headers               count=1  elapsed=5.526ms   number=9118176 hash=974c1c…6520ba
Dec 17 01:57:23 hostname geth[28935]: INFO [12-17|01:57:23.483] Imported new block headers               count=1  elapsed=5.212ms   number=9118177 hash=687fe1…03d187 age=1m10s
Dec 17 01:57:25 hostname geth[28935]: INFO [12-17|01:57:25.780] Imported new block headers               count=1  elapsed=5.384ms   number=9118178 hash=e5c6ff…9beb93
Dec 17 01:57:30 hostname geth[28935]: INFO [12-17|01:57:30.028] Imported new block headers               count=1  elapsed=5.395ms   number=9118179 hash=6cc11c…ab776a
Dec 17 01:57:38 hostname geth[28935]: INFO [12-17|01:57:38.623] Imported new block headers               count=1  elapsed=5.482ms   number=9118180 hash=2c7870…381611
Dec 17 01:57:53 hostname geth[28935]: INFO [12-17|01:57:53.965] Imported new block headers               count=1  elapsed=5.229ms   number=9118180 hash=97b7e5…45faaf
Dec 17 01:57:54 hostname geth[28935]: INFO [12-17|01:57:54.472] Imported new block headers               count=1  elapsed=5.039ms   number=9118181 hash=975f95…11761b
Dec 17 01:58:15 hostname geth[28935]: INFO [12-17|01:58:15.647] Imported new block headers               count=1  elapsed=5.391ms   number=9118182 hash=53728d…5b1040
Dec 17 01:59:07 hostname geth[28935]: INFO [12-17|01:59:07.330] Imported new block headers               count=1  elapsed=5.090ms   number=9118183 hash=6da755…c866ba
Dec 17 01:59:29 hostname geth[28935]: INFO [12-17|01:59:29.114] Imported new block headers               count=1  elapsed=5.046ms   number=9118184 hash=249323…334c7b
Dec 17 01:59:50 hostname geth[28935]: INFO [12-17|01:59:50.401] Imported new block headers               count=1  elapsed=5.757ms   number=9118185 hash=96fbaa…92dc39
Dec 17 01:59:53 hostname geth[28935]: INFO [12-17|01:59:53.375] Imported new block headers               count=1  elapsed=5.046ms   number=9118186 hash=a9211c…e6a584
Dec 17 02:00:10 hostname geth[28935]: INFO [12-17|02:00:10.011] Imported new block headers               count=1  elapsed=10.578ms  number=9118187 hash=105a9b…63fde2
Dec 17 02:00:13 hostname geth[28935]: INFO [12-17|02:00:13.860] Imported new block headers               count=1  elapsed=5.155ms   number=9118188 hash=a87fae…b4f4d0
Dec 17 02:00:48 hostname geth[28935]: INFO [12-17|02:00:48.951] Imported new block headers               count=1  elapsed=5.089ms   number=9118189 hash=45bbae…55f1b1
Dec 17 02:00:50 hostname geth[28935]: INFO [12-17|02:00:50.276] Imported new block headers               count=1  elapsed=5.476ms   number=9118190 hash=7966cc…13d592
Dec 17 02:01:06 hostname geth[28935]: INFO [12-17|02:01:06.755] Imported new block headers               count=1  elapsed=5.304ms   number=9118191 hash=7d6dfc…bcb520
Dec 17 02:01:17 hostname geth[28935]: INFO [12-17|02:01:17.316] Imported new block headers               count=1  elapsed=5.135ms   number=9118192 hash=32d86f…b25848
Dec 17 02:01:21 hostname geth[28935]: INFO [12-17|02:01:21.816] Imported new block headers               count=1  elapsed=5.206ms   number=9118193 hash=b75669…f2f5e8
Dec 17 02:01:34 hostname geth[28935]: INFO [12-17|02:01:34.805] Imported new block headers               count=1  elapsed=5.110ms   number=9118194 hash=e1d177…e41ec6
Dec 17 02:02:15 hostname geth[28935]: INFO [12-17|02:02:15.562] Imported new block headers               count=1  elapsed=6.015ms   number=9118195 hash=c8eb82…e2a596
Dec 17 02:02:29 hostname geth[28935]: INFO [12-17|02:02:29.974] Imported new block headers               count=1  elapsed=5.266ms   number=9118196 hash=1babbe…dc9369
Dec 17 02:02:43 hostname geth[28935]: INFO [12-17|02:02:43.455] Imported new block headers               count=1  elapsed=5.177ms   number=9118197 hash=09f970…72bb9b
Dec 17 02:02:53 hostname geth[28935]: INFO [12-17|02:02:53.247] Imported new block headers               count=1  elapsed=5.037ms   number=9118198 hash=7ef6c8…ddc272
Dec 17 02:03:00 hostname geth[28935]: INFO [12-17|02:03:00.170] Imported new block headers               count=1  elapsed=5.077ms   number=9118199 hash=c262cc…48e545
Dec 17 02:03:23 hostname geth[28935]: INFO [12-17|02:03:23.840] Imported new block headers               count=1  elapsed=4.990ms   number=9118200 hash=3aff4b…31c4b7
Dec 17 02:03:28 hostname geth[28935]: INFO [12-17|02:03:28.328] Imported new block headers               count=1  elapsed=5.286ms   number=9118201 hash=e6e750…c2f62d
Dec 17 02:03:39 hostname geth[28935]: INFO [12-17|02:03:39.621] Imported new block headers               count=1  elapsed=5.061ms   number=9118202 hash=a09255…1b218c
Dec 17 02:03:47 hostname geth[28935]: INFO [12-17|02:03:47.920] Imported new block headers               count=2  elapsed=5.673ms   number=9118203 hash=65de41…10760a
Dec 17 02:04:47 hostname geth[28935]: INFO [12-17|02:04:47.689] Imported new block headers               count=1  elapsed=5.251ms   number=9118204 hash=0a47f2…e05604 age=1m
Dec 17 02:05:40 hostname geth[28935]: INFO [12-17|02:05:40.584] Imported new block headers               count=1  elapsed=5.329ms   number=9118205 hash=f5e482…6624c4
Dec 17 02:05:54 hostname geth[28935]: INFO [12-17|02:05:54.172] Imported new block headers               count=1  elapsed=4.988ms   number=9118206 hash=c18b05…d893e4
Dec 17 02:06:01 hostname geth[28935]: INFO [12-17|02:06:01.812] Imported new block headers               count=1  elapsed=5.944ms   number=9118207 hash=3c840a…8f3823
Dec 17 02:06:07 hostname geth[28935]: INFO [12-17|02:06:07.613] Imported new block headers               count=1  elapsed=5.434ms   number=9118208 hash=e8c100…92b36e
Dec 17 02:06:15 hostname geth[28935]: INFO [12-17|02:06:15.341] Imported new block headers               count=1  elapsed=5.963ms   number=9118209 hash=ca95b4…425e93
Dec 17 02:06:45 hostname geth[28935]: INFO [12-17|02:06:45.152] Imported new block headers               count=1  elapsed=5.294ms   number=9118210 hash=a786b3…56e84d
Dec 17 02:06:49 hostname geth[28935]: INFO [12-17|02:06:49.576] Imported new block headers               count=1  elapsed=5.665ms   number=9118211 hash=923076…689a63
Dec 17 02:07:28 hostname geth[28935]: INFO [12-17|02:07:28.506] Imported new block headers               count=1  elapsed=5.915ms   number=9118212 hash=771784…32fa1f
Dec 17 02:08:03 hostname geth[28935]: INFO [12-17|02:08:03.335] Imported new block headers               count=1  elapsed=5.328ms   number=9118213 hash=b3d8ef…aaa2ca
Dec 17 02:08:15 hostname geth[28935]: INFO [12-17|02:08:15.091] Imported new block headers               count=1  elapsed=5.351ms   number=9118214 hash=b9651b…4c729a
Dec 17 02:08:44 hostname geth[28935]: INFO [12-17|02:08:44.230] Imported new block headers               count=1  elapsed=5.097ms   number=9118215 hash=206ed6…c1ed0a
Dec 17 02:09:00 hostname geth[28935]: INFO [12-17|02:09:00.113] Imported new block headers               count=1  elapsed=5.216ms   number=9118216 hash=489284…d2cbc1
Dec 17 02:09:16 hostname geth[28935]: INFO [12-17|02:09:16.898] Imported new block headers               count=1  elapsed=5.287ms   number=9118217 hash=c5f760…c93a75
Dec 17 02:09:29 hostname geth[28935]: INFO [12-17|02:09:29.412] Imported new block headers               count=1  elapsed=5.189ms   number=9118218 hash=85c5e0…e63c58
Dec 17 02:10:01 hostname geth[28935]: INFO [12-17|02:10:01.624] Imported new block headers               count=1  elapsed=5.213ms   number=9118219 hash=e17b69…8fb6a3
Dec 17 02:10:47 hostname geth[28935]: INFO [12-17|02:10:47.500] Imported new block headers               count=1  elapsed=5.739ms   number=9118220 hash=de50f5…6161f1
Dec 17 02:11:37 hostname geth[28935]: INFO [12-17|02:11:37.206] Imported new block headers               count=1  elapsed=5.431ms   number=9118221 hash=648a18…61c2e8
Dec 17 02:12:26 hostname geth[28935]: INFO [12-17|02:12:26.858] Imported new block headers               count=1  elapsed=5.269ms   number=9118222 hash=b248e4…8e4781
Dec 17 02:12:30 hostname geth[28935]: INFO [12-17|02:12:30.159] Imported new block headers               count=1  elapsed=5.614ms   number=9118223 hash=3b653a…7fdcdd
Dec 17 02:13:03 hostname geth[28935]: INFO [12-17|02:13:03.100] Imported new block headers               count=1  elapsed=5.657ms   number=9118224 hash=4488ee…4d4394
Dec 17 02:13:27 hostname geth[28935]: INFO [12-17|02:13:27.083] Imported new block headers               count=1  elapsed=5.427ms   number=9118225 hash=dd8c20…0ca3a5
Dec 17 02:13:32 hostname geth[28935]: INFO [12-17|02:13:32.987] Imported new block headers               count=1  elapsed=5.828ms   number=9118226 hash=7f0f71…7836b8
Dec 17 02:13:34 hostname geth[28935]: INFO [12-17|02:13:34.951] Imported new block headers               count=1  elapsed=5.140ms   number=9118227 hash=dc4896…9f781e
Dec 17 02:13:36 hostname geth[28935]: INFO [12-17|02:13:36.376] Imported new block headers               count=1  elapsed=5.346ms   number=9118228 hash=ad80ab…e3e7a8
Dec 17 02:13:41 hostname geth[28935]: INFO [12-17|02:13:41.094] Imported new block headers               count=1  elapsed=5.258ms   number=9118229 hash=c4107e…2ff36a
Dec 17 02:13:55 hostname geth[28935]: INFO [12-17|02:13:55.540] Imported new block headers               count=1  elapsed=5.191ms   number=9118230 hash=fa6f9c…d58148
Dec 17 02:14:03 hostname geth[28935]: INFO [12-17|02:14:03.379] Imported new block headers               count=1  elapsed=5.747ms   number=9118231 hash=e1db57…dfc80a
Dec 17 02:14:18 hostname geth[28935]: INFO [12-17|02:14:18.322] Imported new block headers               count=1  elapsed=5.095ms   number=9118232 hash=d7ac2c…2779ef
Dec 17 02:14:22 hostname geth[28935]: INFO [12-17|02:14:22.672] Imported new block headers               count=1  elapsed=5.607ms   number=9118233 hash=becc8f…907ba5
Dec 17 02:14:27 hostname geth[28935]: INFO [12-17|02:14:27.988] Imported new block headers               count=1  elapsed=5.199ms   number=9118234 hash=881e9b…6678cc
Dec 17 02:14:41 hostname geth[28935]: INFO [12-17|02:14:41.754] Imported new block headers               count=1  elapsed=5.120ms   number=9118235 hash=8ec3b4…2bf667
Dec 17 02:15:13 hostname geth[28935]: INFO [12-17|02:15:13.003] Imported new block headers               count=2  elapsed=5.888ms   number=9118236 hash=16a973…dd0ad0
Dec 17 02:15:28 hostname geth[28935]: INFO [12-17|02:15:28.904] Imported new block headers               count=1  elapsed=5.695ms   number=9118237 hash=900b5b…e0c2a8
Dec 17 02:15:45 hostname geth[28935]: INFO [12-17|02:15:45.364] Imported new block headers               count=1  elapsed=5.194ms   number=9118238 hash=655999…5d4a82
Dec 17 02:16:00 hostname geth[28935]: INFO [12-17|02:16:00.795] Imported new block headers               count=1  elapsed=5.208ms   number=9118239 hash=efae4e…96ad3b
Dec 17 02:16:03 hostname geth[28935]: INFO [12-17|02:16:03.662] Imported new block headers               count=1  elapsed=5.252ms   number=9118240 hash=ff5a98…a34295
Dec 17 02:16:19 hostname geth[28935]: INFO [12-17|02:16:19.818] Imported new block headers               count=1  elapsed=5.197ms   number=9118241 hash=411c5e…316ff6
Dec 17 02:16:31 hostname geth[28935]: INFO [12-17|02:16:31.626] Imported new block headers               count=1  elapsed=5.038ms   number=9118242 hash=29cd53…928227
Dec 17 02:16:41 hostname geth[28935]: INFO [12-17|02:16:41.869] Imported new block headers               count=1  elapsed=5.651ms   number=9118243 hash=8e1963…d990f6
Dec 17 02:16:50 hostname geth[28935]: INFO [12-17|02:16:50.460] Imported new block headers               count=1  elapsed=5.059ms   number=9118244 hash=aebfdb…4b4e16
Dec 17 02:16:52 hostname geth[28935]: INFO [12-17|02:16:52.473] Imported new block headers               count=1  elapsed=5.164ms   number=9118245 hash=1b4767…ea80f3
Dec 17 02:17:01 hostname geth[28935]: INFO [12-17|02:17:01.864] Imported new block headers               count=1  elapsed=6.086ms   number=9118246 hash=9ecef9…4612fc
Dec 17 02:17:03 hostname geth[28935]: INFO [12-17|02:17:03.825] Imported new block headers               count=1  elapsed=5.166ms   number=9118247 hash=a421cc…3ed538
Dec 17 02:17:05 hostname geth[28935]: INFO [12-17|02:17:05.321] Imported new block headers               count=1  elapsed=5.151ms   number=9118248 hash=009e8c…3c92fb
Dec 17 02:17:08 hostname geth[28935]: INFO [12-17|02:17:08.006] Imported new block headers               count=1  elapsed=5.303ms   number=9118249 hash=962329…5ed5a6
Dec 17 02:17:13 hostname geth[28935]: INFO [12-17|02:17:13.840] Imported new block headers               count=1  elapsed=5.086ms   number=9118250 hash=3124b4…613db8
Dec 17 02:17:14 hostname geth[28935]: INFO [12-17|02:17:14.949] Imported new block headers               count=1  elapsed=5.624ms   number=9118251 hash=6a177d…675141
Dec 17 02:17:20 hostname geth[28935]: INFO [12-17|02:17:20.441] Imported new block headers               count=1  elapsed=5.909ms   number=9118252 hash=c79772…54dd02
Dec 17 02:17:21 hostname geth[28935]: INFO [12-17|02:17:21.858] Imported new block headers               count=1  elapsed=5.064ms   number=9118253 hash=6e2809…295d97
Dec 17 02:17:49 hostname geth[28935]: INFO [12-17|02:17:49.045] Imported new block headers               count=1  elapsed=5.413ms   number=9118254 hash=1e8f12…24ac43
Dec 17 02:17:50 hostname geth[28935]: INFO [12-17|02:17:50.686] Imported new block headers               count=1  elapsed=5.014ms   number=9118255 hash=37dec3…bbb39a
Dec 17 02:17:53 hostname geth[28935]: INFO [12-17|02:17:53.827] Imported new block headers               count=1  elapsed=5.131ms   number=9118256 hash=7730ad…7527f8
Dec 17 02:17:54 hostname geth[28935]: INFO [12-17|02:17:54.578] Imported new block headers               count=1  elapsed=5.185ms   number=9118257 hash=33995b…4b8ec2
Dec 17 02:18:43 hostname geth[28935]: INFO [12-17|02:18:43.997] Imported new block headers               count=1  elapsed=4.999ms   number=9118258 hash=1c5337…00f8c0
Dec 17 02:18:46 hostname geth[28935]: INFO [12-17|02:18:46.631] Imported new block headers               count=1  elapsed=5.489ms   number=9118259 hash=c1f541…9f898a
Dec 17 02:19:57 hostname geth[28935]: INFO [12-17|02:19:57.777] Imported new block headers               count=1  elapsed=5.231ms   number=9118260 hash=ffc6cb…5046c5 age=1m11s
Dec 17 02:20:06 hostname geth[28935]: INFO [12-17|02:20:06.668] Imported new block headers               count=1  elapsed=5.083ms   number=9118261 hash=6081ae…de28ec
Dec 17 02:21:49 hostname geth[28935]: INFO [12-17|02:21:49.872] Imported new block headers               count=1  elapsed=5.159ms   number=9118262 hash=eb2a9e…d06ba6 age=1m43s
Dec 17 02:21:59 hostname geth[28935]: INFO [12-17|02:21:59.534] Imported new block headers               count=1  elapsed=4.987ms   number=9118263 hash=541993…96cb08
Dec 17 02:22:02 hostname geth[28935]: INFO [12-17|02:22:02.353] Imported new block headers               count=1  elapsed=5.388ms   number=9118264 hash=d29553…1bf886
Dec 17 02:22:57 hostname geth[28935]: INFO [12-17|02:22:57.515] Imported new block headers               count=1  elapsed=5.291ms   number=9118265 hash=067c70…ffda7e
Dec 17 02:23:11 hostname geth[28935]: INFO [12-17|02:23:11.952] Imported new block headers               count=1  elapsed=5.955ms   number=9118266 hash=106e22…b983c6
Dec 17 02:23:15 hostname geth[28935]: INFO [12-17|02:23:15.793] Imported new block headers               count=1  elapsed=5.061ms   number=9118267 hash=dfdbc6…7b3824
Dec 17 02:23:25 hostname geth[28935]: INFO [12-17|02:23:25.349] Imported new block headers               count=1  elapsed=5.532ms   number=9118268 hash=d48c1a…71f813
Dec 17 02:23:36 hostname geth[28935]: INFO [12-17|02:23:36.096] Imported new block headers               count=1  elapsed=5.292ms   number=9118269 hash=de4f18…9e75c6
Dec 17 02:24:29 hostname geth[28935]: INFO [12-17|02:24:29.134] Imported new block headers               count=1  elapsed=5.799ms   number=9118270 hash=c19727…ae0df2
Dec 17 02:24:38 hostname geth[28935]: INFO [12-17|02:24:38.322] Imported new block headers               count=1  elapsed=5.379ms   number=9118271 hash=a7058e…fc9c1b
Dec 17 02:25:35 hostname geth[28935]: INFO [12-17|02:25:35.401] Imported new block headers               count=1  elapsed=5.148ms   number=9118272 hash=46a47d…10883d
Dec 17 02:25:59 hostname geth[28935]: INFO [12-17|02:25:59.519] Imported new block headers               count=1  elapsed=5.128ms   number=9118273 hash=07f6e8…83ed81
Dec 17 02:26:01 hostname geth[28935]: INFO [12-17|02:26:01.091] Imported new block headers               count=1  elapsed=5.137ms   number=9118274 hash=723be1…043e28
Dec 17 02:26:19 hostname geth[28935]: INFO [12-17|02:26:19.882] Imported new block headers               count=1  elapsed=5.247ms   number=9118275 hash=0012c6…df10ce
Dec 17 02:26:21 hostname geth[28935]: INFO [12-17|02:26:21.625] Imported new block headers               count=1  elapsed=5.143ms   number=9118276 hash=3689c5…392655
Dec 17 02:26:25 hostname geth[28935]: INFO [12-17|02:26:25.765] Imported new block headers               count=1  elapsed=5.314ms   number=9118277 hash=3df674…ef368c
Dec 17 02:27:15 hostname geth[28935]: INFO [12-17|02:27:15.281] Imported new block headers               count=1  elapsed=10.449ms  number=9118278 hash=0e347f…fe5228
Dec 17 02:27:20 hostname geth[28935]: INFO [12-17|02:27:20.640] Imported new block headers               count=1  elapsed=5.644ms   number=9118279 hash=ce529d…4b0c86
Dec 17 02:27:39 hostname geth[28935]: INFO [12-17|02:27:39.507] Imported new block headers               count=1  elapsed=5.517ms   number=9118280 hash=71d529…1a7134
Dec 17 02:28:15 hostname geth[28935]: INFO [12-17|02:28:15.010] Imported new block headers               count=1  elapsed=14.873ms  number=9118281 hash=4928a5…97bbee
Dec 17 02:28:31 hostname geth[28935]: INFO [12-17|02:28:31.270] Imported new block headers               count=1  elapsed=5.090ms   number=9118282 hash=d0df91…e4f821
Dec 17 02:28:41 hostname geth[28935]: INFO [12-17|02:28:41.499] Imported new block headers               count=1  elapsed=5.258ms   number=9118283 hash=2b3099…353a00
Dec 17 02:28:53 hostname geth[28935]: INFO [12-17|02:28:53.825] Imported new block headers               count=1  elapsed=5.213ms   number=9118284 hash=72abc9…0c346a
Dec 17 02:29:19 hostname geth[28935]: INFO [12-17|02:29:19.879] Imported new block headers               count=1  elapsed=5.031ms   number=9118285 hash=55e985…0ddc8d
Dec 17 02:29:23 hostname geth[28935]: INFO [12-17|02:29:23.627] Imported new block headers               count=1  elapsed=5.355ms   number=9118286 hash=1e0093…6f67c0
Dec 17 02:29:28 hostname geth[28935]: INFO [12-17|02:29:28.508] Imported new block headers               count=1  elapsed=5.585ms   number=9118287 hash=426220…65dff3
Dec 17 02:29:38 hostname geth[28935]: INFO [12-17|02:29:38.555] Imported new block headers               count=1  elapsed=5.323ms   number=9118288 hash=258997…d6c93d
@c0deright
Copy link
Author

c0deright commented Dec 17, 2019

CPU still at 100% but geth synced some blocks:

Dec 17 12:55:11 hostname geth[28935]: INFO [12-17|12:55:11.504] Imported new block headers               count=1  elapsed=5.288ms   number=9120452 hash=395ccc…8b9f75 age=1m43s
Dec 17 12:55:44 hostname geth[28935]: INFO [12-17|12:55:44.770] Imported new block headers               count=1  elapsed=5.093ms   number=9120454 hash=e30c03…2bbee4 age=1m39s
Dec 17 13:38:21 hostname geth[28935]: WARN [12-17|13:38:21.345] Served eth_coinbase                      reqid=3 t=24.961µs err="mining is not supported in light mode"
Dec 17 13:57:49 hostname geth[28935]: INFO [12-17|13:57:49.398] Imported new block headers               count=1  elapsed=4.862ms   number=9120455 hash=cd446e…b56cc8 age=1h2m41s
Dec 17 13:58:29 hostname geth[28935]: WARN [12-17|13:58:29.828] Served eth_getBlockByNumber              reqid=6 t=20m8.478895412s err="no suitable peers available"
Dec 17 13:58:30 hostname geth[28935]: INFO [12-17|13:58:30.944] Imported new block headers               count=192 elapsed=481.742ms number=9120647 hash=693885…8cc556 age=9m17s
Dec 17 13:58:33 hostname geth[28935]: INFO [12-17|13:58:33.628] Imported new block headers               count=32  elapsed=94.508ms  number=9120679 hash=01e1b0…015613
Dec 17 13:59:14 hostname geth[28935]: INFO [12-17|13:59:14.856] Imported new block headers               count=3   elapsed=10.073ms  number=9120682 hash=c028c4…6b6a9f
Dec 17 14:00:35 hostname geth[28935]: WARN [12-17|14:00:35.095] Synchronisation failed, retrying         err="peer is unknown or unhealthy"
Dec 17 14:01:15 hostname geth[28935]: INFO [12-17|14:01:15.578] Imported new block headers               count=7   elapsed=23.893ms  number=9120689 hash=b6df6e…ae115e ignored=1
Dec 17 14:03:53 hostname geth[28935]: WARN [12-17|14:03:53.491] Served eth_coinbase                      reqid=3 t=24.497µs        err="mining is not supported in light mode"

Sent SIGTERM

Dec 17 14:04:53 hostname geth[28935]: INFO [12-17|14:04:53.859] Got interrupt, shutting down...
Dec 17 14:04:53 hostname geth[28935]: INFO [12-17|14:04:53.860] HTTP endpoint closed                     url=http://0.0.0.0:8545
Dec 17 14:04:53 hostname geth[28935]: INFO [12-17|14:04:53.860] IPC endpoint closed                      url=/home/geth/.ethereum/geth.ipc
Dec 17 14:06:38 hostname geth[28935]: INFO [12-17|14:06:38.971] Blockchain manager stopped
Dec 17 14:06:38 hostname geth[28935]: WARN [12-17|14:06:38.972] Served eth_getBlockByNumber              reqid=6 t=2m45.476373271s err="client is shutting down"
Dec 17 14:06:46 hostname geth[28935]: ERROR[12-17|14:06:46.357] Light Ethereum peer registration failed  id=0cdf4a93328f21e6 conn=staticdial err="peer set is closed"
Dec 17 14:06:46 hostname geth[28935]: ERROR[12-17|14:06:46.584] Light Ethereum peer registration failed  id=0d07ab41e18ff942 conn=staticdial err="peer set is closed"
Dec 17 14:06:46 hostname geth[28935]: ERROR[12-17|14:06:46.744] Light Ethereum peer registration failed  id=6085df138bc6b494 conn=staticdial err="peer set is closed"
Dec 17 14:06:46 hostname geth[28935]: ERROR[12-17|14:06:46.818] Light Ethereum peer registration failed  id=74df075b0a2b5fca conn=staticdial err="peer set is closed"
Dec 17 14:06:52 hostname geth[28935]: ERROR[12-17|14:06:52.474] Light Ethereum peer registration failed  id=7459dfca2c2a3114 conn=staticdial err="peer set is closed"
Dec 17 14:06:52 hostname geth[28935]: ERROR[12-17|14:06:52.538] Light Ethereum peer registration failed  id=a5a3635a2e61cffe conn=staticdial err="peer set is closed"
Dec 17 14:06:53 hostname geth[28935]: ERROR[12-17|14:06:53.322] Light Ethereum peer registration failed  id=2097603f3e07429b conn=staticdial err="peer set is closed"
Dec 17 14:06:57 hostname geth[28935]: ERROR[12-17|14:06:57.010] Light Ethereum peer registration failed  id=2f2cbb4523b32e34 conn=staticdial err="peer set is closed"
Dec 17 14:07:23 hostname geth[28935]: ERROR[12-17|14:07:23.159] Light Ethereum peer registration failed  id=0d07ab41e18ff942 conn=staticdial err="peer set is closed"
Dec 17 14:07:29 hostname geth[28935]: ERROR[12-17|14:07:29.160] Light Ethereum peer registration failed  id=0cdf4a93328f21e6 conn=staticdial err="peer set is closed"
Dec 17 14:07:30 hostname geth[28935]: ERROR[12-17|14:07:30.064] Light Ethereum peer registration failed  id=6085df138bc6b494 conn=staticdial err="peer set is closed"
Dec 17 14:07:30 hostname geth[28935]: ERROR[12-17|14:07:30.110] Light Ethereum peer registration failed  id=74df075b0a2b5fca conn=staticdial err="peer set is closed"
Dec 17 14:07:30 hostname geth[28935]: ERROR[12-17|14:07:30.686] Light Ethereum peer registration failed  id=7459dfca2c2a3114 conn=staticdial err="peer set is closed"
Dec 17 14:07:31 hostname geth[28935]: ERROR[12-17|14:07:31.489] Light Ethereum peer registration failed  id=2097603f3e07429b conn=staticdial err="peer set is closed"
Dec 17 14:07:32 hostname geth[28935]: ERROR[12-17|14:07:32.265] Light Ethereum peer registration failed  id=a5a3635a2e61cffe conn=staticdial err="peer set is closed"

Still running, only ~5% CPU

@c0deright
Copy link
Author

Sent some more SIGKILL until geth panicked and was restarted by systemd:

Dec 17 14:13:11 hostname geth[28935]: WARN [12-17|14:13:11.377] Already shutting down, interrupt more to panic. times=3
Dec 17 14:13:11 hostname geth[28935]: WARN [12-17|14:13:11.953] Already shutting down, interrupt more to panic. times=2
Dec 17 14:13:12 hostname geth[28935]: WARN [12-17|14:13:12.496] Already shutting down, interrupt more to panic. times=1
Dec 17 14:13:18 hostname geth[28935]: panic: boom
Dec 17 14:13:18 hostname geth[28935]: goroutine 65 [running]:
Dec 17 14:13:18 hostname geth[28935]: github.com/ethereum/go-ethereum/internal/debug.LoudPanic(...)
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/src/github.com/ethereum/go-ethereum/internal/debug/loudpanic.go:26
Dec 17 14:13:18 hostname geth[28935]: github.com/ethereum/go-ethereum/cmd/utils.StartNode.func1(0xc0001e1b80)
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/src/github.com/ethereum/go-ethereum/cmd/utils/cmd.go:84 +0x2cc
Dec 17 14:13:18 hostname geth[28935]: created by github.com/ethereum/go-ethereum/cmd/utils.StartNode
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/src/github.com/ethereum/go-ethereum/cmd/utils/cmd.go:70 +0xad
Dec 17 14:13:18 hostname geth[28935]: goroutine 1 [chan receive, 15853 minutes]:
Dec 17 14:13:18 hostname geth[28935]: github.com/ethereum/go-ethereum/node.(*Node).Wait(0xc0001e1b80)
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/src/github.com/ethereum/go-ethereum/node/node.go:492 +0x7c
Dec 17 14:13:18 hostname geth[28935]: main.geth(0xc0002c6580, 0x0, 0x0)
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/src/github.com/ethereum/go-ethereum/cmd/geth/main.go:307 +0xd9
Dec 17 14:13:18 hostname geth[28935]: gopkg.in/urfave/cli%2ev1.HandleAction(0xfa2c60, 0x1229420, 0xc0002c6580, 0xc000034ae0, 0x0)
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/pkg/mod/gopkg.in/urfave/[email protected]/app.go:490 +0xc8
Dec 17 14:13:18 hostname geth[28935]: gopkg.in/urfave/cli%2ev1.(*App).Run(0xc000098000, 0xc0000320f0, 0xf, 0xf, 0x0, 0x0)
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/pkg/mod/gopkg.in/urfave/[email protected]/app.go:264 +0x58c
Dec 17 14:13:18 hostname geth[28935]: main.main()
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/src/github.com/ethereum/go-ethereum/cmd/geth/main.go:246 +0x55
Dec 17 14:13:18 hostname geth[28935]: goroutine 19 [chan receive, 15853 minutes]:
Dec 17 14:13:18 hostname geth[28935]: github.com/rjeczalik/notify.(*nonrecursiveTree).dispatch(0xc0000d4240, 0xc0000d4180)
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/pkg/mod/github.com/rjeczalik/[email protected]/tree_nonrecursive.go:36 +0xb6
Dec 17 14:13:18 hostname geth[28935]: created by github.com/rjeczalik/notify.newNonrecursiveTree
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/pkg/mod/github.com/rjeczalik/[email protected]/tree_nonrecursive.go:29 +0xdc
Dec 17 14:13:18 hostname geth[28935]: goroutine 20 [chan receive, 15853 minutes]:
Dec 17 14:13:18 hostname geth[28935]: github.com/rjeczalik/notify.(*nonrecursiveTree).internal(0xc0000d4240, 0xc0000d41e0)
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/pkg/mod/github.com/rjeczalik/[email protected]/tree_nonrecursive.go:81 +0x58
Dec 17 14:13:18 hostname geth[28935]: created by github.com/rjeczalik/notify.newNonrecursiveTree
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/pkg/mod/github.com/rjeczalik/[email protected]/tree_nonrecursive.go:30 +0x108
Dec 17 14:13:18 hostname geth[28935]: goroutine 21 [syscall]:
Dec 17 14:13:18 hostname geth[28935]: os/signal.signal_recv(0x13e5800)
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/.go/src/runtime/sigqueue.go:147 +0x9c
Dec 17 14:13:18 hostname geth[28935]: os/signal.loop()
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/.go/src/os/signal/signal_unix.go:23 +0x22
Dec 17 14:13:18 hostname geth[28935]: created by os/signal.init.0
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/.go/src/os/signal/signal_unix.go:29 +0x41
Dec 17 14:13:18 hostname geth[28935]: goroutine 22 [chan receive, 15853 minutes]:
Dec 17 14:13:18 hostname geth[28935]: github.com/ethereum/go-ethereum/core.(*txSenderCacher).cache(0xc00018e370)
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/src/github.com/ethereum/go-ethereum/core/tx_cacher.go:63 +0x97
Dec 17 14:13:18 hostname geth[28935]: created by github.com/ethereum/go-ethereum/core.newTxSenderCacher
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/src/github.com/ethereum/go-ethereum/core/tx_cacher.go:55 +0x9f
Dec 17 14:13:18 hostname geth[28935]: goroutine 23 [chan receive, 15853 minutes]:
Dec 17 14:13:18 hostname geth[28935]: github.com/ethereum/go-ethereum/core.(*txSenderCacher).cache(0xc00018e370)
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/src/github.com/ethereum/go-ethereum/core/tx_cacher.go:63 +0x97
Dec 17 14:13:18 hostname geth[28935]: created by github.com/ethereum/go-ethereum/core.newTxSenderCacher
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/src/github.com/ethereum/go-ethereum/core/tx_cacher.go:55 +0x9f
Dec 17 14:13:18 hostname geth[28935]: goroutine 7 [select]:
Dec 17 14:13:18 hostname geth[28935]: github.com/ethereum/go-ethereum/consensus/ethash.(*remoteSealer).loop(0xc0000321e0)
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/src/github.com/ethereum/go-ethereum/consensus/ethash/sealer.go:278 +0x276
Dec 17 14:13:18 hostname geth[28935]: created by github.com/ethereum/go-ethereum/consensus/ethash.startRemoteSealer
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/src/github.com/ethereum/go-ethereum/consensus/ethash/sealer.go:262 +0x2ae
Dec 17 14:13:18 hostname geth[28935]: goroutine 6 [chan receive]:
Dec 17 14:13:18 hostname geth[28935]: github.com/ethereum/go-ethereum/metrics.(*meterArbiter).tick(0x1e93ee0)
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/src/github.com/ethereum/go-ethereum/metrics/meter.go:289 +0x78
Dec 17 14:13:18 hostname geth[28935]: created by github.com/ethereum/go-ethereum/metrics.NewMeterForced
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/src/github.com/ethereum/go-ethereum/metrics/meter.go:70 +0x104
Dec 17 14:13:18 hostname geth[28935]: goroutine 31 [syscall, 15853 minutes]:
Dec 17 14:13:18 hostname geth[28935]: syscall.Syscall6(0xe8, 0x7, 0xc0001caf9c, 0x1, 0xffffffffffffffff, 0x0, 0x0, 0x0, 0x0, 0x0)
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/.go/src/syscall/asm_linux_amd64.s:44 +0x5
Dec 17 14:13:18 hostname geth[28935]: golang.org/x/sys/unix.EpollWait(0x7, 0xc0001caf9c, 0x1, 0x1, 0xffffffffffffffff, 0x0, 0x0, 0x0)
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/pkg/mod/golang.org/x/[email protected]/unix/zsyscall_linux_amd64.go:1760 +0x72
Dec 17 14:13:18 hostname geth[28935]: github.com/rjeczalik/notify.(*inotify).loop(0xc000120000, 0xc0001000c0)
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/pkg/mod/github.com/rjeczalik/[email protected]/watcher_inotify.go:194 +0x77
Dec 17 14:13:18 hostname geth[28935]: created by github.com/rjeczalik/notify.(*inotify).lazyinit
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/pkg/mod/github.com/rjeczalik/[email protected]/watcher_inotify.go:134 +0x15b
Dec 17 14:13:18 hostname geth[28935]: goroutine 11 [select, 15853 minutes]:
Dec 17 14:13:18 hostname geth[28935]: github.com/ethereum/go-ethereum/accounts/keystore.(*watcher).loop(0xc00019b780)
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/src/github.com/ethereum/go-ethereum/accounts/keystore/watch.go:94 +0x50d
Dec 17 14:13:18 hostname geth[28935]: created by github.com/ethereum/go-ethereum/accounts/keystore.(*watcher).start
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/src/github.com/ethereum/go-ethereum/accounts/keystore/watch.go:52 +0x59
Dec 17 14:13:18 hostname geth[28935]: goroutine 12 [select]:
Dec 17 14:13:18 hostname geth[28935]: github.com/ethereum/go-ethereum/accounts/keystore.(*KeyStore).updater(0xc0004308c0)
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/src/github.com/ethereum/go-ethereum/accounts/keystore/keystore.go:205 +0xcc
Dec 17 14:13:18 hostname geth[28935]: created by github.com/ethereum/go-ethereum/accounts/keystore.(*KeyStore).Subscribe
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/src/github.com/ethereum/go-ethereum/accounts/keystore/keystore.go:192 +0x142
Dec 17 14:13:18 hostname geth[28935]: goroutine 13 [sleep]:
Dec 17 14:13:18 hostname geth[28935]: runtime.goparkunlock(...)
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/.go/src/runtime/proc.go:310
Dec 17 14:13:18 hostname geth[28935]: time.Sleep(0x3b9aca00)
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/.go/src/runtime/time.go:105 +0x157
Dec 17 14:13:18 hostname geth[28935]: github.com/ethereum/go-ethereum/accounts/usbwallet.(*Hub).updater(0xc0000c3180)
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/src/github.com/ethereum/go-ethereum/accounts/usbwallet/hub.go:265 +0x3e
Dec 17 14:13:18 hostname geth[28935]: created by github.com/ethereum/go-ethereum/accounts/usbwallet.(*Hub).Subscribe
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/src/github.com/ethereum/go-ethereum/accounts/usbwallet/hub.go:254 +0x142
Dec 17 14:13:18 hostname geth[28935]: goroutine 14 [sleep]:
Dec 17 14:13:18 hostname geth[28935]: runtime.goparkunlock(...)
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/.go/src/runtime/proc.go:310
Dec 17 14:13:18 hostname geth[28935]: time.Sleep(0x3b9aca00)
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/.go/src/runtime/time.go:105 +0x157
Dec 17 14:13:18 hostname geth[28935]: github.com/ethereum/go-ethereum/accounts/usbwallet.(*Hub).updater(0xc0000c32c0)
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/src/github.com/ethereum/go-ethereum/accounts/usbwallet/hub.go:265 +0x3e
Dec 17 14:13:18 hostname geth[28935]: created by github.com/ethereum/go-ethereum/accounts/usbwallet.(*Hub).Subscribe
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/src/github.com/ethereum/go-ethereum/accounts/usbwallet/hub.go:254 +0x142
Dec 17 14:13:18 hostname geth[28935]: goroutine 15 [sleep]:
Dec 17 14:13:18 hostname geth[28935]: runtime.goparkunlock(...)
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/.go/src/runtime/proc.go:310
Dec 17 14:13:18 hostname geth[28935]: time.Sleep(0x3b9aca00)
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/.go/src/runtime/time.go:105 +0x157
Dec 17 14:13:18 hostname geth[28935]: github.com/ethereum/go-ethereum/accounts/usbwallet.(*Hub).updater(0xc0000c3540)
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/src/github.com/ethereum/go-ethereum/accounts/usbwallet/hub.go:265 +0x3e
Dec 17 14:13:18 hostname geth[28935]: created by github.com/ethereum/go-ethereum/accounts/usbwallet.(*Hub).Subscribe
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/src/github.com/ethereum/go-ethereum/accounts/usbwallet/hub.go:254 +0x142
Dec 17 14:13:18 hostname geth[28935]: goroutine 16 [select, 15853 minutes]:
Dec 17 14:13:18 hostname geth[28935]: github.com/ethereum/go-ethereum/accounts.(*Manager).update(0xc0000ec340)
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/src/github.com/ethereum/go-ethereum/accounts/manager.go:111 +0x1b8
Dec 17 14:13:18 hostname geth[28935]: created by github.com/ethereum/go-ethereum/accounts.NewManager
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/src/github.com/ethereum/go-ethereum/accounts/manager.go:79 +0x4be
Dec 17 14:13:18 hostname geth[28935]: goroutine 32 [chan receive, 15853 minutes]:
Dec 17 14:13:18 hostname geth[28935]: github.com/rjeczalik/notify.(*inotify).send(0xc000120000, 0xc0001000c0)
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/pkg/mod/github.com/rjeczalik/[email protected]/watcher_inotify.go:254 +0xab
Dec 17 14:13:18 hostname geth[28935]: created by github.com/rjeczalik/notify.(*inotify).lazyinit
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/pkg/mod/github.com/rjeczalik/[email protected]/watcher_inotify.go:137 +0x1b4
Dec 17 14:13:18 hostname geth[28935]: goroutine 33 [chan receive, 15853 minutes]:
Dec 17 14:13:18 hostname geth[28935]: github.com/rjeczalik/notify.(*inotify).send(0xc000120000, 0xc0001000c0)
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/pkg/mod/github.com/rjeczalik/[email protected]/watcher_inotify.go:254 +0xab
Dec 17 14:13:18 hostname geth[28935]: created by github.com/rjeczalik/notify.(*inotify).lazyinit
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/pkg/mod/github.com/rjeczalik/[email protected]/watcher_inotify.go:137 +0x1b4
Dec 17 14:13:18 hostname geth[28935]: goroutine 34 [select]:
Dec 17 14:13:18 hostname geth[28935]: github.com/syndtr/goleveldb/leveldb/util.(*BufferPool).drain(0xc000431180)
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/pkg/mod/github.com/syndtr/[email protected]/leveldb/util/buffer_pool.go:206 +0x134
Dec 17 14:13:18 hostname geth[28935]: created by github.com/syndtr/goleveldb/leveldb/util.NewBufferPool
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/pkg/mod/github.com/syndtr/[email protected]/leveldb/util/buffer_pool.go:237 +0x176
Dec 17 14:13:18 hostname geth[28935]: goroutine 35 [select, 4 minutes]:
Dec 17 14:13:18 hostname geth[28935]: github.com/syndtr/goleveldb/leveldb.(*session).refLoop(0xc000032d20)
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/pkg/mod/github.com/syndtr/[email protected]/leveldb/session_util.go:189 +0x5f9
Dec 17 14:13:18 hostname geth[28935]: created by github.com/syndtr/goleveldb/leveldb.newSession
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/pkg/mod/github.com/syndtr/[email protected]/leveldb/session.go:93 +0x2b4
Dec 17 14:13:18 hostname geth[28935]: goroutine 36 [select, 2603 minutes]:
Dec 17 14:13:18 hostname geth[28935]: github.com/syndtr/goleveldb/leveldb.(*DB).compactionError(0xc00029e1c0)
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/pkg/mod/github.com/syndtr/[email protected]/leveldb/db_compaction.go:91 +0xcd
Dec 17 14:13:18 hostname geth[28935]: created by github.com/syndtr/goleveldb/leveldb.openDB
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/pkg/mod/github.com/syndtr/[email protected]/leveldb/db.go:148 +0x40c
Dec 17 14:13:18 hostname geth[28935]: goroutine 37 [select]:
Dec 17 14:13:18 hostname geth[28935]: github.com/syndtr/goleveldb/leveldb.(*DB).mpoolDrain(0xc00029e1c0)
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/pkg/mod/github.com/syndtr/[email protected]/leveldb/db_state.go:101 +0xf6
Dec 17 14:13:18 hostname geth[28935]: created by github.com/syndtr/goleveldb/leveldb.openDB
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/pkg/mod/github.com/syndtr/[email protected]/leveldb/db.go:149 +0x42e
Dec 17 14:13:18 hostname geth[28935]: goroutine 38 [select, 2603 minutes]:
Dec 17 14:13:18 hostname geth[28935]: github.com/syndtr/goleveldb/leveldb.(*DB).tCompaction(0xc00029e1c0)
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/pkg/mod/github.com/syndtr/[email protected]/leveldb/db_compaction.go:836 +0x25d
Dec 17 14:13:18 hostname geth[28935]: created by github.com/syndtr/goleveldb/leveldb.openDB
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/pkg/mod/github.com/syndtr/[email protected]/leveldb/db.go:155 +0x582
Dec 17 14:13:18 hostname geth[28935]: goroutine 39 [select, 2603 minutes]:
Dec 17 14:13:18 hostname geth[28935]: github.com/syndtr/goleveldb/leveldb.(*DB).mCompaction(0xc00029e1c0)
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/pkg/mod/github.com/syndtr/[email protected]/leveldb/db_compaction.go:773 +0x150
Dec 17 14:13:18 hostname geth[28935]: created by github.com/syndtr/goleveldb/leveldb.openDB
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/pkg/mod/github.com/syndtr/[email protected]/leveldb/db.go:156 +0x5a4
Dec 17 14:13:18 hostname geth[28935]: goroutine 40 [select]:
Dec 17 14:13:18 hostname geth[28935]: github.com/ethereum/go-ethereum/ethdb/leveldb.(*Database).meter(0xc00011b200, 0xb2d05e00)
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/src/github.com/ethereum/go-ethereum/ethdb/leveldb/leveldb.go:411 +0x19ef
Dec 17 14:13:18 hostname geth[28935]: created by github.com/ethereum/go-ethereum/ethdb/leveldb.New
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/src/github.com/ethereum/go-ethereum/ethdb/leveldb/leveldb.go:132 +0xa25
Dec 17 14:13:18 hostname geth[28935]: goroutine 9230655 [select, 6 minutes]:
Dec 17 14:13:18 hostname geth[28935]: github.com/ethereum/go-ethereum/les.(*serverPool).setRetryDial.func1(0xc0000c23c0, 0xde59c997f2, 0xc0017bfb80)
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/src/github.com/ethereum/go-ethereum/les/serverpool.go:562 +0xd4
Dec 17 14:13:18 hostname geth[28935]: created by github.com/ethereum/go-ethereum/les.(*serverPool).setRetryDial
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/src/github.com/ethereum/go-ethereum/les/serverpool.go:561 +0xad
Dec 17 14:13:18 hostname geth[28935]: goroutine 42 [select]:
Dec 17 14:13:18 hostname geth[28935]: github.com/ethereum/go-ethereum/consensus/ethash.(*remoteSealer).loop(0xc000862960)
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/src/github.com/ethereum/go-ethereum/consensus/ethash/sealer.go:278 +0x276
Dec 17 14:13:18 hostname geth[28935]: created by github.com/ethereum/go-ethereum/consensus/ethash.startRemoteSealer
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/src/github.com/ethereum/go-ethereum/consensus/ethash/sealer.go:262 +0x2ae
Dec 17 14:13:18 hostname geth[28935]: goroutine 9230388 [select, 6 minutes]:
Dec 17 14:13:18 hostname geth[28935]: github.com/ethereum/go-ethereum/les.(*serverPool).setRetryDial.func1(0xc0000c23c0, 0x10c551301ea, 0xc00078ef20)
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/src/github.com/ethereum/go-ethereum/les/serverpool.go:562 +0xd4
Dec 17 14:13:18 hostname geth[28935]: created by github.com/ethereum/go-ethereum/les.(*serverPool).setRetryDial
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/src/github.com/ethereum/go-ethereum/les/serverpool.go:561 +0xad
Dec 17 14:13:18 hostname geth[28935]: goroutine 9230578 [select, 6 minutes]:
Dec 17 14:13:18 hostname geth[28935]: github.com/ethereum/go-ethereum/les.(*serverPool).setRetryDial.func1(0xc0000c23c0, 0x9efd8ed3d0, 0xc001f45340)
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/src/github.com/ethereum/go-ethereum/les/serverpool.go:562 +0xd4
Dec 17 14:13:18 hostname geth[28935]: created by github.com/ethereum/go-ethereum/les.(*serverPool).setRetryDial
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/src/github.com/ethereum/go-ethereum/les/serverpool.go:561 +0xad
Dec 17 14:13:18 hostname geth[28935]: goroutine 9229869 [select, 6 minutes]:
Dec 17 14:13:18 hostname geth[28935]: github.com/ethereum/go-ethereum/les.(*serverPool).setRetryDial.func1(0xc0000c23c0, 0x10d11f390b7, 0xc0017bf4a0)
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/src/github.com/ethereum/go-ethereum/les/serverpool.go:562 +0xd4
Dec 17 14:13:18 hostname geth[28935]: created by github.com/ethereum/go-ethereum/les.(*serverPool).setRetryDial
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/src/github.com/ethereum/go-ethereum/les/serverpool.go:561 +0xad
Dec 17 14:13:18 hostname geth[28935]: goroutine 46 [select, 12 minutes]:
Dec 17 14:13:18 hostname geth[28935]: github.com/ethereum/go-ethereum/light.(*TxPool).eventLoop(0xc0002c6c60)
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/src/github.com/ethereum/go-ethereum/light/txpool.go:289 +0x10a
Dec 17 14:13:18 hostname geth[28935]: created by github.com/ethereum/go-ethereum/light.NewTxPool
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/src/github.com/ethereum/go-ethereum/light/txpool.go:108 +0x3cb
Dec 17 14:13:18 hostname geth[28935]: goroutine 9231961 [select, 4 minutes]:
Dec 17 14:13:18 hostname geth[28935]: github.com/ethereum/go-ethereum/les.(*serverPool).setRetryDial.func1(0xc0000c23c0, 0xb949c0255d, 0xc001703760)
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/src/github.com/ethereum/go-ethereum/les/serverpool.go:562 +0xd4
Dec 17 14:13:18 hostname geth[28935]: created by github.com/ethereum/go-ethereum/les.(*serverPool).setRetryDial
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/src/github.com/ethereum/go-ethereum/les/serverpool.go:561 +0xad
Dec 17 14:13:18 hostname geth[28935]: goroutine 9231204 [select, 4 minutes]:
Dec 17 14:13:18 hostname geth[28935]: github.com/ethereum/go-ethereum/les.(*serverPool).setRetryDial.func1(0xc0000c23c0, 0xe0da574f62, 0xc00078ef20)
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/src/github.com/ethereum/go-ethereum/les/serverpool.go:562 +0xd4
Dec 17 14:13:18 hostname geth[28935]: created by github.com/ethereum/go-ethereum/les.(*serverPool).setRetryDial
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/src/github.com/ethereum/go-ethereum/les/serverpool.go:561 +0xad
Dec 17 14:13:18 hostname geth[28935]: goroutine 49 [chan receive, 6 minutes]:
Dec 17 14:13:18 hostname geth[28935]: github.com/ethereum/go-ethereum/les.(*lightFetcher).syncLoop(0xc000462280)
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/src/github.com/ethereum/go-ethereum/les/fetcher.go:159 +0x27e
Dec 17 14:13:18 hostname geth[28935]: created by github.com/ethereum/go-ethereum/les.newLightFetcher
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/src/github.com/ethereum/go-ethereum/les/fetcher.go:128 +0x215
Dec 17 14:13:18 hostname geth[28935]: goroutine 9232324 [select, 2 minutes]:
Dec 17 14:13:18 hostname geth[28935]: github.com/ethereum/go-ethereum/les.(*serverPool).setRetryDial.func1(0xc0000c23c0, 0xd62866c603, 0xc0017bf600)
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/src/github.com/ethereum/go-ethereum/les/serverpool.go:562 +0xd4
Dec 17 14:13:18 hostname geth[28935]: created by github.com/ethereum/go-ethereum/les.(*serverPool).setRetryDial
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/src/github.com/ethereum/go-ethereum/les/serverpool.go:561 +0xad
Dec 17 14:13:18 hostname geth[28935]: goroutine 9233753 [select]:
Dec 17 14:13:18 hostname geth[28935]: net.(*netFD).connect.func2(0x13f5d00, 0xc011e71d40, 0xc002218980, 0xc0106ac3c0, 0xc0106ac360)
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/.go/src/net/fd_unix.go:129 +0xba
Dec 17 14:13:18 hostname geth[28935]: created by net.(*netFD).connect
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/.go/src/net/fd_unix.go:128 +0x275
Dec 17 14:13:18 hostname geth[28935]: goroutine 52 [select]:
Dec 17 14:13:18 hostname geth[28935]: github.com/syndtr/goleveldb/leveldb/util.(*BufferPool).drain(0xc000116a80)
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/pkg/mod/github.com/syndtr/[email protected]/leveldb/util/buffer_pool.go:206 +0x134
Dec 17 14:13:18 hostname geth[28935]: created by github.com/syndtr/goleveldb/leveldb/util.NewBufferPool
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/pkg/mod/github.com/syndtr/[email protected]/leveldb/util/buffer_pool.go:237 +0x176
Dec 17 14:13:18 hostname geth[28935]: goroutine 53 [select, 4 minutes]:
Dec 17 14:13:18 hostname geth[28935]: github.com/syndtr/goleveldb/leveldb.(*session).refLoop(0xc00098c1e0)
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/pkg/mod/github.com/syndtr/[email protected]/leveldb/session_util.go:189 +0x5f9
Dec 17 14:13:18 hostname geth[28935]: created by github.com/syndtr/goleveldb/leveldb.newSession
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/pkg/mod/github.com/syndtr/[email protected]/leveldb/session.go:93 +0x2b4
Dec 17 14:13:18 hostname geth[28935]: goroutine 66 [select, 15853 minutes]:
Dec 17 14:13:18 hostname geth[28935]: github.com/syndtr/goleveldb/leveldb.(*DB).compactionError(0xc0009ac000)
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/pkg/mod/github.com/syndtr/[email protected]/leveldb/db_compaction.go:91 +0xcd
Dec 17 14:13:18 hostname geth[28935]: created by github.com/syndtr/goleveldb/leveldb.openDB
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/pkg/mod/github.com/syndtr/[email protected]/leveldb/db.go:148 +0x40c
Dec 17 14:13:18 hostname geth[28935]: goroutine 67 [select]:
Dec 17 14:13:18 hostname geth[28935]: github.com/syndtr/goleveldb/leveldb.(*DB).mpoolDrain(0xc0009ac000)
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/pkg/mod/github.com/syndtr/[email protected]/leveldb/db_state.go:101 +0xf6
Dec 17 14:13:18 hostname geth[28935]: created by github.com/syndtr/goleveldb/leveldb.openDB
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/pkg/mod/github.com/syndtr/[email protected]/leveldb/db.go:149 +0x42e
Dec 17 14:13:18 hostname geth[28935]: goroutine 68 [select, 15853 minutes]:
Dec 17 14:13:18 hostname geth[28935]: github.com/syndtr/goleveldb/leveldb.(*DB).tCompaction(0xc0009ac000)
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/pkg/mod/github.com/syndtr/[email protected]/leveldb/db_compaction.go:836 +0x25d
Dec 17 14:13:18 hostname geth[28935]: created by github.com/syndtr/goleveldb/leveldb.openDB
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/pkg/mod/github.com/syndtr/[email protected]/leveldb/db.go:155 +0x582
Dec 17 14:13:18 hostname geth[28935]: goroutine 69 [select, 15853 minutes]:
Dec 17 14:13:18 hostname geth[28935]: github.com/syndtr/goleveldb/leveldb.(*DB).mCompaction(0xc0009ac000)
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/pkg/mod/github.com/syndtr/[email protected]/leveldb/db_compaction.go:773 +0x150
Dec 17 14:13:18 hostname geth[28935]: created by github.com/syndtr/goleveldb/leveldb.openDB
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/pkg/mod/github.com/syndtr/[email protected]/leveldb/db.go:156 +0x5a4
Dec 17 14:13:18 hostname geth[28935]: goroutine 9231982 [select, 4 minutes]:
Dec 17 14:13:18 hostname geth[28935]: github.com/ethereum/go-ethereum/les.(*serverPool).setRetryDial.func1(0xc0000c23c0, 0xeff2ff732d, 0xc0017bfb80)
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/src/github.com/ethereum/go-ethereum/les/serverpool.go:562 +0xd4
Dec 17 14:13:18 hostname geth[28935]: created by github.com/ethereum/go-ethereum/les.(*serverPool).setRetryDial
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/src/github.com/ethereum/go-ethereum/les/serverpool.go:561 +0xad
Dec 17 14:13:18 hostname geth[28935]: goroutine 9233701 [select]:
Dec 17 14:13:18 hostname geth[28935]: net.(*netFD).connect.func2(0x13f5d00, 0xc00612fda0, 0xc00215c780, 0xc004370f00, 0xc004370ea0)
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/.go/src/net/fd_unix.go:129 +0xba
Dec 17 14:13:18 hostname geth[28935]: created by net.(*netFD).connect
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/.go/src/net/fd_unix.go:128 +0x275
Dec 17 14:13:18 hostname geth[28935]: goroutine 71 [select, 13 minutes]:
Dec 17 14:13:18 hostname geth[28935]: github.com/ethereum/go-ethereum/p2p/nat.Map(0x13f65c0, 0xc000079d80, 0xc000876fc0, 0x11561e2, 0x3, 0x765f, 0x765f, 0x115f7b2, 0xc)
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/src/github.com/ethereum/go-ethereum/p2p/nat/nat.go:114 +0x453
Dec 17 14:13:18 hostname geth[28935]: github.com/ethereum/go-ethereum/p2p.(*Server).setupListening.func1(0xc0002d6000, 0xc00053cd80)
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/src/github.com/ethereum/go-ethereum/p2p/server.go:624 +0x8b
Dec 17 14:13:18 hostname geth[28935]: created by github.com/ethereum/go-ethereum/p2p.(*Server).setupListening
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/ethereum-1.9.9+build20761+xenial/build/_workspace/src/github.com/ethereum/go-ethereum/p2p/server.go:623 +0x22f
Dec 17 14:13:18 hostname geth[28935]: goroutine 72 [IO wait, 15853 minutes]:
Dec 17 14:13:18 hostname geth[28935]: internal/poll.runtime_pollWait(0x7f750d7ebea8, 0x72, 0x0)
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/.go/src/runtime/netpoll.go:184 +0x55
Dec 17 14:13:18 hostname geth[28935]: internal/poll.(*pollDesc).wait(0xc0001bc598, 0x72, 0x0, 0x0, 0x1159bb5)
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/.go/src/internal/poll/fd_poll_runtime.go:87 +0x45
Dec 17 14:13:18 hostname geth[28935]: internal/poll.(*pollDesc).waitRead(...)
Dec 17 14:13:18 hostname geth[28935]:         /build/ethereum-XCZsIR/.go/src/internal/poll/fd_poll_runtime.go:92
Dec 17 14:13:18 hostname geth[28935]: internal/poll.(*FD).Accept(0xc0001bc580, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0)
Dec 17 14:13:18 hostname systemd[1]: geth.service: Main process exited, code=exited, status=2/INVALIDARGUMENT
Dec 17 14:13:18 hostname systemd[1]: geth.service: Unit entered failed state.
Dec 17 14:13:18 hostname systemd[1]: geth.service: Failed with result 'exit-code'.
Dec 17 14:13:18 hostname systemd[1]: geth.service: Service hold-off time over, scheduling restart.
Dec 17 14:13:18 hostname systemd[1]: Stopped Ethereum Go daemon (geth).
Dec 17 14:13:18 hostname systemd[1]: Started Ethereum Go daemon (geth).
Dec 17 14:13:18 hostname geth[9406]: INFO [12-17|14:13:18.795] Maximum peer count                       ETH=0 LES=10 total=60
Dec 17 14:13:18 hostname geth[9406]: INFO [12-17|14:13:18.795] Smartcard socket not found, disabling    err="stat /run/pcscd/pcscd.comm: no such file or directory"
Dec 17 14:13:18 hostname geth[9406]: ERROR[12-17|14:13:18.796] Failed to enumerate USB devices          hub=ledger vendor=11415 failcount=1 err="failed to initialize libusb: libusb: unknown error [code -99]"
Dec 17 14:13:18 hostname geth[9406]: ERROR[12-17|14:13:18.796] Failed to enumerate USB devices          hub=trezor vendor=21324 failcount=1 err="failed to initialize libusb: libusb: unknown error [code -99]"
Dec 17 14:13:18 hostname geth[9406]: ERROR[12-17|14:13:18.796] Failed to enumerate USB devices          hub=trezor vendor=4617  failcount=1 err="failed to initialize libusb: libusb: unknown error [code -99]"
Dec 17 14:13:18 hostname geth[9406]: ERROR[12-17|14:13:18.796] Failed to enumerate USB devices          hub=ledger vendor=11415 failcount=2 err="failed to initialize libusb: libusb: unknown error [code -99]"
Dec 17 14:13:18 hostname geth[9406]: ERROR[12-17|14:13:18.797] Failed to enumerate USB devices          hub=trezor vendor=21324 failcount=2 err="failed to initialize libusb: libusb: unknown error [code -99]"
Dec 17 14:13:18 hostname geth[9406]: ERROR[12-17|14:13:18.797] Failed to enumerate USB devices          hub=trezor vendor=4617  failcount=2 err="failed to initialize libusb: libusb: unknown error [code -99]"
Dec 17 14:13:18 hostname geth[9406]: INFO [12-17|14:13:18.797] Starting peer-to-peer node               instance=Geth/v1.9.9-stable-01744997/linux-amd64/go1.13.4
Dec 17 14:13:18 hostname geth[9406]: INFO [12-17|14:13:18.798] Allocated cache and file handles         database=/home/geth/.ethereum/geth/lightchaindata cache=256.00MiB handles=2048
Dec 17 14:13:19 hostname geth[9406]: INFO [12-17|14:13:19.195] Initialised chain configuration          config="{ChainID: 1 Homestead: 1150000 DAO: 1920000 DAOSupport: true EIP150: 2463000 EIP155: 2675000 EIP158: 2675000 Byzantium: 4370000 Constantinople: 7280000 Petersburg: 7280000 Istanbul: 9069000, Muir Glacier: 9200000, Engine: ethash}"
Dec 17 14:13:19 hostname geth[9406]: INFO [12-17|14:13:19.196] Disk storage enabled for ethash caches   dir=/home/geth/.ethereum/geth/ethash count=3
Dec 17 14:13:19 hostname geth[9406]: INFO [12-17|14:13:19.196] Disk storage enabled for ethash DAGs     dir=/home/geth/.ethash               count=2
Dec 17 14:13:19 hostname geth[9406]: INFO [12-17|14:13:19.207] Added trusted checkpoint                 block=9043967 hash=031592…61abd4
Dec 17 14:13:19 hostname geth[9406]: INFO [12-17|14:13:19.207] Loaded most recent local header          number=9120689 hash=b6df6e…ae115e td=13324394325829550306273 age=12m19s
Dec 17 14:13:19 hostname geth[9406]: INFO [12-17|14:13:19.215] Configured checkpoint registrar          address=0x9a9070028361F7AAbeB3f2F2Dc07F82C4a98A02a signers=5 threshold=2
Dec 17 14:13:19 hostname geth[9406]: INFO [12-17|14:13:19.226] UDP listener up                          net=enode://****************************************************censored********************************************************************@[::]:30303
Dec 17 14:13:19 hostname geth[9406]: WARN [12-17|14:13:19.228] Light client mode is an experimental feature
Dec 17 14:13:19 hostname geth[9406]: INFO [12-17|14:13:19.229] New local node record                    seq=8 id=c8b51e62fa715bab ip=127.0.0.1 udp=30303 tcp=30303
Dec 17 14:13:19 hostname geth[9406]: INFO [12-17|14:13:19.230] Started P2P networking                   self=enode://****************************************************censored********************************************************************@127.0.0.1:30303
Dec 17 14:13:19 hostname geth[9406]: INFO [12-17|14:13:19.242] IPC endpoint opened                      url=/home/geth/.ethereum/geth.ipc
Dec 17 14:13:19 hostname geth[9406]: INFO [12-17|14:13:19.243] HTTP endpoint opened                     url=http://0.0.0.0:8545              cors= vhosts=hostname
Dec 17 14:13:19 hostname geth[9406]: ERROR[12-17|14:13:19.798] Failed to enumerate USB devices          hub=ledger vendor=11415 failcount=3 err="failed to initialize libusb: libusb: unknown error [code -99]"
Dec 17 14:13:19 hostname geth[9406]: ERROR[12-17|14:13:19.798] Failed to enumerate USB devices          hub=trezor vendor=4617  failcount=3 err="failed to initialize libusb: libusb: unknown error [code -99]"
Dec 17 14:13:19 hostname geth[9406]: ERROR[12-17|14:13:19.798] Failed to enumerate USB devices          hub=trezor vendor=21324 failcount=3 err="failed to initialize libusb: libusb: unknown error [code -99]"
Dec 17 14:13:53 hostname geth[9406]: INFO [12-17|14:13:53.926] Block synchronisation started
Dec 17 14:14:53 hostname geth[9406]: WARN [12-17|14:14:53.926] Synchronisation failed, dropping peer    peer=a5a3635a2e61cffe56805a223fb400963ae9bd1f5b7316a80e095102a4268aa1 err=timeout
Dec 17 14:15:03 hostname geth[9406]: INFO [12-17|14:15:03.585] Imported new block headers               count=49 elapsed=131.646ms number=9120738 hash=b7fb07…3a7fee
Dec 17 14:15:03 hostname geth[9406]: INFO [12-17|14:15:03.638] Imported new block headers               count=2  elapsed=6.107ms   number=9120740 hash=c32b68…1ae937
Dec 17 14:15:37 hostname geth[9406]: INFO [12-17|14:15:37.125] Imported new block headers               count=1  elapsed=5.380ms   number=9120741 hash=ab39f7…ba5a70 age=1m3s
Dec 17 14:15:51 hostname geth[9406]: INFO [12-17|14:15:51.197] Imported new block headers               count=1  elapsed=5.342ms   number=9120742 hash=a08d79…ad7708
Dec 17 14:16:27 hostname geth[9406]: INFO [12-17|14:16:27.856] Imported new block headers               count=1  elapsed=5.294ms   number=9120743 hash=c7e80b…2a214a
Dec 17 14:17:20 hostname geth[9406]: INFO [12-17|14:17:20.932] Imported new block headers               count=1  elapsed=5.363ms   number=9120744 hash=252b78…345f61
Dec 17 14:17:23 hostname geth[9406]: INFO [12-17|14:17:23.635] Imported new block headers               count=1  elapsed=5.538ms   number=9120745 hash=4b7ec6…24cbfa

@ligi
Copy link
Member

ligi commented Dec 19, 2019

can you do debug.stacks?

@c0deright
Copy link
Author

c0deright commented Dec 19, 2019

@ligi Please elaborate.

Ok, found it: geth --exec 'debug.stacks()' attach > geth.stacks

Will try it when this issue occurs again. Installed a cronjob that does call above geth command every 10 minutes with output written to timestamped files. When CPU rises again I can provide you with multiple such files.

@ligi
Copy link
Member

ligi commented Dec 19, 2019

awesome - let us know when you have the logs

@maxmalysh
Copy link

maxmalysh commented Dec 24, 2019

Chiming in just to say that I have the same problem after upgrading to 1.9.9.

@sidhujag
Copy link

sidhujag commented Dec 24, 2019

Same here.. roughly 10% of our nodes have cpu throttling. Also we have no Choice because it’s the only one with instanbul activated at the right height. We are running it in the context of another decentralized network so hopefully we can resolve quickly. I can get more data aswell to feed analysis.

@degger80
Copy link

I've noticed same problem when there was strange peer in peers list:

name: "Multigeth"
difficulty: 8675610974161104000 (instead of current 1.3*10^22)

like this one: https://www.ethernodes.org/node/98b15be453b329efe258c823c5bb7aa9bac22678881e62b97b9f3e90913e6e844469f91f00bf2ec51c90ccf9c12569f8af8b02355add3c8cf4738a0538a30286

When I got this peer in peers list my geth in light mode became very slow in performing requests. It uses CPU 100% and all that you described. Ma ybe it was another peer but with this dificulty certainly.
If I use admin.removePeer for this node - it became fast until my client connects to this peer again.

Hope this information will help fix it.

@sidhujag
Copy link

@degger80 interesting, seems to make sense... perhaps also related to clients that did not upgrade yet either because they would have forked by now. So if there are any peers that are not the latest they likely will be on different chains

@maxmalysh
Copy link

maxmalysh commented Dec 31, 2019

Same here! Removing the "Multigeth" node helps:

node = {
    caps: ["eth/63", "eth/64", "les/2", "les/3"],
    enode: "enode://7adc5369b5b406818e6f07182c4b7b5c3e3f5fe3c31c9135b893d5f4cb40dbf8c0a224ba38d293aa16465e18d1cfa2792494b2ca5cf3be0712b4c34313f9aae2@34.216.196.4:30303",
    id: "ce5fe2a09484cb1ab6c8e707e29a5f40d6af9a7b3cf9ae4f4d0005c56569803c",
    name: "MultiGeth/v1.9.5-stable-ad5e13d5/linux-amd64/go1.13.4",
    network: {
      inbound: false,
      localAddress: "45.76.1.2:51736",
      remoteAddress: "34.216.196.4:30303",
      static: true,
      trusted: false
    },
    protocols: {
      les: {
        difficulty: 822295141518982000000,
        head: "8d5b5849b20cc013ede633a33875d7d2e12db2effe1ab685bdcca9e0f7a57d6e",
        version: 3
      }
    }
}
> admin.removePeer("enode://7adc5369b5b406818e6f07182c4b7b5c3e3f5fe3c31c9135b893d5f4cb40dbf8c0a224ba38d293aa16465e18d1cfa2792494b2ca5cf3be0712b4c34313f9aae2@34.216.196.4:30303");

@c0deright
Copy link
Author

Same here! Removing the "Multigeth" node helps

Can confirm. Removing the node with wrong difficulty results in geth syncing missing blocks and then dropping CPU @ligi

@johnp1954
Copy link

johnp1954 commented Jan 7, 2020

Had the same issue on 2 nodes and removing Mutligeth nodes resolved the CPU issue

34.216.196.4:30303 MultiGeth/v1.9.5-stable-ad5e13d5/linux-amd64/go1.13.4

34.216.42.219:30303 MultiGeth/v1.9.5-stable-ad5e13d5/linux-amd64/go1.13.4

@sidhujag
Copy link

sidhujag commented Jan 7, 2020

Are developers able to look at what those peers are doing by adding them and analyzing the code paths to shut the attack down?

@ligi
Copy link
Member

ligi commented Jan 7, 2020

Thanks to everyone providing helpful information! Especially the workaround of removing MultiGeth can really help to investigate the issue (cc @zsfelfoldi )
@sidhujag can you provide some more information why you think this is an "attack"?

@maxmalysh
Copy link

Because anyone exploiting this bug can make all light clients unusable.

@ligi
Copy link
Member

ligi commented Jan 7, 2020

sure - but this only makes it a potential attack - my question was if there he has information that there is an active attack

@degger80
Copy link

degger80 commented Jan 7, 2020

Is it by design that geth in light mode connects to not synced nodes and keeps them in peer list?
It can't help them get synced. May be geth in light mode should drop unsynced peers by default?

@sidhujag
Copy link

sidhujag commented Jan 7, 2020

sure - but this only makes it a potential attack - my question was if there he has information that there is an active attack

Perhaps someone is using multi geth to try to dos light nodes..because they are all on the same subnet somewhere on aws so likely a single provider or service or person. Anyways multi geth hasn’t been updated in a while and is out of date but perhaps someone is screwing around.. what I’d like to see is if we are connecting to other multi geth nodes successfully without issue as that would isolate it from attack to bug causing extraneous resource consumption which is still pretty bad itself but not an attack per se.

@sidhujag
Copy link

sidhujag commented Jan 7, 2020

Is it by design that geth in light mode connects to not synced nodes and keeps them in peer list?
It can't help them get synced. May be geth in light mode should drop unsynced peers by default?

How would it end up syncing peers if it cannot connect to non synced peers? In a mesh network every node needs to be able to provide for every other node.

@johnp1954
Copy link

johnp1954 commented Jan 8, 2020

Another one of my nodes is now showing this issue
54.149.140.195:30303 MultiGeth/v1.9.5-stable-ad5e13d5/linux-amd64/go1.13.4
Once again removing it solved the 100%

@degger80
Copy link

degger80 commented Jan 8, 2020

same here. had to admin.removePeer this one:

{
    caps: ["eth/63", "eth/64", "les/2", "les/3"],
    enode: "enode://28e2542029e2b0ba9a2600e126c09a1c777e2683ec3b8a6d63e6c4b30e5e203614bf2eab465c6929cdc48d18f6ac6a06d1649d3560fb46ec902b694549314922@54.149.140.195:30303",
    id: "1020434b3842d06b3a3abb739295c870f90813abcc7ed7c31629b8e120f4886f",
    name: "MultiGeth/v1.9.5-stable-ad5e13d5/linux-amd64/go1.13.4",
    network: {
      inbound: false,
      localAddress: "99.192.166.179:35818",
      remoteAddress: "54.149.140.195:30303",
      static: true,
      trusted: false
    },
    protocols: {
      les: {
        difficulty: 830239186299722700000,
        head: "4427bcae714eaa12af5408eede41b3643d2e11065b94ad2b11d71b6c09621ac3",
        version: 3
      }
    }

@c0deright
Copy link
Author

c0deright commented Jan 12, 2020

My workaround so far (via cronjob every 5 minutes):

#!/bin/sh

exec geth --exec 'admin.peers' attach \
        | grep -P 'enode|MultiGeth' \
        | grep -B1 -F MultiGeth \
        | grep enode \
        | cut -d '"' -f 2 \
        | while read enode
        do
                geth --exec "admin.removePeer('$enode')" attach
        done

This might be done better via javascript but I don't know javascript enough.

Interestingly, jq reports a parse error when trying to parse json returned by geth --exec ... attach:

% geth --exec 'admin.peers' attach | jq
parse error: Invalid numeric literal at line 2, column 9
% geth --exec 'admin.peers' attach | head -n 4
[{
    caps: ["eth/63", "eth/64", "les/2", "les/3"],
    enode: "enode://424a7c4928abd10ebe806da151dd9ed4cdb0fb488e75897372a83c82cf93a94aa733cefbae81d3e983b2ca8479ae5385aa606098daf8cfbc97595888e5005761@68.185.171.253:30303",
    id: "2097603f3e07429b8b935dba09832040cca90f7850376b685ca92fe19f63faf2",
% jq --version
jq-1.5-1-a5b5cbe

@johnp1954
Copy link

Is there any progress on fixing this issue, every day i am having to remove peers for some of my Nodes

@holiman
Copy link
Contributor

holiman commented Jan 23, 2020

I've been trying to repro this, but whenever I 'meet' a multigeth-node, the peer is dropped almost instantly

@c0deright
Copy link
Author

{
    caps: ["eth/63", "eth/64", "les/2", "les/3"],
    enode: "enode://28e2542029e2b0ba9a2600e126c09a1c777e2683ec3b8a6d63e6c4b30e5e203614bf2eab465c6929cdc48d18f6ac6a06d1649d3560fb46ec902b694549314922@54.149.140.195:30303",
    id: "1020434b3842d06b3a3abb739295c870f90813abcc7ed7c31629b8e120f4886f",
    name: "MultiGeth/v1.9.6-stable-b809e44d/linux-amd64/go1.13.5",
    network: {
      inbound: false,
      localAddress: "10.0.48.177:55116",
      remoteAddress: "54.149.140.195:30303",
      static: true,
      trusted: false
    },
    protocols: {
      les: {
        difficulty: 848327201598296300000,
        head: "fc52a313ef1d243da5026ab48596051c12e9f7e32eeda2ae4d5c6e99c9894113",
        version: 3
      }
    }
}

@sidhujag
Copy link

I've been trying to repro this, but whenever I 'meet' a multigeth-node, the peer is dropped almost instantly

maybe they have to connect to you, as inbound instead of outbound

@Lidware
Copy link

Lidware commented Jan 29, 2020

#20464 (comment)

Same problem still... This are the issues quantities for each IP
address last days....
12 times.
node://7adc5369b5b406818e6f07182c4b7b5c3e3f5fe3c31c9135b893d5f4cb40dbf8c0a224ba38d293aa16465e18d1cfa2792494b2ca5cf3be0712b4c34313f9aa
[email protected]:30303
7 Times.
enode://a2ddbcc6604c5d68e29c819e06dcf02b88b08fac91d4a18c48a46658a2a1492658ebdda23fa3e085000ef1978be0f84fb49f9ed6496bc95edee01918bbe6f0
[email protected]:30303
1 time.
enode://28e2542029e2b0ba9a2600e126c09a1c777e2683ec3b8a6d63e6c4b30e5e203614bf2eab465c6929cdc48d18f6ac6a06d1649d3560fb46ec902b6945493149
[email protected]:30303

The remove-peer script is doing the job so far...
above info taken from nodes logs

@karalabe
Copy link
Member

Our current hunch is that the light client fetcher is a bit broken when a peer announces a valid very very long sidechain.

@fjl
Copy link
Contributor

fjl commented Jan 30, 2020

We should work toward #19710 to fix this issue.

@fjl
Copy link
Contributor

fjl commented Jan 30, 2020

Another idea is adding the checkpoint challenge from #20125.

@johnp1954
Copy link

Any update on this ongoing issue?

@rjl493456442 rjl493456442 self-assigned this Feb 13, 2020
@johnp1954
Copy link

Any update on this ongoing issue?

@rjl493456442
Copy link
Member

@johnp1954 We suspect there are some issues in les.fetcher when we connect with a forked client(ETC). We had a PR to re-write the fetcher actually but it's under the review now.

Check the PR here #20692

@johnp1954
Copy link

Thankyou

@turboboost55
Copy link
Contributor

I just noticed that there is a new type of node that is causing high cpu usage when connected. The name is "CoreGeth". It also has the low difficulty similar to the MultiGeth client. Seems to be an ETC node client as well. So I changed my javascript code to only check for low difficulty and not even bother with the name of the client. (see my old code above).

@rjl493456442
Copy link
Member

@turbo-boost any possible to try out the fix #20692? The PR is waiting the review from @zsfelfoldi .

I have a strong feeling that the issue is caused by old les.Fetcher. I am super curious the new fetcher can fix it or not.

@turboboost55
Copy link
Contributor

@rjl493456442 :

  1. How difficult is it to build geth from source? Just download the repo and type "make"?
  2. How do I get that PR? Is it on a specific branch somewhere?

@rjl493456442
Copy link
Member

@turbo-boost Ah, If you are not familiar with Go, probably it takes some time.

  • Install go
  • git clone go-ethereum
  • Add my branch to get PR git remote add gary https://github.com/rjl493456442/go-ethereum.git
  • Checkout to branch git checkout -b les-fetcher gary/les-fetcher
  • Build go install ./cmd/geth

@johnp1954
Copy link

I just noticed that there is a new type of node that is causing high cpu usage when connected. The name is "CoreGeth". It also has the low difficulty similar to the MultiGeth client. Seems to be an ETC node client as well. So I changed my javascript code to only check for low difficulty and not even bother with the name of the client. (see my old code above).

I have seen this too, if you look it appears to be the same node as Multi Geth, just a different name

@johnp1954
Copy link

enode has all the same values and same IP

@turboboost55
Copy link
Contributor

@rjl493456442 I managed to get your PR compiled, and have been running it for about 36 hours. During about 4-5 of those hours, I had CoreGeth (enode://7adc5369b5b40...) connected to my node, and I observed no ill effects. CPU usage was normal. I'll keep running it and let you know if I see any issues.

@rjl493456442
Copy link
Member

@turbo-boost Cool, thanks for trying it.

@princesinha19
Copy link
Contributor

Hello everyone,

I am using a light client and still seeing 100% CPU utilization by Geth. How I can solve this. Thanks

@rjl493456442
Copy link
Member

@princesinha19 Which version are you using? I had a patch for it(unfortunately it's not merged yet), can you please try it?

@princesinha19
Copy link
Contributor

@rjl493456442 Thanks, Gary.

I am using Geth v1.9.14, which is the latest I think.

I will use the suggested solution by you and, will update. Thanks.

@rjl493456442
Copy link
Member

Hi, we just merged the light fetcher rewrite PR. Would close it right now but feel free to reopen or open another issue if it happens again.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
None yet
Projects
None yet
Development

No branches or pull requests

16 participants