]> git.bitcoin.ninja Git - rust-lightning/blob - fuzz/src/full_stack.rs
Merge pull request #1695 from TheBlueMatt/2022-08-log-chan_update
[rust-lightning] / fuzz / src / full_stack.rs
1 // This file is Copyright its original authors, visible in version control
2 // history.
3 //
4 // This file is licensed under the Apache License, Version 2.0 <LICENSE-APACHE
5 // or http://www.apache.org/licenses/LICENSE-2.0> or the MIT license
6 // <LICENSE-MIT or http://opensource.org/licenses/MIT>, at your option.
7 // You may not use this file except in accordance with one or both of these
8 // licenses.
9
10 //! Test that no series of bytes received over the wire/connections created/payments sent can
11 //! result in a crash. We do this by standing up a node and then reading bytes from input to denote
12 //! actions such as creating new inbound/outbound connections, bytes to be read from a connection,
13 //! or payments to send/ways to handle events generated.
14 //! This test has been very useful, though due to its complexity good starting inputs are critical.
15
16 use bitcoin::TxMerkleNode;
17 use bitcoin::blockdata::block::BlockHeader;
18 use bitcoin::blockdata::constants::genesis_block;
19 use bitcoin::blockdata::transaction::{Transaction, TxOut};
20 use bitcoin::blockdata::script::{Builder, Script};
21 use bitcoin::blockdata::opcodes;
22 use bitcoin::blockdata::locktime::PackedLockTime;
23 use bitcoin::consensus::encode::deserialize;
24 use bitcoin::network::constants::Network;
25
26 use bitcoin::hashes::Hash as TraitImport;
27 use bitcoin::hashes::HashEngine as TraitImportEngine;
28 use bitcoin::hashes::sha256::Hash as Sha256;
29 use bitcoin::hash_types::{Txid, BlockHash, WPubkeyHash};
30
31 use lightning::chain;
32 use lightning::chain::{BestBlock, Confirm, Listen};
33 use lightning::chain::chaininterface::{BroadcasterInterface, ConfirmationTarget, FeeEstimator};
34 use lightning::chain::chainmonitor;
35 use lightning::chain::transaction::OutPoint;
36 use lightning::chain::keysinterface::{InMemorySigner, Recipient, KeyMaterial, KeysInterface};
37 use lightning::ln::{PaymentHash, PaymentPreimage, PaymentSecret};
38 use lightning::ln::channelmanager::{ChainParameters, ChannelManager};
39 use lightning::ln::peer_handler::{MessageHandler,PeerManager,SocketDescriptor,IgnoringMessageHandler};
40 use lightning::ln::msgs::DecodeError;
41 use lightning::ln::script::ShutdownScript;
42 use lightning::routing::gossip::{P2PGossipSync, NetworkGraph};
43 use lightning::routing::router::{find_route, PaymentParameters, RouteParameters};
44 use lightning::routing::scoring::FixedPenaltyScorer;
45 use lightning::util::config::UserConfig;
46 use lightning::util::errors::APIError;
47 use lightning::util::events::Event;
48 use lightning::util::enforcing_trait_impls::{EnforcingSigner, EnforcementState};
49 use lightning::util::logger::Logger;
50 use lightning::util::ser::ReadableArgs;
51
52 use utils::test_logger;
53 use utils::test_persister::TestPersister;
54
55 use bitcoin::secp256k1::{PublicKey, SecretKey, Scalar};
56 use bitcoin::secp256k1::ecdh::SharedSecret;
57 use bitcoin::secp256k1::ecdsa::RecoverableSignature;
58 use bitcoin::secp256k1::Secp256k1;
59
60 use std::cell::RefCell;
61 use std::collections::{HashMap, hash_map};
62 use std::convert::TryInto;
63 use std::cmp;
64 use std::sync::{Arc, Mutex};
65 use std::sync::atomic::{AtomicU64,AtomicUsize,Ordering};
66 use bitcoin::bech32::u5;
67
68 #[inline]
69 pub fn slice_to_be16(v: &[u8]) -> u16 {
70         ((v[0] as u16) << 8*1) |
71         ((v[1] as u16) << 8*0)
72 }
73
74 #[inline]
75 pub fn slice_to_be24(v: &[u8]) -> u32 {
76         ((v[0] as u32) << 8*2) |
77         ((v[1] as u32) << 8*1) |
78         ((v[2] as u32) << 8*0)
79 }
80
81 #[inline]
82 pub fn slice_to_be32(v: &[u8]) -> u32 {
83         ((v[0] as u32) << 8*3) |
84         ((v[1] as u32) << 8*2) |
85         ((v[2] as u32) << 8*1) |
86         ((v[3] as u32) << 8*0)
87 }
88
89 #[inline]
90 pub fn be64_to_array(u: u64) -> [u8; 8] {
91         let mut v = [0; 8];
92         v[0] = ((u >> 8*7) & 0xff) as u8;
93         v[1] = ((u >> 8*6) & 0xff) as u8;
94         v[2] = ((u >> 8*5) & 0xff) as u8;
95         v[3] = ((u >> 8*4) & 0xff) as u8;
96         v[4] = ((u >> 8*3) & 0xff) as u8;
97         v[5] = ((u >> 8*2) & 0xff) as u8;
98         v[6] = ((u >> 8*1) & 0xff) as u8;
99         v[7] = ((u >> 8*0) & 0xff) as u8;
100         v
101 }
102
103 struct InputData {
104         data: Vec<u8>,
105         read_pos: AtomicUsize,
106 }
107 impl InputData {
108         fn get_slice(&self, len: usize) -> Option<&[u8]> {
109                 let old_pos = self.read_pos.fetch_add(len, Ordering::AcqRel);
110                 if self.data.len() < old_pos + len {
111                         return None;
112                 }
113                 Some(&self.data[old_pos..old_pos + len])
114         }
115 }
116
117 struct FuzzEstimator {
118         input: Arc<InputData>,
119 }
120 impl FeeEstimator for FuzzEstimator {
121         fn get_est_sat_per_1000_weight(&self, _: ConfirmationTarget) -> u32 {
122                 //TODO: We should actually be testing at least much more than 64k...
123                 match self.input.get_slice(2) {
124                         Some(slice) => cmp::max(slice_to_be16(slice) as u32, 253),
125                         None => 253
126                 }
127         }
128 }
129
130 struct TestBroadcaster {
131         txn_broadcasted: Mutex<Vec<Transaction>>,
132 }
133 impl BroadcasterInterface for TestBroadcaster {
134         fn broadcast_transaction(&self, tx: &Transaction) {
135                 self.txn_broadcasted.lock().unwrap().push(tx.clone());
136         }
137 }
138
139 #[derive(Clone)]
140 struct Peer<'a> {
141         id: u8,
142         peers_connected: &'a RefCell<[bool; 256]>,
143 }
144 impl<'a> SocketDescriptor for Peer<'a> {
145         fn send_data(&mut self, data: &[u8], _resume_read: bool) -> usize {
146                 data.len()
147         }
148         fn disconnect_socket(&mut self) {
149                 assert!(self.peers_connected.borrow()[self.id as usize]);
150                 self.peers_connected.borrow_mut()[self.id as usize] = false;
151         }
152 }
153 impl<'a> PartialEq for Peer<'a> {
154         fn eq(&self, other: &Self) -> bool {
155                 self.id == other.id
156         }
157 }
158 impl<'a> Eq for Peer<'a> {}
159 impl<'a> std::hash::Hash for Peer<'a> {
160         fn hash<H : std::hash::Hasher>(&self, h: &mut H) {
161                 self.id.hash(h)
162         }
163 }
164
165 type ChannelMan = ChannelManager<
166         EnforcingSigner,
167         Arc<chainmonitor::ChainMonitor<EnforcingSigner, Arc<dyn chain::Filter>, Arc<TestBroadcaster>, Arc<FuzzEstimator>, Arc<dyn Logger>, Arc<TestPersister>>>,
168         Arc<TestBroadcaster>, Arc<KeyProvider>, Arc<FuzzEstimator>, Arc<dyn Logger>>;
169 type PeerMan<'a> = PeerManager<Peer<'a>, Arc<ChannelMan>, Arc<P2PGossipSync<Arc<NetworkGraph<Arc<dyn Logger>>>, Arc<dyn chain::Access>, Arc<dyn Logger>>>, IgnoringMessageHandler, Arc<dyn Logger>, IgnoringMessageHandler>;
170
171 struct MoneyLossDetector<'a> {
172         manager: Arc<ChannelMan>,
173         monitor: Arc<chainmonitor::ChainMonitor<EnforcingSigner, Arc<dyn chain::Filter>, Arc<TestBroadcaster>, Arc<FuzzEstimator>, Arc<dyn Logger>, Arc<TestPersister>>>,
174         handler: PeerMan<'a>,
175
176         peers: &'a RefCell<[bool; 256]>,
177         funding_txn: Vec<Transaction>,
178         txids_confirmed: HashMap<Txid, usize>,
179         header_hashes: Vec<(BlockHash, u32)>,
180         height: usize,
181         max_height: usize,
182         blocks_connected: u32,
183 }
184 impl<'a> MoneyLossDetector<'a> {
185         pub fn new(peers: &'a RefCell<[bool; 256]>,
186                    manager: Arc<ChannelMan>,
187                    monitor: Arc<chainmonitor::ChainMonitor<EnforcingSigner, Arc<dyn chain::Filter>, Arc<TestBroadcaster>, Arc<FuzzEstimator>, Arc<dyn Logger>, Arc<TestPersister>>>,
188                    handler: PeerMan<'a>) -> Self {
189                 MoneyLossDetector {
190                         manager,
191                         monitor,
192                         handler,
193
194                         peers,
195                         funding_txn: Vec::new(),
196                         txids_confirmed: HashMap::new(),
197                         header_hashes: vec![(genesis_block(Network::Bitcoin).block_hash(), 0)],
198                         height: 0,
199                         max_height: 0,
200                         blocks_connected: 0,
201                 }
202         }
203
204         fn connect_block(&mut self, all_txn: &[Transaction]) {
205                 let mut txdata = Vec::with_capacity(all_txn.len());
206                 for (idx, tx) in all_txn.iter().enumerate() {
207                         let txid = tx.txid();
208                         match self.txids_confirmed.entry(txid) {
209                                 hash_map::Entry::Vacant(e) => {
210                                         e.insert(self.height);
211                                         txdata.push((idx + 1, tx));
212                                 },
213                                 _ => {},
214                         }
215                 }
216
217                 self.blocks_connected += 1;
218                 let header = BlockHeader { version: 0x20000000, prev_blockhash: self.header_hashes[self.height].0, merkle_root: TxMerkleNode::all_zeros(), time: self.blocks_connected, bits: 42, nonce: 42 };
219                 self.height += 1;
220                 self.manager.transactions_confirmed(&header, &txdata, self.height as u32);
221                 self.manager.best_block_updated(&header, self.height as u32);
222                 (*self.monitor).transactions_confirmed(&header, &txdata, self.height as u32);
223                 (*self.monitor).best_block_updated(&header, self.height as u32);
224                 if self.header_hashes.len() > self.height {
225                         self.header_hashes[self.height] = (header.block_hash(), self.blocks_connected);
226                 } else {
227                         assert_eq!(self.header_hashes.len(), self.height);
228                         self.header_hashes.push((header.block_hash(), self.blocks_connected));
229                 }
230                 self.max_height = cmp::max(self.height, self.max_height);
231         }
232
233         fn disconnect_block(&mut self) {
234                 if self.height > 0 && (self.max_height < 6 || self.height >= self.max_height - 6) {
235                         let header = BlockHeader { version: 0x20000000, prev_blockhash: self.header_hashes[self.height - 1].0, merkle_root: TxMerkleNode::all_zeros(), time: self.header_hashes[self.height].1, bits: 42, nonce: 42 };
236                         self.manager.block_disconnected(&header, self.height as u32);
237                         self.monitor.block_disconnected(&header, self.height as u32);
238                         self.height -= 1;
239                         let removal_height = self.height;
240                         self.txids_confirmed.retain(|_, height| {
241                                 removal_height != *height
242                         });
243                 }
244         }
245 }
246
247 impl<'a> Drop for MoneyLossDetector<'a> {
248         fn drop(&mut self) {
249                 if !::std::thread::panicking() {
250                         // Disconnect all peers
251                         for (idx, peer) in self.peers.borrow().iter().enumerate() {
252                                 if *peer {
253                                         self.handler.socket_disconnected(&Peer{id: idx as u8, peers_connected: &self.peers});
254                                 }
255                         }
256
257                         // Force all channels onto the chain (and time out claim txn)
258                         self.manager.force_close_all_channels_broadcasting_latest_txn();
259                 }
260         }
261 }
262
263 struct KeyProvider {
264         node_secret: SecretKey,
265         inbound_payment_key: KeyMaterial,
266         counter: AtomicU64,
267 }
268 impl KeysInterface for KeyProvider {
269         type Signer = EnforcingSigner;
270
271         fn get_node_secret(&self, _recipient: Recipient) -> Result<SecretKey, ()> {
272                 Ok(self.node_secret.clone())
273         }
274
275         fn ecdh(&self, recipient: Recipient, other_key: &PublicKey, tweak: Option<&Scalar>) -> Result<SharedSecret, ()> {
276                 let mut node_secret = self.get_node_secret(recipient)?;
277                 if let Some(tweak) = tweak {
278                         node_secret = node_secret.mul_tweak(tweak).unwrap();
279                 }
280                 Ok(SharedSecret::new(other_key, &node_secret))
281         }
282
283         fn get_inbound_payment_key_material(&self) -> KeyMaterial {
284                 self.inbound_payment_key.clone()
285         }
286
287         fn get_destination_script(&self) -> Script {
288                 let secp_ctx = Secp256k1::signing_only();
289                 let channel_monitor_claim_key = SecretKey::from_slice(&hex::decode("0fffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff").unwrap()[..]).unwrap();
290                 let our_channel_monitor_claim_key_hash = WPubkeyHash::hash(&PublicKey::from_secret_key(&secp_ctx, &channel_monitor_claim_key).serialize());
291                 Builder::new().push_opcode(opcodes::all::OP_PUSHBYTES_0).push_slice(&our_channel_monitor_claim_key_hash[..]).into_script()
292         }
293
294         fn get_shutdown_scriptpubkey(&self) -> ShutdownScript {
295                 let secp_ctx = Secp256k1::signing_only();
296                 let secret_key = SecretKey::from_slice(&[0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 1]).unwrap();
297                 let pubkey_hash = WPubkeyHash::hash(&PublicKey::from_secret_key(&secp_ctx, &secret_key).serialize());
298                 ShutdownScript::new_p2wpkh(&pubkey_hash)
299         }
300
301         fn get_channel_signer(&self, inbound: bool, channel_value_satoshis: u64) -> EnforcingSigner {
302                 let ctr = self.counter.fetch_add(1, Ordering::Relaxed) as u8;
303                 let secp_ctx = Secp256k1::signing_only();
304                 EnforcingSigner::new(if inbound {
305                         InMemorySigner::new(
306                                 &secp_ctx,
307                                 self.node_secret.clone(),
308                                 SecretKey::from_slice(&[0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 1, ctr]).unwrap(),
309                                 SecretKey::from_slice(&[0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 2, ctr]).unwrap(),
310                                 SecretKey::from_slice(&[0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 3, ctr]).unwrap(),
311                                 SecretKey::from_slice(&[0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 4, ctr]).unwrap(),
312                                 SecretKey::from_slice(&[0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 5, ctr]).unwrap(),
313                                 [0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 6, ctr],
314                                 channel_value_satoshis,
315                                 [0; 32]
316                         )
317                 } else {
318                         InMemorySigner::new(
319                                 &secp_ctx,
320                                 self.node_secret.clone(),
321                                 SecretKey::from_slice(&[0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 7, ctr]).unwrap(),
322                                 SecretKey::from_slice(&[0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 8, ctr]).unwrap(),
323                                 SecretKey::from_slice(&[0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 9, ctr]).unwrap(),
324                                 SecretKey::from_slice(&[0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 10, ctr]).unwrap(),
325                                 SecretKey::from_slice(&[0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 11, ctr]).unwrap(),
326                                 [0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 12, ctr],
327                                 channel_value_satoshis,
328                                 [0; 32]
329                         )
330                 })
331         }
332
333         fn get_secure_random_bytes(&self) -> [u8; 32] {
334                 let ctr = self.counter.fetch_add(1, Ordering::Relaxed);
335                 [0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0,
336                 (ctr >> 8*7) as u8, (ctr >> 8*6) as u8, (ctr >> 8*5) as u8, (ctr >> 8*4) as u8, (ctr >> 8*3) as u8, (ctr >> 8*2) as u8, (ctr >> 8*1) as u8, 14, (ctr >> 8*0) as u8]
337         }
338
339         fn read_chan_signer(&self, mut data: &[u8]) -> Result<EnforcingSigner, DecodeError> {
340                 let inner: InMemorySigner = ReadableArgs::read(&mut data, self.node_secret.clone())?;
341                 let state = Arc::new(Mutex::new(EnforcementState::new()));
342
343                 Ok(EnforcingSigner::new_with_revoked(
344                         inner,
345                         state,
346                         false
347                 ))
348         }
349
350         fn sign_invoice(&self, _hrp_bytes: &[u8], _invoice_data: &[u5], _recipient: Recipient) -> Result<RecoverableSignature, ()> {
351                 unreachable!()
352         }
353 }
354
355 #[inline]
356 pub fn do_test(data: &[u8], logger: &Arc<dyn Logger>) {
357         let input = Arc::new(InputData {
358                 data: data.to_vec(),
359                 read_pos: AtomicUsize::new(0),
360         });
361         let fee_est = Arc::new(FuzzEstimator {
362                 input: input.clone(),
363         });
364
365         macro_rules! get_slice {
366                 ($len: expr) => {
367                         match input.get_slice($len as usize) {
368                                 Some(slice) => slice,
369                                 None => return,
370                         }
371                 }
372         }
373
374         macro_rules! get_pubkey {
375                 () => {
376                         match PublicKey::from_slice(get_slice!(33)) {
377                                 Ok(key) => key,
378                                 Err(_) => return,
379                         }
380                 }
381         }
382
383         let our_network_key = match SecretKey::from_slice(get_slice!(32)) {
384                 Ok(key) => key,
385                 Err(_) => return,
386         };
387
388         let inbound_payment_key = [0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 42];
389
390         let broadcast = Arc::new(TestBroadcaster{ txn_broadcasted: Mutex::new(Vec::new()) });
391         let monitor = Arc::new(chainmonitor::ChainMonitor::new(None, broadcast.clone(), Arc::clone(&logger), fee_est.clone(),
392                 Arc::new(TestPersister { update_ret: Mutex::new(Ok(())) })));
393
394         let keys_manager = Arc::new(KeyProvider { node_secret: our_network_key.clone(), inbound_payment_key: KeyMaterial(inbound_payment_key.try_into().unwrap()), counter: AtomicU64::new(0) });
395         let mut config = UserConfig::default();
396         config.channel_config.forwarding_fee_proportional_millionths =  slice_to_be32(get_slice!(4));
397         config.channel_handshake_config.announced_channel = get_slice!(1)[0] != 0;
398         let network = Network::Bitcoin;
399         let params = ChainParameters {
400                 network,
401                 best_block: BestBlock::from_genesis(network),
402         };
403         let channelmanager = Arc::new(ChannelManager::new(fee_est.clone(), monitor.clone(), broadcast.clone(), Arc::clone(&logger), keys_manager.clone(), config, params));
404         // Adding new calls to `KeysInterface::get_secure_random_bytes` during startup can change all the
405         // keys subsequently generated in this test. Rather than regenerating all the messages manually,
406         // it's easier to just increment the counter here so the keys don't change.
407         keys_manager.counter.fetch_sub(2, Ordering::AcqRel);
408         let our_id = PublicKey::from_secret_key(&Secp256k1::signing_only(), &keys_manager.get_node_secret(Recipient::Node).unwrap());
409         let network_graph = Arc::new(NetworkGraph::new(genesis_block(network).block_hash(), Arc::clone(&logger)));
410         let gossip_sync = Arc::new(P2PGossipSync::new(Arc::clone(&network_graph), None, Arc::clone(&logger)));
411         let scorer = FixedPenaltyScorer::with_penalty(0);
412
413         let peers = RefCell::new([false; 256]);
414         let mut loss_detector = MoneyLossDetector::new(&peers, channelmanager.clone(), monitor.clone(), PeerManager::new(MessageHandler {
415                 chan_handler: channelmanager.clone(),
416                 route_handler: gossip_sync.clone(),
417                 onion_message_handler: IgnoringMessageHandler {},
418         }, our_network_key, &[0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 15, 0], Arc::clone(&logger), IgnoringMessageHandler{}));
419
420         let mut should_forward = false;
421         let mut payments_received: Vec<PaymentHash> = Vec::new();
422         let mut payments_sent = 0;
423         let mut pending_funding_generation: Vec<([u8; 32], PublicKey, u64, Script)> = Vec::new();
424         let mut pending_funding_signatures = HashMap::new();
425
426         loop {
427                 match get_slice!(1)[0] {
428                         0 => {
429                                 let mut new_id = 0;
430                                 for i in 1..256 {
431                                         if !peers.borrow()[i-1] {
432                                                 new_id = i;
433                                                 break;
434                                         }
435                                 }
436                                 if new_id == 0 { return; }
437                                 loss_detector.handler.new_outbound_connection(get_pubkey!(), Peer{id: (new_id - 1) as u8, peers_connected: &peers}, None).unwrap();
438                                 peers.borrow_mut()[new_id - 1] = true;
439                         },
440                         1 => {
441                                 let mut new_id = 0;
442                                 for i in 1..256 {
443                                         if !peers.borrow()[i-1] {
444                                                 new_id = i;
445                                                 break;
446                                         }
447                                 }
448                                 if new_id == 0 { return; }
449                                 loss_detector.handler.new_inbound_connection(Peer{id: (new_id - 1) as u8, peers_connected: &peers}, None).unwrap();
450                                 peers.borrow_mut()[new_id - 1] = true;
451                         },
452                         2 => {
453                                 let peer_id = get_slice!(1)[0];
454                                 if !peers.borrow()[peer_id as usize] { return; }
455                                 loss_detector.handler.socket_disconnected(&Peer{id: peer_id, peers_connected: &peers});
456                                 peers.borrow_mut()[peer_id as usize] = false;
457                         },
458                         3 => {
459                                 let peer_id = get_slice!(1)[0];
460                                 if !peers.borrow()[peer_id as usize] { return; }
461                                 match loss_detector.handler.read_event(&mut Peer{id: peer_id, peers_connected: &peers}, get_slice!(get_slice!(1)[0])) {
462                                         Ok(res) => assert!(!res),
463                                         Err(_) => { peers.borrow_mut()[peer_id as usize] = false; }
464                                 }
465                         },
466                         4 => {
467                                 let final_value_msat = slice_to_be24(get_slice!(3)) as u64;
468                                 let payment_params = PaymentParameters::from_node_id(get_pubkey!());
469                                 let params = RouteParameters {
470                                         payment_params,
471                                         final_value_msat,
472                                         final_cltv_expiry_delta: 42,
473                                 };
474                                 let random_seed_bytes: [u8; 32] = keys_manager.get_secure_random_bytes();
475                                 let route = match find_route(&our_id, &params, &network_graph, None, Arc::clone(&logger), &scorer, &random_seed_bytes) {
476                                         Ok(route) => route,
477                                         Err(_) => return,
478                                 };
479                                 let mut payment_hash = PaymentHash([0; 32]);
480                                 payment_hash.0[0..8].copy_from_slice(&be64_to_array(payments_sent));
481                                 let mut sha = Sha256::engine();
482                                 sha.input(&payment_hash.0[..]);
483                                 payment_hash.0 = Sha256::from_engine(sha).into_inner();
484                                 payments_sent += 1;
485                                 match channelmanager.send_payment(&route, payment_hash, &None) {
486                                         Ok(_) => {},
487                                         Err(_) => return,
488                                 }
489                         },
490                         15 => {
491                                 let final_value_msat = slice_to_be24(get_slice!(3)) as u64;
492                                 let payment_params = PaymentParameters::from_node_id(get_pubkey!());
493                                 let params = RouteParameters {
494                                         payment_params,
495                                         final_value_msat,
496                                         final_cltv_expiry_delta: 42,
497                                 };
498                                 let random_seed_bytes: [u8; 32] = keys_manager.get_secure_random_bytes();
499                                 let mut route = match find_route(&our_id, &params, &network_graph, None, Arc::clone(&logger), &scorer, &random_seed_bytes) {
500                                         Ok(route) => route,
501                                         Err(_) => return,
502                                 };
503                                 route.paths.push(route.paths[0].clone());
504                                 let mut payment_hash = PaymentHash([0; 32]);
505                                 payment_hash.0[0..8].copy_from_slice(&be64_to_array(payments_sent));
506                                 let mut sha = Sha256::engine();
507                                 sha.input(&payment_hash.0[..]);
508                                 payment_hash.0 = Sha256::from_engine(sha).into_inner();
509                                 payments_sent += 1;
510                                 let mut payment_secret = PaymentSecret([0; 32]);
511                                 payment_secret.0[0..8].copy_from_slice(&be64_to_array(payments_sent));
512                                 payments_sent += 1;
513                                 match channelmanager.send_payment(&route, payment_hash, &Some(payment_secret)) {
514                                         Ok(_) => {},
515                                         Err(_) => return,
516                                 }
517                         },
518                         5 => {
519                                 let peer_id = get_slice!(1)[0];
520                                 if !peers.borrow()[peer_id as usize] { return; }
521                                 let their_key = get_pubkey!();
522                                 let chan_value = slice_to_be24(get_slice!(3)) as u64;
523                                 let push_msat_value = slice_to_be24(get_slice!(3)) as u64;
524                                 if channelmanager.create_channel(their_key, chan_value, push_msat_value, 0, None).is_err() { return; }
525                         },
526                         6 => {
527                                 let mut channels = channelmanager.list_channels();
528                                 let channel_id = get_slice!(1)[0] as usize;
529                                 if channel_id >= channels.len() { return; }
530                                 channels.sort_by(|a, b| { a.channel_id.cmp(&b.channel_id) });
531                                 if channelmanager.close_channel(&channels[channel_id].channel_id, &channels[channel_id].counterparty.node_id).is_err() { return; }
532                         },
533                         7 => {
534                                 if should_forward {
535                                         channelmanager.process_pending_htlc_forwards();
536                                         should_forward = false;
537                                 }
538                         },
539                         8 => {
540                                 for payment in payments_received.drain(..) {
541                                         // SHA256 is defined as XOR of all input bytes placed in the first byte, and 0s
542                                         // for the remaining bytes. Thus, if not all remaining bytes are 0s we cannot
543                                         // fulfill this HTLC, but if they are, we can just take the first byte and
544                                         // place that anywhere in our preimage.
545                                         if &payment.0[1..] != &[0; 31] {
546                                                 channelmanager.fail_htlc_backwards(&payment);
547                                         } else {
548                                                 let mut payment_preimage = PaymentPreimage([0; 32]);
549                                                 payment_preimage.0[0] = payment.0[0];
550                                                 channelmanager.claim_funds(payment_preimage);
551                                         }
552                                 }
553                         },
554                         16 => {
555                                 let payment_preimage = PaymentPreimage(keys_manager.get_secure_random_bytes());
556                                 let mut sha = Sha256::engine();
557                                 sha.input(&payment_preimage.0[..]);
558                                 let payment_hash = PaymentHash(Sha256::from_engine(sha).into_inner());
559                                 // Note that this may fail - our hashes may collide and we'll end up trying to
560                                 // double-register the same payment_hash.
561                                 let _ = channelmanager.create_inbound_payment_for_hash(payment_hash, None, 1);
562                         },
563                         9 => {
564                                 for payment in payments_received.drain(..) {
565                                         channelmanager.fail_htlc_backwards(&payment);
566                                 }
567                         },
568                         10 => {
569                                 'outer_loop: for funding_generation in pending_funding_generation.drain(..) {
570                                         let mut tx = Transaction { version: 0, lock_time: PackedLockTime::ZERO, input: Vec::new(), output: vec![TxOut {
571                                                         value: funding_generation.2, script_pubkey: funding_generation.3,
572                                                 }] };
573                                         let funding_output = 'search_loop: loop {
574                                                 let funding_txid = tx.txid();
575                                                 if let None = loss_detector.txids_confirmed.get(&funding_txid) {
576                                                         let outpoint = OutPoint { txid: funding_txid, index: 0 };
577                                                         for chan in channelmanager.list_channels() {
578                                                                 if chan.channel_id == outpoint.to_channel_id() {
579                                                                         tx.version += 1;
580                                                                         continue 'search_loop;
581                                                                 }
582                                                         }
583                                                         break outpoint;
584                                                 }
585                                                 tx.version += 1;
586                                                 if tx.version > 0xff {
587                                                         continue 'outer_loop;
588                                                 }
589                                         };
590                                         if let Err(e) = channelmanager.funding_transaction_generated(&funding_generation.0, &funding_generation.1, tx.clone()) {
591                                                 // It's possible the channel has been closed in the mean time, but any other
592                                                 // failure may be a bug.
593                                                 if let APIError::ChannelUnavailable { err } = e {
594                                                         assert_eq!(err, "No such channel");
595                                                 } else { panic!(); }
596                                         }
597                                         pending_funding_signatures.insert(funding_output, tx);
598                                 }
599                         },
600                         11 => {
601                                 let mut txn = broadcast.txn_broadcasted.lock().unwrap().split_off(0);
602                                 if !txn.is_empty() {
603                                         loss_detector.connect_block(&txn[..]);
604                                         for _ in 2..100 {
605                                                 loss_detector.connect_block(&[]);
606                                         }
607                                 }
608                                 for tx in txn.drain(..) {
609                                         loss_detector.funding_txn.push(tx);
610                                 }
611                         },
612                         12 => {
613                                 let txlen = slice_to_be16(get_slice!(2));
614                                 if txlen == 0 {
615                                         loss_detector.connect_block(&[]);
616                                 } else {
617                                         let txres: Result<Transaction, _> = deserialize(get_slice!(txlen));
618                                         if let Ok(tx) = txres {
619                                                 let mut output_val = 0;
620                                                 for out in tx.output.iter() {
621                                                         if out.value > 21_000_000_0000_0000 { return; }
622                                                         output_val += out.value;
623                                                         if output_val > 21_000_000_0000_0000 { return; }
624                                                 }
625                                                 loss_detector.connect_block(&[tx]);
626                                         } else {
627                                                 return;
628                                         }
629                                 }
630                         },
631                         13 => {
632                                 loss_detector.disconnect_block();
633                         },
634                         14 => {
635                                 let mut channels = channelmanager.list_channels();
636                                 let channel_id = get_slice!(1)[0] as usize;
637                                 if channel_id >= channels.len() { return; }
638                                 channels.sort_by(|a, b| { a.channel_id.cmp(&b.channel_id) });
639                                 channelmanager.force_close_broadcasting_latest_txn(&channels[channel_id].channel_id, &channels[channel_id].counterparty.node_id).unwrap();
640                         },
641                         // 15 is above
642                         _ => return,
643                 }
644                 loss_detector.handler.process_events();
645                 for event in loss_detector.manager.get_and_clear_pending_events() {
646                         match event {
647                                 Event::FundingGenerationReady { temporary_channel_id, counterparty_node_id, channel_value_satoshis, output_script, .. } => {
648                                         pending_funding_generation.push((temporary_channel_id, counterparty_node_id, channel_value_satoshis, output_script));
649                                 },
650                                 Event::PaymentReceived { payment_hash, .. } => {
651                                         //TODO: enhance by fetching random amounts from fuzz input?
652                                         payments_received.push(payment_hash);
653                                 },
654                                 Event::PendingHTLCsForwardable {..} => {
655                                         should_forward = true;
656                                 },
657                                 _ => {},
658                         }
659                 }
660         }
661 }
662
663 pub fn full_stack_test<Out: test_logger::Output>(data: &[u8], out: Out) {
664         let logger: Arc<dyn Logger> = Arc::new(test_logger::TestLogger::new("".to_owned(), out));
665         do_test(data, &logger);
666 }
667
668 #[no_mangle]
669 pub extern "C" fn full_stack_run(data: *const u8, datalen: usize) {
670         let logger: Arc<dyn Logger> = Arc::new(test_logger::TestLogger::new("".to_owned(), test_logger::DevNull {}));
671         do_test(unsafe { std::slice::from_raw_parts(data, datalen) }, &logger);
672 }
673
674 #[cfg(test)]
675 mod tests {
676         use lightning::util::logger::{Logger, Record};
677         use std::collections::HashMap;
678         use std::sync::{Arc, Mutex};
679
680         struct TrackingLogger {
681                 /// (module, message) -> count
682                 pub lines: Mutex<HashMap<(String, String), usize>>,
683         }
684         impl Logger for TrackingLogger {
685                 fn log(&self, record: &Record) {
686                         *self.lines.lock().unwrap().entry((record.module_path.to_string(), format!("{}", record.args))).or_insert(0) += 1;
687                         println!("{:<5} [{} : {}, {}] {}", record.level.to_string(), record.module_path, record.file, record.line, record.args);
688                 }
689         }
690
691         #[test]
692         fn test_no_existing_test_breakage() {
693                 // To avoid accidentally causing all existing fuzz test cases to be useless by making minor
694                 // changes (such as requesting feerate info in a new place), we run a pretty full
695                 // step-through with two peers and HTLC forwarding here. Obviously this is pretty finicky,
696                 // so this should be updated pretty liberally, but at least we'll know when changes occur.
697                 // If nothing else, this test serves as a pretty great initial full_stack_target seed.
698
699                 // What each byte represents is broken down below, and then everything is concatenated into
700                 // one large test at the end (you want %s/ -.*//g %s/\n\| \|\t\|\///g).
701
702                 // Following BOLT 8, lightning message on the wire are: 2-byte encrypted message length +
703                 // 16-byte MAC of the encrypted message length + encrypted Lightning message + 16-byte MAC
704                 // of the Lightning message
705                 // I.e 2nd inbound read, len 18 : 0006 (encrypted message length) + 03000000000000000000000000000000 (MAC of the encrypted message length)
706                 // Len 22 : 0010 00000000 (encrypted lightning message) + 03000000000000000000000000000000 (MAC of the Lightning message)
707
708                 // Writing new code generating transactions and see a new failure ? Don't forget to add input for the FuzzEstimator !
709
710                 // 0100000000000000000000000000000000000000000000000000000000000000 - our network key
711                 // 00000000 - fee_proportional_millionths
712                 // 01 - announce_channels_publicly
713                 //
714                 // 00 - new outbound connection with id 0
715                 // 030000000000000000000000000000000000000000000000000000000000000002 - peer's pubkey
716                 // 030032 - inbound read from peer id 0 of len 50
717                 // 00 030000000000000000000000000000000000000000000000000000000000000002 03000000000000000000000000000000 - noise act two (0||pubkey||mac)
718                 //
719                 // 030012 - inbound read from peer id 0 of len 18
720                 // 000a 03000000000000000000000000000000 - message header indicating message length 10
721                 // 03001a - inbound read from peer id 0 of len 26
722                 // 0010 00022000 00022000 03000000000000000000000000000000 - init message (type 16) with static_remotekey (0x2000) and mac
723                 //
724                 // 030012 - inbound read from peer id 0 of len 18
725                 // 0141 03000000000000000000000000000000 - message header indicating message length 321
726                 // 0300fe - inbound read from peer id 0 of len 254
727                 // 0020 7500000000000000000000000000000000000000000000000000000000000000 ff4f00f805273c1b203bb5ebf8436bfde57b3be8c2f5e95d9491dbb181909679 000000000000c350 0000000000000000 0000000000000162 ffffffffffffffff 0000000000000222 0000000000000000 000000fd 0006 01e3 030000000000000000000000000000000000000000000000000000000000000001 030000000000000000000000000000000000000000000000000000000000000002 030000000000000000000000000000000000000000000000000000000000000003 030000000000000000000000000000000000000000000000000000000000000004 - beginning of open_channel message
728                 // 030053 - inbound read from peer id 0 of len 83
729                 // 030000000000000000000000000000000000000000000000000000000000000005 020900000000000000000000000000000000000000000000000000000000000000 01 03000000000000000000000000000000 - rest of open_channel and mac
730                 //
731                 // 00fd00fd - Two feerate requests (all returning min feerate, which our open_channel also uses) (gonna be ingested by FuzzEstimator)
732                 // - client should now respond with accept_channel (CHECK 1: type 33 to peer 03000000)
733                 //
734                 // 030012 - inbound read from peer id 0 of len 18
735                 // 0084 03000000000000000000000000000000 - message header indicating message length 132
736                 // 030094 - inbound read from peer id 0 of len 148
737                 // 0022 ff4f00f805273c1b203bb5ebf8436bfde57b3be8c2f5e95d9491dbb181909679 3d00000000000000000000000000000000000000000000000000000000000000 0000 00000000000000000000000000000000000000000000000000000000000000210100000000000000000000000000000000000000000000000000000000000000 03000000000000000000000000000000 - funding_created and mac
738                 // - client should now respond with funding_signed (CHECK 2: type 35 to peer 03000000)
739                 //
740                 // 0c005e - connect a block with one transaction of len 94
741                 // 020000000100000000000000000000000000000000000000000000000000000000000000000000000000ffffffff0150c3000000000000220020ae0000000000000000000000000000000000000000000000000000000000000000000000 - the funding transaction
742                 // 0c0000 - connect a block with no transactions
743                 // 0c0000 - connect a block with no transactions
744                 // 0c0000 - connect a block with no transactions
745                 // 0c0000 - connect a block with no transactions
746                 // 0c0000 - connect a block with no transactions
747                 // 0c0000 - connect a block with no transactions
748                 // 0c0000 - connect a block with no transactions
749                 // 0c0000 - connect a block with no transactions
750                 // 0c0000 - connect a block with no transactions
751                 // 0c0000 - connect a block with no transactions
752                 // 0c0000 - connect a block with no transactions
753                 // 0c0000 - connect a block with no transactions
754                 // - by now client should have sent a channel_ready (CHECK 3: SendChannelReady to 03000000 for chan 3d000000)
755                 //
756                 // 030012 - inbound read from peer id 0 of len 18
757                 // 0043 03000000000000000000000000000000 - message header indicating message length 67
758                 // 030053 - inbound read from peer id 0 of len 83
759                 // 0024 3d00000000000000000000000000000000000000000000000000000000000000 020800000000000000000000000000000000000000000000000000000000000000 03000000000000000000000000000000 - channel_ready and mac
760                 //
761                 // 01 - new inbound connection with id 1
762                 // 030132 - inbound read from peer id 1 of len 50
763                 // 0003000000000000000000000000000000000000000000000000000000000000000703000000000000000000000000000000 - inbound noise act 1
764                 // 030142 - inbound read from peer id 1 of len 66
765                 // 000302000000000000000000000000000000000000000000000000000000000000000300000000000000000000000000000003000000000000000000000000000000 - inbound noise act 3
766                 //
767                 // 030112 - inbound read from peer id 1 of len 18
768                 // 000a 01000000000000000000000000000000 - message header indicating message length 10
769                 // 03011a - inbound read from peer id 1 of len 26
770                 // 0010 00022000 00022000 01000000000000000000000000000000 - init message (type 16) with static_remotekey (0x2000) and mac
771                 //
772                 // 05 01 030200000000000000000000000000000000000000000000000000000000000000 00c350 0003e8 - create outbound channel to peer 1 for 50k sat
773                 // 00fd - One feerate requests (all returning min feerate) (gonna be ingested by FuzzEstimator)
774                 //
775                 // 030112 - inbound read from peer id 1 of len 18
776                 // 0110 01000000000000000000000000000000 - message header indicating message length 272
777                 // 0301ff - inbound read from peer id 1 of len 255
778                 // 0021 0000000000000000000000000000000000000000000000000000000000000e05 0000000000000162 00000000004c4b40 00000000000003e8 00000000000003e8 00000002 03f0 0005 030000000000000000000000000000000000000000000000000000000000000100 030000000000000000000000000000000000000000000000000000000000000200 030000000000000000000000000000000000000000000000000000000000000300 030000000000000000000000000000000000000000000000000000000000000400 030000000000000000000000000000000000000000000000000000000000000500 02660000000000000000000000000000 - beginning of accept_channel
779                 // 030121 - inbound read from peer id 1 of len 33
780                 // 0000000000000000000000000000000000 01000000000000000000000000000000 - rest of accept_channel and mac
781                 //
782                 // 0a - create the funding transaction (client should send funding_created now)
783                 //
784                 // 030112 - inbound read from peer id 1 of len 18
785                 // 0062 01000000000000000000000000000000 - message header indicating message length 98
786                 // 030172 - inbound read from peer id 1 of len 114
787                 // 0023 3a00000000000000000000000000000000000000000000000000000000000000 000000000000000000000000000000000000000000000000000000000000007c0001000000000000000000000000000000000000000000000000000000000000 01000000000000000000000000000000 - funding_signed message and mac
788                 //
789                 // 0b - broadcast funding transaction
790                 // - by now client should have sent a channel_ready (CHECK 4: SendChannelReady to 03020000 for chan 3f000000)
791                 //
792                 // 030112 - inbound read from peer id 1 of len 18
793                 // 0043 01000000000000000000000000000000 - message header indicating message length 67
794                 // 030153 - inbound read from peer id 1 of len 83
795                 // 0024 3a00000000000000000000000000000000000000000000000000000000000000 026700000000000000000000000000000000000000000000000000000000000000 01000000000000000000000000000000 - channel_ready and mac
796                 //
797                 // 030012 - inbound read from peer id 0 of len 18
798                 // 05ac 03000000000000000000000000000000 - message header indicating message length 1452
799                 // 0300ff - inbound read from peer id 0 of len 255
800                 // 0080 3d00000000000000000000000000000000000000000000000000000000000000 0000000000000000 0000000000003e80 ff00000000000000000000000000000000000000000000000000000000000000 000003f0 00 030000000000000000000000000000000000000000000000000000000000000555 0000000e000001000000000000000003e8000000a00000000000000000000000000a00000000000000000000000000000000000000000000000000000000000000 ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff - beginning of update_add_htlc from 0 to 1 via client
801                 // 0300ff - inbound read from peer id 0 of len 255
802                 // ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff
803                 // 0300ff - inbound read from peer id 0 of len 255
804                 // ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff
805                 // 0300ff - inbound read from peer id 0 of len 255
806                 // ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff
807                 // 0300ff - inbound read from peer id 0 of len 255
808                 // ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff
809                 // 0300c1 - inbound read from peer id 0 of len 193
810                 // ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff 4e00000000000000000000000000000000000000000000000000000000000000 03000000000000000000000000000000 - end of update_add_htlc from 0 to 1 via client and mac
811                 //
812                 // 030012 - inbound read from peer id 0 of len 18
813                 // 0064 03000000000000000000000000000000 - message header indicating message length 100
814                 // 030074 - inbound read from peer id 0 of len 116
815                 // 0084 3d00000000000000000000000000000000000000000000000000000000000000 00000000000000000000000000000000000000000000000000000000000000300100000000000000000000000000000000000000000000000000000000000000 0000 03000000000000000000000000000000 - commitment_signed and mac
816                 // - client should now respond with revoke_and_ack and commitment_signed (CHECK 5/6: types 133 and 132 to peer 03000000)
817                 //
818                 // 030012 - inbound read from peer id 0 of len 18
819                 // 0063 03000000000000000000000000000000 - message header indicating message length 99
820                 // 030073 - inbound read from peer id 0 of len 115
821                 // 0085 3d00000000000000000000000000000000000000000000000000000000000000 0900000000000000000000000000000000000000000000000000000000000000 020b00000000000000000000000000000000000000000000000000000000000000 03000000000000000000000000000000 - revoke_and_ack and mac
822                 //
823                 // 07 - process the now-pending HTLC forward
824                 // - client now sends id 1 update_add_htlc and commitment_signed (CHECK 7: SendHTLCs event for node 03020000 with 1 HTLCs for channel 3f000000)
825                 //
826                 // - we respond with commitment_signed then revoke_and_ack (a weird, but valid, order)
827                 // 030112 - inbound read from peer id 1 of len 18
828                 // 0064 01000000000000000000000000000000 - message header indicating message length 100
829                 // 030174 - inbound read from peer id 1 of len 116
830                 // 0084 3a00000000000000000000000000000000000000000000000000000000000000 000000000000000000000000000000000000000000000000000000000000006a0001000000000000000000000000000000000000000000000000000000000000 0000 01000000000000000000000000000000 - commitment_signed and mac
831                 //
832                 // 030112 - inbound read from peer id 1 of len 18
833                 // 0063 01000000000000000000000000000000 - message header indicating message length 99
834                 // 030173 - inbound read from peer id 1 of len 115
835                 // 0085 3a00000000000000000000000000000000000000000000000000000000000000 6600000000000000000000000000000000000000000000000000000000000000 026400000000000000000000000000000000000000000000000000000000000000 01000000000000000000000000000000 - revoke_and_ack and mac
836                 //
837                 // 030112 - inbound read from peer id 1 of len 18
838                 // 004a 01000000000000000000000000000000 - message header indicating message length 74
839                 // 03015a - inbound read from peer id 1 of len 90
840                 // 0082 3a00000000000000000000000000000000000000000000000000000000000000 0000000000000000 ff00888888888888888888888888888888888888888888888888888888888888 01000000000000000000000000000000 - update_fulfill_htlc and mac
841                 // - client should immediately claim the pending HTLC from peer 0 (CHECK 8: SendFulfillHTLCs for node 03000000 with preimage ff00888888 for channel 3d000000)
842                 //
843                 // 030112 - inbound read from peer id 1 of len 18
844                 // 0064 01000000000000000000000000000000 - message header indicating message length 100
845                 // 030174 - inbound read from peer id 1 of len 116
846                 // 0084 3a00000000000000000000000000000000000000000000000000000000000000 00000000000000000000000000000000000000000000000000000000000000100001000000000000000000000000000000000000000000000000000000000000 0000 01000000000000000000000000000000 - commitment_signed and mac
847                 //
848                 // 030112 - inbound read from peer id 1 of len 18
849                 // 0063 01000000000000000000000000000000 - message header indicating message length 99
850                 // 030173 - inbound read from peer id 1 of len 115
851                 // 0085 3a00000000000000000000000000000000000000000000000000000000000000 6700000000000000000000000000000000000000000000000000000000000000 026500000000000000000000000000000000000000000000000000000000000000 01000000000000000000000000000000 - revoke_and_ack and mac
852                 //
853                 // - before responding to the commitment_signed generated above, send a new HTLC
854                 // 030012 - inbound read from peer id 0 of len 18
855                 // 05ac 03000000000000000000000000000000 - message header indicating message length 1452
856                 // 0300ff - inbound read from peer id 0 of len 255
857                 // 0080 3d00000000000000000000000000000000000000000000000000000000000000 0000000000000001 0000000000003e80 ff00000000000000000000000000000000000000000000000000000000000000 000003f0 00 030000000000000000000000000000000000000000000000000000000000000555 0000000e000001000000000000000003e8000000a00000000000000000000000000a00000000000000000000000000000000000000000000000000000000000000 ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff - beginning of update_add_htlc from 0 to 1 via client
858                 // 0300ff - inbound read from peer id 0 of len 255
859                 // ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff
860                 // 0300ff - inbound read from peer id 0 of len 255
861                 // ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff
862                 // 0300ff - inbound read from peer id 0 of len 255
863                 // ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff
864                 // 0300ff - inbound read from peer id 0 of len 255
865                 // ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff
866                 // 0300c1 - inbound read from peer id 0 of len 193
867                 // ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff 4e00000000000000000000000000000000000000000000000000000000000000 03000000000000000000000000000000 - end of update_add_htlc from 0 to 1 via client and mac
868                 //
869                 // - now respond to the update_fulfill_htlc+commitment_signed messages the client sent to peer 0
870                 // 030012 - inbound read from peer id 0 of len 18
871                 // 0063 03000000000000000000000000000000 - message header indicating message length 99
872                 // 030073 - inbound read from peer id 0 of len 115
873                 // 0085 3d00000000000000000000000000000000000000000000000000000000000000 0800000000000000000000000000000000000000000000000000000000000000 020a00000000000000000000000000000000000000000000000000000000000000 03000000000000000000000000000000 - revoke_and_ack and mac
874                 // - client should now respond with revoke_and_ack and commitment_signed (CHECK 5/6 duplicates)
875                 //
876                 // 030012 - inbound read from peer id 0 of len 18
877                 // 0064 03000000000000000000000000000000 - message header indicating message length 100
878                 // 030074 - inbound read from peer id 0 of len 116
879                 // 0084 3d00000000000000000000000000000000000000000000000000000000000000 00000000000000000000000000000000000000000000000000000000000000c30100000000000000000000000000000000000000000000000000000000000000 0000 03000000000000000000000000000000 - commitment_signed and mac
880                 //
881                 // 030012 - inbound read from peer id 0 of len 18
882                 // 0063 03000000000000000000000000000000 - message header indicating message length 99
883                 // 030073 - inbound read from peer id 0 of len 115
884                 // 0085 3d00000000000000000000000000000000000000000000000000000000000000 0b00000000000000000000000000000000000000000000000000000000000000 020d00000000000000000000000000000000000000000000000000000000000000 03000000000000000000000000000000 - revoke_and_ack and mac
885                 //
886                 // 07 - process the now-pending HTLC forward
887                 // - client now sends id 1 update_add_htlc and commitment_signed (CHECK 7 duplicate)
888                 // - we respond with revoke_and_ack, then commitment_signed, then update_fail_htlc
889                 //
890                 // 030112 - inbound read from peer id 1 of len 18
891                 // 0064 01000000000000000000000000000000 - message header indicating message length 100
892                 // 030174 - inbound read from peer id 1 of len 116
893                 // 0084 3a00000000000000000000000000000000000000000000000000000000000000 00000000000000000000000000000000000000000000000000000000000000390001000000000000000000000000000000000000000000000000000000000000 0000 01000000000000000000000000000000 - commitment_signed and mac
894                 //
895                 // 030112 - inbound read from peer id 1 of len 18
896                 // 0063 01000000000000000000000000000000 - message header indicating message length 99
897                 // 030173 - inbound read from peer id 1 of len 115
898                 // 0085 3a00000000000000000000000000000000000000000000000000000000000000 6400000000000000000000000000000000000000000000000000000000000000 027000000000000000000000000000000000000000000000000000000000000000 01000000000000000000000000000000 - revoke_and_ack and mac
899                 //
900                 // 030112 - inbound read from peer id 1 of len 18
901                 // 002c 01000000000000000000000000000000 - message header indicating message length 44
902                 // 03013c - inbound read from peer id 1 of len 60
903                 // 0083 3a00000000000000000000000000000000000000000000000000000000000000 0000000000000001 0000 01000000000000000000000000000000 - update_fail_htlc and mac
904                 //
905                 // 030112 - inbound read from peer id 1 of len 18
906                 // 0064 01000000000000000000000000000000 - message header indicating message length 100
907                 // 030174 - inbound read from peer id 1 of len 116
908                 // 0084 3a00000000000000000000000000000000000000000000000000000000000000 00000000000000000000000000000000000000000000000000000000000000390001000000000000000000000000000000000000000000000000000000000000 0000 01000000000000000000000000000000 - commitment_signed and mac
909                 //
910                 // 030112 - inbound read from peer id 1 of len 18
911                 // 0063 01000000000000000000000000000000 - message header indicating message length 99
912                 // 030173 - inbound read from peer id 1 of len 115
913                 // 0085 3a00000000000000000000000000000000000000000000000000000000000000 6500000000000000000000000000000000000000000000000000000000000000 027100000000000000000000000000000000000000000000000000000000000000 01000000000000000000000000000000 - revoke_and_ack and mac
914                 //
915                 // 07 - process the now-pending HTLC forward
916                 // - client now sends id 0 update_fail_htlc and commitment_signed (CHECK 9)
917                 // - now respond to the update_fail_htlc+commitment_signed messages the client sent to peer 0
918                 //
919                 // 030012 - inbound read from peer id 0 of len 18
920                 // 0063 03000000000000000000000000000000 - message header indicating message length 99
921                 // 030073 - inbound read from peer id 0 of len 115
922                 // 0085 3d00000000000000000000000000000000000000000000000000000000000000 0a00000000000000000000000000000000000000000000000000000000000000 020c00000000000000000000000000000000000000000000000000000000000000 03000000000000000000000000000000 - revoke_and_ack and mac
923                 //
924                 // 030012 - inbound read from peer id 0 of len 18
925                 // 0064 03000000000000000000000000000000 - message header indicating message length 100
926                 // 030074 - inbound read from peer id 0 of len 116
927                 // 0084 3d00000000000000000000000000000000000000000000000000000000000000 00000000000000000000000000000000000000000000000000000000000000320100000000000000000000000000000000000000000000000000000000000000 0000 03000000000000000000000000000000 - commitment_signed and mac
928                 // - client should now respond with revoke_and_ack (CHECK 5 duplicate)
929                 //
930                 // 030012 - inbound read from peer id 0 of len 18
931                 // 05ac 03000000000000000000000000000000 - message header indicating message length 1452
932                 // 0300ff - inbound read from peer id 0 of len 255
933                 // 0080 3d00000000000000000000000000000000000000000000000000000000000000 0000000000000002 00000000000b0838 ff00000000000000000000000000000000000000000000000000000000000000 000003f0 00 030000000000000000000000000000000000000000000000000000000000000555 0000000e000001000000000000000927c0000000a00000000000000000000000000a00000000000000000000000000000000000000000000000000000000000000 ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff - beginning of update_add_htlc from 0 to 1 via client
934                 // 0300ff - inbound read from peer id 0 of len 255
935                 // ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff
936                 // 0300ff - inbound read from peer id 0 of len 255
937                 // ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff
938                 // 0300ff - inbound read from peer id 0 of len 255
939                 // ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff
940                 // 0300ff - inbound read from peer id 0 of len 255
941                 // ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff
942                 // 0300c1 - inbound read from peer id 0 of len 193
943                 // ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff 4b00000000000000000000000000000000000000000000000000000000000000 03000000000000000000000000000000 - end of update_add_htlc from 0 to 1 via client and mac
944                 //
945                 // 030012 - inbound read from peer id 0 of len 18
946                 // 00a4 03000000000000000000000000000000 - message header indicating message length 164
947                 // 0300b4 - inbound read from peer id 0 of len 180
948                 // 0084 3d00000000000000000000000000000000000000000000000000000000000000 00000000000000000000000000000000000000000000000000000000000000750100000000000000000000000000000000000000000000000000000000000000 0001 00000000000000000000000000000000000000000000000000000000000000670500000000000000000000000000000000000000000000000000000000000006 03000000000000000000000000000000 - commitment_signed and mac
949                 // - client should now respond with revoke_and_ack and commitment_signed (CHECK 5/6 duplicates)
950                 //
951                 // 030012 - inbound read from peer id 0 of len 18
952                 // 0063 03000000000000000000000000000000 - message header indicating message length 99
953                 // 030073 - inbound read from peer id 0 of len 115
954                 // 0085 3d00000000000000000000000000000000000000000000000000000000000000 0d00000000000000000000000000000000000000000000000000000000000000 020f00000000000000000000000000000000000000000000000000000000000000 03000000000000000000000000000000 - revoke_and_ack and mac
955                 //
956                 // 07 - process the now-pending HTLC forward
957                 // - client now sends id 1 update_add_htlc and commitment_signed (CHECK 7 duplicate)
958                 //
959                 // 0c007d - connect a block with one transaction of len 125
960                 // 02000000013a000000000000000000000000000000000000000000000000000000000000000000000000000000800258020000000000002200204b0000000000000000000000000000000000000000000000000000000000000014c0000000000000160014280000000000000000000000000000000000000005000020 - the commitment transaction for channel 3f00000000000000000000000000000000000000000000000000000000000000
961                 //
962                 // 0c005e - connect a block with one transaction of len 94
963                 // 0200000001730000000000000000000000000000000000000000000000000000000000000000000000000000000001a701000000000000220020b20000000000000000000000000000000000000000000000000000000000000000000000 - the HTLC timeout transaction
964                 // 0c0000 - connect a block with no transactions
965                 // 0c0000 - connect a block with no transactions
966                 // 0c0000 - connect a block with no transactions
967                 // 0c0000 - connect a block with no transactions
968                 // 0c0000 - connect a block with no transactions
969                 //
970                 // 07 - process the now-pending HTLC forward
971                 // - client now fails the HTLC backwards as it was unable to extract the payment preimage (CHECK 9 duplicate and CHECK 10)
972
973                 let logger = Arc::new(TrackingLogger { lines: Mutex::new(HashMap::new()) });
974                 super::do_test(&::hex::decode("01000000000000000000000000000000000000000000000000000000000000000000000001000300000000000000000000000000000000000000000000000000000000000000020300320003000000000000000000000000000000000000000000000000000000000000000203000000000000000000000000000000030012000a0300000000000000000000000000000003001a00100002200000022000030000000000000000000000000000000300120141030000000000000000000000000000000300fe00207500000000000000000000000000000000000000000000000000000000000000ff4f00f805273c1b203bb5ebf8436bfde57b3be8c2f5e95d9491dbb181909679000000000000c35000000000000000000000000000000162ffffffffffffffff00000000000002220000000000000000000000fd000601e3030000000000000000000000000000000000000000000000000000000000000001030000000000000000000000000000000000000000000000000000000000000002030000000000000000000000000000000000000000000000000000000000000003030000000000000000000000000000000000000000000000000000000000000004030053030000000000000000000000000000000000000000000000000000000000000005020900000000000000000000000000000000000000000000000000000000000000010300000000000000000000000000000000fd00fd0300120084030000000000000000000000000000000300940022ff4f00f805273c1b203bb5ebf8436bfde57b3be8c2f5e95d9491dbb1819096793d00000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000210100000000000000000000000000000000000000000000000000000000000000030000000000000000000000000000000c005e020000000100000000000000000000000000000000000000000000000000000000000000000000000000ffffffff0150c3000000000000220020ae00000000000000000000000000000000000000000000000000000000000000000000000c00000c00000c00000c00000c00000c00000c00000c00000c00000c00000c00000c000003001200430300000000000000000000000000000003005300243d0000000000000000000000000000000000000000000000000000000000000002080000000000000000000000000000000000000000000000000000000000000003000000000000000000000000000000010301320003000000000000000000000000000000000000000000000000000000000000000703000000000000000000000000000000030142000302000000000000000000000000000000000000000000000000000000000000000300000000000000000000000000000003000000000000000000000000000000030112000a0100000000000000000000000000000003011a0010000220000002200001000000000000000000000000000000050103020000000000000000000000000000000000000000000000000000000000000000c3500003e800fd0301120110010000000000000000000000000000000301ff00210000000000000000000000000000000000000000000000000000000000000e05000000000000016200000000004c4b4000000000000003e800000000000003e80000000203f00005030000000000000000000000000000000000000000000000000000000000000100030000000000000000000000000000000000000000000000000000000000000200030000000000000000000000000000000000000000000000000000000000000300030000000000000000000000000000000000000000000000000000000000000400030000000000000000000000000000000000000000000000000000000000000500026600000000000000000000000000000301210000000000000000000000000000000000010000000000000000000000000000000a03011200620100000000000000000000000000000003017200233a00000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000007c0001000000000000000000000000000000000000000000000000000000000000010000000000000000000000000000000b03011200430100000000000000000000000000000003015300243a000000000000000000000000000000000000000000000000000000000000000267000000000000000000000000000000000000000000000000000000000000000100000000000000000000000000000003001205ac030000000000000000000000000000000300ff00803d0000000000000000000000000000000000000000000000000000000000000000000000000000000000000000003e80ff00000000000000000000000000000000000000000000000000000000000000000003f0000300000000000000000000000000000000000000000000000000000000000005550000000e000001000000000000000003e8000000a00000000000000000000000000a00000000000000000000000000000000000000000000000000000000000000ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff0300ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff0300ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff0300ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff0300ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff0300c1ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff4e000000000000000000000000000000000000000000000000000000000000000300000000000000000000000000000003001200640300000000000000000000000000000003007400843d000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000030010000000000000000000000000000000000000000000000000000000000000000000300000000000000000000000000000003001200630300000000000000000000000000000003007300853d000000000000000000000000000000000000000000000000000000000000000900000000000000000000000000000000000000000000000000000000000000020b00000000000000000000000000000000000000000000000000000000000000030000000000000000000000000000000703011200640100000000000000000000000000000003017400843a00000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000006a000100000000000000000000000000000000000000000000000000000000000000000100000000000000000000000000000003011200630100000000000000000000000000000003017300853a00000000000000000000000000000000000000000000000000000000000000660000000000000000000000000000000000000000000000000000000000000002640000000000000000000000000000000000000000000000000000000000000001000000000000000000000000000000030112004a0100000000000000000000000000000003015a00823a000000000000000000000000000000000000000000000000000000000000000000000000000000ff008888888888888888888888888888888888888888888888888888888888880100000000000000000000000000000003011200640100000000000000000000000000000003017400843a000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000010000100000000000000000000000000000000000000000000000000000000000000000100000000000000000000000000000003011200630100000000000000000000000000000003017300853a0000000000000000000000000000000000000000000000000000000000000067000000000000000000000000000000000000000000000000000000000000000265000000000000000000000000000000000000000000000000000000000000000100000000000000000000000000000003001205ac030000000000000000000000000000000300ff00803d0000000000000000000000000000000000000000000000000000000000000000000000000000010000000000003e80ff00000000000000000000000000000000000000000000000000000000000000000003f0000300000000000000000000000000000000000000000000000000000000000005550000000e000001000000000000000003e8000000a00000000000000000000000000a00000000000000000000000000000000000000000000000000000000000000ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff0300ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff0300ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff0300ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff0300ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff0300c1ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff4e000000000000000000000000000000000000000000000000000000000000000300000000000000000000000000000003001200630300000000000000000000000000000003007300853d000000000000000000000000000000000000000000000000000000000000000800000000000000000000000000000000000000000000000000000000000000020a000000000000000000000000000000000000000000000000000000000000000300000000000000000000000000000003001200640300000000000000000000000000000003007400843d0000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000c3010000000000000000000000000000000000000000000000000000000000000000000300000000000000000000000000000003001200630300000000000000000000000000000003007300853d000000000000000000000000000000000000000000000000000000000000000b00000000000000000000000000000000000000000000000000000000000000020d00000000000000000000000000000000000000000000000000000000000000030000000000000000000000000000000703011200640100000000000000000000000000000003017400843a000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000039000100000000000000000000000000000000000000000000000000000000000000000100000000000000000000000000000003011200630100000000000000000000000000000003017300853a00000000000000000000000000000000000000000000000000000000000000640000000000000000000000000000000000000000000000000000000000000002700000000000000000000000000000000000000000000000000000000000000001000000000000000000000000000000030112002c0100000000000000000000000000000003013c00833a00000000000000000000000000000000000000000000000000000000000000000000000000000100000100000000000000000000000000000003011200640100000000000000000000000000000003017400843a000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000039000100000000000000000000000000000000000000000000000000000000000000000100000000000000000000000000000003011200630100000000000000000000000000000003017300853a000000000000000000000000000000000000000000000000000000000000006500000000000000000000000000000000000000000000000000000000000000027100000000000000000000000000000000000000000000000000000000000000010000000000000000000000000000000703001200630300000000000000000000000000000003007300853d000000000000000000000000000000000000000000000000000000000000000a00000000000000000000000000000000000000000000000000000000000000020c000000000000000000000000000000000000000000000000000000000000000300000000000000000000000000000003001200640300000000000000000000000000000003007400843d000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000032010000000000000000000000000000000000000000000000000000000000000000000300000000000000000000000000000003001205ac030000000000000000000000000000000300ff00803d00000000000000000000000000000000000000000000000000000000000000000000000000000200000000000b0838ff00000000000000000000000000000000000000000000000000000000000000000003f0000300000000000000000000000000000000000000000000000000000000000005550000000e000001000000000000000927c0000000a00000000000000000000000000a00000000000000000000000000000000000000000000000000000000000000ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff0300ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff0300ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff0300ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff0300ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff0300c1ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff4b000000000000000000000000000000000000000000000000000000000000000300000000000000000000000000000003001200a4030000000000000000000000000000000300b400843d00000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000007501000000000000000000000000000000000000000000000000000000000000000001000000000000000000000000000000000000000000000000000000000000006705000000000000000000000000000000000000000000000000000000000000060300000000000000000000000000000003001200630300000000000000000000000000000003007300853d000000000000000000000000000000000000000000000000000000000000000d00000000000000000000000000000000000000000000000000000000000000020f0000000000000000000000000000000000000000000000000000000000000003000000000000000000000000000000070c007d02000000013a000000000000000000000000000000000000000000000000000000000000000000000000000000800258020000000000002200204b0000000000000000000000000000000000000000000000000000000000000014c00000000000001600142800000000000000000000000000000000000000050000200c005e0200000001730000000000000000000000000000000000000000000000000000000000000000000000000000000001a701000000000000220020b200000000000000000000000000000000000000000000000000000000000000000000000c00000c00000c00000c00000c000007").unwrap(), &(Arc::clone(&logger) as Arc<dyn Logger>));
975
976                 let log_entries = logger.lines.lock().unwrap();
977                 assert_eq!(log_entries.get(&("lightning::ln::peer_handler".to_string(), "Handling SendAcceptChannel event in peer_handler for node 030000000000000000000000000000000000000000000000000000000000000002 for channel ff4f00f805273c1b203bb5ebf8436bfde57b3be8c2f5e95d9491dbb181909679".to_string())), Some(&1)); // 1
978                 assert_eq!(log_entries.get(&("lightning::ln::peer_handler".to_string(), "Handling SendFundingSigned event in peer_handler for node 030000000000000000000000000000000000000000000000000000000000000002 for channel 3d00000000000000000000000000000000000000000000000000000000000000".to_string())), Some(&1)); // 2
979                 assert_eq!(log_entries.get(&("lightning::ln::peer_handler".to_string(), "Handling SendChannelReady event in peer_handler for node 030000000000000000000000000000000000000000000000000000000000000002 for channel 3d00000000000000000000000000000000000000000000000000000000000000".to_string())), Some(&1)); // 3
980                 assert_eq!(log_entries.get(&("lightning::ln::peer_handler".to_string(), "Handling SendChannelReady event in peer_handler for node 030200000000000000000000000000000000000000000000000000000000000000 for channel 3a00000000000000000000000000000000000000000000000000000000000000".to_string())), Some(&1)); // 4
981                 assert_eq!(log_entries.get(&("lightning::ln::peer_handler".to_string(), "Handling SendRevokeAndACK event in peer_handler for node 030000000000000000000000000000000000000000000000000000000000000002 for channel 3d00000000000000000000000000000000000000000000000000000000000000".to_string())), Some(&4)); // 5
982                 assert_eq!(log_entries.get(&("lightning::ln::peer_handler".to_string(), "Handling UpdateHTLCs event in peer_handler for node 030000000000000000000000000000000000000000000000000000000000000002 with 0 adds, 0 fulfills, 0 fails for channel 3d00000000000000000000000000000000000000000000000000000000000000".to_string())), Some(&3)); // 6
983                 assert_eq!(log_entries.get(&("lightning::ln::peer_handler".to_string(), "Handling UpdateHTLCs event in peer_handler for node 030200000000000000000000000000000000000000000000000000000000000000 with 1 adds, 0 fulfills, 0 fails for channel 3a00000000000000000000000000000000000000000000000000000000000000".to_string())), Some(&3)); // 7
984                 assert_eq!(log_entries.get(&("lightning::ln::peer_handler".to_string(), "Handling UpdateHTLCs event in peer_handler for node 030000000000000000000000000000000000000000000000000000000000000002 with 0 adds, 1 fulfills, 0 fails for channel 3d00000000000000000000000000000000000000000000000000000000000000".to_string())), Some(&1)); // 8
985                 assert_eq!(log_entries.get(&("lightning::ln::peer_handler".to_string(), "Handling UpdateHTLCs event in peer_handler for node 030000000000000000000000000000000000000000000000000000000000000002 with 0 adds, 0 fulfills, 1 fails for channel 3d00000000000000000000000000000000000000000000000000000000000000".to_string())), Some(&2)); // 9
986                 assert_eq!(log_entries.get(&("lightning::chain::channelmonitor".to_string(), "Input spending counterparty commitment tx (0000000000000000000000000000000000000000000000000000000000000073:0) in 0000000000000000000000000000000000000000000000000000000000000067 resolves outbound HTLC with payment hash ff00000000000000000000000000000000000000000000000000000000000000 with timeout".to_string())), Some(&1)); // 10
987         }
988 }