]> git.bitcoin.ninja Git - rust-lightning/blob - lightning-background-processor/src/lib.rs
Allowing user-specified error message during force close channel
[rust-lightning] / lightning-background-processor / src / lib.rs
1 //! Utilities that take care of tasks that (1) need to happen periodically to keep Rust-Lightning
2 //! running properly, and (2) either can or should be run in the background. See docs for
3 //! [`BackgroundProcessor`] for more details on the nitty-gritty.
4
5 #![deny(rustdoc::broken_intra_doc_links)]
6 #![deny(rustdoc::private_intra_doc_links)]
7
8 #![deny(missing_docs)]
9 #![cfg_attr(not(feature = "futures"), deny(unsafe_code))]
10
11 #![cfg_attr(docsrs, feature(doc_auto_cfg))]
12
13 #![cfg_attr(all(not(feature = "std"), not(test)), no_std)]
14
15 #[cfg(any(test, feature = "std"))]
16 extern crate core;
17
18 #[cfg(not(feature = "std"))]
19 extern crate alloc;
20
21 #[macro_use] extern crate lightning;
22 extern crate lightning_rapid_gossip_sync;
23
24 use lightning::chain;
25 use lightning::chain::chaininterface::{BroadcasterInterface, FeeEstimator};
26 use lightning::chain::chainmonitor::{ChainMonitor, Persist};
27 use lightning::events::{Event, PathFailure};
28 #[cfg(feature = "std")]
29 use lightning::events::EventHandler;
30 #[cfg(any(feature = "std", feature = "futures"))]
31 use lightning::events::EventsProvider;
32
33 use lightning::ln::channelmanager::AChannelManager;
34 use lightning::ln::msgs::OnionMessageHandler;
35 use lightning::ln::peer_handler::APeerManager;
36 use lightning::routing::gossip::{NetworkGraph, P2PGossipSync};
37 use lightning::routing::utxo::UtxoLookup;
38 use lightning::routing::scoring::{ScoreUpdate, WriteableScore};
39 use lightning::util::logger::Logger;
40 use lightning::util::persist::Persister;
41 #[cfg(feature = "std")]
42 use lightning::util::wakers::Sleeper;
43 use lightning_rapid_gossip_sync::RapidGossipSync;
44
45 use core::ops::Deref;
46 use core::time::Duration;
47
48 #[cfg(feature = "std")]
49 use std::sync::Arc;
50 #[cfg(feature = "std")]
51 use core::sync::atomic::{AtomicBool, Ordering};
52 #[cfg(feature = "std")]
53 use std::thread::{self, JoinHandle};
54 #[cfg(feature = "std")]
55 use std::time::Instant;
56
57 #[cfg(not(feature = "std"))]
58 use alloc::vec::Vec;
59
60 /// `BackgroundProcessor` takes care of tasks that (1) need to happen periodically to keep
61 /// Rust-Lightning running properly, and (2) either can or should be run in the background. Its
62 /// responsibilities are:
63 /// * Processing [`Event`]s with a user-provided [`EventHandler`].
64 /// * Monitoring whether the [`ChannelManager`] needs to be re-persisted to disk, and if so,
65 ///   writing it to disk/backups by invoking the callback given to it at startup.
66 ///   [`ChannelManager`] persistence should be done in the background.
67 /// * Calling [`ChannelManager::timer_tick_occurred`], [`ChainMonitor::rebroadcast_pending_claims`]
68 ///   and [`PeerManager::timer_tick_occurred`] at the appropriate intervals.
69 /// * Calling [`NetworkGraph::remove_stale_channels_and_tracking`] (if a [`GossipSync`] with a
70 ///   [`NetworkGraph`] is provided to [`BackgroundProcessor::start`]).
71 ///
72 /// It will also call [`PeerManager::process_events`] periodically though this shouldn't be relied
73 /// upon as doing so may result in high latency.
74 ///
75 /// # Note
76 ///
77 /// If [`ChannelManager`] persistence fails and the persisted manager becomes out-of-date, then
78 /// there is a risk of channels force-closing on startup when the manager realizes it's outdated.
79 /// However, as long as [`ChannelMonitor`] backups are sound, no funds besides those used for
80 /// unilateral chain closure fees are at risk.
81 ///
82 /// [`ChannelManager`]: lightning::ln::channelmanager::ChannelManager
83 /// [`ChannelManager::timer_tick_occurred`]: lightning::ln::channelmanager::ChannelManager::timer_tick_occurred
84 /// [`ChannelMonitor`]: lightning::chain::channelmonitor::ChannelMonitor
85 /// [`Event`]: lightning::events::Event
86 /// [`PeerManager::timer_tick_occurred`]: lightning::ln::peer_handler::PeerManager::timer_tick_occurred
87 /// [`PeerManager::process_events`]: lightning::ln::peer_handler::PeerManager::process_events
88 #[cfg(feature = "std")]
89 #[must_use = "BackgroundProcessor will immediately stop on drop. It should be stored until shutdown."]
90 pub struct BackgroundProcessor {
91         stop_thread: Arc<AtomicBool>,
92         thread_handle: Option<JoinHandle<Result<(), std::io::Error>>>,
93 }
94
95 #[cfg(not(test))]
96 const FRESHNESS_TIMER: u64 = 60;
97 #[cfg(test)]
98 const FRESHNESS_TIMER: u64 = 1;
99
100 #[cfg(all(not(test), not(debug_assertions)))]
101 const PING_TIMER: u64 = 10;
102 /// Signature operations take a lot longer without compiler optimisations.
103 /// Increasing the ping timer allows for this but slower devices will be disconnected if the
104 /// timeout is reached.
105 #[cfg(all(not(test), debug_assertions))]
106 const PING_TIMER: u64 = 30;
107 #[cfg(test)]
108 const PING_TIMER: u64 = 1;
109
110 #[cfg(not(test))]
111 const ONION_MESSAGE_HANDLER_TIMER: u64 = 10;
112 #[cfg(test)]
113 const ONION_MESSAGE_HANDLER_TIMER: u64 = 1;
114
115 /// Prune the network graph of stale entries hourly.
116 const NETWORK_PRUNE_TIMER: u64 = 60 * 60;
117
118 #[cfg(not(test))]
119 const SCORER_PERSIST_TIMER: u64 = 60 * 5;
120 #[cfg(test)]
121 const SCORER_PERSIST_TIMER: u64 = 1;
122
123 #[cfg(not(test))]
124 const FIRST_NETWORK_PRUNE_TIMER: u64 = 60;
125 #[cfg(test)]
126 const FIRST_NETWORK_PRUNE_TIMER: u64 = 1;
127
128 #[cfg(not(test))]
129 const REBROADCAST_TIMER: u64 = 30;
130 #[cfg(test)]
131 const REBROADCAST_TIMER: u64 = 1;
132
133 #[cfg(feature = "futures")]
134 /// core::cmp::min is not currently const, so we define a trivial (and equivalent) replacement
135 const fn min_u64(a: u64, b: u64) -> u64 { if a < b { a } else { b } }
136 #[cfg(feature = "futures")]
137 const FASTEST_TIMER: u64 = min_u64(min_u64(FRESHNESS_TIMER, PING_TIMER),
138         min_u64(SCORER_PERSIST_TIMER, min_u64(FIRST_NETWORK_PRUNE_TIMER, REBROADCAST_TIMER)));
139
140 /// Either [`P2PGossipSync`] or [`RapidGossipSync`].
141 pub enum GossipSync<
142         P: Deref<Target = P2PGossipSync<G, U, L>>,
143         R: Deref<Target = RapidGossipSync<G, L>>,
144         G: Deref<Target = NetworkGraph<L>>,
145         U: Deref,
146         L: Deref,
147 >
148 where U::Target: UtxoLookup, L::Target: Logger {
149         /// Gossip sync via the lightning peer-to-peer network as defined by BOLT 7.
150         P2P(P),
151         /// Rapid gossip sync from a trusted server.
152         Rapid(R),
153         /// No gossip sync.
154         None,
155 }
156
157 impl<
158         P: Deref<Target = P2PGossipSync<G, U, L>>,
159         R: Deref<Target = RapidGossipSync<G, L>>,
160         G: Deref<Target = NetworkGraph<L>>,
161         U: Deref,
162         L: Deref,
163 > GossipSync<P, R, G, U, L>
164 where U::Target: UtxoLookup, L::Target: Logger {
165         fn network_graph(&self) -> Option<&G> {
166                 match self {
167                         GossipSync::P2P(gossip_sync) => Some(gossip_sync.network_graph()),
168                         GossipSync::Rapid(gossip_sync) => Some(gossip_sync.network_graph()),
169                         GossipSync::None => None,
170                 }
171         }
172
173         fn prunable_network_graph(&self) -> Option<&G> {
174                 match self {
175                         GossipSync::P2P(gossip_sync) => Some(gossip_sync.network_graph()),
176                         GossipSync::Rapid(gossip_sync) => {
177                                 if gossip_sync.is_initial_sync_complete() {
178                                         Some(gossip_sync.network_graph())
179                                 } else {
180                                         None
181                                 }
182                         },
183                         GossipSync::None => None,
184                 }
185         }
186 }
187
188 /// This is not exported to bindings users as the bindings concretize everything and have constructors for us
189 impl<P: Deref<Target = P2PGossipSync<G, U, L>>, G: Deref<Target = NetworkGraph<L>>, U: Deref, L: Deref>
190         GossipSync<P, &RapidGossipSync<G, L>, G, U, L>
191 where
192         U::Target: UtxoLookup,
193         L::Target: Logger,
194 {
195         /// Initializes a new [`GossipSync::P2P`] variant.
196         pub fn p2p(gossip_sync: P) -> Self {
197                 GossipSync::P2P(gossip_sync)
198         }
199 }
200
201 /// This is not exported to bindings users as the bindings concretize everything and have constructors for us
202 impl<'a, R: Deref<Target = RapidGossipSync<G, L>>, G: Deref<Target = NetworkGraph<L>>, L: Deref>
203         GossipSync<
204                 &P2PGossipSync<G, &'a (dyn UtxoLookup + Send + Sync), L>,
205                 R,
206                 G,
207                 &'a (dyn UtxoLookup + Send + Sync),
208                 L,
209         >
210 where
211         L::Target: Logger,
212 {
213         /// Initializes a new [`GossipSync::Rapid`] variant.
214         pub fn rapid(gossip_sync: R) -> Self {
215                 GossipSync::Rapid(gossip_sync)
216         }
217 }
218
219 /// This is not exported to bindings users as the bindings concretize everything and have constructors for us
220 impl<'a, L: Deref>
221         GossipSync<
222                 &P2PGossipSync<&'a NetworkGraph<L>, &'a (dyn UtxoLookup + Send + Sync), L>,
223                 &RapidGossipSync<&'a NetworkGraph<L>, L>,
224                 &'a NetworkGraph<L>,
225                 &'a (dyn UtxoLookup + Send + Sync),
226                 L,
227         >
228 where
229         L::Target: Logger,
230 {
231         /// Initializes a new [`GossipSync::None`] variant.
232         pub fn none() -> Self {
233                 GossipSync::None
234         }
235 }
236
237 fn handle_network_graph_update<L: Deref>(
238         network_graph: &NetworkGraph<L>, event: &Event
239 ) where L::Target: Logger {
240         if let Event::PaymentPathFailed {
241                 failure: PathFailure::OnPath { network_update: Some(ref upd) }, .. } = event
242         {
243                 network_graph.handle_network_update(upd);
244         }
245 }
246
247 /// Updates scorer based on event and returns whether an update occurred so we can decide whether
248 /// to persist.
249 fn update_scorer<'a, S: 'static + Deref<Target = SC> + Send + Sync, SC: 'a + WriteableScore<'a>>(
250         scorer: &'a S, event: &Event, duration_since_epoch: Duration,
251 ) -> bool {
252         match event {
253                 Event::PaymentPathFailed { ref path, short_channel_id: Some(scid), .. } => {
254                         let mut score = scorer.write_lock();
255                         score.payment_path_failed(path, *scid, duration_since_epoch);
256                 },
257                 Event::PaymentPathFailed { ref path, payment_failed_permanently: true, .. } => {
258                         // Reached if the destination explicitly failed it back. We treat this as a successful probe
259                         // because the payment made it all the way to the destination with sufficient liquidity.
260                         let mut score = scorer.write_lock();
261                         score.probe_successful(path, duration_since_epoch);
262                 },
263                 Event::PaymentPathSuccessful { path, .. } => {
264                         let mut score = scorer.write_lock();
265                         score.payment_path_successful(path, duration_since_epoch);
266                 },
267                 Event::ProbeSuccessful { path, .. } => {
268                         let mut score = scorer.write_lock();
269                         score.probe_successful(path, duration_since_epoch);
270                 },
271                 Event::ProbeFailed { path, short_channel_id: Some(scid), .. } => {
272                         let mut score = scorer.write_lock();
273                         score.probe_failed(path, *scid, duration_since_epoch);
274                 },
275                 _ => return false,
276         }
277         true
278 }
279
280 macro_rules! define_run_body {
281         (
282                 $persister: ident, $chain_monitor: ident, $process_chain_monitor_events: expr,
283                 $channel_manager: ident, $process_channel_manager_events: expr,
284                 $peer_manager: ident, $process_onion_message_handler_events: expr, $gossip_sync: ident,
285                 $logger: ident, $scorer: ident, $loop_exit_check: expr, $await: expr, $get_timer: expr,
286                 $timer_elapsed: expr, $check_slow_await: expr, $time_fetch: expr,
287         ) => { {
288                 log_trace!($logger, "Calling ChannelManager's timer_tick_occurred on startup");
289                 $channel_manager.get_cm().timer_tick_occurred();
290                 log_trace!($logger, "Rebroadcasting monitor's pending claims on startup");
291                 $chain_monitor.rebroadcast_pending_claims();
292
293                 let mut last_freshness_call = $get_timer(FRESHNESS_TIMER);
294                 let mut last_onion_message_handler_call = $get_timer(ONION_MESSAGE_HANDLER_TIMER);
295                 let mut last_ping_call = $get_timer(PING_TIMER);
296                 let mut last_prune_call = $get_timer(FIRST_NETWORK_PRUNE_TIMER);
297                 let mut last_scorer_persist_call = $get_timer(SCORER_PERSIST_TIMER);
298                 let mut last_rebroadcast_call = $get_timer(REBROADCAST_TIMER);
299                 let mut have_pruned = false;
300                 let mut have_decayed_scorer = false;
301
302                 loop {
303                         $process_channel_manager_events;
304                         $process_chain_monitor_events;
305                         $process_onion_message_handler_events;
306
307                         // Note that the PeerManager::process_events may block on ChannelManager's locks,
308                         // hence it comes last here. When the ChannelManager finishes whatever it's doing,
309                         // we want to ensure we get into `persist_manager` as quickly as we can, especially
310                         // without running the normal event processing above and handing events to users.
311                         //
312                         // Specifically, on an *extremely* slow machine, we may see ChannelManager start
313                         // processing a message effectively at any point during this loop. In order to
314                         // minimize the time between such processing completing and persisting the updated
315                         // ChannelManager, we want to minimize methods blocking on a ChannelManager
316                         // generally, and as a fallback place such blocking only immediately before
317                         // persistence.
318                         $peer_manager.as_ref().process_events();
319
320                         // Exit the loop if the background processor was requested to stop.
321                         if $loop_exit_check {
322                                 log_trace!($logger, "Terminating background processor.");
323                                 break;
324                         }
325
326                         // We wait up to 100ms, but track how long it takes to detect being put to sleep,
327                         // see `await_start`'s use below.
328                         let mut await_start = None;
329                         if $check_slow_await { await_start = Some($get_timer(1)); }
330                         $await;
331                         let await_slow = if $check_slow_await { $timer_elapsed(&mut await_start.unwrap(), 1) } else { false };
332
333                         // Exit the loop if the background processor was requested to stop.
334                         if $loop_exit_check {
335                                 log_trace!($logger, "Terminating background processor.");
336                                 break;
337                         }
338
339                         if $channel_manager.get_cm().get_and_clear_needs_persistence() {
340                                 log_trace!($logger, "Persisting ChannelManager...");
341                                 $persister.persist_manager(&$channel_manager)?;
342                                 log_trace!($logger, "Done persisting ChannelManager.");
343                         }
344                         if $timer_elapsed(&mut last_freshness_call, FRESHNESS_TIMER) {
345                                 log_trace!($logger, "Calling ChannelManager's timer_tick_occurred");
346                                 $channel_manager.get_cm().timer_tick_occurred();
347                                 last_freshness_call = $get_timer(FRESHNESS_TIMER);
348                         }
349                         if $timer_elapsed(&mut last_onion_message_handler_call, ONION_MESSAGE_HANDLER_TIMER) {
350                                 log_trace!($logger, "Calling OnionMessageHandler's timer_tick_occurred");
351                                 $peer_manager.onion_message_handler().timer_tick_occurred();
352                                 last_onion_message_handler_call = $get_timer(ONION_MESSAGE_HANDLER_TIMER);
353                         }
354                         if await_slow {
355                                 // On various platforms, we may be starved of CPU cycles for several reasons.
356                                 // E.g. on iOS, if we've been in the background, we will be entirely paused.
357                                 // Similarly, if we're on a desktop platform and the device has been asleep, we
358                                 // may not get any cycles.
359                                 // We detect this by checking if our max-100ms-sleep, above, ran longer than a
360                                 // full second, at which point we assume sockets may have been killed (they
361                                 // appear to be at least on some platforms, even if it has only been a second).
362                                 // Note that we have to take care to not get here just because user event
363                                 // processing was slow at the top of the loop. For example, the sample client
364                                 // may call Bitcoin Core RPCs during event handling, which very often takes
365                                 // more than a handful of seconds to complete, and shouldn't disconnect all our
366                                 // peers.
367                                 log_trace!($logger, "100ms sleep took more than a second, disconnecting peers.");
368                                 $peer_manager.as_ref().disconnect_all_peers();
369                                 last_ping_call = $get_timer(PING_TIMER);
370                         } else if $timer_elapsed(&mut last_ping_call, PING_TIMER) {
371                                 log_trace!($logger, "Calling PeerManager's timer_tick_occurred");
372                                 $peer_manager.as_ref().timer_tick_occurred();
373                                 last_ping_call = $get_timer(PING_TIMER);
374                         }
375
376                         // Note that we want to run a graph prune once not long after startup before
377                         // falling back to our usual hourly prunes. This avoids short-lived clients never
378                         // pruning their network graph. We run once 60 seconds after startup before
379                         // continuing our normal cadence. For RGS, since 60 seconds is likely too long,
380                         // we prune after an initial sync completes.
381                         let prune_timer = if have_pruned { NETWORK_PRUNE_TIMER } else { FIRST_NETWORK_PRUNE_TIMER };
382                         let prune_timer_elapsed = $timer_elapsed(&mut last_prune_call, prune_timer);
383                         let should_prune = match $gossip_sync {
384                                 GossipSync::Rapid(_) => !have_pruned || prune_timer_elapsed,
385                                 _ => prune_timer_elapsed,
386                         };
387                         if should_prune {
388                                 // The network graph must not be pruned while rapid sync completion is pending
389                                 if let Some(network_graph) = $gossip_sync.prunable_network_graph() {
390                                         if let Some(duration_since_epoch) = $time_fetch() {
391                                                 log_trace!($logger, "Pruning and persisting network graph.");
392                                                 network_graph.remove_stale_channels_and_tracking_with_time(duration_since_epoch.as_secs());
393                                         } else {
394                                                 log_warn!($logger, "Not pruning network graph, consider enabling `std` or doing so manually with remove_stale_channels_and_tracking_with_time.");
395                                                 log_trace!($logger, "Persisting network graph.");
396                                         }
397
398                                         if let Err(e) = $persister.persist_graph(network_graph) {
399                                                 log_error!($logger, "Error: Failed to persist network graph, check your disk and permissions {}", e)
400                                         }
401
402                                         have_pruned = true;
403                                 }
404                                 let prune_timer = if have_pruned { NETWORK_PRUNE_TIMER } else { FIRST_NETWORK_PRUNE_TIMER };
405                                 last_prune_call = $get_timer(prune_timer);
406                         }
407
408                         if !have_decayed_scorer {
409                                 if let Some(ref scorer) = $scorer {
410                                         if let Some(duration_since_epoch) = $time_fetch() {
411                                                 log_trace!($logger, "Calling time_passed on scorer at startup");
412                                                 scorer.write_lock().time_passed(duration_since_epoch);
413                                         }
414                                 }
415                                 have_decayed_scorer = true;
416                         }
417
418                         if $timer_elapsed(&mut last_scorer_persist_call, SCORER_PERSIST_TIMER) {
419                                 if let Some(ref scorer) = $scorer {
420                                         if let Some(duration_since_epoch) = $time_fetch() {
421                                                 log_trace!($logger, "Calling time_passed and persisting scorer");
422                                                 scorer.write_lock().time_passed(duration_since_epoch);
423                                         } else {
424                                                 log_trace!($logger, "Persisting scorer");
425                                         }
426                                         if let Err(e) = $persister.persist_scorer(&scorer) {
427                                                 log_error!($logger, "Error: Failed to persist scorer, check your disk and permissions {}", e)
428                                         }
429                                 }
430                                 last_scorer_persist_call = $get_timer(SCORER_PERSIST_TIMER);
431                         }
432
433                         if $timer_elapsed(&mut last_rebroadcast_call, REBROADCAST_TIMER) {
434                                 log_trace!($logger, "Rebroadcasting monitor's pending claims");
435                                 $chain_monitor.rebroadcast_pending_claims();
436                                 last_rebroadcast_call = $get_timer(REBROADCAST_TIMER);
437                         }
438                 }
439
440                 // After we exit, ensure we persist the ChannelManager one final time - this avoids
441                 // some races where users quit while channel updates were in-flight, with
442                 // ChannelMonitor update(s) persisted without a corresponding ChannelManager update.
443                 $persister.persist_manager(&$channel_manager)?;
444
445                 // Persist Scorer on exit
446                 if let Some(ref scorer) = $scorer {
447                         $persister.persist_scorer(&scorer)?;
448                 }
449
450                 // Persist NetworkGraph on exit
451                 if let Some(network_graph) = $gossip_sync.network_graph() {
452                         $persister.persist_graph(network_graph)?;
453                 }
454
455                 Ok(())
456         } }
457 }
458
459 #[cfg(feature = "futures")]
460 pub(crate) mod futures_util {
461         use core::future::Future;
462         use core::task::{Poll, Waker, RawWaker, RawWakerVTable};
463         use core::pin::Pin;
464         use core::marker::Unpin;
465         pub(crate) struct Selector<
466                 A: Future<Output=()> + Unpin, B: Future<Output=()> + Unpin, C: Future<Output=bool> + Unpin
467         > {
468                 pub a: A,
469                 pub b: B,
470                 pub c: C,
471         }
472         pub(crate) enum SelectorOutput {
473                 A, B, C(bool),
474         }
475
476         impl<
477                 A: Future<Output=()> + Unpin, B: Future<Output=()> + Unpin, C: Future<Output=bool> + Unpin
478         > Future for Selector<A, B, C> {
479                 type Output = SelectorOutput;
480                 fn poll(mut self: Pin<&mut Self>, ctx: &mut core::task::Context<'_>) -> Poll<SelectorOutput> {
481                         match Pin::new(&mut self.a).poll(ctx) {
482                                 Poll::Ready(()) => { return Poll::Ready(SelectorOutput::A); },
483                                 Poll::Pending => {},
484                         }
485                         match Pin::new(&mut self.b).poll(ctx) {
486                                 Poll::Ready(()) => { return Poll::Ready(SelectorOutput::B); },
487                                 Poll::Pending => {},
488                         }
489                         match Pin::new(&mut self.c).poll(ctx) {
490                                 Poll::Ready(res) => { return Poll::Ready(SelectorOutput::C(res)); },
491                                 Poll::Pending => {},
492                         }
493                         Poll::Pending
494                 }
495         }
496
497         // If we want to poll a future without an async context to figure out if it has completed or
498         // not without awaiting, we need a Waker, which needs a vtable...we fill it with dummy values
499         // but sadly there's a good bit of boilerplate here.
500         fn dummy_waker_clone(_: *const ()) -> RawWaker { RawWaker::new(core::ptr::null(), &DUMMY_WAKER_VTABLE) }
501         fn dummy_waker_action(_: *const ()) { }
502
503         const DUMMY_WAKER_VTABLE: RawWakerVTable = RawWakerVTable::new(
504                 dummy_waker_clone, dummy_waker_action, dummy_waker_action, dummy_waker_action);
505         pub(crate) fn dummy_waker() -> Waker { unsafe { Waker::from_raw(RawWaker::new(core::ptr::null(), &DUMMY_WAKER_VTABLE)) } }
506 }
507 #[cfg(feature = "futures")]
508 use futures_util::{Selector, SelectorOutput, dummy_waker};
509 #[cfg(feature = "futures")]
510 use core::task;
511
512 /// Processes background events in a future.
513 ///
514 /// `sleeper` should return a future which completes in the given amount of time and returns a
515 /// boolean indicating whether the background processing should exit. Once `sleeper` returns a
516 /// future which outputs `true`, the loop will exit and this function's future will complete.
517 /// The `sleeper` future is free to return early after it has triggered the exit condition.
518 ///
519 /// See [`BackgroundProcessor::start`] for information on which actions this handles.
520 ///
521 /// Requires the `futures` feature. Note that while this method is available without the `std`
522 /// feature, doing so will skip calling [`NetworkGraph::remove_stale_channels_and_tracking`],
523 /// you should call [`NetworkGraph::remove_stale_channels_and_tracking_with_time`] regularly
524 /// manually instead.
525 ///
526 /// The `mobile_interruptable_platform` flag should be set if we're currently running on a
527 /// mobile device, where we may need to check for interruption of the application regularly. If you
528 /// are unsure, you should set the flag, as the performance impact of it is minimal unless there
529 /// are hundreds or thousands of simultaneous process calls running.
530 ///
531 /// The `fetch_time` parameter should return the current wall clock time, if one is available. If
532 /// no time is available, some features may be disabled, however the node will still operate fine.
533 ///
534 /// For example, in order to process background events in a [Tokio](https://tokio.rs/) task, you
535 /// could setup `process_events_async` like this:
536 /// ```
537 /// # use lightning::io;
538 /// # use std::sync::{Arc, RwLock};
539 /// # use std::sync::atomic::{AtomicBool, Ordering};
540 /// # use std::time::SystemTime;
541 /// # use lightning_background_processor::{process_events_async, GossipSync};
542 /// # struct Logger {}
543 /// # impl lightning::util::logger::Logger for Logger {
544 /// #     fn log(&self, _record: lightning::util::logger::Record) {}
545 /// # }
546 /// # struct Store {}
547 /// # impl lightning::util::persist::KVStore for Store {
548 /// #     fn read(&self, primary_namespace: &str, secondary_namespace: &str, key: &str) -> io::Result<Vec<u8>> { Ok(Vec::new()) }
549 /// #     fn write(&self, primary_namespace: &str, secondary_namespace: &str, key: &str, buf: &[u8]) -> io::Result<()> { Ok(()) }
550 /// #     fn remove(&self, primary_namespace: &str, secondary_namespace: &str, key: &str, lazy: bool) -> io::Result<()> { Ok(()) }
551 /// #     fn list(&self, primary_namespace: &str, secondary_namespace: &str) -> io::Result<Vec<String>> { Ok(Vec::new()) }
552 /// # }
553 /// # struct EventHandler {}
554 /// # impl EventHandler {
555 /// #     async fn handle_event(&self, _: lightning::events::Event) {}
556 /// # }
557 /// # #[derive(Eq, PartialEq, Clone, Hash)]
558 /// # struct SocketDescriptor {}
559 /// # impl lightning::ln::peer_handler::SocketDescriptor for SocketDescriptor {
560 /// #     fn send_data(&mut self, _data: &[u8], _resume_read: bool) -> usize { 0 }
561 /// #     fn disconnect_socket(&mut self) {}
562 /// # }
563 /// # type ChainMonitor<B, F, FE> = lightning::chain::chainmonitor::ChainMonitor<lightning::sign::InMemorySigner, Arc<F>, Arc<B>, Arc<FE>, Arc<Logger>, Arc<Store>>;
564 /// # type NetworkGraph = lightning::routing::gossip::NetworkGraph<Arc<Logger>>;
565 /// # type P2PGossipSync<UL> = lightning::routing::gossip::P2PGossipSync<Arc<NetworkGraph>, Arc<UL>, Arc<Logger>>;
566 /// # type ChannelManager<B, F, FE> = lightning::ln::channelmanager::SimpleArcChannelManager<ChainMonitor<B, F, FE>, B, FE, Logger>;
567 /// # type Scorer = RwLock<lightning::routing::scoring::ProbabilisticScorer<Arc<NetworkGraph>, Arc<Logger>>>;
568 /// # type PeerManager<B, F, FE, UL> = lightning::ln::peer_handler::SimpleArcPeerManager<SocketDescriptor, ChainMonitor<B, F, FE>, B, FE, Arc<UL>, Logger>;
569 /// #
570 /// # struct Node<
571 /// #     B: lightning::chain::chaininterface::BroadcasterInterface + Send + Sync + 'static,
572 /// #     F: lightning::chain::Filter + Send + Sync + 'static,
573 /// #     FE: lightning::chain::chaininterface::FeeEstimator + Send + Sync + 'static,
574 /// #     UL: lightning::routing::utxo::UtxoLookup + Send + Sync + 'static,
575 /// # > {
576 /// #     peer_manager: Arc<PeerManager<B, F, FE, UL>>,
577 /// #     event_handler: Arc<EventHandler>,
578 /// #     channel_manager: Arc<ChannelManager<B, F, FE>>,
579 /// #     chain_monitor: Arc<ChainMonitor<B, F, FE>>,
580 /// #     gossip_sync: Arc<P2PGossipSync<UL>>,
581 /// #     persister: Arc<Store>,
582 /// #     logger: Arc<Logger>,
583 /// #     scorer: Arc<Scorer>,
584 /// # }
585 /// #
586 /// # async fn setup_background_processing<
587 /// #     B: lightning::chain::chaininterface::BroadcasterInterface + Send + Sync + 'static,
588 /// #     F: lightning::chain::Filter + Send + Sync + 'static,
589 /// #     FE: lightning::chain::chaininterface::FeeEstimator + Send + Sync + 'static,
590 /// #     UL: lightning::routing::utxo::UtxoLookup + Send + Sync + 'static,
591 /// # >(node: Node<B, F, FE, UL>) {
592 ///     let background_persister = Arc::clone(&node.persister);
593 ///     let background_event_handler = Arc::clone(&node.event_handler);
594 ///     let background_chain_mon = Arc::clone(&node.chain_monitor);
595 ///     let background_chan_man = Arc::clone(&node.channel_manager);
596 ///     let background_gossip_sync = GossipSync::p2p(Arc::clone(&node.gossip_sync));
597 ///     let background_peer_man = Arc::clone(&node.peer_manager);
598 ///     let background_logger = Arc::clone(&node.logger);
599 ///     let background_scorer = Arc::clone(&node.scorer);
600 ///
601 ///     // Setup the sleeper.
602 ///     let (stop_sender, stop_receiver) = tokio::sync::watch::channel(());
603 ///
604 ///     let sleeper = move |d| {
605 ///             let mut receiver = stop_receiver.clone();
606 ///             Box::pin(async move {
607 ///                     tokio::select!{
608 ///                             _ = tokio::time::sleep(d) => false,
609 ///                             _ = receiver.changed() => true,
610 ///                     }
611 ///             })
612 ///     };
613 ///
614 ///     let mobile_interruptable_platform = false;
615 ///
616 ///     let handle = tokio::spawn(async move {
617 ///             process_events_async(
618 ///                     background_persister,
619 ///                     |e| background_event_handler.handle_event(e),
620 ///                     background_chain_mon,
621 ///                     background_chan_man,
622 ///                     background_gossip_sync,
623 ///                     background_peer_man,
624 ///                     background_logger,
625 ///                     Some(background_scorer),
626 ///                     sleeper,
627 ///                     mobile_interruptable_platform,
628 ///                     || Some(SystemTime::now().duration_since(SystemTime::UNIX_EPOCH).unwrap())
629 ///             )
630 ///             .await
631 ///             .expect("Failed to process events");
632 ///     });
633 ///
634 ///     // Stop the background processing.
635 ///     stop_sender.send(()).unwrap();
636 ///     handle.await.unwrap();
637 ///     # }
638 ///```
639 #[cfg(feature = "futures")]
640 pub async fn process_events_async<
641         'a,
642         UL: 'static + Deref + Send + Sync,
643         CF: 'static + Deref + Send + Sync,
644         T: 'static + Deref + Send + Sync,
645         F: 'static + Deref + Send + Sync,
646         G: 'static + Deref<Target = NetworkGraph<L>> + Send + Sync,
647         L: 'static + Deref + Send + Sync,
648         P: 'static + Deref + Send + Sync,
649         EventHandlerFuture: core::future::Future<Output = ()>,
650         EventHandler: Fn(Event) -> EventHandlerFuture,
651         PS: 'static + Deref + Send,
652         M: 'static + Deref<Target = ChainMonitor<<CM::Target as AChannelManager>::Signer, CF, T, F, L, P>> + Send + Sync,
653         CM: 'static + Deref + Send + Sync,
654         PGS: 'static + Deref<Target = P2PGossipSync<G, UL, L>> + Send + Sync,
655         RGS: 'static + Deref<Target = RapidGossipSync<G, L>> + Send,
656         PM: 'static + Deref + Send + Sync,
657         S: 'static + Deref<Target = SC> + Send + Sync,
658         SC: for<'b> WriteableScore<'b>,
659         SleepFuture: core::future::Future<Output = bool> + core::marker::Unpin,
660         Sleeper: Fn(Duration) -> SleepFuture,
661         FetchTime: Fn() -> Option<Duration>,
662 >(
663         persister: PS, event_handler: EventHandler, chain_monitor: M, channel_manager: CM,
664         gossip_sync: GossipSync<PGS, RGS, G, UL, L>, peer_manager: PM, logger: L, scorer: Option<S>,
665         sleeper: Sleeper, mobile_interruptable_platform: bool, fetch_time: FetchTime,
666 ) -> Result<(), lightning::io::Error>
667 where
668         UL::Target: 'static + UtxoLookup,
669         CF::Target: 'static + chain::Filter,
670         T::Target: 'static + BroadcasterInterface,
671         F::Target: 'static + FeeEstimator,
672         L::Target: 'static + Logger,
673         P::Target: 'static + Persist<<CM::Target as AChannelManager>::Signer>,
674         PS::Target: 'static + Persister<'a, CM, L, SC>,
675         CM::Target: AChannelManager + Send + Sync,
676         PM::Target: APeerManager + Send + Sync,
677 {
678         let mut should_break = false;
679         let async_event_handler = |event| {
680                 let network_graph = gossip_sync.network_graph();
681                 let event_handler = &event_handler;
682                 let scorer = &scorer;
683                 let logger = &logger;
684                 let persister = &persister;
685                 let fetch_time = &fetch_time;
686                 async move {
687                         if let Some(network_graph) = network_graph {
688                                 handle_network_graph_update(network_graph, &event)
689                         }
690                         if let Some(ref scorer) = scorer {
691                                 if let Some(duration_since_epoch) = fetch_time() {
692                                         if update_scorer(scorer, &event, duration_since_epoch) {
693                                                 log_trace!(logger, "Persisting scorer after update");
694                                                 if let Err(e) = persister.persist_scorer(&scorer) {
695                                                         log_error!(logger, "Error: Failed to persist scorer, check your disk and permissions {}", e)
696                                                 }
697                                         }
698                                 }
699                         }
700                         event_handler(event).await;
701                 }
702         };
703         define_run_body!(
704                 persister, chain_monitor,
705                 chain_monitor.process_pending_events_async(async_event_handler).await,
706                 channel_manager, channel_manager.get_cm().process_pending_events_async(async_event_handler).await,
707                 peer_manager, process_onion_message_handler_events_async(&peer_manager, async_event_handler).await,
708                 gossip_sync, logger, scorer, should_break, {
709                         let fut = Selector {
710                                 a: channel_manager.get_cm().get_event_or_persistence_needed_future(),
711                                 b: chain_monitor.get_update_future(),
712                                 c: sleeper(if mobile_interruptable_platform { Duration::from_millis(100) } else { Duration::from_secs(FASTEST_TIMER) }),
713                         };
714                         match fut.await {
715                                 SelectorOutput::A|SelectorOutput::B => {},
716                                 SelectorOutput::C(exit) => {
717                                         should_break = exit;
718                                 }
719                         }
720                 }, |t| sleeper(Duration::from_secs(t)),
721                 |fut: &mut SleepFuture, _| {
722                         let mut waker = dummy_waker();
723                         let mut ctx = task::Context::from_waker(&mut waker);
724                         match core::pin::Pin::new(fut).poll(&mut ctx) {
725                                 task::Poll::Ready(exit) => { should_break = exit; true },
726                                 task::Poll::Pending => false,
727                         }
728                 }, mobile_interruptable_platform, fetch_time,
729         )
730 }
731
732 #[cfg(feature = "futures")]
733 async fn process_onion_message_handler_events_async<
734         EventHandlerFuture: core::future::Future<Output = ()>,
735         EventHandler: Fn(Event) -> EventHandlerFuture,
736         PM: 'static + Deref + Send + Sync,
737 >(
738         peer_manager: &PM, handler: EventHandler
739 )
740 where
741         PM::Target: APeerManager + Send + Sync,
742 {
743         let events = core::cell::RefCell::new(Vec::new());
744         peer_manager.onion_message_handler().process_pending_events(&|e| events.borrow_mut().push(e));
745
746         for event in events.into_inner() {
747                 handler(event).await
748         }
749 }
750
751 #[cfg(feature = "std")]
752 impl BackgroundProcessor {
753         /// Start a background thread that takes care of responsibilities enumerated in the [top-level
754         /// documentation].
755         ///
756         /// The thread runs indefinitely unless the object is dropped, [`stop`] is called, or
757         /// [`Persister::persist_manager`] returns an error. In case of an error, the error is retrieved by calling
758         /// either [`join`] or [`stop`].
759         ///
760         /// # Data Persistence
761         ///
762         /// [`Persister::persist_manager`] is responsible for writing out the [`ChannelManager`] to disk, and/or
763         /// uploading to one or more backup services. See [`ChannelManager::write`] for writing out a
764         /// [`ChannelManager`]. See the `lightning-persister` crate for LDK's
765         /// provided implementation.
766         ///
767         /// [`Persister::persist_graph`] is responsible for writing out the [`NetworkGraph`] to disk, if
768         /// [`GossipSync`] is supplied. See [`NetworkGraph::write`] for writing out a [`NetworkGraph`].
769         /// See the `lightning-persister` crate for LDK's provided implementation.
770         ///
771         /// Typically, users should either implement [`Persister::persist_manager`] to never return an
772         /// error or call [`join`] and handle any error that may arise. For the latter case,
773         /// `BackgroundProcessor` must be restarted by calling `start` again after handling the error.
774         ///
775         /// # Event Handling
776         ///
777         /// `event_handler` is responsible for handling events that users should be notified of (e.g.,
778         /// payment failed). [`BackgroundProcessor`] may decorate the given [`EventHandler`] with common
779         /// functionality implemented by other handlers.
780         /// * [`P2PGossipSync`] if given will update the [`NetworkGraph`] based on payment failures.
781         ///
782         /// # Rapid Gossip Sync
783         ///
784         /// If rapid gossip sync is meant to run at startup, pass [`RapidGossipSync`] via `gossip_sync`
785         /// to indicate that the [`BackgroundProcessor`] should not prune the [`NetworkGraph`] instance
786         /// until the [`RapidGossipSync`] instance completes its first sync.
787         ///
788         /// [top-level documentation]: BackgroundProcessor
789         /// [`join`]: Self::join
790         /// [`stop`]: Self::stop
791         /// [`ChannelManager`]: lightning::ln::channelmanager::ChannelManager
792         /// [`ChannelManager::write`]: lightning::ln::channelmanager::ChannelManager#impl-Writeable
793         /// [`Persister::persist_manager`]: lightning::util::persist::Persister::persist_manager
794         /// [`Persister::persist_graph`]: lightning::util::persist::Persister::persist_graph
795         /// [`NetworkGraph`]: lightning::routing::gossip::NetworkGraph
796         /// [`NetworkGraph::write`]: lightning::routing::gossip::NetworkGraph#impl-Writeable
797         pub fn start<
798                 'a,
799                 UL: 'static + Deref + Send + Sync,
800                 CF: 'static + Deref + Send + Sync,
801                 T: 'static + Deref + Send + Sync,
802                 F: 'static + Deref + Send + Sync,
803                 G: 'static + Deref<Target = NetworkGraph<L>> + Send + Sync,
804                 L: 'static + Deref + Send + Sync,
805                 P: 'static + Deref + Send + Sync,
806                 EH: 'static + EventHandler + Send,
807                 PS: 'static + Deref + Send,
808                 M: 'static + Deref<Target = ChainMonitor<<CM::Target as AChannelManager>::Signer, CF, T, F, L, P>> + Send + Sync,
809                 CM: 'static + Deref + Send + Sync,
810                 PGS: 'static + Deref<Target = P2PGossipSync<G, UL, L>> + Send + Sync,
811                 RGS: 'static + Deref<Target = RapidGossipSync<G, L>> + Send,
812                 PM: 'static + Deref + Send + Sync,
813                 S: 'static + Deref<Target = SC> + Send + Sync,
814                 SC: for <'b> WriteableScore<'b>,
815         >(
816                 persister: PS, event_handler: EH, chain_monitor: M, channel_manager: CM,
817                 gossip_sync: GossipSync<PGS, RGS, G, UL, L>, peer_manager: PM, logger: L, scorer: Option<S>,
818         ) -> Self
819         where
820                 UL::Target: 'static + UtxoLookup,
821                 CF::Target: 'static + chain::Filter,
822                 T::Target: 'static + BroadcasterInterface,
823                 F::Target: 'static + FeeEstimator,
824                 L::Target: 'static + Logger,
825                 P::Target: 'static + Persist<<CM::Target as AChannelManager>::Signer>,
826                 PS::Target: 'static + Persister<'a, CM, L, SC>,
827                 CM::Target: AChannelManager + Send + Sync,
828                 PM::Target: APeerManager + Send + Sync,
829         {
830                 let stop_thread = Arc::new(AtomicBool::new(false));
831                 let stop_thread_clone = stop_thread.clone();
832                 let handle = thread::spawn(move || -> Result<(), std::io::Error> {
833                         let event_handler = |event| {
834                                 let network_graph = gossip_sync.network_graph();
835                                 if let Some(network_graph) = network_graph {
836                                         handle_network_graph_update(network_graph, &event)
837                                 }
838                                 if let Some(ref scorer) = scorer {
839                                         use std::time::SystemTime;
840                                         let duration_since_epoch = SystemTime::now().duration_since(SystemTime::UNIX_EPOCH)
841                                                 .expect("Time should be sometime after 1970");
842                                         if update_scorer(scorer, &event, duration_since_epoch) {
843                                                 log_trace!(logger, "Persisting scorer after update");
844                                                 if let Err(e) = persister.persist_scorer(&scorer) {
845                                                         log_error!(logger, "Error: Failed to persist scorer, check your disk and permissions {}", e)
846                                                 }
847                                         }
848                                 }
849                                 event_handler.handle_event(event);
850                         };
851                         define_run_body!(
852                                 persister, chain_monitor, chain_monitor.process_pending_events(&event_handler),
853                                 channel_manager, channel_manager.get_cm().process_pending_events(&event_handler),
854                                 peer_manager,
855                                 peer_manager.onion_message_handler().process_pending_events(&event_handler),
856                                 gossip_sync, logger, scorer, stop_thread.load(Ordering::Acquire),
857                                 { Sleeper::from_two_futures(
858                                         &channel_manager.get_cm().get_event_or_persistence_needed_future(),
859                                         &chain_monitor.get_update_future()
860                                 ).wait_timeout(Duration::from_millis(100)); },
861                                 |_| Instant::now(), |time: &Instant, dur| time.elapsed().as_secs() > dur, false,
862                                 || {
863                                         use std::time::SystemTime;
864                                         Some(SystemTime::now().duration_since(SystemTime::UNIX_EPOCH)
865                                                 .expect("Time should be sometime after 1970"))
866                                 },
867                         )
868                 });
869                 Self { stop_thread: stop_thread_clone, thread_handle: Some(handle) }
870         }
871
872         /// Join `BackgroundProcessor`'s thread, returning any error that occurred while persisting
873         /// [`ChannelManager`].
874         ///
875         /// # Panics
876         ///
877         /// This function panics if the background thread has panicked such as while persisting or
878         /// handling events.
879         ///
880         /// [`ChannelManager`]: lightning::ln::channelmanager::ChannelManager
881         pub fn join(mut self) -> Result<(), std::io::Error> {
882                 assert!(self.thread_handle.is_some());
883                 self.join_thread()
884         }
885
886         /// Stop `BackgroundProcessor`'s thread, returning any error that occurred while persisting
887         /// [`ChannelManager`].
888         ///
889         /// # Panics
890         ///
891         /// This function panics if the background thread has panicked such as while persisting or
892         /// handling events.
893         ///
894         /// [`ChannelManager`]: lightning::ln::channelmanager::ChannelManager
895         pub fn stop(mut self) -> Result<(), std::io::Error> {
896                 assert!(self.thread_handle.is_some());
897                 self.stop_and_join_thread()
898         }
899
900         fn stop_and_join_thread(&mut self) -> Result<(), std::io::Error> {
901                 self.stop_thread.store(true, Ordering::Release);
902                 self.join_thread()
903         }
904
905         fn join_thread(&mut self) -> Result<(), std::io::Error> {
906                 match self.thread_handle.take() {
907                         Some(handle) => handle.join().unwrap(),
908                         None => Ok(()),
909                 }
910         }
911 }
912
913 #[cfg(feature = "std")]
914 impl Drop for BackgroundProcessor {
915         fn drop(&mut self) {
916                 self.stop_and_join_thread().unwrap();
917         }
918 }
919
920 #[cfg(all(feature = "std", test))]
921 mod tests {
922         use bitcoin::blockdata::constants::{genesis_block, ChainHash};
923         use bitcoin::blockdata::locktime::absolute::LockTime;
924         use bitcoin::blockdata::transaction::{Transaction, TxOut};
925         use bitcoin::network::constants::Network;
926         use bitcoin::secp256k1::{SecretKey, PublicKey, Secp256k1};
927         use lightning::chain::{BestBlock, Confirm, chainmonitor};
928         use lightning::chain::channelmonitor::ANTI_REORG_DELAY;
929         use lightning::sign::{InMemorySigner, KeysManager};
930         use lightning::chain::transaction::OutPoint;
931         use lightning::events::{Event, PathFailure, MessageSendEventsProvider, MessageSendEvent};
932         use lightning::{get_event_msg, get_event};
933         use lightning::ln::{PaymentHash, ChannelId};
934         use lightning::ln::channelmanager;
935         use lightning::ln::channelmanager::{BREAKDOWN_TIMEOUT, ChainParameters, MIN_CLTV_EXPIRY_DELTA, PaymentId};
936         use lightning::ln::features::{ChannelFeatures, NodeFeatures};
937         use lightning::ln::functional_test_utils::*;
938         use lightning::ln::msgs::{ChannelMessageHandler, Init};
939         use lightning::ln::peer_handler::{PeerManager, MessageHandler, SocketDescriptor, IgnoringMessageHandler};
940         use lightning::routing::gossip::{NetworkGraph, P2PGossipSync};
941         use lightning::routing::scoring::{ChannelUsage, ScoreUpdate, ScoreLookUp, LockableScore};
942         use lightning::routing::router::{DefaultRouter, Path, RouteHop, CandidateRouteHop};
943         use lightning::util::config::UserConfig;
944         use lightning::util::ser::Writeable;
945         use lightning::util::test_utils;
946         use lightning::util::persist::{KVStore,
947                 CHANNEL_MANAGER_PERSISTENCE_PRIMARY_NAMESPACE, CHANNEL_MANAGER_PERSISTENCE_SECONDARY_NAMESPACE, CHANNEL_MANAGER_PERSISTENCE_KEY,
948                 NETWORK_GRAPH_PERSISTENCE_PRIMARY_NAMESPACE, NETWORK_GRAPH_PERSISTENCE_SECONDARY_NAMESPACE, NETWORK_GRAPH_PERSISTENCE_KEY,
949                 SCORER_PERSISTENCE_PRIMARY_NAMESPACE, SCORER_PERSISTENCE_SECONDARY_NAMESPACE, SCORER_PERSISTENCE_KEY};
950         use lightning_persister::fs_store::FilesystemStore;
951         use std::collections::VecDeque;
952         use std::{fs, env};
953         use std::path::PathBuf;
954         use std::sync::{Arc, Mutex};
955         use std::sync::mpsc::SyncSender;
956         use std::time::Duration;
957         use lightning_rapid_gossip_sync::RapidGossipSync;
958         use super::{BackgroundProcessor, GossipSync, FRESHNESS_TIMER};
959
960         const EVENT_DEADLINE: u64 = 5 * FRESHNESS_TIMER;
961
962         #[derive(Clone, Hash, PartialEq, Eq)]
963         struct TestDescriptor{}
964         impl SocketDescriptor for TestDescriptor {
965                 fn send_data(&mut self, _data: &[u8], _resume_read: bool) -> usize {
966                         0
967                 }
968
969                 fn disconnect_socket(&mut self) {}
970         }
971
972         #[cfg(c_bindings)]
973         type LockingWrapper<T> = lightning::routing::scoring::MultiThreadedLockableScore<T>;
974         #[cfg(not(c_bindings))]
975         type LockingWrapper<T> = Mutex<T>;
976
977         type ChannelManager =
978                 channelmanager::ChannelManager<
979                         Arc<ChainMonitor>,
980                         Arc<test_utils::TestBroadcaster>,
981                         Arc<KeysManager>,
982                         Arc<KeysManager>,
983                         Arc<KeysManager>,
984                         Arc<test_utils::TestFeeEstimator>,
985                         Arc<DefaultRouter<
986                                 Arc<NetworkGraph<Arc<test_utils::TestLogger>>>,
987                                 Arc<test_utils::TestLogger>,
988                                 Arc<KeysManager>,
989                                 Arc<LockingWrapper<TestScorer>>,
990                                 (),
991                                 TestScorer>
992                         >,
993                         Arc<test_utils::TestLogger>>;
994
995         type ChainMonitor = chainmonitor::ChainMonitor<InMemorySigner, Arc<test_utils::TestChainSource>, Arc<test_utils::TestBroadcaster>, Arc<test_utils::TestFeeEstimator>, Arc<test_utils::TestLogger>, Arc<FilesystemStore>>;
996
997         type PGS = Arc<P2PGossipSync<Arc<NetworkGraph<Arc<test_utils::TestLogger>>>, Arc<test_utils::TestChainSource>, Arc<test_utils::TestLogger>>>;
998         type RGS = Arc<RapidGossipSync<Arc<NetworkGraph<Arc<test_utils::TestLogger>>>, Arc<test_utils::TestLogger>>>;
999
1000         struct Node {
1001                 node: Arc<ChannelManager>,
1002                 p2p_gossip_sync: PGS,
1003                 rapid_gossip_sync: RGS,
1004                 peer_manager: Arc<PeerManager<TestDescriptor, Arc<test_utils::TestChannelMessageHandler>, Arc<test_utils::TestRoutingMessageHandler>, IgnoringMessageHandler, Arc<test_utils::TestLogger>, IgnoringMessageHandler, Arc<KeysManager>>>,
1005                 chain_monitor: Arc<ChainMonitor>,
1006                 kv_store: Arc<FilesystemStore>,
1007                 tx_broadcaster: Arc<test_utils::TestBroadcaster>,
1008                 network_graph: Arc<NetworkGraph<Arc<test_utils::TestLogger>>>,
1009                 logger: Arc<test_utils::TestLogger>,
1010                 best_block: BestBlock,
1011                 scorer: Arc<LockingWrapper<TestScorer>>,
1012         }
1013
1014         impl Node {
1015                 fn p2p_gossip_sync(&self) -> GossipSync<PGS, RGS, Arc<NetworkGraph<Arc<test_utils::TestLogger>>>, Arc<test_utils::TestChainSource>, Arc<test_utils::TestLogger>> {
1016                         GossipSync::P2P(self.p2p_gossip_sync.clone())
1017                 }
1018
1019                 fn rapid_gossip_sync(&self) -> GossipSync<PGS, RGS, Arc<NetworkGraph<Arc<test_utils::TestLogger>>>, Arc<test_utils::TestChainSource>, Arc<test_utils::TestLogger>> {
1020                         GossipSync::Rapid(self.rapid_gossip_sync.clone())
1021                 }
1022
1023                 fn no_gossip_sync(&self) -> GossipSync<PGS, RGS, Arc<NetworkGraph<Arc<test_utils::TestLogger>>>, Arc<test_utils::TestChainSource>, Arc<test_utils::TestLogger>> {
1024                         GossipSync::None
1025                 }
1026         }
1027
1028         impl Drop for Node {
1029                 fn drop(&mut self) {
1030                         let data_dir = self.kv_store.get_data_dir();
1031                         match fs::remove_dir_all(data_dir.clone()) {
1032                                 Err(e) => println!("Failed to remove test store directory {}: {}", data_dir.display(), e),
1033                                 _ => {}
1034                         }
1035                 }
1036         }
1037
1038         struct Persister {
1039                 graph_error: Option<(std::io::ErrorKind, &'static str)>,
1040                 graph_persistence_notifier: Option<SyncSender<()>>,
1041                 manager_error: Option<(std::io::ErrorKind, &'static str)>,
1042                 scorer_error: Option<(std::io::ErrorKind, &'static str)>,
1043                 kv_store: FilesystemStore,
1044         }
1045
1046         impl Persister {
1047                 fn new(data_dir: PathBuf) -> Self {
1048                         let kv_store = FilesystemStore::new(data_dir);
1049                         Self { graph_error: None, graph_persistence_notifier: None, manager_error: None, scorer_error: None, kv_store }
1050                 }
1051
1052                 fn with_graph_error(self, error: std::io::ErrorKind, message: &'static str) -> Self {
1053                         Self { graph_error: Some((error, message)), ..self }
1054                 }
1055
1056                 fn with_graph_persistence_notifier(self, sender: SyncSender<()>) -> Self {
1057                         Self { graph_persistence_notifier: Some(sender), ..self }
1058                 }
1059
1060                 fn with_manager_error(self, error: std::io::ErrorKind, message: &'static str) -> Self {
1061                         Self { manager_error: Some((error, message)), ..self }
1062                 }
1063
1064                 fn with_scorer_error(self, error: std::io::ErrorKind, message: &'static str) -> Self {
1065                         Self { scorer_error: Some((error, message)), ..self }
1066                 }
1067         }
1068
1069         impl KVStore for Persister {
1070                 fn read(&self, primary_namespace: &str, secondary_namespace: &str, key: &str) -> lightning::io::Result<Vec<u8>> {
1071                         self.kv_store.read(primary_namespace, secondary_namespace, key)
1072                 }
1073
1074                 fn write(&self, primary_namespace: &str, secondary_namespace: &str, key: &str, buf: &[u8]) -> lightning::io::Result<()> {
1075                         if primary_namespace == CHANNEL_MANAGER_PERSISTENCE_PRIMARY_NAMESPACE &&
1076                                 secondary_namespace == CHANNEL_MANAGER_PERSISTENCE_SECONDARY_NAMESPACE &&
1077                                 key == CHANNEL_MANAGER_PERSISTENCE_KEY
1078                         {
1079                                 if let Some((error, message)) = self.manager_error {
1080                                         return Err(std::io::Error::new(error, message))
1081                                 }
1082                         }
1083
1084                         if primary_namespace == NETWORK_GRAPH_PERSISTENCE_PRIMARY_NAMESPACE &&
1085                                 secondary_namespace == NETWORK_GRAPH_PERSISTENCE_SECONDARY_NAMESPACE &&
1086                                 key == NETWORK_GRAPH_PERSISTENCE_KEY
1087                         {
1088                                 if let Some(sender) = &self.graph_persistence_notifier {
1089                                         match sender.send(()) {
1090                                                 Ok(()) => {},
1091                                                 Err(std::sync::mpsc::SendError(())) => println!("Persister failed to notify as receiver went away."),
1092                                         }
1093                                 };
1094
1095                                 if let Some((error, message)) = self.graph_error {
1096                                         return Err(std::io::Error::new(error, message))
1097                                 }
1098                         }
1099
1100                         if primary_namespace == SCORER_PERSISTENCE_PRIMARY_NAMESPACE &&
1101                                 secondary_namespace == SCORER_PERSISTENCE_SECONDARY_NAMESPACE &&
1102                                 key == SCORER_PERSISTENCE_KEY
1103                         {
1104                                 if let Some((error, message)) = self.scorer_error {
1105                                         return Err(std::io::Error::new(error, message))
1106                                 }
1107                         }
1108
1109                         self.kv_store.write(primary_namespace, secondary_namespace, key, buf)
1110                 }
1111
1112                 fn remove(&self, primary_namespace: &str, secondary_namespace: &str, key: &str, lazy: bool) -> lightning::io::Result<()> {
1113                         self.kv_store.remove(primary_namespace, secondary_namespace, key, lazy)
1114                 }
1115
1116                 fn list(&self, primary_namespace: &str, secondary_namespace: &str) -> lightning::io::Result<Vec<String>> {
1117                         self.kv_store.list(primary_namespace, secondary_namespace)
1118                 }
1119         }
1120
1121         struct TestScorer {
1122                 event_expectations: Option<VecDeque<TestResult>>,
1123         }
1124
1125         #[derive(Debug)]
1126         enum TestResult {
1127                 PaymentFailure { path: Path, short_channel_id: u64 },
1128                 PaymentSuccess { path: Path },
1129                 ProbeFailure { path: Path },
1130                 ProbeSuccess { path: Path },
1131         }
1132
1133         impl TestScorer {
1134                 fn new() -> Self {
1135                         Self { event_expectations: None }
1136                 }
1137
1138                 fn expect(&mut self, expectation: TestResult) {
1139                         self.event_expectations.get_or_insert_with(VecDeque::new).push_back(expectation);
1140                 }
1141         }
1142
1143         impl lightning::util::ser::Writeable for TestScorer {
1144                 fn write<W: lightning::util::ser::Writer>(&self, _: &mut W) -> Result<(), lightning::io::Error> { Ok(()) }
1145         }
1146
1147         impl ScoreLookUp for TestScorer {
1148                 type ScoreParams = ();
1149                 fn channel_penalty_msat(
1150                         &self, _candidate: &CandidateRouteHop, _usage: ChannelUsage, _score_params: &Self::ScoreParams
1151                 ) -> u64 { unimplemented!(); }
1152         }
1153
1154         impl ScoreUpdate for TestScorer {
1155                 fn payment_path_failed(&mut self, actual_path: &Path, actual_short_channel_id: u64, _: Duration) {
1156                         if let Some(expectations) = &mut self.event_expectations {
1157                                 match expectations.pop_front().unwrap() {
1158                                         TestResult::PaymentFailure { path, short_channel_id } => {
1159                                                 assert_eq!(actual_path, &path);
1160                                                 assert_eq!(actual_short_channel_id, short_channel_id);
1161                                         },
1162                                         TestResult::PaymentSuccess { path } => {
1163                                                 panic!("Unexpected successful payment path: {:?}", path)
1164                                         },
1165                                         TestResult::ProbeFailure { path } => {
1166                                                 panic!("Unexpected probe failure: {:?}", path)
1167                                         },
1168                                         TestResult::ProbeSuccess { path } => {
1169                                                 panic!("Unexpected probe success: {:?}", path)
1170                                         }
1171                                 }
1172                         }
1173                 }
1174
1175                 fn payment_path_successful(&mut self, actual_path: &Path, _: Duration) {
1176                         if let Some(expectations) = &mut self.event_expectations {
1177                                 match expectations.pop_front().unwrap() {
1178                                         TestResult::PaymentFailure { path, .. } => {
1179                                                 panic!("Unexpected payment path failure: {:?}", path)
1180                                         },
1181                                         TestResult::PaymentSuccess { path } => {
1182                                                 assert_eq!(actual_path, &path);
1183                                         },
1184                                         TestResult::ProbeFailure { path } => {
1185                                                 panic!("Unexpected probe failure: {:?}", path)
1186                                         },
1187                                         TestResult::ProbeSuccess { path } => {
1188                                                 panic!("Unexpected probe success: {:?}", path)
1189                                         }
1190                                 }
1191                         }
1192                 }
1193
1194                 fn probe_failed(&mut self, actual_path: &Path, _: u64, _: Duration) {
1195                         if let Some(expectations) = &mut self.event_expectations {
1196                                 match expectations.pop_front().unwrap() {
1197                                         TestResult::PaymentFailure { path, .. } => {
1198                                                 panic!("Unexpected payment path failure: {:?}", path)
1199                                         },
1200                                         TestResult::PaymentSuccess { path } => {
1201                                                 panic!("Unexpected payment path success: {:?}", path)
1202                                         },
1203                                         TestResult::ProbeFailure { path } => {
1204                                                 assert_eq!(actual_path, &path);
1205                                         },
1206                                         TestResult::ProbeSuccess { path } => {
1207                                                 panic!("Unexpected probe success: {:?}", path)
1208                                         }
1209                                 }
1210                         }
1211                 }
1212                 fn probe_successful(&mut self, actual_path: &Path, _: Duration) {
1213                         if let Some(expectations) = &mut self.event_expectations {
1214                                 match expectations.pop_front().unwrap() {
1215                                         TestResult::PaymentFailure { path, .. } => {
1216                                                 panic!("Unexpected payment path failure: {:?}", path)
1217                                         },
1218                                         TestResult::PaymentSuccess { path } => {
1219                                                 panic!("Unexpected payment path success: {:?}", path)
1220                                         },
1221                                         TestResult::ProbeFailure { path } => {
1222                                                 panic!("Unexpected probe failure: {:?}", path)
1223                                         },
1224                                         TestResult::ProbeSuccess { path } => {
1225                                                 assert_eq!(actual_path, &path);
1226                                         }
1227                                 }
1228                         }
1229                 }
1230                 fn time_passed(&mut self, _: Duration) {}
1231         }
1232
1233         #[cfg(c_bindings)]
1234         impl lightning::routing::scoring::Score for TestScorer {}
1235
1236         impl Drop for TestScorer {
1237                 fn drop(&mut self) {
1238                         if std::thread::panicking() {
1239                                 return;
1240                         }
1241
1242                         if let Some(event_expectations) = &self.event_expectations {
1243                                 if !event_expectations.is_empty() {
1244                                         panic!("Unsatisfied event expectations: {:?}", event_expectations);
1245                                 }
1246                         }
1247                 }
1248         }
1249
1250         fn get_full_filepath(filepath: String, filename: String) -> String {
1251                 let mut path = PathBuf::from(filepath);
1252                 path.push(filename);
1253                 path.to_str().unwrap().to_string()
1254         }
1255
1256         fn create_nodes(num_nodes: usize, persist_dir: &str) -> (String, Vec<Node>) {
1257                 let persist_temp_path = env::temp_dir().join(persist_dir);
1258                 let persist_dir = persist_temp_path.to_string_lossy().to_string();
1259                 let network = Network::Bitcoin;
1260                 let mut nodes = Vec::new();
1261                 for i in 0..num_nodes {
1262                         let tx_broadcaster = Arc::new(test_utils::TestBroadcaster::new(network));
1263                         let fee_estimator = Arc::new(test_utils::TestFeeEstimator { sat_per_kw: Mutex::new(253) });
1264                         let logger = Arc::new(test_utils::TestLogger::with_id(format!("node {}", i)));
1265                         let genesis_block = genesis_block(network);
1266                         let network_graph = Arc::new(NetworkGraph::new(network, logger.clone()));
1267                         let scorer = Arc::new(LockingWrapper::new(TestScorer::new()));
1268                         let now = Duration::from_secs(genesis_block.header.time as u64);
1269                         let seed = [i as u8; 32];
1270                         let keys_manager = Arc::new(KeysManager::new(&seed, now.as_secs(), now.subsec_nanos()));
1271                         let router = Arc::new(DefaultRouter::new(network_graph.clone(), logger.clone(), Arc::clone(&keys_manager), scorer.clone(), Default::default()));
1272                         let chain_source = Arc::new(test_utils::TestChainSource::new(Network::Bitcoin));
1273                         let kv_store = Arc::new(FilesystemStore::new(format!("{}_persister_{}", &persist_dir, i).into()));
1274                         let chain_monitor = Arc::new(chainmonitor::ChainMonitor::new(Some(chain_source.clone()), tx_broadcaster.clone(), logger.clone(), fee_estimator.clone(), kv_store.clone()));
1275                         let best_block = BestBlock::from_network(network);
1276                         let params = ChainParameters { network, best_block };
1277                         let manager = Arc::new(ChannelManager::new(fee_estimator.clone(), chain_monitor.clone(), tx_broadcaster.clone(), router.clone(), logger.clone(), keys_manager.clone(), keys_manager.clone(), keys_manager.clone(), UserConfig::default(), params, genesis_block.header.time));
1278                         let p2p_gossip_sync = Arc::new(P2PGossipSync::new(network_graph.clone(), Some(chain_source.clone()), logger.clone()));
1279                         let rapid_gossip_sync = Arc::new(RapidGossipSync::new(network_graph.clone(), logger.clone()));
1280                         let msg_handler = MessageHandler {
1281                                 chan_handler: Arc::new(test_utils::TestChannelMessageHandler::new(ChainHash::using_genesis_block(Network::Testnet))),
1282                                 route_handler: Arc::new(test_utils::TestRoutingMessageHandler::new()),
1283                                 onion_message_handler: IgnoringMessageHandler{}, custom_message_handler: IgnoringMessageHandler{}
1284                         };
1285                         let peer_manager = Arc::new(PeerManager::new(msg_handler, 0, &seed, logger.clone(), keys_manager.clone()));
1286                         let node = Node { node: manager, p2p_gossip_sync, rapid_gossip_sync, peer_manager, chain_monitor, kv_store, tx_broadcaster, network_graph, logger, best_block, scorer };
1287                         nodes.push(node);
1288                 }
1289
1290                 for i in 0..num_nodes {
1291                         for j in (i+1)..num_nodes {
1292                                 nodes[i].node.peer_connected(&nodes[j].node.get_our_node_id(), &Init {
1293                                         features: nodes[j].node.init_features(), networks: None, remote_network_address: None
1294                                 }, true).unwrap();
1295                                 nodes[j].node.peer_connected(&nodes[i].node.get_our_node_id(), &Init {
1296                                         features: nodes[i].node.init_features(), networks: None, remote_network_address: None
1297                                 }, false).unwrap();
1298                         }
1299                 }
1300
1301                 (persist_dir, nodes)
1302         }
1303
1304         macro_rules! open_channel {
1305                 ($node_a: expr, $node_b: expr, $channel_value: expr) => {{
1306                         begin_open_channel!($node_a, $node_b, $channel_value);
1307                         let events = $node_a.node.get_and_clear_pending_events();
1308                         assert_eq!(events.len(), 1);
1309                         let (temporary_channel_id, tx) = handle_funding_generation_ready!(events[0], $channel_value);
1310                         $node_a.node.funding_transaction_generated(&temporary_channel_id, &$node_b.node.get_our_node_id(), tx.clone()).unwrap();
1311                         $node_b.node.handle_funding_created(&$node_a.node.get_our_node_id(), &get_event_msg!($node_a, MessageSendEvent::SendFundingCreated, $node_b.node.get_our_node_id()));
1312                         get_event!($node_b, Event::ChannelPending);
1313                         $node_a.node.handle_funding_signed(&$node_b.node.get_our_node_id(), &get_event_msg!($node_b, MessageSendEvent::SendFundingSigned, $node_a.node.get_our_node_id()));
1314                         get_event!($node_a, Event::ChannelPending);
1315                         tx
1316                 }}
1317         }
1318
1319         macro_rules! begin_open_channel {
1320                 ($node_a: expr, $node_b: expr, $channel_value: expr) => {{
1321                         $node_a.node.create_channel($node_b.node.get_our_node_id(), $channel_value, 100, 42, None, None).unwrap();
1322                         $node_b.node.handle_open_channel(&$node_a.node.get_our_node_id(), &get_event_msg!($node_a, MessageSendEvent::SendOpenChannel, $node_b.node.get_our_node_id()));
1323                         $node_a.node.handle_accept_channel(&$node_b.node.get_our_node_id(), &get_event_msg!($node_b, MessageSendEvent::SendAcceptChannel, $node_a.node.get_our_node_id()));
1324                 }}
1325         }
1326
1327         macro_rules! handle_funding_generation_ready {
1328                 ($event: expr, $channel_value: expr) => {{
1329                         match $event {
1330                                 Event::FundingGenerationReady { temporary_channel_id, channel_value_satoshis, ref output_script, user_channel_id, .. } => {
1331                                         assert_eq!(channel_value_satoshis, $channel_value);
1332                                         assert_eq!(user_channel_id, 42);
1333
1334                                         let tx = Transaction { version: 1 as i32, lock_time: LockTime::ZERO, input: Vec::new(), output: vec![TxOut {
1335                                                 value: channel_value_satoshis, script_pubkey: output_script.clone(),
1336                                         }]};
1337                                         (temporary_channel_id, tx)
1338                                 },
1339                                 _ => panic!("Unexpected event"),
1340                         }
1341                 }}
1342         }
1343
1344         fn confirm_transaction_depth(node: &mut Node, tx: &Transaction, depth: u32) {
1345                 for i in 1..=depth {
1346                         let prev_blockhash = node.best_block.block_hash;
1347                         let height = node.best_block.height + 1;
1348                         let header = create_dummy_header(prev_blockhash, height);
1349                         let txdata = vec![(0, tx)];
1350                         node.best_block = BestBlock::new(header.block_hash(), height);
1351                         match i {
1352                                 1 => {
1353                                         node.node.transactions_confirmed(&header, &txdata, height);
1354                                         node.chain_monitor.transactions_confirmed(&header, &txdata, height);
1355                                 },
1356                                 x if x == depth => {
1357                                         node.node.best_block_updated(&header, height);
1358                                         node.chain_monitor.best_block_updated(&header, height);
1359                                 },
1360                                 _ => {},
1361                         }
1362                 }
1363         }
1364         fn confirm_transaction(node: &mut Node, tx: &Transaction) {
1365                 confirm_transaction_depth(node, tx, ANTI_REORG_DELAY);
1366         }
1367
1368         #[test]
1369         fn test_background_processor() {
1370                 // Test that when a new channel is created, the ChannelManager needs to be re-persisted with
1371                 // updates. Also test that when new updates are available, the manager signals that it needs
1372                 // re-persistence and is successfully re-persisted.
1373                 let (persist_dir, nodes) = create_nodes(2, "test_background_processor");
1374
1375                 // Go through the channel creation process so that each node has something to persist. Since
1376                 // open_channel consumes events, it must complete before starting BackgroundProcessor to
1377                 // avoid a race with processing events.
1378                 let tx = open_channel!(nodes[0], nodes[1], 100000);
1379
1380                 // Initiate the background processors to watch each node.
1381                 let data_dir = nodes[0].kv_store.get_data_dir();
1382                 let persister = Arc::new(Persister::new(data_dir));
1383                 let event_handler = |_: _| {};
1384                 let bg_processor = BackgroundProcessor::start(persister, event_handler, nodes[0].chain_monitor.clone(), nodes[0].node.clone(), nodes[0].p2p_gossip_sync(), nodes[0].peer_manager.clone(), nodes[0].logger.clone(), Some(nodes[0].scorer.clone()));
1385
1386                 macro_rules! check_persisted_data {
1387                         ($node: expr, $filepath: expr) => {
1388                                 let mut expected_bytes = Vec::new();
1389                                 loop {
1390                                         expected_bytes.clear();
1391                                         match $node.write(&mut expected_bytes) {
1392                                                 Ok(()) => {
1393                                                         match std::fs::read($filepath) {
1394                                                                 Ok(bytes) => {
1395                                                                         if bytes == expected_bytes {
1396                                                                                 break
1397                                                                         } else {
1398                                                                                 continue
1399                                                                         }
1400                                                                 },
1401                                                                 Err(_) => continue
1402                                                         }
1403                                                 },
1404                                                 Err(e) => panic!("Unexpected error: {}", e)
1405                                         }
1406                                 }
1407                         }
1408                 }
1409
1410                 // Check that the initial channel manager data is persisted as expected.
1411                 let filepath = get_full_filepath(format!("{}_persister_0", &persist_dir), "manager".to_string());
1412                 check_persisted_data!(nodes[0].node, filepath.clone());
1413
1414                 loop {
1415                         if !nodes[0].node.get_event_or_persist_condvar_value() { break }
1416                 }
1417
1418                 // Force-close the channel.
1419                 let error_message = "Channel force-closed";
1420                 nodes[0].node.force_close_broadcasting_latest_txn(&ChannelId::v1_from_funding_outpoint(OutPoint { txid: tx.txid(), index: 0 }), &nodes[1].node.get_our_node_id(), error_message.to_string()).unwrap();
1421
1422                 // Check that the force-close updates are persisted.
1423                 check_persisted_data!(nodes[0].node, filepath.clone());
1424                 loop {
1425                         if !nodes[0].node.get_event_or_persist_condvar_value() { break }
1426                 }
1427
1428                 // Check network graph is persisted
1429                 let filepath = get_full_filepath(format!("{}_persister_0", &persist_dir), "network_graph".to_string());
1430                 check_persisted_data!(nodes[0].network_graph, filepath.clone());
1431
1432                 // Check scorer is persisted
1433                 let filepath = get_full_filepath(format!("{}_persister_0", &persist_dir), "scorer".to_string());
1434                 check_persisted_data!(nodes[0].scorer, filepath.clone());
1435
1436                 if !std::thread::panicking() {
1437                         bg_processor.stop().unwrap();
1438                 }
1439         }
1440
1441         #[test]
1442         fn test_timer_tick_called() {
1443                 // Test that:
1444                 // - `ChannelManager::timer_tick_occurred` is called every `FRESHNESS_TIMER`,
1445                 // - `ChainMonitor::rebroadcast_pending_claims` is called every `REBROADCAST_TIMER`,
1446                 // - `PeerManager::timer_tick_occurred` is called every `PING_TIMER`, and
1447                 // - `OnionMessageHandler::timer_tick_occurred` is called every `ONION_MESSAGE_HANDLER_TIMER`.
1448                 let (_, nodes) = create_nodes(1, "test_timer_tick_called");
1449                 let data_dir = nodes[0].kv_store.get_data_dir();
1450                 let persister = Arc::new(Persister::new(data_dir));
1451                 let event_handler = |_: _| {};
1452                 let bg_processor = BackgroundProcessor::start(persister, event_handler, nodes[0].chain_monitor.clone(), nodes[0].node.clone(), nodes[0].no_gossip_sync(), nodes[0].peer_manager.clone(), nodes[0].logger.clone(), Some(nodes[0].scorer.clone()));
1453                 loop {
1454                         let log_entries = nodes[0].logger.lines.lock().unwrap();
1455                         let desired_log_1 = "Calling ChannelManager's timer_tick_occurred".to_string();
1456                         let desired_log_2 = "Calling PeerManager's timer_tick_occurred".to_string();
1457                         let desired_log_3 = "Rebroadcasting monitor's pending claims".to_string();
1458                         let desired_log_4 = "Calling OnionMessageHandler's timer_tick_occurred".to_string();
1459                         if log_entries.get(&("lightning_background_processor", desired_log_1)).is_some() &&
1460                                 log_entries.get(&("lightning_background_processor", desired_log_2)).is_some() &&
1461                                 log_entries.get(&("lightning_background_processor", desired_log_3)).is_some() &&
1462                                 log_entries.get(&("lightning_background_processor", desired_log_4)).is_some() {
1463                                 break
1464                         }
1465                 }
1466
1467                 if !std::thread::panicking() {
1468                         bg_processor.stop().unwrap();
1469                 }
1470         }
1471
1472         #[test]
1473         fn test_channel_manager_persist_error() {
1474                 // Test that if we encounter an error during manager persistence, the thread panics.
1475                 let (_, nodes) = create_nodes(2, "test_persist_error");
1476                 open_channel!(nodes[0], nodes[1], 100000);
1477
1478                 let data_dir = nodes[0].kv_store.get_data_dir();
1479                 let persister = Arc::new(Persister::new(data_dir).with_manager_error(std::io::ErrorKind::Other, "test"));
1480                 let event_handler = |_: _| {};
1481                 let bg_processor = BackgroundProcessor::start(persister, event_handler, nodes[0].chain_monitor.clone(), nodes[0].node.clone(), nodes[0].no_gossip_sync(), nodes[0].peer_manager.clone(), nodes[0].logger.clone(), Some(nodes[0].scorer.clone()));
1482                 match bg_processor.join() {
1483                         Ok(_) => panic!("Expected error persisting manager"),
1484                         Err(e) => {
1485                                 assert_eq!(e.kind(), std::io::ErrorKind::Other);
1486                                 assert_eq!(e.get_ref().unwrap().to_string(), "test");
1487                         },
1488                 }
1489         }
1490
1491         #[tokio::test]
1492         #[cfg(feature = "futures")]
1493         async fn test_channel_manager_persist_error_async() {
1494                 // Test that if we encounter an error during manager persistence, the thread panics.
1495                 let (_, nodes) = create_nodes(2, "test_persist_error_sync");
1496                 open_channel!(nodes[0], nodes[1], 100000);
1497
1498                 let data_dir = nodes[0].kv_store.get_data_dir();
1499                 let persister = Arc::new(Persister::new(data_dir).with_manager_error(std::io::ErrorKind::Other, "test"));
1500
1501                 let bp_future = super::process_events_async(
1502                         persister, |_: _| {async {}}, nodes[0].chain_monitor.clone(), nodes[0].node.clone(),
1503                         nodes[0].rapid_gossip_sync(), nodes[0].peer_manager.clone(), nodes[0].logger.clone(),
1504                         Some(nodes[0].scorer.clone()), move |dur: Duration| {
1505                                 Box::pin(async move {
1506                                         tokio::time::sleep(dur).await;
1507                                         false // Never exit
1508                                 })
1509                         }, false, || Some(Duration::ZERO),
1510                 );
1511                 match bp_future.await {
1512                         Ok(_) => panic!("Expected error persisting manager"),
1513                         Err(e) => {
1514                                 assert_eq!(e.kind(), std::io::ErrorKind::Other);
1515                                 assert_eq!(e.get_ref().unwrap().to_string(), "test");
1516                         },
1517                 }
1518         }
1519
1520         #[test]
1521         fn test_network_graph_persist_error() {
1522                 // Test that if we encounter an error during network graph persistence, an error gets returned.
1523                 let (_, nodes) = create_nodes(2, "test_persist_network_graph_error");
1524                 let data_dir = nodes[0].kv_store.get_data_dir();
1525                 let persister = Arc::new(Persister::new(data_dir).with_graph_error(std::io::ErrorKind::Other, "test"));
1526                 let event_handler = |_: _| {};
1527                 let bg_processor = BackgroundProcessor::start(persister, event_handler, nodes[0].chain_monitor.clone(), nodes[0].node.clone(), nodes[0].p2p_gossip_sync(), nodes[0].peer_manager.clone(), nodes[0].logger.clone(), Some(nodes[0].scorer.clone()));
1528
1529                 match bg_processor.stop() {
1530                         Ok(_) => panic!("Expected error persisting network graph"),
1531                         Err(e) => {
1532                                 assert_eq!(e.kind(), std::io::ErrorKind::Other);
1533                                 assert_eq!(e.get_ref().unwrap().to_string(), "test");
1534                         },
1535                 }
1536         }
1537
1538         #[test]
1539         fn test_scorer_persist_error() {
1540                 // Test that if we encounter an error during scorer persistence, an error gets returned.
1541                 let (_, nodes) = create_nodes(2, "test_persist_scorer_error");
1542                 let data_dir = nodes[0].kv_store.get_data_dir();
1543                 let persister = Arc::new(Persister::new(data_dir).with_scorer_error(std::io::ErrorKind::Other, "test"));
1544                 let event_handler = |_: _| {};
1545                 let bg_processor = BackgroundProcessor::start(persister, event_handler, nodes[0].chain_monitor.clone(), nodes[0].node.clone(), nodes[0].no_gossip_sync(), nodes[0].peer_manager.clone(),  nodes[0].logger.clone(), Some(nodes[0].scorer.clone()));
1546
1547                 match bg_processor.stop() {
1548                         Ok(_) => panic!("Expected error persisting scorer"),
1549                         Err(e) => {
1550                                 assert_eq!(e.kind(), std::io::ErrorKind::Other);
1551                                 assert_eq!(e.get_ref().unwrap().to_string(), "test");
1552                         },
1553                 }
1554         }
1555
1556         #[test]
1557         fn test_background_event_handling() {
1558                 let (_, mut nodes) = create_nodes(2, "test_background_event_handling");
1559                 let channel_value = 100000;
1560                 let data_dir = nodes[0].kv_store.get_data_dir();
1561                 let persister = Arc::new(Persister::new(data_dir.clone()));
1562
1563                 // Set up a background event handler for FundingGenerationReady events.
1564                 let (funding_generation_send, funding_generation_recv) = std::sync::mpsc::sync_channel(1);
1565                 let (channel_pending_send, channel_pending_recv) = std::sync::mpsc::sync_channel(1);
1566                 let event_handler = move |event: Event| match event {
1567                         Event::FundingGenerationReady { .. } => funding_generation_send.send(handle_funding_generation_ready!(event, channel_value)).unwrap(),
1568                         Event::ChannelPending { .. } => channel_pending_send.send(()).unwrap(),
1569                         Event::ChannelReady { .. } => {},
1570                         _ => panic!("Unexpected event: {:?}", event),
1571                 };
1572
1573                 let bg_processor = BackgroundProcessor::start(persister, event_handler, nodes[0].chain_monitor.clone(), nodes[0].node.clone(), nodes[0].no_gossip_sync(), nodes[0].peer_manager.clone(), nodes[0].logger.clone(), Some(nodes[0].scorer.clone()));
1574
1575                 // Open a channel and check that the FundingGenerationReady event was handled.
1576                 begin_open_channel!(nodes[0], nodes[1], channel_value);
1577                 let (temporary_channel_id, funding_tx) = funding_generation_recv
1578                         .recv_timeout(Duration::from_secs(EVENT_DEADLINE))
1579                         .expect("FundingGenerationReady not handled within deadline");
1580                 nodes[0].node.funding_transaction_generated(&temporary_channel_id, &nodes[1].node.get_our_node_id(), funding_tx.clone()).unwrap();
1581                 nodes[1].node.handle_funding_created(&nodes[0].node.get_our_node_id(), &get_event_msg!(nodes[0], MessageSendEvent::SendFundingCreated, nodes[1].node.get_our_node_id()));
1582                 get_event!(nodes[1], Event::ChannelPending);
1583                 nodes[0].node.handle_funding_signed(&nodes[1].node.get_our_node_id(), &get_event_msg!(nodes[1], MessageSendEvent::SendFundingSigned, nodes[0].node.get_our_node_id()));
1584                 let _ = channel_pending_recv.recv_timeout(Duration::from_secs(EVENT_DEADLINE))
1585                         .expect("ChannelPending not handled within deadline");
1586
1587                 // Confirm the funding transaction.
1588                 confirm_transaction(&mut nodes[0], &funding_tx);
1589                 let as_funding = get_event_msg!(nodes[0], MessageSendEvent::SendChannelReady, nodes[1].node.get_our_node_id());
1590                 confirm_transaction(&mut nodes[1], &funding_tx);
1591                 let bs_funding = get_event_msg!(nodes[1], MessageSendEvent::SendChannelReady, nodes[0].node.get_our_node_id());
1592                 nodes[0].node.handle_channel_ready(&nodes[1].node.get_our_node_id(), &bs_funding);
1593                 let _as_channel_update = get_event_msg!(nodes[0], MessageSendEvent::SendChannelUpdate, nodes[1].node.get_our_node_id());
1594                 nodes[1].node.handle_channel_ready(&nodes[0].node.get_our_node_id(), &as_funding);
1595                 let _bs_channel_update = get_event_msg!(nodes[1], MessageSendEvent::SendChannelUpdate, nodes[0].node.get_our_node_id());
1596
1597                 if !std::thread::panicking() {
1598                         bg_processor.stop().unwrap();
1599                 }
1600
1601                 // Set up a background event handler for SpendableOutputs events.
1602                 let (sender, receiver) = std::sync::mpsc::sync_channel(1);
1603                 let event_handler = move |event: Event| match event {
1604                         Event::SpendableOutputs { .. } => sender.send(event).unwrap(),
1605                         Event::ChannelReady { .. } => {},
1606                         Event::ChannelClosed { .. } => {},
1607                         _ => panic!("Unexpected event: {:?}", event),
1608                 };
1609                 let persister = Arc::new(Persister::new(data_dir));
1610                 let bg_processor = BackgroundProcessor::start(persister, event_handler, nodes[0].chain_monitor.clone(), nodes[0].node.clone(), nodes[0].no_gossip_sync(), nodes[0].peer_manager.clone(), nodes[0].logger.clone(), Some(nodes[0].scorer.clone()));
1611
1612                 // Force close the channel and check that the SpendableOutputs event was handled.
1613                 let error_message = "Channel force-closed";
1614                 nodes[0].node.force_close_broadcasting_latest_txn(&nodes[0].node.list_channels()[0].channel_id, &nodes[1].node.get_our_node_id(), error_message.to_string()).unwrap();
1615                 let commitment_tx = nodes[0].tx_broadcaster.txn_broadcasted.lock().unwrap().pop().unwrap();
1616                 confirm_transaction_depth(&mut nodes[0], &commitment_tx, BREAKDOWN_TIMEOUT as u32);
1617
1618                 let event = receiver
1619                         .recv_timeout(Duration::from_secs(EVENT_DEADLINE))
1620                         .expect("Events not handled within deadline");
1621                 match event {
1622                         Event::SpendableOutputs { .. } => {},
1623                         _ => panic!("Unexpected event: {:?}", event),
1624                 }
1625
1626                 if !std::thread::panicking() {
1627                         bg_processor.stop().unwrap();
1628                 }
1629         }
1630
1631         #[test]
1632         fn test_scorer_persistence() {
1633                 let (_, nodes) = create_nodes(2, "test_scorer_persistence");
1634                 let data_dir = nodes[0].kv_store.get_data_dir();
1635                 let persister = Arc::new(Persister::new(data_dir));
1636                 let event_handler = |_: _| {};
1637                 let bg_processor = BackgroundProcessor::start(persister, event_handler, nodes[0].chain_monitor.clone(), nodes[0].node.clone(), nodes[0].no_gossip_sync(), nodes[0].peer_manager.clone(), nodes[0].logger.clone(), Some(nodes[0].scorer.clone()));
1638
1639                 loop {
1640                         let log_entries = nodes[0].logger.lines.lock().unwrap();
1641                         let expected_log = "Calling time_passed and persisting scorer".to_string();
1642                         if log_entries.get(&("lightning_background_processor", expected_log)).is_some() {
1643                                 break
1644                         }
1645                 }
1646
1647                 if !std::thread::panicking() {
1648                         bg_processor.stop().unwrap();
1649                 }
1650         }
1651
1652         macro_rules! do_test_not_pruning_network_graph_until_graph_sync_completion {
1653                 ($nodes: expr, $receive: expr, $sleep: expr) => {
1654                         let features = ChannelFeatures::empty();
1655                         $nodes[0].network_graph.add_channel_from_partial_announcement(
1656                                 42, 53, features, $nodes[0].node.get_our_node_id(), $nodes[1].node.get_our_node_id()
1657                         ).expect("Failed to update channel from partial announcement");
1658                         let original_graph_description = $nodes[0].network_graph.to_string();
1659                         assert!(original_graph_description.contains("42: features: 0000, node_one:"));
1660                         assert_eq!($nodes[0].network_graph.read_only().channels().len(), 1);
1661
1662                         loop {
1663                                 $sleep;
1664                                 let log_entries = $nodes[0].logger.lines.lock().unwrap();
1665                                 let loop_counter = "Calling ChannelManager's timer_tick_occurred".to_string();
1666                                 if *log_entries.get(&("lightning_background_processor", loop_counter))
1667                                         .unwrap_or(&0) > 1
1668                                 {
1669                                         // Wait until the loop has gone around at least twice.
1670                                         break
1671                                 }
1672                         }
1673
1674                         let initialization_input = vec![
1675                                 76, 68, 75, 1, 111, 226, 140, 10, 182, 241, 179, 114, 193, 166, 162, 70, 174, 99, 247,
1676                                 79, 147, 30, 131, 101, 225, 90, 8, 156, 104, 214, 25, 0, 0, 0, 0, 0, 97, 227, 98, 218,
1677                                 0, 0, 0, 4, 2, 22, 7, 207, 206, 25, 164, 197, 231, 230, 231, 56, 102, 61, 250, 251,
1678                                 187, 172, 38, 46, 79, 247, 108, 44, 155, 48, 219, 238, 252, 53, 192, 6, 67, 2, 36, 125,
1679                                 157, 176, 223, 175, 234, 116, 94, 248, 201, 225, 97, 235, 50, 47, 115, 172, 63, 136,
1680                                 88, 216, 115, 11, 111, 217, 114, 84, 116, 124, 231, 107, 2, 158, 1, 242, 121, 152, 106,
1681                                 204, 131, 186, 35, 93, 70, 216, 10, 237, 224, 183, 89, 95, 65, 3, 83, 185, 58, 138,
1682                                 181, 64, 187, 103, 127, 68, 50, 2, 201, 19, 17, 138, 136, 149, 185, 226, 156, 137, 175,
1683                                 110, 32, 237, 0, 217, 90, 31, 100, 228, 149, 46, 219, 175, 168, 77, 4, 143, 38, 128,
1684                                 76, 97, 0, 0, 0, 2, 0, 0, 255, 8, 153, 192, 0, 2, 27, 0, 0, 0, 1, 0, 0, 255, 2, 68,
1685                                 226, 0, 6, 11, 0, 1, 2, 3, 0, 0, 0, 2, 0, 40, 0, 0, 0, 0, 0, 0, 3, 232, 0, 0, 3, 232,
1686                                 0, 0, 0, 1, 0, 0, 0, 0, 58, 85, 116, 216, 255, 8, 153, 192, 0, 2, 27, 0, 0, 25, 0, 0,
1687                                 0, 1, 0, 0, 0, 125, 255, 2, 68, 226, 0, 6, 11, 0, 1, 5, 0, 0, 0, 0, 29, 129, 25, 192,
1688                         ];
1689                         $nodes[0].rapid_gossip_sync.update_network_graph_no_std(&initialization_input[..], Some(1642291930)).unwrap();
1690
1691                         // this should have added two channels and pruned the previous one.
1692                         assert_eq!($nodes[0].network_graph.read_only().channels().len(), 2);
1693
1694                         $receive.expect("Network graph not pruned within deadline");
1695
1696                         // all channels should now be pruned
1697                         assert_eq!($nodes[0].network_graph.read_only().channels().len(), 0);
1698                 }
1699         }
1700
1701         #[test]
1702         fn test_not_pruning_network_graph_until_graph_sync_completion() {
1703                 let (sender, receiver) = std::sync::mpsc::sync_channel(1);
1704
1705                 let (_, nodes) = create_nodes(2, "test_not_pruning_network_graph_until_graph_sync_completion");
1706                 let data_dir = nodes[0].kv_store.get_data_dir();
1707                 let persister = Arc::new(Persister::new(data_dir).with_graph_persistence_notifier(sender));
1708
1709                 let event_handler = |_: _| {};
1710                 let background_processor = BackgroundProcessor::start(persister, event_handler, nodes[0].chain_monitor.clone(), nodes[0].node.clone(), nodes[0].rapid_gossip_sync(), nodes[0].peer_manager.clone(), nodes[0].logger.clone(), Some(nodes[0].scorer.clone()));
1711
1712                 do_test_not_pruning_network_graph_until_graph_sync_completion!(nodes,
1713                         receiver.recv_timeout(Duration::from_secs(super::FIRST_NETWORK_PRUNE_TIMER * 5)),
1714                         std::thread::sleep(Duration::from_millis(1)));
1715
1716                 background_processor.stop().unwrap();
1717         }
1718
1719         #[tokio::test]
1720         #[cfg(feature = "futures")]
1721         async fn test_not_pruning_network_graph_until_graph_sync_completion_async() {
1722                 let (sender, receiver) = std::sync::mpsc::sync_channel(1);
1723
1724                 let (_, nodes) = create_nodes(2, "test_not_pruning_network_graph_until_graph_sync_completion_async");
1725                 let data_dir = nodes[0].kv_store.get_data_dir();
1726                 let persister = Arc::new(Persister::new(data_dir).with_graph_persistence_notifier(sender));
1727
1728                 let (exit_sender, exit_receiver) = tokio::sync::watch::channel(());
1729                 let bp_future = super::process_events_async(
1730                         persister, |_: _| {async {}}, nodes[0].chain_monitor.clone(), nodes[0].node.clone(),
1731                         nodes[0].rapid_gossip_sync(), nodes[0].peer_manager.clone(), nodes[0].logger.clone(),
1732                         Some(nodes[0].scorer.clone()), move |dur: Duration| {
1733                                 let mut exit_receiver = exit_receiver.clone();
1734                                 Box::pin(async move {
1735                                         tokio::select! {
1736                                                 _ = tokio::time::sleep(dur) => false,
1737                                                 _ = exit_receiver.changed() => true,
1738                                         }
1739                                 })
1740                         }, false, || Some(Duration::from_secs(1696300000)),
1741                 );
1742
1743                 let t1 = tokio::spawn(bp_future);
1744                 let t2 = tokio::spawn(async move {
1745                         do_test_not_pruning_network_graph_until_graph_sync_completion!(nodes, {
1746                                 let mut i = 0;
1747                                 loop {
1748                                         tokio::time::sleep(Duration::from_secs(super::FIRST_NETWORK_PRUNE_TIMER)).await;
1749                                         if let Ok(()) = receiver.try_recv() { break Ok::<(), ()>(()); }
1750                                         assert!(i < 5);
1751                                         i += 1;
1752                                 }
1753                         }, tokio::time::sleep(Duration::from_millis(1)).await);
1754                         exit_sender.send(()).unwrap();
1755                 });
1756                 let (r1, r2) = tokio::join!(t1, t2);
1757                 r1.unwrap().unwrap();
1758                 r2.unwrap()
1759         }
1760
1761         macro_rules! do_test_payment_path_scoring {
1762                 ($nodes: expr, $receive: expr) => {
1763                         // Ensure that we update the scorer when relevant events are processed. In this case, we ensure
1764                         // that we update the scorer upon a payment path succeeding (note that the channel must be
1765                         // public or else we won't score it).
1766                         // A background event handler for FundingGenerationReady events must be hooked up to a
1767                         // running background processor.
1768                         let scored_scid = 4242;
1769                         let secp_ctx = Secp256k1::new();
1770                         let node_1_privkey = SecretKey::from_slice(&[42; 32]).unwrap();
1771                         let node_1_id = PublicKey::from_secret_key(&secp_ctx, &node_1_privkey);
1772
1773                         let path = Path { hops: vec![RouteHop {
1774                                 pubkey: node_1_id,
1775                                 node_features: NodeFeatures::empty(),
1776                                 short_channel_id: scored_scid,
1777                                 channel_features: ChannelFeatures::empty(),
1778                                 fee_msat: 0,
1779                                 cltv_expiry_delta: MIN_CLTV_EXPIRY_DELTA as u32,
1780                                 maybe_announced_channel: true,
1781                         }], blinded_tail: None };
1782
1783                         $nodes[0].scorer.write_lock().expect(TestResult::PaymentFailure { path: path.clone(), short_channel_id: scored_scid });
1784                         $nodes[0].node.push_pending_event(Event::PaymentPathFailed {
1785                                 payment_id: None,
1786                                 payment_hash: PaymentHash([42; 32]),
1787                                 payment_failed_permanently: false,
1788                                 failure: PathFailure::OnPath { network_update: None },
1789                                 path: path.clone(),
1790                                 short_channel_id: Some(scored_scid),
1791                         });
1792                         let event = $receive.expect("PaymentPathFailed not handled within deadline");
1793                         match event {
1794                                 Event::PaymentPathFailed { .. } => {},
1795                                 _ => panic!("Unexpected event"),
1796                         }
1797
1798                         // Ensure we'll score payments that were explicitly failed back by the destination as
1799                         // ProbeSuccess.
1800                         $nodes[0].scorer.write_lock().expect(TestResult::ProbeSuccess { path: path.clone() });
1801                         $nodes[0].node.push_pending_event(Event::PaymentPathFailed {
1802                                 payment_id: None,
1803                                 payment_hash: PaymentHash([42; 32]),
1804                                 payment_failed_permanently: true,
1805                                 failure: PathFailure::OnPath { network_update: None },
1806                                 path: path.clone(),
1807                                 short_channel_id: None,
1808                         });
1809                         let event = $receive.expect("PaymentPathFailed not handled within deadline");
1810                         match event {
1811                                 Event::PaymentPathFailed { .. } => {},
1812                                 _ => panic!("Unexpected event"),
1813                         }
1814
1815                         $nodes[0].scorer.write_lock().expect(TestResult::PaymentSuccess { path: path.clone() });
1816                         $nodes[0].node.push_pending_event(Event::PaymentPathSuccessful {
1817                                 payment_id: PaymentId([42; 32]),
1818                                 payment_hash: None,
1819                                 path: path.clone(),
1820                         });
1821                         let event = $receive.expect("PaymentPathSuccessful not handled within deadline");
1822                         match event {
1823                                 Event::PaymentPathSuccessful { .. } => {},
1824                                 _ => panic!("Unexpected event"),
1825                         }
1826
1827                         $nodes[0].scorer.write_lock().expect(TestResult::ProbeSuccess { path: path.clone() });
1828                         $nodes[0].node.push_pending_event(Event::ProbeSuccessful {
1829                                 payment_id: PaymentId([42; 32]),
1830                                 payment_hash: PaymentHash([42; 32]),
1831                                 path: path.clone(),
1832                         });
1833                         let event = $receive.expect("ProbeSuccessful not handled within deadline");
1834                         match event {
1835                                 Event::ProbeSuccessful  { .. } => {},
1836                                 _ => panic!("Unexpected event"),
1837                         }
1838
1839                         $nodes[0].scorer.write_lock().expect(TestResult::ProbeFailure { path: path.clone() });
1840                         $nodes[0].node.push_pending_event(Event::ProbeFailed {
1841                                 payment_id: PaymentId([42; 32]),
1842                                 payment_hash: PaymentHash([42; 32]),
1843                                 path,
1844                                 short_channel_id: Some(scored_scid),
1845                         });
1846                         let event = $receive.expect("ProbeFailure not handled within deadline");
1847                         match event {
1848                                 Event::ProbeFailed { .. } => {},
1849                                 _ => panic!("Unexpected event"),
1850                         }
1851                 }
1852         }
1853
1854         #[test]
1855         fn test_payment_path_scoring() {
1856                 let (sender, receiver) = std::sync::mpsc::sync_channel(1);
1857                 let event_handler = move |event: Event| match event {
1858                         Event::PaymentPathFailed { .. } => sender.send(event).unwrap(),
1859                         Event::PaymentPathSuccessful { .. } => sender.send(event).unwrap(),
1860                         Event::ProbeSuccessful { .. } => sender.send(event).unwrap(),
1861                         Event::ProbeFailed { .. } => sender.send(event).unwrap(),
1862                         _ => panic!("Unexpected event: {:?}", event),
1863                 };
1864
1865                 let (_, nodes) = create_nodes(1, "test_payment_path_scoring");
1866                 let data_dir = nodes[0].kv_store.get_data_dir();
1867                 let persister = Arc::new(Persister::new(data_dir));
1868                 let bg_processor = BackgroundProcessor::start(persister, event_handler, nodes[0].chain_monitor.clone(), nodes[0].node.clone(), nodes[0].no_gossip_sync(), nodes[0].peer_manager.clone(), nodes[0].logger.clone(), Some(nodes[0].scorer.clone()));
1869
1870                 do_test_payment_path_scoring!(nodes, receiver.recv_timeout(Duration::from_secs(EVENT_DEADLINE)));
1871
1872                 if !std::thread::panicking() {
1873                         bg_processor.stop().unwrap();
1874                 }
1875
1876                 let log_entries = nodes[0].logger.lines.lock().unwrap();
1877                 let expected_log = "Persisting scorer after update".to_string();
1878                 assert_eq!(*log_entries.get(&("lightning_background_processor", expected_log)).unwrap(), 5);
1879         }
1880
1881         #[tokio::test]
1882         #[cfg(feature = "futures")]
1883         async fn test_payment_path_scoring_async() {
1884                 let (sender, mut receiver) = tokio::sync::mpsc::channel(1);
1885                 let event_handler = move |event: Event| {
1886                         let sender_ref = sender.clone();
1887                         async move {
1888                                 match event {
1889                                         Event::PaymentPathFailed { .. } => { sender_ref.send(event).await.unwrap() },
1890                                         Event::PaymentPathSuccessful { .. } => { sender_ref.send(event).await.unwrap() },
1891                                         Event::ProbeSuccessful { .. } => { sender_ref.send(event).await.unwrap() },
1892                                         Event::ProbeFailed { .. } => { sender_ref.send(event).await.unwrap() },
1893                                         _ => panic!("Unexpected event: {:?}", event),
1894                                 }
1895                         }
1896                 };
1897
1898                 let (_, nodes) = create_nodes(1, "test_payment_path_scoring_async");
1899                 let data_dir = nodes[0].kv_store.get_data_dir();
1900                 let persister = Arc::new(Persister::new(data_dir));
1901
1902                 let (exit_sender, exit_receiver) = tokio::sync::watch::channel(());
1903
1904                 let bp_future = super::process_events_async(
1905                         persister, event_handler, nodes[0].chain_monitor.clone(), nodes[0].node.clone(),
1906                         nodes[0].no_gossip_sync(), nodes[0].peer_manager.clone(), nodes[0].logger.clone(),
1907                         Some(nodes[0].scorer.clone()), move |dur: Duration| {
1908                                 let mut exit_receiver = exit_receiver.clone();
1909                                 Box::pin(async move {
1910                                         tokio::select! {
1911                                                 _ = tokio::time::sleep(dur) => false,
1912                                                 _ = exit_receiver.changed() => true,
1913                                         }
1914                                 })
1915                         }, false, || Some(Duration::ZERO),
1916                 );
1917                 let t1 = tokio::spawn(bp_future);
1918                 let t2 = tokio::spawn(async move {
1919                         do_test_payment_path_scoring!(nodes, receiver.recv().await);
1920                         exit_sender.send(()).unwrap();
1921
1922                         let log_entries = nodes[0].logger.lines.lock().unwrap();
1923                         let expected_log = "Persisting scorer after update".to_string();
1924                         assert_eq!(*log_entries.get(&("lightning_background_processor", expected_log)).unwrap(), 5);
1925                 });
1926
1927                 let (r1, r2) = tokio::join!(t1, t2);
1928                 r1.unwrap().unwrap();
1929                 r2.unwrap()
1930         }
1931 }