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.

Sign up for GitHub

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

Jump to bottom

Crash (signal 11) #2065

Closed
jogc opened this issue Nov 1, 2018 · 3 comments
Closed

Crash (signal 11) #2065

jogc opened this issue Nov 1, 2018 · 3 comments
Labels
crash

Comments

@jogc
Copy link

jogc commented Nov 1, 2018 •
edited by cdecker
Loading

I got this and now c-lightning is refusing to start. Version 0.6.1, using bitcoind bitcoin-0.16.3-x86_64-linux-gnu

Nov 01 00:16:45 X lightningd[26438]: 2018-10-31T16:16:45.518Z lightningd(26438): ... feerate estimate for slow hit floor 253
Nov 01 00:16:57 X lightningd[26438]: 2018-10-31T16:16:57.297Z lightning_gossipd(26463): Received channel_update for channel 538752:1002:1(0) now ACTIVE was ACTIVE (from subdaemon)
Nov 01 00:16:57 X lightningd[26438]: 2018-10-31T16:16:57.297Z lightning_gossipd(26463): Received channel_update for channel 539471:490:0(0) now ACTIVE was ACTIVE (from subdaemon)
Nov 01 00:16:57 X lightningd[26438]: 2018-10-31T16:16:57.298Z lightning_gossipd(26463): Received channel_update for channel 539581:751:0(0) now ACTIVE was ACTIVE (from subdaemon)
Nov 01 00:16:57 X lightningd[26438]: 2018-10-31T16:16:57.298Z lightning_gossipd(26463): Received channel_update for channel 544335:415:0(0) now ACTIVE was ACTIVE (from subdaemon)
Nov 01 00:16:57 X lightningd[26438]: 2018-10-31T16:16:57.315Z lightning_gossipd(26463): Received channel_update for channel 545049:1248:0(0) now ACTIVE was ACTIVE (from subdaemon)
Nov 01 00:16:57 X lightningd[26438]: 2018-10-31T16:16:57.315Z lightning_gossipd(26463): Received channel_update for channel 546038:524:0(0) now ACTIVE was ACTIVE (from subdaemon)
Nov 01 00:17:04 X lightningd[26438]: 2018-10-31T16:17:04.176Z lightning_gossipd(26463): Received channel_update for channel 547846:180:1(0) now ACTIVE was ACTIVE (from subdaemon)
Nov 01 00:17:05 X lightningd[26438]: 2018-10-31T16:17:05.830Z lightning_gossipd(26463): Received channel_update for channel 547292:355:1(1) now ACTIVE was ACTIVE (from subdaemon)
Nov 01 00:17:08 X lightningd[26438]: 2018-10-31T16:17:08.013Z lightning_gossipd(26463): Received channel_update for channel 540098:1334:0(1) now ACTIVE was ACTIVE (from subdaemon)
Nov 01 00:17:13 X lightningd[26438]: 2018-10-31T16:17:13.648Z lightning_gossipd(26463): Received channel_update for channel 544862:619:0(1) now ACTIVE was ACTIVE (from subdaemon)
Nov 01 00:17:15 X lightningd[26438]: 2018-10-31T16:17:15.555Z lightningd(26438): ... feerate estimate for slow hit floor 253
Nov 01 00:17:21 X lightningd[26438]: 2018-10-31T16:17:21.301Z lightning_gossipd(26463): Received channel_update for channel 540098:1334:0(1) now ACTIVE was ACTIVE (from subdaemon)
Nov 01 00:17:24 X lightningd[26438]: 2018-10-31T16:17:24.077Z lightning_gossipd(26463): Received channel_update for channel 533665:83:1(1) now ACTIVE was DISABLED (from subdaemon)
Nov 01 00:17:34 X lightningd[26438]: 2018-10-31T16:17:34.908Z lightning_connectd(26462): UPDATE WIRE_CONNECTCTL_CONNECT_FAILED
Nov 01 00:17:34 X lightningd[26438]: 2018-10-31T16:17:34.908Z lightningd(26438): 03fc5b91ce2d857f146fd9b986363374ffe04dc143d8bcd6d7664c8873c463cdfc chan #84: Will try reconnect in 300 seconds
Nov 01 00:17:34 X lightningd[26438]: 2018-10-31T16:17:34.908Z lightning_connectd(26462): Failed connected out for 03fc5b91ce2d857f146fd9b986363374ffe04dc143d8bcd6d7664c8873c463cdfc: 185.228.141.6:9735: Connection establishment: Connection refused. 185.228.141.6:9735: Connection establishment: Connection refused.
Nov 01 00:17:45 X lightningd[26438]: 2018-10-31T16:17:45.569Z lightningd(26438): Adding block 548154: 000000000000000000021133a4c5b144091da2315613f58d1f63c5002f123a3e
Nov 01 00:17:46 X lightningd[26438]: 2018-10-31T16:17:46.321Z lightning_gossipd(26463): Deleting channel 547430:1361:0 due to the funding outpoint being spent
Nov 01 00:17:46 X lightningd[26438]: 2018-10-31T16:17:46.459Z lightningd(26438): Adding block 548155: 0000000000000000001990e0583fc327c7b01f96ad4eff7e59b28ae5d17d91de
Nov 01 00:17:46 X lightningd[26438]: 2018-10-31T16:17:46.987Z lightningd(26438): 039bcf7778ad7b14cf5974cd2705b06a58a8bbf564ca1df4791100f81104716624 chan #82: Got depth change 5566->5568 for bdb959c92a0f675962b5bf3ef6eb6ee06efa3a102bf494bd871417a27021efd0
Nov 01 00:17:46 X lightningd[26438]: 2018-10-31T16:17:46.988Z lightningd(26438): 039bcf7778ad7b14cf5974cd2705b06a58a8bbf564ca1df4791100f81104716624 chan #82: Funding tx bdb959c92a0f675962b5bf3ef6eb6ee06efa3a102bf494bd871417a27021efd0 depth 5568 of 3
Nov 01 00:17:46 X lightningd[26438]: 2018-10-31T16:17:46.988Z lightningd(26438): 039bcf7778ad7b14cf5974cd2705b06a58a8bbf564ca1df4791100f81104716624 chan #82: Funding tx confirmed, but peer disconnected
Nov 01 00:17:46 X lightningd[26438]: 2018-10-31T16:17:46.992Z lightningd(26438): 021cb4abab993064c54b6d992accd417267ee3f7c4bfd2f02ea4e32d3d3e34490f chan #3: Got depth change 15755->15757 for e15bc62f3d5aeae8eef1518aea4929d183e08ca76685d18a8de04bf4d83d58eb
Nov 01 00:17:46 X lightningd[26438]: 2018-10-31T16:17:46.992Z lightningd(26438): 021cb4abab993064c54b6d992accd417267ee3f7c4bfd2f02ea4e32d3d3e34490f chan #3: Funding tx e15bc62f3d5aeae8eef1518aea4929d183e08ca76685d18a8de04bf4d83d58eb depth 15757 of 3
Nov 01 00:17:46 X lightningd[26438]: 2018-10-31T16:17:46.992Z lightningd(26438): 021cb4abab993064c54b6d992accd417267ee3f7c4bfd2f02ea4e32d3d3e34490f chan #3: Funding tx confirmed, but peer disconnected
Nov 01 00:17:46 X lightningd[26438]: 2018-10-31T16:17:46.992Z lightningd(26438): 0283183481c1421d43d591b0e330221e741ba41f0f846a5b482a37cff46b6998ec chan #19: Got depth change 2->4 for 4a75007503bd7fd5864bc22ec20b66bccb9695383f7092e5d3f71c99ed06c5e6
Nov 01 00:17:47 X lightningd[26438]: 2018-10-31T16:17:47.052Z lightningd(26438): lightning_onchaind-0283183481c1421d43d591b0e330221e741ba41f0f846a5b482a37cff46b6998ec chan #19: Got new message WIRE_ONCHAIN_DEPTH
Nov 01 00:17:47 X lightningd[26438]: 2018-10-31T16:17:47.052Z lightningd(26438): lightning_onchaind-0283183481c1421d43d591b0e330221e741ba41f0f846a5b482a37cff46b6998ec chan #19: Sending 1 missing htlc messages
Nov 01 00:17:47 X lightningd[26438]: 2018-10-31T16:17:47.052Z lightningd(26438): lightning_onchaind-0283183481c1421d43d591b0e330221e741ba41f0f846a5b482a37cff46b6998ec chan #19: UPDATE WIRE_ONCHAIN_MISSING_HTLC_OUTPUT
Nov 01 00:17:47 X lightningd[26438]: lightningd: Fatal signal 11 (version v0.6.1)
Nov 01 00:17:47 X lightningd[26438]: 0x55ec30226af3 crashdump
Nov 01 00:17:47 X lightningd[26438]:         common/daemon.c:37
Nov 01 00:17:47 X lightningd[26438]: 0x7f701d39afbf ???
Nov 01 00:17:47 X lightningd[26438]:         ???:0
Nov 01 00:17:47 X lightningd[26438]: 0x55ec3020b49f new_log_entry
Nov 01 00:17:47 X lightningd[26438]:         lightningd/log.c:235
Nov 01 00:17:47 X lightningd[26438]: 0x55ec3020b5cd logv
Nov 01 00:17:47 X lightningd[26438]:         lightningd/log.c:258
Nov 01 00:17:47 X lightningd[26438]: 0x55ec3020b975 log_
Nov 01 00:17:47 X lightningd[26438]:         lightningd/log.c:318
Nov 01 00:17:47 X lightningd[26438]: 0x55ec3021bec3 local_fail_htlc
Nov 01 00:17:47 X lightningd[26438]:         lightningd/peer_htlcs.c:133
Nov 01 00:17:47 X lightningd[26438]: 0x55ec3021d819 onchain_failed_our_htlc
Nov 01 00:17:47 X lightningd[26438]:         lightningd/peer_htlcs.c:866
Nov 01 00:17:47 X lightningd[26438]: 0x55ec3020d534 handle_missing_htlc_output
Nov 01 00:17:47 X lightningd[26438]:         lightningd/onchain_control.c:227
Nov 01 00:17:47 X lightningd[26438]: 0x55ec3020d8b8 onchain_msg
Nov 01 00:17:47 X lightningd[26438]:         lightningd/onchain_control.c:309
Nov 01 00:17:47 X lightningd[26438]: 0x55ec30220f6e sd_msg_read
Nov 01 00:17:47 X lightningd[26438]:         lightningd/subd.c:475
Nov 01 00:17:47 X lightningd[26438]: 0x55ec3025dd5e next_plan
Nov 01 00:17:47 X lightningd[26438]:         ccan/ccan/io/io.c:59
Nov 01 00:17:47 X lightningd[26438]: 0x55ec3025e856 do_plan
Nov 01 00:17:47 X lightningd[26438]:         ccan/ccan/io/io.c:387
Nov 01 00:17:47 X lightningd[26438]: 0x55ec3025e894 io_ready
Nov 01 00:17:47 X lightningd[26438]:         ccan/ccan/io/io.c:397
Nov 01 00:17:47 X lightningd[26438]: 0x55ec30260475 io_loop
Nov 01 00:17:47 X lightningd[26438]:         ccan/ccan/io/poll.c:310
Nov 01 00:17:47 X lightningd[26438]: 0x55ec3020a910 main
Nov 01 00:17:47 X lightningd[26438]:         lightningd/lightningd.c:732
Nov 01 00:17:47 X lightningd[26438]: 0x7f701d387b16 ???
Nov 01 00:17:47 X lightningd[26438]:         ???:0
Nov 01 00:17:47 X lightningd[26438]: 0x55ec301f7b39 ???
Nov 01 00:17:47 X lightningd[26438]:         ???:0
Nov 01 00:17:47 X lightningd[26438]: 0xffffffffffffffff ???
Nov 01 00:17:47 X lightningd[26438]:         ???:0
Nov 01 00:17:47 X lightningd[26438]: 2018-10-31T16:17:47.174Z lightningd(26438): FATAL SIGNAL 11 (version v0.6.1)
Nov 01 00:17:47 X lightningd[26438]: 2018-10-31T16:17:47.174Z lightningd(26438): backtrace: common/daemon.c:42 (crashdump) 0x55ec30226b47
Nov 01 00:17:47 X lightningd[26438]: 2018-10-31T16:17:47.174Z lightningd(26438): backtrace: (null):0 ((null)) 0x7f701d39afbf
Nov 01 00:17:47 X lightningd[26438]: 2018-10-31T16:17:47.174Z lightningd(26438): backtrace: lightningd/log.c:235 (new_log_entry) 0x55ec3020b49f
Nov 01 00:17:47 X lightningd[26438]: 2018-10-31T16:17:47.174Z lightningd(26438): backtrace: lightningd/log.c:258 (logv) 0x55ec3020b5cd
Nov 01 00:17:47 X lightningd[26438]: 2018-10-31T16:17:47.174Z lightningd(26438): backtrace: lightningd/log.c:318 (log_) 0x55ec3020b975
Nov 01 00:17:47 X lightningd[26438]: 2018-10-31T16:17:47.174Z lightningd(26438): backtrace: lightningd/peer_htlcs.c:133 (local_fail_htlc) 0x55ec3021bec3
Nov 01 00:17:47 X lightningd[26438]: 2018-10-31T16:17:47.174Z lightningd(26438): backtrace: lightningd/peer_htlcs.c:866 (onchain_failed_our_htlc) 0x55ec3021d819
Nov 01 00:17:47 X lightningd[26438]: 2018-10-31T16:17:47.174Z lightningd(26438): backtrace: lightningd/onchain_control.c:227 (handle_missing_htlc_output) 0x55ec3020d534
Nov 01 00:17:47 X lightningd[26438]: 2018-10-31T16:17:47.174Z lightningd(26438): backtrace: lightningd/onchain_control.c:309 (onchain_msg) 0x55ec3020d8b8
Nov 01 00:17:47 X lightningd[26438]: 2018-10-31T16:17:47.174Z lightningd(26438): backtrace: lightningd/subd.c:475 (sd_msg_read) 0x55ec30220f6e
Nov 01 00:17:47 X lightningd[26438]: 2018-10-31T16:17:47.174Z lightningd(26438): backtrace: ccan/ccan/io/io.c:59 (next_plan) 0x55ec3025dd5e
Nov 01 00:17:47 X lightningd[26438]: 2018-10-31T16:17:47.174Z lightningd(26438): backtrace: ccan/ccan/io/io.c:387 (do_plan) 0x55ec3025e856
Nov 01 00:17:47 X lightningd[26438]: 2018-10-31T16:17:47.174Z lightningd(26438): backtrace: ccan/ccan/io/io.c:397 (io_ready) 0x55ec3025e894
Nov 01 00:17:47 X lightningd[26438]: 2018-10-31T16:17:47.174Z lightningd(26438): backtrace: ccan/ccan/io/poll.c:310 (io_loop) 0x55ec30260475
Nov 01 00:17:47 X lightningd[26438]: 2018-10-31T16:17:47.174Z lightningd(26438): backtrace: lightningd/lightningd.c:732 (main) 0x55ec3020a910
Nov 01 00:17:47 X lightningd[26438]: 2018-10-31T16:17:47.174Z lightningd(26438): backtrace: (null):0 ((null)) 0x7f701d387b16
Nov 01 00:17:47 X lightningd[26438]: 2018-10-31T16:17:47.174Z lightningd(26438): backtrace: (null):0 ((null)) 0x55ec301f7b39
Nov 01 00:17:47 X lightningd[26438]: 2018-10-31T16:17:47.174Z lightningd(26438): backtrace: (null):0 ((null)) 0xffffffffffffffff
Nov 01 00:17:47 X lightningd[26438]: Log dumped in crash.log.20181031161747
Nov 01 00:17:47 X lightningd[26438]: lightning_channeld: Writing out status 65522: lightning_channeld: lightning_channeld: Broken pipe
Nov 01 00:17:47 X systemd[1]: c-lightning.service: Main process exited, code=killed, status=11/SEGV
Nov 01 00:17:47 X lightningd[26438]: Writing out status 65522Writing out status 65522: lightning_channeld: : lightning_openingd: Writing out status 65522lightning_channeld: lightning_openingd: Broken pipe
Nov 01 00:17:47 X lightningd[26438]: Writing out status 65522Broken pipe
Nov 01 00:17:47 X lightningd[26438]: : : Writing out status 65522Writing out status 65522Broken pipe
Nov 01 00:17:47 X lightningd[26438]: : : Broken pipe
Nov 01 00:17:47 X lightningd[26438]: Broken pipe
Nov 01 00:17:47 X lightningd[26438]: Broken pipe
Nov 01 00:17:47 X lightningd[26438]: lightning_onchaind: Writing out status 65520: Broken pipe
Nov 01 00:17:47 X lightningd[26438]: lightning_openingd: lightning_channeld: Writing out status 65522Writing out status 65522: : Broken pipe
Nov 01 00:17:47 X lightningd[26438]: Broken pipe
Nov 01 00:17:47 X lightningd[26438]: lightning_channeld: lightning_channeld: Writing out status 65522Writing out status 65522: : Broken pipe
Nov 01 00:17:47 X lightningd[26438]: Broken pipe
Nov 01 00:17:47 X lightningd[26438]: lightning_channeld: Writing out status 65522: Broken pipe
Nov 01 00:17:47 X lightningd[26438]: lightning_channeld: Writing out status 65522: Broken pipe
Nov 01 00:17:47 X lightningd[26438]: lightning_channeld: Writing out status 65522: Broken pipe
Nov 01 00:17:47 X systemd[1]: c-lightning.service: Failed with result 'signal'.

Nov 01 00:17:47 X systemd[1]: c-lightning.service: Service RestartSec=100ms expired, scheduling restart.
Nov 01 00:17:47 X systemd[1]: c-lightning.service: Scheduled restart job, restart counter is at 1.
Nov 01 00:17:47 X systemd[1]: Stopped c-lightning.service.
Nov 01 00:17:47 X systemd[1]: Started c-lightning.service.
Nov 01 00:17:47 X lightningd[5674]: 2018-10-31T16:17:47.683Z lightningd(5674): testing /extra/home/c-lightning/0.6.1-git/lightning/lightningd/lightning_channeld
Nov 01 00:17:47 X lightningd[5674]: 2018-10-31T16:17:47.690Z lightningd(5674): testing /extra/home/c-lightning/0.6.1-git/lightning/lightningd/lightning_closingd
Nov 01 00:17:47 X lightningd[5674]: 2018-10-31T16:17:47.720Z lightningd(5674): testing /extra/home/c-lightning/0.6.1-git/lightning/lightningd/lightning_connectd
Nov 01 00:17:47 X lightningd[5674]: 2018-10-31T16:17:47.726Z lightningd(5674): testing /extra/home/c-lightning/0.6.1-git/lightning/lightningd/lightning_gossipd
Nov 01 00:17:47 X lightningd[5674]: 2018-10-31T16:17:47.746Z lightningd(5674): testing /extra/home/c-lightning/0.6.1-git/lightning/lightningd/lightning_hsmd
Nov 01 00:17:47 X lightningd[5674]: 2018-10-31T16:17:47.749Z lightningd(5674): testing /extra/home/c-lightning/0.6.1-git/lightning/lightningd/lightning_onchaind
Nov 01 00:17:47 X lightningd[5674]: 2018-10-31T16:17:47.750Z lightningd(5674): testing /extra/home/c-lightning/0.6.1-git/lightning/lightningd/lightning_openingd
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.277Z lightning_hsmd(5693): pid 5693, msgfd 13
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.311Z lightning_connectd(5695): pid 5695, msgfd 16
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.312Z lightning_hsmd(5693): Client: Received message 11 from client
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.312Z lightning_hsmd(5693): Client: Received message 9 from client
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.608Z lightning_connectd(5695): Created IPv4 listener on port 19248
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.608Z lightning_connectd(5695): REPLY WIRE_CONNECTCTL_INIT_REPLY with 0 fds
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.609Z lightning_gossipd(5696): pid 5696, msgfd 15
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.640Z lightningd(5674): Loaded 0 HTLC signatures from DB
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.642Z lightningd(5674): Loaded 0 HTLC signatures from DB
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.643Z lightningd(5674): Loaded 0 HTLC signatures from DB
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.644Z lightningd(5674): Loaded 0 HTLC signatures from DB
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.651Z lightningd(5674): Loaded 0 HTLC signatures from DB
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.653Z lightningd(5674): Loaded 0 HTLC signatures from DB
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.654Z lightningd(5674): Loaded 0 HTLC signatures from DB
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.655Z lightningd(5674): Loaded 0 HTLC signatures from DB
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.656Z lightningd(5674): Loaded 0 HTLC signatures from DB
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.657Z lightningd(5674): Loaded 0 HTLC signatures from DB
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.658Z lightningd(5674): Loaded 0 HTLC signatures from DB
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.659Z lightningd(5674): Loaded 0 HTLC signatures from DB
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.660Z lightningd(5674): Loaded 0 HTLC signatures from DB
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.661Z lightningd(5674): Loaded 0 HTLC signatures from DB
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.662Z lightningd(5674): Loaded 0 HTLC signatures from DB
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.664Z lightningd(5674): Loaded 0 HTLC signatures from DB
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.665Z lightningd(5674): Loaded 0 HTLC signatures from DB
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.666Z lightningd(5674): Loaded 0 HTLC signatures from DB
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.667Z lightningd(5674): Loaded 0 HTLC signatures from DB
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.668Z lightningd(5674): Loaded 0 HTLC signatures from DB
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.669Z lightningd(5674): Loaded 0 HTLC signatures from DB
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.670Z lightningd(5674): Loaded 0 HTLC signatures from DB
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.671Z lightningd(5674): Loaded 0 HTLC signatures from DB
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.672Z lightningd(5674): Loaded 0 HTLC signatures from DB
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.673Z lightningd(5674): Loaded 0 HTLC signatures from DB
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.674Z lightningd(5674): Loaded 0 HTLC signatures from DB
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.676Z lightningd(5674): Loaded 0 HTLC signatures from DB
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.677Z lightningd(5674): Loaded 0 HTLC signatures from DB
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.679Z lightningd(5674): Loaded 0 HTLC signatures from DB
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.680Z lightningd(5674): Loaded 0 HTLC signatures from DB
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.681Z lightningd(5674): Loaded 0 HTLC signatures from DB
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.683Z lightningd(5674): Loaded 0 HTLC signatures from DB
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.684Z lightningd(5674): Loaded 0 HTLC signatures from DB
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.685Z lightningd(5674): Loaded 0 HTLC signatures from DB
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.687Z lightningd(5674): Loaded 0 HTLC signatures from DB
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.688Z lightningd(5674): Loaded 0 HTLC signatures from DB
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.690Z lightningd(5674): Loaded 0 HTLC signatures from DB
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.690Z lightningd(5674): Loaded 37 channels from DB
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.690Z lightningd(5674): Loading HTLCs for channel 3
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.690Z lightningd(5674): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.690Z lightningd(5674): Loading HTLCs for channel 19
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.690Z lightningd(5674): Restored 0 incoming and 1 outgoing HTLCS
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.690Z lightningd(5674): Loading HTLCs for channel 21
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.691Z lightningd(5674): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.691Z lightningd(5674): Loading HTLCs for channel 32
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.691Z lightningd(5674): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.691Z lightningd(5674): Loading HTLCs for channel 40
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.692Z lightningd(5674): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.692Z lightningd(5674): Loading HTLCs for channel 43
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.692Z lightningd(5674): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.692Z lightningd(5674): Loading HTLCs for channel 53
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.692Z lightningd(5674): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.692Z lightningd(5674): Loading HTLCs for channel 56
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.693Z lightningd(5674): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.693Z lightningd(5674): Loading HTLCs for channel 58
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.693Z lightningd(5674): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.693Z lightningd(5674): Loading HTLCs for channel 61
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.694Z lightningd(5674): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.694Z lightningd(5674): Loading HTLCs for channel 63
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.694Z lightningd(5674): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.694Z lightningd(5674): Loading HTLCs for channel 64
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.695Z lightningd(5674): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.695Z lightningd(5674): Loading HTLCs for channel 65
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.695Z lightningd(5674): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.695Z lightningd(5674): Loading HTLCs for channel 66
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.695Z lightningd(5674): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.695Z lightningd(5674): Loading HTLCs for channel 69
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.704Z lightningd(5674): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.704Z lightningd(5674): Loading HTLCs for channel 70
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.705Z lightningd(5674): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.705Z lightningd(5674): Loading HTLCs for channel 71
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.705Z lightningd(5674): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.705Z lightningd(5674): Loading HTLCs for channel 73
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.706Z lightningd(5674): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.706Z lightningd(5674): Loading HTLCs for channel 75
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.706Z lightningd(5674): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.706Z lightningd(5674): Loading HTLCs for channel 76
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.706Z lightningd(5674): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.706Z lightningd(5674): Loading HTLCs for channel 82
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.706Z lightningd(5674): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.706Z lightningd(5674): Loading HTLCs for channel 84
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.706Z lightningd(5674): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.706Z lightningd(5674): Loading HTLCs for channel 85
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.706Z lightningd(5674): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.706Z lightningd(5674): Loading HTLCs for channel 89
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.706Z lightningd(5674): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.706Z lightningd(5674): Loading HTLCs for channel 341
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.707Z lightningd(5674): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.707Z lightningd(5674): Loading HTLCs for channel 5698
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.707Z lightningd(5674): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.707Z lightningd(5674): Loading HTLCs for channel 5851
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.707Z lightningd(5674): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.707Z lightningd(5674): Loading HTLCs for channel 6135
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.707Z lightningd(5674): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.707Z lightningd(5674): Loading HTLCs for channel 6544
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.707Z lightningd(5674): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.707Z lightningd(5674): Loading HTLCs for channel 6564
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.707Z lightningd(5674): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.707Z lightningd(5674): Loading HTLCs for channel 6962
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.707Z lightningd(5674): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.707Z lightningd(5674): Loading HTLCs for channel 6964
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.708Z lightningd(5674): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.708Z lightningd(5674): Loading HTLCs for channel 6965
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.708Z lightningd(5674): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.708Z lightningd(5674): Loading HTLCs for channel 7257
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.708Z lightningd(5674): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.708Z lightningd(5674): Loading HTLCs for channel 12012
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.708Z lightningd(5674): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.708Z lightningd(5674): Loading HTLCs for channel 12759
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.708Z lightningd(5674): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.708Z lightningd(5674): Loading HTLCs for channel 15060
Nov 01 00:17:48 X lightningd[5674]: 2018-10-31T16:17:48.708Z lightningd(5674): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:48 X lightningd[5674]: Unable to find corresponding htlc_in 1483 for htlc_out 1484
Nov 01 00:17:48 X systemd[1]: c-lightning.service: Main process exited, code=exited, status=1/FAILURE
Nov 01 00:17:48 X systemd[1]: c-lightning.service: Failed with result 'exit-code'.
Nov 01 00:17:48 X systemd[1]: c-lightning.service: Service RestartSec=100ms expired, scheduling restart.
Nov 01 00:17:48 X systemd[1]: c-lightning.service: Scheduled restart job, restart counter is at 2.
Nov 01 00:17:48 X systemd[1]: Stopped c-lightning.service.
Nov 01 00:17:48 X systemd[1]: Started c-lightning.service.
Nov 01 00:17:48 X lightningd[5700]: 2018-10-31T16:17:48.867Z lightningd(5700): testing /extra/home/c-lightning/0.6.1-git/lightning/lightningd/lightning_channeld
Nov 01 00:17:48 X lightningd[5700]: 2018-10-31T16:17:48.869Z lightningd(5700): testing /extra/home/c-lightning/0.6.1-git/lightning/lightningd/lightning_closingd
Nov 01 00:17:48 X lightningd[5700]: 2018-10-31T16:17:48.871Z lightningd(5700): testing /extra/home/c-lightning/0.6.1-git/lightning/lightningd/lightning_connectd
Nov 01 00:17:48 X lightningd[5700]: 2018-10-31T16:17:48.873Z lightningd(5700): testing /extra/home/c-lightning/0.6.1-git/lightning/lightningd/lightning_gossipd
Nov 01 00:17:48 X lightningd[5700]: 2018-10-31T16:17:48.874Z lightningd(5700): testing /extra/home/c-lightning/0.6.1-git/lightning/lightningd/lightning_hsmd
Nov 01 00:17:48 X lightningd[5700]: 2018-10-31T16:17:48.876Z lightningd(5700): testing /extra/home/c-lightning/0.6.1-git/lightning/lightningd/lightning_onchaind
Nov 01 00:17:48 X lightningd[5700]: 2018-10-31T16:17:48.878Z lightningd(5700): testing /extra/home/c-lightning/0.6.1-git/lightning/lightningd/lightning_openingd
Nov 01 00:17:48 X lightningd[5700]: 2018-10-31T16:17:48.985Z lightning_hsmd(5720): pid 5720, msgfd 13
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.009Z lightning_connectd(5722): pid 5722, msgfd 16
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.010Z lightning_hsmd(5720): Client: Received message 11 from client
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.010Z lightning_hsmd(5720): Client: Received message 9 from client
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.035Z lightning_connectd(5722): Created IPv4 listener on port 19248
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.035Z lightning_connectd(5722): REPLY WIRE_CONNECTCTL_INIT_REPLY with 0 fds
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.035Z lightning_gossipd(5723): pid 5723, msgfd 15
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.064Z lightningd(5700): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.065Z lightningd(5700): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.067Z lightningd(5700): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.068Z lightningd(5700): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.070Z lightningd(5700): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.071Z lightningd(5700): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.073Z lightningd(5700): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.075Z lightningd(5700): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.076Z lightningd(5700): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.078Z lightningd(5700): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.079Z lightningd(5700): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.080Z lightningd(5700): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.082Z lightningd(5700): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.083Z lightningd(5700): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.085Z lightningd(5700): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.086Z lightningd(5700): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.087Z lightningd(5700): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.089Z lightningd(5700): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.090Z lightningd(5700): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.091Z lightningd(5700): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.093Z lightningd(5700): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.094Z lightningd(5700): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.096Z lightningd(5700): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.097Z lightningd(5700): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.098Z lightningd(5700): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.100Z lightningd(5700): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.101Z lightningd(5700): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.102Z lightningd(5700): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.104Z lightningd(5700): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.105Z lightningd(5700): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.107Z lightningd(5700): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.108Z lightningd(5700): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.109Z lightningd(5700): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.111Z lightningd(5700): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.112Z lightningd(5700): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.113Z lightningd(5700): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.115Z lightningd(5700): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.115Z lightningd(5700): Loaded 37 channels from DB
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.115Z lightningd(5700): Loading HTLCs for channel 3
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.115Z lightningd(5700): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.115Z lightningd(5700): Loading HTLCs for channel 19
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.115Z lightningd(5700): Restored 0 incoming and 1 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.115Z lightningd(5700): Loading HTLCs for channel 21
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.115Z lightningd(5700): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.115Z lightningd(5700): Loading HTLCs for channel 32
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.115Z lightningd(5700): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.115Z lightningd(5700): Loading HTLCs for channel 40
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.116Z lightningd(5700): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.116Z lightningd(5700): Loading HTLCs for channel 43
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.116Z lightningd(5700): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.116Z lightningd(5700): Loading HTLCs for channel 53
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.116Z lightningd(5700): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.116Z lightningd(5700): Loading HTLCs for channel 56
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.116Z lightningd(5700): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.116Z lightningd(5700): Loading HTLCs for channel 58
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.116Z lightningd(5700): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.116Z lightningd(5700): Loading HTLCs for channel 61
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.116Z lightningd(5700): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.116Z lightningd(5700): Loading HTLCs for channel 63
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.117Z lightningd(5700): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.117Z lightningd(5700): Loading HTLCs for channel 64
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.117Z lightningd(5700): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.117Z lightningd(5700): Loading HTLCs for channel 65
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.117Z lightningd(5700): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.117Z lightningd(5700): Loading HTLCs for channel 66
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.117Z lightningd(5700): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.117Z lightningd(5700): Loading HTLCs for channel 69
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.118Z lightningd(5700): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.118Z lightningd(5700): Loading HTLCs for channel 70
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.118Z lightningd(5700): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.118Z lightningd(5700): Loading HTLCs for channel 71
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.118Z lightningd(5700): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.118Z lightningd(5700): Loading HTLCs for channel 73
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.119Z lightningd(5700): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.119Z lightningd(5700): Loading HTLCs for channel 75
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.119Z lightningd(5700): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.119Z lightningd(5700): Loading HTLCs for channel 76
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.119Z lightningd(5700): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.119Z lightningd(5700): Loading HTLCs for channel 82
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.119Z lightningd(5700): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.119Z lightningd(5700): Loading HTLCs for channel 84
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.119Z lightningd(5700): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.119Z lightningd(5700): Loading HTLCs for channel 85
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.119Z lightningd(5700): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.119Z lightningd(5700): Loading HTLCs for channel 89
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.119Z lightningd(5700): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.119Z lightningd(5700): Loading HTLCs for channel 341
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.119Z lightningd(5700): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.119Z lightningd(5700): Loading HTLCs for channel 5698
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.120Z lightningd(5700): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.120Z lightningd(5700): Loading HTLCs for channel 5851
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.120Z lightningd(5700): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.120Z lightningd(5700): Loading HTLCs for channel 6135
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.120Z lightningd(5700): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.120Z lightningd(5700): Loading HTLCs for channel 6544
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.120Z lightningd(5700): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.120Z lightningd(5700): Loading HTLCs for channel 6564
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.120Z lightningd(5700): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.120Z lightningd(5700): Loading HTLCs for channel 6962
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.120Z lightningd(5700): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.120Z lightningd(5700): Loading HTLCs for channel 6964
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.120Z lightningd(5700): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.120Z lightningd(5700): Loading HTLCs for channel 6965
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.120Z lightningd(5700): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.120Z lightningd(5700): Loading HTLCs for channel 7257
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.120Z lightningd(5700): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.120Z lightningd(5700): Loading HTLCs for channel 12012
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.121Z lightningd(5700): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.121Z lightningd(5700): Loading HTLCs for channel 12759
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.121Z lightningd(5700): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.121Z lightningd(5700): Loading HTLCs for channel 15060
Nov 01 00:17:49 X lightningd[5700]: 2018-10-31T16:17:49.121Z lightningd(5700): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5700]: Unable to find corresponding htlc_in 1483 for htlc_out 1484
Nov 01 00:17:49 X systemd[1]: c-lightning.service: Main process exited, code=exited, status=1/FAILURE
Nov 01 00:17:49 X systemd[1]: c-lightning.service: Failed with result 'exit-code'.
Nov 01 00:17:49 X systemd[1]: c-lightning.service: Service RestartSec=100ms expired, scheduling restart.
Nov 01 00:17:49 X systemd[1]: c-lightning.service: Scheduled restart job, restart counter is at 3.
Nov 01 00:17:49 X systemd[1]: Stopped c-lightning.service.
Nov 01 00:17:49 X systemd[1]: Started c-lightning.service.
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.366Z lightningd(5726): testing /extra/home/c-lightning/0.6.1-git/lightning/lightningd/lightning_channeld
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.368Z lightningd(5726): testing /extra/home/c-lightning/0.6.1-git/lightning/lightningd/lightning_closingd
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.370Z lightningd(5726): testing /extra/home/c-lightning/0.6.1-git/lightning/lightningd/lightning_connectd
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.372Z lightningd(5726): testing /extra/home/c-lightning/0.6.1-git/lightning/lightningd/lightning_gossipd
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.374Z lightningd(5726): testing /extra/home/c-lightning/0.6.1-git/lightning/lightningd/lightning_hsmd
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.375Z lightningd(5726): testing /extra/home/c-lightning/0.6.1-git/lightning/lightningd/lightning_onchaind
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.377Z lightningd(5726): testing /extra/home/c-lightning/0.6.1-git/lightning/lightningd/lightning_openingd
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.466Z lightning_hsmd(5747): pid 5747, msgfd 13
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.491Z lightning_connectd(5749): pid 5749, msgfd 16
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.491Z lightning_hsmd(5747): Client: Received message 11 from client
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.491Z lightning_hsmd(5747): Client: Received message 9 from client
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.515Z lightning_connectd(5749): Created IPv4 listener on port 19248
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.515Z lightning_connectd(5749): REPLY WIRE_CONNECTCTL_INIT_REPLY with 0 fds
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.516Z lightning_gossipd(5750): pid 5750, msgfd 15
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.542Z lightningd(5726): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.544Z lightningd(5726): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.545Z lightningd(5726): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.547Z lightningd(5726): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.548Z lightningd(5726): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.549Z lightningd(5726): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.551Z lightningd(5726): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.553Z lightningd(5726): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.554Z lightningd(5726): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.555Z lightningd(5726): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.557Z lightningd(5726): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.558Z lightningd(5726): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.560Z lightningd(5726): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.561Z lightningd(5726): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.562Z lightningd(5726): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.564Z lightningd(5726): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.565Z lightningd(5726): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.567Z lightningd(5726): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.568Z lightningd(5726): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.569Z lightningd(5726): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.571Z lightningd(5726): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.572Z lightningd(5726): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.574Z lightningd(5726): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.575Z lightningd(5726): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.577Z lightningd(5726): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.578Z lightningd(5726): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.580Z lightningd(5726): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.581Z lightningd(5726): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.583Z lightningd(5726): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.584Z lightningd(5726): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.585Z lightningd(5726): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.587Z lightningd(5726): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.588Z lightningd(5726): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.590Z lightningd(5726): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.591Z lightningd(5726): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.593Z lightningd(5726): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.594Z lightningd(5726): Loaded 0 HTLC signatures from DB
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.594Z lightningd(5726): Loaded 37 channels from DB
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.594Z lightningd(5726): Loading HTLCs for channel 3
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.594Z lightningd(5726): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.594Z lightningd(5726): Loading HTLCs for channel 19
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.595Z lightningd(5726): Restored 0 incoming and 1 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.595Z lightningd(5726): Loading HTLCs for channel 21
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.595Z lightningd(5726): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.595Z lightningd(5726): Loading HTLCs for channel 32
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.595Z lightningd(5726): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.595Z lightningd(5726): Loading HTLCs for channel 40
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.595Z lightningd(5726): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.595Z lightningd(5726): Loading HTLCs for channel 43
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.596Z lightningd(5726): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.596Z lightningd(5726): Loading HTLCs for channel 53
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.596Z lightningd(5726): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.596Z lightningd(5726): Loading HTLCs for channel 56
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.596Z lightningd(5726): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.596Z lightningd(5726): Loading HTLCs for channel 58
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.596Z lightningd(5726): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.596Z lightningd(5726): Loading HTLCs for channel 61
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.596Z lightningd(5726): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.597Z lightningd(5726): Loading HTLCs for channel 63
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.597Z lightningd(5726): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.597Z lightningd(5726): Loading HTLCs for channel 64
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.597Z lightningd(5726): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.597Z lightningd(5726): Loading HTLCs for channel 65
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.597Z lightningd(5726): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.597Z lightningd(5726): Loading HTLCs for channel 66
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.597Z lightningd(5726): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.597Z lightningd(5726): Loading HTLCs for channel 69
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.599Z lightningd(5726): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.599Z lightningd(5726): Loading HTLCs for channel 70
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.599Z lightningd(5726): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.599Z lightningd(5726): Loading HTLCs for channel 71
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.599Z lightningd(5726): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.599Z lightningd(5726): Loading HTLCs for channel 73
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.599Z lightningd(5726): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.599Z lightningd(5726): Loading HTLCs for channel 75
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.600Z lightningd(5726): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.600Z lightningd(5726): Loading HTLCs for channel 76
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.600Z lightningd(5726): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.600Z lightningd(5726): Loading HTLCs for channel 82
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.600Z lightningd(5726): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.600Z lightningd(5726): Loading HTLCs for channel 84
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.600Z lightningd(5726): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.600Z lightningd(5726): Loading HTLCs for channel 85
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.600Z lightningd(5726): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.600Z lightningd(5726): Loading HTLCs for channel 89
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.601Z lightningd(5726): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.601Z lightningd(5726): Loading HTLCs for channel 341
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.601Z lightningd(5726): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.601Z lightningd(5726): Loading HTLCs for channel 5698
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.601Z lightningd(5726): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.601Z lightningd(5726): Loading HTLCs for channel 5851
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.601Z lightningd(5726): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.601Z lightningd(5726): Loading HTLCs for channel 6135
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.601Z lightningd(5726): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.601Z lightningd(5726): Loading HTLCs for channel 6544
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.601Z lightningd(5726): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.602Z lightningd(5726): Loading HTLCs for channel 6564
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.602Z lightningd(5726): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.602Z lightningd(5726): Loading HTLCs for channel 6962
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.602Z lightningd(5726): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.602Z lightningd(5726): Loading HTLCs for channel 6964
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.602Z lightningd(5726): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.602Z lightningd(5726): Loading HTLCs for channel 6965
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.602Z lightningd(5726): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.602Z lightningd(5726): Loading HTLCs for channel 7257
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.602Z lightningd(5726): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.602Z lightningd(5726): Loading HTLCs for channel 12012
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.603Z lightningd(5726): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.603Z lightningd(5726): Loading HTLCs for channel 12759
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.603Z lightningd(5726): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.603Z lightningd(5726): Loading HTLCs for channel 15060
Nov 01 00:17:49 X lightningd[5726]: 2018-10-31T16:17:49.603Z lightningd(5726): Restored 0 incoming and 0 outgoing HTLCS
Nov 01 00:17:49 X lightningd[5726]: Unable to find corresponding htlc_in 1483 for htlc_out 1484
Nov 01 00:17:49 X systemd[1]: c-lightning.service: Main process exited, code=exited, status=1/FAILURE
Nov 01 00:17:49 X systemd[1]: c-lightning.service: Failed with result 'exit-code'.
Nov 01 00:17:49 X systemd[1]: c-lightning.service: Service RestartSec=100ms expired, scheduling restart.
Nov 01 00:17:49 X systemd[1]: c-lightning.service: Scheduled restart job, restart counter is at 4.
Nov 01 00:17:49 X systemd[1]: Stopped c-lightning.service.
@jogc
Copy link
Author

jogc commented Nov 1, 2018

So I upgraded to 0.6.2 and now its starting again, but I'll leave this up in case the crash itself effects 0.6.2 as well.

@cdecker
Copy link
Member

cdecker commented Nov 1, 2018

The restart issue was indeed fixed. The original crash seems to be a null-pointer in the logging, here:

lightning/lightningd/log.c

Lines 233 to 244 in 0dcd668

static struct log_entry *new_log_entry(struct log *log, enum log_level level)
{
struct log_entry *l = tal(log->lr, struct log_entry);
l->time = time_now();
l->level = level;
l->skipped = 0;
l->prefix = log->prefix;
l->io = NULL;
return l;
}

So this is most likely a log_book that is not being initialized correctly @rustyrussell?

@cdecker cdecker added the crash label Nov 1, 2018
@darosior
Copy link
Contributor

darosior commented Nov 4, 2019

@jogc I think this can be closed ?

@jogc jogc closed this as completed Nov 4, 2019
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
crash
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
3 participants
@cdecker @jogc @darosior