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

Handle blockheight disagreement between us and payee #3376

Merged
merged 6 commits into from
Jan 21, 2020

Conversation

ZmnSCPxj
Copy link
Collaborator

@ZmnSCPxj ZmnSCPxj commented Dec 26, 2019

Fixes #3367

Due to the recent BOLT change that merges final_expiry_too_soon into incorrect_or_unknown_payment_details, the previously-retried transient failure final_expiry_too_soon (17), which used to be how blockheight disagreements between payee and payer were signalled, is now a permanent failure incorrect_or_unknown_payment_details (PERM|15). Since it is a permanent failure at the payee end, pay did not retry it. (The commit linked is factually incorrect as well: it claims final_expiry_too_soon to be PERM|17, but looking at its actual diff shows it was 17 before the commit).

Blockheight disagreements between payer and payee are transient and should be retried. This patch is parses the returned height from the payee and checks if it is the same as the height at which we started the attempt; if different, we wait until we reach the payee blockheight using the new waitblockheight command (the first commit), then retry.

@NicolasDorier please notice this PR.

Currently draft since I still have to make a proper reproducible test for this. PRed now so that @NicolasDorier can go patch it in his software.

Test plan:

  • Start a line_graph of l1, l2, l3.
  • Set up l2 so it stops before it can forward any payment (I believe we have this feature in our test suite somehow, just need to review it again).
  • Get an invoice from l3.
  • In a background executor, have l1 pay the invoice.
  • Wait for l2 to stop.
  • Advance the blockchain by 1 block.
  • Restart l2, setting it up so it will now correctly forward payments.
  • Check that the background executor now completes successfully.

@NicolasDorier
Copy link
Collaborator

@ZmnSCPxj you want me to test now or wait a bit it is more stable?

@ZmnSCPxj
Copy link
Collaborator Author

It should be stable at this point. I just need to create a reproducible test, which requires me to dredge through our codebase for how we do this kind of testing where we have to pause forwarding temporarily. You can evaluate with your test suite now in parallel to confirm the fix.

@ZmnSCPxj
Copy link
Collaborator Author

@rustyrussell @cdecker @niftynei the CI "just" needs some re-kicking, it seems failure is only timeout due to valgrind being slow. For some reason I can no longer restart the travis-ci for any ElementsProject/lightning PRs myself, I used to be able to do that, maybe some permissions problem?

@ZmnSCPxj
Copy link
Collaborator Author

ZmnSCPxj commented Dec 26, 2019

@NicolasDorier there are 4 commits, you have to cherry-pick them in order.

git cherry-pick 138a7e0 c340ae8 78a0827 1545350 

The attempt is declared in c340ae8 and then used in 1545350. If you only cherry-picked 1545350 then the declaration is not created and initialized. There are 4 commits since I want to separate refactors that make later functional changes easier to make, from the functional changes.

@NicolasDorier
Copy link
Collaborator

@ZmnSCPxj yes sorry, by the time you replied, I removed the comment ;)

@NicolasDorier
Copy link
Collaborator

For the repro of the test, it is simple enough.

Create two nodes, sharing the same bitcoind, connect them together.

Then create 6 block and imediately after create an invoice and pay it. If you repeat that enough time, eventually you'll get the repro. (10 times should be enough)

@NicolasDorier
Copy link
Collaborator

@ZmnSCPxj

Got the error again. But I think you improved the situation, it took a while before happening again.

lightningd_1       | 2019-12-26T08:09:08.119Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: peer_in WIRE_REVOKE_AND_ACK
lightningd_1       | 2019-12-26T08:09:08.120Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Received revoke_and_ack
lightningd_1       | 2019-12-26T08:09:08.120Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: htlc 206: SENT_REMOVE_ACK_COMMIT->RCVD_REMOVE_ACK_REVOCATION
lightningd_1       | 2019-12-26T08:09:08.121Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: rcvd_revoke_and_ack: HTLC LOCAL 206 = RCVD_REMOVE_ACK_REVOCATION/SENT_REMOVE_ACK_REVOCATION FAILED
lightningd_1       | 2019-12-26T08:09:08.121Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: No commits outstanding after recv revoke_and_ack
lightningd_1       | 2019-12-26T08:09:08.121Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: HTLC 206[LOCAL] => RCVD_REMOVE_ACK_REVOCATION
lightningd_1       | 2019-12-26T08:09:08.121Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Sending master 1022
lightningd_1       | 2019-12-26T08:09:08.121Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-chan#1: got revoke 413: 1 changed
lightningd_1       | 2019-12-26T08:09:08.121Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-chan#1: HTLC out 206 SENT_REMOVE_ACK_COMMIT->RCVD_REMOVE_ACK_REVOCATION
lightningd_1       | 2019-12-26T08:09:08.122Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-chan#1: Removing out HTLC 206 state RCVD_REMOVE_ACK_REVOCATION REMOTEFAIL
lightningd_1       | 2019-12-26T08:09:08.122Z INFO 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-chan#1: htlc 206 failed from 0th node with code 0x400f (WIRE_INCORRECT_OR_UNKNOWN_PAYMENT_DETAILS)
lightningd_1       | 2019-12-26T08:09:08.122Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-chan#1: failmsg: 400f00000000000003e8000000fb
lightningd_dest_1  | 2019-12-26T08:09:08.119Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: ... , awaiting 1121
lightningd_dest_1  | 2019-12-26T08:09:08.119Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Got it!
lightningd_dest_1  | 2019-12-26T08:09:08.124Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: peer_out WIRE_REVOKE_AND_ACK
lightningd_1       | 2019-12-26T08:09:08.152Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: ... , awaiting 1122
lightningd_1       | 2019-12-26T08:09:08.152Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Got it!
lightningd_1       | 2019-12-26T08:09:08.152Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: revoke_and_ack LOCAL: remote_per_commit = 03ad3e9aa884084707a48a96ffefe19526545e4d278fc7a56438ad316b2e70a356, old_remote_per_commit = 026291b4b313c30a342b4149c754d2fccc2a0d39fe9d7f9b0f145bf008ece28c66
lightningd_1       | 2019-12-26T08:09:08.153Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Trying commit
lightningd_1       | 2019-12-26T08:09:08.153Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Can't send commit: nothing to send
lightningd_1       | 2019-12-26T08:09:08.223Z DEBUG lightningd: Adding block 250: 3e0288a3dc390af95af902c7291abe633f35a9b6e6940de8daa157ff449d0a2b
lightningd_1       | 2019-12-26T08:09:08.319Z DEBUG lightningd: Adding block 251: 70ed185bab07d2b8352e4e6175b112d89a83594214934c255085feac6ccfada0
lightningd_1       | 2019-12-26T08:09:08.450Z UNUSUAL lightningd: Unable to estimate CONSERVATIVE/2 fee

@ZmnSCPxj
Copy link
Collaborator Author

Unfortunately our test environment tends to strongly synchronize all nodes to bitcoind, so it may require a lot more loops to reproduce. In any case I have a test I am trying out in https://github.com/ZmnSCPxj/lightning/tree/blockheight-disagreement-try which should fail (it does not include the fix) to check that the test actually does tickle the bug.

Hmmm looks like it would be more reliable to parse height from the raw_message at this point, sigh. Needs a waitblockheight of some kind.

@NicolasDorier
Copy link
Collaborator

Other error, seem slightly different?

lightningd_dest_1  | 2019-12-26T08:14:00.425Z DEBUG hsmd: Client: Received message 18 from client
lightningd_dest_1  | 2019-12-26T08:14:00.425Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Derived key 0353e5207cc477d326e30729945425b5b099122df070d8ea110496033c6060d293 from basepoint 023f414bc16cd032e1551c6bbfae09ed59d42668fb5c9b566b89d29b5ba001c9a3, point 0216afc6f8996138b640556a23136c90ee83fb79ce1c7409c01ec34c98f61869b1
lightningd_dest_1  | 2019-12-26T08:14:00.425Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Received commit_sig with 0 htlc sigs
lightningd_dest_1  | 2019-12-26T08:14:00.426Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Sending revoke_and_ack
lightningd_dest_1  | 2019-12-26T08:14:00.427Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: htlc 264: RCVD_REMOVE_ACK_COMMIT->SENT_REMOVE_ACK_REVOCATION
lightningd_dest_1  | 2019-12-26T08:14:00.427Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: sending_revoke_and_ack: HTLC REMOTE 264 = SENT_REMOVE_ACK_REVOCATION/RCVD_REMOVE_ACK_REVOCATION FAILCODE:16399
lightningd_dest_1  | 2019-12-26T08:14:00.428Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Sending master 1021
lightningd_dest_1  | 2019-12-26T08:14:00.428Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-chan#1: got commitsig 530: feerate 4000, 0 added, 0 fulfilled, 0 failed, 1 changed
lightningd_dest_1  | 2019-12-26T08:14:00.430Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-chan#1: HTLC in 264 RCVD_REMOVE_REVOCATION->RCVD_REMOVE_ACK_COMMIT
lightningd_dest_1  | 2019-12-26T08:14:00.431Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-chan#1: HTLC in 264 RCVD_REMOVE_ACK_COMMIT->SENT_REMOVE_ACK_REVOCATION
lightningd_dest_1  | 2019-12-26T08:14:00.431Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-chan#1: Removing in HTLC 264 state SENT_REMOVE_ACK_REVOCATION WIRE_INCORRECT_OR_UNKNOWN_PAYMENT_DETAILS
lightningd_dest_1  | 2019-12-26T08:14:00.474Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: ... , awaiting 1121
lightningd_dest_1  | 2019-12-26T08:14:00.475Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Got it!
lightningd_1       | 2019-12-26T08:14:00.475Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: peer_in WIRE_REVOKE_AND_ACK
lightningd_1       | 2019-12-26T08:14:00.476Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Received revoke_and_ack
lightningd_1       | 2019-12-26T08:14:00.476Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: htlc 264: SENT_REMOVE_ACK_COMMIT->RCVD_REMOVE_ACK_REVOCATION
lightningd_1       | 2019-12-26T08:14:00.476Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: rcvd_revoke_and_ack: HTLC LOCAL 264 = RCVD_REMOVE_ACK_REVOCATION/SENT_REMOVE_ACK_REVOCATION FAILED
lightningd_1       | 2019-12-26T08:14:00.476Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: No commits outstanding after recv revoke_and_ack
lightningd_1       | 2019-12-26T08:14:00.477Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: HTLC 264[LOCAL] => RCVD_REMOVE_ACK_REVOCATION
lightningd_1       | 2019-12-26T08:14:00.477Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Sending master 1022
lightningd_1       | 2019-12-26T08:14:00.477Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-chan#1: got revoke 529: 1 changed
lightningd_1       | 2019-12-26T08:14:00.477Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-chan#1: HTLC out 264 SENT_REMOVE_ACK_COMMIT->RCVD_REMOVE_ACK_REVOCATION
lightningd_1       | 2019-12-26T08:14:00.477Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-chan#1: Removing out HTLC 264 state RCVD_REMOVE_ACK_REVOCATION REMOTEFAIL
lightningd_1       | 2019-12-26T08:14:00.478Z INFO 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-chan#1: htlc 264 failed from 0th node with code 0x400f (WIRE_INCORRECT_OR_UNKNOWN_PAYMENT_DETAILS)
lightningd_1       | 2019-12-26T08:14:00.478Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-chan#1: failmsg: 400f00000000000003e800000123
lightningd_dest_1  | 2019-12-26T08:14:00.479Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: peer_out WIRE_REVOKE_AND_ACK
lightningd_1       | 2019-12-26T08:14:00.494Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: ... , awaiting 1122
lightningd_1       | 2019-12-26T08:14:00.494Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Got it!
lightningd_1       | 2019-12-26T08:14:00.494Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: revoke_and_ack LOCAL: remote_per_commit = 029b2017c05dfadbf6e646d79766bbbfa318fc4fe1b15934588f680dffd0289dd6, old_remote_per_commit = 0216afc6f8996138b640556a23136c90ee83fb79ce1c7409c01ec34c98f61869b1
lightningd_1       | 2019-12-26T08:14:00.495Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Trying commit
lightningd_1       | 2019-12-26T08:14:00.495Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Can't send commit: nothing to send
lightningd_1       | 2019-12-26T08:14:00.615Z DEBUG lightningd: Adding block 290: 086f3d9027cef26f5d66262c9448905aac6c6dc37617a1fdab6d5902f7e3a82d
lightningd_1       | 2019-12-26T08:14:00.684Z DEBUG lightningd: Adding block 291: 78e3610fd1a0014f9e6838bb3fa29cdf06a0b2bb611d45fe82834088304bb264
lightningd_1       | 2019-12-26T08:14:00.800Z UNUSUAL lightningd: Unable to estimate CONSERVATIVE/2 fee
lightningd_1       | 2019-12-26T08:14:00.818Z UNUSUAL lightningd: Unable to estimate ECONOMICAL/4 fee
lightningd_1       | 2019-12-26T08:14:00.838Z UNUSUAL lightningd: Unable to estimate ECONOMICAL/100

@NicolasDorier
Copy link
Collaborator

So in summary, still got errors with the following messages:
400f00000000000003e800000123
400f00000000000003e8000000fb

@ZmnSCPxj
Copy link
Collaborator Author

The changes in the last bytes of the messages are just the blockheight at which the payee is. The intended way to detect the blockheight disagreement is to check if the payer local blockheight at the time the sendpay was made, is less than the blockheight of the payee. So the difference in those last few bytes is simply your test being relatively random on what blockheight it triggers.

@NicolasDorier
Copy link
Collaborator

The previous error I reported was 400f00000000000027100000009d so I guess you fixed this one at least.

@ZmnSCPxj
Copy link
Collaborator Author

0x123 == 291 for example, meaning the payee was at height 291 when it got the payment. In your log we then see the payer actually process blocks 290 and 291. Similarly, 0xfb == 251, we see that the log shows the payer only begins to process blocks 250 and 251 after the payment has been returned and failed. So it is still the same error, blockheight disagreement.

@NicolasDorier
Copy link
Collaborator

@ZmnSCPxj it is not same error than 400f00000000000027100000009d the last bytes are not the only one which changed. Before was 2710 now is 3e80

@ZmnSCPxj
Copy link
Collaborator Author

ZmnSCPxj commented Dec 26, 2019

The 2710 and 03e8 are the actual amounts being received at the payee. 2710==10000 msat, 03e8 == 1000 msat. Did you change your sent amount?

The initial 400f is the WIRE_INCORRECT_OR_UNKNOWN_PAYMENT_DETAILS, so that is the actual error being reported.

@NicolasDorier
Copy link
Collaborator

NicolasDorier commented Dec 26, 2019

No I did not, but it is possible as there are two places where I send different amount and it randomly fail another place. I found Expiry cltv too soon 304 < 295 + 10 in the logs so I guess this is same message.

@NicolasDorier
Copy link
Collaborator

	if ((code != PAY_UNPARSEABLE_ONION) &&
	    ((failcode == 17) ||
	     ((failcode == WIRE_INCORRECT_OR_UNKNOWN_PAYMENT_DETAILS) &&
	      (attempt->start_block != attempt->waitsendpay_err_block))))
		return start_pay_attempt(cmd, pc,
					 "Retrying due to possible "
					 "blockheight disagreement "
					 "with receiver: "
					 "we sent at height %"PRIu32", "
					 "currently at height %"PRIu32".",
					 attempt->start_block,
					 attempt->waitsendpay_err_block);

This message does not appear anywhere

@NicolasDorier
Copy link
Collaborator

here full log of an attempt which happened faster:


lightningd_dest_1  | 2019-12-26T08:30:31.772Z UNUSUAL lightningd: Unable to estimate CONSERVATIVE/2 fee
lightningd_dest_1  | 2019-12-26T08:30:31.804Z UNUSUAL lightningd: Unable to estimate ECONOMICAL/4 fee
lightningd_dest_1  | 2019-12-26T08:30:31.824Z UNUSUAL lightningd: Unable to estimate ECONOMICAL/100 fee
lightningd_1       | 2019-12-26T08:30:32.171Z UNUSUAL lightningd: Unable to estimate CONSERVATIVE/2 fee
lightningd_1       | 2019-12-26T08:30:32.194Z UNUSUAL lightningd: Unable to estimate ECONOMICAL/4 fee
lightningd_1       | 2019-12-26T08:30:32.224Z UNUSUAL lightningd: Unable to estimate ECONOMICAL/100 fee
lightningd_dest_1  | 2019-12-26T08:30:32.658Z DEBUG lightningd: exposeprivate = NULL
lightningd_dest_1  | 2019-12-26T08:30:32.659Z DEBUG gossipd: REPLY WIRE_GOSSIP_GET_INCOMING_CHANNELS_REPLY with 0 fds
lightningd_dest_1  | 2019-12-26T08:30:32.660Z UNUSUAL lightningd: invoice: insufficient incoming capacity for 1000msat
lightningd_dest_1  | 2019-12-26T08:30:32.687Z DEBUG hsmd: Client: Received message 8 from client
lightningd_1       | 2019-12-26T08:30:32.733Z DEBUG gossipd: REPLY WIRE_GOSSIP_GETCHANNELS_REPLY with 0 fds
lightningd_1       | 2019-12-26T08:30:32.741Z DEBUG gossipd: Trying to find a route from (me) to 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf for 1001msat
lightningd_1       | 2019-12-26T08:30:32.741Z DEBUG gossipd: REPLY WIRE_GOSSIP_GETROUTE_REPLY with 0 fds
lightningd_1       | 2019-12-26T08:30:32.743Z INFO lightningd: Sending 1001msat over 1 hops to deliver 1001msat
lightningd_1       | 2019-12-26T08:30:32.744Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: NEW:: HTLC LOCAL 265 = SENT_ADD_HTLC/RCVD_ADD_HTLC
lightningd_1       | 2019-12-26T08:30:32.744Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Adding HTLC 265 amount=1001msat cltv=308 gave CHANNEL_ERR_ADD_OK
lightningd_1       | 2019-12-26T08:30:32.745Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: peer_out WIRE_UPDATE_ADD_HTLC
lightningd_1       | 2019-12-26T08:30:32.745Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: REPLY WIRE_CHANNEL_OFFER_HTLC_REPLY with 0 fds
lightningd_1       | 2019-12-26T08:30:32.756Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Trying commit
lightningd_1       | 2019-12-26T08:30:32.756Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: htlc 265: SENT_ADD_HTLC->SENT_ADD_COMMIT
lightningd_1       | 2019-12-26T08:30:32.756Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: htlc added REMOTE: local 16775492724 remote 1722276
lightningd_1       | 2019-12-26T08:30:32.756Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: -> local 16775491723 remote 1722276
lightningd_1       | 2019-12-26T08:30:32.756Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: sending_commit: HTLC LOCAL 265 = SENT_ADD_COMMIT/RCVD_ADD_COMMIT
lightningd_1       | 2019-12-26T08:30:32.757Z DEBUG hsmd: Client: Received message 19 from client
lightningd_1       | 2019-12-26T08:30:32.757Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Creating commit_sig signature 531 3044022073dd649e2459bcb36344d8a7705ee8e4a47a3ea2db16ba80f86c4cde5b2f462d0220408ca1f122de965be8c5415e1042ca547c2f1b89b460e86c3aafc3fc5a1e9f0e01 for tx 0200000001b399e7d4320d1e9591432cd40a39f07e4696d898028d0415436c52ffc293a5b600000000001742ab8002ba06000000000000220020257f50faef0c5796f7d9aa8c9fc653fd16bb61cc88eb501709f119b2c1c3b618f3edff000000000016001492902e4d84471c9e0e83fef2ef72fe3210faf16cd71a1220 wscript 522102305d92049c25c0537cff15899fd6f402c0f5b181a31304a5ce8ef27c117eabf62103fba00a0705ca7f347c579366d13075fe8d4dabd22a9445198be75b566b3d953052ae key 03fba00a0705ca7f347c579366d13075fe8d4dabd22a9445198be75b566b3d9530
lightningd_dest_1  | 2019-12-26T08:30:32.745Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: peer_in WIRE_UPDATE_ADD_HTLC
lightningd_dest_1  | 2019-12-26T08:30:32.745Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: NEW:: HTLC REMOTE 265 = RCVD_ADD_HTLC/SENT_ADD_HTLC 
lightningd_dest_1  | 2019-12-26T08:30:32.746Z DEBUG hsmd: Client: Received message 1 from client
lightningd_1       | 2019-12-26T08:30:32.759Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Telling master we're about to commit...
lightningd_1       | 2019-12-26T08:30:32.773Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Sending master 1020
lightningd_1       | 2019-12-26T08:30:32.774Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-chan#1: HTLC out 265 SENT_ADD_HTLC->SENT_ADD_COMMIT
lightningd_1       | 2019-12-26T08:30:32.837Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: ... , awaiting 1120
lightningd_1       | 2019-12-26T08:30:32.840Z DEBUG lightningd: Payment part 0/0 status 0
lightningd_1       | 2019-12-26T08:30:32.841Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Got it!
lightningd_1       | 2019-12-26T08:30:32.841Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Sending commit_sig with 0 htlc sigs
lightningd_1       | 2019-12-26T08:30:32.842Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: peer_out WIRE_COMMITMENT_SIGNED
lightningd_1       | 2019-12-26T08:30:32.860Z DEBUG lightningd: Adding block 292: 202185f92fee67301fce48b80af208b0f2eaf2ff801302d60fe644c688d188f6
lightningd_dest_1  | 2019-12-26T08:30:32.845Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: peer_in WIRE_COMMITMENT_SIGNED
lightningd_dest_1  | 2019-12-26T08:30:32.846Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Received commit
lightningd_dest_1  | 2019-12-26T08:30:32.872Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: htlc 265: RCVD_ADD_HTLC->RCVD_ADD_COMMIT
lightningd_dest_1  | 2019-12-26T08:30:32.873Z DEBUG hsmd: Client: Received message 18 from client
lightningd_dest_1  | 2019-12-26T08:30:32.873Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: htlc added LOCAL: local 1722276 remote 16775492724
lightningd_dest_1  | 2019-12-26T08:30:32.873Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: -> local 1722276 remote 16775491723
lightningd_dest_1  | 2019-12-26T08:30:32.873Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: rcvd_commit: HTLC REMOTE 265 = RCVD_ADD_COMMIT/SENT_ADD_COMMIT
lightningd_dest_1  | 2019-12-26T08:30:32.874Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Feerates are 4000/4000
lightningd_dest_1  | 2019-12-26T08:30:32.874Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: We need 170668sat at feerate 4000 for 0 untrimmed htlcs: we have 16775491723msat/16775492724msat
lightningd_dest_1  | 2019-12-26T08:30:32.874Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Derived key 02646fcb283031ddc721a65a624eaae033353df1a5e1ebd3809a6d0f3af998eaa3 from basepoint 023f414bc16cd032e1551c6bbfae09ed59d42668fb5c9b566b89d29b5ba001c9a3, point 029b2017c05dfadbf6e646d79766bbbfa318fc4fe1b15934588f680dffd0289dd6
lightningd_dest_1  | 2019-12-26T08:30:32.875Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Received commit_sig with 0 htlc sigs
lightningd_dest_1  | 2019-12-26T08:30:32.875Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Sending revoke_and_ack
lightningd_dest_1  | 2019-12-26T08:30:32.876Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: htlc 265: RCVD_ADD_COMMIT->SENT_ADD_REVOCATION
lightningd_dest_1  | 2019-12-26T08:30:32.876Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: sending_revoke_and_ack: HTLC REMOTE 265 = SENT_ADD_REVOCATION/RCVD_ADD_REVOCATION
lightningd_dest_1  | 2019-12-26T08:30:32.876Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: revoke_and_ack made pending: commit timer
lightningd_dest_1  | 2019-12-26T08:30:32.877Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Sending master 1021
lightningd_dest_1  | 2019-12-26T08:30:32.877Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-chan#1: got commitsig 531: feerate 4000, 1 added, 0 fulfilled, 0 failed, 0 changed
lightningd_dest_1  | 2019-12-26T08:30:32.923Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-chan#1: Adding their HTLC 265
lightningd_dest_1  | 2019-12-26T08:30:32.923Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-chan#1: HTLC in 265 RCVD_ADD_COMMIT->SENT_ADD_REVOCATION
lightningd_1       | 2019-12-26T08:30:33.051Z DEBUG lightningd: Adding block 293: 3a3c069e8318d31d403ceff0d21db784b0c40201357d1ff4a7579dce2e9b2f7f
lightningd_dest_1  | 2019-12-26T08:30:33.059Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: ... , awaiting 1121
lightningd_dest_1  | 2019-12-26T08:30:33.059Z UNUSUAL lightningd: Unable to estimate CONSERVATIVE/2 fee
lightningd_dest_1  | 2019-12-26T08:30:33.067Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Got it!
lightningd_dest_1  | 2019-12-26T08:30:33.068Z DEBUG hsmd: Client: Received message 19 from client
lightningd_dest_1  | 2019-12-26T08:30:33.068Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: peer_out WIRE_REVOKE_AND_ACK
lightningd_dest_1  | 2019-12-26T08:30:33.068Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Trying commit
lightningd_dest_1  | 2019-12-26T08:30:33.068Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: htlc 265: SENT_ADD_REVOCATION->SENT_ADD_ACK_COMMIT
lightningd_dest_1  | 2019-12-26T08:30:33.068Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: htlc added REMOTE: local 1722276 remote 16775492724
lightningd_dest_1  | 2019-12-26T08:30:33.068Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: -> local 1722276 remote 16775491723
lightningd_dest_1  | 2019-12-26T08:30:33.069Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: sending_commit: HTLC REMOTE 265 = SENT_ADD_ACK_COMMIT/RCVD_ADD_ACK_COMMIT
lightningd_dest_1  | 2019-12-26T08:30:33.069Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Creating commit_sig signature 531 304402205115fe81530a1cd7b38b544e85b568137fb58b55ec1e6f95b1437e57acd8da2202206e4b405726ed34aeb03dbe26d87b315085e12871ab9d712d75bf33828160526501 for tx 0200000001b399e7d4320d1e9591432cd40a39f07e4696d898028d0415436c52ffc293a5b600000000001742ab8002ba060000000000001600142a08b0bea717615a09590719e1f7827b23d7fa5ff3edff00000000002200203b58b2c6a160cf6962769757e3c5d1c1511eb93a31328f4cc6612c54941d5eabd71a1220 wscript 522102305d92049c25c0537cff15899fd6f402c0f5b181a31304a5ce8ef27c117eabf62103fba00a0705ca7f347c579366d13075fe8d4dabd22a9445198be75b566b3d953052ae key 02305d92049c25c0537cff15899fd6f402c0f5b181a31304a5ce8ef27c117eabf6
lightningd_dest_1  | 2019-12-26T08:30:33.070Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Telling master we're about to commit...
lightningd_dest_1  | 2019-12-26T08:30:33.070Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Sending master 1020
lightningd_dest_1  | 2019-12-26T08:30:33.070Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-chan#1: HTLC in 265 SENT_ADD_REVOCATION->SENT_ADD_ACK_COMMIT
lightningd_1       | 2019-12-26T08:30:33.129Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: peer_in WIRE_REVOKE_AND_ACK
lightningd_dest_1  | 2019-12-26T08:30:33.152Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: ... , awaiting 1120
lightningd_dest_1  | 2019-12-26T08:30:33.152Z UNUSUAL lightningd: Unable to estimate ECONOMICAL/4 fee
lightningd_dest_1  | 2019-12-26T08:30:33.157Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Got it!
lightningd_dest_1  | 2019-12-26T08:30:33.193Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Sending commit_sig with 0 htlc sigs
lightningd_dest_1  | 2019-12-26T08:30:33.197Z UNUSUAL lightningd: Unable to estimate ECONOMICAL/100 fee
lightningd_dest_1  | 2019-12-26T08:30:33.223Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: peer_out WIRE_COMMITMENT_SIGNED
lightningd_dest_1  | 2019-12-26T08:30:33.225Z DEBUG lightningd: Adding block 292: 202185f92fee67301fce48b80af208b0f2eaf2ff801302d60fe644c688d188f6
lightningd_1       | 2019-12-26T08:30:33.307Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Received revoke_and_ack
lightningd_1       | 2019-12-26T08:30:33.307Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: htlc 265: SENT_ADD_COMMIT->RCVD_ADD_REVOCATION
lightningd_1       | 2019-12-26T08:30:33.307Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: rcvd_revoke_and_ack: HTLC LOCAL 265 = RCVD_ADD_REVOCATION/SENT_ADD_REVOCATION
lightningd_1       | 2019-12-26T08:30:33.307Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Commits outstanding after recv revoke_and_ack
lightningd_1       | 2019-12-26T08:30:33.307Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: HTLC 265[LOCAL] => RCVD_ADD_REVOCATION
lightningd_1       | 2019-12-26T08:30:33.308Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Sending master 1022
lightningd_1       | 2019-12-26T08:30:33.308Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-chan#1: got revoke 530: 1 changed
lightningd_1       | 2019-12-26T08:30:33.308Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-chan#1: HTLC out 265 SENT_ADD_COMMIT->RCVD_ADD_REVOCATION
lightningd_dest_1  | 2019-12-26T08:30:33.370Z DEBUG lightningd: Adding block 293: 3a3c069e8318d31d403ceff0d21db784b0c40201357d1ff4a7579dce2e9b2f7f
lightningd_1       | 2019-12-26T08:30:33.377Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: ... , awaiting 1122
lightningd_1       | 2019-12-26T08:30:33.379Z UNUSUAL lightningd: Unable to estimate CONSERVATIVE/2 fee
lightningd_1       | 2019-12-26T08:30:33.394Z DEBUG hsmd: Client: Received message 18 from client
lightningd_1       | 2019-12-26T08:30:33.394Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Got it!
lightningd_1       | 2019-12-26T08:30:33.395Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: revoke_and_ack LOCAL: remote_per_commit = 036c4846c194b1f4598b2196cd791068c1a25e5a3daaf349ae60e50e2013a29408, old_remote_per_commit = 029b2017c05dfadbf6e646d79766bbbfa318fc4fe1b15934588f680dffd0289dd6
lightningd_1       | 2019-12-26T08:30:33.395Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Trying commit
lightningd_1       | 2019-12-26T08:30:33.395Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Can't send commit: nothing to send
lightningd_1       | 2019-12-26T08:30:33.395Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: peer_in WIRE_COMMITMENT_SIGNED
lightningd_1       | 2019-12-26T08:30:33.395Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Received commit
lightningd_1       | 2019-12-26T08:30:33.395Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: htlc 265: RCVD_ADD_REVOCATION->RCVD_ADD_ACK_COMMIT
lightningd_1       | 2019-12-26T08:30:33.395Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: htlc added LOCAL: local 16775492724 remote 1722276
lightningd_1       | 2019-12-26T08:30:33.395Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: -> local 16775491723 remote 1722276
lightningd_1       | 2019-12-26T08:30:33.395Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: rcvd_commit: HTLC LOCAL 265 = RCVD_ADD_ACK_COMMIT/SENT_ADD_ACK_COMMIT
lightningd_1       | 2019-12-26T08:30:33.395Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Derived key 020b02232ff994631455739c833c2b13a175b414bd77ac4be5f43eadcf4afe94c7 from basepoint 033881f4781bf91126cfd33552c17bcb1b0e61ed8b424fdf6d36fb99e253d42fa6, point 023fbb2cf8b0ae0941e8c1b928dde5613173375c9b178e42eb5531b5853b96d6eb
lightningd_1       | 2019-12-26T08:30:33.395Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Received commit_sig with 0 htlc sigs
lightningd_1       | 2019-12-26T08:30:33.396Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Sending revoke_and_ack
lightningd_1       | 2019-12-26T08:30:33.396Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: htlc 265: RCVD_ADD_ACK_COMMIT->SENT_ADD_ACK_REVOCATION
lightningd_1       | 2019-12-26T08:30:33.396Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: sending_revoke_and_ack: HTLC LOCAL 265 = SENT_ADD_ACK_REVOCATION/RCVD_ADD_ACK_REVOCATION
lightningd_1       | 2019-12-26T08:30:33.396Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Sending master 1021
lightningd_1       | 2019-12-26T08:30:33.396Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-chan#1: got commitsig 531: feerate 4000, 0 added, 0 fulfilled, 0 failed, 1 changed
lightningd_1       | 2019-12-26T08:30:33.396Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-chan#1: HTLC out 265 RCVD_ADD_REVOCATION->RCVD_ADD_ACK_COMMIT
lightningd_1       | 2019-12-26T08:30:33.396Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-chan#1: HTLC out 265 RCVD_ADD_ACK_COMMIT->SENT_ADD_ACK_REVOCATION
lightningd_1       | 2019-12-26T08:30:33.475Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: ... , awaiting 1121
lightningd_1       | 2019-12-26T08:30:33.476Z UNUSUAL lightningd: Unable to estimate ECONOMICAL/4 fee
lightningd_1       | 2019-12-26T08:30:33.480Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Got it!
lightningd_1       | 2019-12-26T08:30:33.481Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: peer_out WIRE_REVOKE_AND_ACK
lightningd_1       | 2019-12-26T08:30:33.491Z UNUSUAL lightningd: Unable to estimate ECONOMICAL/100 fee
lightningd_dest_1  | 2019-12-26T08:30:33.495Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: peer_in WIRE_REVOKE_AND_ACK
lightningd_dest_1  | 2019-12-26T08:30:33.495Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Received revoke_and_ack
lightningd_dest_1  | 2019-12-26T08:30:33.495Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: htlc 265: SENT_ADD_ACK_COMMIT->RCVD_ADD_ACK_REVOCATION
lightningd_dest_1  | 2019-12-26T08:30:33.495Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: rcvd_revoke_and_ack: HTLC REMOTE 265 = RCVD_ADD_ACK_REVOCATION/SENT_ADD_ACK_REVOCATION
lightningd_dest_1  | 2019-12-26T08:30:33.495Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: No commits outstanding after recv revoke_and_ack
lightningd_dest_1  | 2019-12-26T08:30:33.495Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: HTLC 265[REMOTE] => RCVD_ADD_ACK_REVOCATION
lightningd_dest_1  | 2019-12-26T08:30:33.496Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Sending master 1022
lightningd_dest_1  | 2019-12-26T08:30:33.496Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-chan#1: got revoke 530: 1 changed
lightningd_dest_1  | 2019-12-26T08:30:33.496Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-chan#1: HTLC in 265 SENT_ADD_ACK_COMMIT->RCVD_ADD_ACK_REVOCATION
lightningd_dest_1  | 2019-12-26T08:30:33.496Z INFO lightningd: Resolved invoice 'jnso1vLcVGm9QidkrptTwiRndiw' with amount 1001msat in 1 htlcs
lightningd_dest_1  | 2019-12-26T08:30:33.497Z DEBUG lightningd: Command returned result after jcon close
lightningd_dest_1  | 2019-12-26T08:30:33.497Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-chan#1: HTLC in 265 RCVD_ADD_ACK_REVOCATION->SENT_REMOVE_HTLC
lightningd_dest_1  | 2019-12-26T08:30:33.497Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-chan#1: their htlc 265 locked
lightningd_dest_1  | 2019-12-26T08:30:33.514Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: ... , awaiting 1122
lightningd_dest_1  | 2019-12-26T08:30:33.515Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Nope, got 1005 instead
lightningd_dest_1  | 2019-12-26T08:30:33.515Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Got it!
lightningd_1       | 2019-12-26T08:30:33.516Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: peer_in WIRE_UPDATE_FULFILL_HTLC
lightningd_1       | 2019-12-26T08:30:33.516Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: FULFILL:: HTLC LOCAL 265 = RCVD_REMOVE_HTLC/SENT_REMOVE_HTLC FULFILLED
lightningd_dest_1  | 2019-12-26T08:30:33.517Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: revoke_and_ack REMOTE: remote_per_commit = 034d36a05d30e9dc1417b1eb551fd986eaed3302f686571d1e77cbf4439bcaf5ab, old_remote_per_commit = 023fbb2cf8b0ae0941e8c1b928dde5613173375c9b178e42eb5531b5853b96d6eb
lightningd_dest_1  | 2019-12-26T08:30:33.517Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Now dealing with deferred WIRE_CHANNEL_FULFILL_HTLC
lightningd_dest_1  | 2019-12-26T08:30:33.517Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: FULFILL:: HTLC REMOTE 265 = SENT_REMOVE_HTLC/RCVD_REMOVE_HTLC FULFILLED
lightningd_dest_1  | 2019-12-26T08:30:33.518Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: peer_out WIRE_UPDATE_FULFILL_HTLC
lightningd_dest_1  | 2019-12-26T08:30:33.518Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Trying commit
lightningd_dest_1  | 2019-12-26T08:30:33.518Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: htlc 265: SENT_REMOVE_HTLC->SENT_REMOVE_COMMIT
lightningd_dest_1  | 2019-12-26T08:30:33.518Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: htlc added REMOTE: local 1722276 remote 16775491723
lightningd_dest_1  | 2019-12-26T08:30:33.518Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: -> local 1723277 remote 16775491723
lightningd_dest_1  | 2019-12-26T08:30:33.519Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: sending_commit: HTLC REMOTE 265 = SENT_REMOVE_COMMIT/RCVD_REMOVE_COMMIT FULFILLED
lightningd_1       | 2019-12-26T08:30:33.526Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Trying commit
lightningd_1       | 2019-12-26T08:30:33.526Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Can't send commit: nothing to send
lightningd_dest_1  | 2019-12-26T08:30:33.519Z DEBUG hsmd: Client: Received message 19 from client
lightningd_dest_1  | 2019-12-26T08:30:33.519Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Creating commit_sig signature 532 304402203bbb7588ac5480803c58cf504bbe3de913dba13d03e6bc5de5e1bcdfdee7315f02203efe43867ae963245a34ccf52939bf18c94ae8c64edb0c31bc5e27763c4142a201 for tx 0200000001b399e7d4320d1e9591432cd40a39f07e4696d898028d0415436c52ffc293a5b600000000001742ab8002bb060000000000001600142a08b0bea717615a09590719e1f7827b23d7fa5ff3edff0000000000220020b7c81b79cbd2ad1ad0b4211b1bc545bf06e7a5c29c36f073c09b5dde61e607b2d01a1220 wscript 522102305d92049c25c0537cff15899fd6f402c0f5b181a31304a5ce8ef27c117eabf62103fba00a0705ca7f347c579366d13075fe8d4dabd22a9445198be75b566b3d953052ae key 02305d92049c25c0537cff15899fd6f402c0f5b181a31304a5ce8ef27c117eabf6
lightningd_dest_1  | 2019-12-26T08:30:33.519Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Telling master we're about to commit...
lightningd_dest_1  | 2019-12-26T08:30:33.520Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Sending master 1020
lightningd_dest_1  | 2019-12-26T08:30:33.520Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-chan#1: HTLC in 265 SENT_REMOVE_HTLC->SENT_REMOVE_COMMIT
lightningd_1       | 2019-12-26T08:30:33.549Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: peer_in WIRE_COMMITMENT_SIGNED
lightningd_1       | 2019-12-26T08:30:33.549Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Received commit
lightningd_1       | 2019-12-26T08:30:33.549Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: htlc 265: RCVD_REMOVE_HTLC->RCVD_REMOVE_COMMIT
lightningd_1       | 2019-12-26T08:30:33.550Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: htlc added LOCAL: local 16775491723 remote 1722276
lightningd_dest_1  | 2019-12-26T08:30:33.547Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: ... , awaiting 1120
lightningd_dest_1  | 2019-12-26T08:30:33.548Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Got it!
lightningd_dest_1  | 2019-12-26T08:30:33.548Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Sending commit_sig with 0 htlc sigs
lightningd_1       | 2019-12-26T08:30:33.550Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: -> local 16775491723 remote 1723277
lightningd_1       | 2019-12-26T08:30:33.551Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: rcvd_commit: HTLC LOCAL 265 = RCVD_REMOVE_COMMIT/SENT_REMOVE_COMMIT FULFILLED
lightningd_1       | 2019-12-26T08:30:33.553Z DEBUG hsmd: Client: Received message 18 from client
lightningd_1       | 2019-12-26T08:30:33.553Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Derived key 02ef677659fdf418eea245a0fcfff3c1be60bb20521fafcd21a858ae7f4c33ce08 from basepoint 033881f4781bf91126cfd33552c17bcb1b0e61ed8b424fdf6d36fb99e253d42fa6, point 034d36a05d30e9dc1417b1eb551fd986eaed3302f686571d1e77cbf4439bcaf5ab
lightningd_1       | 2019-12-26T08:30:33.553Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Received commit_sig with 0 htlc sigs
lightningd_1       | 2019-12-26T08:30:33.554Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Sending revoke_and_ack
lightningd_1       | 2019-12-26T08:30:33.554Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: htlc 265: RCVD_REMOVE_COMMIT->SENT_REMOVE_REVOCATION
lightningd_1       | 2019-12-26T08:30:33.554Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: sending_revoke_and_ack: HTLC LOCAL 265 = SENT_REMOVE_REVOCATION/RCVD_REMOVE_REVOCATION FULFILLED
lightningd_1       | 2019-12-26T08:30:33.554Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: revoke_and_ack made pending: commit timer
lightningd_1       | 2019-12-26T08:30:33.554Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Sending master 1021
lightningd_1       | 2019-12-26T08:30:33.554Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-chan#1: got commitsig 532: feerate 4000, 0 added, 1 fulfilled, 0 failed, 0 changed
lightningd_1       | 2019-12-26T08:30:33.555Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-chan#1: HTLC out 265 SENT_ADD_ACK_REVOCATION->RCVD_REMOVE_COMMIT
lightningd_1       | 2019-12-26T08:30:33.556Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-chan#1: HTLC out 265 RCVD_REMOVE_COMMIT->SENT_REMOVE_REVOCATION
lightningd_dest_1  | 2019-12-26T08:30:33.552Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: peer_out WIRE_COMMITMENT_SIGNED
lightningd_1       | 2019-12-26T08:30:33.625Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: ... , awaiting 1121
lightningd_dest_1  | 2019-12-26T08:30:33.627Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: peer_in WIRE_REVOKE_AND_ACK
lightningd_dest_1  | 2019-12-26T08:30:33.628Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Received revoke_and_ack
lightningd_dest_1  | 2019-12-26T08:30:33.628Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: htlc 265: SENT_REMOVE_COMMIT->RCVD_REMOVE_REVOCATION
lightningd_dest_1  | 2019-12-26T08:30:33.629Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: rcvd_revoke_and_ack: HTLC REMOTE 265 = RCVD_REMOVE_REVOCATION/SENT_REMOVE_REVOCATION FULFILLED
lightningd_dest_1  | 2019-12-26T08:30:33.629Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Commits outstanding after recv revoke_and_ack
lightningd_dest_1  | 2019-12-26T08:30:33.630Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: HTLC 265[REMOTE] => RCVD_REMOVE_REVOCATION
lightningd_dest_1  | 2019-12-26T08:30:33.630Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Sending master 1022
lightningd_dest_1  | 2019-12-26T08:30:33.630Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-chan#1: got revoke 531: 1 changed
lightningd_dest_1  | 2019-12-26T08:30:33.631Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-chan#1: HTLC in 265 SENT_REMOVE_COMMIT->RCVD_REMOVE_REVOCATION
lightningd_1       | 2019-12-26T08:30:33.626Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Got it!
lightningd_1       | 2019-12-26T08:30:33.631Z DEBUG hsmd: Client: Received message 19 from client
lightningd_1       | 2019-12-26T08:30:33.631Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: peer_out WIRE_REVOKE_AND_ACK
lightningd_1       | 2019-12-26T08:30:33.632Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Trying commit
lightningd_1       | 2019-12-26T08:30:33.632Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: htlc 265: SENT_REMOVE_REVOCATION->SENT_REMOVE_ACK_COMMIT
lightningd_1       | 2019-12-26T08:30:33.632Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: htlc added REMOTE: local 16775491723 remote 1722276
lightningd_1       | 2019-12-26T08:30:33.633Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: -> local 16775491723 remote 1723277
lightningd_1       | 2019-12-26T08:30:33.633Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: sending_commit: HTLC LOCAL 265 = SENT_REMOVE_ACK_COMMIT/RCVD_REMOVE_ACK_COMMIT FULFILLED
lightningd_1       | 2019-12-26T08:30:33.633Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Creating commit_sig signature 532 304402201fdd3f2689a0322339d9454ff7065634a88e4c5c98e760ed008d148c41bb5a4102207b3054675edd6f80c52ed0c6d9febdbccc9d8b7658ba890c2307aa2d9abe2db001 for tx 0200000001b399e7d4320d1e9591432cd40a39f07e4696d898028d0415436c52ffc293a5b600000000001742ab8002bb060000000000002200203d35d0ace8d624708f144f7d062da86d73fc51cc6bc64ece245855574d84ee5af3edff000000000016001492902e4d84471c9e0e83fef2ef72fe3210faf16cd01a1220 wscript 522102305d92049c25c0537cff15899fd6f402c0f5b181a31304a5ce8ef27c117eabf62103fba00a0705ca7f347c579366d13075fe8d4dabd22a9445198be75b566b3d953052ae key 03fba00a0705ca7f347c579366d13075fe8d4dabd22a9445198be75b566b3d9530
lightningd_1       | 2019-12-26T08:30:33.644Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Telling master we're about to commit...
lightningd_1       | 2019-12-26T08:30:33.644Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Sending master 1020
lightningd_1       | 2019-12-26T08:30:33.645Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-chan#1: HTLC out 265 SENT_REMOVE_REVOCATION->SENT_REMOVE_ACK_COMMIT
lightningd_dest_1  | 2019-12-26T08:30:33.690Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: ... , awaiting 1122
lightningd_dest_1  | 2019-12-26T08:30:33.690Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Got it!
lightningd_dest_1  | 2019-12-26T08:30:33.691Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: revoke_and_ack REMOTE: remote_per_commit = 036d97821a28943a60e8b57978f0b139491caaacdf848a71ebe9db03966ed5bc6a, old_remote_per_commit = 034d36a05d30e9dc1417b1eb551fd986eaed3302f686571d1e77cbf4439bcaf5ab
lightningd_dest_1  | 2019-12-26T08:30:33.691Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Trying commit
lightningd_dest_1  | 2019-12-26T08:30:33.691Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Can't send commit: nothing to send
lightningd_dest_1  | 2019-12-26T08:30:33.691Z DEBUG lightningd: exposeprivate = NULL
lightningd_dest_1  | 2019-12-26T08:30:33.692Z DEBUG gossipd: REPLY WIRE_GOSSIP_GET_INCOMING_CHANNELS_REPLY with 0 fds
lightningd_dest_1  | 2019-12-26T08:30:33.693Z UNUSUAL lightningd: invoice: insufficient incoming capacity for 10000msat
lightningd_1       | 2019-12-26T08:30:33.705Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: ... , awaiting 1120
lightningd_1       | 2019-12-26T08:30:33.706Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Got it!
lightningd_1       | 2019-12-26T08:30:33.706Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Sending commit_sig with 0 htlc sigs
lightningd_1       | 2019-12-26T08:30:33.706Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: peer_out WIRE_COMMITMENT_SIGNED
lightningd_dest_1  | 2019-12-26T08:30:33.724Z DEBUG hsmd: Client: Received message 8 from client
lightningd_dest_1  | 2019-12-26T08:30:33.725Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: peer_in WIRE_COMMITMENT_SIGNED
lightningd_dest_1  | 2019-12-26T08:30:33.725Z DEBUG hsmd: Client: Received message 18 from client
lightningd_dest_1  | 2019-12-26T08:30:33.725Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Received commit
lightningd_dest_1  | 2019-12-26T08:30:33.725Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: htlc 265: RCVD_REMOVE_REVOCATION->RCVD_REMOVE_ACK_COMMIT
lightningd_dest_1  | 2019-12-26T08:30:33.725Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: htlc added LOCAL: local 1722276 remote 16775491723
lightningd_dest_1  | 2019-12-26T08:30:33.725Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: -> local 1723277 remote 16775491723
lightningd_dest_1  | 2019-12-26T08:30:33.726Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: rcvd_commit: HTLC REMOTE 265 = RCVD_REMOVE_ACK_COMMIT/SENT_REMOVE_ACK_COMMIT FULFILLED
lightningd_dest_1  | 2019-12-26T08:30:33.726Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Feerates are 4000/4000
lightningd_dest_1  | 2019-12-26T08:30:33.726Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: We need 170668sat at feerate 4000 for 0 untrimmed htlcs: we have 16775491723msat/16775491723msat
lightningd_dest_1  | 2019-12-26T08:30:33.726Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Derived key 0304562a6d0bf4ecbcdc984f645d8dc689b52562c8b18f99c35b10050348e18b3c from basepoint 023f414bc16cd032e1551c6bbfae09ed59d42668fb5c9b566b89d29b5ba001c9a3, point 036c4846c194b1f4598b2196cd791068c1a25e5a3daaf349ae60e50e2013a29408
lightningd_dest_1  | 2019-12-26T08:30:33.726Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Received commit_sig with 0 htlc sigs
lightningd_dest_1  | 2019-12-26T08:30:33.726Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Sending revoke_and_ack
lightningd_dest_1  | 2019-12-26T08:30:33.727Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: htlc 265: RCVD_REMOVE_ACK_COMMIT->SENT_REMOVE_ACK_REVOCATION
lightningd_dest_1  | 2019-12-26T08:30:33.727Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: sending_revoke_and_ack: HTLC REMOTE 265 = SENT_REMOVE_ACK_REVOCATION/RCVD_REMOVE_ACK_REVOCATION FULFILLED
lightningd_dest_1  | 2019-12-26T08:30:33.727Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Sending master 1021
lightningd_dest_1  | 2019-12-26T08:30:33.727Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-chan#1: got commitsig 532: feerate 4000, 0 added, 0 fulfilled, 0 failed, 1 changed
lightningd_dest_1  | 2019-12-26T08:30:33.727Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-chan#1: HTLC in 265 RCVD_REMOVE_REVOCATION->RCVD_REMOVE_ACK_COMMIT
lightningd_dest_1  | 2019-12-26T08:30:33.727Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-chan#1: HTLC in 265 RCVD_REMOVE_ACK_COMMIT->SENT_REMOVE_ACK_REVOCATION
lightningd_dest_1  | 2019-12-26T08:30:33.727Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-chan#1: Removing in HTLC 265 state SENT_REMOVE_ACK_REVOCATION FULFILLED
lightningd_dest_1  | 2019-12-26T08:30:33.727Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-chan#1: Balance 1722276msat -> 1723277msat
lightningd_dest_1  | 2019-12-26T08:30:33.770Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: ... , awaiting 1121
lightningd_1       | 2019-12-26T08:30:33.773Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: peer_in WIRE_REVOKE_AND_ACK
lightningd_dest_1  | 2019-12-26T08:30:33.773Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Got it!
lightningd_1       | 2019-12-26T08:30:33.774Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Received revoke_and_ack
lightningd_1       | 2019-12-26T08:30:33.774Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: htlc 265: SENT_REMOVE_ACK_COMMIT->RCVD_REMOVE_ACK_REVOCATION
lightningd_1       | 2019-12-26T08:30:33.774Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: rcvd_revoke_and_ack: HTLC LOCAL 265 = RCVD_REMOVE_ACK_REVOCATION/SENT_REMOVE_ACK_REVOCATION FULFILLED
lightningd_1       | 2019-12-26T08:30:33.774Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: No commits outstanding after recv revoke_and_ack
lightningd_dest_1  | 2019-12-26T08:30:33.774Z DEBUG lightningd: exposeprivate = NULL
lightningd_dest_1  | 2019-12-26T08:30:33.776Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: peer_out WIRE_REVOKE_AND_ACK
lightningd_1       | 2019-12-26T08:30:33.775Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: HTLC 265[LOCAL] => RCVD_REMOVE_ACK_REVOCATION
lightningd_1       | 2019-12-26T08:30:33.775Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Sending master 1022
lightningd_1       | 2019-12-26T08:30:33.775Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-chan#1: got revoke 531: 1 changed
lightningd_1       | 2019-12-26T08:30:33.775Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-chan#1: HTLC out 265 SENT_REMOVE_ACK_COMMIT->RCVD_REMOVE_ACK_REVOCATION
lightningd_1       | 2019-12-26T08:30:33.776Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-chan#1: Removing out HTLC 265 state RCVD_REMOVE_ACK_REVOCATION FULFILLED
lightningd_1       | 2019-12-26T08:30:33.776Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-chan#1: Balance 16775492724msat -> 16775491723msat
lightningd_dest_1  | 2019-12-26T08:30:33.777Z DEBUG gossipd: REPLY WIRE_GOSSIP_GET_INCOMING_CHANNELS_REPLY with 0 fds
lightningd_dest_1  | 2019-12-26T08:30:33.778Z UNUSUAL lightningd: invoice: insufficient incoming capacity for 10000msat
lightningd_1       | 2019-12-26T08:30:33.806Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: ... , awaiting 1122
lightningd_1       | 2019-12-26T08:30:33.807Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Got it!
lightningd_1       | 2019-12-26T08:30:33.807Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: revoke_and_ack LOCAL: remote_per_commit = 02342b38cc908f421180444937d12a07483b072113ec9b1b64df47862d12fa39b4, old_remote_per_commit = 036c4846c194b1f4598b2196cd791068c1a25e5a3daaf349ae60e50e2013a29408
lightningd_1       | 2019-12-26T08:30:33.807Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Trying commit
lightningd_1       | 2019-12-26T08:30:33.807Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Can't send commit: nothing to send
lightningd_dest_1  | 2019-12-26T08:30:33.819Z DEBUG hsmd: Client: Received message 8 from client
lightningd_1       | 2019-12-26T08:30:33.934Z DEBUG gossipd: REPLY WIRE_GOSSIP_GETCHANNELS_REPLY with 0 fds
lightningd_1       | 2019-12-26T08:30:33.938Z DEBUG gossipd: REPLY WIRE_GOSSIP_GETCHANNELS_REPLY with 0 fds
lightningd_1       | 2019-12-26T08:30:33.943Z DEBUG gossipd: Trying to find a route from (me) to 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf for 10002msat
lightningd_1       | 2019-12-26T08:30:33.943Z DEBUG gossipd: REPLY WIRE_GOSSIP_GETROUTE_REPLY with 0 fds
lightningd_1       | 2019-12-26T08:30:33.944Z INFO lightningd: Sending 10002msat over 1 hops to deliver 10002msat
lightningd_dest_1  | 2019-12-26T08:30:33.946Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: peer_in WIRE_UPDATE_ADD_HTLC
lightningd_dest_1  | 2019-12-26T08:30:33.946Z DEBUG hsmd: Client: Received message 1 from client
lightningd_dest_1  | 2019-12-26T08:30:33.946Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: NEW:: HTLC REMOTE 266 = RCVD_ADD_HTLC/SENT_ADD_HTLC 
lightningd_1       | 2019-12-26T08:30:33.945Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: NEW:: HTLC LOCAL 266 = SENT_ADD_HTLC/RCVD_ADD_HTLC
lightningd_1       | 2019-12-26T08:30:33.946Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Adding HTLC 266 amount=10002msat cltv=316 gave CHANNEL_ERR_ADD_OK
lightningd_1       | 2019-12-26T08:30:33.947Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: peer_out WIRE_UPDATE_ADD_HTLC
lightningd_1       | 2019-12-26T08:30:33.947Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: REPLY WIRE_CHANNEL_OFFER_HTLC_REPLY with 0 fds
lightningd_1       | 2019-12-26T08:30:33.957Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Trying commit
lightningd_1       | 2019-12-26T08:30:33.957Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: htlc 266: SENT_ADD_HTLC->SENT_ADD_COMMIT
lightningd_1       | 2019-12-26T08:30:33.957Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: htlc added REMOTE: local 16775491723 remote 1723277
lightningd_1       | 2019-12-26T08:30:33.957Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: -> local 16775481721 remote 1723277
lightningd_1       | 2019-12-26T08:30:33.957Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: sending_commit: HTLC LOCAL 266 = SENT_ADD_COMMIT/RCVD_ADD_COMMIT
lightningd_1       | 2019-12-26T08:30:33.959Z DEBUG hsmd: Client: Received message 19 from client
lightningd_1       | 2019-12-26T08:30:33.959Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Creating commit_sig signature 533 30440220768c87f1666498823a19a8a54995c6761586eba70db47be95d94c2bce58411a50220088391e2c69765633dc3d4e6aa7871f24df8ef25d2d94074bf512a7c9af4407701 for tx 0200000001b399e7d4320d1e9591432cd40a39f07e4696d898028d0415436c52ffc293a5b600000000001742ab8002bb06000000000000220020536919b54659f9958b11640b40dce48c9962a838520ce8ad5fb083ce712dd22ce9edff000000000016001492902e4d84471c9e0e83fef2ef72fe3210faf16cd11a1220 wscript 522102305d92049c25c0537cff15899fd6f402c0f5b181a31304a5ce8ef27c117eabf62103fba00a0705ca7f347c579366d13075fe8d4dabd22a9445198be75b566b3d953052ae key 03fba00a0705ca7f347c579366d13075fe8d4dabd22a9445198be75b566b3d9530
lightningd_1       | 2019-12-26T08:30:33.959Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Telling master we're about to commit...
lightningd_1       | 2019-12-26T08:30:33.959Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Sending master 1020
lightningd_1       | 2019-12-26T08:30:33.960Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-chan#1: HTLC out 266 SENT_ADD_HTLC->SENT_ADD_COMMIT
lightningd_1       | 2019-12-26T08:30:33.991Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: ... , awaiting 1120
lightningd_1       | 2019-12-26T08:30:33.991Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Got it!
lightningd_dest_1  | 2019-12-26T08:30:33.993Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: peer_in WIRE_COMMITMENT_SIGNED
lightningd_dest_1  | 2019-12-26T08:30:33.993Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Received commit
lightningd_dest_1  | 2019-12-26T08:30:33.994Z DEBUG hsmd: Client: Received message 18 from client
lightningd_1       | 2019-12-26T08:30:33.992Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Sending commit_sig with 0 htlc sigs
lightningd_1       | 2019-12-26T08:30:33.992Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: peer_out WIRE_COMMITMENT_SIGNED
lightningd_1       | 2019-12-26T08:30:33.993Z DEBUG lightningd: Payment part 0/0 status 0
lightningd_dest_1  | 2019-12-26T08:30:33.994Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: htlc 266: RCVD_ADD_HTLC->RCVD_ADD_COMMIT
lightningd_dest_1  | 2019-12-26T08:30:33.994Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: htlc added LOCAL: local 1723277 remote 16775491723
lightningd_dest_1  | 2019-12-26T08:30:33.995Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: -> local 1723277 remote 16775481721
lightningd_dest_1  | 2019-12-26T08:30:33.995Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: rcvd_commit: HTLC REMOTE 266 = RCVD_ADD_COMMIT/SENT_ADD_COMMIT
lightningd_dest_1  | 2019-12-26T08:30:33.995Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Feerates are 4000/4000
lightningd_dest_1  | 2019-12-26T08:30:33.995Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: We need 170668sat at feerate 4000 for 0 untrimmed htlcs: we have 16775481721msat/16775491723msat
lightningd_dest_1  | 2019-12-26T08:30:33.995Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Derived key 0373b8aa7f3713b953154564781374c06a2f395d257d01a41bdc1d9ffdf406fdf4 from basepoint 023f414bc16cd032e1551c6bbfae09ed59d42668fb5c9b566b89d29b5ba001c9a3, point 02342b38cc908f421180444937d12a07483b072113ec9b1b64df47862d12fa39b4
lightningd_dest_1  | 2019-12-26T08:30:33.996Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Received commit_sig with 0 htlc sigs
lightningd_dest_1  | 2019-12-26T08:30:33.996Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Sending revoke_and_ack
lightningd_dest_1  | 2019-12-26T08:30:33.996Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: htlc 266: RCVD_ADD_COMMIT->SENT_ADD_REVOCATION
lightningd_dest_1  | 2019-12-26T08:30:33.996Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: sending_revoke_and_ack: HTLC REMOTE 266 = SENT_ADD_REVOCATION/RCVD_ADD_REVOCATION
lightningd_dest_1  | 2019-12-26T08:30:33.996Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: revoke_and_ack made pending: commit timer
lightningd_dest_1  | 2019-12-26T08:30:33.997Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Sending master 1021
lightningd_dest_1  | 2019-12-26T08:30:33.997Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-chan#1: got commitsig 533: feerate 4000, 1 added, 0 fulfilled, 0 failed, 0 changed
lightningd_dest_1  | 2019-12-26T08:30:33.998Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-chan#1: Adding their HTLC 266
lightningd_dest_1  | 2019-12-26T08:30:33.998Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-chan#1: HTLC in 266 RCVD_ADD_COMMIT->SENT_ADD_REVOCATION
lightningd_dest_1  | 2019-12-26T08:30:34.035Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: ... , awaiting 1121
lightningd_dest_1  | 2019-12-26T08:30:34.036Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Got it!
lightningd_dest_1  | 2019-12-26T08:30:34.036Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: peer_out WIRE_REVOKE_AND_ACK
lightningd_dest_1  | 2019-12-26T08:30:34.037Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Trying commit
lightningd_dest_1  | 2019-12-26T08:30:34.037Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: htlc 266: SENT_ADD_REVOCATION->SENT_ADD_ACK_COMMIT
lightningd_dest_1  | 2019-12-26T08:30:34.037Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: htlc added REMOTE: local 1723277 remote 16775491723
lightningd_dest_1  | 2019-12-26T08:30:34.037Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: -> local 1723277 remote 16775481721
lightningd_dest_1  | 2019-12-26T08:30:34.037Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: sending_commit: HTLC REMOTE 266 = SENT_ADD_ACK_COMMIT/RCVD_ADD_ACK_COMMIT
lightningd_dest_1  | 2019-12-26T08:30:34.038Z DEBUG hsmd: Client: Received message 19 from client
lightningd_dest_1  | 2019-12-26T08:30:34.038Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Creating commit_sig signature 533 3044022013a943446c0aa5de4ac68d3bc5c6a2929200b5be422261ab5309878328855435022039fd382bc88e1c8dc34d17c6352459758390e3a469576879933e477140a2e62001 for tx 0200000001b399e7d4320d1e9591432cd40a39f07e4696d898028d0415436c52ffc293a5b600000000001742ab8002bb060000000000001600142a08b0bea717615a09590719e1f7827b23d7fa5fe9edff00000000002200203ab57015121b10dd2904d7f1229807b585ba1046be5e8b1665a4d4288d794b54d11a1220 wscript 522102305d92049c25c0537cff15899fd6f402c0f5b181a31304a5ce8ef27c117eabf62103fba00a0705ca7f347c579366d13075fe8d4dabd22a9445198be75b566b3d953052ae key 02305d92049c25c0537cff15899fd6f402c0f5b181a31304a5ce8ef27c117eabf6
lightningd_1       | 2019-12-26T08:30:34.042Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: peer_in WIRE_REVOKE_AND_ACK
lightningd_1       | 2019-12-26T08:30:34.042Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Received revoke_and_ack
lightningd_1       | 2019-12-26T08:30:34.042Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: htlc 266: SENT_ADD_COMMIT->RCVD_ADD_REVOCATION
lightningd_1       | 2019-12-26T08:30:34.042Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: rcvd_revoke_and_ack: HTLC LOCAL 266 = RCVD_ADD_REVOCATION/SENT_ADD_REVOCATION
lightningd_1       | 2019-12-26T08:30:34.043Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Commits outstanding after recv revoke_and_ack
lightningd_1       | 2019-12-26T08:30:34.043Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: HTLC 266[LOCAL] => RCVD_ADD_REVOCATION
lightningd_1       | 2019-12-26T08:30:34.043Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Sending master 1022
lightningd_1       | 2019-12-26T08:30:34.043Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-chan#1: got revoke 532: 1 changed
lightningd_1       | 2019-12-26T08:30:34.043Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-chan#1: HTLC out 266 SENT_ADD_COMMIT->RCVD_ADD_REVOCATION
lightningd_dest_1  | 2019-12-26T08:30:34.043Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Telling master we're about to commit...
lightningd_dest_1  | 2019-12-26T08:30:34.044Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Sending master 1020
lightningd_dest_1  | 2019-12-26T08:30:34.044Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-chan#1: HTLC in 266 SENT_ADD_REVOCATION->SENT_ADD_ACK_COMMIT
lightningd_1       | 2019-12-26T08:30:34.109Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: ... , awaiting 1122
lightningd_1       | 2019-12-26T08:30:34.110Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Got it!
lightningd_1       | 2019-12-26T08:30:34.111Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: revoke_and_ack LOCAL: remote_per_commit = 02aed89251e4e237dc36c131cdfa3a5bda0bd9f8de05103ed13a69e3c87762f324, old_remote_per_commit = 02342b38cc908f421180444937d12a07483b072113ec9b1b64df47862d12fa39b4
lightningd_1       | 2019-12-26T08:30:34.111Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Trying commit
lightningd_1       | 2019-12-26T08:30:34.111Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Can't send commit: nothing to send
lightningd_dest_1  | 2019-12-26T08:30:34.116Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: ... , awaiting 1120
lightningd_dest_1  | 2019-12-26T08:30:34.122Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Got it!
lightningd_dest_1  | 2019-12-26T08:30:34.122Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Sending commit_sig with 0 htlc sigs
lightningd_1       | 2019-12-26T08:30:34.118Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: peer_in WIRE_COMMITMENT_SIGNED
lightningd_1       | 2019-12-26T08:30:34.120Z DEBUG hsmd: Client: Received message 18 from client
lightningd_1       | 2019-12-26T08:30:34.120Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Received commit
lightningd_1       | 2019-12-26T08:30:34.120Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: htlc 266: RCVD_ADD_REVOCATION->RCVD_ADD_ACK_COMMIT
lightningd_1       | 2019-12-26T08:30:34.121Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: htlc added LOCAL: local 16775491723 remote 1723277
lightningd_1       | 2019-12-26T08:30:34.121Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: -> local 16775481721 remote 1723277
lightningd_1       | 2019-12-26T08:30:34.122Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: rcvd_commit: HTLC LOCAL 266 = RCVD_ADD_ACK_COMMIT/SENT_ADD_ACK_COMMIT
lightningd_dest_1  | 2019-12-26T08:30:34.135Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: peer_out WIRE_COMMITMENT_SIGNED
lightningd_1       | 2019-12-26T08:30:34.123Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Derived key 02674008dc85cad89b35df4a13ee762bad83a861776a841168b3b9552e086ff6a7 from basepoint 033881f4781bf91126cfd33552c17bcb1b0e61ed8b424fdf6d36fb99e253d42fa6, point 036d97821a28943a60e8b57978f0b139491caaacdf848a71ebe9db03966ed5bc6a
lightningd_1       | 2019-12-26T08:30:34.137Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Received commit_sig with 0 htlc sigs
lightningd_1       | 2019-12-26T08:30:34.138Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Sending revoke_and_ack
lightningd_1       | 2019-12-26T08:30:34.138Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: htlc 266: RCVD_ADD_ACK_COMMIT->SENT_ADD_ACK_REVOCATION
lightningd_1       | 2019-12-26T08:30:34.138Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: sending_revoke_and_ack: HTLC LOCAL 266 = SENT_ADD_ACK_REVOCATION/RCVD_ADD_ACK_REVOCATION
lightningd_1       | 2019-12-26T08:30:34.139Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Sending master 1021
lightningd_1       | 2019-12-26T08:30:34.139Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-chan#1: got commitsig 533: feerate 4000, 0 added, 0 fulfilled, 0 failed, 1 changed
lightningd_1       | 2019-12-26T08:30:34.139Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-chan#1: HTLC out 266 RCVD_ADD_REVOCATION->RCVD_ADD_ACK_COMMIT
lightningd_1       | 2019-12-26T08:30:34.140Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-chan#1: HTLC out 266 RCVD_ADD_ACK_COMMIT->SENT_ADD_ACK_REVOCATION
lightningd_1       | 2019-12-26T08:30:34.180Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: ... , awaiting 1121
lightningd_1       | 2019-12-26T08:30:34.180Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Got it!
lightningd_1       | 2019-12-26T08:30:34.180Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: peer_out WIRE_REVOKE_AND_ACK
lightningd_dest_1  | 2019-12-26T08:30:34.181Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: peer_in WIRE_REVOKE_AND_ACK
lightningd_dest_1  | 2019-12-26T08:30:34.181Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Received revoke_and_ack
lightningd_dest_1  | 2019-12-26T08:30:34.181Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: htlc 266: SENT_ADD_ACK_COMMIT->RCVD_ADD_ACK_REVOCATION
lightningd_dest_1  | 2019-12-26T08:30:34.181Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: rcvd_revoke_and_ack: HTLC REMOTE 266 = RCVD_ADD_ACK_REVOCATION/SENT_ADD_ACK_REVOCATION
lightningd_dest_1  | 2019-12-26T08:30:34.182Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: No commits outstanding after recv revoke_and_ack
lightningd_dest_1  | 2019-12-26T08:30:34.182Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: HTLC 266[REMOTE] => RCVD_ADD_ACK_REVOCATION
lightningd_dest_1  | 2019-12-26T08:30:34.182Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Sending master 1022
lightningd_dest_1  | 2019-12-26T08:30:34.182Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-chan#1: got revoke 532: 1 changed
lightningd_dest_1  | 2019-12-26T08:30:34.182Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-chan#1: HTLC in 266 SENT_ADD_ACK_COMMIT->RCVD_ADD_ACK_REVOCATION
lightningd_dest_1  | 2019-12-26T08:30:34.183Z INFO lightningd: Resolved invoice 'p6isGS1auj2ZRbqvg9Q3QCMPMTb' with amount 10002msat in 1 htlcs
lightningd_dest_1  | 2019-12-26T08:30:34.183Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-chan#1: HTLC in 266 RCVD_ADD_ACK_REVOCATION->SENT_REMOVE_HTLC
lightningd_dest_1  | 2019-12-26T08:30:34.184Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-chan#1: their htlc 266 locked
lightningd_1       | 2019-12-26T08:30:34.207Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: peer_in WIRE_UPDATE_FULFILL_HTLC
lightningd_1       | 2019-12-26T08:30:34.207Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: FULFILL:: HTLC LOCAL 266 = RCVD_REMOVE_HTLC/SENT_REMOVE_HTLC FULFILLED
lightningd_dest_1  | 2019-12-26T08:30:34.202Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: ... , awaiting 1122
lightningd_dest_1  | 2019-12-26T08:30:34.202Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Nope, got 1005 instead
lightningd_dest_1  | 2019-12-26T08:30:34.203Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Got it!
lightningd_dest_1  | 2019-12-26T08:30:34.203Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: revoke_and_ack REMOTE: remote_per_commit = 029f77af367ccd7a3017ee345706e36dd224d9827f917775436770f4a3028d6dcd, old_remote_per_commit = 036d97821a28943a60e8b57978f0b139491caaacdf848a71ebe9db03966ed5bc6a
lightningd_dest_1  | 2019-12-26T08:30:34.203Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Now dealing with deferred WIRE_CHANNEL_FULFILL_HTLC
lightningd_dest_1  | 2019-12-26T08:30:34.203Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: FULFILL:: HTLC REMOTE 266 = SENT_REMOVE_HTLC/RCVD_REMOVE_HTLC FULFILLED
lightningd_dest_1  | 2019-12-26T08:30:34.203Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: peer_out WIRE_UPDATE_FULFILL_HTLC
lightningd_dest_1  | 2019-12-26T08:30:34.205Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Trying commit
lightningd_dest_1  | 2019-12-26T08:30:34.206Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: htlc 266: SENT_REMOVE_HTLC->SENT_REMOVE_COMMIT
lightningd_dest_1  | 2019-12-26T08:30:34.206Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: htlc added REMOTE: local 1723277 remote 16775481721
lightningd_dest_1  | 2019-12-26T08:30:34.206Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: -> local 1733279 remote 16775481721
lightningd_dest_1  | 2019-12-26T08:30:34.206Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: sending_commit: HTLC REMOTE 266 = SENT_REMOVE_COMMIT/RCVD_REMOVE_COMMIT FULFILLED
lightningd_dest_1  | 2019-12-26T08:30:34.207Z DEBUG hsmd: Client: Received message 19 from client
lightningd_dest_1  | 2019-12-26T08:30:34.208Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Creating commit_sig signature 534 30440220101dee669420a729ff2bf082653bcd8eecf8e6cba946b15d1017f81ffe1a3c010220349619c5e8fc618789654ebe6b13fd27e72ac0403f8f4acf2aee26d7faf6a22901 for tx 0200000001b399e7d4320d1e9591432cd40a39f07e4696d898028d0415436c52ffc293a5b600000000001742ab8002c5060000000000001600142a08b0bea717615a09590719e1f7827b23d7fa5fe9edff00000000002200206d04607d9a7fddd7d6a574cb69a9cca7f6fd80be26ec14051e9c64ecb1b62f72d21a1220 wscript 522102305d92049c25c0537cff15899fd6f402c0f5b181a31304a5ce8ef27c117eabf62103fba00a0705ca7f347c579366d13075fe8d4dabd22a9445198be75b566b3d953052ae key 02305d92049c25c0537cff15899fd6f402c0f5b181a31304a5ce8ef27c117eabf6
lightningd_dest_1  | 2019-12-26T08:30:34.208Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Telling master we're about to commit...
lightningd_dest_1  | 2019-12-26T08:30:34.209Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Sending master 1020
lightningd_dest_1  | 2019-12-26T08:30:34.209Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-chan#1: HTLC in 266 SENT_REMOVE_HTLC->SENT_REMOVE_COMMIT
lightningd_1       | 2019-12-26T08:30:34.216Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Trying commit
lightningd_1       | 2019-12-26T08:30:34.216Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Can't send commit: nothing to send
lightningd_1       | 2019-12-26T08:30:34.270Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: peer_in WIRE_COMMITMENT_SIGNED
lightningd_1       | 2019-12-26T08:30:34.270Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Received commit
lightningd_1       | 2019-12-26T08:30:34.271Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: htlc 266: RCVD_REMOVE_HTLC->RCVD_REMOVE_COMMIT
lightningd_1       | 2019-12-26T08:30:34.271Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: htlc added LOCAL: local 16775481721 remote 1723277
lightningd_1       | 2019-12-26T08:30:34.271Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: -> local 16775481721 remote 1733279
lightningd_1       | 2019-12-26T08:30:34.272Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: rcvd_commit: HTLC LOCAL 266 = RCVD_REMOVE_COMMIT/SENT_REMOVE_COMMIT FULFILLED
lightningd_1       | 2019-12-26T08:30:34.272Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Derived key 03f8a1bff7c9331b940c2e9f361f679e6668801d07c52d6383bb2da1cb63614b96 from basepoint 033881f4781bf91126cfd33552c17bcb1b0e61ed8b424fdf6d36fb99e253d42fa6, point 029f77af367ccd7a3017ee345706e36dd224d9827f917775436770f4a3028d6dcd
lightningd_1       | 2019-12-26T08:30:34.272Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Received commit_sig with 0 htlc sigs
lightningd_dest_1  | 2019-12-26T08:30:34.269Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: ... , awaiting 1120
lightningd_dest_1  | 2019-12-26T08:30:34.269Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Got it!
lightningd_dest_1  | 2019-12-26T08:30:34.270Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Sending commit_sig with 0 htlc sigs
lightningd_dest_1  | 2019-12-26T08:30:34.270Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: peer_out WIRE_COMMITMENT_SIGNED
lightningd_dest_1  | 2019-12-26T08:30:34.286Z UNUSUAL lightningd: Unable to estimate CONSERVATIVE/2 fee
lightningd_1       | 2019-12-26T08:30:34.274Z DEBUG hsmd: Client: Received message 18 from client
lightningd_1       | 2019-12-26T08:30:34.274Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Sending revoke_and_ack
lightningd_1       | 2019-12-26T08:30:34.274Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: htlc 266: RCVD_REMOVE_COMMIT->SENT_REMOVE_REVOCATION
lightningd_1       | 2019-12-26T08:30:34.274Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: sending_revoke_and_ack: HTLC LOCAL 266 = SENT_REMOVE_REVOCATION/RCVD_REMOVE_REVOCATION FULFILLED
lightningd_1       | 2019-12-26T08:30:34.275Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: revoke_and_ack made pending: commit timer
lightningd_1       | 2019-12-26T08:30:34.275Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Sending master 1021
lightningd_1       | 2019-12-26T08:30:34.275Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-chan#1: got commitsig 534: feerate 4000, 0 added, 1 fulfilled, 0 failed, 0 changed
lightningd_1       | 2019-12-26T08:30:34.275Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-chan#1: HTLC out 266 SENT_ADD_ACK_REVOCATION->RCVD_REMOVE_COMMIT
lightningd_1       | 2019-12-26T08:30:34.276Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-chan#1: HTLC out 266 RCVD_REMOVE_COMMIT->SENT_REMOVE_REVOCATION
lightningd_dest_1  | 2019-12-26T08:30:34.318Z UNUSUAL lightningd: Unable to estimate ECONOMICAL/4 fee
lightningd_dest_1  | 2019-12-26T08:30:34.338Z UNUSUAL lightningd: Unable to estimate ECONOMICAL/100 fee
lightningd_1       | 2019-12-26T08:30:34.363Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: ... , awaiting 1121
lightningd_1       | 2019-12-26T08:30:34.366Z DEBUG hsmd: Client: Received message 19 from client
lightningd_1       | 2019-12-26T08:30:34.366Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Got it!
lightningd_1       | 2019-12-26T08:30:34.367Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: peer_out WIRE_REVOKE_AND_ACK
lightningd_1       | 2019-12-26T08:30:34.367Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Trying commit
lightningd_1       | 2019-12-26T08:30:34.368Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: htlc 266: SENT_REMOVE_REVOCATION->SENT_REMOVE_ACK_COMMIT
lightningd_1       | 2019-12-26T08:30:34.368Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: htlc added REMOTE: local 16775481721 remote 1723277
lightningd_1       | 2019-12-26T08:30:34.369Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: -> local 16775481721 remote 1733279
lightningd_1       | 2019-12-26T08:30:34.370Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: sending_commit: HTLC LOCAL 266 = SENT_REMOVE_ACK_COMMIT/RCVD_REMOVE_ACK_COMMIT FULFILLED
lightningd_dest_1  | 2019-12-26T08:30:34.383Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: peer_in WIRE_REVOKE_AND_ACK
lightningd_dest_1  | 2019-12-26T08:30:34.383Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Received revoke_and_ack
lightningd_dest_1  | 2019-12-26T08:30:34.384Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: htlc 266: SENT_REMOVE_COMMIT->RCVD_REMOVE_REVOCATION
lightningd_dest_1  | 2019-12-26T08:30:34.384Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: rcvd_revoke_and_ack: HTLC REMOTE 266 = RCVD_REMOVE_REVOCATION/SENT_REMOVE_REVOCATION FULFILLED
lightningd_dest_1  | 2019-12-26T08:30:34.384Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Commits outstanding after recv revoke_and_ack
lightningd_dest_1  | 2019-12-26T08:30:34.385Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: HTLC 266[REMOTE] => RCVD_REMOVE_REVOCATION
lightningd_dest_1  | 2019-12-26T08:30:34.385Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Sending master 1022
lightningd_dest_1  | 2019-12-26T08:30:34.385Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-chan#1: got revoke 533: 1 changed
lightningd_dest_1  | 2019-12-26T08:30:34.386Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-chan#1: HTLC in 266 SENT_REMOVE_COMMIT->RCVD_REMOVE_REVOCATION
lightningd_1       | 2019-12-26T08:30:34.388Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Creating commit_sig signature 534 3044022068dec79084716051f0aa19c1310f68ddfad798c3c4d8310415da62b9ebdc306102200d032ec811a78634ec6ea362a1bad7084c899bba46bfcc8d95b80c70d6fb90cf01 for tx 0200000001b399e7d4320d1e9591432cd40a39f07e4696d898028d0415436c52ffc293a5b600000000001742ab8002c5060000000000002200209e2aae248b24a46183c07f64db09d097398e3faa539a5dbdae4be9cfe14b6650e9edff000000000016001492902e4d84471c9e0e83fef2ef72fe3210faf16cd21a1220 wscript 522102305d92049c25c0537cff15899fd6f402c0f5b181a31304a5ce8ef27c117eabf62103fba00a0705ca7f347c579366d13075fe8d4dabd22a9445198be75b566b3d953052ae key 03fba00a0705ca7f347c579366d13075fe8d4dabd22a9445198be75b566b3d9530
lightningd_1       | 2019-12-26T08:30:34.399Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Telling master we're about to commit...
lightningd_1       | 2019-12-26T08:30:34.399Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Sending master 1020
lightningd_1       | 2019-12-26T08:30:34.399Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-chan#1: HTLC out 266 SENT_REMOVE_REVOCATION->SENT_REMOVE_ACK_COMMIT
lightningd_1       | 2019-12-26T08:30:34.472Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: ... , awaiting 1120
lightningd_1       | 2019-12-26T08:30:34.474Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Got it!
lightningd_1       | 2019-12-26T08:30:34.474Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Sending commit_sig with 0 htlc sigs
lightningd_1       | 2019-12-26T08:30:34.475Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: peer_out WIRE_COMMITMENT_SIGNED
lightningd_1       | 2019-12-26T08:30:34.482Z DEBUG gossipd: Trying to find a route from (me) to 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf for 10000msat
lightningd_1       | 2019-12-26T08:30:34.485Z DEBUG gossipd: REPLY WIRE_GOSSIP_GETROUTE_REPLY with 0 fds
lightningd_1       | 2019-12-26T08:30:34.527Z INFO lightningd: Sending 10000msat over 1 hops to deliver 10000msat
lightningd_1       | 2019-12-26T08:30:34.539Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: NEW:: HTLC LOCAL 267 = SENT_ADD_HTLC/RCVD_ADD_HTLC
lightningd_1       | 2019-12-26T08:30:34.540Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Adding HTLC 267 amount=10000msat cltv=304 gave CHANNEL_ERR_ADD_OK
lightningd_1       | 2019-12-26T08:30:34.540Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: peer_out WIRE_UPDATE_ADD_HTLC
lightningd_1       | 2019-12-26T08:30:34.540Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: REPLY WIRE_CHANNEL_OFFER_HTLC_REPLY with 0 fds
lightningd_dest_1  | 2019-12-26T08:30:34.506Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: ... , awaiting 1122
lightningd_dest_1  | 2019-12-26T08:30:34.525Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Got it!
lightningd_dest_1  | 2019-12-26T08:30:34.525Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: revoke_and_ack REMOTE: remote_per_commit = 02e4caac0595a8749b610db37a061f4c94819420148f7ad7b81a129650aca55435, old_remote_per_commit = 029f77af367ccd7a3017ee345706e36dd224d9827f917775436770f4a3028d6dcd
lightningd_dest_1  | 2019-12-26T08:30:34.525Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Trying commit
lightningd_dest_1  | 2019-12-26T08:30:34.526Z DEBUG hsmd: Client: Received message 18 from client
lightningd_dest_1  | 2019-12-26T08:30:34.536Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Can't send commit: nothing to send
lightningd_dest_1  | 2019-12-26T08:30:34.536Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: peer_in WIRE_COMMITMENT_SIGNED
lightningd_dest_1  | 2019-12-26T08:30:34.536Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Received commit
lightningd_dest_1  | 2019-12-26T08:30:34.536Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: htlc 266: RCVD_REMOVE_REVOCATION->RCVD_REMOVE_ACK_COMMIT
lightningd_dest_1  | 2019-12-26T08:30:34.536Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: htlc added LOCAL: local 1723277 remote 16775481721
lightningd_dest_1  | 2019-12-26T08:30:34.536Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: -> local 1733279 remote 16775481721
lightningd_dest_1  | 2019-12-26T08:30:34.537Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: rcvd_commit: HTLC REMOTE 266 = RCVD_REMOVE_ACK_COMMIT/SENT_REMOVE_ACK_COMMIT FULFILLED
lightningd_dest_1  | 2019-12-26T08:30:34.537Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Feerates are 4000/4000
lightningd_dest_1  | 2019-12-26T08:30:34.537Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: We need 170668sat at feerate 4000 for 0 untrimmed htlcs: we have 16775481721msat/16775481721msat
lightningd_dest_1  | 2019-12-26T08:30:34.537Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Derived key 037276ea7313188a669094587a3a0d37dd121756ed3a5d68ad409d69ca697eb8ee from basepoint 023f414bc16cd032e1551c6bbfae09ed59d42668fb5c9b566b89d29b5ba001c9a3, point 02aed89251e4e237dc36c131cdfa3a5bda0bd9f8de05103ed13a69e3c87762f324
lightningd_dest_1  | 2019-12-26T08:30:34.537Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Received commit_sig with 0 htlc sigs
lightningd_dest_1  | 2019-12-26T08:30:34.537Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Sending revoke_and_ack
lightningd_dest_1  | 2019-12-26T08:30:34.537Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: htlc 266: RCVD_REMOVE_ACK_COMMIT->SENT_REMOVE_ACK_REVOCATION
lightningd_dest_1  | 2019-12-26T08:30:34.538Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: sending_revoke_and_ack: HTLC REMOTE 266 = SENT_REMOVE_ACK_REVOCATION/RCVD_REMOVE_ACK_REVOCATION FULFILLED
lightningd_dest_1  | 2019-12-26T08:30:34.538Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Sending master 1021
lightningd_dest_1  | 2019-12-26T08:30:34.538Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-chan#1: got commitsig 534: feerate 4000, 0 added, 0 fulfilled, 0 failed, 1 changed
lightningd_dest_1  | 2019-12-26T08:30:34.538Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-chan#1: HTLC in 266 RCVD_REMOVE_REVOCATION->RCVD_REMOVE_ACK_COMMIT
lightningd_dest_1  | 2019-12-26T08:30:34.538Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-chan#1: HTLC in 266 RCVD_REMOVE_ACK_COMMIT->SENT_REMOVE_ACK_REVOCATION
lightningd_dest_1  | 2019-12-26T08:30:34.538Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-chan#1: Removing in HTLC 266 state SENT_REMOVE_ACK_REVOCATION FULFILLED
lightningd_dest_1  | 2019-12-26T08:30:34.538Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-chan#1: Balance 1723277msat -> 1733279msat
lightningd_1       | 2019-12-26T08:30:34.556Z UNUSUAL lightningd: Unable to estimate CONSERVATIVE/2 fee
lightningd_1       | 2019-12-26T08:30:34.571Z UNUSUAL lightningd: Unable to estimate ECONOMICAL/4 fee
lightningd_1       | 2019-12-26T08:30:34.589Z UNUSUAL lightningd: Unable to estimate ECONOMICAL/100 fee
lightningd_1       | 2019-12-26T08:30:34.708Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: peer_in WIRE_REVOKE_AND_ACK
lightningd_1       | 2019-12-26T08:30:34.708Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Received revoke_and_ack
lightningd_1       | 2019-12-26T08:30:34.708Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: htlc 266: SENT_REMOVE_ACK_COMMIT->RCVD_REMOVE_ACK_REVOCATION
lightningd_1       | 2019-12-26T08:30:34.708Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: rcvd_revoke_and_ack: HTLC LOCAL 266 = RCVD_REMOVE_ACK_REVOCATION/SENT_REMOVE_ACK_REVOCATION FULFILLED
lightningd_1       | 2019-12-26T08:30:34.709Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: No commits outstanding after recv revoke_and_ack
lightningd_1       | 2019-12-26T08:30:34.709Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: HTLC 266[LOCAL] => RCVD_REMOVE_ACK_REVOCATION
lightningd_1       | 2019-12-26T08:30:34.709Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Sending master 1022
lightningd_dest_1  | 2019-12-26T08:30:34.706Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: ... , awaiting 1121
lightningd_dest_1  | 2019-12-26T08:30:34.706Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Got it!
lightningd_dest_1  | 2019-12-26T08:30:34.707Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: peer_out WIRE_REVOKE_AND_ACK
lightningd_dest_1  | 2019-12-26T08:30:34.707Z DEBUG hsmd: Client: Received message 1 from client
lightningd_dest_1  | 2019-12-26T08:30:34.707Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: peer_in WIRE_UPDATE_ADD_HTLC
lightningd_dest_1  | 2019-12-26T08:30:34.708Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: NEW:: HTLC REMOTE 267 = RCVD_ADD_HTLC/SENT_ADD_HTLC 
lightningd_1       | 2019-12-26T08:30:34.709Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-chan#1: got revoke 533: 1 changed
lightningd_1       | 2019-12-26T08:30:34.709Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-chan#1: HTLC out 266 SENT_REMOVE_ACK_COMMIT->RCVD_REMOVE_ACK_REVOCATION
lightningd_1       | 2019-12-26T08:30:34.709Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-chan#1: Removing out HTLC 266 state RCVD_REMOVE_ACK_REVOCATION FULFILLED
lightningd_1       | 2019-12-26T08:30:34.709Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-chan#1: Balance 16775491723msat -> 16775481721msat
lightningd_1       | 2019-12-26T08:30:34.770Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: ... , awaiting 1122
lightningd_1       | 2019-12-26T08:30:34.772Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Got it!
lightningd_1       | 2019-12-26T08:30:34.774Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: revoke_and_ack LOCAL: remote_per_commit = 0227c8d97889bb2adc1761a6c7a416ae1aa3792984186b9b604b9433e5367599e5, old_remote_per_commit = 02aed89251e4e237dc36c131cdfa3a5bda0bd9f8de05103ed13a69e3c87762f324
lightningd_1       | 2019-12-26T08:30:34.774Z DEBUG hsmd: Client: Received message 19 from client
lightningd_1       | 2019-12-26T08:30:34.774Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Trying commit
lightningd_1       | 2019-12-26T08:30:34.775Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: htlc 267: SENT_ADD_HTLC->SENT_ADD_COMMIT
lightningd_1       | 2019-12-26T08:30:34.775Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: htlc added REMOTE: local 16775481721 remote 1733279
lightningd_1       | 2019-12-26T08:30:34.775Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: -> local 16775471721 remote 1733279
lightningd_1       | 2019-12-26T08:30:34.775Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: sending_commit: HTLC LOCAL 267 = SENT_ADD_COMMIT/RCVD_ADD_COMMIT
lightningd_1       | 2019-12-26T08:30:34.775Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Creating commit_sig signature 535 304402203572fef45a124dfb2ae8ea439ead13fbdfc9ed446b05e6980cadfced07dc268102202d618350763435db1e9ad73bf5fcdfe64ce5674c1e72744fda4630db1a738fb701 for tx 0200000001b399e7d4320d1e9591432cd40a39f07e4696d898028d0415436c52ffc293a5b600000000001742ab8002c50600000000000022002093a3b6738b9c8a5736509d0f49e7bca4bcd3e38f8742f367865a853925cc838bdfedff000000000016001492902e4d84471c9e0e83fef2ef72fe3210faf16cd31a1220 wscript 522102305d92049c25c0537cff15899fd6f402c0f5b181a31304a5ce8ef27c117eabf62103fba00a0705ca7f347c579366d13075fe8d4dabd22a9445198be75b566b3d953052ae key 03fba00a0705ca7f347c579366d13075fe8d4dabd22a9445198be75b566b3d9530
lightningd_1       | 2019-12-26T08:30:34.776Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Telling master we're about to commit...
lightningd_1       | 2019-12-26T08:30:34.776Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Sending master 1020
lightningd_1       | 2019-12-26T08:30:34.777Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-chan#1: HTLC out 267 SENT_ADD_HTLC->SENT_ADD_COMMIT
lightningd_dest_1  | 2019-12-26T08:30:34.864Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: peer_in WIRE_COMMITMENT_SIGNED
lightningd_dest_1  | 2019-12-26T08:30:34.864Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Received commit
lightningd_dest_1  | 2019-12-26T08:30:34.865Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: htlc 267: RCVD_ADD_HTLC->RCVD_ADD_COMMIT
lightningd_dest_1  | 2019-12-26T08:30:34.865Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: htlc added LOCAL: local 1733279 remote 16775481721
lightningd_dest_1  | 2019-12-26T08:30:34.865Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: -> local 1733279 remote 16775471721
lightningd_dest_1  | 2019-12-26T08:30:34.865Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: rcvd_commit: HTLC REMOTE 267 = RCVD_ADD_COMMIT/SENT_ADD_COMMIT
lightningd_dest_1  | 2019-12-26T08:30:34.866Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Feerates are 4000/4000
lightningd_dest_1  | 2019-12-26T08:30:34.866Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: We need 170668sat at feerate 4000 for 0 untrimmed htlcs: we have 16775471721msat/16775481721msat
lightningd_dest_1  | 2019-12-26T08:30:34.866Z DEBUG hsmd: Client: Received message 18 from client
lightningd_dest_1  | 2019-12-26T08:30:34.866Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Derived key 02364758336ff46eca77fc0e41d59b08e95c247af979ec55e959179bb87ad2ddfd from basepoint 023f414bc16cd032e1551c6bbfae09ed59d42668fb5c9b566b89d29b5ba001c9a3, point 0227c8d97889bb2adc1761a6c7a416ae1aa3792984186b9b604b9433e5367599e5
lightningd_dest_1  | 2019-12-26T08:30:34.866Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Received commit_sig with 0 htlc sigs
lightningd_dest_1  | 2019-12-26T08:30:34.867Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Sending revoke_and_ack
lightningd_dest_1  | 2019-12-26T08:30:34.867Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: htlc 267: RCVD_ADD_COMMIT->SENT_ADD_REVOCATION
lightningd_dest_1  | 2019-12-26T08:30:34.867Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: sending_revoke_and_ack: HTLC REMOTE 267 = SENT_ADD_REVOCATION/RCVD_ADD_REVOCATION
lightningd_dest_1  | 2019-12-26T08:30:34.867Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: revoke_and_ack made pending: commit timer
lightningd_dest_1  | 2019-12-26T08:30:34.867Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Sending master 1021
lightningd_dest_1  | 2019-12-26T08:30:34.867Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-chan#1: got commitsig 535: feerate 4000, 1 added, 0 fulfilled, 0 failed, 0 changed
lightningd_dest_1  | 2019-12-26T08:30:34.868Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-chan#1: Adding their HTLC 267
lightningd_dest_1  | 2019-12-26T08:30:34.868Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-chan#1: HTLC in 267 RCVD_ADD_COMMIT->SENT_ADD_REVOCATION
lightningd_1       | 2019-12-26T08:30:34.863Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: ... , awaiting 1120
lightningd_1       | 2019-12-26T08:30:34.863Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Got it!
lightningd_1       | 2019-12-26T08:30:34.864Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Sending commit_sig with 0 htlc sigs
lightningd_1       | 2019-12-26T08:30:34.868Z DEBUG lightningd: Payment part 0/0 status 0
lightningd_1       | 2019-12-26T08:30:34.868Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: peer_out WIRE_COMMITMENT_SIGNED
lightningd_dest_1  | 2019-12-26T08:30:34.895Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: ... , awaiting 1121
lightningd_dest_1  | 2019-12-26T08:30:34.895Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Got it!
lightningd_dest_1  | 2019-12-26T08:30:34.895Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: peer_out WIRE_REVOKE_AND_ACK
lightningd_dest_1  | 2019-12-26T08:30:34.895Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Trying commit
lightningd_dest_1  | 2019-12-26T08:30:34.895Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: htlc 267: SENT_ADD_REVOCATION->SENT_ADD_ACK_COMMIT
lightningd_dest_1  | 2019-12-26T08:30:34.895Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: htlc added REMOTE: local 1733279 remote 16775481721
lightningd_dest_1  | 2019-12-26T08:30:34.895Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: -> local 1733279 remote 16775471721
lightningd_dest_1  | 2019-12-26T08:30:34.895Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: sending_commit: HTLC REMOTE 267 = SENT_ADD_ACK_COMMIT/RCVD_ADD_ACK_COMMIT
lightningd_dest_1  | 2019-12-26T08:30:34.896Z DEBUG hsmd: Client: Received message 19 from client
lightningd_dest_1  | 2019-12-26T08:30:34.896Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Creating commit_sig signature 535 3044022016bb9c85db12fbe46517e8291db54b9a38b31441a7e37f0b224f79f95bcbbc36022030498f3482db32c3cb9ee08b850b4e992a755f9433922b2101a60ae80243337f01 for tx 0200000001b399e7d4320d1e9591432cd40a39f07e4696d898028d0415436c52ffc293a5b600000000001742ab8002c5060000000000001600142a08b0bea717615a09590719e1f7827b23d7fa5fdfedff00000000002200208327c9823dc9d05c918d1ec41f6fef8ab50c0f070f51d7b0dd57abaeee0dcb3ad31a1220 wscript 522102305d92049c25c0537cff15899fd6f402c0f5b181a31304a5ce8ef27c117eabf62103fba00a0705ca7f347c579366d13075fe8d4dabd22a9445198be75b566b3d953052ae key 02305d92049c25c0537cff15899fd6f402c0f5b181a31304a5ce8ef27c117eabf6
lightningd_dest_1  | 2019-12-26T08:30:34.897Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Telling master we're about to commit...
lightningd_dest_1  | 2019-12-26T08:30:34.897Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Sending master 1020
lightningd_dest_1  | 2019-12-26T08:30:34.897Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-chan#1: HTLC in 267 SENT_ADD_REVOCATION->SENT_ADD_ACK_COMMIT
lightningd_1       | 2019-12-26T08:30:34.896Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: peer_in WIRE_REVOKE_AND_ACK
lightningd_1       | 2019-12-26T08:30:34.897Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Received revoke_and_ack
lightningd_1       | 2019-12-26T08:30:34.897Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: htlc 267: SENT_ADD_COMMIT->RCVD_ADD_REVOCATION
lightningd_1       | 2019-12-26T08:30:34.897Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: rcvd_revoke_and_ack: HTLC LOCAL 267 = RCVD_ADD_REVOCATION/SENT_ADD_REVOCATION
lightningd_1       | 2019-12-26T08:30:34.897Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Commits outstanding after recv revoke_and_ack
lightningd_1       | 2019-12-26T08:30:34.897Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: HTLC 267[LOCAL] => RCVD_ADD_REVOCATION
lightningd_1       | 2019-12-26T08:30:34.897Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Sending master 1022
lightningd_1       | 2019-12-26T08:30:34.897Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-chan#1: got revoke 534: 1 changed
lightningd_1       | 2019-12-26T08:30:34.897Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-chan#1: HTLC out 267 SENT_ADD_COMMIT->RCVD_ADD_REVOCATION
lightningd_1       | 2019-12-26T08:30:34.938Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: ... , awaiting 1122
lightningd_1       | 2019-12-26T08:30:34.938Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Got it!
lightningd_1       | 2019-12-26T08:30:34.938Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: revoke_and_ack LOCAL: remote_per_commit = 024f18ef2a5478e52d4ef4dec184e6405c919cd939bf7439a7b91691a437cdb077, old_remote_per_commit = 0227c8d97889bb2adc1761a6c7a416ae1aa3792984186b9b604b9433e5367599e5
lightningd_1       | 2019-12-26T08:30:34.939Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Trying commit
lightningd_1       | 2019-12-26T08:30:34.939Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Can't send commit: nothing to send
lightningd_1       | 2019-12-26T08:30:34.945Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: peer_in WIRE_COMMITMENT_SIGNED
lightningd_1       | 2019-12-26T08:30:34.946Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Received commit
lightningd_1       | 2019-12-26T08:30:34.946Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: htlc 267: RCVD_ADD_REVOCATION->RCVD_ADD_ACK_COMMIT
lightningd_1       | 2019-12-26T08:30:34.946Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: htlc added LOCAL: local 16775481721 remote 1733279
lightningd_1       | 2019-12-26T08:30:34.947Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: -> local 16775471721 remote 1733279
lightningd_1       | 2019-12-26T08:30:34.947Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: rcvd_commit: HTLC LOCAL 267 = RCVD_ADD_ACK_COMMIT/SENT_ADD_ACK_COMMIT
lightningd_1       | 2019-12-26T08:30:34.947Z DEBUG hsmd: Client: Received message 18 from client
lightningd_1       | 2019-12-26T08:30:34.947Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Derived key 03715190fa81cd216d788b5db1fc0d53e27649e50a6abb0e10ef207c02c44bdd7a from basepoint 033881f4781bf91126cfd33552c17bcb1b0e61ed8b424fdf6d36fb99e253d42fa6, point 02e4caac0595a8749b610db37a061f4c94819420148f7ad7b81a129650aca55435
lightningd_1       | 2019-12-26T08:30:34.947Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Received commit_sig with 0 htlc sigs
lightningd_1       | 2019-12-26T08:30:34.947Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Sending revoke_and_ack
lightningd_1       | 2019-12-26T08:30:34.947Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: htlc 267: RCVD_ADD_ACK_COMMIT->SENT_ADD_ACK_REVOCATION
lightningd_1       | 2019-12-26T08:30:34.947Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: sending_revoke_and_ack: HTLC LOCAL 267 = SENT_ADD_ACK_REVOCATION/RCVD_ADD_ACK_REVOCATION
lightningd_1       | 2019-12-26T08:30:34.948Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Sending master 1021
lightningd_1       | 2019-12-26T08:30:34.948Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-chan#1: got commitsig 535: feerate 4000, 0 added, 0 fulfilled, 0 failed, 1 changed
lightningd_1       | 2019-12-26T08:30:34.948Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-chan#1: HTLC out 267 RCVD_ADD_REVOCATION->RCVD_ADD_ACK_COMMIT
lightningd_1       | 2019-12-26T08:30:34.948Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-chan#1: HTLC out 267 RCVD_ADD_ACK_COMMIT->SENT_ADD_ACK_REVOCATION
lightningd_dest_1  | 2019-12-26T08:30:34.945Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: ... , awaiting 1120
lightningd_dest_1  | 2019-12-26T08:30:34.945Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Got it!
lightningd_dest_1  | 2019-12-26T08:30:34.945Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Sending commit_sig with 0 htlc sigs
lightningd_dest_1  | 2019-12-26T08:30:34.945Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: peer_out WIRE_COMMITMENT_SIGNED
lightningd_1       | 2019-12-26T08:30:34.979Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: ... , awaiting 1121
lightningd_dest_1  | 2019-12-26T08:30:34.980Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: peer_in WIRE_REVOKE_AND_ACK
lightningd_dest_1  | 2019-12-26T08:30:34.981Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Received revoke_and_ack
lightningd_dest_1  | 2019-12-26T08:30:34.981Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: htlc 267: SENT_ADD_ACK_COMMIT->RCVD_ADD_ACK_REVOCATION
lightningd_dest_1  | 2019-12-26T08:30:34.981Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: rcvd_revoke_and_ack: HTLC REMOTE 267 = RCVD_ADD_ACK_REVOCATION/SENT_ADD_ACK_REVOCATION
lightningd_dest_1  | 2019-12-26T08:30:34.982Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: No commits outstanding after recv revoke_and_ack
lightningd_dest_1  | 2019-12-26T08:30:34.982Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: HTLC 267[REMOTE] => RCVD_ADD_ACK_REVOCATION
lightningd_dest_1  | 2019-12-26T08:30:34.982Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Sending master 1022
lightningd_dest_1  | 2019-12-26T08:30:34.983Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-chan#1: got revoke 534: 1 changed
lightningd_dest_1  | 2019-12-26T08:30:34.983Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-chan#1: HTLC in 267 SENT_ADD_ACK_COMMIT->RCVD_ADD_ACK_REVOCATION
lightningd_dest_1  | 2019-12-26T08:30:34.986Z INFO lightningd: Resolved invoice '26cWQaqVxSwMNGWhxvzBm9EHtuYA' with amount 10000msat in 1 htlcs
lightningd_dest_1  | 2019-12-26T08:30:34.987Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-chan#1: HTLC in 267 RCVD_ADD_ACK_REVOCATION->SENT_REMOVE_HTLC
lightningd_dest_1  | 2019-12-26T08:30:34.987Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-chan#1: their htlc 267 locked
lightningd_1       | 2019-12-26T08:30:34.980Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Got it!
lightningd_1       | 2019-12-26T08:30:34.980Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: peer_out WIRE_REVOKE_AND_ACK
lightningd_dest_1  | 2019-12-26T08:30:35.009Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: ... , awaiting 1122
lightningd_dest_1  | 2019-12-26T08:30:35.010Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Nope, got 1005 instead
lightningd_dest_1  | 2019-12-26T08:30:35.010Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Got it!
lightningd_dest_1  | 2019-12-26T08:30:35.010Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: revoke_and_ack REMOTE: remote_per_commit = 03c632b477b54effb2c61bafc5a42344185252f633dcda7b2c68786d03d7d5efa0, old_remote_per_commit = 02e4caac0595a8749b610db37a061f4c94819420148f7ad7b81a129650aca55435
lightningd_dest_1  | 2019-12-26T08:30:35.010Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Now dealing with deferred WIRE_CHANNEL_FULFILL_HTLC
lightningd_dest_1  | 2019-12-26T08:30:35.010Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: FULFILL:: HTLC REMOTE 267 = SENT_REMOVE_HTLC/RCVD_REMOVE_HTLC FULFILLED
lightningd_dest_1  | 2019-12-26T08:30:35.011Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: peer_out WIRE_UPDATE_FULFILL_HTLC
lightningd_dest_1  | 2019-12-26T08:30:35.011Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Trying commit
lightningd_dest_1  | 2019-12-26T08:30:35.011Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: htlc 267: SENT_REMOVE_HTLC->SENT_REMOVE_COMMIT
lightningd_dest_1  | 2019-12-26T08:30:35.013Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: htlc added REMOTE: local 1733279 remote 16775471721
lightningd_dest_1  | 2019-12-26T08:30:35.013Z DEBUG hsmd: Client: Received message 19 from client
lightningd_1       | 2019-12-26T08:30:35.012Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: peer_in WIRE_UPDATE_FULFILL_HTLC
lightningd_1       | 2019-12-26T08:30:35.014Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: FULFILL:: HTLC LOCAL 267 = RCVD_REMOVE_HTLC/SENT_REMOVE_HTLC FULFILLED
lightningd_dest_1  | 2019-12-26T08:30:35.013Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: -> local 1743279 remote 16775471721
lightningd_dest_1  | 2019-12-26T08:30:35.014Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: sending_commit: HTLC REMOTE 267 = SENT_REMOVE_COMMIT/RCVD_REMOVE_COMMIT FULFILLED
lightningd_dest_1  | 2019-12-26T08:30:35.014Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Creating commit_sig signature 536 304402206794031bcd7f033c0de5cd1631b7abd1fa03ec53c4847cda3e0908660e060b5d02200ce09a71a63e2398580fe3f39df213d497e79bb8f02f1f7425a211cca6e8383e01 for tx 0200000001b399e7d4320d1e9591432cd40a39f07e4696d898028d0415436c52ffc293a5b600000000001742ab8002cf060000000000001600142a08b0bea717615a09590719e1f7827b23d7fa5fdfedff00000000002200206a6c9b863b258ff16ed5ee21eba53aa5a96a51856e9fea1a03c7a8f1e5b3b3e6dc1a1220 wscript 522102305d92049c25c0537cff15899fd6f402c0f5b181a31304a5ce8ef27c117eabf62103fba00a0705ca7f347c579366d13075fe8d4dabd22a9445198be75b566b3d953052ae key 02305d92049c25c0537cff15899fd6f402c0f5b181a31304a5ce8ef27c117eabf6
lightningd_dest_1  | 2019-12-26T08:30:35.014Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Telling master we're about to commit...
lightningd_dest_1  | 2019-12-26T08:30:35.014Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Sending master 1020
lightningd_dest_1  | 2019-12-26T08:30:35.015Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-chan#1: HTLC in 267 SENT_REMOVE_HTLC->SENT_REMOVE_COMMIT
lightningd_1       | 2019-12-26T08:30:35.023Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Trying commit
lightningd_1       | 2019-12-26T08:30:35.023Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Can't send commit: nothing to send
lightningd_dest_1  | 2019-12-26T08:30:35.046Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: ... , awaiting 1120
lightningd_dest_1  | 2019-12-26T08:30:35.047Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Got it!
lightningd_dest_1  | 2019-12-26T08:30:35.047Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Sending commit_sig with 0 htlc sigs
lightningd_dest_1  | 2019-12-26T08:30:35.048Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: peer_out WIRE_COMMITMENT_SIGNED
lightningd_1       | 2019-12-26T08:30:35.060Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: peer_in WIRE_COMMITMENT_SIGNED
lightningd_1       | 2019-12-26T08:30:35.060Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Received commit
lightningd_1       | 2019-12-26T08:30:35.060Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: htlc 267: RCVD_REMOVE_HTLC->RCVD_REMOVE_COMMIT
lightningd_1       | 2019-12-26T08:30:35.060Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: htlc added LOCAL: local 16775471721 remote 1733279
lightningd_1       | 2019-12-26T08:30:35.060Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: -> local 16775471721 remote 1743279
lightningd_1       | 2019-12-26T08:30:35.060Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: rcvd_commit: HTLC LOCAL 267 = RCVD_REMOVE_COMMIT/SENT_REMOVE_COMMIT FULFILLED
lightningd_1       | 2019-12-26T08:30:35.061Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Derived key 0302becb9124a7c9ef4338171208ad08545ac52976c9adbbd9a5120d41d1ab2e41 from basepoint 033881f4781bf91126cfd33552c17bcb1b0e61ed8b424fdf6d36fb99e253d42fa6, point 03c632b477b54effb2c61bafc5a42344185252f633dcda7b2c68786d03d7d5efa0
lightningd_1       | 2019-12-26T08:30:35.061Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Received commit_sig with 0 htlc sigs
lightningd_1       | 2019-12-26T08:30:35.061Z DEBUG hsmd: Client: Received message 18 from client
lightningd_1       | 2019-12-26T08:30:35.061Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Sending revoke_and_ack
lightningd_1       | 2019-12-26T08:30:35.062Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: htlc 267: RCVD_REMOVE_COMMIT->SENT_REMOVE_REVOCATION
lightningd_1       | 2019-12-26T08:30:35.062Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: sending_revoke_and_ack: HTLC LOCAL 267 = SENT_REMOVE_REVOCATION/RCVD_REMOVE_REVOCATION FULFILLED
lightningd_1       | 2019-12-26T08:30:35.062Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: revoke_and_ack made pending: commit timer
lightningd_1       | 2019-12-26T08:30:35.062Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Sending master 1021
lightningd_1       | 2019-12-26T08:30:35.062Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-chan#1: got commitsig 536: feerate 4000, 0 added, 1 fulfilled, 0 failed, 0 changed
lightningd_1       | 2019-12-26T08:30:35.062Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-chan#1: HTLC out 267 SENT_ADD_ACK_REVOCATION->RCVD_REMOVE_COMMIT
lightningd_1       | 2019-12-26T08:30:35.063Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-chan#1: HTLC out 267 RCVD_REMOVE_COMMIT->SENT_REMOVE_REVOCATION
lightningd_dest_1  | 2019-12-26T08:30:35.093Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: peer_in WIRE_REVOKE_AND_ACK
lightningd_dest_1  | 2019-12-26T08:30:35.093Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Received revoke_and_ack
lightningd_dest_1  | 2019-12-26T08:30:35.093Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: htlc 267: SENT_REMOVE_COMMIT->RCVD_REMOVE_REVOCATION
lightningd_dest_1  | 2019-12-26T08:30:35.093Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: rcvd_revoke_and_ack: HTLC REMOTE 267 = RCVD_REMOVE_REVOCATION/SENT_REMOVE_REVOCATION FULFILLED
lightningd_dest_1  | 2019-12-26T08:30:35.093Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Commits outstanding after recv revoke_and_ack
lightningd_dest_1  | 2019-12-26T08:30:35.095Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: HTLC 267[REMOTE] => RCVD_REMOVE_REVOCATION
lightningd_dest_1  | 2019-12-26T08:30:35.095Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Sending master 1022
lightningd_dest_1  | 2019-12-26T08:30:35.095Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-chan#1: got revoke 535: 1 changed
lightningd_dest_1  | 2019-12-26T08:30:35.095Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-chan#1: HTLC in 267 SENT_REMOVE_COMMIT->RCVD_REMOVE_REVOCATION
lightningd_1       | 2019-12-26T08:30:35.092Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: ... , awaiting 1121
lightningd_1       | 2019-12-26T08:30:35.092Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Got it!
lightningd_1       | 2019-12-26T08:30:35.092Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: peer_out WIRE_REVOKE_AND_ACK
lightningd_1       | 2019-12-26T08:30:35.094Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Trying commit
lightningd_1       | 2019-12-26T08:30:35.094Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: htlc 267: SENT_REMOVE_REVOCATION->SENT_REMOVE_ACK_COMMIT
lightningd_1       | 2019-12-26T08:30:35.094Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: htlc added REMOTE: local 16775471721 remote 1733279
lightningd_1       | 2019-12-26T08:30:35.094Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: -> local 16775471721 remote 1743279
lightningd_1       | 2019-12-26T08:30:35.094Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: sending_commit: HTLC LOCAL 267 = SENT_REMOVE_ACK_COMMIT/RCVD_REMOVE_ACK_COMMIT FULFILLED
lightningd_1       | 2019-12-26T08:30:35.097Z DEBUG hsmd: Client: Received message 19 from client
lightningd_1       | 2019-12-26T08:30:35.097Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Creating commit_sig signature 536 3044022077b6344e955c6f5e2efe34271b44942b5305bea74e191b0adfafa06beb94e1ed02207fcaa5aff0feb15d49ccf98feff6e28b3921c8ba728a41c48fa75d0e13f2214501 for tx 0200000001b399e7d4320d1e9591432cd40a39f07e4696d898028d0415436c52ffc293a5b600000000001742ab8002cf060000000000002200201ad9f6ae8136b839ef1357ac736663da25cf2e2e49654fb6e6f1300daaa445c6dfedff000000000016001492902e4d84471c9e0e83fef2ef72fe3210faf16cdc1a1220 wscript 522102305d92049c25c0537cff15899fd6f402c0f5b181a31304a5ce8ef27c117eabf62103fba00a0705ca7f347c579366d13075fe8d4dabd22a9445198be75b566b3d953052ae key 03fba00a0705ca7f347c579366d13075fe8d4dabd22a9445198be75b566b3d9530
lightningd_1       | 2019-12-26T08:30:35.098Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Telling master we're about to commit...
lightningd_1       | 2019-12-26T08:30:35.098Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Sending master 1020
lightningd_1       | 2019-12-26T08:30:35.098Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-chan#1: HTLC out 267 SENT_REMOVE_REVOCATION->SENT_REMOVE_ACK_COMMIT
lightningd_1       | 2019-12-26T08:30:35.149Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: ... , awaiting 1120
lightningd_1       | 2019-12-26T08:30:35.150Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Got it!
lightningd_1       | 2019-12-26T08:30:35.150Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Sending commit_sig with 0 htlc sigs
lightningd_1       | 2019-12-26T08:30:35.151Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: peer_out WIRE_COMMITMENT_SIGNED
lightningd_dest_1  | 2019-12-26T08:30:35.172Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: ... , awaiting 1122
lightningd_dest_1  | 2019-12-26T08:30:35.193Z DEBUG hsmd: Client: Received message 18 from client
lightningd_dest_1  | 2019-12-26T08:30:35.194Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Got it!
lightningd_dest_1  | 2019-12-26T08:30:35.194Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: revoke_and_ack REMOTE: remote_per_commit = 029a28bdc82758c094bfaa81e294e311ba0d91f386864158c88d5550e107e42068, old_remote_per_commit = 03c632b477b54effb2c61bafc5a42344185252f633dcda7b2c68786d03d7d5efa0
lightningd_dest_1  | 2019-12-26T08:30:35.194Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Trying commit
lightningd_dest_1  | 2019-12-26T08:30:35.194Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Can't send commit: nothing to send
lightningd_dest_1  | 2019-12-26T08:30:35.194Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: peer_in WIRE_COMMITMENT_SIGNED
lightningd_dest_1  | 2019-12-26T08:30:35.194Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Received commit
lightningd_dest_1  | 2019-12-26T08:30:35.195Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: htlc 267: RCVD_REMOVE_REVOCATION->RCVD_REMOVE_ACK_COMMIT
lightningd_dest_1  | 2019-12-26T08:30:35.196Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: htlc added LOCAL: local 1733279 remote 16775471721
lightningd_dest_1  | 2019-12-26T08:30:35.196Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: -> local 1743279 remote 16775471721
lightningd_dest_1  | 2019-12-26T08:30:35.196Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: rcvd_commit: HTLC REMOTE 267 = RCVD_REMOVE_ACK_COMMIT/SENT_REMOVE_ACK_COMMIT FULFILLED
lightningd_dest_1  | 2019-12-26T08:30:35.196Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Feerates are 4000/4000
lightningd_dest_1  | 2019-12-26T08:30:35.197Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: We need 170668sat at feerate 4000 for 0 untrimmed htlcs: we have 16775471721msat/16775471721msat
lightningd_dest_1  | 2019-12-26T08:30:35.197Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Derived key 02b20f9995a52231273a048b4d20dc2c087c1c2163e51e2201f63050de2332be07 from basepoint 023f414bc16cd032e1551c6bbfae09ed59d42668fb5c9b566b89d29b5ba001c9a3, point 024f18ef2a5478e52d4ef4dec184e6405c919cd939bf7439a7b91691a437cdb077
lightningd_dest_1  | 2019-12-26T08:30:35.197Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Received commit_sig with 0 htlc sigs
lightningd_dest_1  | 2019-12-26T08:30:35.221Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Sending revoke_and_ack
lightningd_dest_1  | 2019-12-26T08:30:35.221Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: htlc 267: RCVD_REMOVE_ACK_COMMIT->SENT_REMOVE_ACK_REVOCATION
lightningd_dest_1  | 2019-12-26T08:30:35.221Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: sending_revoke_and_ack: HTLC REMOTE 267 = SENT_REMOVE_ACK_REVOCATION/RCVD_REMOVE_ACK_REVOCATION FULFILLED
lightningd_dest_1  | 2019-12-26T08:30:35.222Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Sending master 1021
lightningd_dest_1  | 2019-12-26T08:30:35.223Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-chan#1: got commitsig 536: feerate 4000, 0 added, 0 fulfilled, 0 failed, 1 changed
lightningd_dest_1  | 2019-12-26T08:30:35.223Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-chan#1: HTLC in 267 RCVD_REMOVE_REVOCATION->RCVD_REMOVE_ACK_COMMIT
lightningd_dest_1  | 2019-12-26T08:30:35.223Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-chan#1: HTLC in 267 RCVD_REMOVE_ACK_COMMIT->SENT_REMOVE_ACK_REVOCATION
lightningd_dest_1  | 2019-12-26T08:30:35.224Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-chan#1: Removing in HTLC 267 state SENT_REMOVE_ACK_REVOCATION FULFILLED
lightningd_dest_1  | 2019-12-26T08:30:35.237Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-chan#1: Balance 1733279msat -> 1743279msat
lightningd_1       | 2019-12-26T08:30:35.300Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: peer_in WIRE_REVOKE_AND_ACK
lightningd_1       | 2019-12-26T08:30:35.301Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Received revoke_and_ack
lightningd_1       | 2019-12-26T08:30:35.302Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: htlc 267: SENT_REMOVE_ACK_COMMIT->RCVD_REMOVE_ACK_REVOCATION
lightningd_1       | 2019-12-26T08:30:35.303Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: rcvd_revoke_and_ack: HTLC LOCAL 267 = RCVD_REMOVE_ACK_REVOCATION/SENT_REMOVE_ACK_REVOCATION FULFILLED
lightningd_1       | 2019-12-26T08:30:35.304Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: No commits outstanding after recv revoke_and_ack
lightningd_1       | 2019-12-26T08:30:35.305Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: HTLC 267[LOCAL] => RCVD_REMOVE_ACK_REVOCATION
lightningd_dest_1  | 2019-12-26T08:30:35.297Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: ... , awaiting 1121
lightningd_dest_1  | 2019-12-26T08:30:35.299Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Got it!
lightningd_dest_1  | 2019-12-26T08:30:35.299Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: peer_out WIRE_REVOKE_AND_ACK
lightningd_1       | 2019-12-26T08:30:35.316Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Sending master 1022
lightningd_1       | 2019-12-26T08:30:35.317Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-chan#1: got revoke 535: 1 changed
lightningd_1       | 2019-12-26T08:30:35.318Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-chan#1: HTLC out 267 SENT_REMOVE_ACK_COMMIT->RCVD_REMOVE_ACK_REVOCATION
lightningd_1       | 2019-12-26T08:30:35.319Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-chan#1: Removing out HTLC 267 state RCVD_REMOVE_ACK_REVOCATION FULFILLED
lightningd_1       | 2019-12-26T08:30:35.319Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-chan#1: Balance 16775481721msat -> 16775471721msat
lightningd_dest_1  | 2019-12-26T08:30:35.359Z UNUSUAL lightningd: Unable to estimate CONSERVATIVE/2 fee
lightningd_1       | 2019-12-26T08:30:35.373Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: ... , awaiting 1122
lightningd_1       | 2019-12-26T08:30:35.374Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Got it!
lightningd_1       | 2019-12-26T08:30:35.375Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: revoke_and_ack LOCAL: remote_per_commit = 03f37df43b953ac8a095f0d9a0e7459090127de13c6dbcb07a7046d0936fd69078, old_remote_per_commit = 024f18ef2a5478e52d4ef4dec184e6405c919cd939bf7439a7b91691a437cdb077
lightningd_1       | 2019-12-26T08:30:35.376Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Trying commit
lightningd_1       | 2019-12-26T08:30:35.377Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Can't send commit: nothing to send
lightningd_dest_1  | 2019-12-26T08:30:35.378Z UNUSUAL lightningd: Unable to estimate ECONOMICAL/4 fee
lightningd_dest_1  | 2019-12-26T08:30:35.394Z UNUSUAL lightningd: Unable to estimate ECONOMICAL/100 fee
lightningd_dest_1  | 2019-12-26T08:30:35.479Z DEBUG jsonrpc#27: Abandoning command waitanyinvoice
lightningd_1       | 2019-12-26T08:30:35.600Z UNUSUAL lightningd: Unable to estimate CONSERVATIVE/2 fee
lightningd_1       | 2019-12-26T08:30:35.621Z UNUSUAL lightningd: Unable to estimate ECONOMICAL/4 fee
lightningd_dest_1  | 2019-12-26T08:30:35.621Z DEBUG lightningd: Adding block 294: 227875bc898c7724f1544d1fad0c6937fe023df77f9eec430287fab18ffd3e60
lightningd_1       | 2019-12-26T08:30:35.675Z UNUSUAL lightningd: Unable to estimate ECONOMICAL/100 fee
lightningd_dest_1  | 2019-12-26T08:30:35.708Z DEBUG lightningd: Adding block 295: 585b338f6466be74c1375b1726c1c728c606690183ead9233bd3a22d074446f1
lightningd_dest_1  | 2019-12-26T08:30:35.756Z DEBUG lightningd: exposeprivate = NULL
lightningd_dest_1  | 2019-12-26T08:30:35.757Z DEBUG gossipd: REPLY WIRE_GOSSIP_GET_INCOMING_CHANNELS_REPLY with 0 fds
lightningd_dest_1  | 2019-12-26T08:30:35.758Z UNUSUAL lightningd: invoice: insufficient incoming capacity for 1000msat
lightningd_dest_1  | 2019-12-26T08:30:35.781Z DEBUG hsmd: Client: Received message 8 from client
lightningd_1       | 2019-12-26T08:30:35.801Z DEBUG gossipd: Trying to find a route from (me) to 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf for 1000msat
lightningd_1       | 2019-12-26T08:30:35.801Z DEBUG gossipd: REPLY WIRE_GOSSIP_GETROUTE_REPLY with 0 fds
lightningd_1       | 2019-12-26T08:30:35.803Z INFO lightningd: Sending 1000msat over 1 hops to deliver 1000msat
lightningd_1       | 2019-12-26T08:30:35.804Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: NEW:: HTLC LOCAL 268 = SENT_ADD_HTLC/RCVD_ADD_HTLC
lightningd_1       | 2019-12-26T08:30:35.804Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Adding HTLC 268 amount=1000msat cltv=304 gave CHANNEL_ERR_ADD_OK
lightningd_1       | 2019-12-26T08:30:35.804Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: peer_out WIRE_UPDATE_ADD_HTLC
lightningd_dest_1  | 2019-12-26T08:30:35.805Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: peer_in WIRE_UPDATE_ADD_HTLC
lightningd_dest_1  | 2019-12-26T08:30:35.805Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: NEW:: HTLC REMOTE 268 = RCVD_ADD_HTLC/SENT_ADD_HTLC 
lightningd_1       | 2019-12-26T08:30:35.805Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: REPLY WIRE_CHANNEL_OFFER_HTLC_REPLY with 0 fds
lightningd_dest_1  | 2019-12-26T08:30:35.807Z DEBUG hsmd: Client: Received message 1 from client
lightningd_1       | 2019-12-26T08:30:35.817Z DEBUG hsmd: Client: Received message 19 from client
lightningd_1       | 2019-12-26T08:30:35.818Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Trying commit
lightningd_1       | 2019-12-26T08:30:35.820Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: htlc 268: SENT_ADD_HTLC->SENT_ADD_COMMIT
lightningd_1       | 2019-12-26T08:30:35.820Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: htlc added REMOTE: local 16775471721 remote 1743279
lightningd_1       | 2019-12-26T08:30:35.820Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: -> local 16775470721 remote 1743279
lightningd_1       | 2019-12-26T08:30:35.821Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: sending_commit: HTLC LOCAL 268 = SENT_ADD_COMMIT/RCVD_ADD_COMMIT
lightningd_1       | 2019-12-26T08:30:35.821Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Creating commit_sig signature 537 304402202271176465f268d9af453d3466c65ebd75f518b992c536fe2d5c2aa32bd77ba802207a26a85381a52d0a0301cf602115e53ac00fa33d8a0a364afacb468eaa9a9a2701 for tx 0200000001b399e7d4320d1e9591432cd40a39f07e4696d898028d0415436c52ffc293a5b600000000001742ab8002cf06000000000000220020c5481c75590a8898f53a6a991b66c232996358dc84a62b0926aa6b6faec96bbadeedff000000000016001492902e4d84471c9e0e83fef2ef72fe3210faf16cdd1a1220 wscript 522102305d92049c25c0537cff15899fd6f402c0f5b181a31304a5ce8ef27c117eabf62103fba00a0705ca7f347c579366d13075fe8d4dabd22a9445198be75b566b3d953052ae key 03fba00a0705ca7f347c579366d13075fe8d4dabd22a9445198be75b566b3d9530
lightningd_1       | 2019-12-26T08:30:35.821Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Telling master we're about to commit...
lightningd_1       | 2019-12-26T08:30:35.821Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Sending master 1020
lightningd_1       | 2019-12-26T08:30:35.822Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-chan#1: HTLC out 268 SENT_ADD_HTLC->SENT_ADD_COMMIT
lightningd_dest_1  | 2019-12-26T08:30:35.862Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: peer_in WIRE_COMMITMENT_SIGNED
lightningd_dest_1  | 2019-12-26T08:30:35.863Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Received commit
lightningd_dest_1  | 2019-12-26T08:30:35.863Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: htlc 268: RCVD_ADD_HTLC->RCVD_ADD_COMMIT
lightningd_1       | 2019-12-26T08:30:35.861Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: ... , awaiting 1120
lightningd_1       | 2019-12-26T08:30:35.862Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Got it!
lightningd_1       | 2019-12-26T08:30:35.864Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Sending commit_sig with 0 htlc sigs
lightningd_1       | 2019-12-26T08:30:35.865Z DEBUG lightningd: Payment part 0/0 status 0
lightningd_1       | 2019-12-26T08:30:35.865Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: peer_out WIRE_COMMITMENT_SIGNED
lightningd_dest_1  | 2019-12-26T08:30:35.866Z DEBUG hsmd: Client: Received message 18 from client
lightningd_dest_1  | 2019-12-26T08:30:35.866Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: htlc added LOCAL: local 1743279 remote 16775471721
lightningd_dest_1  | 2019-12-26T08:30:35.866Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: -> local 1743279 remote 16775470721
lightningd_dest_1  | 2019-12-26T08:30:35.866Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: rcvd_commit: HTLC REMOTE 268 = RCVD_ADD_COMMIT/SENT_ADD_COMMIT
lightningd_dest_1  | 2019-12-26T08:30:35.867Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Feerates are 4000/4000
lightningd_dest_1  | 2019-12-26T08:30:35.867Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: We need 170668sat at feerate 4000 for 0 untrimmed htlcs: we have 16775470721msat/16775471721msat
lightningd_dest_1  | 2019-12-26T08:30:35.867Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Derived key 02e95463ea71b58062ae66cd8404fc887c1702cf4cf298ce40b5b1947c10d52dd9 from basepoint 023f414bc16cd032e1551c6bbfae09ed59d42668fb5c9b566b89d29b5ba001c9a3, point 03f37df43b953ac8a095f0d9a0e7459090127de13c6dbcb07a7046d0936fd69078
lightningd_dest_1  | 2019-12-26T08:30:35.867Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Received commit_sig with 0 htlc sigs
lightningd_dest_1  | 2019-12-26T08:30:35.868Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Sending revoke_and_ack
lightningd_dest_1  | 2019-12-26T08:30:35.868Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: htlc 268: RCVD_ADD_COMMIT->SENT_ADD_REVOCATION
lightningd_dest_1  | 2019-12-26T08:30:35.868Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: sending_revoke_and_ack: HTLC REMOTE 268 = SENT_ADD_REVOCATION/RCVD_ADD_REVOCATION
lightningd_dest_1  | 2019-12-26T08:30:35.869Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: revoke_and_ack made pending: commit timer
lightningd_dest_1  | 2019-12-26T08:30:35.869Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Sending master 1021
lightningd_dest_1  | 2019-12-26T08:30:35.869Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-chan#1: got commitsig 537: feerate 4000, 1 added, 0 fulfilled, 0 failed, 0 changed
lightningd_dest_1  | 2019-12-26T08:30:35.873Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-chan#1: Adding their HTLC 268
lightningd_dest_1  | 2019-12-26T08:30:35.873Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-chan#1: HTLC in 268 RCVD_ADD_COMMIT->SENT_ADD_REVOCATION
lightningd_dest_1  | 2019-12-26T08:30:35.902Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: ... , awaiting 1121
lightningd_dest_1  | 2019-12-26T08:30:35.904Z DEBUG hsmd: Client: Received message 19 from client
lightningd_dest_1  | 2019-12-26T08:30:35.904Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Got it!
lightningd_dest_1  | 2019-12-26T08:30:35.904Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: peer_out WIRE_REVOKE_AND_ACK
lightningd_dest_1  | 2019-12-26T08:30:35.904Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Trying commit
lightningd_dest_1  | 2019-12-26T08:30:35.905Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: htlc 268: SENT_ADD_REVOCATION->SENT_ADD_ACK_COMMIT
lightningd_dest_1  | 2019-12-26T08:30:35.905Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: htlc added REMOTE: local 1743279 remote 16775471721
lightningd_dest_1  | 2019-12-26T08:30:35.905Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: -> local 1743279 remote 16775470721
lightningd_dest_1  | 2019-12-26T08:30:35.905Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: sending_commit: HTLC REMOTE 268 = SENT_ADD_ACK_COMMIT/RCVD_ADD_ACK_COMMIT
lightningd_dest_1  | 2019-12-26T08:30:35.905Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Creating commit_sig signature 537 304402200292abcf3eb0d36a84bb8e3af7d5ea744f7c37e45af14ed0b2a3f8c49ac5f0aa02201cdb0f6b528cf2ba5e569f963ae99ef438e04ac23bbaceb37e7783322431245901 for tx 0200000001b399e7d4320d1e9591432cd40a39f07e4696d898028d0415436c52ffc293a5b600000000001742ab8002cf060000000000001600142a08b0bea717615a09590719e1f7827b23d7fa5fdeedff000000000022002079600b7935f44b7b19dae6e7d33a05e312c70913f4831dd0952f9cd93d31ea3add1a1220 wscript 522102305d92049c25c0537cff15899fd6f402c0f5b181a31304a5ce8ef27c117eabf62103fba00a0705ca7f347c579366d13075fe8d4dabd22a9445198be75b566b3d953052ae key 02305d92049c25c0537cff15899fd6f402c0f5b181a31304a5ce8ef27c117eabf6
lightningd_dest_1  | 2019-12-26T08:30:35.905Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Telling master we're about to commit...
lightningd_dest_1  | 2019-12-26T08:30:35.905Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Sending master 1020
lightningd_dest_1  | 2019-12-26T08:30:35.906Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-chan#1: HTLC in 268 SENT_ADD_REVOCATION->SENT_ADD_ACK_COMMIT
lightningd_1       | 2019-12-26T08:30:35.903Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: peer_in WIRE_REVOKE_AND_ACK
lightningd_1       | 2019-12-26T08:30:35.903Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Received revoke_and_ack
lightningd_1       | 2019-12-26T08:30:35.903Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: htlc 268: SENT_ADD_COMMIT->RCVD_ADD_REVOCATION
lightningd_1       | 2019-12-26T08:30:35.903Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: rcvd_revoke_and_ack: HTLC LOCAL 268 = RCVD_ADD_REVOCATION/SENT_ADD_REVOCATION
lightningd_1       | 2019-12-26T08:30:35.903Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Commits outstanding after recv revoke_and_ack
lightningd_1       | 2019-12-26T08:30:35.904Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: HTLC 268[LOCAL] => RCVD_ADD_REVOCATION
lightningd_1       | 2019-12-26T08:30:35.904Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Sending master 1022
lightningd_1       | 2019-12-26T08:30:35.904Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-chan#1: got revoke 536: 1 changed
lightningd_1       | 2019-12-26T08:30:35.904Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-chan#1: HTLC out 268 SENT_ADD_COMMIT->RCVD_ADD_REVOCATION
lightningd_dest_1  | 2019-12-26T08:30:35.928Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: ... , awaiting 1120
lightningd_dest_1  | 2019-12-26T08:30:35.929Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Got it!
lightningd_dest_1  | 2019-12-26T08:30:35.929Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Sending commit_sig with 0 htlc sigs
lightningd_dest_1  | 2019-12-26T08:30:35.929Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: peer_out WIRE_COMMITMENT_SIGNED
lightningd_1       | 2019-12-26T08:30:35.932Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: ... , awaiting 1122
lightningd_1       | 2019-12-26T08:30:35.933Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Got it!
lightningd_1       | 2019-12-26T08:30:35.933Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: revoke_and_ack LOCAL: remote_per_commit = 03cc16b6491af1b0e3a918cfe3dcb3f5f4bbf8015ef792c30866605aa486afd418, old_remote_per_commit = 03f37df43b953ac8a095f0d9a0e7459090127de13c6dbcb07a7046d0936fd69078
lightningd_1       | 2019-12-26T08:30:35.933Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Trying commit
lightningd_1       | 2019-12-26T08:30:35.933Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Can't send commit: nothing to send
lightningd_1       | 2019-12-26T08:30:35.933Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: peer_in WIRE_COMMITMENT_SIGNED
lightningd_1       | 2019-12-26T08:30:35.933Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Received commit
lightningd_1       | 2019-12-26T08:30:35.933Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: htlc 268: RCVD_ADD_REVOCATION->RCVD_ADD_ACK_COMMIT
lightningd_1       | 2019-12-26T08:30:35.933Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: htlc added LOCAL: local 16775471721 remote 1743279
lightningd_1       | 2019-12-26T08:30:35.933Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: -> local 16775470721 remote 1743279
lightningd_1       | 2019-12-26T08:30:35.933Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: rcvd_commit: HTLC LOCAL 268 = RCVD_ADD_ACK_COMMIT/SENT_ADD_ACK_COMMIT
lightningd_1       | 2019-12-26T08:30:35.934Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Derived key 03c3b80586590dafe84d19f4506f494c5d221483e085cf59d57bd87b3ee8cfd2d6 from basepoint 033881f4781bf91126cfd33552c17bcb1b0e61ed8b424fdf6d36fb99e253d42fa6, point 029a28bdc82758c094bfaa81e294e311ba0d91f386864158c88d5550e107e42068
lightningd_1       | 2019-12-26T08:30:35.934Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Received commit_sig with 0 htlc sigs
lightningd_1       | 2019-12-26T08:30:35.935Z DEBUG hsmd: Client: Received message 18 from client
lightningd_1       | 2019-12-26T08:30:35.935Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Sending revoke_and_ack
lightningd_1       | 2019-12-26T08:30:35.935Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: htlc 268: RCVD_ADD_ACK_COMMIT->SENT_ADD_ACK_REVOCATION
lightningd_1       | 2019-12-26T08:30:35.935Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: sending_revoke_and_ack: HTLC LOCAL 268 = SENT_ADD_ACK_REVOCATION/RCVD_ADD_ACK_REVOCATION
lightningd_1       | 2019-12-26T08:30:35.935Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Sending master 1021
lightningd_1       | 2019-12-26T08:30:35.935Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-chan#1: got commitsig 537: feerate 4000, 0 added, 0 fulfilled, 0 failed, 1 changed
lightningd_1       | 2019-12-26T08:30:35.935Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-chan#1: HTLC out 268 RCVD_ADD_REVOCATION->RCVD_ADD_ACK_COMMIT
lightningd_1       | 2019-12-26T08:30:35.936Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-chan#1: HTLC out 268 RCVD_ADD_ACK_COMMIT->SENT_ADD_ACK_REVOCATION
lightningd_1       | 2019-12-26T08:30:35.955Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: ... , awaiting 1121
lightningd_1       | 2019-12-26T08:30:35.956Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Got it!
lightningd_1       | 2019-12-26T08:30:35.956Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: peer_out WIRE_REVOKE_AND_ACK
lightningd_dest_1  | 2019-12-26T08:30:35.956Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: peer_in WIRE_REVOKE_AND_ACK
lightningd_dest_1  | 2019-12-26T08:30:35.956Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Received revoke_and_ack
lightningd_dest_1  | 2019-12-26T08:30:35.956Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: htlc 268: SENT_ADD_ACK_COMMIT->RCVD_ADD_ACK_REVOCATION
lightningd_dest_1  | 2019-12-26T08:30:35.956Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: rcvd_revoke_and_ack: HTLC REMOTE 268 = RCVD_ADD_ACK_REVOCATION/SENT_ADD_ACK_REVOCATION
lightningd_dest_1  | 2019-12-26T08:30:35.956Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: No commits outstanding after recv revoke_and_ack
lightningd_dest_1  | 2019-12-26T08:30:35.956Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: HTLC 268[REMOTE] => RCVD_ADD_ACK_REVOCATION
lightningd_dest_1  | 2019-12-26T08:30:35.957Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Sending master 1022
lightningd_dest_1  | 2019-12-26T08:30:35.957Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-chan#1: got revoke 536: 1 changed
lightningd_dest_1  | 2019-12-26T08:30:35.957Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-chan#1: HTLC in 268 SENT_ADD_ACK_COMMIT->RCVD_ADD_ACK_REVOCATION
lightningd_dest_1  | 2019-12-26T08:30:35.957Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-chan#1: Expiry cltv too soon 304 < 295 + 10
lightningd_dest_1  | 2019-12-26T08:30:35.957Z INFO 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-chan#1: failed htlc 268 code 0x400f (WIRE_INCORRECT_OR_UNKNOWN_PAYMENT_DETAILS)
lightningd_dest_1  | 2019-12-26T08:30:35.957Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-chan#1: HTLC in 268 RCVD_ADD_ACK_REVOCATION->SENT_REMOVE_HTLC
lightningd_dest_1  | 2019-12-26T08:30:35.957Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-chan#1: their htlc 268 locked
lightningd_dest_1  | 2019-12-26T08:30:35.974Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: ... , awaiting 1122
lightningd_dest_1  | 2019-12-26T08:30:35.975Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Nope, got 1006 instead
lightningd_dest_1  | 2019-12-26T08:30:35.976Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Got it!
lightningd_dest_1  | 2019-12-26T08:30:35.976Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: revoke_and_ack REMOTE: remote_per_commit = 021fdbe2d17f3f99306c284f3a273300e62485e9615633046227f4ab1113a8ea9c, old_remote_per_commit = 029a28bdc82758c094bfaa81e294e311ba0d91f386864158c88d5550e107e42068
lightningd_dest_1  | 2019-12-26T08:30:35.976Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Now dealing with deferred WIRE_CHANNEL_FAIL_HTLC
lightningd_dest_1  | 2019-12-26T08:30:35.976Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: FAIL:: HTLC REMOTE 268 = SENT_REMOVE_HTLC/RCVD_REMOVE_HTLC
lightningd_dest_1  | 2019-12-26T08:30:35.976Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: peer_out WIRE_UPDATE_FAIL_HTLC
lightningd_dest_1  | 2019-12-26T08:30:35.976Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Trying commit
lightningd_dest_1  | 2019-12-26T08:30:35.976Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: htlc 268: SENT_REMOVE_HTLC->SENT_REMOVE_COMMIT
lightningd_dest_1  | 2019-12-26T08:30:35.976Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: htlc added REMOTE: local 1743279 remote 16775470721
lightningd_dest_1  | 2019-12-26T08:30:35.976Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: -> local 1743279 remote 16775471721
lightningd_dest_1  | 2019-12-26T08:30:35.976Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: sending_commit: HTLC REMOTE 268 = SENT_REMOVE_COMMIT/RCVD_REMOVE_COMMIT FAILCODE:16399
lightningd_1       | 2019-12-26T08:30:35.977Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: peer_in WIRE_UPDATE_FAIL_HTLC
lightningd_1       | 2019-12-26T08:30:35.977Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: FAIL:: HTLC LOCAL 268 = RCVD_REMOVE_HTLC/SENT_REMOVE_HTLC
lightningd_dest_1  | 2019-12-26T08:30:35.978Z DEBUG hsmd: Client: Received message 19 from client
lightningd_dest_1  | 2019-12-26T08:30:35.978Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Creating commit_sig signature 538 304402203e160d8509434befb60bf8dca714940d02d93189cbfdd588864d2304c56e357d022059075d071dfb18ee3022112fe96aeaf61964ecf92363530741eeb0fde7b16ee801 for tx 0200000001b399e7d4320d1e9591432cd40a39f07e4696d898028d0415436c52ffc293a5b600000000001742ab8002cf060000000000001600142a08b0bea717615a09590719e1f7827b23d7fa5fdfedff0000000000220020de57e85bc3c2338dea6a1ea64e0e053c52538a18c33ab73483c9072644c3d344de1a1220 wscript 522102305d92049c25c0537cff15899fd6f402c0f5b181a31304a5ce8ef27c117eabf62103fba00a0705ca7f347c579366d13075fe8d4dabd22a9445198be75b566b3d953052ae key 02305d92049c25c0537cff15899fd6f402c0f5b181a31304a5ce8ef27c117eabf6
lightningd_dest_1  | 2019-12-26T08:30:35.979Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Telling master we're about to commit...
lightningd_dest_1  | 2019-12-26T08:30:35.979Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Sending master 1020
lightningd_dest_1  | 2019-12-26T08:30:35.979Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-chan#1: HTLC in 268 SENT_REMOVE_HTLC->SENT_REMOVE_COMMIT
lightningd_1       | 2019-12-26T08:30:35.987Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Trying commit
lightningd_1       | 2019-12-26T08:30:35.988Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Can't send commit: nothing to send
lightningd_dest_1  | 2019-12-26T08:30:36.006Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: ... , awaiting 1120
lightningd_dest_1  | 2019-12-26T08:30:36.007Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Got it!
lightningd_dest_1  | 2019-12-26T08:30:36.007Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Sending commit_sig with 0 htlc sigs
lightningd_dest_1  | 2019-12-26T08:30:36.007Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: peer_out WIRE_COMMITMENT_SIGNED
lightningd_1       | 2019-12-26T08:30:36.019Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: peer_in WIRE_COMMITMENT_SIGNED
lightningd_1       | 2019-12-26T08:30:36.020Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Received commit
lightningd_1       | 2019-12-26T08:30:36.020Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: htlc 268: RCVD_REMOVE_HTLC->RCVD_REMOVE_COMMIT
lightningd_1       | 2019-12-26T08:30:36.020Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: htlc added LOCAL: local 16775470721 remote 1743279
lightningd_1       | 2019-12-26T08:30:36.020Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: -> local 16775471721 remote 1743279
lightningd_1       | 2019-12-26T08:30:36.020Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: rcvd_commit: HTLC LOCAL 268 = RCVD_REMOVE_COMMIT/SENT_REMOVE_COMMIT FAILED
lightningd_1       | 2019-12-26T08:30:36.020Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Derived key 03231e6ef0960e1e5e0ae10348bad37aab08e4e497b3e94c38dad271789b79a53e from basepoint 033881f4781bf91126cfd33552c17bcb1b0e61ed8b424fdf6d36fb99e253d42fa6, point 021fdbe2d17f3f99306c284f3a273300e62485e9615633046227f4ab1113a8ea9c
lightningd_1       | 2019-12-26T08:30:36.020Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Received commit_sig with 0 htlc sigs
lightningd_1       | 2019-12-26T08:30:36.022Z DEBUG hsmd: Client: Received message 18 from client
lightningd_1       | 2019-12-26T08:30:36.022Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Sending revoke_and_ack
lightningd_1       | 2019-12-26T08:30:36.022Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: htlc 268: RCVD_REMOVE_COMMIT->SENT_REMOVE_REVOCATION
lightningd_1       | 2019-12-26T08:30:36.022Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: sending_revoke_and_ack: HTLC LOCAL 268 = SENT_REMOVE_REVOCATION/RCVD_REMOVE_REVOCATION FAILED
lightningd_1       | 2019-12-26T08:30:36.022Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: revoke_and_ack made pending: commit timer
lightningd_1       | 2019-12-26T08:30:36.022Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Sending master 1021
lightningd_1       | 2019-12-26T08:30:36.022Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-chan#1: got commitsig 538: feerate 4000, 0 added, 0 fulfilled, 1 failed, 0 changed
lightningd_1       | 2019-12-26T08:30:36.022Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-chan#1: HTLC out 268 SENT_ADD_ACK_REVOCATION->RCVD_REMOVE_COMMIT
lightningd_1       | 2019-12-26T08:30:36.022Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-chan#1: Our HTLC 268 failed (0)
lightningd_1       | 2019-12-26T08:30:36.022Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-chan#1: HTLC out 268 RCVD_REMOVE_COMMIT->SENT_REMOVE_REVOCATION
lightningd_dest_1  | 2019-12-26T08:30:36.042Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: peer_in WIRE_REVOKE_AND_ACK
lightningd_1       | 2019-12-26T08:30:36.041Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: ... , awaiting 1121
lightningd_1       | 2019-12-26T08:30:36.041Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Got it!
lightningd_1       | 2019-12-26T08:30:36.041Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: peer_out WIRE_REVOKE_AND_ACK
lightningd_1       | 2019-12-26T08:30:36.041Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Trying commit
lightningd_1       | 2019-12-26T08:30:36.041Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: htlc 268: SENT_REMOVE_REVOCATION->SENT_REMOVE_ACK_COMMIT
lightningd_1       | 2019-12-26T08:30:36.041Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: htlc added REMOTE: local 16775470721 remote 1743279
lightningd_1       | 2019-12-26T08:30:36.041Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: -> local 16775471721 remote 1743279
lightningd_1       | 2019-12-26T08:30:36.041Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: sending_commit: HTLC LOCAL 268 = SENT_REMOVE_ACK_COMMIT/RCVD_REMOVE_ACK_COMMIT FAILED
lightningd_dest_1  | 2019-12-26T08:30:36.042Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Received revoke_and_ack
lightningd_dest_1  | 2019-12-26T08:30:36.043Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: htlc 268: SENT_REMOVE_COMMIT->RCVD_REMOVE_REVOCATION
lightningd_1       | 2019-12-26T08:30:36.046Z DEBUG hsmd: Client: Received message 19 from client
lightningd_1       | 2019-12-26T08:30:36.046Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Creating commit_sig signature 538 304402200d820edcd2ad73d140812124e46de7bde1984587c9ad50330bcf080970673c03022008b740eeedbb54f9bd141b4b82507f22b90b487c248abe1bc23d3c33b1beb36901 for tx 0200000001b399e7d4320d1e9591432cd40a39f07e4696d898028d0415436c52ffc293a5b600000000001742ab8002cf060000000000002200207a923d7707481edf5cc6335b566b71359d634450fbe5cf86bc18891b90766b60dfedff000000000016001492902e4d84471c9e0e83fef2ef72fe3210faf16cde1a1220 wscript 522102305d92049c25c0537cff15899fd6f402c0f5b181a31304a5ce8ef27c117eabf62103fba00a0705ca7f347c579366d13075fe8d4dabd22a9445198be75b566b3d953052ae key 03fba00a0705ca7f347c579366d13075fe8d4dabd22a9445198be75b566b3d9530
lightningd_1       | 2019-12-26T08:30:36.046Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Telling master we're about to commit...
lightningd_1       | 2019-12-26T08:30:36.047Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Sending master 1020
lightningd_1       | 2019-12-26T08:30:36.047Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-chan#1: HTLC out 268 SENT_REMOVE_REVOCATION->SENT_REMOVE_ACK_COMMIT
lightningd_dest_1  | 2019-12-26T08:30:36.043Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: rcvd_revoke_and_ack: HTLC REMOTE 268 = RCVD_REMOVE_REVOCATION/SENT_REMOVE_REVOCATION FAILCODE:16399
lightningd_dest_1  | 2019-12-26T08:30:36.043Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Commits outstanding after recv revoke_and_ack
lightningd_dest_1  | 2019-12-26T08:30:36.044Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: HTLC 268[REMOTE] => RCVD_REMOVE_REVOCATION
lightningd_dest_1  | 2019-12-26T08:30:36.044Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Sending master 1022
lightningd_dest_1  | 2019-12-26T08:30:36.044Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-chan#1: got revoke 537: 1 changed
lightningd_dest_1  | 2019-12-26T08:30:36.044Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-chan#1: HTLC in 268 SENT_REMOVE_COMMIT->RCVD_REMOVE_REVOCATION
lightningd_1       | 2019-12-26T08:30:36.076Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: ... , awaiting 1120
lightningd_1       | 2019-12-26T08:30:36.076Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Got it!
lightningd_1       | 2019-12-26T08:30:36.076Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Sending commit_sig with 0 htlc sigs
lightningd_1       | 2019-12-26T08:30:36.076Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: peer_out WIRE_COMMITMENT_SIGNED
lightningd_dest_1  | 2019-12-26T08:30:36.080Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: ... , awaiting 1122
lightningd_dest_1  | 2019-12-26T08:30:36.081Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Got it!
lightningd_dest_1  | 2019-12-26T08:30:36.081Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: revoke_and_ack REMOTE: remote_per_commit = 02a2dce31d66ef1f5c380c4f2b03750e52e136270d7ea3183d93fa993f34b6f173, old_remote_per_commit = 021fdbe2d17f3f99306c284f3a273300e62485e9615633046227f4ab1113a8ea9c
lightningd_dest_1  | 2019-12-26T08:30:36.081Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Trying commit
lightningd_dest_1  | 2019-12-26T08:30:36.081Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Can't send commit: nothing to send
lightningd_dest_1  | 2019-12-26T08:30:36.090Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: peer_in WIRE_COMMITMENT_SIGNED
lightningd_dest_1  | 2019-12-26T08:30:36.090Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Received commit
lightningd_dest_1  | 2019-12-26T08:30:36.090Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: htlc 268: RCVD_REMOVE_REVOCATION->RCVD_REMOVE_ACK_COMMIT
lightningd_dest_1  | 2019-12-26T08:30:36.090Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: htlc added LOCAL: local 1743279 remote 16775470721
lightningd_dest_1  | 2019-12-26T08:30:36.090Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: -> local 1743279 remote 16775471721
lightningd_dest_1  | 2019-12-26T08:30:36.090Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: rcvd_commit: HTLC REMOTE 268 = RCVD_REMOVE_ACK_COMMIT/SENT_REMOVE_ACK_COMMIT FAILCODE:16399
lightningd_dest_1  | 2019-12-26T08:30:36.090Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Feerates are 4000/4000
lightningd_dest_1  | 2019-12-26T08:30:36.090Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: We need 170668sat at feerate 4000 for 0 untrimmed htlcs: we have 16775471721msat/16775471721msat
lightningd_dest_1  | 2019-12-26T08:30:36.091Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Derived key 0222ee1072941af8664bb6e159a47d6e5b5f5bed888c90c9fe5343ef61b1f718b8 from basepoint 023f414bc16cd032e1551c6bbfae09ed59d42668fb5c9b566b89d29b5ba001c9a3, point 03cc16b6491af1b0e3a918cfe3dcb3f5f4bbf8015ef792c30866605aa486afd418
lightningd_dest_1  | 2019-12-26T08:30:36.092Z DEBUG hsmd: Client: Received message 18 from client
lightningd_dest_1  | 2019-12-26T08:30:36.092Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Received commit_sig with 0 htlc sigs
lightningd_dest_1  | 2019-12-26T08:30:36.092Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Sending revoke_and_ack
lightningd_dest_1  | 2019-12-26T08:30:36.092Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: htlc 268: RCVD_REMOVE_ACK_COMMIT->SENT_REMOVE_ACK_REVOCATION
lightningd_dest_1  | 2019-12-26T08:30:36.092Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: sending_revoke_and_ack: HTLC REMOTE 268 = SENT_REMOVE_ACK_REVOCATION/RCVD_REMOVE_ACK_REVOCATION FAILCODE:16399
lightningd_dest_1  | 2019-12-26T08:30:36.092Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Sending master 1021
lightningd_dest_1  | 2019-12-26T08:30:36.092Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-chan#1: got commitsig 538: feerate 4000, 0 added, 0 fulfilled, 0 failed, 1 changed
lightningd_dest_1  | 2019-12-26T08:30:36.092Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-chan#1: HTLC in 268 RCVD_REMOVE_REVOCATION->RCVD_REMOVE_ACK_COMMIT
lightningd_dest_1  | 2019-12-26T08:30:36.092Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-chan#1: HTLC in 268 RCVD_REMOVE_ACK_COMMIT->SENT_REMOVE_ACK_REVOCATION
lightningd_dest_1  | 2019-12-26T08:30:36.092Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-chan#1: Removing in HTLC 268 state SENT_REMOVE_ACK_REVOCATION WIRE_INCORRECT_OR_UNKNOWN_PAYMENT_DETAILS
lightningd_dest_1  | 2019-12-26T08:30:36.135Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: ... , awaiting 1121
lightningd_dest_1  | 2019-12-26T08:30:36.136Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: Got it!
lightningd_dest_1  | 2019-12-26T08:30:36.136Z DEBUG 028bd79a39a7cb162f8afde966f37ba9ef9e8618b7aa24992e8e6c36a060141532-channeld-chan#1: peer_out WIRE_REVOKE_AND_ACK
lightningd_1       | 2019-12-26T08:30:36.136Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: peer_in WIRE_REVOKE_AND_ACK
lightningd_1       | 2019-12-26T08:30:36.136Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Received revoke_and_ack
lightningd_1       | 2019-12-26T08:30:36.137Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: htlc 268: SENT_REMOVE_ACK_COMMIT->RCVD_REMOVE_ACK_REVOCATION
lightningd_1       | 2019-12-26T08:30:36.137Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: rcvd_revoke_and_ack: HTLC LOCAL 268 = RCVD_REMOVE_ACK_REVOCATION/SENT_REMOVE_ACK_REVOCATION FAILED
lightningd_1       | 2019-12-26T08:30:36.138Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: No commits outstanding after recv revoke_and_ack
lightningd_1       | 2019-12-26T08:30:36.138Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: HTLC 268[LOCAL] => RCVD_REMOVE_ACK_REVOCATION
lightningd_1       | 2019-12-26T08:30:36.138Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Sending master 1022
lightningd_1       | 2019-12-26T08:30:36.138Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-chan#1: got revoke 537: 1 changed
lightningd_1       | 2019-12-26T08:30:36.138Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-chan#1: HTLC out 268 SENT_REMOVE_ACK_COMMIT->RCVD_REMOVE_ACK_REVOCATION
lightningd_1       | 2019-12-26T08:30:36.139Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-chan#1: Removing out HTLC 268 state RCVD_REMOVE_ACK_REVOCATION REMOTEFAIL
lightningd_1       | 2019-12-26T08:30:36.139Z INFO 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-chan#1: htlc 268 failed from 0th node with code 0x400f (WIRE_INCORRECT_OR_UNKNOWN_PAYMENT_DETAILS)
lightningd_1       | 2019-12-26T08:30:36.139Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-chan#1: failmsg: 400f00000000000003e800000127
lightningd_1       | 2019-12-26T08:30:36.153Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: ... , awaiting 1122
lightningd_1       | 2019-12-26T08:30:36.155Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Got it!
lightningd_1       | 2019-12-26T08:30:36.156Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: revoke_and_ack LOCAL: remote_per_commit = 038427d7c729e383686aa60892984f25d1dd77b2576a865999b28cb6d851123d9f, old_remote_per_commit = 03cc16b6491af1b0e3a918cfe3dcb3f5f4bbf8015ef792c30866605aa486afd418
lightningd_1       | 2019-12-26T08:30:36.158Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Trying commit
lightningd_1       | 2019-12-26T08:30:36.159Z DEBUG 0315f7d8d1042bf44b62ce0871060b8f948018f774b0f8ef71ffb0a9c26477ecdf-channeld-chan#1: Can't send commit: nothing to send
lightningd_1       | 2019-12-26T08:30:36.244Z DEBUG lightningd: Adding block 294: 227875bc898c7724f1544d1fad0c6937fe023df77f9eec430287fab18ffd3e60
lightningd_1       | 2019-12-26T08:30:36.382Z DEBUG lightningd: Adding block 295: 585b338f6466be74c1375b1726c1c728c606690183ead9233bd3a22d074446f1
lightningd_dest_1  | 2019-12-26T08:30:36.415Z UNUSUAL lightningd: Unable to estimate CONSERVATIVE/2 fee
lightningd_dest_1  | 2019-12-26T08:30:36.444Z UNUSUAL lightningd: Unable to estimate ECONOMICAL/4 fee
lightningd_dest_1  | 2019-12-26T08:30:36.489Z UNUSUAL lightningd: Unable to estimate ECONOMICAL/100 fee
lightningd_1       | 2019-12-26T08:30:36.703Z UNUSUAL lightningd: Unable to estimate CONSERVATIVE/2 fee
lightningd_1       | 2019-12-26T08:30:36.720Z UNUSUAL lightningd: Unable to estimate ECONOMICAL/4 fee

@ZmnSCPxj
Copy link
Collaborator Author

ZmnSCPxj commented Dec 26, 2019

The message will only appear in listpays or paystatus commands, and will not appear in logs.

@NicolasDorier
Copy link
Collaborator

Let me know if I can do anything else.

@ZmnSCPxj
Copy link
Collaborator Author

Well I am reimplementing it yet again, thanks anyway for the quick feedback.

@ZmnSCPxj
Copy link
Collaborator Author

Hi @NicolasDorier another set of commits for evaluation, note that you need to remove the previous patch completely and re-apply this set.

@NicolasDorier
Copy link
Collaborator

@ZmnSCPxj It seems to have fixed it. Ran the test in a 30 times loop and it passed.

@ZmnSCPxj
Copy link
Collaborator Author

Thanks for your patience and cooperation. Hopefully that unblocks 0.8.0 for your software. For now I will be unavailable for some time so will take this out of draft status even if I have not been able to derive a decent test.

@ZmnSCPxj ZmnSCPxj marked this pull request as ready for review December 27, 2019 08:00
@NicolasDorier
Copy link
Collaborator

NicolasDorier commented Dec 27, 2019

I wait for ACK and merge, and will add that to my branch!

EDIT: Actually I will just push that to my branch now, thanks a lot for fixing it!

@cdecker
Copy link
Member

cdecker commented Dec 27, 2019

Slowly going through this thread 😉

@rustyrussell @cdecker @niftynei the CI "just" needs some re-kicking, it
seems failure is only timeout due to valgrind being slow. For some reason I
can no longer restart the travis-ci for any ElementsProject/lightning PRs
myself, I used to be able to do that, maybe some permissions problem?

Could be an issue with Travis migrating some things lately, I think they're
going for a tighter integration with Github Actions and the travis.com product
(while the open-source variant was travis.org). I'll look into it, and see if
I can find out what went wrong.

Create two nodes, sharing the same bitcoind, connect them together.

Then create 6 block and imediately after create an invoice and pay it. If
you repeat that enough time, eventually you'll get the repro. (10 times
should be enough)

We can do this slightly better: in the test framework the lightningds don't
talk directly to the backing bitcoind but rather to a BitcoinRpcProxy
reverse proxy written in python. The way to set this up would be to have one
proxy instance mock out the call to getblockhash and return an empty result
above the height that the node should be stuck at. Then you can wait for both
nodes to sync up to their desired heights and start testing. No need to race
and accidentally reproduce the issue.

Unfortunately our test environment tends to strongly synchronize all nodes to
bitcoind, so it may require a lot more loops to reproduce. In any case I
have a test I am trying out in
https://github.com/ZmnSCPxj/lightning/tree/blockheight-disagreement-try which
should fail (it does not include the fix) to check that the test actually does
tickle the bug.

See above 😉

@NicolasDorier
Copy link
Collaborator

NicolasDorier commented Dec 28, 2019

Strange, it seems I got this problem again.

So I have two layers of tests, on on my lightning client library, where I could reproduce the error which seemed to be fixed by this PR, and I can't reproduce it there anymore.

The other layer of tests is at btcpayserver level, and it seems I still have the error... It does not make sense this happen only on btcpayserver tests.
Will poke around trying to find what happen, it may be my fault as well.

@ZmnSCPxj
Copy link
Collaborator Author

ZmnSCPxj commented Jan 4, 2020

Rebased and added new test as per @cdecker recommendation. The test reliably fails before the fix introduced in this PR: https://travis-ci.org/ZmnSCPxj/lightning/jobs/632554191#L1343 and is thus presumed to replicate the reported scenario.

@NicolasDorier how is replication of this test/fix at btcpayserver level?

@NicolasDorier
Copy link
Collaborator

NicolasDorier commented Jan 6, 2020

@ZmnSCPxj I executed the tests again. Does not happen, it is weird, I will blame some caching or timing issue on my side.

Did not code review, but this fixed my issue.

@ZmnSCPxj
Copy link
Collaborator Author

ZmnSCPxj commented Jan 6, 2020

Okay, pending ACK from @cdecker @niftynei or @rustyrussell then.

@NicolasDorier
Copy link
Collaborator

NicolasDorier commented Jan 11, 2020

@ZmnSCPxj so this bug still appear but very rarely.

It happens sometimes when paying an invoice when I create a channel between two peers before running my actual tests.

The specificity of this step is that a bunch of block may be mined in relatively short period or time (say 6 blocks at once or more).
My actual tests which mine only a single block at a time are running reliably.

So I imagine you are retrying only a single time.

Would it be a bad idea for me to just retry on my client a payment when I see this error?

I don't think this is a blocker for this PR. This case probably don't happen in mainnet.

@ZmnSCPxj
Copy link
Collaborator Author

The specificity of this step is that a bunch of block may be mined in relatively short period or time (say 6 blocks at once or more).
My actual tests which mine only a single block at a time are running reliably.

So I imagine you are retrying only a single time.

The payer should wait until it reaches what the payee reports as blockheight in the failure message (i.e. waitblockheight), and then pay; and it uses the normal retry mechanism, which is basically "try-and-try-until-you-die" i.e. does not care why we retried and does not put limits on how many times it retries unless you reach the pay timeout. So this should not happen. Unfortunately pay does not log a lot of information in the lightningd log, you should be able to see what happened with a paystatus though.

@NicolasDorier
Copy link
Collaborator

@ZmnSCPxj then something strange. I saw it happening in some of my tests.

I ended up doing this hack https://github.com/btcpayserver/BTCPayServer.Lightning/blob/master/src/BTCPayServer.Lightning.All/Tests/ConnectChannels.cs#L93 in my code where I open a channel for my tests. Now everything works fine and reliably.

This PR is fixing things for sure as this was happening before even during 1 block disagreement.

@cdecker
Copy link
Member

cdecker commented Jan 13, 2020

The code seems pretty straightforward, but I'm wondering if a more direct approach isn't more desirable: return an error indicating that the user may need to wait instead of automatically waiting for the blockheight to be reached. The pay statemachine is already incredibly complex as it is, and I'm worried that yet another failure case being handled automagically (especially such a rare one, because remember this is highly unlikely to happen on mainnet) is not adding much benefit, but quite some complexity that is to be maintained.

In addition I don't think that waitblockheight should be exposed at all, since we can just poll getinfo to get the same effect.

@ZmnSCPxj
Copy link
Collaborator Author

ZmnSCPxj commented Jan 13, 2020

The code seems pretty straightforward, but I'm wondering if a more direct approach isn't more desirable: return an error indicating that the user may need to wait instead of automatically waiting for the blockheight to be reached.

Presumably the user is interested in getting the payment through, and rare and random failures pointing to incorrect_or_unknown_payment_details are just future maintenance burdens, ultimately you would tell them "just retry when this error occurs" anyway.

If the user wanted to handle retrying by themselves, they are welcome to use sendpay.

In any case, before lightning/bolts@6729755 this would not have been an issue, since that failure would have been a temporary failure at the payee and we would have just spun using the classic loop.

The Real Issue is the merging of a temporary failure into a permanent one at the BOLT spec, which leads to this problem. Since there are good reasons for that merge, we need to adapt to the BOLT spec change. Otherwise this is a real regression, one that used not to happen before lightning/bolts@6729755 .

In addition I don't think that waitblockheight should be exposed at all, since we can just poll getinfo to get the same effect.

I suggest still exposing waitblockheight, even if in a separate plugin using a getinfo poll-loop. Having lots of small RPC commands we can easily string together seems better than pushing even more complexity into single large monolothic plugins. For example, we should probably move getshadowroute to a separate plugin instead of putting the shadow-route-insertion code in pay; pay calls getshadowroute with a routehint or destination, getshadowroute returns a possibly-increased amount and final_cltv, then pay continues with its getroute-sendpay loop. That should be even easier to maintain, and it would allow us to build alternative payment strategy plugins based on lots of small commands that are strung together by a pay replacement. This would help make pay replacements be easier to implement: you just call the bits of pay you want to keep, and replace only the little bits of code you want to alter, without having to reimplement most of pay.

Worse, due to how libplugin is currently structured, a getinfo loop cannot sleep between calls to getinfo, and since getinfo is a fast command that immediately succeds, you can expect a high CPU load if you do not sleep between getinfo calls, so putting waitblockheight at lightningd where it can get into the block-broadcasting loop instead of wasting CPU load. Or make block-arrived notification, but again the current libplugin cannot have a plugin-implemented command block on a notification, we need some kind of inter-thread (where thread = processing for a notification / hook / command, not a "real" OS-level thread) communication for libplugin first.

Comment on lines +820 to +976
getstartblockheight_error(struct command *cmd,
const char *buf,
const jsmntok_t *error,
struct pay_command *pc)
{
/* Should never happen. */
plugin_err("getstartblockheight: getinfo failed!? '%.*s'",
error->end - error->start, buf);
}
Copy link
Member

Choose a reason for hiding this comment

The reason will be displayed to describe this comment to others. Learn more.

This likely needs to fail the pay_attempt otherwise we're leaking memory.

Copy link
Collaborator Author

Choose a reason for hiding this comment

The reason will be displayed to describe this comment to others. Learn more.

plugin_err leads to the plugin dying, meaning all its memory will disappear.

plugins/pay.c Outdated Show resolved Hide resolved
ZmnSCPxj and others added 6 commits January 15, 2020 16:04
This is needed to fully implement handling of blockheight disagreements
between us and payee.
If payee believes the blockheight is higher than ours, then `pay`
should wait for our node to achieve that blockheight.

Changelog-Add: Implement `waitblockheight` to wait for a specific blockheight.
Changelog-Fixed: Detect a previously non-permanent error (`final_cltv_too_soon`) that has been merged into a permanent error (`incorrect_or_unknown_payment_details`), and retry that failure case in `pay`.
@cdecker
Copy link
Member

cdecker commented Jan 15, 2020

The Real Issue is the merging of a temporary failure into a permanent one at the BOLT spec, which leads to this problem. Since there are good reasons for that merge, we need to adapt to the BOLT spec change. Otherwise this is a real regression, one that used not to happen before lightningnetwork/lightning-rfc@6729755 .

In that case we should address the root issue and amend the spec to introduce a TEMP variant of the unknown payment details error, rather than coding workarounds for special cases in like this imho 😉

In the meantime I think it's ok to work around the issue like it is, but we need to address the cause, not the symptoms :-)

@ZmnSCPxj
Copy link
Collaborator Author

ZmnSCPxj commented Jan 15, 2020

In that case we should address the root issue and amend the spec to introduce a TEMP variant of the unknown payment details error, rather than coding workarounds for special cases in like this imho 😉

That is what I initially wanted to do, but after a little digging I realized we simply cannot have a non-PERM variant of the unknown payment details error, since that error would basically be a rename of the old final_expiry_too_soon (it is the only failure that was merged into incorrect_or_unknown_payment_details that does not have PERM bit set), effectively reverting the merge of that failure code with the PERM incorrect_or_unknown_payment_details. The entire point of the merge was to make it much harder for a forwarding node to probe if the next hop is the final one, and break our efforts to hide it by shadow routing over there in pay: basically the prober could just create its own onion with a ridiculously low final CLTV targeting the next hop, and check if it returns incorrect_or_unknown_payment_details (the next hop is not the final payee) or final_expiry_too_soon / new non-PERM unknown payment details (the next hop is the final payee and it is pissed we gave a final expiry that was too soon).

This is a tradeoff, basically, we are closing a privacy-leaking case, at the cost of increased complexity at our implementation (but which should not reach our users, hence why we do not propagate the failure in this case and special-case its detection). I believe that privacy-preservation is important enough that we should accept complexity in our implementation: it is always easier to break privacy than to preserve it.

@ZmnSCPxj
Copy link
Collaborator Author

PING!

@cdecker
Copy link
Member

cdecker commented Jan 21, 2020

ACK 5b7c215

@cdecker cdecker merged commit a9f0f05 into ElementsProject:master Jan 21, 2020
@ZmnSCPxj ZmnSCPxj deleted the blockheight-disagreement branch January 23, 2020 05:42
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

Successfully merging this pull request may close these issues.

Spurious WIRE_INCORRECT_OR_UNKNOWN_PAYMENT_DETAILS
3 participants