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