2024-04-19 01:48:53.587 [WRN] LTND: open /tmp/lndtest-node2127775919/lnd.conf: no such file or directory 2024-04-19 01:48:53.588 [INF] LTND: Version: 0.17.99-beta commit=fn/v1.0.5-58-gda4b505, build=development, logging=default, debuglevel=debug,DISC=trace 2024-04-19 01:48:53.588 [INF] LTND: Active chain: Bitcoin (network=regtest) 2024-04-19 01:48:53.588 [INF] LTND: Pprof listening on 127.0.0.1:5830 2024-04-19 01:48:53.588 [INF] RPCS: Generating TLS certificates... 2024-04-19 01:48:53.602 [INF] RPCS: Done generating TLS certificates 2024-04-19 01:48:53.604 [DBG] PRPC: Peers RPC server successfully register with root gRPC server 2024-04-19 01:48:53.604 [DBG] RRPC: Router RPC server successfully register with root gRPC server 2024-04-19 01:48:53.604 [DBG] VRPC: Versioner RPC server successfully registered with root gRPC server 2024-04-19 01:48:53.604 [DBG] ARPC: Autopilot RPC server successfully register with root gRPC server 2024-04-19 01:48:53.604 [DBG] IRPC: Invoices RPC server successfully registered with root gRPC server 2024-04-19 01:48:53.604 [DBG] NRPC: Neutrino RPC server successfully register with root gRPC server 2024-04-19 01:48:53.604 [DBG] WLKT: WalletKit RPC server successfully registered with root gRPC server 2024-04-19 01:48:53.604 [DBG] NTFR: ChainNotifier RPC server successfully register with root gRPC server 2024-04-19 01:48:53.604 [DBG] NTFR: ChainKit RPC server successfully register with root gRPC server 2024-04-19 01:48:53.604 [DBG] DRPC: DEV RPC server successfully register with root the gRPC server 2024-04-19 01:48:53.604 [DBG] SGNR: Signer RPC server successfully register with root gRPC server 2024-04-19 01:48:53.604 [INF] RPCS: RPC server listening on 127.0.0.1:5828 2024-04-19 01:48:53.615 [DBG] PRPC: Peers REST server successfully registered with root REST server 2024-04-19 01:48:53.617 [DBG] RRPC: Router REST server successfully registered with root REST server 2024-04-19 01:48:53.618 [DBG] VRPC: Versioner REST server successfully registered with root REST server 2024-04-19 01:48:53.629 [DBG] ARPC: Autopilot REST server successfully registered with root REST server 2024-04-19 01:48:53.630 [DBG] IRPC: Invoices REST server successfully registered with root REST server 2024-04-19 01:48:53.630 [DBG] NRPC: Neutrino REST server successfully registered with root REST server 2024-04-19 01:48:53.630 [DBG] WLKT: WalletKit REST server successfully registered with root REST server 2024-04-19 01:48:53.634 [DBG] NTFR: ChainNotifier REST server successfully registered with root REST server 2024-04-19 01:48:53.635 [DBG] NTFR: ChainKit REST server successfully registered with root REST server 2024-04-19 01:48:53.635 [DBG] DRPC: DEV REST server successfully registered with the root REST server 2024-04-19 01:48:53.635 [DBG] SGNR: Signer REST server successfully registered with root REST server 2024-04-19 01:48:53.635 [INF] RPCS: gRPC proxy started at 127.0.0.1:5829 2024-04-19 01:48:53.636 [INF] LTND: Opening the main database, this might take a few minutes... 2024-04-19 01:48:53.636 [INF] LTND: Opening bbolt database, sync_freelist=false, auto_compact=false 2024-04-19 01:48:53.637 [INF] LTND: Creating local graph and channel state DB instances 2024-04-19 01:48:53.642 [DBG] CHDB: Populating in-memory channel graph, this might take a while... 2024-04-19 01:48:53.642 [DBG] CHDB: Finished populating in-memory channel graph (took 110.32µs, num_node_features=0, num_nodes=0, num_channels=0) 2024-04-19 01:48:53.642 [INF] CHDB: Checking for schema update: latest_version=31, db_version=31 2024-04-19 01:48:53.642 [INF] CHDB: Checking for optional update: prune_revocation_log=false, db_version=empty 2024-04-19 01:48:53.642 [INF] LTND: Database(s) now open (time_to_open=6.292606ms)! 2024-04-19 01:48:53.643 [INF] LTND: We're not running within systemd or the service type is not 'notify' 2024-04-19 01:48:53.643 [INF] LTND: Waiting for wallet encryption password. Use `lncli create` to create a wallet, `lncli unlock` to unlock an existing wallet, or `lncli changepassword` to change the password of an existing wallet and unlock it. 2024-04-19 01:48:53.718 [DBG] RPCS: [/lnrpc.State/SubscribeState] requested 2024-04-19 01:48:53.719 [DBG] RPCS: [/lnrpc.WalletUnlocker/InitWallet] requested 2024-04-19 01:48:53.781 [INF] LNWL: Opened wallet 2024-04-19 01:48:53.781 [INF] LTND: Wallet recovery mode enabled with address lookahead of 100 addresses 2024-04-19 01:48:53.818 [DBG] LNWL: Loading mempool spends... 2024-04-19 01:48:53.822 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:48:53.822 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:48:53.822 [DBG] LNWL: Loaded mempool spends in 4.147084ms 2024-04-19 01:48:53.822 [INF] LNWL: Started listening for bitcoind block notifications via ZMQ on 127.0.0.1:5559 2024-04-19 01:48:53.824 [INF] LNWL: Started listening for bitcoind transaction notifications via ZMQ on 127.0.0.1:5560 2024-04-19 01:48:53.824 [INF] LNWL: Started polling mempool to cache new transactions 2024-04-19 01:48:53.828 [INF] CHRE: Using external fee estimator http://localhost:5556/fee-estimates.json: cached=false 2024-04-19 01:48:53.838 [INF] LNWL: The wallet has been unlocked without a time limit 2024-04-19 01:48:53.932 [INF] CHRE: LightningWallet opened 2024-04-19 01:48:53.940 [INF] HSWC: Cleaning circuits from disk for closed channels 2024-04-19 01:48:53.940 [DBG] HSWC: Found 0 closed channels 2024-04-19 01:48:53.940 [INF] HSWC: Finished cleaning: no closed channels found, no actions taken. 2024-04-19 01:48:53.940 [INF] HSWC: Restoring in-memory circuit state from disk 2024-04-19 01:48:53.940 [INF] HSWC: Payment circuits loaded: num_pending=0, num_open=0 2024-04-19 01:48:53.941 [DBG] CRTR: Instantiating mission control with config: maximum history: 1000, minimum failure relax interval: 1m0s, estimator type: apriori, penalty halflife time: 1h0m0s, apriori hop probability: 0.6, apriori weight: 0.5, previous success probability: 0.95, capacity fraction: 0.9999 2024-04-19 01:48:53.941 [DBG] CRTR: Mission control state reconstruction started 2024-04-19 01:48:53.941 [DBG] CRTR: Mission control state reconstruction finished: n=0, time=4.84µs 2024-04-19 01:48:53.942 [DBG] SRVR: Instantiating payment session source with config: AttemptCost=100 + 0.1%, MinRouteProbability=0.01 2024-04-19 01:48:53.942 [DBG] SRVR: Sweeper batch window duration: 5s 2024-04-19 01:48:53.942 [INF] SWPR: Migrating UTXO nursery finalized TXIDs 2024-04-19 01:48:53.942 [DBG] SRVR: Using the dev config for the fundingMgr: &{0s}, reservationTimeout=10m0s, zombieSweeperInterval=1m0s 2024-04-19 01:48:53.942 [INF] LTND: Channel backup proxy channel notifier starting 2024-04-19 01:48:53.942 [INF] ATPL: Instantiating autopilot with active=false, max_channels=5, allocation=0.600000, min_chan_size=20000, max_chan_size=16777215, private=false, min_confs=1, conf_target=3 2024-04-19 01:48:53.942 [DBG] LTND: Populating dependencies for sub RPC server: SignRPC 2024-04-19 01:48:53.942 [DBG] LTND: Populating dependencies for sub RPC server: WalletKitRPC 2024-04-19 01:48:53.942 [DBG] LTND: Populating dependencies for sub RPC server: AutopilotRPC 2024-04-19 01:48:53.942 [DBG] LTND: Populating dependencies for sub RPC server: ChainRPC 2024-04-19 01:48:53.942 [DBG] LTND: Populating dependencies for sub RPC server: InvoicesRPC 2024-04-19 01:48:53.942 [DBG] LTND: Populating dependencies for sub RPC server: PeersRPC 2024-04-19 01:48:53.942 [DBG] LTND: Populating dependencies for sub RPC server: NeutrinoKitRPC 2024-04-19 01:48:53.942 [DBG] LTND: Populating dependencies for sub RPC server: RouterRPC 2024-04-19 01:48:53.942 [DBG] LTND: Populating dependencies for sub RPC server: WatchtowerRPC 2024-04-19 01:48:53.942 [DBG] LTND: Populating dependencies for sub RPC server: WatchtowerClientRPC 2024-04-19 01:48:53.942 [DBG] LTND: Populating dependencies for sub RPC server: DevRPC 2024-04-19 01:48:53.942 [INF] RRPC: Making macaroons for Router RPC Server at: /tmp/lndtest-node2127775919/data/chain/bitcoin/regtest/router.macaroon 2024-04-19 01:48:53.943 [INF] IRPC: Baking macaroons for invoices RPC Server at: /tmp/lndtest-node2127775919/data/chain/bitcoin/regtest/invoices.macaroon 2024-04-19 01:48:53.943 [INF] WLKT: Baking macaroons for WalletKit RPC Server at: /tmp/lndtest-node2127775919/data/chain/bitcoin/regtest/walletkit.macaroon 2024-04-19 01:48:53.943 [INF] NTFR: Baking macaroons for ChainNotifier RPC Server at: /tmp/lndtest-node2127775919/data/chain/bitcoin/regtest/chainnotifier.macaroon 2024-04-19 01:48:53.943 [INF] SGNR: Making macaroons for Signer RPC Server at: /tmp/lndtest-node2127775919/data/chain/bitcoin/regtest/signer.macaroon 2024-04-19 01:48:53.944 [DBG] RPCS: Starting sub RPC server: PeersRPC 2024-04-19 01:48:53.944 [DBG] RPCS: Starting sub RPC server: RouterRPC 2024-04-19 01:48:53.944 [DBG] RPCS: Starting sub RPC server: VersionRPC 2024-04-19 01:48:53.944 [DBG] RPCS: Starting sub RPC server: WatchtowerClientRPC 2024-04-19 01:48:53.944 [DBG] RPCS: Starting sub RPC server: AutopilotRPC 2024-04-19 01:48:53.944 [DBG] RPCS: Starting sub RPC server: InvoicesRPC 2024-04-19 01:48:53.944 [DBG] RPCS: Starting sub RPC server: NeutrinoKitRPC 2024-04-19 01:48:53.944 [DBG] RPCS: Starting sub RPC server: WalletKitRPC 2024-04-19 01:48:53.944 [DBG] RPCS: Starting sub RPC server: WatchtowerRPC 2024-04-19 01:48:53.944 [DBG] RPCS: Starting sub RPC server: ChainRPC 2024-04-19 01:48:53.944 [DBG] RPCS: Starting sub RPC server: DevRPC 2024-04-19 01:48:53.944 [DBG] RPCS: Starting sub RPC server: SignRPC 2024-04-19 01:48:53.944 [INF] LTND: We're not running within systemd or the service type is not 'notify' 2024-04-19 01:48:53.945 [DBG] LNWL: Locating suitable block for birthday 2024-04-16 18:15:05 +0000 UTC between blocks 0-797 2024-04-19 01:48:53.946 [INF] LTND: Waiting for chain backend to finish sync, start_height=797 2024-04-19 01:48:53.949 [DBG] LNWL: Checking candidate block: height=398, hash=4d25696c52974d42bad737c823bf0f3355b161507bd225a6dab30b29529797e2, timestamp=2024-04-19 01:26:49 +0000 UTC 2024-04-19 01:48:53.952 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:48:53.952 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:48:53.952 [DBG] LTND: Syncing to block timestamp: 2024-04-19 01:48:51 +0000 UTC, is synced=false 2024-04-19 01:48:53.953 [DBG] LNWL: Checking candidate block: height=199, hash=642a9fc83e02afe4be3868c67390a344a89f9024ad0e9a5c0417d629bd16d65c, timestamp=2024-04-19 01:26:15 +0000 UTC 2024-04-19 01:48:53.954 [DBG] LNWL: Checking candidate block: height=99, hash=7aeaef3dd5496e29f481946ea3c1f26ae2b790547eff8bac17ee9543632a1058, timestamp=2024-04-19 01:25:59 +0000 UTC 2024-04-19 01:48:53.956 [DBG] LNWL: Checking candidate block: height=49, hash=6a11706d4829564dd37e0f395b78aab1016c53ab5b9e634c23565355ff1eb9ce, timestamp=2024-04-19 01:25:50 +0000 UTC 2024-04-19 01:48:53.957 [DBG] LNWL: Checking candidate block: height=24, hash=04463e4f33e7bcd885cd21bb2a427783534f523484c97f1bd9159ea79dd61df8, timestamp=2024-04-19 01:25:46 +0000 UTC 2024-04-19 01:48:53.958 [DBG] LNWL: Checking candidate block: height=12, hash=4231ce645c7ca8652ff2c2c2901469ea036a0a29a67c98306baadd0ce2535a9e, timestamp=2024-04-19 01:25:44 +0000 UTC 2024-04-19 01:48:53.959 [DBG] LNWL: Checking candidate block: height=6, hash=7a06b96dfa609234b19e3ab6bb2dfa540004c5a69bc92e5cc9aa641b75ebc4ff, timestamp=2024-04-19 01:25:43 +0000 UTC 2024-04-19 01:48:53.961 [DBG] LNWL: Checking candidate block: height=3, hash=17bbedebd350400b815fd008d9b4f91632b5352017a8656dbe81449aac7048cf, timestamp=2024-04-19 01:25:42 +0000 UTC 2024-04-19 01:48:53.962 [DBG] LNWL: Checking candidate block: height=1, hash=3a8882b7910c3989d9938074138c8c8a25429fb212670c885af9624f20794ea3, timestamp=2024-04-19 01:25:41 +0000 UTC 2024-04-19 01:48:53.963 [DBG] LNWL: Checking candidate block: height=0, hash=0f9188f13cb7b2c71f2a335e3a4fc328bf5beb436012afca590b1a11466e2206, timestamp=2011-02-02 23:16:42 +0000 UTC 2024-04-19 01:48:53.963 [DBG] LNWL: Found birthday block: height=0, hash=0f9188f13cb7b2c71f2a335e3a4fc328bf5beb436012afca590b1a11466e2206, timestamp=2011-02-02 23:16:42 +0000 UTC 2024-04-19 01:48:53.965 [INF] LNWL: RECOVERY MODE ENABLED -- rescanning for used addresses with recovery_window=100 2024-04-19 01:48:54.016 [DBG] RPCS: [/lnrpc.State/SubscribeState] requested 2024-04-19 01:48:54.016 [DBG] RPCS: [/lnrpc.State/SubscribeState] requested 2024-04-19 01:48:54.049 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:48:54.049 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:48:54.057 [INF] LNWL: Seed birthday surpassed, starting recovery of wallet from height=1 hash=3a8882b7910c3989d9938074138c8c8a25429fb212670c885af9624f20794ea3 with recovery-window=100 2024-04-19 01:48:54.127 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:48:54.127 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:48:54.264 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:48:54.264 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:48:54.337 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:48:54.337 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:48:54.481 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:48:54.482 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:48:54.610 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:48:54.611 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:48:54.708 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:48:54.708 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:48:54.769 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:48:54.769 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:48:54.837 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:48:54.837 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:48:54.940 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:48:54.940 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:48:54.955 [DBG] LTND: Syncing to block timestamp: 2024-04-19 01:48:51 +0000 UTC, is synced=false 2024-04-19 01:48:55.058 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:48:55.059 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:48:55.139 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:48:55.139 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:48:55.255 [INF] LNWL: Scanning 797 blocks for recoverable addresses 2024-04-19 01:48:55.286 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:48:55.286 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:48:55.363 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:48:55.363 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:48:55.476 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:48:55.476 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:48:55.623 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:48:55.623 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:48:55.754 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:48:55.754 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:48:55.873 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:48:55.873 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:48:55.953 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:48:55.953 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:48:55.957 [DBG] LTND: Syncing to block timestamp: 2024-04-19 01:48:51 +0000 UTC, is synced=false 2024-04-19 01:48:56.053 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:48:56.053 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:48:56.196 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:48:56.196 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:48:56.348 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:48:56.348 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:48:56.460 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:48:56.460 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:48:56.537 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:48:56.537 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:48:56.678 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:48:56.678 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:48:56.764 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:48:56.765 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:48:56.878 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:48:56.878 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:48:56.962 [DBG] LTND: Syncing to block timestamp: 2024-04-19 01:48:51 +0000 UTC, is synced=false 2024-04-19 01:48:57.005 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:48:57.005 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:48:57.109 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:48:57.110 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:48:57.162 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:48:57.162 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:48:57.215 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:48:57.215 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:48:57.291 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:48:57.291 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:48:57.407 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:48:57.407 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:48:57.482 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:48:57.482 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:48:57.622 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:48:57.622 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:48:57.765 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:48:57.765 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:48:57.903 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:48:57.903 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:48:57.965 [DBG] LTND: Syncing to block timestamp: 2024-04-19 01:48:51 +0000 UTC, is synced=false 2024-04-19 01:48:58.005 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:48:58.005 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:48:58.119 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:48:58.119 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:48:58.246 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:48:58.246 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:48:58.307 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:48:58.307 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:48:58.426 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:48:58.426 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:48:58.561 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:48:58.561 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:48:58.697 [INF] LNWL: Recovered 1 external addrs at height=790 hash=1e85341328ba932a18a4d89c5c4026bcd2136dde2a29b3c6a9dcba822b6b265b 2024-04-19 01:48:58.697 [INF] LNWL: Found 1 spends from watched outpoints at height=790 hash=1e85341328ba932a18a4d89c5c4026bcd2136dde2a29b3c6a9dcba822b6b265b 2024-04-19 01:48:58.700 [DBG] LNWL: Marking transaction d3c66810e5b07edee0a2c6d9024b159a28d662735831112a89b1591584c7c3fd output 0 (1 BTC) spendable 2024-04-19 01:48:58.700 [DBG] LNWL: Marked address bcrt1q3ucdl7va40ewnlpe7dhynddv4k0a3jhuscn3k7 used 2024-04-19 01:48:58.704 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:48:58.704 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:48:58.724 [INF] LNWL: Recovered 1 internal addrs at height=792 hash=377532f65c6b60f8e79770af9acb6ea459419158f62f6fc3f042eb8a5530d0c9 2024-04-19 01:48:58.724 [INF] LNWL: Found 1 spends from watched outpoints at height=792 hash=377532f65c6b60f8e79770af9acb6ea459419158f62f6fc3f042eb8a5530d0c9 2024-04-19 01:48:58.729 [DBG] LNWL: Marking transaction fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5 output 1 (0.89991763 BTC) spendable 2024-04-19 01:48:58.729 [DBG] LNWL: Marked address bcrt1pq6w4sfyeasu9wk3ltaznuteldq6ftpcd48789hfzky24493seygsmvxhjn used 2024-04-19 01:48:58.755 [INF] LNWL: Recovered addresses from blocks 1-797 2024-04-19 01:48:58.766 [INF] LNWL: Started rescan from block 6aaf98ecc16d579f416a81e36d8ec9c7053128bec843ada5f48e54768d180f24 (height 797) for 2 addresses 2024-04-19 01:48:58.766 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:48:58.766 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:48:58.768 [INF] LNWL: Catching up block hashes to height 797, this might take a while 2024-04-19 01:48:58.769 [INF] LNWL: Done catching up block hashes 2024-04-19 01:48:58.769 [INF] LNWL: Finished rescan for 2 addresses (synced to block 6aaf98ecc16d579f416a81e36d8ec9c7053128bec843ada5f48e54768d180f24, height 797) 2024-04-19 01:48:58.879 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:48:58.879 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:48:58.968 [DBG] LTND: Syncing to block timestamp: 2024-04-19 01:48:51 +0000 UTC, is synced=true 2024-04-19 01:48:58.970 [INF] LTND: Chain backend is fully synced (end_height=797)! 2024-04-19 01:48:58.971 [WRN] HLCK: check: disk space configured with 0 attempts, skipping it 2024-04-19 01:48:58.971 [WRN] HLCK: check: tls configured with 0 attempts, skipping it 2024-04-19 01:48:58.971 [INF] LNWL: SigPool starting 2024-04-19 01:48:58.971 [DBG] HLCK: Monitoring: chain backend 2024-04-19 01:48:58.976 [INF] NTFN: New block epoch subscription 2024-04-19 01:48:58.976 [INF] NTFN: New block epoch subscription 2024-04-19 01:48:58.976 [INF] BTCN: Broadcaster now active 2024-04-19 01:48:58.976 [INF] CHNF: ChannelNotifier starting 2024-04-19 01:48:58.976 [INF] PRNF: PeerNotifier starting 2024-04-19 01:48:58.976 [INF] HSWC: HtlcNotifier starting 2024-04-19 01:48:58.976 [INF] SWPR: Sweeper starting 2024-04-19 01:48:58.976 [INF] NTFN: New block epoch subscription 2024-04-19 01:48:58.977 [INF] UTXN: UTXO nursery starting 2024-04-19 01:48:58.977 [DBG] SWPR: Sweep ticker started 2024-04-19 01:48:58.978 [INF] BRAR: Breach arbiter starting 2024-04-19 01:48:58.978 [DBG] BRAR: Found 0 closing channels, 0 retribution records 2024-04-19 01:48:58.978 [INF] FNDG: Funding manager starting 2024-04-19 01:48:58.978 [INF] HSWC: HTLC Switch starting 2024-04-19 01:48:58.978 [INF] NTFN: New block epoch subscription 2024-04-19 01:48:58.978 [INF] BRAR: Starting contract observer, watching for breaches. 2024-04-19 01:48:58.980 [INF] NTFN: New block epoch subscription 2024-04-19 01:48:58.980 [INF] CNCT: ChainArbitrator starting 2024-04-19 01:48:58.980 [INF] NTFN: New block epoch subscription 2024-04-19 01:48:58.980 [INF] NTFN: New block epoch subscription 2024-04-19 01:48:58.980 [DBG] HSWC: InterceptableSwitch running: height=797, requireInterceptor=false 2024-04-19 01:48:58.980 [INF] DISC: Authenticated Gossiper starting 2024-04-19 01:48:58.980 [INF] NTFN: New block epoch subscription 2024-04-19 01:48:58.981 [INF] CRTR: Channel Router starting 2024-04-19 01:48:58.981 [DBG] DISC: New block: height=797, hash=6aaf98ecc16d579f416a81e36d8ec9c7053128bec843ada5f48e54768d180f24 2024-04-19 01:48:58.983 [DBG] CHDB: Pruned graph, cache now has num_node_features=0, num_nodes=0, num_channels=0 2024-04-19 01:48:58.983 [INF] CRTR: FilteredChainView starting 2024-04-19 01:48:58.987 [INF] CRTR: Filtering chain using 0 channels active 2024-04-19 01:48:58.989 [INF] CRTR: Prune tip for Channel Graph: height=797, hash=6aaf98ecc16d579f416a81e36d8ec9c7053128bec843ada5f48e54768d180f24 2024-04-19 01:48:58.989 [DBG] CRTR: Cleaning network result store. 2024-04-19 01:48:58.989 [INF] INVC: InvoiceRegistry starting 2024-04-19 01:48:58.989 [DBG] INVC: Adding 0 pending invoices to the expiry watcher 2024-04-19 01:48:58.989 [INF] HSWC: Onion processor starting 2024-04-19 01:48:58.990 [INF] NTFN: New block epoch subscription 2024-04-19 01:48:58.991 [INF] NTFN: New block epoch subscription 2024-04-19 01:48:58.991 [INF] NANN: Channel Status Manager starting 2024-04-19 01:48:58.991 [INF] CHFT: ChannelEventStore starting 2024-04-19 01:48:58.991 [INF] CHFT: Adding 0 channels to event store 2024-04-19 01:48:58.991 [INF] CHBU: chanbackup.SubSwapper starting 2024-04-19 01:48:58.999 [INF] CHBU: Updating backup file at /tmp/lndtest-node2127775919/data/chain/bitcoin/regtest/channel.backup 2024-04-19 01:48:58.999 [INF] CHBU: Swapping old multi backup file from /tmp/lndtest-node2127775919/data/chain/bitcoin/regtest/temp-dont-use.backup to /tmp/lndtest-node2127775919/data/chain/bitcoin/regtest/channel.backup 2024-04-19 01:48:59.000 [DBG] CHBU: SubSwapper's backupUpdater is active! 2024-04-19 01:48:59.000 [INF] CMGR: Server listening on 127.0.0.1:5827 2024-04-19 01:48:59.000 [DBG] CRTR: New graph topology client subscription, client 1 2024-04-19 01:48:59.000 [DBG] SRVR: Establishing 0 persistent connections on start 2024-04-19 01:48:59.000 [INF] SRVR: Auto peer bootstrapping is disabled 2024-04-19 01:48:59.001 [DBG] RPCS: [/lnrpc.Lightning/GetInfo] requested 2024-04-19 01:48:59.005 [DBG] RPCS: [/lnrpc.Lightning/RestoreChannelBackups] requested 2024-04-19 01:48:59.009 [INF] CHBU: Restoring ChannelPoint(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0) to disk: 2024-04-19 01:48:59.013 [DBG] RPCS: [/lnrpc.Lightning/SubscribeChannelGraph] requested 2024-04-19 01:48:59.013 [DBG] CRTR: New graph topology client subscription, client 2 2024-04-19 01:48:59.014 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:48:59.014 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:48:59.041 [DBG] LTND: Using new ECDH revocation producer format for channel point fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0 2024-04-19 01:48:59.048 [INF] LTND: SCB Recovery: created channel shell for ChannelPoint(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0), chan_type=42 2024-04-19 01:48:59.048 [INF] LTND: Inserting 1 SCB channel shells into DB 2024-04-19 01:48:59.048 [INF] LTND: Informing chain watchers of new restored channels 2024-04-19 01:48:59.048 [INF] CNCT: Creating new ChannelArbitrator for ChannelPoint(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0) 2024-04-19 01:48:59.048 [DBG] CNCT: New ChainEventSubscription(id=0) for ChannelPoint(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0) 2024-04-19 01:48:59.049 [DBG] CNCT: Starting ChannelArbitrator(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0), htlc_set=(map[contractcourt.HtlcSetKey]contractcourt.htlcSet) (len=2) { (contractcourt.HtlcSetKey) LocalHtlcSet: (contractcourt.htlcSet) { incomingHTLCs: (map[uint64]channeldb.HTLC) { }, outgoingHTLCs: (map[uint64]channeldb.HTLC) { } }, (contractcourt.HtlcSetKey) RemoteHtlcSet: (contractcourt.htlcSet) { incomingHTLCs: (map[uint64]channeldb.HTLC) { }, outgoingHTLCs: (map[uint64]channeldb.HTLC) { } } } , state=StateDefault 2024-04-19 01:48:59.050 [INF] CNCT: ChannelArbitrator(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): starting state=StateDefault, trigger=chainTrigger, triggerHeight=797 2024-04-19 01:48:59.050 [DBG] CNCT: ChannelArbitrator(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): attempting state step with trigger=chainTrigger from state=StateDefault 2024-04-19 01:48:59.050 [DBG] CNCT: ChannelArbitrator(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): new block (height=797) examining active HTLC's 2024-04-19 01:48:59.050 [DBG] CNCT: ChannelArbitrator(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): checking commit chain actions at height=797, in_htlc_count=0, out_htlc_count=0 2024-04-19 01:48:59.050 [DBG] CNCT: ChannelArbitrator(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): no actions for chain trigger, terminating 2024-04-19 01:48:59.050 [DBG] CNCT: ChannelArbitrator(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): terminating at state=StateDefault 2024-04-19 01:48:59.050 [DBG] CNCT: Starting chain watcher for ChannelPoint(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0) 2024-04-19 01:48:59.050 [INF] NTFN: New spend subscription: spend_id=1, outpoint=fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0, script=0 d666b4e18da33576a3d1521842771645569626e8f199314525d138ab5d48795e, height_hint=792 2024-04-19 01:48:59.050 [INF] NTFN: Dispatching historical spend rescan for outpoint=fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0, script=0 d666b4e18da33576a3d1521842771645569626e8f199314525d138ab5d48795e, start=792, end=797 2024-04-19 01:48:59.063 [DBG] NTFN: Updated spend hint to height=797 for unconfirmed spend request outpoint=fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0, script=0 d666b4e18da33576a3d1521842771645569626e8f199314525d138ab5d48795e 2024-04-19 01:48:59.063 [INF] CHBU: Attempting to connect to node=0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df (addrs=([]net.Addr) (len=1 cap=1) { (*net.TCPAddr)(0x5d84618)(127.0.0.1:5823) } ) to restore ChannelPoint(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0) 2024-04-19 01:48:59.063 [INF] LTND: Peer([2 103 7 74 140 59 204 60 170 35 126 117 147 193 52 95 156 123 44 103 41 240 130 50 49 112 164 172 227 188 140 231 223]) is already connected, proceeding with chan restore 2024-04-19 01:48:59.063 [INF] LTND: Attempting to connect to 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:5823 for SCB restore DLP 2024-04-19 01:48:59.063 [DBG] SRVR: Connecting to 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:5823 2024-04-19 01:48:59.064 [INF] CNCT: Close observer for ChannelPoint(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0) active 2024-04-19 01:48:59.064 [DBG] CMGR: Attempting to connect to 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:5823 (reqid 1) 2024-04-19 01:48:59.064 [DBG] CMGR: Failed to connect to 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:5823 (reqid 1): dial tcp 127.0.0.1:5823: connect: connection refused 2024-04-19 01:48:59.065 [DBG] CMGR: Retrying connection to 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:5823 (reqid 1) in 5s 2024-04-19 01:48:59.144 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:48:59.144 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:48:59.201 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:48:59.201 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:48:59.265 [DBG] RPCS: [/lnrpc.Lightning/WalletBalance] requested 2024-04-19 01:48:59.324 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:48:59.324 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:48:59.454 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:48:59.454 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:48:59.552 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:48:59.552 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:48:59.682 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:48:59.682 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:48:59.764 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:48:59.764 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:48:59.876 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:48:59.876 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:48:59.943 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:48:59.943 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:00.011 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:00.011 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:00.066 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:00.066 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:00.128 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:00.128 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:00.229 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:00.229 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:00.369 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:00.369 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:00.450 [DBG] RPCS: [walletbalance] Total balance=0.89991763 BTC (confirmed=0.89991763 BTC, unconfirmed=0 BTC) 2024-04-19 01:49:00.492 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:00.492 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:00.600 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:00.600 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:00.654 [DBG] RPCS: [/lnrpc.Lightning/PendingChannels] requested 2024-04-19 01:49:00.659 [DBG] RPCS: [/lnrpc.Lightning/CloseChannel] requested 2024-04-19 01:49:00.664 [ERR] RPCS: [/lnrpc.Lightning/CloseChannel]: cannot close channel with state: ChanStatusRestored 2024-04-19 01:49:00.673 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:00.673 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:00.814 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:00.814 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:00.919 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:00.919 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:01.005 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:01.006 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:01.070 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:01.070 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:01.199 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:01.199 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:01.317 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:01.317 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:01.447 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:01.447 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:01.589 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:01.589 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:01.716 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:01.716 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:01.828 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:01.828 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:01.923 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:01.923 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:02.029 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:02.029 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:02.137 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:02.138 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:02.226 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:02.226 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:02.330 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:02.330 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:02.368 [DBG] RPCS: [/lnrpc.Lightning/GetInfo] requested 2024-04-19 01:49:02.383 [INF] SRVR: New inbound connection from 127.0.0.1:33148 2024-04-19 01:49:02.383 [INF] SRVR: Finalizing connection to 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:33148, inbound=true 2024-04-19 01:49:02.384 [DBG] PRNF: PeerNotifier notifying peer: 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df online 2024-04-19 01:49:02.384 [DBG] PEER: Peer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): Sending Init to 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:33148 2024-04-19 01:49:02.384 [DBG] CMGR: Canceling: 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:5823 (reqid 1) 2024-04-19 01:49:02.386 [DBG] PEER: Peer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): Received Init from 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:33148 2024-04-19 01:49:02.386 [DBG] PEER: Peer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): Loaded 1 active channels from database 2024-04-19 01:49:02.391 [DBG] LNWL: ChannelPoint(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): Restoring 0 dangling remote updates 2024-04-19 01:49:02.392 [DBG] LNWL: ChannelPoint(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): Restoring 0 local updates that the peer should sign 2024-04-19 01:49:02.392 [INF] PEER: Peer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): Loading ChannelPoint(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0), isPending=false 2024-04-19 01:49:02.392 [WRN] PEER: Peer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): Unable to find our forwarding policy for channel fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0, using default values 2024-04-19 01:49:02.392 [DBG] CNCT: New ChainEventSubscription(id=1) for ChannelPoint(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0) 2024-04-19 01:49:02.392 [INF] HSWC: ChannelLink(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): starting 2024-04-19 01:49:02.393 [INF] HSWC: Trimming open circuits for chan_id=792:1:0, start_htlc_id=0 2024-04-19 01:49:02.393 [INF] HSWC: Adding live link chan_id=e5223b49d179bfdf9690a42a8855cee05f331e1bc1a7f607769f3515a29128fb, short_chan_id=792:1:0 2024-04-19 01:49:02.393 [DBG] SRVR: Notifying that peer 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:33148 is online 2024-04-19 01:49:02.393 [INF] CNCT: Attempting to update ContractSignals for ChannelPoint(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0) 2024-04-19 01:49:02.394 [WRN] LNWL: ChannelPoint(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): Set available amount to 0 because we could not pay for the CommitmentFee of the new ChannelState: ourBalance is negative after applying the fee: ourBalance=0, current commitFee(w/o additional htlc)=0 mSAT, feeBuffer=0 mSAT (type=feebuffer) local_chan_initiator 2024-04-19 01:49:02.394 [WRN] LNWL: ChannelPoint(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): Set available amount to 0 because we could not pay for the CommitmentFee of the new ChannelState: ourBalance is negative after applying the fee: ourBalance=0, current commitFee(w/o additional htlc)=0 mSAT, feeBuffer=0 mSAT (type=feebuffer) local_chan_initiator 2024-04-19 01:49:02.394 [INF] HSWC: ChannelLink(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): HTLC manager started, bandwidth=0 mSAT 2024-04-19 01:49:02.394 [INF] HSWC: ChannelLink(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): Attempting to re-synchronize channel: SCID=792:1:0, status=ChanStatusRestored, initiator=true, pending=false, local commitment has height=0, local_htlc_index=0, local_log_index=0, remote_htlc_index=0, remote_log_index=0, remote commitment has height=0, local_htlc_index=0, local_log_index=0, remote_htlc_index=0, remote_log_index=0 2024-04-19 01:49:02.394 [INF] PEER: Peer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): Negotiated chan series queries 2024-04-19 01:49:02.394 [INF] DISC: Creating new GossipSyncer for peer=0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df 2024-04-19 01:49:02.394 [DBG] DISC: Created new GossipSyncer[state=chansSynced type=PassiveSync] for peer=0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:33148 2024-04-19 01:49:02.394 [DBG] DISC: Starting GossipSyncer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df) 2024-04-19 01:49:02.394 [DBG] DISC: Attempting initial historical sync with GossipSyncer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df) 2024-04-19 01:49:02.394 [DBG] PEER: Peer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): Sending ChannelReestablish(chan_id=e5223b49d179bfdf9690a42a8855cee05f331e1bc1a7f607769f3515a29128fb, next_local_height=0, remote_tail_height=0) to 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:33148 2024-04-19 01:49:02.394 [DBG] DISC: GossipSyncer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): state=chansSynced, type=PassiveSync 2024-04-19 01:49:02.395 [DBG] DISC: GossipSyncer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): state=syncingChans, type=PassiveSync 2024-04-19 01:49:02.395 [INF] DISC: GossipSyncer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): requesting new chans from height=0 and 797 blocks after 2024-04-19 01:49:02.395 [DBG] DISC: GossipSyncer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): state=waitingQueryRangeReply, type=PassiveSync 2024-04-19 01:49:02.395 [DBG] PEER: Peer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): Sending QueryChannelRange(chain_hash=0f9188f13cb7b2c71f2a335e3a4fc328bf5beb436012afca590b1a11466e2206, start_height=0, end_height=796) to 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:33148 2024-04-19 01:49:02.402 [DBG] PEER: Peer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): Received NodeAnnouncement(node=0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df, update_time=2024-04-19 01:49:02 +0000 UTC) from 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:33148 2024-04-19 01:49:02.402 [TRC] DISC: Received network message: peer=0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:33148, msg=NodeAnnouncement, is_remote=true 2024-04-19 01:49:02.402 [DBG] DISC: Processing NodeAnnouncement: peer=0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:33148, timestamp=2024-04-19 01:49:02 +0000 UTC, node=0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df 2024-04-19 01:49:02.403 [DBG] CRTR: Checking stale node 303236373037346138633362636333636161323337653735393363313334356639633762326336373239663038323332333137306134616365336263386365376466 got Ignoring node announcement for node not found in channel graph (0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df) 2024-04-19 01:49:02.403 [DBG] DISC: Skipped processing stale node: 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df 2024-04-19 01:49:02.403 [TRC] DISC: Processed network message NodeAnnouncement, returned len(announcements)=0, allowDependents=true 2024-04-19 01:49:02.407 [DBG] PEER: Peer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): Received ChannelReestablish(chan_id=e5223b49d179bfdf9690a42a8855cee05f331e1bc1a7f607769f3515a29128fb, next_local_height=1, remote_tail_height=0) from 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:33148 2024-04-19 01:49:02.407 [INF] HSWC: ChannelLink(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): received re-establishment message from remote side 2024-04-19 01:49:02.407 [ERR] LNWL: ChannelPoint(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): sync failed with local data loss: remote believes our tail height is 0, while we have 0! 2024-04-19 01:49:02.407 [WRN] LNWL: ChannelPoint(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): detected restored triggering DLP 2024-04-19 01:49:02.407 [WRN] HSWC: ChannelLink(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): error when syncing channel states: ChannelPoint(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0) with CommitPoint(03aed30a4ecea6f760b78c47fd59f340708ec782d8cca2c7f9838023c85a25aba8) had possible local commitment state data loss 2024-04-19 01:49:02.408 [ERR] HSWC: ChannelLink(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): failing link: unable to synchronize channel states: ChannelPoint(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0) with CommitPoint(03aed30a4ecea6f760b78c47fd59f340708ec782d8cca2c7f9838023c85a25aba8) had possible local commitment state data loss with error: unable to resume channel, recovery required 2024-04-19 01:49:02.408 [INF] HSWC: ChannelLink(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): stopping 2024-04-19 01:49:02.408 [INF] HSWC: ChannelLink(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): exited 2024-04-19 01:49:02.408 [INF] HSWC: Removing channel link with ChannelID(e5223b49d179bfdf9690a42a8855cee05f331e1bc1a7f607769f3515a29128fb) 2024-04-19 01:49:02.408 [DBG] PEER: Peer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): Sending Error(chan_id=e5223b49d179bfdf9690a42a8855cee05f331e1bc1a7f607769f3515a29128fb, err=unable to resume channel, recovery required) to 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:33148 2024-04-19 01:49:02.409 [DBG] PEER: Peer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): Received QueryChannelRange(chain_hash=0f9188f13cb7b2c71f2a335e3a4fc328bf5beb436012afca590b1a11466e2206, start_height=0, end_height=796) from 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:33148 2024-04-19 01:49:02.409 [DBG] PEER: Peer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): Received ChannelUpdate(chain_hash=0f9188f13cb7b2c71f2a335e3a4fc328bf5beb436012afca590b1a11466e2206, short_chan_id=870813209264128, mflags=00000001, cflags=00000001, update_time=2024-04-19 01:48:50 +0000 UTC) from 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:33148 2024-04-19 01:49:02.409 [DBG] PEER: Peer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): Received ReplyChannelRange(start_height=0, end_height=796, num_chans=1, encoding=0) from 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:33148 2024-04-19 01:49:02.409 [INF] DISC: GossipSyncer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): buffering chan range reply of size=1 2024-04-19 01:49:02.409 [INF] DISC: GossipSyncer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): filtering through 1 chans 2024-04-19 01:49:02.410 [INF] DISC: GossipSyncer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): starting query for 1 new chans 2024-04-19 01:49:02.410 [DBG] DISC: GossipSyncer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): state=queryNewChannels, type=PassiveSync 2024-04-19 01:49:02.410 [INF] DISC: GossipSyncer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): querying for 1 new channels 2024-04-19 01:49:02.410 [DBG] DISC: GossipSyncer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): state=waitingQueryChanReply, type=PassiveSync 2024-04-19 01:49:02.410 [INF] DISC: GossipSyncer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): filtering chan range: start_height=0, num_blocks=797 2024-04-19 01:49:02.410 [INF] DISC: GossipSyncer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): sending final chan range chunk, size=0 2024-04-19 01:49:02.410 [TRC] DISC: Received network message: peer=0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:33148, msg=ChannelUpdate, is_remote=true 2024-04-19 01:49:02.410 [DBG] DISC: Processing ChannelUpdate: peer=0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:33148, short_chan_id=870813209264128, 2024-04-19 01:49:02.410 [DBG] DISC: Got ChannelUpdate for edge not found in graph(shortChanID=870813209264128), saving for reprocessing later 2024-04-19 01:49:02.410 [TRC] DISC: Processed network message ChannelUpdate, returned len(announcements)=0, allowDependents=false 2024-04-19 01:49:02.410 [DBG] PEER: Peer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): Sending QueryShortChanIDs(chain_hash=0f9188f13cb7b2c71f2a335e3a4fc328bf5beb436012afca590b1a11466e2206, encoding=0, num_chans=1) to 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:33148 2024-04-19 01:49:02.410 [DBG] PEER: Peer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): Sending ReplyChannelRange(start_height=0, end_height=796, num_chans=0, encoding=0) to 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:33148 2024-04-19 01:49:02.413 [DBG] PEER: Peer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): Received AnnounceSignatures(chan_id=e5223b49d179bfdf9690a42a8855cee05f331e1bc1a7f607769f3515a29128fb, short_chan_id=870813209264128) from 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:33148 2024-04-19 01:49:02.413 [TRC] DISC: Received network message: peer=0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:33148, msg=AnnounceSignatures, is_remote=true 2024-04-19 01:49:02.413 [INF] DISC: Received new remote announcement signature for 792:1:0 2024-04-19 01:49:02.413 [INF] DISC: Orphan remote proof announcement with short_chan_id=870813209264128, adding to waiting batch 2024-04-19 01:49:02.413 [DBG] DISC: Processed network message AnnounceSignatures, returned len(announcements)=0 2024-04-19 01:49:02.415 [DBG] PEER: Peer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): Received ChannelAnnouncement(chain_hash=0f9188f13cb7b2c71f2a335e3a4fc328bf5beb436012afca590b1a11466e2206, short_chan_id=870813209264128) from 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:33148 2024-04-19 01:49:02.415 [DBG] PEER: Peer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): Received ChannelUpdate(chain_hash=0f9188f13cb7b2c71f2a335e3a4fc328bf5beb436012afca590b1a11466e2206, short_chan_id=870813209264128, mflags=00000001, cflags=00000000, update_time=2024-04-19 01:48:50 +0000 UTC) from 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:33148 2024-04-19 01:49:02.416 [DBG] PEER: Peer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): Received NodeAnnouncement(node=0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df, update_time=2024-04-19 01:49:00 +0000 UTC) from 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:33148 2024-04-19 01:49:02.416 [DBG] PEER: Peer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): Received ChannelUpdate(chain_hash=0f9188f13cb7b2c71f2a335e3a4fc328bf5beb436012afca590b1a11466e2206, short_chan_id=870813209264128, mflags=00000001, cflags=00000011, update_time=2024-04-19 01:49:02 +0000 UTC) from 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:33148 2024-04-19 01:49:02.416 [DBG] PEER: Peer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): Received NodeAnnouncement(node=02605b08614383d52aacdab2b91330d8a51506c69a63bd8dae3c56a34efd85b709, update_time=2024-04-19 01:48:51 +0000 UTC) from 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:33148 2024-04-19 01:49:02.416 [DBG] PEER: Peer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): Received ReplyShortChanIDsEnd(chain_hash=0f9188f13cb7b2c71f2a335e3a4fc328bf5beb436012afca590b1a11466e2206, complete=1) from 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:33148 2024-04-19 01:49:02.416 [WRN] DISC: ignoring remote ChannelAnnouncement for own channel 2024-04-19 01:49:02.416 [TRC] DISC: Received network message: peer=0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:33148, msg=ChannelUpdate, is_remote=true 2024-04-19 01:49:02.416 [TRC] DISC: Received network message: peer=0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:33148, msg=NodeAnnouncement, is_remote=true 2024-04-19 01:49:02.416 [DBG] DISC: Processing NodeAnnouncement: peer=0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:33148, timestamp=2024-04-19 01:49:00 +0000 UTC, node=0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df 2024-04-19 01:49:02.416 [DBG] CRTR: Checking stale node 303236373037346138633362636333636161323337653735393363313334356639633762326336373239663038323332333137306134616365336263386365376466 got Ignoring node announcement for node not found in channel graph (0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df) 2024-04-19 01:49:02.416 [DBG] DISC: Skipped processing stale node: 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df 2024-04-19 01:49:02.416 [TRC] DISC: Processed network message NodeAnnouncement, returned len(announcements)=0, allowDependents=true 2024-04-19 01:49:02.416 [DBG] DISC: Processing ChannelUpdate: peer=0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:33148, short_chan_id=870813209264128, 2024-04-19 01:49:02.416 [DBG] DISC: Got ChannelUpdate for edge not found in graph(shortChanID=870813209264128), saving for reprocessing later 2024-04-19 01:49:02.417 [TRC] DISC: Processed network message ChannelUpdate, returned len(announcements)=0, allowDependents=false 2024-04-19 01:49:02.417 [TRC] DISC: Received network message: peer=0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:33148, msg=ChannelUpdate, is_remote=true 2024-04-19 01:49:02.417 [TRC] DISC: Received network message: peer=0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:33148, msg=NodeAnnouncement, is_remote=true 2024-04-19 01:49:02.417 [DBG] DISC: Processing ChannelUpdate: peer=0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:33148, short_chan_id=870813209264128, 2024-04-19 01:49:02.417 [DBG] DISC: Got ChannelUpdate for edge not found in graph(shortChanID=870813209264128), saving for reprocessing later 2024-04-19 01:49:02.417 [TRC] DISC: Processed network message ChannelUpdate, returned len(announcements)=0, allowDependents=false 2024-04-19 01:49:02.417 [DBG] DISC: Processing NodeAnnouncement: peer=0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:33148, timestamp=2024-04-19 01:48:51 +0000 UTC, node=02605b08614383d52aacdab2b91330d8a51506c69a63bd8dae3c56a34efd85b709 2024-04-19 01:49:02.418 [DBG] CRTR: Checking stale node 303236303562303836313433383364353261616364616232623931333330643861353135303663363961363362643864616533633536613334656664383562373039 got Ignoring outdated announcement for 02605b08614383d52aacdab2b91330d8a51506c69a63bd8dae3c56a34efd85b709 2024-04-19 01:49:02.418 [DBG] DISC: Skipped processing stale node: 02605b08614383d52aacdab2b91330d8a51506c69a63bd8dae3c56a34efd85b709 2024-04-19 01:49:02.418 [TRC] DISC: Processed network message NodeAnnouncement, returned len(announcements)=0, allowDependents=true 2024-04-19 01:49:02.418 [DBG] DISC: GossipSyncer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): state=queryNewChannels, type=PassiveSync 2024-04-19 01:49:02.418 [INF] DISC: GossipSyncer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): no more chans to query 2024-04-19 01:49:02.418 [DBG] DISC: GossipSyncer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): state=chansSynced, type=PassiveSync 2024-04-19 01:49:02.418 [DBG] DISC: Initial historical sync completed 2024-04-19 01:49:02.418 [DBG] DISC: Attempting to transition 1 passive GossipSyncers to active 2024-04-19 01:49:02.418 [DBG] DISC: Transitioning passive GossipSyncer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df) to active 2024-04-19 01:49:02.418 [DBG] DISC: GossipSyncer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): transitioning from PassiveSync to ActiveSync 2024-04-19 01:49:02.418 [INF] DISC: GossipSyncer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): applying gossipFilter(start=2024-04-19 01:49:02.418576509 +0000 UTC m=+8.902429862, end=2160-05-26 08:17:17.418576509 +0000 UTC) 2024-04-19 01:49:02.418 [DBG] DISC: GossipSyncer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): state=chansSynced, type=ActiveSync 2024-04-19 01:49:02.418 [DBG] PEER: Peer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): Sending GossipTimestampRange(chain_hash=0f9188f13cb7b2c71f2a335e3a4fc328bf5beb436012afca590b1a11466e2206, first_stamp=2024-04-19 01:49:02 +0000 UTC, stamp_range=4294967295) to 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:33148 2024-04-19 01:49:02.422 [DBG] PEER: Peer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): Received ChannelUpdate(chain_hash=0f9188f13cb7b2c71f2a335e3a4fc328bf5beb436012afca590b1a11466e2206, short_chan_id=870813209264128, mflags=00000001, cflags=00000011, update_time=2024-04-19 01:49:02 +0000 UTC) from 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:33148 2024-04-19 01:49:02.422 [TRC] DISC: Received network message: peer=0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:33148, msg=ChannelUpdate, is_remote=true 2024-04-19 01:49:02.422 [DBG] DISC: Processing ChannelUpdate: peer=0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:33148, short_chan_id=870813209264128, 2024-04-19 01:49:02.422 [DBG] DISC: Got ChannelUpdate for edge not found in graph(shortChanID=870813209264128), saving for reprocessing later 2024-04-19 01:49:02.422 [TRC] DISC: Processed network message ChannelUpdate, returned len(announcements)=0, allowDependents=false 2024-04-19 01:49:02.423 [DBG] PEER: Peer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): Received ChannelAnnouncement(chain_hash=0f9188f13cb7b2c71f2a335e3a4fc328bf5beb436012afca590b1a11466e2206, short_chan_id=870813209264128) from 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:33148 2024-04-19 01:49:02.424 [WRN] DISC: ignoring remote ChannelAnnouncement for own channel 2024-04-19 01:49:02.425 [DBG] PEER: Peer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): Received ChannelUpdate(chain_hash=0f9188f13cb7b2c71f2a335e3a4fc328bf5beb436012afca590b1a11466e2206, short_chan_id=870813209264128, mflags=00000001, cflags=00000000, update_time=2024-04-19 01:48:50 +0000 UTC) from 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:33148 2024-04-19 01:49:02.425 [TRC] DISC: Received network message: peer=0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:33148, msg=ChannelUpdate, is_remote=true 2024-04-19 01:49:02.425 [DBG] DISC: Processing ChannelUpdate: peer=0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:33148, short_chan_id=870813209264128, 2024-04-19 01:49:02.426 [DBG] DISC: Got ChannelUpdate for edge not found in graph(shortChanID=870813209264128), saving for reprocessing later 2024-04-19 01:49:02.426 [TRC] DISC: Processed network message ChannelUpdate, returned len(announcements)=0, allowDependents=false 2024-04-19 01:49:02.426 [DBG] PEER: Peer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): Received ChannelUpdate(chain_hash=0f9188f13cb7b2c71f2a335e3a4fc328bf5beb436012afca590b1a11466e2206, short_chan_id=870813209264128, mflags=00000001, cflags=00000011, update_time=2024-04-19 01:49:02 +0000 UTC) from 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:33148 2024-04-19 01:49:02.426 [TRC] DISC: Received network message: peer=0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:33148, msg=ChannelUpdate, is_remote=true 2024-04-19 01:49:02.426 [DBG] DISC: Processing ChannelUpdate: peer=0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:33148, short_chan_id=870813209264128, 2024-04-19 01:49:02.426 [DBG] DISC: Got ChannelUpdate for edge not found in graph(shortChanID=870813209264128), saving for reprocessing later 2024-04-19 01:49:02.426 [TRC] DISC: Processed network message ChannelUpdate, returned len(announcements)=0, allowDependents=false 2024-04-19 01:49:02.429 [DBG] PEER: Peer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): Received GossipTimestampRange(chain_hash=0f9188f13cb7b2c71f2a335e3a4fc328bf5beb436012afca590b1a11466e2206, first_stamp=2024-04-19 01:49:02 +0000 UTC, stamp_range=4294967295) from 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:33148 2024-04-19 01:49:02.429 [DBG] CHDB: ChanUpdatesInHorizon hit percentage: NaN (0/0) 2024-04-19 01:49:02.430 [INF] DISC: GossipSyncer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): applying new update horizon: start=2024-04-19 01:49:02 +0000 UTC, end=2160-05-26 08:17:17 +0000 UTC, backlog_size=0 2024-04-19 01:49:02.477 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:02.477 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:02.483 [DBG] PEER: Peer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): Received Error(chan_id=e5223b49d179bfdf9690a42a8855cee05f331e1bc1a7f607769f3515a29128fb, err=sync error) from 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:33148 2024-04-19 01:49:02.635 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:02.635 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:02.685 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:02.686 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:02.739 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:02.740 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:02.787 [DBG] RPCS: [/lnrpc.Lightning/ListPeers] requested 2024-04-19 01:49:02.788 [DBG] RPCS: [listpeers] yielded [0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:33148] peers 2024-04-19 01:49:02.857 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:02.857 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:02.972 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:02.973 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:03.048 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:03.048 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:03.139 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:03.139 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:03.270 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:03.270 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:03.405 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:03.405 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:03.469 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:03.470 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:03.573 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:03.573 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:03.600 [DBG] RPCS: [/lnrpc.Lightning/PendingChannels] requested 2024-04-19 01:49:03.670 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:03.670 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:03.743 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:03.743 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:03.807 [DBG] RPCS: [/lnrpc.Lightning/StopDaemon] requested 2024-04-19 01:49:03.809 [INF] LTND: Received shutdown request. 2024-04-19 01:49:03.809 [INF] LTND: Shutting down... 2024-04-19 01:49:03.810 [INF] LTND: Gracefully shutting down. 2024-04-19 01:49:03.810 [INF] NANN: Channel Status Manager shutting down... 2024-04-19 01:49:03.810 [DBG] NANN: Channel Status Manager shutdown complete 2024-04-19 01:49:03.810 [INF] HSWC: HTLC Switch shutting down... 2024-04-19 01:49:03.810 [INF] NTFN: Cancelling epoch notification, epoch_id=5 2024-04-19 01:49:03.812 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:03.812 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:03.813 [DBG] HSWC: HTLC Switch shutdown complete 2024-04-19 01:49:03.813 [INF] HSWC: Onion processor shutting down... 2024-04-19 01:49:03.813 [INF] HSWC: Decaying hash log received shutdown request 2024-04-19 01:49:03.813 [INF] NTFN: Cancelling epoch notification, epoch_id=10 2024-04-19 01:49:03.813 [DBG] HSWC: Onion processor shutdown complete 2024-04-19 01:49:03.813 [INF] INVC: InvoiceRegistry shutting down... 2024-04-19 01:49:03.813 [INF] NTFN: Cancelling epoch notification, epoch_id=9 2024-04-19 01:49:03.814 [DBG] INVC: InvoiceRegistry shutdown complete 2024-04-19 01:49:03.814 [INF] CRTR: Channel Router shutting down... 2024-04-19 01:49:03.814 [INF] CRTR: FilteredChainView stopping 2024-04-19 01:49:03.814 [DBG] CRTR: Channel Router shutdown complete 2024-04-19 01:49:03.814 [INF] CNCT: ChainArbitrator shutting down... 2024-04-19 01:49:03.814 [DBG] CNCT: Stopping ChannelArbitrator(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0) 2024-04-19 01:49:03.814 [INF] NTFN: Cancelling epoch notification, epoch_id=7 2024-04-19 01:49:03.814 [DBG] CNCT: ChainArbitrator shutdown complete 2024-04-19 01:49:03.814 [INF] FNDG: Funding manager shutting down... 2024-04-19 01:49:03.814 [DBG] FNDG: Funding manager shutdown complete 2024-04-19 01:49:03.814 [INF] BRAR: Breach arbiter shutting down... 2024-04-19 01:49:03.814 [DBG] BRAR: Breach arbiter shutdown complete 2024-04-19 01:49:03.814 [INF] UTXN: UTXO nursery shutting down... 2024-04-19 01:49:03.814 [INF] NTFN: Cancelling epoch notification, epoch_id=4 2024-04-19 01:49:03.814 [DBG] UTXN: UTXO nursery shutdown complete 2024-04-19 01:49:03.814 [INF] DISC: Authenticated gossiper shutting down... 2024-04-19 01:49:03.814 [INF] DISC: Authenticated Gossiper is stopping 2024-04-19 01:49:03.814 [INF] NTFN: Cancelling epoch notification, epoch_id=8 2024-04-19 01:49:03.815 [DBG] DISC: SyncManager is stopping 2024-04-19 01:49:03.815 [DBG] DISC: Stopping GossipSyncer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df) 2024-04-19 01:49:03.815 [DBG] DISC: GossipSyncer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df) stopped 2024-04-19 01:49:03.815 [DBG] DISC: SyncManager stopped 2024-04-19 01:49:03.815 [DBG] DISC: reliableSender is stopping 2024-04-19 01:49:03.815 [DBG] DISC: reliableSender stopped 2024-04-19 01:49:03.815 [INF] DISC: Authenticated Gossiper stopped 2024-04-19 01:49:03.815 [DBG] DISC: Authenticated gossiper shutdown complete 2024-04-19 01:49:03.815 [INF] SWPR: Sweeper shutting down... 2024-04-19 01:49:03.815 [INF] NTFN: Cancelling epoch notification, epoch_id=3 2024-04-19 01:49:03.815 [DBG] SWPR: Sweeper shutdown complete 2024-04-19 01:49:03.815 [INF] CHNF: ChannelNotifier shutting down... 2024-04-19 01:49:03.815 [DBG] CHNF: ChannelNotifier shutdown complete 2024-04-19 01:49:03.815 [INF] PRNF: PeerNotifier shutting down... 2024-04-19 01:49:03.815 [DBG] PRNF: PeerNotifier shutdown complete 2024-04-19 01:49:03.815 [INF] HSWC: HtlcNotifier shutting down... 2024-04-19 01:49:03.815 [DBG] HSWC: HtlcNotifier shutdown complete 2024-04-19 01:49:03.815 [INF] CHBU: chanbackup.SubSwapper shutting down... 2024-04-19 01:49:03.815 [DBG] CHBU: chanbackup.SubSwapper shutdown complete 2024-04-19 01:49:03.815 [INF] NTFN: bitcoind notifier shutting down... 2024-04-19 01:49:03.816 [ERR] NTFN: dead epoch stream in BestBlockTracker 2024-04-19 01:49:03.816 [ERR] HSWC: InterceptableSwitch stopped: block epoch stream stopped 2024-04-19 01:49:03.816 [INF] NTFN: Stopping mempool notifier 2024-04-19 01:49:03.816 [DBG] NTFN: mempool notifier stopped 2024-04-19 01:49:03.816 [DBG] NTFN: bitcoind notifier shutdown complete 2024-04-19 01:49:03.816 [INF] CHFT: ChannelEventStore shutting down... 2024-04-19 01:49:03.816 [DBG] CHFT: recording flap count for: 1 peers 2024-04-19 01:49:03.816 [DBG] CHFT: ChannelEventStore shutdown complete 2024-04-19 01:49:03.816 [DBG] CRTR: Stopping mission control store ticker 2024-04-19 01:49:03.816 [DBG] CRTR: Mission control store ticker stopped 2024-04-19 01:49:03.816 [INF] SRVR: Disconnecting from 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:33148 2024-04-19 01:49:03.816 [INF] PEER: Peer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): disconnecting 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:33148, reason: server: DisconnectPeer called 2024-04-19 01:49:03.816 [INF] PEER: Peer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): unable to read message from peer: read next header: read tcp 127.0.0.1:5827->127.0.0.1:33148: use of closed network connection 2024-04-19 01:49:03.816 [INF] HLCK: Health monitor shutting down 2024-04-19 01:49:03.817 [DBG] HLCK: Health check: monitor quit 2024-04-19 01:49:03.817 [DBG] SRVR: Waiting for server to shutdown... 2024-04-19 01:49:03.917 [DBG] SRVR: Peer 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:33148 has been disconnected 2024-04-19 01:49:03.917 [DBG] SRVR: Server quitting, exit early for peer 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:33148 2024-04-19 01:49:03.917 [DBG] SRVR: Stopping buffer pools... 2024-04-19 01:49:03.917 [INF] RPCS: Stopping RPC Server 2024-04-19 01:49:03.917 [INF] RPCS: Stopping PeersRPC Sub-RPC Server 2024-04-19 01:49:03.918 [INF] RPCS: Stopping RouterRPC Sub-RPC Server 2024-04-19 01:49:03.918 [INF] RPCS: Stopping VersionRPC Sub-RPC Server 2024-04-19 01:49:03.918 [INF] RPCS: Stopping WatchtowerClientRPC Sub-RPC Server 2024-04-19 01:49:03.918 [INF] RPCS: Stopping AutopilotRPC Sub-RPC Server 2024-04-19 01:49:03.918 [INF] RPCS: Stopping InvoicesRPC Sub-RPC Server 2024-04-19 01:49:03.918 [INF] RPCS: Stopping NeutrinoKitRPC Sub-RPC Server 2024-04-19 01:49:03.918 [INF] RPCS: Stopping WalletKitRPC Sub-RPC Server 2024-04-19 01:49:03.918 [INF] RPCS: Stopping WatchtowerRPC Sub-RPC Server 2024-04-19 01:49:03.918 [INF] RPCS: Stopping ChainRPC Sub-RPC Server 2024-04-19 01:49:03.918 [INF] RPCS: Stopping DevRPC Sub-RPC Server 2024-04-19 01:49:03.918 [INF] RPCS: Stopping SignRPC Sub-RPC Server 2024-04-19 01:49:03.920 [INF] LTND: Stopping pprof server... 2024-04-19 01:49:03.920 [INF] LTND: Shutdown complete 2024-04-19 01:49:03.975 [WRN] LTND: open /tmp/lndtest-node2127775919/lnd.conf: no such file or directory 2024-04-19 01:49:03.976 [INF] LTND: Version: 0.17.99-beta commit=fn/v1.0.5-58-gda4b505, build=development, logging=default, debuglevel=debug,DISC=trace 2024-04-19 01:49:03.976 [INF] LTND: Active chain: Bitcoin (network=regtest) 2024-04-19 01:49:03.976 [INF] LTND: Pprof listening on 127.0.0.1:5830 2024-04-19 01:49:03.985 [DBG] NTFR: ChainNotifier RPC server successfully register with root gRPC server 2024-04-19 01:49:03.985 [DBG] NTFR: ChainKit RPC server successfully register with root gRPC server 2024-04-19 01:49:03.985 [DBG] IRPC: Invoices RPC server successfully registered with root gRPC server 2024-04-19 01:49:03.985 [DBG] PRPC: Peers RPC server successfully register with root gRPC server 2024-04-19 01:49:03.985 [DBG] SGNR: Signer RPC server successfully register with root gRPC server 2024-04-19 01:49:03.986 [DBG] ARPC: Autopilot RPC server successfully register with root gRPC server 2024-04-19 01:49:03.986 [DBG] DRPC: DEV RPC server successfully register with root the gRPC server 2024-04-19 01:49:03.986 [DBG] NRPC: Neutrino RPC server successfully register with root gRPC server 2024-04-19 01:49:03.986 [DBG] RRPC: Router RPC server successfully register with root gRPC server 2024-04-19 01:49:03.986 [DBG] VRPC: Versioner RPC server successfully registered with root gRPC server 2024-04-19 01:49:03.986 [DBG] WLKT: WalletKit RPC server successfully registered with root gRPC server 2024-04-19 01:49:03.986 [INF] RPCS: RPC server listening on 127.0.0.1:5828 2024-04-19 01:49:03.990 [DBG] NTFR: ChainNotifier REST server successfully registered with root REST server 2024-04-19 01:49:03.995 [DBG] NTFR: ChainKit REST server successfully registered with root REST server 2024-04-19 01:49:03.995 [DBG] IRPC: Invoices REST server successfully registered with root REST server 2024-04-19 01:49:03.995 [DBG] PRPC: Peers REST server successfully registered with root REST server 2024-04-19 01:49:03.996 [DBG] SGNR: Signer REST server successfully registered with root REST server 2024-04-19 01:49:03.996 [DBG] ARPC: Autopilot REST server successfully registered with root REST server 2024-04-19 01:49:03.996 [DBG] DRPC: DEV REST server successfully registered with the root REST server 2024-04-19 01:49:03.997 [DBG] NRPC: Neutrino REST server successfully registered with root REST server 2024-04-19 01:49:03.997 [DBG] RRPC: Router REST server successfully registered with root REST server 2024-04-19 01:49:03.998 [DBG] VRPC: Versioner REST server successfully registered with root REST server 2024-04-19 01:49:03.998 [DBG] WLKT: WalletKit REST server successfully registered with root REST server 2024-04-19 01:49:03.999 [INF] RPCS: gRPC proxy started at 127.0.0.1:5829 2024-04-19 01:49:03.999 [INF] LTND: Opening the main database, this might take a few minutes... 2024-04-19 01:49:03.999 [INF] LTND: Opening bbolt database, sync_freelist=false, auto_compact=false 2024-04-19 01:49:03.999 [INF] LTND: Creating local graph and channel state DB instances 2024-04-19 01:49:04.005 [DBG] CHDB: Populating in-memory channel graph, this might take a while... 2024-04-19 01:49:04.006 [DBG] CHDB: Finished populating in-memory channel graph (took 276.4µs, num_node_features=1, num_nodes=0, num_channels=0) 2024-04-19 01:49:04.006 [INF] CHDB: Checking for schema update: latest_version=31, db_version=31 2024-04-19 01:49:04.006 [INF] CHDB: Checking for optional update: prune_revocation_log=false, db_version=empty 2024-04-19 01:49:04.006 [INF] LTND: Database(s) now open (time_to_open=7.089367ms)! 2024-04-19 01:49:04.006 [INF] LTND: We're not running within systemd or the service type is not 'notify' 2024-04-19 01:49:04.006 [INF] LTND: Waiting for wallet encryption password. Use `lncli create` to create a wallet, `lncli unlock` to unlock an existing wallet, or `lncli changepassword` to change the password of an existing wallet and unlock it. 2024-04-19 01:49:04.148 [DBG] RPCS: [/lnrpc.State/SubscribeState] requested 2024-04-19 01:49:04.352 [DBG] RPCS: [/lnrpc.WalletUnlocker/UnlockWallet] requested 2024-04-19 01:49:04.370 [INF] LNWL: Opened wallet 2024-04-19 01:49:04.395 [DBG] LNWL: Loading mempool spends... 2024-04-19 01:49:04.396 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:04.396 [INF] LNWL: Started listening for bitcoind block notifications via ZMQ on 127.0.0.1:5559 2024-04-19 01:49:04.396 [INF] LNWL: Started listening for bitcoind transaction notifications via ZMQ on 127.0.0.1:5560 2024-04-19 01:49:04.399 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:04.399 [DBG] LNWL: Loaded mempool spends in 4.503204ms 2024-04-19 01:49:04.399 [INF] LNWL: Started polling mempool to cache new transactions 2024-04-19 01:49:04.404 [INF] CHRE: Using external fee estimator http://localhost:5556/fee-estimates.json: cached=false 2024-04-19 01:49:04.410 [INF] LNWL: The wallet has been unlocked without a time limit 2024-04-19 01:49:04.412 [INF] CHRE: LightningWallet opened 2024-04-19 01:49:04.412 [DBG] LNWL: Birthday block has already been verified: height=0, hash=0f9188f13cb7b2c71f2a335e3a4fc328bf5beb436012afca590b1a11466e2206 2024-04-19 01:49:04.422 [INF] HSWC: Cleaning circuits from disk for closed channels 2024-04-19 01:49:04.422 [DBG] HSWC: Found 0 closed channels 2024-04-19 01:49:04.422 [INF] HSWC: Finished cleaning: no closed channels found, no actions taken. 2024-04-19 01:49:04.422 [INF] HSWC: Restoring in-memory circuit state from disk 2024-04-19 01:49:04.422 [INF] HSWC: Payment circuits loaded: num_pending=0, num_open=0 2024-04-19 01:49:04.426 [DBG] CRTR: Instantiating mission control with config: maximum history: 1000, minimum failure relax interval: 1m0s, estimator type: apriori, penalty halflife time: 1h0m0s, apriori hop probability: 0.6, apriori weight: 0.5, previous success probability: 0.95, capacity fraction: 0.9999 2024-04-19 01:49:04.426 [DBG] CRTR: Mission control state reconstruction started 2024-04-19 01:49:04.426 [DBG] CRTR: Mission control state reconstruction finished: n=0, time=7.8µs 2024-04-19 01:49:04.426 [DBG] SRVR: Instantiating payment session source with config: AttemptCost=100 + 0.1%, MinRouteProbability=0.01 2024-04-19 01:49:04.426 [DBG] SRVR: Sweeper batch window duration: 5s 2024-04-19 01:49:04.427 [DBG] SRVR: Using the dev config for the fundingMgr: &{0s}, reservationTimeout=10m0s, zombieSweeperInterval=1m0s 2024-04-19 01:49:04.428 [DBG] CHBU: Crafting backup for ChannelPoint(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0) 2024-04-19 01:49:04.428 [INF] LTND: Channel backup proxy channel notifier starting 2024-04-19 01:49:04.428 [INF] ATPL: Instantiating autopilot with active=false, max_channels=5, allocation=0.600000, min_chan_size=20000, max_chan_size=16777215, private=false, min_confs=1, conf_target=3 2024-04-19 01:49:04.428 [DBG] LTND: Populating dependencies for sub RPC server: SignRPC 2024-04-19 01:49:04.428 [DBG] LTND: Populating dependencies for sub RPC server: WalletKitRPC 2024-04-19 01:49:04.428 [DBG] LTND: Populating dependencies for sub RPC server: AutopilotRPC 2024-04-19 01:49:04.428 [DBG] LTND: Populating dependencies for sub RPC server: ChainRPC 2024-04-19 01:49:04.428 [DBG] LTND: Populating dependencies for sub RPC server: InvoicesRPC 2024-04-19 01:49:04.428 [DBG] LTND: Populating dependencies for sub RPC server: PeersRPC 2024-04-19 01:49:04.428 [DBG] LTND: Populating dependencies for sub RPC server: NeutrinoKitRPC 2024-04-19 01:49:04.428 [DBG] LTND: Populating dependencies for sub RPC server: RouterRPC 2024-04-19 01:49:04.428 [DBG] LTND: Populating dependencies for sub RPC server: WatchtowerRPC 2024-04-19 01:49:04.429 [DBG] LTND: Populating dependencies for sub RPC server: WatchtowerClientRPC 2024-04-19 01:49:04.429 [DBG] LTND: Populating dependencies for sub RPC server: DevRPC 2024-04-19 01:49:04.429 [DBG] RPCS: Starting sub RPC server: ChainRPC 2024-04-19 01:49:04.429 [DBG] RPCS: Starting sub RPC server: InvoicesRPC 2024-04-19 01:49:04.429 [DBG] RPCS: Starting sub RPC server: PeersRPC 2024-04-19 01:49:04.429 [DBG] RPCS: Starting sub RPC server: SignRPC 2024-04-19 01:49:04.429 [DBG] RPCS: Starting sub RPC server: WatchtowerClientRPC 2024-04-19 01:49:04.429 [DBG] RPCS: Starting sub RPC server: AutopilotRPC 2024-04-19 01:49:04.429 [DBG] RPCS: Starting sub RPC server: DevRPC 2024-04-19 01:49:04.429 [DBG] RPCS: Starting sub RPC server: NeutrinoKitRPC 2024-04-19 01:49:04.429 [DBG] RPCS: Starting sub RPC server: RouterRPC 2024-04-19 01:49:04.429 [DBG] RPCS: Starting sub RPC server: VersionRPC 2024-04-19 01:49:04.430 [DBG] RPCS: Starting sub RPC server: WalletKitRPC 2024-04-19 01:49:04.430 [DBG] RPCS: Starting sub RPC server: WatchtowerRPC 2024-04-19 01:49:04.430 [INF] LTND: We're not running within systemd or the service type is not 'notify' 2024-04-19 01:49:04.431 [INF] LTND: Waiting for chain backend to finish sync, start_height=797 2024-04-19 01:49:04.432 [DBG] LTND: Syncing to block timestamp: 2024-04-19 01:48:51 +0000 UTC, is synced=false 2024-04-19 01:49:04.443 [INF] LNWL: Started rescan from block 6aaf98ecc16d579f416a81e36d8ec9c7053128bec843ada5f48e54768d180f24 (height 797) for 2 addresses 2024-04-19 01:49:04.450 [INF] LNWL: Catching up block hashes to height 797, this might take a while 2024-04-19 01:49:04.450 [INF] LNWL: Done catching up block hashes 2024-04-19 01:49:04.450 [INF] LNWL: Finished rescan for 2 addresses (synced to block 6aaf98ecc16d579f416a81e36d8ec9c7053128bec843ada5f48e54768d180f24, height 797) 2024-04-19 01:49:04.514 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:04.514 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:04.594 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:04.594 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:04.664 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:04.664 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:04.793 [DBG] RPCS: [/lnrpc.State/SubscribeState] requested 2024-04-19 01:49:04.793 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:04.793 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:04.793 [DBG] RPCS: [/lnrpc.State/SubscribeState] requested 2024-04-19 01:49:04.851 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:04.851 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:04.962 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:04.962 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:05.016 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:05.016 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:05.138 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:05.138 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:05.198 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:05.198 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:05.300 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:05.301 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:05.398 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:05.398 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:05.435 [DBG] LTND: Syncing to block timestamp: 2024-04-19 01:48:51 +0000 UTC, is synced=true 2024-04-19 01:49:05.436 [INF] LTND: Chain backend is fully synced (end_height=797)! 2024-04-19 01:49:05.436 [WRN] HLCK: check: disk space configured with 0 attempts, skipping it 2024-04-19 01:49:05.436 [WRN] HLCK: check: tls configured with 0 attempts, skipping it 2024-04-19 01:49:05.436 [INF] LNWL: SigPool starting 2024-04-19 01:49:05.437 [DBG] HLCK: Monitoring: chain backend 2024-04-19 01:49:05.442 [INF] NTFN: New block epoch subscription 2024-04-19 01:49:05.442 [INF] NTFN: New block epoch subscription 2024-04-19 01:49:05.442 [INF] BTCN: Broadcaster now active 2024-04-19 01:49:05.442 [INF] CHNF: ChannelNotifier starting 2024-04-19 01:49:05.442 [INF] PRNF: PeerNotifier starting 2024-04-19 01:49:05.442 [INF] HSWC: HtlcNotifier starting 2024-04-19 01:49:05.442 [INF] SWPR: Sweeper starting 2024-04-19 01:49:05.442 [INF] NTFN: New block epoch subscription 2024-04-19 01:49:05.442 [INF] UTXN: UTXO nursery starting 2024-04-19 01:49:05.442 [DBG] SWPR: Sweep ticker started 2024-04-19 01:49:05.443 [INF] BRAR: Breach arbiter starting 2024-04-19 01:49:05.443 [DBG] BRAR: Found 0 closing channels, 0 retribution records 2024-04-19 01:49:05.443 [INF] FNDG: Funding manager starting 2024-04-19 01:49:05.444 [INF] HSWC: HTLC Switch starting 2024-04-19 01:49:05.444 [INF] NTFN: New block epoch subscription 2024-04-19 01:49:05.447 [INF] BRAR: Starting contract observer, watching for breaches. 2024-04-19 01:49:05.449 [INF] NTFN: New block epoch subscription 2024-04-19 01:49:05.450 [INF] CNCT: ChainArbitrator starting 2024-04-19 01:49:05.450 [INF] CNCT: Creating ChannelArbitrators for 1 active channels 2024-04-19 01:49:05.451 [DBG] CNCT: New ChainEventSubscription(id=0) for ChannelPoint(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0) 2024-04-19 01:49:05.451 [INF] NTFN: New block epoch subscription 2024-04-19 01:49:05.451 [DBG] HSWC: InterceptableSwitch running: height=797, requireInterceptor=false 2024-04-19 01:49:05.451 [DBG] CNCT: Starting chain watcher for ChannelPoint(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0) 2024-04-19 01:49:05.451 [DBG] NTFN: Using height hint 797 retrieved from cache for outpoint=fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0, script=0 d666b4e18da33576a3d1521842771645569626e8f199314525d138ab5d48795e instead of 792 for spend subscription 2024-04-19 01:49:05.451 [INF] NTFN: New spend subscription: spend_id=1, outpoint=fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0, script=0 d666b4e18da33576a3d1521842771645569626e8f199314525d138ab5d48795e, height_hint=797 2024-04-19 01:49:05.451 [INF] NTFN: Dispatching historical spend rescan for outpoint=fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0, script=0 d666b4e18da33576a3d1521842771645569626e8f199314525d138ab5d48795e, start=797, end=797 2024-04-19 01:49:05.455 [DBG] LNWL: ChannelPoint(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): Restoring 0 dangling remote updates 2024-04-19 01:49:05.455 [DBG] LNWL: ChannelPoint(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): Restoring 0 local updates that the peer should sign 2024-04-19 01:49:05.455 [DBG] FNDG: ChannelPoint(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0) with chan_id=e5223b49d179bfdf9690a42a8855cee05f331e1bc1a7f607769f3515a29128fb not found in opening database, assuming already announced to the network 2024-04-19 01:49:05.456 [INF] CNCT: Close observer for ChannelPoint(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0) active 2024-04-19 01:49:05.456 [DBG] CNCT: Starting ChannelArbitrator(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0), htlc_set=(map[contractcourt.HtlcSetKey]contractcourt.htlcSet) (len=2) { (contractcourt.HtlcSetKey) LocalHtlcSet: (contractcourt.htlcSet) { incomingHTLCs: (map[uint64]channeldb.HTLC) { }, outgoingHTLCs: (map[uint64]channeldb.HTLC) { } }, (contractcourt.HtlcSetKey) RemoteHtlcSet: (contractcourt.htlcSet) { incomingHTLCs: (map[uint64]channeldb.HTLC) { }, outgoingHTLCs: (map[uint64]channeldb.HTLC) { } } } , state=StateDefault 2024-04-19 01:49:05.457 [INF] NTFN: Historical spend dispatch finished for request outpoint=fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0, script=0 d666b4e18da33576a3d1521842771645569626e8f199314525d138ab5d48795e (start=797 end=797) with details: 2024-04-19 01:49:05.458 [INF] CNCT: ChannelArbitrator(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): starting state=StateDefault, trigger=chainTrigger, triggerHeight=797 2024-04-19 01:49:05.458 [DBG] CNCT: ChannelArbitrator(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): attempting state step with trigger=chainTrigger from state=StateDefault 2024-04-19 01:49:05.458 [DBG] CNCT: ChannelArbitrator(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): new block (height=797) examining active HTLC's 2024-04-19 01:49:05.459 [DBG] CNCT: ChannelArbitrator(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): checking commit chain actions at height=797, in_htlc_count=0, out_htlc_count=0 2024-04-19 01:49:05.459 [DBG] CNCT: ChannelArbitrator(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): no actions for chain trigger, terminating 2024-04-19 01:49:05.459 [DBG] CNCT: ChannelArbitrator(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): terminating at state=StateDefault 2024-04-19 01:49:05.459 [INF] DISC: Authenticated Gossiper starting 2024-04-19 01:49:05.459 [INF] NTFN: New block epoch subscription 2024-04-19 01:49:05.459 [INF] NTFN: New block epoch subscription 2024-04-19 01:49:05.459 [DBG] CNCT: ChannelArbitrator(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): attempting state step with trigger=chainTrigger from state=StateDefault 2024-04-19 01:49:05.459 [DBG] CNCT: ChannelArbitrator(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): new block (height=797) examining active HTLC's 2024-04-19 01:49:05.459 [DBG] CNCT: ChannelArbitrator(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): checking commit chain actions at height=797, in_htlc_count=0, out_htlc_count=0 2024-04-19 01:49:05.459 [DBG] CNCT: ChannelArbitrator(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): no actions for chain trigger, terminating 2024-04-19 01:49:05.459 [DBG] CNCT: ChannelArbitrator(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): terminating at state=StateDefault 2024-04-19 01:49:05.460 [INF] CRTR: Channel Router starting 2024-04-19 01:49:05.460 [DBG] DISC: New block: height=797, hash=6aaf98ecc16d579f416a81e36d8ec9c7053128bec843ada5f48e54768d180f24 2024-04-19 01:49:05.462 [INF] CRTR: FilteredChainView starting 2024-04-19 01:49:05.468 [DBG] NTFN: Updated spend hint to height=797 for unconfirmed spend request outpoint=fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0, script=0 d666b4e18da33576a3d1521842771645569626e8f199314525d138ab5d48795e 2024-04-19 01:49:05.471 [INF] CRTR: Filtering chain using 0 channels active 2024-04-19 01:49:05.473 [INF] CRTR: Prune tip for Channel Graph: height=797, hash=6aaf98ecc16d579f416a81e36d8ec9c7053128bec843ada5f48e54768d180f24 2024-04-19 01:49:05.473 [DBG] CRTR: Cleaning network result store. 2024-04-19 01:49:05.473 [INF] INVC: InvoiceRegistry starting 2024-04-19 01:49:05.474 [DBG] INVC: Adding 0 pending invoices to the expiry watcher 2024-04-19 01:49:05.474 [INF] HSWC: Onion processor starting 2024-04-19 01:49:05.474 [INF] NTFN: New block epoch subscription 2024-04-19 01:49:05.475 [INF] NTFN: New block epoch subscription 2024-04-19 01:49:05.475 [INF] NANN: Channel Status Manager starting 2024-04-19 01:49:05.476 [INF] CHFT: ChannelEventStore starting 2024-04-19 01:49:05.477 [INF] CHFT: Adding 0 channels to event store 2024-04-19 01:49:05.477 [INF] CHBU: chanbackup.SubSwapper starting 2024-04-19 01:49:05.490 [INF] CHBU: Updating backup file at /tmp/lndtest-node2127775919/data/chain/bitcoin/regtest/channel.backup 2024-04-19 01:49:05.490 [INF] CHBU: Swapping old multi backup file from /tmp/lndtest-node2127775919/data/chain/bitcoin/regtest/temp-dont-use.backup to /tmp/lndtest-node2127775919/data/chain/bitcoin/regtest/channel.backup 2024-04-19 01:49:05.490 [DBG] CHBU: SubSwapper's backupUpdater is active! 2024-04-19 01:49:05.490 [INF] CMGR: Server listening on 127.0.0.1:5827 2024-04-19 01:49:05.490 [DBG] CRTR: New graph topology client subscription, client 1 2024-04-19 01:49:05.491 [DBG] SRVR: Establishing 1 persistent connections on start 2024-04-19 01:49:05.491 [INF] SRVR: Auto peer bootstrapping is disabled 2024-04-19 01:49:05.491 [DBG] SRVR: Attempting persistent connection to channel peer 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:5823 2024-04-19 01:49:05.491 [DBG] CMGR: Attempting to connect to 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:5823 (reqid 1) 2024-04-19 01:49:05.504 [DBG] RPCS: [/lnrpc.Lightning/GetInfo] requested 2024-04-19 01:49:05.506 [DBG] CMGR: Connected to 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:5823 (reqid 1) 2024-04-19 01:49:05.506 [INF] SRVR: Established connection to: 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:5823 2024-04-19 01:49:05.506 [INF] SRVR: Finalizing connection to 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:5823, inbound=false 2024-04-19 01:49:05.506 [DBG] PRNF: PeerNotifier notifying peer: 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df online 2024-04-19 01:49:05.506 [DBG] PEER: Peer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): Sending Init to 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:5823 2024-04-19 01:49:05.516 [DBG] RPCS: [/lnrpc.Lightning/SubscribeChannelGraph] requested 2024-04-19 01:49:05.516 [DBG] CRTR: New graph topology client subscription, client 2 2024-04-19 01:49:05.517 [DBG] PEER: Peer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): Received Init from 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:5823 2024-04-19 01:49:05.517 [DBG] PEER: Peer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): Loaded 1 active channels from database 2024-04-19 01:49:05.523 [DBG] LNWL: ChannelPoint(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): Restoring 0 dangling remote updates 2024-04-19 01:49:05.523 [DBG] LNWL: ChannelPoint(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): Restoring 0 local updates that the peer should sign 2024-04-19 01:49:05.523 [INF] PEER: Peer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): Loading ChannelPoint(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0), isPending=false 2024-04-19 01:49:05.523 [WRN] PEER: Peer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): Unable to find our forwarding policy for channel fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0, using default values 2024-04-19 01:49:05.523 [DBG] CNCT: New ChainEventSubscription(id=1) for ChannelPoint(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0) 2024-04-19 01:49:05.523 [INF] HSWC: ChannelLink(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): starting 2024-04-19 01:49:05.531 [INF] HSWC: Trimming open circuits for chan_id=792:1:0, start_htlc_id=0 2024-04-19 01:49:05.531 [INF] HSWC: Adding live link chan_id=e5223b49d179bfdf9690a42a8855cee05f331e1bc1a7f607769f3515a29128fb, short_chan_id=792:1:0 2024-04-19 01:49:05.531 [DBG] SRVR: Notifying that peer 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:5823 is online 2024-04-19 01:49:05.531 [INF] CNCT: Attempting to update ContractSignals for ChannelPoint(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0) 2024-04-19 01:49:05.531 [WRN] LNWL: ChannelPoint(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): Set available amount to 0 because we could not pay for the CommitmentFee of the new ChannelState: ourBalance is negative after applying the fee: ourBalance=0, current commitFee(w/o additional htlc)=0 mSAT, feeBuffer=0 mSAT (type=feebuffer) local_chan_initiator 2024-04-19 01:49:05.532 [WRN] LNWL: ChannelPoint(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): Set available amount to 0 because we could not pay for the CommitmentFee of the new ChannelState: ourBalance is negative after applying the fee: ourBalance=0, current commitFee(w/o additional htlc)=0 mSAT, feeBuffer=0 mSAT (type=feebuffer) local_chan_initiator 2024-04-19 01:49:05.532 [INF] HSWC: ChannelLink(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): HTLC manager started, bandwidth=0 mSAT 2024-04-19 01:49:05.532 [INF] HSWC: ChannelLink(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): Attempting to re-synchronize channel: SCID=792:1:0, status=ChanStatusLocalDataLoss|ChanStatusRestored, initiator=true, pending=false, local commitment has height=0, local_htlc_index=0, local_log_index=0, remote_htlc_index=0, remote_log_index=0, remote commitment has height=0, local_htlc_index=0, local_log_index=0, remote_htlc_index=0, remote_log_index=0 2024-04-19 01:49:05.533 [DBG] PEER: Peer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): Sending ChannelReestablish(chan_id=e5223b49d179bfdf9690a42a8855cee05f331e1bc1a7f607769f3515a29128fb, next_local_height=0, remote_tail_height=0) to 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:5823 2024-04-19 01:49:05.533 [INF] PEER: Peer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): Negotiated chan series queries 2024-04-19 01:49:05.533 [INF] DISC: Creating new GossipSyncer for peer=0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df 2024-04-19 01:49:05.533 [DBG] DISC: Created new GossipSyncer[state=chansSynced type=PassiveSync] for peer=0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:5823 2024-04-19 01:49:05.534 [DBG] DISC: Starting GossipSyncer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df) 2024-04-19 01:49:05.534 [DBG] DISC: Attempting initial historical sync with GossipSyncer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df) 2024-04-19 01:49:05.534 [DBG] DISC: GossipSyncer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): state=chansSynced, type=PassiveSync 2024-04-19 01:49:05.534 [DBG] DISC: GossipSyncer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): state=syncingChans, type=PassiveSync 2024-04-19 01:49:05.534 [INF] DISC: GossipSyncer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): requesting new chans from height=0 and 797 blocks after 2024-04-19 01:49:05.534 [DBG] DISC: GossipSyncer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): state=waitingQueryRangeReply, type=PassiveSync 2024-04-19 01:49:05.535 [DBG] PEER: Peer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): Received ChannelReestablish(chan_id=e5223b49d179bfdf9690a42a8855cee05f331e1bc1a7f607769f3515a29128fb, next_local_height=1, remote_tail_height=0) from 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:5823 2024-04-19 01:49:05.535 [DBG] PEER: Peer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): Received QueryChannelRange(chain_hash=0f9188f13cb7b2c71f2a335e3a4fc328bf5beb436012afca590b1a11466e2206, start_height=0, end_height=796) from 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:5823 2024-04-19 01:49:05.535 [DBG] PEER: Peer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): Received NodeAnnouncement(node=0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df, update_time=2024-04-19 01:49:05 +0000 UTC) from 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:5823 2024-04-19 01:49:05.535 [DBG] PEER: Peer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): Sending QueryChannelRange(chain_hash=0f9188f13cb7b2c71f2a335e3a4fc328bf5beb436012afca590b1a11466e2206, start_height=0, end_height=796) to 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:5823 2024-04-19 01:49:05.536 [TRC] DISC: Received network message: peer=0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:5823, msg=NodeAnnouncement, is_remote=true 2024-04-19 01:49:05.540 [DBG] DISC: Processing NodeAnnouncement: peer=0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:5823, timestamp=2024-04-19 01:49:05 +0000 UTC, node=0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df 2024-04-19 01:49:05.540 [DBG] CRTR: Checking stale node 303236373037346138633362636333636161323337653735393363313334356639633762326336373239663038323332333137306134616365336263386365376466 got Ignoring node announcement for node not found in channel graph (0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df) 2024-04-19 01:49:05.545 [DBG] DISC: Skipped processing stale node: 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df 2024-04-19 01:49:05.545 [TRC] DISC: Processed network message NodeAnnouncement, returned len(announcements)=0, allowDependents=true 2024-04-19 01:49:05.544 [INF] HSWC: ChannelLink(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): received re-establishment message from remote side 2024-04-19 01:49:05.545 [ERR] LNWL: ChannelPoint(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): sync failed with local data loss: remote believes our tail height is 0, while we have 0! 2024-04-19 01:49:05.545 [WRN] LNWL: ChannelPoint(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): detected restored triggering DLP 2024-04-19 01:49:05.545 [WRN] HSWC: ChannelLink(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): error when syncing channel states: ChannelPoint(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0) with CommitPoint(03aed30a4ecea6f760b78c47fd59f340708ec782d8cca2c7f9838023c85a25aba8) had possible local commitment state data loss 2024-04-19 01:49:05.548 [ERR] HSWC: ChannelLink(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): failing link: unable to synchronize channel states: ChannelPoint(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0) with CommitPoint(03aed30a4ecea6f760b78c47fd59f340708ec782d8cca2c7f9838023c85a25aba8) had possible local commitment state data loss with error: unable to resume channel, recovery required 2024-04-19 01:49:05.548 [INF] HSWC: ChannelLink(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): exited 2024-04-19 01:49:05.544 [INF] DISC: GossipSyncer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): filtering chan range: start_height=0, num_blocks=797 2024-04-19 01:49:05.548 [INF] DISC: GossipSyncer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): sending final chan range chunk, size=0 2024-04-19 01:49:05.548 [DBG] PEER: Peer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): Sending ReplyChannelRange(start_height=0, end_height=796, num_chans=0, encoding=0) to 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:5823 2024-04-19 01:49:05.549 [INF] HSWC: ChannelLink(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): stopping 2024-04-19 01:49:05.549 [INF] HSWC: Removing channel link with ChannelID(e5223b49d179bfdf9690a42a8855cee05f331e1bc1a7f607769f3515a29128fb) 2024-04-19 01:49:05.549 [DBG] PEER: Peer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): Sending Error(chan_id=e5223b49d179bfdf9690a42a8855cee05f331e1bc1a7f607769f3515a29128fb, err=unable to resume channel, recovery required) to 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:5823 2024-04-19 01:49:05.550 [DBG] PEER: Peer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): Received ReplyChannelRange(start_height=0, end_height=796, num_chans=1, encoding=0) from 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:5823 2024-04-19 01:49:05.550 [DBG] PEER: Peer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): Received GossipTimestampRange(chain_hash=0f9188f13cb7b2c71f2a335e3a4fc328bf5beb436012afca590b1a11466e2206, first_stamp=2024-04-19 01:49:05 +0000 UTC, stamp_range=4294967295) from 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:5823 2024-04-19 01:49:05.550 [DBG] CHDB: ChanUpdatesInHorizon hit percentage: NaN (0/0) 2024-04-19 01:49:05.550 [INF] DISC: GossipSyncer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): applying new update horizon: start=2024-04-19 01:49:05 +0000 UTC, end=2160-05-26 08:17:20 +0000 UTC, backlog_size=0 2024-04-19 01:49:05.550 [INF] DISC: GossipSyncer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): buffering chan range reply of size=1 2024-04-19 01:49:05.551 [INF] DISC: GossipSyncer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): filtering through 1 chans 2024-04-19 01:49:05.551 [INF] DISC: GossipSyncer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): starting query for 1 new chans 2024-04-19 01:49:05.551 [DBG] DISC: GossipSyncer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): state=queryNewChannels, type=PassiveSync 2024-04-19 01:49:05.551 [INF] DISC: GossipSyncer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): querying for 1 new channels 2024-04-19 01:49:05.551 [DBG] DISC: GossipSyncer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): state=waitingQueryChanReply, type=PassiveSync 2024-04-19 01:49:05.551 [DBG] PEER: Peer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): Sending QueryShortChanIDs(chain_hash=0f9188f13cb7b2c71f2a335e3a4fc328bf5beb436012afca590b1a11466e2206, encoding=0, num_chans=1) to 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:5823 2024-04-19 01:49:05.552 [DBG] PEER: Peer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): Received ChannelAnnouncement(chain_hash=0f9188f13cb7b2c71f2a335e3a4fc328bf5beb436012afca590b1a11466e2206, short_chan_id=870813209264128) from 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:5823 2024-04-19 01:49:05.552 [DBG] PEER: Peer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): Received ChannelUpdate(chain_hash=0f9188f13cb7b2c71f2a335e3a4fc328bf5beb436012afca590b1a11466e2206, short_chan_id=870813209264128, mflags=00000001, cflags=00000000, update_time=2024-04-19 01:48:50 +0000 UTC) from 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:5823 2024-04-19 01:49:05.552 [DBG] PEER: Peer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): Received NodeAnnouncement(node=0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df, update_time=2024-04-19 01:49:00 +0000 UTC) from 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:5823 2024-04-19 01:49:05.553 [DBG] PEER: Peer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): Received ChannelUpdate(chain_hash=0f9188f13cb7b2c71f2a335e3a4fc328bf5beb436012afca590b1a11466e2206, short_chan_id=870813209264128, mflags=00000001, cflags=00000011, update_time=2024-04-19 01:49:02 +0000 UTC) from 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:5823 2024-04-19 01:49:05.553 [WRN] DISC: ignoring remote ChannelAnnouncement for own channel 2024-04-19 01:49:05.553 [TRC] DISC: Received network message: peer=0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:5823, msg=ChannelUpdate, is_remote=true 2024-04-19 01:49:05.553 [DBG] PEER: Peer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): Received NodeAnnouncement(node=02605b08614383d52aacdab2b91330d8a51506c69a63bd8dae3c56a34efd85b709, update_time=2024-04-19 01:48:51 +0000 UTC) from 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:5823 2024-04-19 01:49:05.553 [TRC] DISC: Received network message: peer=0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:5823, msg=NodeAnnouncement, is_remote=true 2024-04-19 01:49:05.554 [DBG] DISC: Processing NodeAnnouncement: peer=0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:5823, timestamp=2024-04-19 01:49:00 +0000 UTC, node=0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df 2024-04-19 01:49:05.554 [DBG] CRTR: Checking stale node 303236373037346138633362636333636161323337653735393363313334356639633762326336373239663038323332333137306134616365336263386365376466 got Ignoring node announcement for node not found in channel graph (0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df) 2024-04-19 01:49:05.554 [DBG] DISC: Skipped processing stale node: 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df 2024-04-19 01:49:05.554 [TRC] DISC: Processed network message NodeAnnouncement, returned len(announcements)=0, allowDependents=true 2024-04-19 01:49:05.554 [DBG] DISC: Processing ChannelUpdate: peer=0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:5823, short_chan_id=870813209264128, 2024-04-19 01:49:05.554 [DBG] DISC: Got ChannelUpdate for edge not found in graph(shortChanID=870813209264128), saving for reprocessing later 2024-04-19 01:49:05.554 [TRC] DISC: Processed network message ChannelUpdate, returned len(announcements)=0, allowDependents=false 2024-04-19 01:49:05.554 [TRC] DISC: Received network message: peer=0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:5823, msg=ChannelUpdate, is_remote=true 2024-04-19 01:49:05.554 [TRC] DISC: Received network message: peer=0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:5823, msg=NodeAnnouncement, is_remote=true 2024-04-19 01:49:05.554 [DBG] DISC: Processing NodeAnnouncement: peer=0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:5823, timestamp=2024-04-19 01:48:51 +0000 UTC, node=02605b08614383d52aacdab2b91330d8a51506c69a63bd8dae3c56a34efd85b709 2024-04-19 01:49:05.554 [DBG] CRTR: Checking stale node 303236303562303836313433383364353261616364616232623931333330643861353135303663363961363362643864616533633536613334656664383562373039 got Ignoring outdated announcement for 02605b08614383d52aacdab2b91330d8a51506c69a63bd8dae3c56a34efd85b709 2024-04-19 01:49:05.554 [DBG] DISC: Skipped processing stale node: 02605b08614383d52aacdab2b91330d8a51506c69a63bd8dae3c56a34efd85b709 2024-04-19 01:49:05.554 [TRC] DISC: Processed network message NodeAnnouncement, returned len(announcements)=0, allowDependents=true 2024-04-19 01:49:05.554 [DBG] DISC: Processing ChannelUpdate: peer=0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:5823, short_chan_id=870813209264128, 2024-04-19 01:49:05.554 [DBG] DISC: Got ChannelUpdate for edge not found in graph(shortChanID=870813209264128), saving for reprocessing later 2024-04-19 01:49:05.554 [TRC] DISC: Processed network message ChannelUpdate, returned len(announcements)=0, allowDependents=false 2024-04-19 01:49:05.555 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:05.555 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:05.555 [DBG] PEER: Peer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): Received ReplyShortChanIDsEnd(chain_hash=0f9188f13cb7b2c71f2a335e3a4fc328bf5beb436012afca590b1a11466e2206, complete=1) from 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:5823 2024-04-19 01:49:05.555 [DBG] DISC: GossipSyncer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): state=queryNewChannels, type=PassiveSync 2024-04-19 01:49:05.555 [INF] DISC: GossipSyncer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): no more chans to query 2024-04-19 01:49:05.555 [DBG] DISC: GossipSyncer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): state=chansSynced, type=PassiveSync 2024-04-19 01:49:05.555 [DBG] DISC: Initial historical sync completed 2024-04-19 01:49:05.555 [DBG] DISC: Attempting to transition 1 passive GossipSyncers to active 2024-04-19 01:49:05.555 [DBG] DISC: Transitioning passive GossipSyncer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df) to active 2024-04-19 01:49:05.555 [DBG] DISC: GossipSyncer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): transitioning from PassiveSync to ActiveSync 2024-04-19 01:49:05.555 [INF] DISC: GossipSyncer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): applying gossipFilter(start=2024-04-19 01:49:05.555689857 +0000 UTC m=+1.623119329, end=2160-05-26 08:17:20.555689857 +0000 UTC) 2024-04-19 01:49:05.555 [DBG] DISC: GossipSyncer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): state=chansSynced, type=ActiveSync 2024-04-19 01:49:05.555 [DBG] PEER: Peer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): Sending GossipTimestampRange(chain_hash=0f9188f13cb7b2c71f2a335e3a4fc328bf5beb436012afca590b1a11466e2206, first_stamp=2024-04-19 01:49:05 +0000 UTC, stamp_range=4294967295) to 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:5823 2024-04-19 01:49:05.606 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:05.606 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:05.740 [DBG] LNWL: Skipping coinbase tx 0880543f10acecfd90c790a04bc4ad00e3aab8d68ed51b80b0d1509dc4eeed71 2024-04-19 01:49:05.741 [DBG] LNWL: Filtering block 798 (1cbff62e5dc4c900270c42d7357af0d6080d19fca9d617afd9087ed5579ad24a) with 2 transactions 2024-04-19 01:49:05.741 [DBG] LNWL: Filtering block 798 (1cbff62e5dc4c900270c42d7357af0d6080d19fca9d617afd9087ed5579ad24a) with 2 transactions 2024-04-19 01:49:05.741 [DBG] NTFN: Found spend of outpoint=fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0, script=0 d666b4e18da33576a3d1521842771645569626e8f199314525d138ab5d48795e: spend_tx=97db806b41310cfef9fb54cae4942f2b407fd2d6c698d95963f22faa29845e63, block_height=798 2024-04-19 01:49:05.741 [INF] CRTR: Pruning channel graph using block 1cbff62e5dc4c900270c42d7357af0d6080d19fca9d617afd9087ed5579ad24a (height=798) 2024-04-19 01:49:05.743 [DBG] CHDB: Pruned graph, cache now has num_node_features=1, num_nodes=0, num_channels=0 2024-04-19 01:49:05.743 [INF] CRTR: Block 1cbff62e5dc4c900270c42d7357af0d6080d19fca9d617afd9087ed5579ad24a (height=798) closed 0 channels 2024-04-19 01:49:05.744 [DBG] NTFN: Filtering 2 txns for 1 spend requests at height 798 2024-04-19 01:49:05.744 [DBG] NTFN: Found spend of outpoint=fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0, script=0 d666b4e18da33576a3d1521842771645569626e8f199314525d138ab5d48795e: spend_tx=97db806b41310cfef9fb54cae4942f2b407fd2d6c698d95963f22faa29845e63, block_height=798 2024-04-19 01:49:05.744 [DBG] NTFN: Spend request outpoint=fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0, script=0 d666b4e18da33576a3d1521842771645569626e8f199314525d138ab5d48795e spent at tip=798 2024-04-19 01:49:05.754 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:05.754 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:05.755 [INF] NTFN: New block: height=798, sha=1cbff62e5dc4c900270c42d7357af0d6080d19fca9d617afd9087ed5579ad24a 2024-04-19 01:49:05.755 [INF] NTFN: Dispatching confirmed spend notification for outpoint=fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0, script=0 d666b4e18da33576a3d1521842771645569626e8f199314525d138ab5d48795e at current height=798: 97db806b41310cfef9fb54cae4942f2b407fd2d6c698d95963f22faa29845e63[0] spending fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0 at height=798 2024-04-19 01:49:05.755 [DBG] SWPR: New block: height=798, sha=1cbff62e5dc4c900270c42d7357af0d6080d19fca9d617afd9087ed5579ad24a 2024-04-19 01:49:05.755 [INF] UTXN: Attempting to graduate height=798: num_kids=0, num_babies=0 2024-04-19 01:49:05.755 [DBG] DISC: New block: height=798, hash=1cbff62e5dc4c900270c42d7357af0d6080d19fca9d617afd9087ed5579ad24a 2024-04-19 01:49:05.755 [DBG] CNCT: ChannelArbitrator(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): attempting state step with trigger=chainTrigger from state=StateDefault 2024-04-19 01:49:05.756 [DBG] CNCT: ChannelArbitrator(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): new block (height=798) examining active HTLC's 2024-04-19 01:49:05.756 [DBG] CNCT: ChannelArbitrator(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): checking commit chain actions at height=798, in_htlc_count=0, out_htlc_count=0 2024-04-19 01:49:05.756 [DBG] CNCT: ChannelArbitrator(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): no actions for chain trigger, terminating 2024-04-19 01:49:05.756 [DBG] CNCT: ChannelArbitrator(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): terminating at state=StateDefault 2024-04-19 01:49:05.755 [WRN] CNCT: Unknown commitment broadcast for ChannelPoint(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0) 2024-04-19 01:49:05.760 [WRN] CNCT: Remote node broadcast state #0, which is more than 1 beyond best known state #0!!! Attempting recovery... 2024-04-19 01:49:05.761 [INF] CNCT: ChannelPoint(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0) is tweakless, moving to sweep directly on chain 2024-04-19 01:49:05.762 [INF] CNCT: Unilateral close of ChannelPoint(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0) detected 2024-04-19 01:49:05.764 [INF] CNCT: ChannelArbitrator(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): remote party has closed channel out on-chain 2024-04-19 01:49:05.790 [DBG] CNCT: ChannelArbitrator(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): attempting state step with trigger=remoteCloseTrigger from state=StateDefault 2024-04-19 01:49:05.790 [DBG] CNCT: ChannelArbitrator(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): new block (height=798) examining active HTLC's 2024-04-19 01:49:05.790 [DBG] CNCT: ChannelArbitrator(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): checking commit chain actions at height=798, in_htlc_count=0, out_htlc_count=0 2024-04-19 01:49:05.790 [DBG] CHBU: Removing channel fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0 from backup state 2024-04-19 01:49:05.790 [INF] CHBU: Updating on-disk multi SCB backup: num_old_chans=1, num_new_chans=0 2024-04-19 01:49:05.790 [WRN] CHFT: could not remove channel: channel: fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0 not present 2024-04-19 01:49:05.799 [INF] CHBU: Updating backup file at /tmp/lndtest-node2127775919/data/chain/bitcoin/regtest/channel.backup 2024-04-19 01:49:05.800 [INF] CHBU: Swapping old multi backup file from /tmp/lndtest-node2127775919/data/chain/bitcoin/regtest/temp-dont-use.backup to /tmp/lndtest-node2127775919/data/chain/bitcoin/regtest/channel.backup 2024-04-19 01:49:05.800 [DBG] CNCT: ChannelArbitrator(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): attempting state step with trigger=remoteCloseTrigger from state=StateContractClosed 2024-04-19 01:49:05.801 [DBG] CNCT: ChannelArbitrator(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): checking commit chain actions at height=798, in_htlc_count=0, out_htlc_count=0 2024-04-19 01:49:05.801 [DBG] CNCT: ChannelArbitrator(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): inserting 2 contract resolvers 2024-04-19 01:49:05.812 [DBG] CNCT: ChannelArbitrator(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): attempting to resolve *contractcourt.commitSweepResolver 2024-04-19 01:49:05.812 [DBG] CNCT: ChannelArbitrator(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): contract *contractcourt.commitSweepResolver not yet resolved 2024-04-19 01:49:05.812 [INF] NTFN: New confirmation subscription: conf_id=1, txid=97db806b41310cfef9fb54cae4942f2b407fd2d6c698d95963f22faa29845e63, num_confs=1 height_hint=798 2024-04-19 01:49:05.813 [DBG] NTFN: Dispatching historical confirmation rescan for txid=97db806b41310cfef9fb54cae4942f2b407fd2d6c698d95963f22faa29845e63 2024-04-19 01:49:05.812 [DBG] CNCT: ChannelArbitrator(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): attempting to resolve *contractcourt.anchorResolver 2024-04-19 01:49:05.814 [DBG] CNCT: ChannelArbitrator(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): contract *contractcourt.anchorResolver not yet resolved 2024-04-19 01:49:05.814 [INF] SWPR: Sweep request received: out_point=97db806b41310cfef9fb54cae4942f2b407fd2d6c698d95963f22faa29845e63:0, witness_type=CommitmentAnchor, relative_time_lock=0, absolute_time_lock=0, amount=0.00000330 BTC, parent=(), params=(fee=253 sat/kw, force=false, exclusive_group=nil) 2024-04-19 01:49:05.814 [INF] NTFN: New spend subscription: spend_id=2, outpoint=97db806b41310cfef9fb54cae4942f2b407fd2d6c698d95963f22faa29845e63:0, script=0 b9fdf0fb331d50394f22189a5aefb3e83099775a7dc519f03d666ac2b43e0d3e, height_hint=798 2024-04-19 01:49:05.815 [INF] NTFN: Dispatching historical spend rescan for outpoint=97db806b41310cfef9fb54cae4942f2b407fd2d6c698d95963f22faa29845e63:0, script=0 b9fdf0fb331d50394f22189a5aefb3e83099775a7dc519f03d666ac2b43e0d3e, start=798, end=798 2024-04-19 01:49:05.823 [DBG] NTFN: Updated spend hint to height=798 for unconfirmed spend request outpoint=97db806b41310cfef9fb54cae4942f2b407fd2d6c698d95963f22faa29845e63:0, script=0 b9fdf0fb331d50394f22189a5aefb3e83099775a7dc519f03d666ac2b43e0d3e 2024-04-19 01:49:05.823 [DBG] CNCT: ChannelArbitrator(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): attempting state step with trigger=remoteCloseTrigger from state=StateWaitingFullResolution 2024-04-19 01:49:05.823 [INF] CNCT: ChannelArbitrator(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): still awaiting contract resolution 2024-04-19 01:49:05.824 [DBG] CNCT: ChannelArbitrator(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): still have unresolved contract: *contractcourt.commitSweepResolver 2024-04-19 01:49:05.824 [DBG] CNCT: ChannelArbitrator(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): terminating at state=StateWaitingFullResolution 2024-04-19 01:49:05.823 [DBG] NTFN: Updating confirmation details for txid=97db806b41310cfef9fb54cae4942f2b407fd2d6c698d95963f22faa29845e63 2024-04-19 01:49:05.834 [INF] NTFN: Dispatching 1 confirmation notification for txid=97db806b41310cfef9fb54cae4942f2b407fd2d6c698d95963f22faa29845e63 2024-04-19 01:49:05.834 [INF] NTFN: Canceling confirmation notification: conf_id=1, txid=97db806b41310cfef9fb54cae4942f2b407fd2d6c698d95963f22faa29845e63 2024-04-19 01:49:05.834 [DBG] CNCT: *contractcourt.commitSweepResolver(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): commit conf_height=798, unlock_height=799 2024-04-19 01:49:05.834 [DBG] CNCT: *contractcourt.commitSweepResolver(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): waiting for CSV lock to expire at height 799 2024-04-19 01:49:05.834 [INF] NTFN: New block epoch subscription 2024-04-19 01:49:05.834 [INF] NTFN: Cancelling epoch notification, epoch_id=11 2024-04-19 01:49:05.835 [DBG] CNCT: *contractcourt.commitSweepResolver(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): isDelayedOutput=true, isLocalCommitTx=false 2024-04-19 01:49:05.835 [INF] CNCT: *contractcourt.commitSweepResolver(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): Sweeping with witness type: CommitmentToRemoteConfirmed 2024-04-19 01:49:05.835 [INF] CNCT: *contractcourt.commitSweepResolver(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): sweeping commit output 2024-04-19 01:49:05.835 [WRN] LNWL: Web API does not have a fee rate for target=6, using the fee rate for target=1 instead 2024-04-19 01:49:05.836 [DBG] LNWL: Web API returning 30000 sat/kw for conf target of 6 2024-04-19 01:49:05.836 [INF] SWPR: Sweep request received: out_point=97db806b41310cfef9fb54cae4942f2b407fd2d6c698d95963f22faa29845e63:2, witness_type=CommitmentToRemoteConfirmed, relative_time_lock=1, absolute_time_lock=0, amount=0.04996530 BTC, parent=(), params=(fee=6 blocks, force=false, exclusive_group=nil) 2024-04-19 01:49:05.836 [INF] NTFN: New spend subscription: spend_id=3, outpoint=97db806b41310cfef9fb54cae4942f2b407fd2d6c698d95963f22faa29845e63:2, script=0 6ac11b61a316a4643993fff2461c12c5a4c619ed57072040de5ae219aaca754b, height_hint=798 2024-04-19 01:49:05.836 [INF] NTFN: Dispatching historical spend rescan for outpoint=97db806b41310cfef9fb54cae4942f2b407fd2d6c698d95963f22faa29845e63:2, script=0 6ac11b61a316a4643993fff2461c12c5a4c619ed57072040de5ae219aaca754b, start=798, end=798 2024-04-19 01:49:05.847 [DBG] NTFN: Updated spend hint to height=798 for unconfirmed spend request outpoint=97db806b41310cfef9fb54cae4942f2b407fd2d6c698d95963f22faa29845e63:2, script=0 6ac11b61a316a4643993fff2461c12c5a4c619ed57072040de5ae219aaca754b 2024-04-19 01:49:05.885 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:05.886 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:05.950 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:05.950 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:06.049 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:06.049 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:06.158 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:06.159 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:06.269 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:06.269 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:06.338 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:06.338 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:06.416 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:06.416 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:06.443 [DBG] RPCS: [/lnrpc.Lightning/GetInfo] requested 2024-04-19 01:49:06.554 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:06.555 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:06.623 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:06.623 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:06.757 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:06.757 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:06.851 [DBG] RPCS: [/lnrpc.Lightning/PendingChannels] requested 2024-04-19 01:49:06.855 [DBG] UTXN: NurseryReport: building nursery report for channel fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0 2024-04-19 01:49:06.873 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:06.873 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:06.983 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:06.984 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:07.052 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:07.052 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:07.164 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:07.164 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:07.235 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:07.235 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:07.351 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:07.352 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:07.471 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:07.471 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:07.575 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:07.575 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:07.701 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:07.701 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:07.792 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:07.792 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:07.904 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:07.904 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:08.044 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:08.045 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:08.110 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:08.110 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:08.162 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:08.162 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:08.216 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:08.216 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:08.321 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:08.321 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:08.469 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:08.469 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:08.603 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:08.603 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:08.678 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:08.679 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:08.766 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:08.767 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:08.886 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:08.886 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:08.997 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:08.997 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:09.126 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:09.126 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:09.254 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:09.254 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:09.347 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:09.347 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:09.483 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:09.483 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:09.625 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:09.626 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:09.730 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:09.730 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:09.798 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:09.798 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:09.905 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:09.905 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:09.987 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:09.987 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:10.135 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:10.135 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:10.215 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:10.215 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:10.361 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:10.361 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:10.443 [DBG] SWPR: Sweep ticker ticks, attempt sweeping... 2024-04-19 01:49:10.446 [WRN] LNWL: Web API does not have a fee rate for target=6, using the fee rate for target=1 instead 2024-04-19 01:49:10.446 [DBG] LNWL: Web API returning 30000 sat/kw for conf target of 6 2024-04-19 01:49:10.446 [DBG] SWPR: Added positive yield input 97db806b41310cfef9fb54cae4942f2b407fd2d6c698d95963f22faa29845e63:2 (CommitmentToRemoteConfirmed) to input set 2024-04-19 01:49:10.446 [INF] SWPR: Candidate sweep set of size=1 (+0 wallet inputs), has yield=0.04981800 BTC, weight=491 2024-04-19 01:49:10.446 [DBG] SWPR: Sweep candidates at height=798: total_num_pending=0, total_num_new=1 2024-04-19 01:49:10.466 [INF] SWPR: Creating sweep transaction 996298152f4fd7aa8d266be27125b453bd6025da9be6ca168928e58dad368335 for 1 inputs (97db806b41310cfef9fb54cae4942f2b407fd2d6c698d95963f22faa29845e63:2 (CommitmentToRemoteConfirmed)) using 30000 sat/kw, tx_weight=491, tx_fee=0.00014730 BTC, parents_count=0, parents_fee=0 BTC, parents_weight=0 2024-04-19 01:49:10.466 [DBG] SWPR: Rescheduling input 97db806b41310cfef9fb54cae4942f2b407fd2d6c698d95963f22faa29845e63:2 after 1 attempts at height 799 (delta 1) 2024-04-19 01:49:10.466 [DBG] SWPR: Publishing sweep tx 996298152f4fd7aa8d266be27125b453bd6025da9be6ca168928e58dad368335, num_inputs=1, height=798 2024-04-19 01:49:10.468 [INF] LNWL: Inserting unconfirmed transaction 996298152f4fd7aa8d266be27125b453bd6025da9be6ca168928e58dad368335 2024-04-19 01:49:10.468 [DBG] LNWL: Marked address bcrt1p0jrmyq9wpcf2n3uqsnhrx2j3tmucqxx6vec2k0hvfefpa3km3mws5tvw4j used 2024-04-19 01:49:10.469 [DBG] BTWL: TestMempoolAccept result: (*btcjson.TestMempoolAcceptResult)(0x4c23530)({ Txid: (string) (len=64) "996298152f4fd7aa8d266be27125b453bd6025da9be6ca168928e58dad368335", Wtxid: (string) (len=64) "e60ecfc969010974c4ccd255a53f01471ea598155b1da982ceb8046772c8f56c", PackageError: (string) "", Allowed: (bool) false, Vsize: (int32) 0, Fees: (*btcjson.TestMempoolAcceptFees)(), RejectReason: (string) (len=22) "txn-already-in-mempool" }) 2024-04-19 01:49:10.469 [WRN] BTWL: Transaction 996298152f4fd7aa8d266be27125b453bd6025da9be6ca168928e58dad368335 not accepted by mempool: txn-already-in-mempool 2024-04-19 01:49:10.480 [DBG] SWPR: Added positive yield input 97db806b41310cfef9fb54cae4942f2b407fd2d6c698d95963f22faa29845e63:0 (CommitmentAnchor) to input set 2024-04-19 01:49:10.484 [INF] SWPR: Candidate sweep set of size=1 (+1 wallet inputs), has yield=0.00000147 BTC, weight=725 2024-04-19 01:49:10.484 [DBG] SWPR: Sweep candidates at height=798: total_num_pending=0, total_num_new=1 2024-04-19 01:49:10.490 [INF] NTFN: New confirmation subscription: conf_id=2, txid=996298152f4fd7aa8d266be27125b453bd6025da9be6ca168928e58dad368335, num_confs=6 height_hint=798 2024-04-19 01:49:10.490 [DBG] NTFN: Dispatching historical confirmation rescan for txid=996298152f4fd7aa8d266be27125b453bd6025da9be6ca168928e58dad368335 2024-04-19 01:49:10.492 [DBG] NTFN: Confirmation details for txid=996298152f4fd7aa8d266be27125b453bd6025da9be6ca168928e58dad368335 not found during historical dispatch, waiting to dispatch at tip 2024-04-19 01:49:10.501 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:10.501 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:10.504 [INF] SWPR: Creating sweep transaction 701414c16826639cdf6975704f150246054b16f436a77db5cc013ce59a7718ce for 2 inputs (97db806b41310cfef9fb54cae4942f2b407fd2d6c698d95963f22faa29845e63:0 (CommitmentAnchor), fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:1 (TaprootPubKeySpend)) using 253 sat/kw, tx_weight=725, tx_fee=0.00000183 BTC, parents_count=0, parents_fee=0 BTC, parents_weight=0 2024-04-19 01:49:10.504 [DBG] SWPR: Rescheduling input 97db806b41310cfef9fb54cae4942f2b407fd2d6c698d95963f22faa29845e63:0 after 1 attempts at height 799 (delta 1) 2024-04-19 01:49:10.504 [DBG] SWPR: Publishing sweep tx 701414c16826639cdf6975704f150246054b16f436a77db5cc013ce59a7718ce, num_inputs=2, height=798 2024-04-19 01:49:10.507 [INF] LNWL: Inserting unconfirmed transaction 701414c16826639cdf6975704f150246054b16f436a77db5cc013ce59a7718ce 2024-04-19 01:49:10.507 [DBG] LNWL: Marked address bcrt1p9mcfxgu3r508rkt2e4hezg3r7an5pr8jnl83v6kqduzt67rrvtlqluaxq7 used 2024-04-19 01:49:10.508 [DBG] BTWL: TestMempoolAccept result: (*btcjson.TestMempoolAcceptResult)(0x53fa630)({ Txid: (string) (len=64) "701414c16826639cdf6975704f150246054b16f436a77db5cc013ce59a7718ce", Wtxid: (string) (len=64) "690cf6dff366d51eedd9c3456d0d8c95b8eb691c6b94a29acfbffa15bb54c3e0", PackageError: (string) "", Allowed: (bool) false, Vsize: (int32) 0, Fees: (*btcjson.TestMempoolAcceptFees)(), RejectReason: (string) (len=22) "txn-already-in-mempool" }) 2024-04-19 01:49:10.508 [WRN] BTWL: Transaction 701414c16826639cdf6975704f150246054b16f436a77db5cc013ce59a7718ce not accepted by mempool: txn-already-in-mempool 2024-04-19 01:49:10.516 [INF] NTFN: New confirmation subscription: conf_id=3, txid=701414c16826639cdf6975704f150246054b16f436a77db5cc013ce59a7718ce, num_confs=6 height_hint=798 2024-04-19 01:49:10.516 [DBG] NTFN: Dispatching historical confirmation rescan for txid=701414c16826639cdf6975704f150246054b16f436a77db5cc013ce59a7718ce 2024-04-19 01:49:10.517 [DBG] NTFN: Confirmation details for txid=701414c16826639cdf6975704f150246054b16f436a77db5cc013ce59a7718ce not found during historical dispatch, waiting to dispatch at tip 2024-04-19 01:49:10.551 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:10.551 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:10.691 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:10.692 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:10.804 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:10.804 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:10.861 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:10.862 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:10.990 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:10.990 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:11.098 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:11.098 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:11.207 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:11.207 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:11.349 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:11.349 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:11.422 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:11.422 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:11.494 [DBG] LNWL: Filtering block 799 (148fda4415604aa317852a99242e7bbb534198d247f534ac7b8bf693ea339f0a) with 4 transactions 2024-04-19 01:49:11.495 [DBG] LNWL: Filtering block 799 (148fda4415604aa317852a99242e7bbb534198d247f534ac7b8bf693ea339f0a) with 4 transactions 2024-04-19 01:49:11.495 [INF] CRTR: Pruning channel graph using block 148fda4415604aa317852a99242e7bbb534198d247f534ac7b8bf693ea339f0a (height=799) 2024-04-19 01:49:11.495 [DBG] CHDB: Pruned graph, cache now has num_node_features=1, num_nodes=0, num_channels=0 2024-04-19 01:49:11.495 [INF] CRTR: Block 148fda4415604aa317852a99242e7bbb534198d247f534ac7b8bf693ea339f0a (height=799) closed 0 channels 2024-04-19 01:49:11.495 [INF] LNWL: Marking unconfirmed transaction 996298152f4fd7aa8d266be27125b453bd6025da9be6ca168928e58dad368335 mined in block 799 2024-04-19 01:49:11.495 [DBG] LNWL: Marked address bcrt1p0jrmyq9wpcf2n3uqsnhrx2j3tmucqxx6vec2k0hvfefpa3km3mws5tvw4j used 2024-04-19 01:49:11.497 [DBG] LNWL: Skipping coinbase tx a549e4eab22ed4a11826663459fcd20bf5627510d55a6e0a2e5bb3af623a6446 2024-04-19 01:49:11.498 [DBG] NTFN: Found spend of outpoint=97db806b41310cfef9fb54cae4942f2b407fd2d6c698d95963f22faa29845e63:2, script=0 6ac11b61a316a4643993fff2461c12c5a4c619ed57072040de5ae219aaca754b: spend_tx=996298152f4fd7aa8d266be27125b453bd6025da9be6ca168928e58dad368335, block_height=799 2024-04-19 01:49:11.498 [DBG] NTFN: Found spend of outpoint=97db806b41310cfef9fb54cae4942f2b407fd2d6c698d95963f22faa29845e63:0, script=0 b9fdf0fb331d50394f22189a5aefb3e83099775a7dc519f03d666ac2b43e0d3e: spend_tx=701414c16826639cdf6975704f150246054b16f436a77db5cc013ce59a7718ce, block_height=799 2024-04-19 01:49:11.499 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:11.499 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:11.502 [DBG] NTFN: Filtering 4 txns for 3 spend requests at height 799 2024-04-19 01:49:11.502 [DBG] NTFN: Found spend of outpoint=97db806b41310cfef9fb54cae4942f2b407fd2d6c698d95963f22faa29845e63:2, script=0 6ac11b61a316a4643993fff2461c12c5a4c619ed57072040de5ae219aaca754b: spend_tx=996298152f4fd7aa8d266be27125b453bd6025da9be6ca168928e58dad368335, block_height=799 2024-04-19 01:49:11.502 [DBG] NTFN: Spend request outpoint=97db806b41310cfef9fb54cae4942f2b407fd2d6c698d95963f22faa29845e63:2, script=0 6ac11b61a316a4643993fff2461c12c5a4c619ed57072040de5ae219aaca754b spent at tip=799 2024-04-19 01:49:11.502 [DBG] NTFN: Found initial confirmation of txid=996298152f4fd7aa8d266be27125b453bd6025da9be6ca168928e58dad368335: height=799, hash=148fda4415604aa317852a99242e7bbb534198d247f534ac7b8bf693ea339f0a 2024-04-19 01:49:11.502 [DBG] NTFN: Found spend of outpoint=97db806b41310cfef9fb54cae4942f2b407fd2d6c698d95963f22faa29845e63:0, script=0 b9fdf0fb331d50394f22189a5aefb3e83099775a7dc519f03d666ac2b43e0d3e: spend_tx=701414c16826639cdf6975704f150246054b16f436a77db5cc013ce59a7718ce, block_height=799 2024-04-19 01:49:11.502 [DBG] NTFN: Spend request outpoint=97db806b41310cfef9fb54cae4942f2b407fd2d6c698d95963f22faa29845e63:0, script=0 b9fdf0fb331d50394f22189a5aefb3e83099775a7dc519f03d666ac2b43e0d3e spent at tip=799 2024-04-19 01:49:11.502 [DBG] NTFN: Found initial confirmation of txid=701414c16826639cdf6975704f150246054b16f436a77db5cc013ce59a7718ce: height=799, hash=148fda4415604aa317852a99242e7bbb534198d247f534ac7b8bf693ea339f0a 2024-04-19 01:49:11.503 [INF] LNWL: Marking unconfirmed transaction 701414c16826639cdf6975704f150246054b16f436a77db5cc013ce59a7718ce mined in block 799 2024-04-19 01:49:11.503 [DBG] LNWL: Marked address bcrt1p9mcfxgu3r508rkt2e4hezg3r7an5pr8jnl83v6kqduzt67rrvtlqluaxq7 used 2024-04-19 01:49:11.524 [INF] NTFN: New block: height=799, sha=148fda4415604aa317852a99242e7bbb534198d247f534ac7b8bf693ea339f0a 2024-04-19 01:49:11.525 [INF] NTFN: Dispatching confirmed spend notification for outpoint=97db806b41310cfef9fb54cae4942f2b407fd2d6c698d95963f22faa29845e63:2, script=0 6ac11b61a316a4643993fff2461c12c5a4c619ed57072040de5ae219aaca754b at current height=799: 996298152f4fd7aa8d266be27125b453bd6025da9be6ca168928e58dad368335[0] spending 97db806b41310cfef9fb54cae4942f2b407fd2d6c698d95963f22faa29845e63:2 at height=799 2024-04-19 01:49:11.525 [INF] UTXN: Attempting to graduate height=799: num_kids=0, num_babies=0 2024-04-19 01:49:11.525 [INF] NTFN: Dispatching confirmed spend notification for outpoint=97db806b41310cfef9fb54cae4942f2b407fd2d6c698d95963f22faa29845e63:0, script=0 b9fdf0fb331d50394f22189a5aefb3e83099775a7dc519f03d666ac2b43e0d3e at current height=799: 701414c16826639cdf6975704f150246054b16f436a77db5cc013ce59a7718ce[0] spending 97db806b41310cfef9fb54cae4942f2b407fd2d6c698d95963f22faa29845e63:0 at height=799 2024-04-19 01:49:11.525 [DBG] SWPR: New block: height=799, sha=148fda4415604aa317852a99242e7bbb534198d247f534ac7b8bf693ea339f0a 2024-04-19 01:49:11.525 [DBG] SWPR: Delivering spend ntfn for 97db806b41310cfef9fb54cae4942f2b407fd2d6c698d95963f22faa29845e63:2 2024-04-19 01:49:11.525 [DBG] SWPR: Delivered spend ntfn for 97db806b41310cfef9fb54cae4942f2b407fd2d6c698d95963f22faa29845e63:2 2024-04-19 01:49:11.525 [DBG] SWPR: Dispatching sweep success for 97db806b41310cfef9fb54cae4942f2b407fd2d6c698d95963f22faa29845e63:2 to 1 listeners 2024-04-19 01:49:11.525 [DBG] SWPR: Canceling spend ntfn for 97db806b41310cfef9fb54cae4942f2b407fd2d6c698d95963f22faa29845e63:2 2024-04-19 01:49:11.525 [INF] NTFN: Canceling spend notification: spend_id=3, outpoint=97db806b41310cfef9fb54cae4942f2b407fd2d6c698d95963f22faa29845e63:2, script=0 6ac11b61a316a4643993fff2461c12c5a4c619ed57072040de5ae219aaca754b 2024-04-19 01:49:11.525 [INF] CNCT: *contractcourt.commitSweepResolver(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): local commitment output fully resolved by sweep tx: 996298152f4fd7aa8d266be27125b453bd6025da9be6ca168928e58dad368335 2024-04-19 01:49:11.525 [DBG] DISC: New block: height=799, hash=148fda4415604aa317852a99242e7bbb534198d247f534ac7b8bf693ea339f0a 2024-04-19 01:49:11.525 [DBG] SWPR: Delivering spend ntfn for 97db806b41310cfef9fb54cae4942f2b407fd2d6c698d95963f22faa29845e63:0 2024-04-19 01:49:11.525 [DBG] SWPR: Delivered spend ntfn for 97db806b41310cfef9fb54cae4942f2b407fd2d6c698d95963f22faa29845e63:0 2024-04-19 01:49:11.525 [DBG] SWPR: Dispatching sweep success for 97db806b41310cfef9fb54cae4942f2b407fd2d6c698d95963f22faa29845e63:0 to 1 listeners 2024-04-19 01:49:11.526 [DBG] SWPR: Canceling spend ntfn for 97db806b41310cfef9fb54cae4942f2b407fd2d6c698d95963f22faa29845e63:0 2024-04-19 01:49:11.526 [INF] NTFN: Canceling spend notification: spend_id=2, outpoint=97db806b41310cfef9fb54cae4942f2b407fd2d6c698d95963f22faa29845e63:0, script=0 b9fdf0fb331d50394f22189a5aefb3e83099775a7dc519f03d666ac2b43e0d3e 2024-04-19 01:49:11.525 [DBG] BTCN: Re-broadcasting 2 transactions 2024-04-19 01:49:11.526 [DBG] CNCT: ChannelArbitrator(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): marking contract *contractcourt.anchorResolver fully resolved 2024-04-19 01:49:11.527 [DBG] BTCN: Re-broadcast of txid=996298152f4fd7aa8d266be27125b453bd6025da9be6ca168928e58dad368335, now confirmed! 2024-04-19 01:49:11.528 [DBG] BTCN: Re-broadcast of txid=701414c16826639cdf6975704f150246054b16f436a77db5cc013ce59a7718ce, now confirmed! 2024-04-19 01:49:11.536 [DBG] CNCT: ChannelArbitrator(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): marking contract *contractcourt.commitSweepResolver fully resolved 2024-04-19 01:49:11.536 [INF] CNCT: ChannelArbitrator(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): a contract has been fully resolved! 2024-04-19 01:49:11.536 [DBG] CNCT: ChannelArbitrator(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): attempting state step with trigger=chainTrigger from state=StateWaitingFullResolution 2024-04-19 01:49:11.536 [INF] CNCT: ChannelArbitrator(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): still awaiting contract resolution 2024-04-19 01:49:11.536 [DBG] CNCT: ChannelArbitrator(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): still have unresolved contract: *contractcourt.commitSweepResolver 2024-04-19 01:49:11.536 [DBG] CNCT: ChannelArbitrator(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): terminating at state=StateWaitingFullResolution 2024-04-19 01:49:11.547 [INF] CNCT: ChannelArbitrator(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): a contract has been fully resolved! 2024-04-19 01:49:11.547 [DBG] CNCT: ChannelArbitrator(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): attempting state step with trigger=chainTrigger from state=StateWaitingFullResolution 2024-04-19 01:49:11.547 [INF] CNCT: ChannelArbitrator(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): still awaiting contract resolution 2024-04-19 01:49:11.557 [DBG] CNCT: ChannelArbitrator(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0): attempting state step with trigger=chainTrigger from state=StateFullyResolved 2024-04-19 01:49:11.557 [INF] CNCT: ChannelPoint(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0) has been fully resolved on-chain at height=799 2024-04-19 01:49:11.557 [INF] CNCT: Marking ChannelPoint(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0) fully resolved 2024-04-19 01:49:11.557 [DBG] CHBU: Removing channel fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0 from backup state 2024-04-19 01:49:11.558 [INF] CHBU: Updating on-disk multi SCB backup: num_old_chans=0, num_new_chans=0 2024-04-19 01:49:11.558 [INF] CHDB: Pruning link node 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df with zero open channels from database 2024-04-19 01:49:11.558 [DBG] CNCT: Stopping ChannelArbitrator(fb2891a215359f7607f6a7c11b1e335fe0ce55882aa49096dfbf79d1493b22e5:0) 2024-04-19 01:49:11.566 [INF] CHBU: Updating backup file at /tmp/lndtest-node2127775919/data/chain/bitcoin/regtest/channel.backup 2024-04-19 01:49:11.566 [INF] CHBU: Swapping old multi backup file from /tmp/lndtest-node2127775919/data/chain/bitcoin/regtest/temp-dont-use.backup to /tmp/lndtest-node2127775919/data/chain/bitcoin/regtest/channel.backup 2024-04-19 01:49:11.606 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:11.607 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:11.719 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:11.719 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:11.867 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:11.867 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:11.983 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:11.983 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:12.115 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:12.116 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:12.199 [DBG] RPCS: [/lnrpc.Lightning/GetInfo] requested 2024-04-19 01:49:12.253 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:12.253 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:12.381 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:12.381 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:12.485 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:12.485 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:12.612 [DBG] RPCS: [/lnrpc.Lightning/PendingChannels] requested 2024-04-19 01:49:12.622 [DBG] LNWL: Skipping coinbase tx 640b533493e4c7927e2bf707e514b9d93b41ef9f896a25f62db6108174d596a3 2024-04-19 01:49:12.623 [DBG] LNWL: Filtering block 800 (7c9326a4118f70f77ee43f08b13443211f92fc678ece3e08df6439c1048889ac) with 1 transactions 2024-04-19 01:49:12.623 [DBG] LNWL: Filtering block 800 (7c9326a4118f70f77ee43f08b13443211f92fc678ece3e08df6439c1048889ac) with 1 transactions 2024-04-19 01:49:12.623 [INF] CRTR: Pruning channel graph using block 7c9326a4118f70f77ee43f08b13443211f92fc678ece3e08df6439c1048889ac (height=800) 2024-04-19 01:49:12.623 [DBG] CHDB: Pruned graph, cache now has num_node_features=1, num_nodes=0, num_channels=0 2024-04-19 01:49:12.623 [INF] CRTR: Block 7c9326a4118f70f77ee43f08b13443211f92fc678ece3e08df6439c1048889ac (height=800) closed 0 channels 2024-04-19 01:49:12.626 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:12.626 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:12.629 [DBG] NTFN: Filtering 1 txns for 3 spend requests at height 800 2024-04-19 01:49:12.629 [INF] NTFN: New block: height=800, sha=7c9326a4118f70f77ee43f08b13443211f92fc678ece3e08df6439c1048889ac 2024-04-19 01:49:12.629 [INF] UTXN: Attempting to graduate height=800: num_kids=0, num_babies=0 2024-04-19 01:49:12.629 [DBG] DISC: New block: height=800, hash=7c9326a4118f70f77ee43f08b13443211f92fc678ece3e08df6439c1048889ac 2024-04-19 01:49:12.629 [DBG] SWPR: New block: height=800, sha=7c9326a4118f70f77ee43f08b13443211f92fc678ece3e08df6439c1048889ac 2024-04-19 01:49:12.725 [DBG] LNWL: Filtering block 801 (7458da28d79ae47ea1ca19da52a00b6659f5eb86f3f7c6c5b870091db6b94dd7) with 1 transactions 2024-04-19 01:49:12.726 [DBG] LNWL: Filtering block 801 (7458da28d79ae47ea1ca19da52a00b6659f5eb86f3f7c6c5b870091db6b94dd7) with 1 transactions 2024-04-19 01:49:12.726 [INF] CRTR: Pruning channel graph using block 7458da28d79ae47ea1ca19da52a00b6659f5eb86f3f7c6c5b870091db6b94dd7 (height=801) 2024-04-19 01:49:12.726 [DBG] CHDB: Pruned graph, cache now has num_node_features=1, num_nodes=0, num_channels=0 2024-04-19 01:49:12.726 [INF] CRTR: Block 7458da28d79ae47ea1ca19da52a00b6659f5eb86f3f7c6c5b870091db6b94dd7 (height=801) closed 0 channels 2024-04-19 01:49:12.727 [DBG] LNWL: Skipping coinbase tx 21d8b7ecccc0d8bd9b14ba6b0def859ae8c82cf94033661ba62bdd7dace341ce 2024-04-19 01:49:12.733 [DBG] NTFN: Filtering 1 txns for 3 spend requests at height 801 2024-04-19 01:49:12.733 [INF] NTFN: New block: height=801, sha=7458da28d79ae47ea1ca19da52a00b6659f5eb86f3f7c6c5b870091db6b94dd7 2024-04-19 01:49:12.733 [DBG] DISC: New block: height=801, hash=7458da28d79ae47ea1ca19da52a00b6659f5eb86f3f7c6c5b870091db6b94dd7 2024-04-19 01:49:12.733 [DBG] SWPR: New block: height=801, sha=7458da28d79ae47ea1ca19da52a00b6659f5eb86f3f7c6c5b870091db6b94dd7 2024-04-19 01:49:12.733 [INF] UTXN: Attempting to graduate height=801: num_kids=0, num_babies=0 2024-04-19 01:49:12.759 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:12.759 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:12.843 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:12.843 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:12.950 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:12.950 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:13.047 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:13.047 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:13.107 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:13.107 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:13.192 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:13.192 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:13.227 [DBG] RPCS: [/lnrpc.Lightning/GetInfo] requested 2024-04-19 01:49:13.306 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:13.306 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:13.423 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:13.423 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:13.477 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:13.477 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:13.566 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:13.566 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:13.668 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:13.668 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:13.739 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:13.739 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:13.885 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:13.885 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:14.016 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:14.016 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:14.093 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:14.093 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:14.243 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:14.243 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:14.338 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:14.338 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:14.410 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:14.410 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:14.528 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:14.529 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:14.615 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:14.615 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:14.681 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:14.681 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:14.768 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:14.768 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:14.888 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:14.888 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:14.993 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:14.993 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:15.088 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:15.088 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:15.151 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:15.151 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:15.227 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:15.227 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:15.286 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:15.286 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:15.423 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:15.423 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:15.443 [DBG] SWPR: Sweep ticker ticks, attempt sweeping... 2024-04-19 01:49:15.530 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:15.530 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:15.627 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:15.627 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:15.773 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:15.773 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:15.902 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:15.902 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:16.031 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:16.031 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:16.083 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:16.083 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:16.147 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:16.147 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:16.282 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:16.282 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:16.428 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:16.428 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:16.500 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:16.500 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:16.620 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:16.620 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:16.696 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:16.696 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:16.834 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:16.834 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:16.943 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:16.944 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:17.004 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:17.004 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:17.154 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:17.154 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:17.282 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:17.282 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:17.366 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:17.366 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:17.462 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:17.462 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:17.591 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:17.591 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:17.689 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:17.689 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:17.754 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:17.754 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:17.889 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:17.889 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:18.007 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:18.007 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:18.154 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:18.154 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:18.283 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:18.283 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:18.362 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:18.362 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:18.483 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:18.483 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:18.604 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:18.604 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:18.670 [DBG] LNWL: Skipping coinbase tx df7661ba552d97c8573dd03b8edfeaeaf600da6995d7bfe9d042909862ad8f6e 2024-04-19 01:49:18.671 [DBG] LNWL: Filtering block 802 (1da064dad29cb69f56d27ce0dcf2e48b17f09eeb5f418aa4390c23981cde6893) with 2 transactions 2024-04-19 01:49:18.671 [DBG] LNWL: Filtering block 802 (1da064dad29cb69f56d27ce0dcf2e48b17f09eeb5f418aa4390c23981cde6893) with 2 transactions 2024-04-19 01:49:18.671 [INF] CRTR: Pruning channel graph using block 1da064dad29cb69f56d27ce0dcf2e48b17f09eeb5f418aa4390c23981cde6893 (height=802) 2024-04-19 01:49:18.671 [DBG] CHDB: Pruned graph, cache now has num_node_features=1, num_nodes=0, num_channels=0 2024-04-19 01:49:18.672 [INF] CRTR: Block 1da064dad29cb69f56d27ce0dcf2e48b17f09eeb5f418aa4390c23981cde6893 (height=802) closed 0 channels 2024-04-19 01:49:18.682 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:18.682 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:18.683 [DBG] NTFN: Filtering 2 txns for 3 spend requests at height 802 2024-04-19 01:49:18.684 [INF] NTFN: New block: height=802, sha=1da064dad29cb69f56d27ce0dcf2e48b17f09eeb5f418aa4390c23981cde6893 2024-04-19 01:49:18.684 [DBG] SWPR: New block: height=802, sha=1da064dad29cb69f56d27ce0dcf2e48b17f09eeb5f418aa4390c23981cde6893 2024-04-19 01:49:18.684 [INF] UTXN: Attempting to graduate height=802: num_kids=0, num_babies=0 2024-04-19 01:49:18.684 [DBG] DISC: New block: height=802, hash=1da064dad29cb69f56d27ce0dcf2e48b17f09eeb5f418aa4390c23981cde6893 2024-04-19 01:49:18.822 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:18.822 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:18.897 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:18.897 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:19.042 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:19.042 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:19.150 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:19.150 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:19.225 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:19.225 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:19.276 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:19.276 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:19.350 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:19.350 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:19.378 [DBG] RPCS: [/lnrpc.Lightning/GetInfo] requested 2024-04-19 01:49:19.488 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:19.488 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:19.581 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:19.581 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:19.661 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:19.661 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:19.766 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:19.767 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:19.793 [DBG] RPCS: [/lnrpc.Lightning/WalletBalance] requested 2024-04-19 01:49:19.856 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:19.856 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:19.949 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:19.949 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:20.096 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:20.096 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:20.229 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:20.229 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:20.341 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:20.342 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:20.443 [DBG] SWPR: Sweep ticker ticks, attempt sweeping... 2024-04-19 01:49:20.476 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:20.477 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:20.569 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:20.569 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:20.682 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:20.682 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:20.817 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:20.817 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:20.901 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:20.901 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:20.972 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:20.972 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:21.086 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:21.087 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:21.222 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:21.222 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:21.338 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:21.338 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:21.472 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:21.472 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:21.496 [DBG] RPCS: [walletbalance] Total balance=0.94973710 BTC (confirmed=0.94973710 BTC, unconfirmed=0 BTC) 2024-04-19 01:49:21.615 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:21.615 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:21.709 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:21.709 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:21.782 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:21.782 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:21.880 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:21.880 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:21.957 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:21.957 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:22.049 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:22.049 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:22.102 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:22.102 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:22.198 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:22.198 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:22.274 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:22.274 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:22.386 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:22.386 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:22.462 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:22.462 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:22.556 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:22.556 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:22.694 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:22.694 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:22.779 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:22.779 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:22.872 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:22.872 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:23.012 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:23.012 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:23.076 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:23.076 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:23.211 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:23.211 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:23.287 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:23.287 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:23.375 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:23.375 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:23.440 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:23.440 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:23.578 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:23.578 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:23.590 [DBG] RPCS: [/lnrpc.Lightning/ListChannels] requested 2024-04-19 01:49:23.590 [DBG] RPCS: [listchannels] fetched 0 channels from DB 2024-04-19 01:49:23.654 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:23.655 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:23.793 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:23.793 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:23.898 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:23.898 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:24.039 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:24.039 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:24.091 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:24.091 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:24.237 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:24.237 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:24.371 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:24.371 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:24.521 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:24.521 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:24.628 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:24.628 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:24.716 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:24.716 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:24.820 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:24.820 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:24.885 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:24.885 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:24.982 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:24.982 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:25.086 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:25.086 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:25.206 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:25.206 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:25.342 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:25.342 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:25.443 [DBG] SWPR: Sweep ticker ticks, attempt sweeping... 2024-04-19 01:49:25.455 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:25.455 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:25.560 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:25.561 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:25.673 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:25.673 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:25.813 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:25.813 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:25.926 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:25.926 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:25.983 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:25.983 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:26.115 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:26.116 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:26.251 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:26.251 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:26.346 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:26.346 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:26.456 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:26.456 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:26.592 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:26.592 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:26.668 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:26.668 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:26.818 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:26.819 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:26.963 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:26.963 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:27.032 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:27.032 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:27.183 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:27.183 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:27.262 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:27.262 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:27.385 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:27.385 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:27.489 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:27.489 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:27.542 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:27.542 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:27.687 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:27.687 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:27.792 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:27.793 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:27.867 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:27.867 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:27.983 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:27.984 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:28.104 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:28.104 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:28.216 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:28.216 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:28.288 [DBG] RPCS: [/lnrpc.Lightning/StopDaemon] requested 2024-04-19 01:49:28.288 [INF] LTND: Received shutdown request. 2024-04-19 01:49:28.288 [INF] LTND: Shutting down... 2024-04-19 01:49:28.288 [INF] LTND: Gracefully shutting down. 2024-04-19 01:49:28.289 [INF] NANN: Channel Status Manager shutting down... 2024-04-19 01:49:28.289 [DBG] NANN: Channel Status Manager shutdown complete 2024-04-19 01:49:28.289 [INF] HSWC: HTLC Switch shutting down... 2024-04-19 01:49:28.289 [INF] NTFN: Cancelling epoch notification, epoch_id=5 2024-04-19 01:49:28.292 [DBG] HSWC: HTLC Switch shutdown complete 2024-04-19 01:49:28.292 [INF] HSWC: Onion processor shutting down... 2024-04-19 01:49:28.292 [INF] HSWC: Decaying hash log received shutdown request 2024-04-19 01:49:28.292 [INF] NTFN: Cancelling epoch notification, epoch_id=10 2024-04-19 01:49:28.292 [DBG] HSWC: Onion processor shutdown complete 2024-04-19 01:49:28.292 [INF] INVC: InvoiceRegistry shutting down... 2024-04-19 01:49:28.292 [INF] NTFN: Cancelling epoch notification, epoch_id=9 2024-04-19 01:49:28.292 [DBG] INVC: InvoiceRegistry shutdown complete 2024-04-19 01:49:28.292 [INF] CRTR: Channel Router shutting down... 2024-04-19 01:49:28.292 [INF] CRTR: FilteredChainView stopping 2024-04-19 01:49:28.292 [DBG] CRTR: Channel Router shutdown complete 2024-04-19 01:49:28.293 [INF] CNCT: ChainArbitrator shutting down... 2024-04-19 01:49:28.293 [INF] NTFN: Cancelling epoch notification, epoch_id=7 2024-04-19 01:49:28.293 [DBG] CNCT: ChainArbitrator shutdown complete 2024-04-19 01:49:28.293 [INF] FNDG: Funding manager shutting down... 2024-04-19 01:49:28.293 [DBG] FNDG: Funding manager shutdown complete 2024-04-19 01:49:28.293 [INF] BRAR: Breach arbiter shutting down... 2024-04-19 01:49:28.293 [DBG] BRAR: Breach arbiter shutdown complete 2024-04-19 01:49:28.293 [INF] UTXN: UTXO nursery shutting down... 2024-04-19 01:49:28.293 [INF] NTFN: Cancelling epoch notification, epoch_id=4 2024-04-19 01:49:28.293 [DBG] UTXN: UTXO nursery shutdown complete 2024-04-19 01:49:28.293 [INF] DISC: Authenticated gossiper shutting down... 2024-04-19 01:49:28.293 [INF] DISC: Authenticated Gossiper is stopping 2024-04-19 01:49:28.293 [INF] NTFN: Cancelling epoch notification, epoch_id=8 2024-04-19 01:49:28.293 [DBG] DISC: SyncManager is stopping 2024-04-19 01:49:28.293 [DBG] DISC: Stopping GossipSyncer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df) 2024-04-19 01:49:28.293 [DBG] DISC: GossipSyncer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df) stopped 2024-04-19 01:49:28.294 [DBG] DISC: SyncManager stopped 2024-04-19 01:49:28.294 [DBG] DISC: reliableSender is stopping 2024-04-19 01:49:28.294 [DBG] DISC: reliableSender stopped 2024-04-19 01:49:28.294 [INF] DISC: Authenticated Gossiper stopped 2024-04-19 01:49:28.294 [DBG] DISC: Authenticated gossiper shutdown complete 2024-04-19 01:49:28.294 [INF] SWPR: Sweeper shutting down... 2024-04-19 01:49:28.294 [INF] NTFN: Cancelling epoch notification, epoch_id=3 2024-04-19 01:49:28.294 [DBG] SWPR: Sweeper shutdown complete 2024-04-19 01:49:28.294 [INF] CHNF: ChannelNotifier shutting down... 2024-04-19 01:49:28.294 [DBG] CHNF: ChannelNotifier shutdown complete 2024-04-19 01:49:28.294 [INF] PRNF: PeerNotifier shutting down... 2024-04-19 01:49:28.294 [DBG] PRNF: PeerNotifier shutdown complete 2024-04-19 01:49:28.294 [INF] HSWC: HtlcNotifier shutting down... 2024-04-19 01:49:28.294 [DBG] HSWC: HtlcNotifier shutdown complete 2024-04-19 01:49:28.294 [INF] CHBU: chanbackup.SubSwapper shutting down... 2024-04-19 01:49:28.294 [DBG] CHBU: chanbackup.SubSwapper shutdown complete 2024-04-19 01:49:28.294 [INF] NTFN: bitcoind notifier shutting down... 2024-04-19 01:49:28.294 [INF] NTFN: Stopping mempool notifier 2024-04-19 01:49:28.294 [DBG] NTFN: mempool notifier stopped 2024-04-19 01:49:28.294 [DBG] NTFN: bitcoind notifier shutdown complete 2024-04-19 01:49:28.295 [ERR] HSWC: InterceptableSwitch stopped: block epoch stream stopped 2024-04-19 01:49:28.295 [ERR] NTFN: dead epoch stream in BestBlockTracker 2024-04-19 01:49:28.295 [INF] CHFT: ChannelEventStore shutting down... 2024-04-19 01:49:28.295 [DBG] CHFT: recording flap count for: 1 peers 2024-04-19 01:49:28.295 [DBG] CHFT: ChannelEventStore shutdown complete 2024-04-19 01:49:28.295 [DBG] CRTR: Stopping mission control store ticker 2024-04-19 01:49:28.295 [DBG] CRTR: Mission control store ticker stopped 2024-04-19 01:49:28.295 [INF] SRVR: Disconnecting from 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:5823 2024-04-19 01:49:28.295 [INF] PEER: Peer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): disconnecting 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:5823, reason: server: DisconnectPeer called 2024-04-19 01:49:28.295 [INF] PEER: Peer(0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df): unable to read message from peer: read next header: read tcp 127.0.0.1:42026->127.0.0.1:5823: use of closed network connection 2024-04-19 01:49:28.295 [INF] HLCK: Health monitor shutting down 2024-04-19 01:49:28.296 [DBG] HLCK: Health check: monitor quit 2024-04-19 01:49:28.296 [DBG] SRVR: Waiting for server to shutdown... 2024-04-19 01:49:28.336 [DBG] LNWL: Batching GetRawTransaction in 1 batches... 2024-04-19 01:49:28.336 [DBG] LNWL: Finished batch GetRawTransaction 2024-04-19 01:49:28.396 [DBG] SRVR: Peer 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:5823 has been disconnected 2024-04-19 01:49:28.396 [DBG] SRVR: Server quitting, exit early for peer 0267074a8c3bcc3caa237e7593c1345f9c7b2c6729f082323170a4ace3bc8ce7df@127.0.0.1:5823 2024-04-19 01:49:28.396 [DBG] SRVR: Stopping buffer pools... 2024-04-19 01:49:28.396 [INF] RPCS: Stopping RPC Server 2024-04-19 01:49:28.396 [INF] RPCS: Stopping ChainRPC Sub-RPC Server 2024-04-19 01:49:28.396 [INF] RPCS: Stopping InvoicesRPC Sub-RPC Server 2024-04-19 01:49:28.397 [INF] RPCS: Stopping PeersRPC Sub-RPC Server 2024-04-19 01:49:28.397 [INF] RPCS: Stopping SignRPC Sub-RPC Server 2024-04-19 01:49:28.397 [INF] RPCS: Stopping WatchtowerClientRPC Sub-RPC Server 2024-04-19 01:49:28.397 [INF] RPCS: Stopping AutopilotRPC Sub-RPC Server 2024-04-19 01:49:28.397 [INF] RPCS: Stopping DevRPC Sub-RPC Server 2024-04-19 01:49:28.397 [INF] RPCS: Stopping NeutrinoKitRPC Sub-RPC Server 2024-04-19 01:49:28.397 [INF] RPCS: Stopping RouterRPC Sub-RPC Server 2024-04-19 01:49:28.397 [INF] RPCS: Stopping VersionRPC Sub-RPC Server 2024-04-19 01:49:28.397 [INF] RPCS: Stopping WalletKitRPC Sub-RPC Server 2024-04-19 01:49:28.397 [INF] RPCS: Stopping WatchtowerRPC Sub-RPC Server 2024-04-19 01:49:28.398 [INF] LTND: Stopping pprof server... 2024-04-19 01:49:28.398 [INF] LTND: Shutdown complete