Large payments needing splice-in falling through the cracks? #78

1ma · 2024-07-18T21:03:40Z

1ma
Jul 18, 2024

I'm evaluating phoenixd on testnet and just observed a sequence of events that seem like a major bug.

Starting point: I'm running phoenixd 0.3.1 released earlier today, and I've got an already established channel with ACINQ of 3491108 sats, with an inbound liquidity of 2025584 sats.

$ phoenix-cli getinfo
{
    "nodeId": "031688a1eaab87a31af887a9518b0312bc86bf94c5653a9369c29b1b73588e4f61",
    "channels": [
        {
            "state": "Normal",
            "channelId": "74ad215d961308721c435b9fedb1d5eb1158a22e227479ec815bc01f817bbcef",
            "balanceSat": 1457524,
            "inboundLiquiditySat": 2025584,
            "capacitySat": 3491108,
            "fundingTxId": "3dfde0826691e81c36b1657e4a94a02a00f16c23fc9d4a32fe3e046ee4e0d8f4"
        }
    ],
    "chain": "testnet",
    "blockHeight": 2868896,
    "version": "0.3.1-b735020"
}

From the Phoenix Testnet app (commit 80ea438) I make a BOLT-12 payment of 5 million sats to phoenixd with the expectation that this will make the channel bigger with a splice-in transaction. The payment succeeds, but phoenixd doesn't acknowledge the receival in its stdout, and the channel UTXO is not spent.
I make another test transaction of just 12 sats. This time phoenixd acknowledges the payment in stdout, and the channel stays the same as it doesn't need any extra liquidity.
I send another 2.5 million sat BOLT-12 payment to test a third time (this one should splice-in the channel too). Phoenix again says that the payment succeeded while phoenixd doesn't acknolwedge it.
After these 3 payments the channel still has the same funding TX, and balance on phoenixd has just increased by 12 sats while decreased by 7500012 sats + fees on the Phoenix app.

App screenshot:

phoenixd output:

2024-07-18 15:31:01 datadir: /home/admin/.phoenix
2024-07-18 15:31:01 chain: Testnet
2024-07-18 15:31:01 autoLiquidity: 2000000 sat
2024-07-18 15:31:01 nodeid: 031688a1eaab87a31af887a9518b0312bc86bf94c5653a9369c29b1b73588e4f61
2024-07-18 15:31:01 offer: lno1qgsyxjtl6luzd9t3pr62xr7eemp6awnejusgf6gw45q75vcfqqqqqqqsespexwyy4tcadvgg89l9aljus6709kx235hhqrk6n8dey98uyuftzdqzcp4gsaf6kzt03gv6tqjgksrudfpf8ww4ntpnzj7kkwnut7dksmxqyqhtrjefqfwhxv49rdslkhcr8f5fksnd7kxgdg84fu9pfj0tyuujlsqr882nm960nqxmecq2h360jqqv7f64t05mfgghe2kmp3ehl2ezzxmwlfsu954xmztlwjkmvkfzhauygelclhczldqmdvxl9q870ljfxy9knyrku0pjakm7epl8su8wcj8vp5xc09rsqyyyg9aeswvesq2f33p6e389wdpj
2024-07-18 15:31:01 connecting to lightning peer...
2024-07-18 15:31:02 connected to lightning peer
2024-07-18 15:31:02 listening on http://127.0.0.1:9740
2024-07-18 15:49:12 received lightning payment: 1956968 sat (newchannel)
2024-07-18 15:50:47 received lightning payment: 1000 sat (lightningpayment)
2024-07-18 15:51:49 received lightning payment: 123 sat (lightningpayment)
2024-07-18 20:09:22 disconnected from lightning peer
2024-07-18 20:09:25 connecting to lightning peer...
2024-07-18 20:09:25 connected to lightning peer
2024-07-18 20:32:13 received lightning payment: 12 sat (lightningpayment)

phoenixd.log (some of the values in this log look sensitive, however being testnet I think it should be fine):

2024-07-18 20:25:06 Info: (fr.acinq.lightning.io.Peer) on-chain fees: Feerates(minimum=1 sat/byte, slow=1 sat/byte, medium=1 sat/byte, fast=1 sat/byte, fastest=1 sat/byte)
    remoteNodeId: 03933884aaf1d6b108397e5efe5c86bcf2d8ca8d2f700eda99db9214fc2712b134
2024-07-18 20:28:06 Info: (fr.acinq.lightning.io.Peer) on-chain fees: Feerates(minimum=1 sat/byte, slow=1 sat/byte, medium=1 sat/byte, fast=1 sat/byte, fastest=1 sat/byte)
    remoteNodeId: 03933884aaf1d6b108397e5efe5c86bcf2d8ca8d2f700eda99db9214fc2712b134
2024-07-18 20:30:45 Info: (fr.acinq.lightning.io.Peer) received OnionMessage(blindingKey=0304067b21ae3c9789756369475c4b5fc6e10c9545560f00b4bae9ffecc9a2f289, onionRoutingPacket=OnionRoutingPacket(version=0, publicKey=024404888a4a10401dfbaec3a217e121c920323b6b02dbd4917e4e0b937e5be7ca, payload=<1300 bytes>, hmac=dfb4de87e3c2d7e756a37302ac6acb9a5ec66caaf310094fb0f89ff316b7539c))
    remoteNodeId: 03933884aaf1d6b108397e5efe5c86bcf2d8ca8d2f700eda99db9214fc2712b134
    connectionId: 1721333365237
2024-07-18 20:30:45 Info: (fr.acinq.lightning.io.Peer) received OnionMessage
    remoteNodeId: 03933884aaf1d6b108397e5efe5c86bcf2d8ca8d2f700eda99db9214fc2712b134
    connectionId: 1721333365237
2024-07-18 20:30:45 Info: (fr.acinq.lightning.io.Peer) sending BOLT 12 invoice with amount=5000000000 msat, paymentHash=517e35179f44620161a21fab353ed266104810fec164cfe801f4318483a9ad5e, payerId=024ddff5790231a4f773d628c4b76a5d6068a7101f6d4a6c4b5bd9181d49b26312 to introduction node 03933884aaf1d6b108397e5efe5c86bcf2d8ca8d2f700eda99db9214fc2712b134
    remoteNodeId: 03933884aaf1d6b108397e5efe5c86bcf2d8ca8d2f700eda99db9214fc2712b134
2024-07-18 20:30:45 Info: (fr.acinq.lightning.io.Peer) sending OnionMessage(blindingKey=02e455ae376c60a89bfe267c637057f13b66bd4ea3fcc7ad9e2d98649b7f999f7c, onionRoutingPacket=OnionRoutingPacket(version=0, publicKey=03ba8faed589740a458180592298a5bee014e8e94f149c29aefb63af37b786ead6, payload=<0 bytes>, hmac=ee533a11f6aa069af851b4d351e2b5a86d5ab6502348bdd457267a1f81f5b17e)) (truncated payload)
    remoteNodeId: 03933884aaf1d6b108397e5efe5c86bcf2d8ca8d2f700eda99db9214fc2712b134
    connectionId: 1721333365237
2024-07-18 20:30:47 Info: (fr.acinq.lightning.io.Peer) received PayToOpenRequest(chainHash=43497fd7f826957108f4a30fd9cec3aeba79972084e90ead01ea330900000000, amountMsat=5000000000 msat, payToOpenFeeSatoshis=72439 sat, paymentHash=517e35179f44620161a21fab353ed266104810fec164cfe801f4318483a9ad5e, expireAt=1721334710, finalPacket=OnionRoutingPacket(version=0, publicKey=0261bf26ec6c857c011a1399ac927ce7ccce525e149ccbf606e1a308eef8650520, payload=d0e03aa9e7e3e5d87af0128a483949943107a938ea42cd65a53eb2b688ac2e69cf29a54dc08b7a0184b28ea257b371565d67f6bab859302c750cf3ada0401b132d560ed0113d49175932f4ed912f69aca759daa64a37626dfdebedbb566e962978e09134a6b71fa0acf786a5ec13a250293b6addb65ed11b7eabb22538142ebdb06b9759680b122814755eeb487f0582af8093206a34fa1e77e9f3e717fb74dc41a00a15defab260fb20ab08580d9281b4a6ccec1958909efe8c85f884a4246568e07e8ce0dc37cea91d9ccf886512fed37ba590f2579db770015a38265e9251b017a52268a76670807396f8028232e4e7e04290cbc7bc692f1a1aff0b9b7c2d225cce1678669eeb42a6820258610343cf774abaa2007177ddf4c91fdb5e63d19ff9f084dfe19ff882cc588bdb91601357c8cb7500d36da47207271b181561fe9c19279add45796feaf09f267dcb85c53bc11aded66f05b702d506dd882e73270d75b0001d3b8637fb7cccf9f430098784a3724d62cc292aba08de31e818f9b50d445557cbc8078af8f69c55ed4a851186afa0da8833498a963f549e13de4eecdcea9e0761be11a484b64825ee7ce0536522261eb9bfa3bd3e88152905968e74d39dc8a36cfecf4ef76821a5ad9f137d1d409aa24099fdfb555334722476aa335b92afbdef19cb5479345fa6ce248841b46b2250d2356ca2588346a52b40b151efbe2e18a21bfdb2d53d7f26401a16a4c2b17b2c1a1e3691a8c7f15d40fb133c1791b24a8b5ce265ec757ad05042bcde2b977e976a6d75fc20e095c7a48dbe7f8c87f80c073f325d39884c74e16c56385db427c546b5c5da1833b31cda0ea34b954dce6c5cf7a4c0b551e7b56370f316cbb94cf3a67aa87fe1af6fc25ee83ba9628a85d00ba0bc0032e1f5cf3c508b2dfc51e43d654dc3f4bfe16f0a180516dc6560a848a69f539bad96f3a6b5777399bf193227a6f8ae55ba9137ef696f79f2f7ff16322057989ad07c672c0b74fb9373d1f173434d16b15ff3a38698b58a604b5eb94f465c430f95ce274e01a3922da2b751454c6ecdc497edb2328810583550c0562161f81d2d7584c97e028c86614e7e5936cd15b1bd7a3dc84ac49c3f13c407b523ee3cb9dcd6e351a5d645768f94bcc0ad255868a5e1e5ad72378ecd5a34c9ac3912c5b5838df704999a42752211197eefd5f821987cb021f71b747c803b8f6317ef81fc786bb9fcf05a2f7a4383e444c13ab4eaaeba2c6772e6febe3539297f45f311df6bc6317cea82ab49a2f746242386e8537ea29ff98a9fc6c336aaebb500ebcc979b02b1c1721f086af88ac1a07f8788ba9f8c1b5f535c5c1bf33a0e06d61da3091d89e5825fd89f8db7b286611ecb5d67b71fe98f5f4f11e4ffc8bf57bb9e2ae39d3f51cb23b74cae1adba1c5073a2aba4a8b2643a7611de8528f11beea6d0bde74392e912769d4e4508b24060042e2d27733891f8133dfcbdb8d2b6ab955ca75e7bf0eb4f329e3057ec88755d1566facc33723d31855500f068b6a9dbb0780169c003fffa513bed9f5c4c3622b2dc07fd6621567c8003b132ec992eae7613049ede9477919bcb31f597fb193b7d2715f7fbc6cd4e30764e50ecdba918cef4fea79de54ba7e03b2ac8c31159ea2aa72425d96bdf0fc2bc202bfdd216cca91352977d92e48f69ba4ed0afa9f5fa52f67aa7f8ad1b76c707c9f6239784b14a0590f4ccf293f7d1aca3af7fe290088b905665468afbb1e69384ed5cb827dac82cc52cf763fe2136d40a20e95b7c57cb7cdc37f3b49511572397d2c44b79853f1ecdab8cf7adc71eebd92325ddbf1be, hmac=5c47142e99e1e2d4c133b2f54d5b6224c008d0d5831c2c997b78065fd9ec3ee1), liquidity=2000000 sat, tlvStream=TlvStream(records=[Blinding(publicKey=0224af96997434457a2e29dbb27197952517bbb138a75fd6107393924be684b265)], unknown=[]))
    remoteNodeId: 03933884aaf1d6b108397e5efe5c86bcf2d8ca8d2f700eda99db9214fc2712b134
    connectionId: 1721333365237
2024-07-18 20:30:47 Info: (fr.acinq.lightning.io.Peer) received PayToOpenRequest
    remoteNodeId: 03933884aaf1d6b108397e5efe5c86bcf2d8ca8d2f700eda99db9214fc2712b134
    connectionId: 1721333365237
2024-07-18 20:30:47 Info: (fr.acinq.lightning.payment.IncomingPaymentHandler) processing pay-to-open part amount=5000000000 msat fees=72439 sat
    paymentHash: 517e35179f44620161a21fab353ed266104810fec164cfe801f4318483a9ad5e
    amount: 5000000000 msat
    totalAmount: 5000000000 msat
2024-07-18 20:30:47 Info: (fr.acinq.lightning.payment.IncomingPaymentHandler) fee-credit liquidity policy check: amount=5000000000 msat fee=72439000 msat (miningFee=2439000 msat serviceFee=70000000 msat) maxMiningFee=20000000 msat currentFeeCredit=0 sat maxAllowedCredit=100000 sat policy=Auto(maxMiningFee=20000 sat, maxRelativeFeeBasisPoints=3000, skipMiningFeeCheck=false, maxAllowedCredit=100000 sat)
    paymentHash: 517e35179f44620161a21fab353ed266104810fec164cfe801f4318483a9ad5e
    amount: 5000000000 msat
    totalAmount: 5000000000 msat
2024-07-18 20:30:47 Info: (fr.acinq.lightning.payment.IncomingPaymentHandler) pay-to-open decision: Accepted(amount=5000000000 msat, fee=72439000 msat, source=OffChainPayment)
    paymentHash: 517e35179f44620161a21fab353ed266104810fec164cfe801f4318483a9ad5e
    amount: 5000000000 msat
    totalAmount: 5000000000 msat
2024-07-18 20:30:47 Info: (fr.acinq.lightning.payment.IncomingPaymentHandler) accepted pay-to-open
    paymentHash: 517e35179f44620161a21fab353ed266104810fec164cfe801f4318483a9ad5e
    amount: 5000000000 msat
    totalAmount: 5000000000 msat
2024-07-18 20:30:47 Info: (fr.acinq.lightning.payment.IncomingPaymentHandler) payment received (5000000000 msat) with blinded route
    paymentHash: 517e35179f44620161a21fab353ed266104810fec164cfe801f4318483a9ad5e
    amount: 5000000000 msat
    totalAmount: 5000000000 msat
2024-07-18 20:30:47 Info: (fr.acinq.lightning.io.Peer) sending PayToOpenResponse
    remoteNodeId: 03933884aaf1d6b108397e5efe5c86bcf2d8ca8d2f700eda99db9214fc2712b134
    connectionId: 1721333365237
2024-07-18 20:30:47 Info: (fr.acinq.lightning.io.Peer) sending PayToOpenResponse(chainHash=43497fd7f826957108f4a30fd9cec3aeba79972084e90ead01ea330900000000, paymentHash=517e35179f44620161a21fab353ed266104810fec164cfe801f4318483a9ad5e, result=Success(paymentPreimage=88a42221e99e9cc2929ab213b1669a002d0cf32234f7ca509214cfa351c2a7ee, addToFeeCredit=false))
    remoteNodeId: 03933884aaf1d6b108397e5efe5c86bcf2d8ca8d2f700eda99db9214fc2712b134
    connectionId: 1721333365237
2024-07-18 20:30:47 Info: (fr.acinq.lightning.io.Peer) received Stfu(channelId=74ad215d961308721c435b9fedb1d5eb1158a22e227479ec815bc01f817bbcef, initiator=true)
    remoteNodeId: 03933884aaf1d6b108397e5efe5c86bcf2d8ca8d2f700eda99db9214fc2712b134
    connectionId: 1721333365237
    channelId: 74ad215d961308721c435b9fedb1d5eb1158a22e227479ec815bc01f817bbcef
2024-07-18 20:30:47 Info: (fr.acinq.lightning.io.Peer) sending Stfu(channelId=74ad215d961308721c435b9fedb1d5eb1158a22e227479ec815bc01f817bbcef, initiator=false)
    remoteNodeId: 03933884aaf1d6b108397e5efe5c86bcf2d8ca8d2f700eda99db9214fc2712b134
    connectionId: 1721333365237
2024-07-18 20:30:47 Info: (fr.acinq.lightning.io.Peer) received SpliceInit(channelId=74ad215d961308721c435b9fedb1d5eb1158a22e227479ec815bc01f817bbcef, fundingContribution=6991986 sat, feerate=1500 sat/kw, lockTime=2868895, fundingPubkey=02bd4c12f54cddb0fd1e4372c14c558a694ee536415ce1fad8f7877cade64a8eae, tlvStream=TlvStream(records=[fr.acinq.lightning.wire.ChannelTlv.RequireConfirmedInputsTlv@5a458330, PushAmountTlv(amount=4927561000 msat), OriginsTlv(origins=[PayToOpenOrigin(paymentHash=517e35179f44620161a21fab353ed266104810fec164cfe801f4318483a9ad5e, serviceFee=72439000 msat, miningFee=0 sat, amount=4927561000 msat)])], unknown=[]))
    remoteNodeId: 03933884aaf1d6b108397e5efe5c86bcf2d8ca8d2f700eda99db9214fc2712b134
    connectionId: 1721333365237
    channelId: 74ad215d961308721c435b9fedb1d5eb1158a22e227479ec815bc01f817bbcef
2024-07-18 20:30:47 Info: (fr.acinq.lightning.channel.states.ChannelState) accepting splice with remote.amount=6991986 sat remote.push=4927561000 msat
    remoteNodeId: 03933884aaf1d6b108397e5efe5c86bcf2d8ca8d2f700eda99db9214fc2712b134
    state: Normal
    channelId: 74ad215d961308721c435b9fedb1d5eb1158a22e227479ec815bc01f817bbcef
    commitments: active=[1] inactive=[]
2024-07-18 20:30:47 Info: (fr.acinq.lightning.io.Peer) sending SpliceAck(channelId=74ad215d961308721c435b9fedb1d5eb1158a22e227479ec815bc01f817bbcef, fundingContribution=0 sat, fundingPubkey=02dd322e273a8f2322091e8e0d31b5f05b3581a2ad37760d550372ce8e2bfb9342, tlvStream=TlvStream(records=[], unknown=[]))
    remoteNodeId: 03933884aaf1d6b108397e5efe5c86bcf2d8ca8d2f700eda99db9214fc2712b134
    connectionId: 1721333365237
2024-07-18 20:30:47 Info: (fr.acinq.lightning.io.Peer) received TxAbort(channelId=74ad215d961308721c435b9fedb1d5eb1158a22e227479ec815bc01f817bbcef, data=6368616e6e656c2066756e64696e67206572726f72, tlvs=TlvStream(records=[], unknown=[]))
    remoteNodeId: 03933884aaf1d6b108397e5efe5c86bcf2d8ca8d2f700eda99db9214fc2712b134
    connectionId: 1721333365237
    channelId: 74ad215d961308721c435b9fedb1d5eb1158a22e227479ec815bc01f817bbcef
2024-07-18 20:30:47 Info: (fr.acinq.lightning.channel.states.ChannelState) our peer aborted the splice attempt: ascii='channel funding error' bin=6368616e6e656c2066756e64696e67206572726f72
    remoteNodeId: 03933884aaf1d6b108397e5efe5c86bcf2d8ca8d2f700eda99db9214fc2712b134
    state: Normal
    channelId: 74ad215d961308721c435b9fedb1d5eb1158a22e227479ec815bc01f817bbcef
    commitments: active=[1] inactive=[]
2024-07-18 20:30:47 Info: (fr.acinq.lightning.io.Peer) sending TxAbort(channelId=74ad215d961308721c435b9fedb1d5eb1158a22e227479ec815bc01f817bbcef, data=73706c6963652061626f72746564, tlvs=TlvStream(records=[], unknown=[]))
    remoteNodeId: 03933884aaf1d6b108397e5efe5c86bcf2d8ca8d2f700eda99db9214fc2712b134
    connectionId: 1721333365237
2024-07-18 20:31:06 Info: (fr.acinq.lightning.io.Peer) on-chain fees: Feerates(minimum=1 sat/byte, slow=1 sat/byte, medium=1 sat/byte, fast=1 sat/byte, fastest=1 sat/byte)
    remoteNodeId: 03933884aaf1d6b108397e5efe5c86bcf2d8ca8d2f700eda99db9214fc2712b134
2024-07-18 20:32:12 Info: (fr.acinq.lightning.io.Peer) received OnionMessage(blindingKey=0304067b21ae3c9789756369475c4b5fc6e10c9545560f00b4bae9ffecc9a2f289, onionRoutingPacket=OnionRoutingPacket(version=0, publicKey=033388df6a0eea190b5fe5ee70ffab32aff41235a18fa6f140fb47826bc89d68aa, payload=<1300 bytes>, hmac=66402b4e8b5672fd33d468c796b123715a8a6f121dc2d65dff72772467a3f47b))
    remoteNodeId: 03933884aaf1d6b108397e5efe5c86bcf2d8ca8d2f700eda99db9214fc2712b134
    connectionId: 1721333365237
2024-07-18 20:32:12 Info: (fr.acinq.lightning.io.Peer) received OnionMessage
    remoteNodeId: 03933884aaf1d6b108397e5efe5c86bcf2d8ca8d2f700eda99db9214fc2712b134
    connectionId: 1721333365237
2024-07-18 20:32:12 Info: (fr.acinq.lightning.io.Peer) sending BOLT 12 invoice with amount=12000 msat, paymentHash=78554aaf217e30dc12f89dfaa52f3ed7e0e6c1905f52786543c218c0fd695224, payerId=024ddff5790231a4f773d628c4b76a5d6068a7101f6d4a6c4b5bd9181d49b26312 to introduction node 03933884aaf1d6b108397e5efe5c86bcf2d8ca8d2f700eda99db9214fc2712b134
    remoteNodeId: 03933884aaf1d6b108397e5efe5c86bcf2d8ca8d2f700eda99db9214fc2712b134
2024-07-18 20:32:12 Info: (fr.acinq.lightning.io.Peer) sending OnionMessage(blindingKey=02c36af8d27fefafbb2694157c4f7535c8e4c428453b15f1c16ce9de9cd0aba4e3, onionRoutingPacket=OnionRoutingPacket(version=0, publicKey=03eac6ec25bf348ee3b4d5d30ea364e99e9c4bb84108b4af52c54d0a7646a577af, payload=<0 bytes>, hmac=e4da3db1eb1b438536fc448202b8ec573b639f5b401368e2cc6952a752c2d98d)) (truncated payload)
    remoteNodeId: 03933884aaf1d6b108397e5efe5c86bcf2d8ca8d2f700eda99db9214fc2712b134
    connectionId: 1721333365237
2024-07-18 20:32:13 Info: (fr.acinq.lightning.io.Peer) received UpdateAddHtlc(channelId=74ad215d961308721c435b9fedb1d5eb1158a22e227479ec815bc01f817bbcef, id=2, amountMsat=12000 msat, paymentHash=78554aaf217e30dc12f89dfaa52f3ed7e0e6c1905f52786543c218c0fd695224, cltvExpiry=CltvExpiry(2869003), onionRoutingPacket=OnionRoutingPacket(version=0, publicKey=03901f00ec0b53334452879cf0aa890724eaf65aea6ddd31a100627c617940edad, payload=4c6d1181128224d2b0319c324fc23f37d4e0b6e14a8f09083a5db203ba579abb50591f7e5e60610c353bd19b7ce61ca17b8e223b57b364697f7b0fbb19009f8fdad8206ecf0b58cee6ebab47f9c5c4787b272a94cd1b977b4e4796c439f88a59f05ed4c07c0c9795b85f40631d333f4ef8275e5f0f5ff335c2a6c9e1e969fcadea109a7aa145abb368d2e068da99d5773c0386f27447755b689830a57c0d904af5d81c2e745c1b1b6c194ff4bb40366dd7905cd6a74c2cefc1a8f9fc22ef2b71e4ca6f3c2a1e43d5350bdd8f79823718e6ea6d2d4ed81b47ddd998c491434539015bab6854e9b72a6fbacf5a62f695257194ef5e3c5c7a749f8490b0ccbdab9d71ca33d9979424ae42d03e9b0f6d04b896fb7a2b5c7e8de0341c910d6825802e97c3bc8df79eb4846c6d1ffb2a508b2c2dc445b88b3caed884881812983fd0fa5a1f30048ac429a648e984c492f3b5771cf244cee1b215ce761c75dcf9476765b9af46169e85685c1d246bab47acfe809d2e4b56abac4c9340ef5e915c12c58a020252fb1dccd57be5d87f3fa42bd7bcb1c8f30a90dadee229ba7e0961185ec51c75a6d7bb9a4533fb555e3eada472e632acecff6625d7b349092f0bf90925045d7104973e311d35b09224f822ae7c88f94f958cd0f5ce7a775570dcb8d9393a3ac4ba2597c044d2f9b1354c4bde5e8a61b2d8cbe77179ff82dafce63002538599fb8c122043f88c4eb2f941b60eb95d76e41dc58b6736710c9cc1946a9885051d1afb14fa9734fe98fd597d7fc3318df88a53d0f3332cc58f18720d4406d9c3d6f248bd3412b059fefdceeef03e839e297005fd33b7d281c39f6d07b37f2a27c88146dfd991a49e7ac86ab6228e36f4bfd2ec347c2b93803e6364fc58f8841943a60d057e86b46f38ce9e7e5be862c5b9686f42652adf7a3afa567522156e136928acf0c66f711f1647f54aed53325a20a67d94b18e33635d2f022b632b1f1e88dd75cd11deb5dfc672e5a6ba74b81f398b127cea7de8a5365474b0492f2f52598367b437a879b02414f1cbfdab4e1acfdc5beca31ab553a26692b5f7c1708ebb411f7be93068cbe043afb2776385bd69a095c7d7813ca1fbdcafb9e0915367e9a0ca6112c3db26dd1e3d6e096ca8e32b3fe957a10ce5748a6324e15e86c5d61768c272877f46725978ed00b2c5b97cc46c140caf983f44a1baa3556f16a2e26ed83100368ca676e2b677e3c92ed8eaf6d0cd2812fd15ef32afc598922205a40e9fd673e55b4d96854ad3110d3cf04c225b8a77516cdde1886ad5a5ceb9f40b9f5839f335df3cc7aa59078509a50886c0b86fb1900271f93a4e70c6fdd1ca632aa8490b35218b4e9054c0ea490245655448c798c965756c9cc35bb8511a2ca1a2adfac13250c35ddc9b8c043dda08432c6d1008bcc6e8b96d7105de0f0a1653299e5b4dc83ba0ef05c0119ccf2e765b60fb54d99bff11b7fb60b70d03c1698bcceb03f79a2cb524fe17522868bdd836631b0d596a767f18f2b4cd09ed4b2bd1f4d5d97e57a1621813bcf0a0e0c9ab3a5a597b66646ae7e9199f9b6c2f083ed8b4acf1500511db48df54f643f045f74042973df491f1532e069fbe354a1848263c35f306b398ad1c4f0e4bd6a8e26bde3b2cb88b8e44c2e7b1b92a1b3d5c31a265dd4f785b1690ff2c6d1a7c7516c53b46a1a24b7506427965ca6c2dc3e199f4cc7d9b846a456a0711824e5d77cf4f188d3663e7bf6b2f5efc095732ffe9adadc0e806289bffeb86303e46ab7ef3e65ba8181f57cd145f88e4c73b2043ee74014169340ea85e0b9b666a02ac2fcb58d63a263d0d, hmac=ac3c4092d015fa5f6ec1bb6fcb1b5b010e8f8ba34b3f5d1a26ff7abf4bb75419), tlvStream=TlvStream(records=[Blinding(publicKey=030d9b941b3746b6607fb8c3ce06523cba1cc106c57bb1012c8e09dc75e533e08d)], unknown=[]))
    remoteNodeId: 03933884aaf1d6b108397e5efe5c86bcf2d8ca8d2f700eda99db9214fc2712b134
    connectionId: 1721333365237
    channelId: 74ad215d961308721c435b9fedb1d5eb1158a22e227479ec815bc01f817bbcef
2024-07-18 20:32:13 Info: (fr.acinq.lightning.io.Peer) received CommitSig(channelId=74ad215d961308721c435b9fedb1d5eb1158a22e227479ec815bc01f817bbcef, signature=037fad255e329de3c5c4cd36ceaddda120130e9b16955ec606cb19f16c4348ef4160d584e2b6cbaffae4ed4245996ad905e94994dc6cbbeb41a6b060e425e32a, htlcSignatures=[], tlvStream=TlvStream(records=[], unknown=[]))
    remoteNodeId: 03933884aaf1d6b108397e5efe5c86bcf2d8ca8d2f700eda99db9214fc2712b134
    connectionId: 1721333365237
    channelId: 74ad215d961308721c435b9fedb1d5eb1158a22e227479ec815bc01f817bbcef
2024-07-18 20:32:13 Info: (fr.acinq.lightning.channel.states.ChannelState) built local commit number=5 toLocalMsat=1457512000 toRemoteMsat=2033584000 htlc_in=2 htlc_out= feeratePerKw=2500 sat/kw txid=e12c1f25f7fca85e158c25e35484ba65626cca8ab17a9a0034f205b732a21873 fundingTxId=3dfde0826691e81c36b1657e4a94a02a00f16c23fc9d4a32fe3e046ee4e0d8f4
    remoteNodeId: 03933884aaf1d6b108397e5efe5c86bcf2d8ca8d2f700eda99db9214fc2712b134
    state: Normal
    channelId: 74ad215d961308721c435b9fedb1d5eb1158a22e227479ec815bc01f817bbcef
    commitments: active=[1] inactive=[]
2024-07-18 20:32:13 Info: (fr.acinq.lightning.io.Peer) storing state=Normal
    remoteNodeId: 03933884aaf1d6b108397e5efe5c86bcf2d8ca8d2f700eda99db9214fc2712b134
    channelId: 74ad215d961308721c435b9fedb1d5eb1158a22e227479ec815bc01f817bbcef
2024-07-18 20:32:13 Info: (fr.acinq.lightning.channel.states.ChannelState) built remote commit number=5 toLocalMsat=2033584000 toRemoteMsat=1457512000 htlc_in=2 htlc_out= feeratePerKw=2500 sat/kw txId=aae29461b999d9a1322b8b91f6f404ca62a8256cd19953809f1ccff00eb17016 fundingTxId=3dfde0826691e81c36b1657e4a94a02a00f16c23fc9d4a32fe3e046ee4e0d8f4
    remoteNodeId: 03933884aaf1d6b108397e5efe5c86bcf2d8ca8d2f700eda99db9214fc2712b134
    state: Normal
    channelId: 74ad215d961308721c435b9fedb1d5eb1158a22e227479ec815bc01f817bbcef
    commitments: active=[1] inactive=[]
2024-07-18 20:32:13 Info: (fr.acinq.lightning.io.Peer) storing state=Normal
    remoteNodeId: 03933884aaf1d6b108397e5efe5c86bcf2d8ca8d2f700eda99db9214fc2712b134
    channelId: 74ad215d961308721c435b9fedb1d5eb1158a22e227479ec815bc01f817bbcef
2024-07-18 20:32:13 Info: (fr.acinq.lightning.io.Peer) sending RevokeAndAck(channelId=74ad215d961308721c435b9fedb1d5eb1158a22e227479ec815bc01f817bbcef, perCommitmentSecret=<private_key>, nextPerCommitmentPoint=03e0aa82b2cbc65a5943ca5c98c7b2559200e4f8802dfb9331072ea8d6b3aafdec, tlvStream=TlvStream(records=[ChannelData(ecb=0402b1dd35bf33b76fed (truncated))], unknown=[]))
    remoteNodeId: 03933884aaf1d6b108397e5efe5c86bcf2d8ca8d2f700eda99db9214fc2712b134
    connectionId: 1721333365237
2024-07-18 20:32:13 Info: (fr.acinq.lightning.io.Peer) sending CommitSig(channelId=74ad215d961308721c435b9fedb1d5eb1158a22e227479ec815bc01f817bbcef, signature=11287718b5c2e6f594f2d026ea04b5750b43cbc994ec902d7d7130fe1d041e1e5ded4819df3c7e2588933de368b60d0955b7ff344d8b961a01cb5d702e11b418, htlcSignatures=[], tlvStream=TlvStream(records=[ChannelData(ecb=040295f4e93002b33dea (truncated))], unknown=[]))
    remoteNodeId: 03933884aaf1d6b108397e5efe5c86bcf2d8ca8d2f700eda99db9214fc2712b134
    connectionId: 1721333365237
2024-07-18 20:32:13 Info: (fr.acinq.lightning.io.Peer) received RevokeAndAck(channelId=74ad215d961308721c435b9fedb1d5eb1158a22e227479ec815bc01f817bbcef, perCommitmentSecret=<private_key>, nextPerCommitmentPoint=03680d55b802623091c21533c66829ddc09eafd44fa361aed7dd29dd09850fe04b, tlvStream=TlvStream(records=[], unknown=[]))
    remoteNodeId: 03933884aaf1d6b108397e5efe5c86bcf2d8ca8d2f700eda99db9214fc2712b134
    connectionId: 1721333365237
    channelId: 74ad215d961308721c435b9fedb1d5eb1158a22e227479ec815bc01f817bbcef
2024-07-18 20:32:13 Info: (fr.acinq.lightning.io.Peer) storing state=Normal
    remoteNodeId: 03933884aaf1d6b108397e5efe5c86bcf2d8ca8d2f700eda99db9214fc2712b134
    channelId: 74ad215d961308721c435b9fedb1d5eb1158a22e227479ec815bc01f817bbcef
2024-07-18 20:32:13 Info: (fr.acinq.lightning.payment.IncomingPaymentHandler) processing htlc part expiry=CltvExpiry(2869003)
    paymentHash: 78554aaf217e30dc12f89dfaa52f3ed7e0e6c1905f52786543c218c0fd695224
    amount: 12000 msat
    totalAmount: 12000 msat
2024-07-18 20:32:13 Info: (fr.acinq.lightning.payment.IncomingPaymentHandler) payment received (12000 msat) with blinded route
    paymentHash: 78554aaf217e30dc12f89dfaa52f3ed7e0e6c1905f52786543c218c0fd695224
    amount: 12000 msat
    totalAmount: 12000 msat
2024-07-18 20:32:13 Info: (fr.acinq.lightning.channel.states.ChannelState) built remote commit number=6 toLocalMsat=2033584000 toRemoteMsat=1457524000 htlc_in= htlc_out= feeratePerKw=2500 sat/kw txId=440f981fc87d17eb09513d502f46534716557b6f201d0e03d470713f6dae616c fundingTxId=3dfde0826691e81c36b1657e4a94a02a00f16c23fc9d4a32fe3e046ee4e0d8f4
    remoteNodeId: 03933884aaf1d6b108397e5efe5c86bcf2d8ca8d2f700eda99db9214fc2712b134
    state: Normal
    channelId: 74ad215d961308721c435b9fedb1d5eb1158a22e227479ec815bc01f817bbcef
    commitments: active=[1] inactive=[]
2024-07-18 20:32:13 Info: (fr.acinq.lightning.io.Peer) storing state=Normal
    remoteNodeId: 03933884aaf1d6b108397e5efe5c86bcf2d8ca8d2f700eda99db9214fc2712b134
    channelId: 74ad215d961308721c435b9fedb1d5eb1158a22e227479ec815bc01f817bbcef
2024-07-18 20:32:13 Info: (fr.acinq.lightning.io.Peer) sending UpdateFulfillHtlc(channelId=74ad215d961308721c435b9fedb1d5eb1158a22e227479ec815bc01f817bbcef, id=2, paymentPreimage=0316c5dfc8d097f35cae2d37719d0723f9fcc7bb103f6cdef197692c38cecd0a)
    remoteNodeId: 03933884aaf1d6b108397e5efe5c86bcf2d8ca8d2f700eda99db9214fc2712b134
    connectionId: 1721333365237
2024-07-18 20:32:13 Info: (fr.acinq.lightning.io.Peer) sending CommitSig(channelId=74ad215d961308721c435b9fedb1d5eb1158a22e227479ec815bc01f817bbcef, signature=ad4ffd10507a6d535df2c6fe097ddd48d6b2a68635b13b1fc8e0a61883842f802d3ee92f0161d94793777c31347abb0cc21290dda56b85c725d56cd78af8bbf8, htlcSignatures=[], tlvStream=TlvStream(records=[AlternativeFeerateSigs(sigs=[AlternativeFeerateSig(feerate=253 sat/kw, sig=d604aa19331019b3f9c30aeb096477732971ca44be5fd9e2f1c6c1b2ff89de51301a04f0c0b32cfcce64c3f8008b017b33a2883fe77d12c159c05629bdf2131d), AlternativeFeerateSig(feerate=500 sat/kw, sig=194f2f8b655a77977b7d4980296e78aa71d688c37203519d941799577211ddd0177b13b7d42e85610dda5e4c6034f03709bc00689577eb944d1da4810d7479cc), AlternativeFeerateSig(feerate=1250 sat/kw, sig=f163ba309085ae98f4a5bf3ae46b0a3d9ca9bf948ba016a6b859dc77ff714afa04e0f87840276cc0170e66ce5d85459ce17a7408aaae220a1811d21542af88aa), AlternativeFeerateSig(feerate=2500 sat/kw, sig=ad4ffd10507a6d535df2c6fe097ddd48d6b2a68635b13b1fc8e0a61883842f802d3ee92f0161d94793777c31347abb0cc21290dda56b85c725d56cd78af8bbf8)]), ChannelData(ecb=0402a0b1a2ebfb96d9b5 (truncated))], unknown=[]))
    remoteNodeId: 03933884aaf1d6b108397e5efe5c86bcf2d8ca8d2f700eda99db9214fc2712b134
    connectionId: 1721333365237
2024-07-18 20:32:13 Info: (fr.acinq.lightning.io.Peer) received RevokeAndAck(channelId=74ad215d961308721c435b9fedb1d5eb1158a22e227479ec815bc01f817bbcef, perCommitmentSecret=<private_key>, nextPerCommitmentPoint=03c3a7af69d81992abd0ddf3247add152f8a71b7ae69caa10730359a6e8e7c876d, tlvStream=TlvStream(records=[], unknown=[]))
    remoteNodeId: 03933884aaf1d6b108397e5efe5c86bcf2d8ca8d2f700eda99db9214fc2712b134
    connectionId: 1721333365237
    channelId: 74ad215d961308721c435b9fedb1d5eb1158a22e227479ec815bc01f817bbcef
2024-07-18 20:32:13 Info: (fr.acinq.lightning.io.Peer) storing state=Normal
    remoteNodeId: 03933884aaf1d6b108397e5efe5c86bcf2d8ca8d2f700eda99db9214fc2712b134
    channelId: 74ad215d961308721c435b9fedb1d5eb1158a22e227479ec815bc01f817bbcef
2024-07-18 20:32:13 Info: (fr.acinq.lightning.io.Peer) received CommitSig(channelId=74ad215d961308721c435b9fedb1d5eb1158a22e227479ec815bc01f817bbcef, signature=57a6e9180c66ab2993cfeeeb2f7b74dde376e676be208bdcf99346fe515cf3466714f332df2dc7eeb9c1042e0887c026c64c9521b6ecfb628a2eb54da09f99ff, htlcSignatures=[], tlvStream=TlvStream(records=[], unknown=[]))
    remoteNodeId: 03933884aaf1d6b108397e5efe5c86bcf2d8ca8d2f700eda99db9214fc2712b134
    connectionId: 1721333365237
    channelId: 74ad215d961308721c435b9fedb1d5eb1158a22e227479ec815bc01f817bbcef
2024-07-18 20:32:13 Info: (fr.acinq.lightning.channel.states.ChannelState) built local commit number=6 toLocalMsat=1457524000 toRemoteMsat=2033584000 htlc_in= htlc_out= feeratePerKw=2500 sat/kw txid=dc1d8204ce20cf3538b7b862f81b6f2b7aac8f110d4838f675601ee136b4834b fundingTxId=3dfde0826691e81c36b1657e4a94a02a00f16c23fc9d4a32fe3e046ee4e0d8f4
    remoteNodeId: 03933884aaf1d6b108397e5efe5c86bcf2d8ca8d2f700eda99db9214fc2712b134
    state: Normal
    channelId: 74ad215d961308721c435b9fedb1d5eb1158a22e227479ec815bc01f817bbcef
    commitments: active=[1] inactive=[]
2024-07-18 20:32:13 Info: (fr.acinq.lightning.io.Peer) storing state=Normal
    remoteNodeId: 03933884aaf1d6b108397e5efe5c86bcf2d8ca8d2f700eda99db9214fc2712b134
    channelId: 74ad215d961308721c435b9fedb1d5eb1158a22e227479ec815bc01f817bbcef
2024-07-18 20:32:13 Info: (fr.acinq.lightning.io.Peer) sending RevokeAndAck(channelId=74ad215d961308721c435b9fedb1d5eb1158a22e227479ec815bc01f817bbcef, perCommitmentSecret=<private_key>, nextPerCommitmentPoint=03b81e70d22e9c6d6a57358f18f624460bf257246eada5678ea11ad078957e3131, tlvStream=TlvStream(records=[ChannelData(ecb=040281e5a8b587fe6cae (truncated))], unknown=[]))
    remoteNodeId: 03933884aaf1d6b108397e5efe5c86bcf2d8ca8d2f700eda99db9214fc2712b134
    connectionId: 1721333365237
2024-07-18 20:32:51 Info: (fr.acinq.lightning.io.Peer) received OnionMessage(blindingKey=0304067b21ae3c9789756369475c4b5fc6e10c9545560f00b4bae9ffecc9a2f289, onionRoutingPacket=OnionRoutingPacket(version=0, publicKey=0298c81eb2329503fc686debbf832572d535379a3019da477cecc45f24d0a4dfa8, payload=<1300 bytes>, hmac=399a705d950f6a071f945ab99decb9412cb68eb454689e18534f33ff0fb89440))
    remoteNodeId: 03933884aaf1d6b108397e5efe5c86bcf2d8ca8d2f700eda99db9214fc2712b134
    connectionId: 1721333365237
2024-07-18 20:32:51 Info: (fr.acinq.lightning.io.Peer) received OnionMessage
    remoteNodeId: 03933884aaf1d6b108397e5efe5c86bcf2d8ca8d2f700eda99db9214fc2712b134
    connectionId: 1721333365237
2024-07-18 20:32:51 Info: (fr.acinq.lightning.io.Peer) sending BOLT 12 invoice with amount=2500000000 msat, paymentHash=a76c4cc20a667fdb041526d999ab9a3e1402ffda6a4284c13a5ee33e1f288bb3, payerId=024ddff5790231a4f773d628c4b76a5d6068a7101f6d4a6c4b5bd9181d49b26312 to introduction node 03933884aaf1d6b108397e5efe5c86bcf2d8ca8d2f700eda99db9214fc2712b134
    remoteNodeId: 03933884aaf1d6b108397e5efe5c86bcf2d8ca8d2f700eda99db9214fc2712b134
2024-07-18 20:32:51 Info: (fr.acinq.lightning.io.Peer) sending OnionMessage(blindingKey=03791fe3f653f084142e6a8c804a65574b29d3fcb9eccba7fc176b4b8c6f758d1a, onionRoutingPacket=OnionRoutingPacket(version=0, publicKey=02ff34dff42ae98099ae5967d3fc0d5b6af6bc1ca259b99ab2f2be08f96f20bf7a, payload=<0 bytes>, hmac=c33d35025a18ee5b662ef50c01f594d18441e10dd643beb0c5b4cb4a60a23c0c)) (truncated payload)
    remoteNodeId: 03933884aaf1d6b108397e5efe5c86bcf2d8ca8d2f700eda99db9214fc2712b134
    connectionId: 1721333365237
2024-07-18 20:32:53 Info: (fr.acinq.lightning.io.Peer) received PayToOpenRequest(chainHash=43497fd7f826957108f4a30fd9cec3aeba79972084e90ead01ea330900000000, amountMsat=2500000000 msat, payToOpenFeeSatoshis=47439 sat, paymentHash=a76c4cc20a667fdb041526d999ab9a3e1402ffda6a4284c13a5ee33e1f288bb3, expireAt=1721334836, finalPacket=OnionRoutingPacket(version=0, publicKey=0272e11a6dd4f276f06b853ddad6b6770eafa9191a4bd2c960ac65108d4400277e, payload=e357b07c4a492b56ff347a4ba7eea0a187541ec9d7768a6106bdc812815083fa019b249b8c87cadf1b62345368780922dbf21f1a4ad18ed12175f811338e3051860b15c4d2bd52b24b722349f01444f2da5b6e1cb2b5c92fa29abba5a16e8278f1eddccda9d62c7dcdd7b7825872302f28b7f22587ddbc55ec093f765279b25d4261c9ad1f6d5902d368f0c8e9fd16bd2b6b8bafc4097c5564f3a383552af407f3edd2bb9f5798629eee9420c217e043b38ec458ceb4fe5587713a1f22ae090d16fbd238741133558604c38d503046823283f493f6929f9ce261c0ea653652d58c78b3a9728cedbef6a0ccd36c45d9c25127572e3eb5f67e273c71a19bf7701d93c965c5f8460ecad895ba87c408f1b374a78d6ac31adb7aaad8258356ac8c64093f3ba4a81207c7f2958e7c90eded515c44c0da96b692a5f6e78947ab8a020f62b97eaee99d7caf2a20952fed1d94722ecd511623a7ebf363e3e617fb18870bbef8132cf1bca3ba4b22ddc7ffdf087f04d648959eb0b59832e6c7909488f319e962d4729affb729677dee585227b6ec641dbec643854fae86582280c615465addc06e6f91703ad6bcbdb429b212b477131e0706f28ff91f7bda4a56464d3a9dbdfd4692a0e52b554a880b05f6b3eee90938d9a3ffb59428aa6d68663c34815636f45802878263ac5aa9b0f2eed1152ef4f80d47107abd377c5ceee0cb73582fe2d4a4562d4781c14f036e80417cc58ba697b3c44a8c3a210564e8e800537dfc899a9b1f9f8734d25418c01dac0bd3fd10833ab749f48f03c1615af0d8f5391e6fba2872db3cbe7a7cb613e6bb1d3a5dc5a27490f109b3c7a6d3e0269b25758e4ad7468bc7559b3628155729fd6a717a8469b0b4e4b3c85f77aaee94ff591eb6fd934190ac462a27e02caf28c0eb38575f205f45ea031790713beaf8b5d0e11ed11249477498caca5b1a6df1def256ace4ce8fce38dae64f28187127c036fcee886e72108758261ec52148a39a9d6f6ce202841398315024bf7504e52398a5187588bb2559eebb7e0cc3c55a5d8ede8787829ac13b57e989c582c8b2f98467295b86765b647b7a8e2070440268cc28dd119dde8b2ec94eeecaf43aaafc1fbc6d8e427e670c112234aa92361bae35deb75098fc2ecdd4a37a04b37cb6948b83c5a92ff547914ad5cd6628017f188a26ec5249f81b27f3c3dd08b72ba77c66dad853f8c5704442b3db06f07bcbafd7bdace2d5bd2197900af5eb51131a5635173e5a2540f4e0ba3d44da7909104ea3268aaa7a0b3ab0ebe5e5d1099a9b84ea76568eb1dbbd7620194d8ee5175fb4521981d1ef237a17bd6dbf948e61cfaa7ccfe40218973ca2e72dd7535823557d67d77cf4b22915b092cebdb665084f29d4208bc2c504f81fb40cb777af32778bf8cb3ee6291afced13371aa2feed3535640fa4161276a0bf4d9f6d2e990360dbce4042fbdb7c66853b6140c3384f457286b236ca8750e7d047dd47ab4c498603f1704d5355fb599354f7ec60a1e39ad50978b48f7441349d4fafdeb8b8a853ac47ce1fef722c52b5cc00ca741c601a82b3b4c387fd5b4fcb5fb5ecae01b6c5b18f6d4d3cd9b86229fe8903adc861654edec65a2ce5d12773415e9cc5d1d1753aafb07a3b452f2687c9a0733c86872d8b81c7f35e902073416147b61e3a147beb0398871c19761b5fd4060f87d593f3298dbff580f952a9dd00da7eba5f2e816bc33edb5fb11caac7a4151866cee06ea4b5759e6465881e42dca0544909cbbfc297b93151fd0145220bca68cc9b80180089a8338512dac3406d9ecdfe1ad482a603f6688da8a92f, hmac=0c18bef42e37b8f0caea062ac8813bd36bf55466d6c0d69a941b45134d92d824), liquidity=2000000 sat, tlvStream=TlvStream(records=[Blinding(publicKey=0361ec294deb25cef1263d69f96b084a7f83b5dadfa17be60cebf1636b3c59d2df)], unknown=[]))
    remoteNodeId: 03933884aaf1d6b108397e5efe5c86bcf2d8ca8d2f700eda99db9214fc2712b134
    connectionId: 1721333365237
2024-07-18 20:32:53 Info: (fr.acinq.lightning.io.Peer) received PayToOpenRequest
    remoteNodeId: 03933884aaf1d6b108397e5efe5c86bcf2d8ca8d2f700eda99db9214fc2712b134
    connectionId: 1721333365237
2024-07-18 20:32:53 Info: (fr.acinq.lightning.payment.IncomingPaymentHandler) processing pay-to-open part amount=2500000000 msat fees=47439 sat
    paymentHash: a76c4cc20a667fdb041526d999ab9a3e1402ffda6a4284c13a5ee33e1f288bb3
    amount: 2500000000 msat
    totalAmount: 2500000000 msat
2024-07-18 20:32:53 Info: (fr.acinq.lightning.payment.IncomingPaymentHandler) fee-credit liquidity policy check: amount=2500000000 msat fee=47439000 msat (miningFee=2439000 msat serviceFee=45000000 msat) maxMiningFee=20000000 msat currentFeeCredit=0 sat maxAllowedCredit=100000 sat policy=Auto(maxMiningFee=20000 sat, maxRelativeFeeBasisPoints=3000, skipMiningFeeCheck=false, maxAllowedCredit=100000 sat)
    paymentHash: a76c4cc20a667fdb041526d999ab9a3e1402ffda6a4284c13a5ee33e1f288bb3
    amount: 2500000000 msat
    totalAmount: 2500000000 msat
2024-07-18 20:32:53 Info: (fr.acinq.lightning.payment.IncomingPaymentHandler) pay-to-open decision: Accepted(amount=2500000000 msat, fee=47439000 msat, source=OffChainPayment)
    paymentHash: a76c4cc20a667fdb041526d999ab9a3e1402ffda6a4284c13a5ee33e1f288bb3
    amount: 2500000000 msat
    totalAmount: 2500000000 msat
2024-07-18 20:32:53 Info: (fr.acinq.lightning.payment.IncomingPaymentHandler) accepted pay-to-open
    paymentHash: a76c4cc20a667fdb041526d999ab9a3e1402ffda6a4284c13a5ee33e1f288bb3
    amount: 2500000000 msat
    totalAmount: 2500000000 msat
2024-07-18 20:32:53 Info: (fr.acinq.lightning.payment.IncomingPaymentHandler) payment received (2500000000 msat) with blinded route
    paymentHash: a76c4cc20a667fdb041526d999ab9a3e1402ffda6a4284c13a5ee33e1f288bb3
    amount: 2500000000 msat
    totalAmount: 2500000000 msat
2024-07-18 20:32:53 Info: (fr.acinq.lightning.io.Peer) sending PayToOpenResponse
    remoteNodeId: 03933884aaf1d6b108397e5efe5c86bcf2d8ca8d2f700eda99db9214fc2712b134
    connectionId: 1721333365237
2024-07-18 20:32:53 Info: (fr.acinq.lightning.io.Peer) sending PayToOpenResponse(chainHash=43497fd7f826957108f4a30fd9cec3aeba79972084e90ead01ea330900000000, paymentHash=a76c4cc20a667fdb041526d999ab9a3e1402ffda6a4284c13a5ee33e1f288bb3, result=Success(paymentPreimage=54684443f4ce8c93bccfc67c1af796a32273ac6d28786fc5a28b2156fa12c3d7, addToFeeCredit=false))
    remoteNodeId: 03933884aaf1d6b108397e5efe5c86bcf2d8ca8d2f700eda99db9214fc2712b134
    connectionId: 1721333365237
2024-07-18 20:34:06 Info: (fr.acinq.lightning.io.Peer) on-chain fees: Feerates(minimum=1 sat/byte, slow=1 sat/byte, medium=1 sat/byte, fast=1 sat/byte, fastest=1 sat/byte)
    remoteNodeId: 03933884aaf1d6b108397e5efe5c86bcf2d8ca8d2f700eda99db9214fc2712b134
2024-07-18 20:37:07 Info: (fr.acinq.lightning.io.Peer) on-chain fees: Feerates(minimum=1 sat/byte, slow=1 sat/byte, medium=1 sat/byte, fast=1 sat/byte, fastest=1 sat/byte)
    remoteNodeId: 03933884aaf1d6b108397e5efe5c86bcf2d8ca8d2f700eda99db9214fc2712b134
2024-07-18 20:40:07 Info: (fr.acinq.lightning.io.Peer) on-chain fees: Feerates(minimum=1 sat/byte, slow=1 sat/byte, medium=1 sat/byte, fast=1 sat/byte, fastest=1 sat/byte)
    remoteNodeId: 03933884aaf1d6b108397e5efe5c86bcf2d8ca8d2f700eda99db9214fc2712b134

Answered by pm47

Jul 19, 2024

Hello, our testnet node was out of suitable utxos to honor the channel requests, hence the our peer aborted the splice attempt: ascii='channel funding error'. In that case they are automatically retried and finally took place a few minutes ago when we fixed the hot wallet.

View full answer

1ma · 2024-07-18T21:49:26Z

1ma
Jul 18, 2024
Author

Narrowed down the phoenix.log down to this.

First payment:

2024-07-18 20:30:47 Info: (fr.acinq.lightning.payment.IncomingPaymentHandler) pay-to-open decision: Accepted(amount=5000000000 msat, fee=72439000 msat, source=OffChainPayment)
    paymentHash: 517e35179f44620161a21fab353ed266104810fec164cfe801f4318483a9ad5e
    amount: 5000000000 msat
    totalAmount: 5000000000 msat
2024-07-18 20:30:47 Info: (fr.acinq.lightning.payment.IncomingPaymentHandler) accepted pay-to-open
    paymentHash: 517e35179f44620161a21fab353ed266104810fec164cfe801f4318483a9ad5e
    amount: 5000000000 msat
    totalAmount: 5000000000 msat
2024-07-18 20:30:47 Info: (fr.acinq.lightning.payment.IncomingPaymentHandler) payment received (5000000000 msat) with blinded route
    paymentHash: 517e35179f44620161a21fab353ed266104810fec164cfe801f4318483a9ad5e
    amount: 5000000000 msat
    totalAmount: 5000000000 msat
2024-07-18 20:30:47 Info: (fr.acinq.lightning.io.Peer) sending PayToOpenResponse
    remoteNodeId: 03933884aaf1d6b108397e5efe5c86bcf2d8ca8d2f700eda99db9214fc2712b134
    connectionId: 1721333365237
2024-07-18 20:30:47 Info: (fr.acinq.lightning.io.Peer) sending PayToOpenResponse(chainHash=43497fd7f826957108f4a30fd9cec3aeba79972084e90ead01ea330900000000, paymentHash=517e35179f44620161a21fab353ed266104810fec164cfe801f4318483a9ad5e, result=Success(paymentPreimage=88a42221e99e9cc2929ab213b1669a002d0cf32234f7ca509214cfa351c2a7ee, addToFeeCredit=false))
    remoteNodeId: 03933884aaf1d6b108397e5efe5c86bcf2d8ca8d2f700eda99db9214fc2712b134
    connectionId: 1721333365237
2024-07-18 20:30:47 Info: (fr.acinq.lightning.io.Peer) received Stfu(channelId=74ad215d961308721c435b9fedb1d5eb1158a22e227479ec815bc01f817bbcef, initiator=true)
    remoteNodeId: 03933884aaf1d6b108397e5efe5c86bcf2d8ca8d2f700eda99db9214fc2712b134
    connectionId: 1721333365237
    channelId: 74ad215d961308721c435b9fedb1d5eb1158a22e227479ec815bc01f817bbcef
2024-07-18 20:30:47 Info: (fr.acinq.lightning.io.Peer) sending Stfu(channelId=74ad215d961308721c435b9fedb1d5eb1158a22e227479ec815bc01f817bbcef, initiator=false)
    remoteNodeId: 03933884aaf1d6b108397e5efe5c86bcf2d8ca8d2f700eda99db9214fc2712b134
    connectionId: 1721333365237
2024-07-18 20:30:47 Info: (fr.acinq.lightning.io.Peer) received SpliceInit(channelId=74ad215d961308721c435b9fedb1d5eb1158a22e227479ec815bc01f817bbcef, fundingContribution=6991986 sat, feerate=1500 sat/kw, lockTime=2868895, fundingPubkey=02bd4c12f54cddb0fd1e4372c14c558a694ee536415ce1fad8f7877cade64a8eae, tlvStream=TlvStream(records=[fr.acinq.lightning.wire.ChannelTlv.RequireConfirmedInputsTlv@5a458330, PushAmountTlv(amount=4927561000 msat), OriginsTlv(origins=[PayToOpenOrigin(paymentHash=517e35179f44620161a21fab353ed266104810fec164cfe801f4318483a9ad5e, serviceFee=72439000 msat, miningFee=0 sat, amount=4927561000 msat)])], unknown=[]))
    remoteNodeId: 03933884aaf1d6b108397e5efe5c86bcf2d8ca8d2f700eda99db9214fc2712b134
    connectionId: 1721333365237
    channelId: 74ad215d961308721c435b9fedb1d5eb1158a22e227479ec815bc01f817bbcef
2024-07-18 20:30:47 Info: (fr.acinq.lightning.channel.states.ChannelState) accepting splice with remote.amount=6991986 sat remote.push=4927561000 msat
    remoteNodeId: 03933884aaf1d6b108397e5efe5c86bcf2d8ca8d2f700eda99db9214fc2712b134
    state: Normal
    channelId: 74ad215d961308721c435b9fedb1d5eb1158a22e227479ec815bc01f817bbcef
    commitments: active=[1] inactive=[]
2024-07-18 20:30:47 Info: (fr.acinq.lightning.io.Peer) sending SpliceAck(channelId=74ad215d961308721c435b9fedb1d5eb1158a22e227479ec815bc01f817bbcef, fundingContribution=0 sat, fundingPubkey=02dd322e273a8f2322091e8e0d31b5f05b3581a2ad37760d550372ce8e2bfb9342, tlvStream=TlvStream(records=[], unknown=[]))
    remoteNodeId: 03933884aaf1d6b108397e5efe5c86bcf2d8ca8d2f700eda99db9214fc2712b134
    connectionId: 1721333365237
2024-07-18 20:30:47 Info: (fr.acinq.lightning.io.Peer) received TxAbort(channelId=74ad215d961308721c435b9fedb1d5eb1158a22e227479ec815bc01f817bbcef, data=6368616e6e656c2066756e64696e67206572726f72, tlvs=TlvStream(records=[], unknown=[]))
    remoteNodeId: 03933884aaf1d6b108397e5efe5c86bcf2d8ca8d2f700eda99db9214fc2712b134
    connectionId: 1721333365237
    channelId: 74ad215d961308721c435b9fedb1d5eb1158a22e227479ec815bc01f817bbcef
2024-07-18 20:30:47 Info: (fr.acinq.lightning.channel.states.ChannelState) our peer aborted the splice attempt: ascii='channel funding error' bin=6368616e6e656c2066756e64696e67206572726f72
    remoteNodeId: 03933884aaf1d6b108397e5efe5c86bcf2d8ca8d2f700eda99db9214fc2712b134
    state: Normal
    channelId: 74ad215d961308721c435b9fedb1d5eb1158a22e227479ec815bc01f817bbcef
    commitments: active=[1] inactive=[]
2024-07-18 20:30:47 Info: (fr.acinq.lightning.io.Peer) sending TxAbort(channelId=74ad215d961308721c435b9fedb1d5eb1158a22e227479ec815bc01f817bbcef, data=73706c6963652061626f72746564, tlvs=TlvStream(records=[], unknown=[]))
    remoteNodeId: 03933884aaf1d6b108397e5efe5c86bcf2d8ca8d2f700eda99db9214fc2712b134
    connectionId: 1721333365237

Second payment:

2024-07-18 20:32:53 Info: (fr.acinq.lightning.payment.IncomingPaymentHandler) pay-to-open decision: Accepted(amount=2500000000 msat, fee=47439000 msat, source=OffChainPayment)
    paymentHash: a76c4cc20a667fdb041526d999ab9a3e1402ffda6a4284c13a5ee33e1f288bb3
    amount: 2500000000 msat
    totalAmount: 2500000000 msat
2024-07-18 20:32:53 Info: (fr.acinq.lightning.payment.IncomingPaymentHandler) accepted pay-to-open
    paymentHash: a76c4cc20a667fdb041526d999ab9a3e1402ffda6a4284c13a5ee33e1f288bb3
    amount: 2500000000 msat
    totalAmount: 2500000000 msat
2024-07-18 20:32:53 Info: (fr.acinq.lightning.payment.IncomingPaymentHandler) payment received (2500000000 msat) with blinded route
    paymentHash: a76c4cc20a667fdb041526d999ab9a3e1402ffda6a4284c13a5ee33e1f288bb3
    amount: 2500000000 msat
    totalAmount: 2500000000 msat
2024-07-18 20:32:53 Info: (fr.acinq.lightning.io.Peer) sending PayToOpenResponse
    remoteNodeId: 03933884aaf1d6b108397e5efe5c86bcf2d8ca8d2f700eda99db9214fc2712b134
    connectionId: 1721333365237
2024-07-18 20:32:53 Info: (fr.acinq.lightning.io.Peer) sending PayToOpenResponse(chainHash=43497fd7f826957108f4a30fd9cec3aeba79972084e90ead01ea330900000000, paymentHash=a76c4cc20a667fdb041526d999ab9a3e1402ffda6a4284c13a5ee33e1f288bb3, result=Success(paymentPreimage=54684443f4ce8c93bccfc67c1af796a32273ac6d28786fc5a28b2156fa12c3d7, addToFeeCredit=false))
    remoteNodeId: 03933884aaf1d6b108397e5efe5c86bcf2d8ca8d2f700eda99db9214fc2712b134
    connectionId: 1721333365237

(after that it's just Feerate updates)

Edit: list incoming payments command:

$ phoenix-cli listincomingpayments
[
    {
        "paymentHash": "a76c4cc20a667fdb041526d999ab9a3e1402ffda6a4284c13a5ee33e1f288bb3",
        "preimage": "54684443f4ce8c93bccfc67c1af796a32273ac6d28786fc5a28b2156fa12c3d7",
        "isPaid": true,
        "receivedSat": 0,
        "fees": 0,
        "completedAt": 1721334773260,
        "createdAt": 1721334773248
    },
    {
        "paymentHash": "78554aaf217e30dc12f89dfaa52f3ed7e0e6c1905f52786543c218c0fd695224",
        "preimage": "0316c5dfc8d097f35cae2d37719d0723f9fcc7bb103f6cdef197692c38cecd0a",
        "isPaid": true,
        "receivedSat": 12,
        "fees": 0,
        "completedAt": 1721334733472,
        "createdAt": 1721334733464
    },
    {
        "paymentHash": "517e35179f44620161a21fab353ed266104810fec164cfe801f4318483a9ad5e",
        "preimage": "88a42221e99e9cc2929ab213b1669a002d0cf32234f7ca509214cfa351c2a7ee",
        "isPaid": true,
        "receivedSat": 0,
        "fees": 0,
        "completedAt": 1721334647166,
        "createdAt": 1721334647158
    },
    (other payments)
]

2 replies

pm47 Jul 19, 2024
Maintainer

Hello, our testnet node was out of suitable utxos to honor the channel requests, hence the our peer aborted the splice attempt: ascii='channel funding error'. In that case they are automatically retried and finally took place a few minutes ago when we fixed the hot wallet.

Answer selected by 1ma

1ma Jul 19, 2024
Author

All good. This morning I saw that restarting the phoenixd process would attempt to do the splice again, and now I've just seen that the channel updated and the incoming payments have the correct amount.

Thanks.

Provide feedback

Saved searches

Use saved searches to filter your results more quickly

Large payments needing splice-in falling through the cracks? #78

{{title}}

{{editor}}'s edit

{{editor}}'s edit

Replies: 1 comment 2 replies

{{title}}

{{editor}}'s edit

{{editor}}'s edit

{{title}}

{{title}}

{{editor}}'s edit

{{editor}}'s edit

Select a reply

Large payments needing splice-in falling through the cracks? #78

1ma Jul 18, 2024

Replies: 1 comment · 2 replies

1ma Jul 18, 2024 Author

pm47 Jul 19, 2024 Maintainer

1ma Jul 19, 2024 Author

1ma
Jul 18, 2024

Replies: 1 comment 2 replies

1ma
Jul 18, 2024
Author

pm47 Jul 19, 2024
Maintainer

1ma Jul 19, 2024
Author