Lightning: ONCHAIND_OUR_UNILATERAL

Created on 27 Jan 2018  路  5Comments  路  Source: ElementsProject/lightning

Hi !

I closed my channel with SLEEPYARK node, but after the 6 confirmations for the closing transaction the channel is "ONCHAIND_OUR_UNILATERAL" and my funds are not returned to my wallet. Any other thing i need to do besides closing the channel ?

Thanks!

root@ubuntu:~# lightning-cli listpeers

{  
   "peers":[  
      {  
         "id":"02f6725f9c1c40333b67faea92fd211c183050f28df32cac3f9d69685fe9665432",
         "connected":true,
         "netaddr":[  
            "104.198.32.198:9735"
         ],
         "channels":[  
            {  
               "state":"CHANNELD_NORMAL",
               "owner":"lightning_channeld",
               "short_channel_id":"506202:864:1",
               "funding_txid":"d5b58ff303656cd1442f07aa7562fa311dab0285cf707c2162a065ded622a5e0",
               "msatoshi_to_us":189998900,
               "msatoshi_total":200000000,
               "dust_limit_satoshis":546,
               "max_htlc_value_in_flight_msat":18446744073709551615,
               "channel_reserve_satoshis":0,
               "htlc_minimum_msat":0,
               "to_self_delay":144,
               "max_accepted_htlcs":483
            }
         ]
      }
   ]
}

root@ubuntu:/home/ubuntu# lightning-cli close 02f6725f9c1c40333b67faea92fd211c183050f28df32cac3f9d69685fe9665432
{  }

root@ubuntu:/home/ubuntu# lightning-cli listpeers

{  
   "peers":[  
      {  
         "id":"02f6725f9c1c40333b67faea92fd211c183050f28df32cac3f9d69685fe9665432",
         "connected":false,
         "channels":[  
            {  
               "state":"CLOSINGD_SIGEXCHANGE",
               "short_channel_id":"506202:864:1",
               "funding_txid":"d5b58ff303656cd1442f07aa7562fa311dab0285cf707c2162a065ded622a5e0",
               "msatoshi_to_us":189998900,
               "msatoshi_total":200000000,
               "dust_limit_satoshis":546,
               "max_htlc_value_in_flight_msat":18446744073709551615,
               "channel_reserve_satoshis":0,
               "htlc_minimum_msat":0,
               "to_self_delay":144,
               "max_accepted_htlcs":483
            }
         ]
      }
   ]
}

+6 confirmations close transaction
https://www.smartbit.com.au/tx/6d28e0e5a23c7b25b4551b78bb1bcf22dec321b118ac719842043f6eb6119a62

root@ubuntu:~# lightning-cli listpeers
{  
   "peers":[  
      {  
         "id":"02f6725f9c1c40333b67faea92fd211c183050f28df32cac3f9d69685fe9665432",
         "connected":false,
         "channels":[  
            {  
               "state":"ONCHAIND_OUR_UNILATERAL",
               "owner":"lightning_onchaind",
               "short_channel_id":"506202:864:1",
               "funding_txid":"d5b58ff303656cd1442f07aa7562fa311dab0285cf707c2162a065ded622a5e0",
               "msatoshi_to_us":189998900,
               "msatoshi_total":200000000,
               "dust_limit_satoshis":546,
               "max_htlc_value_in_flight_msat":18446744073709551615,
               "channel_reserve_satoshis":0,
               "htlc_minimum_msat":0,
               "to_self_delay":144,
               "max_accepted_htlcs":483
            }
         ]
      }
   ]
}

and this on lightningd log

lightningd(1): Adding block 506413: 0000000000000000002bcf0e5c12b8dbc75a43e41bbe938f45ed64b66a158050
lightningd(1): peer 02f6725f9c1c40333b67faea92fd211c183050f28df32cac3f9d69685fe9665432: Got depth change 40->41 for 6d28e0e5a23c7b25b4551b78bb1bcf22dec321b118ac719842043f6eb6119a62
lightning_onchaind(32172): TRACE: Got new message WIRE_ONCHAIN_DEPTH
lightning_onchaind(32172): TRACE: FUNDING_TRANSACTION/FUNDING_OUTPUT->OUR_UNILATERAL depth 41
lightning_onchaind(32172): TRACE: OUR_UNILATERAL/OUTPUT_TO_THEM->SELF depth 41
lightningd(1): Normal feerate 9930 (was 20664)
picture brunoaduarte

All 5 comments

No worries, the funds are currently being delayed since we were unable to do a mutual close and so your client decided to do a unilateral close. That means that after 144 blocks you'll get your funds added to your wallet again.

The issue that prevented a mutual close is known, and we are working to fix it. Could you search your logs for "Bad signature"? That'd help us figure out whether you are affected by this bug.

cdecker picture cdecker on 28 Jan 2018
👍1

I think this is because the to_self_delay which is a minimal amount of blocks that need to pass.
144 * ~10 minutes = ~24 hours

ronaldvdmeer picture ronaldvdmeer on 28 Jan 2018
👍1

Sure @cdecker , there's a

STATUS_FAIL_PEER_BAD: Bad closing_signed signature

here's the log from channel closing to 3rd confirmation (depth 3)

lightning_channeld(21422): TRACE: Trying commit
lightning_channeld(21422): TRACE: Can't send commit: nothing to send
lightning_channeld(21422): TRACE: peer_out WIRE_SHUTDOWN
lightningd(1):jcon fd 14: Closing (No such file or directory)
lightning_channeld(21422): TRACE: Read decrypt 0026e0a522d6de65a062217c70cf8502ab1d31fa6275aa072f44d16c6503f38fb5d400160014565f424bbf08e369561cd012b12a0623a451806e
lightning_channeld(21422): TRACE: peer_in WIRE_SHUTDOWN
lightning_channeld(21422): UPDATE WIRE_CHANNEL_GOT_SHUTDOWN
lightning_channeld(21422): UPDATE WIRE_CHANNEL_SHUTDOWN_COMPLETE
lightning_channeld(21422): UPDATE WIRE_CHANNEL_SHUTDOWN_COMPLETE
lightning_closingd(32056): pid 32056, msgfd 16
lightningd(1): Forcing fee rate, ignoring estimate
lightningd(1): Forcing fee rate, ignoring estimate
lightningd(1): Forcing fee rate, ignoring estimate
lightningd(1): peer 02f6725f9c1c40333b67faea92fd211c183050f28df32cac3f9d69685fe9665432: state: CHANNELD_SHUTTING_DOWN -> CLOSINGD_SIGEXCHANGE
lightning_closingd(32056): TRACE: satoshi_out = 189998/10002
lightning_closingd(32056): TRACE: dustlimit = 546
lightning_closingd(32056): TRACE: fee = 7602
lightning_closingd(32056): TRACE: Making close tx at = 189998/10002 fee 7602
lightning_closingd(32056): TRACE: sending fee offer 7602
lightning_closingd(32056): TRACE: Read decrypt 0027e0a522d6de65a062217c70cf8502ab1d31fa6275aa072f44d16c6503f38fb5d40000000000000b9e07b165b9696519bbae26cadd12993293e2a7fa042f336f243e464f0e70e315fb60e9c27cb907cbd7d4719966502d513e80d4a5a9b02f31f862be32ea5d2ae210
lightning_closingd(32056): TRACE: Making close tx at = 189998/10002 fee 2974
lightning_closingd(32056): TRACE: Making close tx at = 189998/0 fee 2974
lightning_closingd(32056): STATUS_FAIL_PEER_BAD: Bad closing_signed signature for 0200000001e0a522d6de65a062217c70cf8502ab1d31fa6275aa072f44d16c6503f38fb5d50100000000ffffffff021227000000000000160014565f424bbf08e369561cd012b12a0623a451806e90da02000000000016001444d084aa6aed3aa4b4c8c5d8e33a18585a7d89b400000000 (and trimmed version 0200000001e0a522d6de65a062217c70cf8502ab1d31fa6275aa072f44d16c6503f38fb5d50100000000ffffffff0190da02000000000016001444d084aa6aed3aa4b4c8c5d8e33a18585a7d89b400000000)
lightningd(1): peer 02f6725f9c1c40333b67faea92fd211c183050f28df32cac3f9d69685fe9665432: Peer permanent failure in CLOSINGD_SIGEXCHANGE: lightning_closingd: Bad closing_signed signature for 0200000001e0a522d6de65a062217c70cf8502ab1d31fa6275aa072f44d16c6503f38fb5d50100000000ffffffff021227000000000000160014565f424bbf08e369561cd012b12a0623a451806e90da02000000000016001444d084aa6aed3aa4b4c8c5d8e33a18585a7d89b400000000 (and trimmed version 0200000001e0a522d6de65a062217c70cf8502ab1d31fa6275aa072f44d16c6503f38fb5d50100000000ffffffff0190da02000000000016001444d084aa6aed3aa4b4c8c5d8e33a18585a7d89b400000000)
lightningd(1):   (tx 6d28e0e5a23c7b25b4551b78bb1bcf22dec321b118ac719842043f6eb6119a62)
lightningd(1): sendrawtransaction: 02000000000101e0a522d6de65a062217c70cf8502ab1d31fa6275aa072f44d16c6503f38fb5d50100000000c4853080021127000000000000160014ce82454ac392f7b39de84c853e6f0cb47beee91d7a9f020000000000220020b65bfbc5b80ea52e667a4f2aa654d50f49b0597f6a94a22428d7c3af380a6e56040048304502210088097e4851ccee19789468e510ec305354d6af0f0560a957a96079974cfd5bd1022065a2942865bb1668d41f7dffd58939b00e143ae26a0377e415eb3e0aa79ca5f80147304402201bb538ce0d4416a4cd8423a989f55adcf2f9189422c0b238b88a1b532f562b5702205a5abd94b0b2a461e993ac0ad93ef556d53bd58cd0aa3388dc0d41d6537c881d01475221022753ddacaba541d185ebab78cd41c78c5cb160ae92b09c6398fda885e822b7192103aca784f6ec506180a2dd8f1e3d3da2cdfdcdfa1e063191f979bfb3314857e59252ae34f2c520
lightning_gossipd(13): TRACE: Forgetting remote peer 02f6725f9c1c40333b67faea92fd211c183050f28df32cac3f9d69685fe9665432
lightningd(1): sendrawtx exit 0, gave 6d28e0e5a23c7b25b4551b78bb1bcf22dec321b118ac719842043f6eb6119a62

lightningd(1): Connected json input
lightning_gossipd(13): TRACE: req: type WIRE_GOSSIP_GETPEERS_REQUEST len 4
lightning_gossipd(13): REPLY WIRE_GOSSIP_GETPEERS_REPLY with 0 fds
lightningd(1):jcon fd 14: Success
lightningd(1):jcon fd 14: Closing (Bad file descriptor)
lightningd(1): Immediate feerate 29158 (was 40555)
lightningd(1): Normal feerate 27820 (was 29118)
lightningd(1): Connected json input
lightning_gossipd(13): TRACE: req: type WIRE_GOSSIP_GETPEERS_REQUEST len 4
lightning_gossipd(13): REPLY WIRE_GOSSIP_GETPEERS_REPLY with 0 fds
lightningd(1):jcon fd 14: Success
lightningd(1):jcon fd 14: Closing (Bad file descriptor)
lightningd(1): Connected json input
lightning_gossipd(13): TRACE: req: type WIRE_GOSSIP_GETPEERS_REQUEST len 4
lightning_gossipd(13): REPLY WIRE_GOSSIP_GETPEERS_REPLY with 0 fds
lightningd(1):jcon fd 14: Success
lightningd(1):jcon fd 14: Closing (Bad file descriptor)
lightningd(1): Connected json input
lightningd(1):jcon fd 14: Success
lightningd(1):jcon fd 14: Closing (Bad file descriptor)
lightningd(1): Connected json input
lightningd(1):jcon fd 14: Success
lightningd(1):jcon fd 14: Closing (No such file or directory)
lightningd(1): Connected json input
lightningd(1):jcon fd 14: Success
lightningd(1):jcon fd 14: Closing (No such file or directory)
lightningd(1): Connected json input
lightningd(1):jcon fd 14: Success
lightningd(1):jcon fd 14: Closing (No such file or directory)
lightningd(1): Connected json input
lightning_gossipd(13): TRACE: req: type WIRE_GOSSIP_GETPEERS_REQUEST len 4
lightning_gossipd(13): REPLY WIRE_GOSSIP_GETPEERS_REPLY with 0 fds
lightningd(1):jcon fd 14: Success
lightningd(1):jcon fd 14: Closing (No such file or directory)
lightningd(1): Connected json input
lightningd(1):jcon fd 14: Success
lightningd(1):jcon fd 14: Closing (No such file or directory)
lightningd(1): Adding block 506373: 0000000000000000000fedcedf401637bf4410adeb23b5c465a96b3a415578b2
lightningd(1): peer 02f6725f9c1c40333b67faea92fd211c183050f28df32cac3f9d69685fe9665432: Got UTXO spend for d5b58ff303656cd1442f07aa7562fa311dab0285cf707c2162a065ded622a5e0:1: 6d28e0e5a23c7b25b4551b78bb1bcf22dec321b118ac719842043f6eb6119a62
lightningd(1): peer 02f6725f9c1c40333b67faea92fd211c183050f28df32cac3f9d69685fe9665432: Peer permanent failure in CLOSINGD_SIGEXCHANGE: Funding transaction spent
lightningd(1):   (tx 6d28e0e5a23c7b25b4551b78bb1bcf22dec321b118ac719842043f6eb6119a62)
lightningd(1): sendrawtransaction: 02000000000101e0a522d6de65a062217c70cf8502ab1d31fa6275aa072f44d16c6503f38fb5d50100000000c4853080021127000000000000160014ce82454ac392f7b39de84c853e6f0cb47beee91d7a9f020000000000220020b65bfbc5b80ea52e667a4f2aa654d50f49b0597f6a94a22428d7c3af380a6e56040048304502210088097e4851ccee19789468e510ec305354d6af0f0560a957a96079974cfd5bd1022065a2942865bb1668d41f7dffd58939b00e143ae26a0377e415eb3e0aa79ca5f80147304402201bb538ce0d4416a4cd8423a989f55adcf2f9189422c0b238b88a1b532f562b5702205a5abd94b0b2a461e993ac0ad93ef556d53bd58cd0aa3388dc0d41d6537c881d01475221022753ddacaba541d185ebab78cd41c78c5cb160ae92b09c6398fda885e822b7192103aca784f6ec506180a2dd8f1e3d3da2cdfdcdfa1e063191f979bfb3314857e59252ae34f2c520
lightningd(1): peer 02f6725f9c1c40333b67faea92fd211c183050f28df32cac3f9d69685fe9665432: state: CLOSINGD_SIGEXCHANGE -> FUNDING_SPEND_SEEN
lightning_onchaind(32172): pid 32172, msgfd 16
lightningd(1): Forcing fee rate, ignoring estimate
lightningd(1): sendrawtx exit 27, gave error code: -27
error message:
transaction already in block chain

lightningd(1): peer 02f6725f9c1c40333b67faea92fd211c183050f28df32cac3f9d69685fe9665432: Got depth change 0->1 for 6d28e0e5a23c7b25b4551b78bb1bcf22dec321b118ac719842043f6eb6119a62
lightning_onchaind(32172): TRACE: Tracking output 1 of d5b58ff303656cd1442f07aa7562fa311dab0285cf707c2162a065ded622a5e0: FUNDING_TRANSACTION/FUNDING_OUTPUT
lightning_onchaind(32172): TRACE: Remote per-commit point: 0335d6ee2316520a8eecc9e744e3e858c1c3f9391b844c5e5e25706b6282ad9541
lightning_onchaind(32172): TRACE: Old remote per-commit point: 03fc68919bfd3c966b32ef113d0820700cac1894f4807db76344452bcf6592f412
lightning_onchaind(32172): TRACE: commitnum = 36, revocations_received = 36
lightning_onchaind(32172): UPDATE WIRE_ONCHAIN_INIT_REPLY
lightningd(1): peer 02f6725f9c1c40333b67faea92fd211c183050f28df32cac3f9d69685fe9665432: state: FUNDING_SPEND_SEEN -> ONCHAIND_OUR_UNILATERAL
lightning_onchaind(32172): TRACE: Initial feerate 0 to 26381
lightning_onchaind(32172): TRACE: Resolved FUNDING_TRANSACTION/FUNDING_OUTPUT by OUR_UNILATERAL (6d28e0e5a23c7b25b4551b78bb1bcf22dec321b118ac719842043f6eb6119a62)
lightning_onchaind(32172): TRACE: Deconstructing unilateral tx: 36 using keyset:  self_revocation_key: 036db9d22163a6c85e21a90ead1d62be0d647f68f71a2d507e45d1d574a36145e4 self_delayed_payment_key: 026e7b28b9f7004ec6d6e082c65f3595d67721f53899b4f01b4f07ed2d21ea8fbf self_payment_key: 0273ddbb3ed4c1f4b0959bee716255195e89fc0bce11a2f691e282c746f6ee0801 other_payment_key: 03ddae09147deb5daeb47bdbdc9325b9f43e743f3291f4e69d3796a471242993db self_htlc_key: 025ee0dc9da9e50118d907b6562d323b5d4a42e4065e1eca65d3f3cf8370b1d8d0 other_htlc_key: 02a2238d0aec5e53c73282768ccb17976b0f3efe4943826271bff83055482108db
lightning_onchaind(32172): TRACE: Script to-me: 144: 0020b65bfbc5b80ea52e667a4f2aa654d50f49b0597f6a94a22428d7c3af380a6e56 (6321036db9d22163a6c85e21a90ead1d62be0d647f68f71a2d507e45d1d574a36145e467029000b27521026e7b28b9f7004ec6d6e082c65f3595d67721f53899b4f01b4f07ed2d21ea8fbf68ac)
lightning_onchaind(32172): TRACE: Script to-them: 0014ce82454ac392f7b39de84c853e6f0cb47beee91d
lightning_onchaind(32172): TRACE: Output 0: 0014ce82454ac392f7b39de84c853e6f0cb47beee91d
lightning_onchaind(32172): TRACE: Output 1: 0020b65bfbc5b80ea52e667a4f2aa654d50f49b0597f6a94a22428d7c3af380a6e56
lightning_onchaind(32172): TRACE: Tracking output 0 of 6d28e0e5a23c7b25b4551b78bb1bcf22dec321b118ac719842043f6eb6119a62: OUR_UNILATERAL/OUTPUT_TO_THEM
lightning_onchaind(32172): TRACE: Ignoring output 0 of 6d28e0e5a23c7b25b4551b78bb1bcf22dec321b118ac719842043f6eb6119a62: OUR_UNILATERAL/OUTPUT_TO_THEM
lightning_onchaind(32172): TRACE: Tracking output 1 of 6d28e0e5a23c7b25b4551b78bb1bcf22dec321b118ac719842043f6eb6119a62: OUR_UNILATERAL/DELAYED_OUTPUT_TO_US
lightning_onchaind(32172): TRACE: Propose handling OUR_UNILATERAL/DELAYED_OUTPUT_TO_US by OUR_DELAYED_RETURN_TO_WALLET (02000000000101629a11b66e3f04429871ac18b121c3de22cf1bbb781b55b4257b3ca2e5e0286d01000000009000000001b58b02000000000016001444d084aa6aed3aa4b4c8c5d8e33a18585a7d89b40347304402205248d6243f8dca21e6979a4c4ea751ee1621ed6562ce77c70837dc25cf2bfbc402202051a618550bd2d718cb122bb6c9a0a0e2ece144240c99d7abc7ad07169f01f901004d6321036db9d22163a6c85e21a90ead1d62be0d647f68f71a2d507e45d1d574a36145e467029000b27521026e7b28b9f7004ec6d6e082c65f3595d67721f53899b4f01b4f07ed2d21ea8fbf68ac00000000) in 144 blocks
lightning_onchaind(32172): TRACE: Got new message WIRE_ONCHAIN_DEPTH
lightning_onchaind(32172): TRACE: FUNDING_TRANSACTION/FUNDING_OUTPUT->OUR_UNILATERAL depth 1
lightning_onchaind(32172): TRACE: OUR_UNILATERAL/OUTPUT_TO_THEM->SELF depth 1
lightningd(1): Immediate feerate 29155 (was 29158)
lightningd(1): Normal feerate 27818 (was 27820)
lightningd(1): Connected json input
lightningd(1):jcon fd 14: Success
lightningd(1):jcon fd 14: Closing (No such file or directory)
lightningd(1): Connected json input
lightning_gossipd(13): TRACE: req: type WIRE_GOSSIP_GETPEERS_REQUEST len 4
lightning_gossipd(13): REPLY WIRE_GOSSIP_GETPEERS_REPLY with 0 fds
lightningd(1):jcon fd 14: Success
lightningd(1):jcon fd 14: Closing (Bad file descriptor)
lightningd(1): Adding block 506374: 00000000000000000008c387880452de4187b152f486512dc3606643ff8fc0c7
lightningd(1): peer 02f6725f9c1c40333b67faea92fd211c183050f28df32cac3f9d69685fe9665432: Got depth change 1->2 for 6d28e0e5a23c7b25b4551b78bb1bcf22dec321b118ac719842043f6eb6119a62
lightning_onchaind(32172): TRACE: Got new message WIRE_ONCHAIN_DEPTH
lightning_onchaind(32172): TRACE: FUNDING_TRANSACTION/FUNDING_OUTPUT->OUR_UNILATERAL depth 2
lightning_onchaind(32172): TRACE: OUR_UNILATERAL/OUTPUT_TO_THEM->SELF depth 2
lightningd(1): Connected json input
lightning_gossipd(13): TRACE: req: type WIRE_GOSSIP_GETPEERS_REQUEST len 4
lightning_gossipd(13): REPLY WIRE_GOSSIP_GETPEERS_REPLY with 0 fds
lightningd(1):jcon fd 14: Success
lightningd(1):jcon fd 14: Closing (Bad file descriptor)
lightningd(1): Connected json input
lightningd(1):jcon fd 14: Success
lightningd(1):jcon fd 14: Closing (No such file or directory)
lightningd(1): Connected json input
lightningd(1):jcon fd 14: Success
lightningd(1):jcon fd 14: Closing (No such file or directory)
lightningd(1): Connected json input
lightning_gossipd(13): TRACE: req: type WIRE_GOSSIP_GETPEERS_REQUEST len 4
lightning_gossipd(13): REPLY WIRE_GOSSIP_GETPEERS_REPLY with 0 fds
lightningd(1):jcon fd 14: Success
lightningd(1):jcon fd 14: Closing (No such file or directory)
lightningd(1): Immediate feerate 29156 (was 29155)
lightningd(1): Normal feerate 27820 (was 27818)
lightningd(1): Adding block 506375: 00000000000000000002bf8b46e938a3433217d0a19b53d5a5cbdbca3ca40472
lightningd(1): peer 02f6725f9c1c40333b67faea92fd211c183050f28df32cac3f9d69685fe9665432: Got depth change 2->3 for 6d28e0e5a23c7b25b4551b78bb1bcf22dec321b118ac719842043f6eb6119a62
lightning_onchaind(32172): TRACE: Got new message WIRE_ONCHAIN_DEPTH
lightning_onchaind(32172): TRACE: Sending 0 missing htlc messages
lightning_onchaind(32172): TRACE: FUNDING_TRANSACTION/FUNDING_OUTPUT->OUR_UNILATERAL depth 3
lightning_onchaind(32172): TRACE: OUR_UNILATERAL/OUTPUT_TO_THEM->SELF depth 3
brunoaduarte picture brunoaduarte on 28 Jan 2018

Thanks, I'll see if I can find the matching entry in the logs of the counterparty :-)

cdecker picture cdecker on 28 Jan 2018
👍1

I extracted the matching entries and opened a tracking issue here #818, closing this for now.

cdecker picture cdecker on 28 Jan 2018
👍1
Was this page helpful?
0 / 5 - 0 ratings

Related issues

SPIRY-RO picture SPIRY-RO  路  4Comments
brunoaduarte picture brunoaduarte  路  5Comments
mloop1 picture mloop1  路  4Comments
cdecker picture cdecker  路  4Comments
Christewart picture Christewart  路  3Comments