search

ElementsProject / lightning

Core Lightning — Lightning Network implementation focusing on spec compliance and performance
Other
2.85k stars 902 forks source link

code: -25?error message:?Missing inputs? #1630

Closed NicolasDorier closed 6 years ago

NicolasDorier commented 6 years ago

So an LND node opened a channel with my clightning on testnet. One invoice has been paid, then LND closed the channel.

I noticed this strange error code: -25?error message:?Missing inputs?. I don't know if that is expected, but it looks fishy to me so I prefer reporting.

It also seems the channel did not close cooperatively?

The LND node can't add the clightning node as peer anymore.

2018-06-25T04:39:45.294Z lightningd(11): No fee estimate for Normal: basing on Immediate rate
2018-06-25T04:39:45.294Z lightningd(11): No fee estimate for Slow: basing on Immediate rate
2018-06-25T04:39:45.295Z lightningd(11): No fee estimate for Slow: basing on Normal rate
2018-06-25T05:30:20.292Z lightningd(11): 02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #1: State changed from CHANNELD_AWAITING_LOCKIN to CHANNELD_NORMAL
2018-06-25T05:33:00.195Z lightningd(11): Resolving invoice '2sML4wJaWEJW8rLvEbq12bm142j' with HTLC 0
2018-06-25T05:34:57.561Z lightningd(11): 02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #1: State changed from CHANNELD_NORMAL to CHANNELD_SHUTTING_DOWN
2018-06-25T05:34:57.614Z lightningd(11): 02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #1: State changed from CHANNELD_SHUTTING_DOWN to CLOSINGD_SIGEXCHANGE
2018-06-25T05:34:58.251Z lightningd(11): 02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #1: State changed from CLOSINGD_SIGEXCHANGE to CLOSINGD_COMPLETE
2018-06-25T06:23:21.230Z lightningd(11): 02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #1: Peer permanent failure in CLOSINGD_COMPLETE: Funding transaction spent
2018-06-25T06:23:21.230Z lightningd(11):         (tx 0e0b5ad65acb1a7070a85281ad70e725a3f37383fdd8eb8dbd05dcf6b167a46b)
2018-06-25T06:23:21.235Z lightningd(11): 02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #1: State changed from CLOSINGD_COMPLETE to FUNDING_SPEND_SEEN
2018-06-25T06:23:21.425Z lightningd(11): 02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #1: State changed from FUNDING_SPEND_SEEN to ONCHAIN
2018-06-26T04:20:21.653Z lightningd(11): 02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2: State changed from CHANNELD_AWAITING_LOCKIN to CHANNELD_NORMAL
2018-06-26T04:33:32.333Z lightningd(11): Resolving invoice '2LrdZJQxghs9BRvb6kwAvWHEhcuj' with HTLC 0
2018-06-26T04:39:51.819Z lightningd(11): 02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2: Peer permanent failure in CHANNELD_NORMAL: Funding transaction spent
2018-06-26T04:39:51.819Z lightningd(11):         (tx dc7af453dd254d11bfc6f3301c5818dc5d3c17e5d8ee983f180f8effd3723e55)
2018-06-26T04:39:51.821Z lightningd(11): 02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2: State changed from CHANNELD_NORMAL to FUNDING_SPEND_SEEN
2018-06-26T04:39:51.931Z lightningd(11): Broadcasting tx 020000000001019658e25d4b1fe0ad5e698dda34c842d4ec1fc13f675c647685511e949e96db300000000000735c8480026259030000000000220020a4b6487ed5456412b8cc4223653afa0de069cd419cde6e17df20e937213502202ccb0b000000000016001499ec37370c045963b9992136baedd419906a66a60400473044022033dff32fb80cfaef539e56f3292352ffc72cd50753c04ab1e50e69483460b40b02202709e46fe59c378d1a7b34d9c9890ebaa3a2df6836c6f1df3c1541f220d08a610147304402206fdadb5351111f56b6658f0849873d8ae7a483f438d1d29bcde3a40dd62631940220365f6b3c53ef7ea2f65877cf6390dd7cfb3017838e3b181406c359e7fa7b1cbf0147522102e81055a6b4f2cfd3a9dad516269a3ec0b74e939babf5e80430a44c49054e6e4b21039cec953270a8756c8d7c557f5ddf9d97407be691783e8963f0a68d02e8d833dd52ae11b7b720: 25 error code: -25?error message:?Missing inputs?
2018-06-26T04:39:51.931Z lightningd(11): 02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2: State changed from FUNDING_SPEND_SEEN to ONCHAIN

The channel status is

          "status": [
            "ONCHAIN:Tracking their unilateral close",
            "ONCHAIN:All outputs resolved: waiting 99 more blocks before forgetting channel"
          ],

Logs

       {
          "type": "DEBUG",
          "time": "84872.315437896",
          "source": "lightning_channeld-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "... , awaiting 1122"
        },
        {
          "type": "DEBUG",
          "time": "84872.315524296",
          "source": "lightning_channeld-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "Nope, got 1005 instead"
        },
        {
          "type": "DEBUG",
          "time": "84872.316636794",
          "source": "lightning_channeld-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "Got it!"
        },
        {
          "type": "DEBUG",
          "time": "84872.316658794",
          "source": "lightning_channeld-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "revoke_and_ack REMOTE: remote_per_commit = 02e1adef336a16b0ec82bfb48f1c325134f39d11252aaf3fa2f3563532386d92d1, old_remote_per_commit = 0306b6b389a7d12e9c41790da10621429b38e896de752d02e41360390637b4a249"
        },
        {
          "type": "DEBUG",
          "time": "84872.316706194",
          "source": "lightning_channeld-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "Now dealing with deferred WIRE_CHANNEL_FULFILL_HTLC"
        },
        {
          "type": "DEBUG",
          "time": "84872.316725194",
          "source": "lightning_channeld-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "FULFILL:: HTLC REMOTE 0 = SENT_REMOVE_HTLC/RCVD_REMOVE_HTLC FULFILLED"
        },
        {
          "type": "DEBUG",
          "time": "84872.316742894",
          "source": "lightning_channeld-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "Trying commit"
        },
        {
          "type": "DEBUG",
          "time": "84872.316759394",
          "source": "lightning_channeld-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "htlc 0: SENT_REMOVE_HTLC->SENT_REMOVE_COMMIT"
        },
        {
          "type": "DEBUG",
          "time": "84872.316777194",
          "source": "lightning_channeld-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "htlc removed REMOTE: local +219490000 remote +0"
        },
        {
          "type": "DEBUG",
          "time": "84872.316794794",
          "source": "lightning_channeld-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "sending_commit: HTLC REMOTE 0 = SENT_REMOVE_COMMIT/RCVD_REMOVE_COMMIT FULFILLED"
        },
        {
          "type": "DEBUG",
          "time": "84872.316813394",
          "source": "lightning_channeld-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "Derived key 03d50c953662fdc5aa3d23c183a43b70e2e4178f1e9cebf28502e10b9986398879 from basepoint 020095623e49533d2f45ed2cf73410c1313ee29ff468dd27e857a9325fd591611f, point 02e1adef336a16b0ec82bfb48f1c325134f39d11252aaf3fa2f3563532386d92d1"
        },
        {
          "type": "DEBUG",
          "time": "84872.316834994",
          "source": "lightning_channeld-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "Creating commit_sig signature 2 304402201e12604e46cd2fab38e41995bdc8de81191e9d65ae92d489f613ded2145452c102200f923d4c3279d5a17cd53e209d681f9ae651c6257809203fde91fc1a5e4ce52f for tx 02000000019658e25d4b1fe0ad5e698dda34c842d4ec1fc13f675c647685511e949e96db300000000000735c8480026259030000000000160014f34b5ca7da104693dca35b149199d4644055a9952ccb0b00000000002200203e034ea4543e258e8364850f1fadfc348405eab225232749a754b2490d34501411b7b720 wscript 522102e81055a6b4f2cfd3a9dad516269a3ec0b74e939babf5e80430a44c49054e6e4b21039cec953270a8756c8d7c557f5ddf9d97407be691783e8963f0a68d02e8d833dd52ae key 039cec953270a8756c8d7c557f5ddf9d97407be691783e8963f0a68d02e8d833dd"
        },
        {
          "type": "DEBUG",
          "time": "84872.316874394",
          "source": "lightning_channeld-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "Telling master we're about to commit..."
        },
        {
          "type": "DEBUG",
          "time": "84872.316887394",
          "source": "lightning_channeld-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "Sending master 1020"
        },
        {
          "type": "DEBUG",
          "time": "84872.316900094",
          "source": "lightning_channeld-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "UPDATE WIRE_CHANNEL_SENDING_COMMITSIG"
        },
        {
          "type": "DEBUG",
          "time": "84872.316907594",
          "source": "02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "HTLC in 0 SENT_REMOVE_HTLC->SENT_REMOVE_COMMIT"
        },
        {
          "type": "DEBUG",
          "time": "84872.415560748",
          "source": "lightning_channeld-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "... , awaiting 1120"
        },
        {
          "type": "DEBUG",
          "time": "84872.415860148",
          "source": "lightning_channeld-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "Got it!"
        },
        {
          "type": "DEBUG",
          "time": "84872.415893548",
          "source": "lightning_channeld-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "Sending commit_sig with 0 htlc sigs"
        },
        {
          "type": "DEBUG",
          "time": "84872.415926147",
          "source": "lightning_channeld-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "peer_out WIRE_UPDATE_FULFILL_HTLC"
        },
        {
          "type": "DEBUG",
          "time": "84872.415941447",
          "source": "lightning_channeld-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "peer_out WIRE_COMMITMENT_SIGNED"
        },
        {
          "type": "DEBUG",
          "time": "84872.758297142",
          "source": "lightning_channeld-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "peer_in WIRE_REVOKE_AND_ACK"
        },
        {
          "type": "DEBUG",
          "time": "84872.758393942",
          "source": "lightning_channeld-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "Received revoke_and_ack"
        },
        {
          "type": "DEBUG",
          "time": "84872.758426142",
          "source": "lightning_channeld-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "htlc 0: SENT_REMOVE_COMMIT->RCVD_REMOVE_REVOCATION"
        },
        {
          "type": "DEBUG",
          "time": "84872.758535242",
          "source": "lightning_channeld-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "rcvd_revoke_and_ack: HTLC REMOTE 0 = RCVD_REMOVE_REVOCATION/SENT_REMOVE_REVOCATION FULFILLED"
        },
        {
          "type": "DEBUG",
          "time": "84872.758572142",
          "source": "lightning_channeld-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "Commits outstanding after recv revoke_and_ack"
        },
        {
          "type": "DEBUG",
          "time": "84872.758608342",
          "source": "lightning_channeld-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "HTLC 0[REMOTE] => RCVD_REMOVE_REVOCATION"
        },
        {
          "type": "DEBUG",
          "time": "84872.758645542",
          "source": "lightning_channeld-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "Sending master 1022"
        },
        {
          "type": "DEBUG",
          "time": "84872.758673642",
          "source": "lightning_channeld-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "UPDATE WIRE_CHANNEL_GOT_REVOKE"
        },
        {
          "type": "DEBUG",
          "time": "84872.758688842",
          "source": "02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "got revoke 1: 1 changed"
        },
        {
          "type": "DEBUG",
          "time": "84872.758696442",
          "source": "02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "HTLC in 0 SENT_REMOVE_COMMIT->RCVD_REMOVE_REVOCATION"
        },
        {
          "type": "DEBUG",
          "time": "84872.781012909",
          "source": "lightning_channeld-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "... , awaiting 1122"
        },
        {
          "type": "DEBUG",
          "time": "84872.782351107",
          "source": "lightning_channeld-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "Got it!"
        },
        {
          "type": "DEBUG",
          "time": "84872.782370307",
          "source": "lightning_channeld-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "revoke_and_ack REMOTE: remote_per_commit = 0250148ae4fda247b70c8cc74274aa62c7035458987b4193baf161b7097248a543, old_remote_per_commit = 02e1adef336a16b0ec82bfb48f1c325134f39d11252aaf3fa2f3563532386d92d1"
        },
        {
          "type": "DEBUG",
          "time": "84872.782466106",
          "source": "lightning_channeld-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "Trying commit"
        },
        {
          "type": "DEBUG",
          "time": "84872.782483606",
          "source": "lightning_channeld-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "Can't send commit: nothing to send"
        },
        {
          "type": "DEBUG",
          "time": "84872.782501106",
          "source": "lightning_channeld-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "peer_in WIRE_COMMITMENT_SIGNED"
        },
        {
          "type": "DEBUG",
          "time": "84872.782518006",
          "source": "lightning_channeld-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "Received commit"
        },
        {
          "type": "DEBUG",
          "time": "84872.782534906",
          "source": "lightning_channeld-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "htlc 0: RCVD_REMOVE_REVOCATION->RCVD_REMOVE_ACK_COMMIT"
        },
        {
          "type": "DEBUG",
          "time": "84872.782595306",
          "source": "lightning_channeld-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "htlc removed LOCAL: local +219490000 remote +0"
        },
        {
          "type": "DEBUG",
          "time": "84872.782614606",
          "source": "lightning_channeld-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "rcvd_commit: HTLC REMOTE 0 = RCVD_REMOVE_ACK_COMMIT/SENT_REMOVE_ACK_COMMIT FULFILLED"
        },
        {
          "type": "DEBUG",
          "time": "84872.782633506",
          "source": "lightning_channeld-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "Derived key 02264f439560c171adf6f5915e1452d4c3d957e0f9e4a715cfb77533da46685ae1 from basepoint 03ba0e10cd75fa937165a019054f0b67d688a2c3ff9e122e0a35ab5c168628a7bb, point 0395b1d5eb737e70a43b98707f3a270a32999f92ab32033e1b62fbaa60a3bc3303"
        },
        {
          "type": "DEBUG",
          "time": "84872.782652806",
          "source": "lightning_channeld-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "Received commit_sig with 0 htlc sigs"
        },
        {
          "type": "DEBUG",
          "time": "84872.782670306",
          "source": "lightning_channeld-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "Sending master 1021"
        },
        {
          "type": "DEBUG",
          "time": "84872.782686906",
          "source": "lightning_channeld-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "UPDATE WIRE_CHANNEL_GOT_COMMITSIG"
        },
        {
          "type": "DEBUG",
          "time": "84872.782696106",
          "source": "02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "got commitsig 2: feerate 10500, 0 added, 0 fulfilled, 0 failed, 1 changed"
        },
        {
          "type": "DEBUG",
          "time": "84872.782703106",
          "source": "02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "HTLC in 0 RCVD_REMOVE_REVOCATION->RCVD_REMOVE_ACK_COMMIT"
        },
        {
          "type": "DEBUG",
          "time": "84872.782825806",
          "source": "02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "HTLC in 0 RCVD_REMOVE_ACK_COMMIT->SENT_REMOVE_ACK_REVOCATION"
        },
        {
          "type": "DEBUG",
          "time": "84872.782846806",
          "source": "02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "Removing in HTLC 0 state SENT_REMOVE_ACK_REVOCATION FULFILLED"
        },
        {
          "type": "DEBUG",
          "time": "84872.782849306",
          "source": "02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "Balance 0 -> 219490000"
        },
        {
          "type": "DEBUG",
          "time": "84872.810602865",
          "source": "lightning_channeld-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "... , awaiting 1121"
        },
        {
          "type": "DEBUG",
          "time": "84872.811081564",
          "source": "lightning_channeld-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "Got it!"
        },
        {
          "type": "DEBUG",
          "time": "84872.811097864",
          "source": "lightning_channeld-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "Sending revocation #1 for 03947986fa3ac81a1d7e6554747ef9a4722d88481e75932fe459326a0e46bab886"
        },
        {
          "type": "DEBUG",
          "time": "84872.811113064",
          "source": "lightning_channeld-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "Sending revoke_and_ack"
        },
        {
          "type": "DEBUG",
          "time": "84872.811127264",
          "source": "lightning_channeld-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "htlc 0: RCVD_REMOVE_ACK_COMMIT->SENT_REMOVE_ACK_REVOCATION"
        },
        {
          "type": "DEBUG",
          "time": "84872.811158964",
          "source": "lightning_channeld-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "sending_revoke_and_ack: HTLC REMOTE 0 = SENT_REMOVE_ACK_REVOCATION/RCVD_REMOVE_ACK_REVOCATION FULFILLED"
        },
        {
          "type": "DEBUG",
          "time": "84872.811173364",
          "source": "lightning_channeld-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "peer_out WIRE_REVOKE_AND_ACK"
        },
        {
          "type": "DEBUG",
          "time": "84920.629514400",
          "source": "lightning_channeld-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "peer_in WIRE_PING"
        },
        {
          "type": "DEBUG",
          "time": "84920.629538000",
          "source": "lightning_channeld-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "Got ping, sending WIRE_PONG"
        },
        {
          "type": "DEBUG",
          "time": "84920.629553900",
          "source": "lightning_channeld-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "peer_out WIRE_PONG"
        },
        {
          "type": "DEBUG",
          "time": "84980.626385499",
          "source": "lightning_channeld-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "peer_in WIRE_PING"
        },
        {
          "type": "DEBUG",
          "time": "84980.626446798",
          "source": "lightning_channeld-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "Got ping, sending WIRE_PONG"
        },
        {
          "type": "DEBUG",
          "time": "84980.626484098",
          "source": "lightning_channeld-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "peer_out WIRE_PONG"
        },
        {
          "type": "DEBUG",
          "time": "85040.625561432",
          "source": "lightning_channeld-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "peer_in WIRE_PING"
        },
        {
          "type": "DEBUG",
          "time": "85040.625590632",
          "source": "lightning_channeld-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "Got ping, sending WIRE_PONG"
        },
        {
          "type": "DEBUG",
          "time": "85040.625610032",
          "source": "lightning_channeld-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "peer_out WIRE_PONG"
        },
        {
          "type": "DEBUG",
          "time": "85100.623205206",
          "source": "lightning_channeld-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "peer_in WIRE_PING"
        },
        {
          "type": "DEBUG",
          "time": "85100.623233406",
          "source": "lightning_channeld-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "Got ping, sending WIRE_PONG"
        },
        {
          "type": "DEBUG",
          "time": "85100.623252406",
          "source": "lightning_channeld-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "peer_out WIRE_PONG"
        },
        {
          "type": "DEBUG",
          "time": "85160.622532214",
          "source": "lightning_channeld-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "peer_in WIRE_PING"
        },
        {
          "type": "DEBUG",
          "time": "85160.622558914",
          "source": "lightning_channeld-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "Got ping, sending WIRE_PONG"
        },
        {
          "type": "DEBUG",
          "time": "85160.622577914",
          "source": "lightning_channeld-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "peer_out WIRE_PONG"
        },
        {
          "type": "DEBUG",
          "time": "85220.616927519",
          "source": "lightning_channeld-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "peer_in WIRE_PING"
        },
        {
          "type": "DEBUG",
          "time": "85220.616949919",
          "source": "lightning_channeld-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "Got ping, sending WIRE_PONG"
        },
        {
          "type": "DEBUG",
          "time": "85220.616965519",
          "source": "lightning_channeld-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "peer_out WIRE_PONG"
        },
        {
          "type": "DEBUG",
          "time": "85251.767890079",
          "source": "02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "Got UTXO spend for 30db969e941e518576645c673fc11fecd442c834da8d695eade01f4b5de25896:0: 5b81422c678f93e5fd1da924e043212ca5ca0c801088a5a180d6efd5a74a6b65"
        },
        {
          "type": "UNUSUAL",
          "time": "85251.767930979",
          "source": "02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "Peer permanent failure in CHANNELD_NORMAL: Funding transaction spent"
        },
        {
          "type": "DEBUG",
          "time": "85251.768163878",
          "source": "lightning_channeld-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "Status closed, but not exited. Killing"
        },
        {
          "type": "INFO",
          "time": "85251.769222077",
          "source": "02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "State changed from CHANNELD_NORMAL to FUNDING_SPEND_SEEN"
        },
        {
          "type": "DEBUG",
          "time": "85251.771255774",
          "source": "lightning_onchaind-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "pid 11835, msgfd 42"
        },
        {
          "type": "DEBUG",
          "time": "85251.820296995",
          "source": "02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "Got depth change 1->2 for 30db969e941e518576645c673fc11fecd442c834da8d695eade01f4b5de25896"
        },
        {
          "type": "DEBUG",
          "time": "85251.820305995",
          "source": "02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "Funding tx 30db969e941e518576645c673fc11fecd442c834da8d695eade01f4b5de25896 depth 2 of 1"
        },
        {
          "type": "DEBUG",
          "time": "85251.820310695",
          "source": "02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "Funding tx confirmed, but peer in state FUNDING_SPEND_SEEN"
        },
        {
          "type": "DEBUG",
          "time": "85251.820329095",
          "source": "02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "Got depth change 0->1 for 5b81422c678f93e5fd1da924e043212ca5ca0c801088a5a180d6efd5a74a6b65"
        },
        {
          "type": "DEBUG",
          "time": "85251.820429395",
          "source": "02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #1:",
          "log": "Got depth change 75->76 for 0e0b5ad65acb1a7070a85281ad70e725a3f37383fdd8eb8dbd05dcf6b167a46b"
        },
        {
          "type": "DEBUG",
          "time": "85251.879229502",
          "source": "lightning_onchaind-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "Tracking output 0 of 30db969e941e518576645c673fc11fecd442c834da8d695eade01f4b5de25896: FUNDING_TRANSACTION/FUNDING_OUTPUT"
        },
        {
          "type": "DEBUG",
          "time": "85251.879525801",
          "source": "lightning_onchaind-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #1:",
          "log": "Got new message WIRE_ONCHAIN_DEPTH"
        },
        {
          "type": "DEBUG",
          "time": "85251.879532701",
          "source": "lightning_onchaind-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "Remote per-commit point: 0250148ae4fda247b70c8cc74274aa62c7035458987b4193baf161b7097248a543"
        },
        {
          "type": "DEBUG",
          "time": "85251.879547601",
          "source": "lightning_onchaind-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #1:",
          "log": "FUNDING_TRANSACTION/FUNDING_OUTPUT->MUTUAL_CLOSE depth 76"
        },
        {
          "type": "DEBUG",
          "time": "85251.879553401",
          "source": "lightning_onchaind-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "Old remote per-commit point: 02e1adef336a16b0ec82bfb48f1c325134f39d11252aaf3fa2f3563532386d92d1"
        },
        {
          "type": "DEBUG",
          "time": "85251.879576701",
          "source": "lightning_onchaind-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "commitnum = 2, revocations_received = 2"
        },
        {
          "type": "DEBUG",
          "time": "85251.879590001",
          "source": "lightning_onchaind-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "Their unilateral tx, old commit point"
        },
        {
          "type": "DEBUG",
          "time": "85251.879602301",
          "source": "lightning_onchaind-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "UPDATE WIRE_ONCHAIN_INIT_REPLY"
        },
        {
          "type": "INFO",
          "time": "85251.879605101",
          "source": "02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "State changed from FUNDING_SPEND_SEEN to ONCHAIN"
        },
        {
          "type": "DEBUG",
          "time": "85251.908149455",
          "source": "lightning_onchaind-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "Resolved FUNDING_TRANSACTION/FUNDING_OUTPUT by THEIR_UNILATERAL (5b81422c678f93e5fd1da924e043212ca5ca0c801088a5a180d6efd5a74a6b65)"
        },
        {
          "type": "DEBUG",
          "time": "85251.908171955",
          "source": "lightning_onchaind-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "Deriving keyset 2: per_commit_point=02e1adef336a16b0ec82bfb48f1c325134f39d11252aaf3fa2f3563532386d92d1 self_payment_basepoint=0275ae2c1feb9e07dbf67fa2a4cb1d7ea0bb30eb7a61a5f57ef51e0a75543db9db other_payment_basepoint=03f3212fcc878373195bdd4d9093755a60179c155762d4a5bd790737f5e25f817a self_htlc_basepoint=03ba0e10cd75fa937165a019054f0b67d688a2c3ff9e122e0a35ab5c168628a7bb other_htlc_basepoint=020095623e49533d2f45ed2cf73410c1313ee29ff468dd27e857a9325fd591611f self_delayed_basepoint=031a2d4a7540fcb2b6f2a5ab67f2cc5e6e91e66fb26e11636bb6ab02010cf7ddad other_revocation_basepoint=03a670e9699bd7bcfe58672b94baff6d9b7672a12a468bebb91be0025ac41404af"
        },
        {
          "type": "DEBUG",
          "time": "85251.908196355",
          "source": "lightning_onchaind-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "Deconstructing unilateral tx: 2 using keyset:  self_revocation_key: 036f75be13dc242194aa520122a2a967ccd87c0ac5e80e4b80907e2aeab7ad74d2 self_delayed_payment_key: 0348dccfc2acc4d0459823bc04f9427af8b7e74aa8eac1a102c907ff1867f66d17 self_payment_key: 02f49fba09fe8d33f7059599f8d512321dc90ae9d2aa68ae82c00a5c5487144120 other_payment_key: 034c3ccc16c3d9d46e86408aa6e5c04e95087c91ca498d5bb6349ed7aa15d17222 self_htlc_key: 02cf09652b6a50bda48c25a72a2fb8d0f6bfda40a8c98bff562a446b0e8060d858 other_htlc_key: 03d50c953662fdc5aa3d23c183a43b70e2e4178f1e9cebf28502e10b9986398879"
        },
        {
          "type": "DEBUG",
          "time": "85251.908219455",
          "source": "lightning_onchaind-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "Script to-them: 6: 00203e034ea4543e258e8364850f1fadfc348405eab225232749a754b2490d345014 (6321036f75be13dc242194aa520122a2a967ccd87c0ac5e80e4b80907e2aeab7ad74d26756b275210348dccfc2acc4d0459823bc04f9427af8b7e74aa8eac1a102c907ff1867f66d1768ac)"
        },
        {
          "type": "DEBUG",
          "time": "85251.908239655",
          "source": "lightning_onchaind-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "Script to-me: 0014f34b5ca7da104693dca35b149199d4644055a995"
        },
        {
          "type": "DEBUG",
          "time": "85251.908257955",
          "source": "lightning_onchaind-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "Output 0: 0014f34b5ca7da104693dca35b149199d4644055a995"
        },
        {
          "type": "DEBUG",
          "time": "85251.908632855",
          "source": "lightning_onchaind-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "Output 1: 00203e034ea4543e258e8364850f1fadfc348405eab225232749a754b2490d345014"
        },
        {
          "type": "DEBUG",
          "time": "85251.908650755",
          "source": "lightning_onchaind-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "Tracking output 0 of 5b81422c678f93e5fd1da924e043212ca5ca0c801088a5a180d6efd5a74a6b65: THEIR_UNILATERAL/OUTPUT_TO_US"
        },
        {
          "type": "DEBUG",
          "time": "85251.908667855",
          "source": "lightning_onchaind-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "Ignoring output 0 of 5b81422c678f93e5fd1da924e043212ca5ca0c801088a5a180d6efd5a74a6b65: THEIR_UNILATERAL/OUTPUT_TO_US"
        },
        {
          "type": "DEBUG",
          "time": "85251.908703755",
          "source": "lightning_onchaind-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "UPDATE WIRE_ONCHAIN_ADD_UTXO"
        },
        {
          "type": "DEBUG",
          "time": "85251.935895511",
          "source": "lightning_onchaind-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "Tracking output 1 of 5b81422c678f93e5fd1da924e043212ca5ca0c801088a5a180d6efd5a74a6b65: THEIR_UNILATERAL/DELAYED_OUTPUT_TO_THEM"
        },
        {
          "type": "DEBUG",
          "time": "85251.935919711",
          "source": "lightning_onchaind-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "Ignoring output 1 of 5b81422c678f93e5fd1da924e043212ca5ca0c801088a5a180d6efd5a74a6b65: THEIR_UNILATERAL/DELAYED_OUTPUT_TO_THEM"
        },
        {
          "type": "DEBUG",
          "time": "85251.935964411",
          "source": "lightning_onchaind-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "Got new message WIRE_ONCHAIN_DEPTH"
        },
        {
          "type": "DEBUG",
          "time": "85251.936021111",
          "source": "lightning_onchaind-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "FUNDING_TRANSACTION/FUNDING_OUTPUT->THEIR_UNILATERAL depth 1"
        },
        {
          "type": "DEBUG",
          "time": "85251.936040011",
          "source": "lightning_onchaind-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "THEIR_UNILATERAL/OUTPUT_TO_US->SELF depth 1"
        },
        {
          "type": "DEBUG",
          "time": "85251.936058211",
          "source": "lightning_onchaind-02b23e6f96326047b1d15c225a7c8c22285a73713d998c22edec82b595ef938ac7 chan #2:",
          "log": "THEIR_UNILATERAL/DELAYED_OUTPUT_TO_THEM->SELF depth 1"
        }
cdecker commented 6 years ago

That's all ok, the "missing input" is just because the lnd node closed unilaterally, and we still tried to publish ours, which was not possible since that'd be doublespending theirs (both spend the funding output). So those lines can be safely ignored. We aren't really smart about publishing those transactions yet, we just try to get ours in and try to push them for a while.

No idea why the lnd node closed unilaterally, but we indeed tried to close collaboratively (which failed for some reason), the other side would have logs that are more helpful :wink:

A new connection will succeed once the 100 blocks have passed, since we don't (currently) allow concurrent channels. In the meantime try an indirect connection :-)

NicolasDorier commented 6 years ago

Ok thanks, so not a problem closing this.

I think not being smart on publishing is fine, and should be prefered as at least it can't be gamed.

The log scared me :p

cdecker commented 6 years ago

The log scared me :p

You're definitely not alone :wink: