A specific channel is not announcing my side #8552
Description
I had a channel with a peer closed and at the same time they opened another, bigger channel to me. During the "waiting for x more blocks before forgetting the channel" on the old one the peer would stay disconnected. Now that the old channel is forgotten and i'm connected to that peer again my node won't announce my side of the channel.
Here is a log of the node startup while grep'ing for the peer id:
Sep 16 10:19:46 lightningd[3534983]: DEBUG 026f46207fd290a33cbd86e29b3ad0a47cdd44ab9aa5267cde66483e10aa9d3180-chan#1804: Initial channel state CGOSSIP_WAITING_FOR_MATCHING_PEER_SIGS
Sep 16 10:19:46 lightningd[3534983]: DEBUG 026f46207fd290a33cbd86e29b3ad0a47cdd44ab9aa5267cde66483e10aa9d3180-chan#1804: Loading in HTLCs for channel 1804 (state=CHANNELD_NORMAL)
Sep 16 10:19:49 lightningd[3534983]: DEBUG 026f46207fd290a33cbd86e29b3ad0a47cdd44ab9aa5267cde66483e10aa9d3180-chan#1804: channel_gossip: not sending channel_announcement_sigs until reestablished
Sep 16 10:19:49 lightningd[3534983]: DEBUG lightningd: Got [chanbackup,peers,026f46207fd290a33cbd86e29b3ad0a47cdd44ab9aa5267cde66483e10aa9d3180]
Sep 16 10:19:49 lightningd[3534983]: DEBUG 026f46207fd290a33cbd86e29b3ad0a47cdd44ab9aa5267cde66483e10aa9d3180-chan#1804: Watching for funding txid: 731dec2525eae82cc935ed40b6ad85d86dc7fdaa6e231d0786fb9c3fde4604e0
Sep 16 10:19:49 lightningd[3534983]: DEBUG 026f46207fd290a33cbd86e29b3ad0a47cdd44ab9aa5267cde66483e10aa9d3180-connectd: Initializing important peer with 0 addresses
Sep 16 10:19:49 lightningd[3534983]: DEBUG 026f46207fd290a33cbd86e29b3ad0a47cdd44ab9aa5267cde66483e10aa9d3180-connectd: Too many connections, waiting...
Sep 16 10:19:54 lightningd[3534983]: DEBUG 026f46207fd290a33cbd86e29b3ad0a47cdd44ab9aa5267cde66483e10aa9d3180-chan#1804: channel_gossip: not sending channel_announcement_sigs until reestablished
Sep 16 10:19:54 lightningd[3534983]: DEBUG 026f46207fd290a33cbd86e29b3ad0a47cdd44ab9aa5267cde66483e10aa9d3180-chan#1804: Funding tx 731dec2525eae82cc935ed40b6ad85d86dc7fdaa6e231d0786fb9c3fde4604e0 depth 687 of 3
Sep 16 10:19:54 lightningd[3534983]: DEBUG 026f46207fd290a33cbd86e29b3ad0a47cdd44ab9aa5267cde66483e10aa9d3180-chan#1804: Funding tx 731dec2525eae82cc935ed40b6ad85d86dc7fdaa6e231d0786fb9c3fde4604e0 confirmed, but peer disconnected
Sep 16 10:19:56 lightningd[3534983]: DEBUG 026f46207fd290a33cbd86e29b3ad0a47cdd44ab9aa5267cde66483e10aa9d3180-connectd: Unblocking for timer
Sep 16 10:19:57 lightningd[3534983]: DEBUG 026f46207fd290a33cbd86e29b3ad0a47cdd44ab9aa5267cde66483e10aa9d3180-connectd: Connected out, starting crypto
Sep 16 10:19:57 lightningd[3534983]: DEBUG 026f46207fd290a33cbd86e29b3ad0a47cdd44ab9aa5267cde66483e10aa9d3180-connectd: Connect OUT
Sep 16 10:19:57 lightningd[3534983]: DEBUG 026f46207fd290a33cbd86e29b3ad0a47cdd44ab9aa5267cde66483e10aa9d3180-connectd: peer_out WIRE_INIT
Sep 16 10:19:57 lightningd[3534983]: DEBUG 026f46207fd290a33cbd86e29b3ad0a47cdd44ab9aa5267cde66483e10aa9d3180-connectd: peer_in WIRE_INIT
Sep 16 10:19:57 lightningd[3534983]: DEBUG 026f46207fd290a33cbd86e29b3ad0a47cdd44ab9aa5267cde66483e10aa9d3180-connectd: Handed peer, entering loop
Sep 16 10:19:57 lightningd[3534983]: DEBUG 026f46207fd290a33cbd86e29b3ad0a47cdd44ab9aa5267cde66483e10aa9d3180-chan#1804: Peer has reconnected, state CHANNELD_NORMAL: connecting subd
Sep 16 10:19:57 lightningd[3534983]: DEBUG 026f46207fd290a33cbd86e29b3ad0a47cdd44ab9aa5267cde66483e10aa9d3180-channeld-chan#1804: pid 3535591, msgfd 125
Sep 16 10:19:57 lightningd[3534983]: DEBUG 026f46207fd290a33cbd86e29b3ad0a47cdd44ab9aa5267cde66483e10aa9d3180-chan#1804: Already have funding locked in
Sep 16 10:19:57 lightningd[3534983]: DEBUG 026f46207fd290a33cbd86e29b3ad0a47cdd44ab9aa5267cde66483e10aa9d3180-channeld-chan#1804: option_static_remotekey = 1, option_anchor_outputs = 0 option_anchors_zero_fee_htlc_tx = 1
Sep 16 10:19:57 lightningd[3534983]: DEBUG 026f46207fd290a33cbd86e29b3ad0a47cdd44ab9aa5267cde66483e10aa9d3180-channeld-chan#1804: init REMOTE: remote_per_commit = 034c45c1095d202a71fe9ae254fc7d1b21c3ad6e299e85153b76bb77fc2882bede, old_remote_per_commit = 02397cfac65b3c55fa9e9ec322dca61a30a183ea6b758b947992349d95da4bb9fb next_idx_local = 6016 next_idx_remote = 6021 revocations_received = 6020 feerates { RCVD_ADD_ACK_REVOCATION:271 } range 253-5340 blockheights { RCVD_ADD_ACK_REVOCATION:0 }, our current 914924
Sep 16 10:19:57 lightningd[3534983]: DEBUG 026f46207fd290a33cbd86e29b3ad0a47cdd44ab9aa5267cde66483e10aa9d3180-channeld-chan#1804: Restoring HTLC 0/1: id=1995 amount=39099741msat cltv=915822 payment_hash=d11ea12455fe87bfec565482fe4bda51e93c7123ca9ea392625f350704abdab5
Sep 16 10:19:57 lightningd[3534983]: DEBUG 026f46207fd290a33cbd86e29b3ad0a47cdd44ab9aa5267cde66483e10aa9d3180-channeld-chan#1804: NEW:: HTLC REMOTE 1995 = RCVD_ADD_ACK_REVOCATION/SENT_ADD_ACK_REVOCATION
Sep 16 10:19:57 lightningd[3534983]: DEBUG 026f46207fd290a33cbd86e29b3ad0a47cdd44ab9aa5267cde66483e10aa9d3180-channeld-chan#1804: Sending channel_reestablish with next_funding_tx_id: NULL, your_last_funding_locked: NULL, my_current_funding_locked: NULL, next_local_commit_number: 6016,
Sep 16 10:19:57 lightningd[3534983]: DEBUG 026f46207fd290a33cbd86e29b3ad0a47cdd44ab9aa5267cde66483e10aa9d3180-channeld-chan#1804: peer_out WIRE_CHANNEL_REESTABLISH
Sep 16 10:19:57 lightningd[3534983]: DEBUG 026f46207fd290a33cbd86e29b3ad0a47cdd44ab9aa5267cde66483e10aa9d3180-channeld-chan#1804: billboard: Sent reestablish, waiting for theirs
Sep 16 10:19:57 lightningd[3534983]: DEBUG 026f46207fd290a33cbd86e29b3ad0a47cdd44ab9aa5267cde66483e10aa9d3180-hsmd: Got WIRE_HSMD_GET_PER_COMMITMENT_POINT
Sep 16 10:19:57 lightningd[3534983]: DEBUG 026f46207fd290a33cbd86e29b3ad0a47cdd44ab9aa5267cde66483e10aa9d3180-hsmd: Got WIRE_HSMD_GET_PER_COMMITMENT_POINT
Sep 16 10:19:57 lightningd[3534983]: DEBUG 026f46207fd290a33cbd86e29b3ad0a47cdd44ab9aa5267cde66483e10aa9d3180-channeld-chan#1804: peer_in WIRE_CHANNEL_REESTABLISH
Sep 16 10:19:57 lightningd[3534983]: DEBUG 026f46207fd290a33cbd86e29b3ad0a47cdd44ab9aa5267cde66483e10aa9d3180-channeld-chan#1804: Got reestablish commit=6021 revoke=6015 inflights: 0, active splices: 0
Sep 16 10:19:57 lightningd[3534983]: DEBUG 026f46207fd290a33cbd86e29b3ad0a47cdd44ab9aa5267cde66483e10aa9d3180-channeld-chan#1804: Splice resume check with local_next_funding: omitted, remote_next_funding: empty, inflights: 0
Sep 16 10:19:57 lightningd[3534983]: DEBUG 026f46207fd290a33cbd86e29b3ad0a47cdd44ab9aa5267cde66483e10aa9d3180-channeld-chan#1804: next_revocation_number = 6015
Sep 16 10:19:57 lightningd[3534983]: DEBUG 026f46207fd290a33cbd86e29b3ad0a47cdd44ab9aa5267cde66483e10aa9d3180-channeld-chan#1804: option_static_remotekey: fields are correct
Sep 16 10:19:57 lightningd[3534983]: DEBUG 026f46207fd290a33cbd86e29b3ad0a47cdd44ab9aa5267cde66483e10aa9d3180-chan#1804: channel_gossip: reestablished
Sep 16 10:19:57 lightningd[3534983]: DEBUG 026f46207fd290a33cbd86e29b3ad0a47cdd44ab9aa5267cde66483e10aa9d3180-channeld-chan#1804: billboard perm: Reconnected, and reestablished.
Sep 16 10:19:57 lightningd[3534983]: DEBUG 026f46207fd290a33cbd86e29b3ad0a47cdd44ab9aa5267cde66483e10aa9d3180-channeld-chan#1804: billboard: Channel ready for use.
Sep 16 10:19:57 lightningd[3534983]: DEBUG 026f46207fd290a33cbd86e29b3ad0a47cdd44ab9aa5267cde66483e10aa9d3180-channeld-chan#1804: billboard: Channel ready for use.
Sep 16 10:19:57 lightningd[3534983]: DEBUG 026f46207fd290a33cbd86e29b3ad0a47cdd44ab9aa5267cde66483e10aa9d3180-hsmd: Got WIRE_HSMD_REVOKE_COMMITMENT_TX
Sep 16 10:19:57 lightningd[3534983]: DEBUG 026f46207fd290a33cbd86e29b3ad0a47cdd44ab9aa5267cde66483e10aa9d3180-connectd: peer_out WIRE_ANNOUNCEMENT_SIGNATURES
Sep 16 10:19:57 lightningd[3534983]: DEBUG 026f46207fd290a33cbd86e29b3ad0a47cdd44ab9aa5267cde66483e10aa9d3180-channeld-chan#1804: Trying commit
Sep 16 10:19:57 lightningd[3534983]: DEBUG 026f46207fd290a33cbd86e29b3ad0a47cdd44ab9aa5267cde66483e10aa9d3180-channeld-chan#1804: Can't send commit: nothing to send, feechange not wanted ({ RCVD_ADD_ACK_REVOCATION:271 }) blockheight not wanted ({ RCVD_ADD_ACK_REVOCATION:0 })
Sep 16 10:20:16 lightningd[3534983]: DEBUG 026f46207fd290a33cbd86e29b3ad0a47cdd44ab9aa5267cde66483e10aa9d3180-channeld-chan#1804: peer_in WIRE_UPDATE_ADD_HTLC
Sep 16 10:20:16 lightningd[3534983]: DEBUG 026f46207fd290a33cbd86e29b3ad0a47cdd44ab9aa5267cde66483e10aa9d3180-channeld-chan#1804: NEW:: HTLC REMOTE 3011 = RCVD_ADD_HTLC/SENT_ADD_HTLC
Sep 16 10:20:16 lightningd[3534983]: DEBUG 026f46207fd290a33cbd86e29b3ad0a47cdd44ab9aa5267cde66483e10aa9d3180-channeld-chan#1804: peer_in WIRE_COMMITMENT_SIGNED
Sep 16 10:20:16 lightningd[3534983]: DEBUG 026f46207fd290a33cbd86e29b3ad0a47cdd44ab9aa5267cde66483e10aa9d3180-channeld-chan#1804: fromwire_commitment_signed(0x560d24c2fd68) primary
Sep 16 10:20:16 lightningd[3534983]: DEBUG 026f46207fd290a33cbd86e29b3ad0a47cdd44ab9aa5267cde66483e10aa9d3180-channeld-chan#1804: msg_batch[0]: 0x560d24c2fd68
Sep 16 10:20:16 lightningd[3534983]: DEBUG 026f46207fd290a33cbd86e29b3ad0a47cdd44ab9aa5267cde66483e10aa9d3180-channeld-chan#1804: Sorting the msg_batch of tal_count 1, batch_size: 1
Sep 16 10:20:16 lightningd[3534983]: DEBUG 026f46207fd290a33cbd86e29b3ad0a47cdd44ab9aa5267cde66483e10aa9d3180-channeld-chan#1804: handle_peer_commit_sig(splice: 0, remote_splice: 0, commit_index: 0, local_index: 6016, msg: 0x560d24c2fd68)
Sep 16 10:20:16 lightningd[3534983]: DEBUG 026f46207fd290a33cbd86e29b3ad0a47cdd44ab9aa5267cde66483e10aa9d3180-channeld-chan#1804: Received commit
Sep 16 10:20:16 lightningd[3534983]: DEBUG 026f46207fd290a33cbd86e29b3ad0a47cdd44ab9aa5267cde66483e10aa9d3180-channeld-chan#1804: htlc 3011: RCVD_ADD_HTLC->RCVD_ADD_COMMIT
Sep 16 10:20:16 lightningd[3534983]: DEBUG 026f46207fd290a33cbd86e29b3ad0a47cdd44ab9aa5267cde66483e10aa9d3180-channeld-chan#1804: htlc added LOCAL: local 14382026059 remote 10578874200
Sep 16 10:20:16 lightningd[3534983]: DEBUG 026f46207fd290a33cbd86e29b3ad0a47cdd44ab9aa5267cde66483e10aa9d3180-channeld-chan#1804: -> local 14382026059 remote 10500866924
Sep 16 10:20:16 lightningd[3534983]: DEBUG 026f46207fd290a33cbd86e29b3ad0a47cdd44ab9aa5267cde66483e10aa9d3180-channeld-chan#1804: rcvd_commit: HTLC REMOTE 3011 = RCVD_ADD_COMMIT/SENT_ADD_COMMIT
Sep 16 10:20:16 lightningd[3534983]: DEBUG 026f46207fd290a33cbd86e29b3ad0a47cdd44ab9aa5267cde66483e10aa9d3180-channeld-chan#1804: Feerates are 271/271
Sep 16 10:20:16 lightningd[3534983]: DEBUG 026f46207fd290a33cbd86e29b3ad0a47cdd44ab9aa5267cde66483e10aa9d3180-channeld-chan#1804: Committed 2, removing 0, adding 0
Sep 16 10:20:16 lightningd[3534983]: DEBUG 026f46207fd290a33cbd86e29b3ad0a47cdd44ab9aa5267cde66483e10aa9d3180-channeld-chan#1804: We need 251057sat at feerate 271 for 2 untrimmed htlcs: we have 10500866924msat/10578874200msat (will have 10500866924msat)
Sep 16 10:20:16 lightningd[3534983]: DEBUG 026f46207fd290a33cbd86e29b3ad0a47cdd44ab9aa5267cde66483e10aa9d3180-channeld-chan#1804: Derived key 03f1abc9487629d8c6c73a5f3f7355714ecae57293557217a587016b1b50f96f83 from basepoint 03e7d7639afe55675e7ac0c29067243ee63700e45823e0f64f6b9ec09704a4623a, point 02cb5d6d45bc50ff5db867ccf57fe2852bbf632b9579848ff7318fddff93d3d6c3
Sep 16 10:20:16 lightningd[3534983]: DEBUG 026f46207fd290a33cbd86e29b3ad0a47cdd44ab9aa5267cde66483e10aa9d3180-channeld-chan#1804: Received commit_sig with 2 htlc sigs
Sep 16 10:20:16 lightningd[3534983]: DEBUG 026f46207fd290a33cbd86e29b3ad0a47cdd44ab9aa5267cde66483e10aa9d3180-channeld-chan#1804: Sending revoke_and_ack
Sep 16 10:20:16 lightningd[3534983]: DEBUG 026f46207fd290a33cbd86e29b3ad0a47cdd44ab9aa5267cde66483e10aa9d3180-channeld-chan#1804: htlc 3011: RCVD_ADD_COMMIT->SENT_ADD_REVOCATION
Sep 16 10:20:16 lightningd[3534983]: DEBUG 026f46207fd290a33cbd86e29b3ad0a47cdd44ab9aa5267cde66483e10aa9d3180-channeld-chan#1804: sending_revoke_and_ack: HTLC REMOTE 3011 = SENT_ADD_REVOCATION/RCVD_ADD_REVOCATION
Sep 16 10:20:16 lightningd[3534983]: DEBUG 026f46207fd290a33cbd86e29b3ad0a47cdd44ab9aa5267cde66483e10aa9d3180-channeld-chan#1804: revoke_and_ack made pending: commit timer
Sep 16 10:20:16 lightningd[3534983]: DEBUG 026f46207fd290a33cbd86e29b3ad0a47cdd44ab9aa5267cde66483e10aa9d3180-channeld-chan#1804: Sending master 1021
Sep 16 10:20:16 lightningd[3534983]: DEBUG 026f46207fd290a33cbd86e29b3ad0a47cdd44ab9aa5267cde66483e10aa9d3180-chan#1804: got commitsig 6016: feerate 271, blockheight: 0, 1 added, 0 fulfilled, 0 failed, 0 changed. 0 splice commitments.
Sep 16 10:20:16 lightningd[3534983]: DEBUG 026f46207fd290a33cbd86e29b3ad0a47cdd44ab9aa5267cde66483e10aa9d3180-chan#1804: Adding their HTLC 3011
Sep 16 10:20:16 lightningd[3534983]: DEBUG 026f46207fd290a33cbd86e29b3ad0a47cdd44ab9aa5267cde66483e10aa9d3180-chan#1804: HTLC in 3011 RCVD_ADD_COMMIT->SENT_ADD_REVOCATION
Sep 16 10:20:16 lightningd[3534983]: DEBUG 026f46207fd290a33cbd86e29b3ad0a47cdd44ab9aa5267cde66483e10aa9d3180-channeld-chan#1804: ... , awaiting 1121
Sep 16 10:20:16 lightningd[3534983]: DEBUG 026f46207fd290a33cbd86e29b3ad0a47cdd44ab9aa5267cde66483e10aa9d3180-hsmd: Got WIRE_HSMD_VALIDATE_COMMITMENT_TX
Sep 16 10:20:16 lightningd[3534983]: DEBUG 026f46207fd290a33cbd86e29b3ad0a47cdd44ab9aa5267cde66483e10aa9d3180-channeld-chan#1804: Got it!
Sep 16 10:20:16 lightningd[3534983]: DEBUG 026f46207fd290a33cbd86e29b3ad0a47cdd44ab9aa5267cde66483e10aa9d3180-hsmd: Got WIRE_HSMD_REVOKE_COMMITMENT_TX
I already tried disconnecting the peer, restarting the node and deleting my gossip_store, none of it made my node announce my side of the channel. Their side is announced and works in forwarding, mine does not obviously. I have confirmed this on lightning explorers aswell, they only have my peer's side.
I'm on cln 25.09