Merge pull request #224 from TheBlueMatt/2018-10-221-whitespace
[rust-lightning] / fuzz / fuzz_targets / full_stack_target.rs
1 extern crate bitcoin;
2 extern crate crypto;
3 extern crate lightning;
4 extern crate secp256k1;
5
6 use bitcoin::blockdata::block::BlockHeader;
7 use bitcoin::blockdata::transaction::{Transaction, TxOut};
8 use bitcoin::blockdata::script::Script;
9 use bitcoin::network::constants::Network;
10 use bitcoin::network::serialize::{deserialize, serialize, BitcoinHash};
11 use bitcoin::util::hash::Sha256dHash;
12
13 use crypto::digest::Digest;
14
15 use lightning::chain::chaininterface::{BroadcasterInterface,ConfirmationTarget,ChainListener,FeeEstimator,ChainWatchInterfaceUtil};
16 use lightning::chain::transaction::OutPoint;
17 use lightning::ln::channelmonitor;
18 use lightning::ln::channelmanager::{ChannelManager, PaymentFailReason};
19 use lightning::ln::peer_handler::{MessageHandler,PeerManager,SocketDescriptor};
20 use lightning::ln::router::Router;
21 use lightning::util::events::{EventsProvider,Event};
22 use lightning::util::reset_rng_state;
23 use lightning::util::logger::Logger;
24 use lightning::util::sha2::Sha256;
25
26 mod utils;
27
28 use utils::test_logger;
29
30 use secp256k1::key::{PublicKey,SecretKey};
31 use secp256k1::Secp256k1;
32
33 use std::cell::RefCell;
34 use std::collections::HashMap;
35 use std::cmp;
36 use std::hash::Hash;
37 use std::sync::Arc;
38 use std::sync::atomic::{AtomicUsize,Ordering};
39
40 #[inline]
41 pub fn slice_to_be16(v: &[u8]) -> u16 {
42         ((v[0] as u16) << 8*1) |
43         ((v[1] as u16) << 8*0)
44 }
45
46 #[inline]
47 pub fn slice_to_be24(v: &[u8]) -> u32 {
48         ((v[0] as u32) << 8*2) |
49         ((v[1] as u32) << 8*1) |
50         ((v[2] as u32) << 8*0)
51 }
52
53 #[inline]
54 pub fn slice_to_be32(v: &[u8]) -> u32 {
55         ((v[0] as u32) << 8*3) |
56         ((v[1] as u32) << 8*2) |
57         ((v[2] as u32) << 8*1) |
58         ((v[3] as u32) << 8*0)
59 }
60
61 #[inline]
62 pub fn be64_to_array(u: u64) -> [u8; 8] {
63         let mut v = [0; 8];
64         v[0] = ((u >> 8*7) & 0xff) as u8;
65         v[1] = ((u >> 8*6) & 0xff) as u8;
66         v[2] = ((u >> 8*5) & 0xff) as u8;
67         v[3] = ((u >> 8*4) & 0xff) as u8;
68         v[4] = ((u >> 8*3) & 0xff) as u8;
69         v[5] = ((u >> 8*2) & 0xff) as u8;
70         v[6] = ((u >> 8*1) & 0xff) as u8;
71         v[7] = ((u >> 8*0) & 0xff) as u8;
72         v
73 }
74
75 struct InputData {
76         data: Vec<u8>,
77         read_pos: AtomicUsize,
78 }
79 impl InputData {
80         fn get_slice(&self, len: usize) -> Option<&[u8]> {
81                 let old_pos = self.read_pos.fetch_add(len, Ordering::AcqRel);
82                 if self.data.len() < old_pos + len {
83                         return None;
84                 }
85                 Some(&self.data[old_pos..old_pos + len])
86         }
87 }
88
89 struct FuzzEstimator {
90         input: Arc<InputData>,
91 }
92 impl FeeEstimator for FuzzEstimator {
93         fn get_est_sat_per_1000_weight(&self, _: ConfirmationTarget) -> u64 {
94                 //TODO: We should actually be testing at least much more than 64k...
95                 match self.input.get_slice(2) {
96                         Some(slice) => cmp::max(slice_to_be16(slice) as u64, 253),
97                         None => 0
98                 }
99         }
100 }
101
102 struct TestBroadcaster {}
103 impl BroadcasterInterface for TestBroadcaster {
104         fn broadcast_transaction(&self, _tx: &Transaction) {}
105 }
106
107 #[derive(Clone)]
108 struct Peer<'a> {
109         id: u8,
110         peers_connected: &'a RefCell<[bool; 256]>,
111 }
112 impl<'a> SocketDescriptor for Peer<'a> {
113         fn send_data(&mut self, data: &Vec<u8>, write_offset: usize, _resume_read: bool) -> usize {
114                 assert!(write_offset < data.len());
115                 data.len() - write_offset
116         }
117         fn disconnect_socket(&mut self) {
118                 assert!(self.peers_connected.borrow()[self.id as usize]);
119                 self.peers_connected.borrow_mut()[self.id as usize] = false;
120         }
121 }
122 impl<'a> PartialEq for Peer<'a> {
123         fn eq(&self, other: &Self) -> bool {
124                 self.id == other.id
125         }
126 }
127 impl<'a> Eq for Peer<'a> {}
128 impl<'a> Hash for Peer<'a> {
129         fn hash<H : std::hash::Hasher>(&self, h: &mut H) {
130                 self.id.hash(h)
131         }
132 }
133
134 struct MoneyLossDetector<'a> {
135         manager: Arc<ChannelManager>,
136         monitor: Arc<channelmonitor::SimpleManyChannelMonitor<OutPoint>>,
137         handler: PeerManager<Peer<'a>>,
138
139         peers: &'a RefCell<[bool; 256]>,
140         funding_txn: Vec<Transaction>,
141         header_hashes: Vec<Sha256dHash>,
142         height: usize,
143         max_height: usize,
144
145 }
146 impl<'a> MoneyLossDetector<'a> {
147         pub fn new(peers: &'a RefCell<[bool; 256]>, manager: Arc<ChannelManager>, monitor: Arc<channelmonitor::SimpleManyChannelMonitor<OutPoint>>, handler: PeerManager<Peer<'a>>) -> Self {
148                 MoneyLossDetector {
149                         manager,
150                         monitor,
151                         handler,
152
153                         peers,
154                         funding_txn: Vec::new(),
155                         header_hashes: vec![Default::default()],
156                         height: 0,
157                         max_height: 0,
158                 }
159         }
160
161         fn connect_block(&mut self, txn: &[&Transaction], txn_idxs: &[u32]) {
162                 let header = BlockHeader { version: 0x20000000, prev_blockhash: self.header_hashes[self.height], merkle_root: Default::default(), time: 42, bits: 42, nonce: 42 };
163                 self.height += 1;
164                 self.manager.block_connected(&header, self.height as u32, txn, txn_idxs);
165                 (*self.monitor).block_connected(&header, self.height as u32, txn, txn_idxs);
166                 if self.header_hashes.len() > self.height {
167                         self.header_hashes[self.height] = header.bitcoin_hash();
168                 } else {
169                         assert_eq!(self.header_hashes.len(), self.height);
170                         self.header_hashes.push(header.bitcoin_hash());
171                 }
172                 self.max_height = cmp::max(self.height, self.max_height);
173         }
174
175         fn disconnect_block(&mut self) {
176                 if self.height > 0 && (self.max_height < 6 || self.height >= self.max_height - 6) {
177                         self.height -= 1;
178                         let header = BlockHeader { version: 0x20000000, prev_blockhash: self.header_hashes[self.height], merkle_root: Default::default(), time: 42, bits: 42, nonce: 42 };
179                         self.manager.block_disconnected(&header);
180                         self.monitor.block_disconnected(&header);
181                 }
182         }
183 }
184
185 impl<'a> Drop for MoneyLossDetector<'a> {
186         fn drop(&mut self) {
187                 // Disconnect all peers
188                 for (idx, peer) in self.peers.borrow().iter().enumerate() {
189                         if *peer {
190                                 self.handler.disconnect_event(&Peer{id: idx as u8, peers_connected: &self.peers});
191                         }
192                 }
193
194                 // Force all channels onto the chain (and time out claim txn)
195                 self.manager.force_close_all_channels();
196         }
197 }
198
199 #[inline]
200 pub fn do_test(data: &[u8], logger: &Arc<Logger>) {
201         reset_rng_state();
202
203         let input = Arc::new(InputData {
204                 data: data.to_vec(),
205                 read_pos: AtomicUsize::new(0),
206         });
207         let fee_est = Arc::new(FuzzEstimator {
208                 input: input.clone(),
209         });
210
211         macro_rules! get_slice {
212                 ($len: expr) => {
213                         match input.get_slice($len as usize) {
214                                 Some(slice) => slice,
215                                 None => return,
216                         }
217                 }
218         }
219
220         let secp_ctx = Secp256k1::new();
221         macro_rules! get_pubkey {
222                 () => {
223                         match PublicKey::from_slice(&secp_ctx, get_slice!(33)) {
224                                 Ok(key) => key,
225                                 Err(_) => return,
226                         }
227                 }
228         }
229
230         let our_network_key = match SecretKey::from_slice(&secp_ctx, get_slice!(32)) {
231                 Ok(key) => key,
232                 Err(_) => return,
233         };
234
235         let watch = Arc::new(ChainWatchInterfaceUtil::new(Network::Bitcoin, Arc::clone(&logger)));
236         let broadcast = Arc::new(TestBroadcaster{});
237         let monitor = channelmonitor::SimpleManyChannelMonitor::new(watch.clone(), broadcast.clone());
238
239         let channelmanager = ChannelManager::new(our_network_key, slice_to_be32(get_slice!(4)), get_slice!(1)[0] != 0, Network::Bitcoin, fee_est.clone(), monitor.clone(), watch.clone(), broadcast.clone(), Arc::clone(&logger)).unwrap();
240         let router = Arc::new(Router::new(PublicKey::from_secret_key(&secp_ctx, &our_network_key), watch.clone(), Arc::clone(&logger)));
241
242         let peers = RefCell::new([false; 256]);
243         let mut loss_detector = MoneyLossDetector::new(&peers, channelmanager.clone(), monitor.clone(), PeerManager::new(MessageHandler {
244                 chan_handler: channelmanager.clone(),
245                 route_handler: router.clone(),
246         }, our_network_key, Arc::clone(&logger)));
247
248         let mut should_forward = false;
249         let mut payments_received: Vec<[u8; 32]> = Vec::new();
250         let mut payments_sent = 0;
251         let mut pending_funding_generation: Vec<([u8; 32], u64, Script)> = Vec::new();
252         let mut pending_funding_signatures = HashMap::new();
253         let mut pending_funding_relay = Vec::new();
254
255         loop {
256                 match get_slice!(1)[0] {
257                         0 => {
258                                 let mut new_id = 0;
259                                 for i in 1..256 {
260                                         if !peers.borrow()[i-1] {
261                                                 new_id = i;
262                                                 break;
263                                         }
264                                 }
265                                 if new_id == 0 { return; }
266                                 loss_detector.handler.new_outbound_connection(get_pubkey!(), Peer{id: (new_id - 1) as u8, peers_connected: &peers}).unwrap();
267                                 peers.borrow_mut()[new_id - 1] = true;
268                         },
269                         1 => {
270                                 let mut new_id = 0;
271                                 for i in 1..256 {
272                                         if !peers.borrow()[i-1] {
273                                                 new_id = i;
274                                                 break;
275                                         }
276                                 }
277                                 if new_id == 0 { return; }
278                                 loss_detector.handler.new_inbound_connection(Peer{id: (new_id - 1) as u8, peers_connected: &peers}).unwrap();
279                                 peers.borrow_mut()[new_id - 1] = true;
280                         },
281                         2 => {
282                                 let peer_id = get_slice!(1)[0];
283                                 if !peers.borrow()[peer_id as usize] { return; }
284                                 loss_detector.handler.disconnect_event(&Peer{id: peer_id, peers_connected: &peers});
285                                 peers.borrow_mut()[peer_id as usize] = false;
286                         },
287                         3 => {
288                                 let peer_id = get_slice!(1)[0];
289                                 if !peers.borrow()[peer_id as usize] { return; }
290                                 match loss_detector.handler.read_event(&mut Peer{id: peer_id, peers_connected: &peers}, get_slice!(get_slice!(1)[0]).to_vec()) {
291                                         Ok(res) => assert!(!res),
292                                         Err(_) => { peers.borrow_mut()[peer_id as usize] = false; }
293                                 }
294                         },
295                         4 => {
296                                 let value = slice_to_be24(get_slice!(3)) as u64;
297                                 let route = match router.get_route(&get_pubkey!(), None, &Vec::new(), value, 42) {
298                                         Ok(route) => route,
299                                         Err(_) => return,
300                                 };
301                                 let mut payment_hash = [0; 32];
302                                 payment_hash[0..8].copy_from_slice(&be64_to_array(payments_sent));
303                                 let mut sha = Sha256::new();
304                                 sha.input(&payment_hash);
305                                 sha.result(&mut payment_hash);
306                                 payments_sent += 1;
307                                 match channelmanager.send_payment(route, payment_hash) {
308                                         Ok(_) => {},
309                                         Err(_) => return,
310                                 }
311                         },
312                         5 => {
313                                 let peer_id = get_slice!(1)[0];
314                                 if !peers.borrow()[peer_id as usize] { return; }
315                                 let their_key = get_pubkey!();
316                                 let chan_value = slice_to_be24(get_slice!(3)) as u64;
317                                 let push_msat_value = slice_to_be24(get_slice!(3)) as u64;
318                                 if channelmanager.create_channel(their_key, chan_value, push_msat_value, 0).is_err() { return; }
319                         },
320                         6 => {
321                                 let mut channels = channelmanager.list_channels();
322                                 let channel_id = get_slice!(1)[0] as usize;
323                                 if channel_id >= channels.len() { return; }
324                                 channels.sort_by(|a, b| { a.channel_id.cmp(&b.channel_id) });
325                                 if channelmanager.close_channel(&channels[channel_id].channel_id).is_err() { return; }
326                         },
327                         7 => {
328                                 if should_forward {
329                                         channelmanager.process_pending_htlc_forwards();
330                                         should_forward = false;
331                                 }
332                         },
333                         8 => {
334                                 for payment in payments_received.drain(..) {
335                                         // SHA256 is defined as XOR of all input bytes placed in the first byte, and 0s
336                                         // for the remaining bytes. Thus, if not all remaining bytes are 0s we cannot
337                                         // fulfill this HTLC, but if they are, we can just take the first byte and
338                                         // place that anywhere in our preimage.
339                                         if &payment[1..] != &[0; 31] {
340                                                 channelmanager.fail_htlc_backwards(&payment, PaymentFailReason::PreimageUnknown);
341                                         } else {
342                                                 let mut payment_preimage = [0; 32];
343                                                 payment_preimage[0] = payment[0];
344                                                 channelmanager.claim_funds(payment_preimage);
345                                         }
346                                 }
347                         },
348                         9 => {
349                                 for payment in payments_received.drain(..) {
350                                         channelmanager.fail_htlc_backwards(&payment, PaymentFailReason::PreimageUnknown);
351                                 }
352                         },
353                         10 => {
354                                 for funding_generation in pending_funding_generation.drain(..) {
355                                         let mut tx = Transaction { version: 0, lock_time: 0, input: Vec::new(), output: vec![TxOut {
356                                                         value: funding_generation.1, script_pubkey: funding_generation.2,
357                                                 }] };
358                                         let funding_output = OutPoint::new(Sha256dHash::from_data(&serialize(&tx).unwrap()[..]), 0);
359                                         let mut found_duplicate_txo = false;
360                                         for chan in channelmanager.list_channels() {
361                                                 if chan.channel_id == funding_output.to_channel_id() {
362                                                         found_duplicate_txo = true;
363                                                 }
364                                         }
365                                         if !found_duplicate_txo {
366                                                 channelmanager.funding_transaction_generated(&funding_generation.0, funding_output.clone());
367                                                 pending_funding_signatures.insert(funding_output, tx);
368                                         }
369                                 }
370                         },
371                         11 => {
372                                 if !pending_funding_relay.is_empty() {
373                                         let mut txn = Vec::with_capacity(pending_funding_relay.len());
374                                         let mut txn_idxs = Vec::with_capacity(pending_funding_relay.len());
375                                         for (idx, tx) in pending_funding_relay.iter().enumerate() {
376                                                 txn.push(tx);
377                                                 txn_idxs.push(idx as u32 + 1);
378                                         }
379
380                                         loss_detector.connect_block(&txn[..], &txn_idxs[..]);
381                                         txn_idxs.clear();
382                                         for _ in 2..100 {
383                                                 loss_detector.connect_block(&txn[..], &txn_idxs[..]);
384                                         }
385                                 }
386                                 for tx in pending_funding_relay.drain(..) {
387                                         loss_detector.funding_txn.push(tx);
388                                 }
389                         },
390                         12 => {
391                                 let txlen = slice_to_be16(get_slice!(2));
392                                 if txlen == 0 {
393                                         loss_detector.connect_block(&[], &[]);
394                                 } else {
395                                         let txres: Result<Transaction, _> = deserialize(get_slice!(txlen));
396                                         if let Ok(tx) = txres {
397                                                 loss_detector.connect_block(&[&tx], &[1]);
398                                         } else {
399                                                 return;
400                                         }
401                                 }
402                         },
403                         13 => {
404                                 loss_detector.disconnect_block();
405                         },
406                         14 => {
407                                 let mut channels = channelmanager.list_channels();
408                                 let channel_id = get_slice!(1)[0] as usize;
409                                 if channel_id >= channels.len() { return; }
410                                 channels.sort_by(|a, b| { a.channel_id.cmp(&b.channel_id) });
411                                 channelmanager.force_close_channel(&channels[channel_id].channel_id);
412                         },
413                         _ => return,
414                 }
415                 loss_detector.handler.process_events();
416                 for event in loss_detector.handler.get_and_clear_pending_events() {
417                         match event {
418                                 Event::FundingGenerationReady { temporary_channel_id, channel_value_satoshis, output_script, .. } => {
419                                         pending_funding_generation.push((temporary_channel_id, channel_value_satoshis, output_script));
420                                 },
421                                 Event::FundingBroadcastSafe { funding_txo, .. } => {
422                                         pending_funding_relay.push(pending_funding_signatures.remove(&funding_txo).unwrap());
423                                 },
424                                 Event::PaymentReceived { payment_hash, .. } => {
425                                         payments_received.push(payment_hash);
426                                 },
427                                 Event::PaymentSent {..} => {},
428                                 Event::PaymentFailed {..} => {},
429
430                                 Event::PendingHTLCsForwardable {..} => {
431                                         should_forward = true;
432                                 },
433                                 _ => panic!("Unknown event"),
434                         }
435                 }
436         }
437 }
438
439 #[cfg(feature = "afl")]
440 #[macro_use] extern crate afl;
441 #[cfg(feature = "afl")]
442 fn main() {
443         fuzz!(|data| {
444                 let logger: Arc<Logger> = Arc::new(test_logger::TestLogger{});
445                 do_test(data, &logger);
446         });
447 }
448
449 #[cfg(feature = "honggfuzz")]
450 #[macro_use] extern crate honggfuzz;
451 #[cfg(feature = "honggfuzz")]
452 fn main() {
453         loop {
454                 fuzz!(|data| {
455                         let logger: Arc<Logger> = Arc::new(test_logger::TestLogger{});
456                         do_test(data, &logger);
457                 });
458         }
459 }
460
461 extern crate hex;
462 #[cfg(test)]
463 mod tests {
464         use utils::test_logger;
465         use lightning::util::logger::{Logger, Record};
466         use std::collections::HashMap;
467         use std::sync::{Arc, Mutex};
468
469         #[test]
470         fn duplicate_crash() {
471                 let logger: Arc<Logger> = Arc::new(test_logger::TestLogger{});
472                 super::do_test(&::hex::decode("00").unwrap(), &logger);
473         }
474
475         struct TrackingLogger {
476                 /// (module, message) -> count
477                 pub lines: Mutex<HashMap<(String, String), usize>>,
478         }
479         impl Logger for TrackingLogger {
480                 fn log(&self, record: &Record) {
481                         *self.lines.lock().unwrap().entry((record.module_path.to_string(), format!("{}", record.args))).or_insert(0) += 1;
482                         println!("{:<5} [{} : {}, {}] {}", record.level.to_string(), record.module_path, record.file, record.line, record.args);
483                 }
484         }
485
486         #[test]
487         fn test_no_existing_test_breakage() {
488                 // To avoid accidentally causing all existing fuzz test cases to be useless by making minor
489                 // changes (such as requesting feerate info in a new place), we run a pretty full
490                 // step-through with two peers and HTLC forwarding here. Obviously this is pretty finicky,
491                 // so this should be updated pretty liberally, but at least we'll know when changes occur.
492                 // If nothing else, this test serves as a pretty great initial full_stack_target seed.
493
494                 // What each byte represents is broken down below, and then everything is concatenated into
495                 // one large test at the end (you want %s/ -.*//g %s/\n\| \|\t\|\///g).
496
497                 // 0000000000000000000000000000000000000000000000000000000000000000 - our network key
498                 // 00000000 - fee_proportional_millionths
499                 // 01 - announce_channels_publicly
500                 //
501                 // 00 - new outbound connection with id 0
502                 // 030000000000000000000000000000000000000000000000000000000000000000 - peer's pubkey
503                 // 030032 - inbound read from peer id 0 of len 50
504                 // 00 030000000000000000000000000000000000000000000000000000000000000000 03000000000000000000000000000000 - noise act two (0||pubkey||mac)
505                 //
506                 // 030012 - inbound read from peer id 0 of len 18
507                 // 0006 03000000000000000000000000000000 - message header indicating message length 6
508                 // 030016 - inbound read from peer id 0 of len 22
509                 // 0010 00000000 03000000000000000000000000000000 - init message with no features (type 16)
510                 //
511                 // 030012 - inbound read from peer id 0 of len 18
512                 // 0141 03000000000000000000000000000000 - message header indicating message length 321
513                 // 0300fe - inbound read from peer id 0 of len 254
514                 // 0020 7500000000000000000000000000000000000000000000000000000000000000 ff4f00f805273c1b203bb5ebf8436bfde57b3be8c2f5e95d9491dbb181909679 000000000000c350 0000000000000000 0000000000000222 ffffffffffffffff 0000000000000222 0000000000000000 000000fd 0006 01e3 030000000000000000000000000000000000000000000000000000000000000001 030000000000000000000000000000000000000000000000000000000000000002 030000000000000000000000000000000000000000000000000000000000000003 030000000000000000000000000000000000000000000000000000000000000004 - beginning of open_channel message
515                 // 030053 - inbound read from peer id 0 of len 83
516                 // 030000000000000000000000000000000000000000000000000000000000000005 030000000000000000000000000000000000000000000000000000000000000000 01 03000000000000000000000000000000 - rest of open_channel and mac
517                 //
518                 // 00fd00fd00fd - Three feerate requests (all returning min feerate, which our open_channel also uses)
519                 // - client should now respond with accept_channel (CHECK 1: type 33 to peer 03000000)
520                 //
521                 // 030012 - inbound read from peer id 0 of len 18
522                 // 0084 03000000000000000000000000000000 - message header indicating message length 132
523                 // 030094 - inbound read from peer id 0 of len 148
524                 // 0022 ff4f00f805273c1b203bb5ebf8436bfde57b3be8c2f5e95d9491dbb181909679 3d00000000000000000000000000000000000000000000000000000000000000 0000 36000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000001 03000000000000000000000000000000 - funding_created and mac
525                 // - client should now respond with funding_signed (CHECK 2: type 35 to peer 03000000)
526                 //
527                 // 0c005e - connect a block with one transaction of len 94
528                 // 020000000100000000000000000000000000000000000000000000000000000000000000000000000000ffffffff0150c3000000000000220020ae0000000000000000000000000000000000000000000000000000000000000000000000 - the funding transaction
529                 // 0c0000 - connect a block with no transactions
530                 // 0c0000 - connect a block with no transactions
531                 // 0c0000 - connect a block with no transactions
532                 // 0c0000 - connect a block with no transactions
533                 // 0c0000 - connect a block with no transactions
534                 // 0c0000 - connect a block with no transactions
535                 // 0c0000 - connect a block with no transactions
536                 // 0c0000 - connect a block with no transactions
537                 // 0c0000 - connect a block with no transactions
538                 // 0c0000 - connect a block with no transactions
539                 // 0c0000 - connect a block with no transactions
540                 // 0c0000 - connect a block with no transactions
541                 // - by now client should have sent a funding_locked (CHECK 3: SendFundingLocked to 03000000 for chan 3d000000)
542                 //
543                 // 030012 - inbound read from peer id 0 of len 18
544                 // 0043 03000000000000000000000000000000 - message header indicating message length 67
545                 // 030053 - inbound read from peer id 0 of len 83
546                 // 0024 3d00000000000000000000000000000000000000000000000000000000000000 030100000000000000000000000000000000000000000000000000000000000000 03000000000000000000000000000000 - funding_locked and mac
547                 //
548                 // 01 - new inbound connection with id 1
549                 // 030132 - inbound read from peer id 1 of len 50
550                 // 0003000000000000000000000000000000000000000000000000000000000000000003000000000000000000000000000000 - inbound noise act 1
551                 // 030142 - inbound read from peer id 1 of len 66
552                 // 000302000000000000000000000000000000000000000000000000000000000000000300000000000000000000000000000003000000000000000000000000000000 - inbound noise act 3
553                 //
554                 // 030112 - inbound read from peer id 1 of len 18
555                 // 0006 01000000000000000000000000000000 - message header indicating message length 6
556                 // 030116 - inbound read from peer id 1 of len 22
557                 // 0010 00000000 01000000000000000000000000000000 - init message with no features (type 16)
558                 //
559                 // 05 01 030200000000000000000000000000000000000000000000000000000000000000 00c350 0003e8 - create outbound channel to peer 1 for 50k sat
560                 // 00fd00fd00fd - Three feerate requests (all returning min feerate)
561                 //
562                 // 030112 - inbound read from peer id 1 of len 18
563                 // 0110 01000000000000000000000000000000 - message header indicating message length 272
564                 // 0301ff - inbound read from peer id 1 of len 255
565                 // 0021 0200000000000000020000000000000002000000000000000200000000000000 000000000000001a 00000000004c4b40 00000000000003e8 00000000000003e8 00000002 03f0 0005 030000000000000000000000000000000000000000000000000000000000000100 030000000000000000000000000000000000000000000000000000000000000200 030000000000000000000000000000000000000000000000000000000000000300 030000000000000000000000000000000000000000000000000000000000000400 030000000000000000000000000000000000000000000000000000000000000500 03000000000000000000000000000000 - beginning of accept_channel
566                 // 030121 - inbound read from peer id 1 of len 33
567                 // 0000000000000000000000000000000000 01000000000000000000000000000000 - rest of accept_channel and mac
568                 //
569                 // 0a - create the funding transaction (client should send funding_created now)
570                 //
571                 // 030112 - inbound read from peer id 1 of len 18
572                 // 0062 01000000000000000000000000000000 - message header indicating message length 98
573                 // 030172 - inbound read from peer id 1 of len 114
574                 // 0023 3f00000000000000000000000000000000000000000000000000000000000000f6000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000100 01000000000000000000000000000000 - funding_signed message and mac
575                 //
576                 // 0b - broadcast funding transaction
577                 // - by now client should have sent a funding_locked (CHECK 4: SendFundingLocked to 03020000 for chan 3f000000)
578                 //
579                 // 030112 - inbound read from peer id 1 of len 18
580                 // 0043 01000000000000000000000000000000 - message header indicating message length 67
581                 // 030153 - inbound read from peer id 1 of len 83
582                 // 0024 3f00000000000000000000000000000000000000000000000000000000000000 030100000000000000000000000000000000000000000000000000000000000000 01000000000000000000000000000000 - funding_locked and mac
583                 //
584                 // 030012 - inbound read from peer id 0 of len 18
585                 // 05ac 03000000000000000000000000000000 - message header indicating message length 1452
586                 // 0300ff - inbound read from peer id 0 of len 255
587                 // 0080 3d00000000000000000000000000000000000000000000000000000000000000 0000000000000000 0000000000003e80 ff00000000000000000000000000000000000000000000000000000000000000 00000121 00 030000000000000000000000000000000000000000000000000000000000000555 0000000e000001000000000000000003e8000000010000000000000000000000000a00000000000000000000000000000000000000000000000000000000000000 ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff - beginning of update_add_htlc from 0 to 1 via client
588                 // 0300ff - inbound read from peer id 0 of len 255
589                 // ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff
590                 // 0300ff - inbound read from peer id 0 of len 255
591                 // ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff
592                 // 0300ff - inbound read from peer id 0 of len 255
593                 // ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff
594                 // 0300ff - inbound read from peer id 0 of len 255
595                 // ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff
596                 // 0300c1 - inbound read from peer id 0 of len 193
597                 // ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff ef00000000000000000000000000000000000000000000000000000000000000 03000000000000000000000000000000 - end of update_add_htlc from 0 to 1 via client and mac
598                 //
599                 // 00fd - A feerate request (returning min feerate, which our open_channel also uses)
600                 //
601                 // 030012 - inbound read from peer id 0 of len 18
602                 // 0064 03000000000000000000000000000000 - message header indicating message length 100
603                 // 030074 - inbound read from peer id 0 of len 116
604                 // 0084 3d00000000000000000000000000000000000000000000000000000000000000 27000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000001 0000 03000000000000000000000000000000 - commitment_signed and mac
605                 // - client should now respond with revoke_and_ack and commitment_signed (CHECK 5/6: types 133 and 132 to peer 03000000)
606                 //
607                 // 030012 - inbound read from peer id 0 of len 18
608                 // 0063 03000000000000000000000000000000 - message header indicating message length 99
609                 // 030073 - inbound read from peer id 0 of len 115
610                 // 0085 3d00000000000000000000000000000000000000000000000000000000000000 0000000000000000000000000000000000000000000000000000000000000000 030200000000000000000000000000000000000000000000000000000000000000 03000000000000000000000000000000 - revoke_and_ack and mac
611                 //
612                 // 07 - process the now-pending HTLC forward
613                 // - client now sends id 1 update_add_htlc and commitment_signed (CHECK 7: SendHTLCs event for node 03020000 with 1 HTLCs for channel 3f000000)
614                 //
615                 // - we respond with commitment_signed then revoke_and_ack (a weird, but valid, order)
616                 // 030112 - inbound read from peer id 1 of len 18
617                 // 0064 01000000000000000000000000000000 - message header indicating message length 100
618                 // 030174 - inbound read from peer id 1 of len 116
619                 // 0084 3f00000000000000000000000000000000000000000000000000000000000000 f7000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000100 0000 01000000000000000000000000000000 - commitment_signed and mac
620                 //
621                 // 030112 - inbound read from peer id 1 of len 18
622                 // 0063 01000000000000000000000000000000 - message header indicating message length 99
623                 // 030173 - inbound read from peer id 1 of len 115
624                 // 0085 3f00000000000000000000000000000000000000000000000000000000000000 0000000000000000000000000000000000000000000000000000000000000000 030200000000000000000000000000000000000000000000000000000000000000 01000000000000000000000000000000 - revoke_and_ack and mac
625                 //
626                 // 030112 - inbound read from peer id 1 of len 18
627                 // 004a 01000000000000000000000000000000 - message header indicating message length 74
628                 // 03015a - inbound read from peer id 1 of len 90
629                 // 0082 3f00000000000000000000000000000000000000000000000000000000000000 0000000000000000 ff00888888888888888888888888888888888888888888888888888888888888 01000000000000000000000000000000 - update_fulfill_htlc and mac
630                 // - client should immediately claim the pending HTLC from peer 0 (CHECK 8: SendFulfillHTLCs for node 03000000 with preimage ff00888888 for channel 3d000000)
631                 //
632                 // 030112 - inbound read from peer id 1 of len 18
633                 // 0064 01000000000000000000000000000000 - message header indicating message length 100
634                 // 030174 - inbound read from peer id 1 of len 116
635                 // 0084 3f00000000000000000000000000000000000000000000000000000000000000 fb000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000100 0000 01000000000000000000000000000000 - commitment_signed and mac
636                 //
637                 // 030112 - inbound read from peer id 1 of len 18
638                 // 0063 01000000000000000000000000000000 - message header indicating message length 99
639                 // 030173 - inbound read from peer id 1 of len 115
640                 // 0085 3f00000000000000000000000000000000000000000000000000000000000000 0100000000000000000000000000000000000000000000000000000000000000 030300000000000000000000000000000000000000000000000000000000000000 01000000000000000000000000000000 - revoke_and_ack and mac
641                 //
642                 // - before responding to the commitment_signed generated above, send a new HTLC
643                 // 030012 - inbound read from peer id 0 of len 18
644                 // 05ac 03000000000000000000000000000000 - message header indicating message length 1452
645                 // 0300ff - inbound read from peer id 0 of len 255
646                 // 0080 3d00000000000000000000000000000000000000000000000000000000000000 0000000000000001 0000000000003e80 ff00000000000000000000000000000000000000000000000000000000000000 00000121 00 030000000000000000000000000000000000000000000000000000000000000555 0000000e000001000000000000000003e8000000010000000000000000000000000a00000000000000000000000000000000000000000000000000000000000000 ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff - beginning of update_add_htlc from 0 to 1 via client
647                 // 0300ff - inbound read from peer id 0 of len 255
648                 // ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff
649                 // 0300ff - inbound read from peer id 0 of len 255
650                 // ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff
651                 // 0300ff - inbound read from peer id 0 of len 255
652                 // ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff
653                 // 0300ff - inbound read from peer id 0 of len 255
654                 // ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff
655                 // 0300c1 - inbound read from peer id 0 of len 193
656                 // ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff ef00000000000000000000000000000000000000000000000000000000000000 03000000000000000000000000000000 - end of update_add_htlc from 0 to 1 via client and mac
657                 //
658                 // 00fd - A feerate request (returning min feerate, which our open_channel also uses)
659                 //
660                 // - now respond to the update_fulfill_htlc+commitment_signed messages the client sent to peer 0
661                 // 030012 - inbound read from peer id 0 of len 18
662                 // 0063 03000000000000000000000000000000 - message header indicating message length 99
663                 // 030073 - inbound read from peer id 0 of len 115
664                 // 0085 3d00000000000000000000000000000000000000000000000000000000000000 0100000000000000000000000000000000000000000000000000000000000000 030300000000000000000000000000000000000000000000000000000000000000 03000000000000000000000000000000 - revoke_and_ack and mac
665                 // - client should now respond with revoke_and_ack and commitment_signed (CHECK 5/6 duplicates)
666                 //
667                 // 030012 - inbound read from peer id 0 of len 18
668                 // 0064 03000000000000000000000000000000 - message header indicating message length 100
669                 // 030074 - inbound read from peer id 0 of len 116
670                 // 0084 3d00000000000000000000000000000000000000000000000000000000000000 d4000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000001 0000 03000000000000000000000000000000 - commitment_signed and mac
671                 //
672                 // 030012 - inbound read from peer id 0 of len 18
673                 // 0063 03000000000000000000000000000000 - message header indicating message length 99
674                 // 030073 - inbound read from peer id 0 of len 115
675                 // 0085 3d00000000000000000000000000000000000000000000000000000000000000 0200000000000000000000000000000000000000000000000000000000000000 030400000000000000000000000000000000000000000000000000000000000000 03000000000000000000000000000000 - revoke_and_ack and mac
676                 //
677                 // 07 - process the now-pending HTLC forward
678                 // - client now sends id 1 update_add_htlc and commitment_signed (CHECK 7 duplicate)
679                 // - we respond with revoke_and_ack, then commitment_signed, then update_fail_htlc
680                 //
681                 // 030112 - inbound read from peer id 1 of len 18
682                 // 0064 01000000000000000000000000000000 - message header indicating message length 100
683                 // 030174 - inbound read from peer id 1 of len 116
684                 // 0084 3f00000000000000000000000000000000000000000000000000000000000000 fa000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000100 0000 01000000000000000000000000000000 - commitment_signed and mac
685                 //
686                 // 030112 - inbound read from peer id 1 of len 18
687                 // 0063 01000000000000000000000000000000 - message header indicating message length 99
688                 // 030173 - inbound read from peer id 1 of len 115
689                 // 0085 3f00000000000000000000000000000000000000000000000000000000000000 0200000000000000000000000000000000000000000000000000000000000000 030400000000000000000000000000000000000000000000000000000000000000 01000000000000000000000000000000 - revoke_and_ack and mac
690                 //
691                 // 030112 - inbound read from peer id 1 of len 18
692                 // 002c 01000000000000000000000000000000 - message header indicating message length 44
693                 // 03013c - inbound read from peer id 1 of len 60
694                 // 0083 3f00000000000000000000000000000000000000000000000000000000000000 0000000000000001 0000 01000000000000000000000000000000 - update_fail_htlc and mac
695                 //
696                 // 030112 - inbound read from peer id 1 of len 18
697                 // 0064 01000000000000000000000000000000 - message header indicating message length 100
698                 // 030174 - inbound read from peer id 1 of len 116
699                 // 0084 3f00000000000000000000000000000000000000000000000000000000000000 fd000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000100 0000 01000000000000000000000000000000 - commitment_signed and mac
700                 //
701                 // 030112 - inbound read from peer id 1 of len 18
702                 // 0063 01000000000000000000000000000000 - message header indicating message length 99
703                 // 030173 - inbound read from peer id 1 of len 115
704                 // 0085 3f00000000000000000000000000000000000000000000000000000000000000 0300000000000000000000000000000000000000000000000000000000000000 030500000000000000000000000000000000000000000000000000000000000000 01000000000000000000000000000000 - revoke_and_ack and mac
705                 //
706                 // - client now sends id 0 update_fail_htlc and commitment_signed (CHECK 9)
707                 // - now respond to the update_fail_htlc+commitment_signed messages the client sent to peer 0
708                 // 030012 - inbound read from peer id 0 of len 18
709                 // 0063 03000000000000000000000000000000 - message header indicating message length 99
710                 // 030073 - inbound read from peer id 0 of len 115
711                 // 0085 3d00000000000000000000000000000000000000000000000000000000000000 0300000000000000000000000000000000000000000000000000000000000000 030500000000000000000000000000000000000000000000000000000000000000 03000000000000000000000000000000 - revoke_and_ack and mac
712                 //
713                 // 030012 - inbound read from peer id 0 of len 18
714                 // 0064 03000000000000000000000000000000 - message header indicating message length 100
715                 // 030074 - inbound read from peer id 0 of len 116
716                 // 0084 3d00000000000000000000000000000000000000000000000000000000000000 25000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000001 0000 03000000000000000000000000000000 - commitment_signed and mac
717                 // - client should now respond with revoke_and_ack (CHECK 5 duplicate)
718                 //
719                 // 030012 - inbound read from peer id 0 of len 18
720                 // 05ac 03000000000000000000000000000000 - message header indicating message length 1452
721                 // 0300ff - inbound read from peer id 0 of len 255
722                 // 0080 3d00000000000000000000000000000000000000000000000000000000000000 0000000000000002 000000000003e900 ff00000000000000000000000000000000000000000000000000000000000000 00000121 00 030000000000000000000000000000000000000000000000000000000000000555 0000000e0000010000000000000003e800000000010000000000000000000000000a00000000000000000000000000000000000000000000000000000000000000 ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff - beginning of update_add_htlc from 0 to 1 via client
723                 // 0300ff - inbound read from peer id 0 of len 255
724                 // ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff
725                 // 0300ff - inbound read from peer id 0 of len 255
726                 // ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff
727                 // 0300ff - inbound read from peer id 0 of len 255
728                 // ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff
729                 // 0300ff - inbound read from peer id 0 of len 255
730                 // ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff
731                 // 0300c1 - inbound read from peer id 0 of len 193
732                 // ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff ef00000000000000000000000000000000000000000000000000000000000000 03000000000000000000000000000000 - end of update_add_htlc from 0 to 1 via client and mac
733                 //
734                 // 00fd - A feerate request (returning min feerate, which our open_channel also uses)
735                 //
736                 // 030012 - inbound read from peer id 0 of len 18
737                 // 00a4 03000000000000000000000000000000 - message header indicating message length 164
738                 // 0300b4 - inbound read from peer id 0 of len 180
739                 // 0084 3d00000000000000000000000000000000000000000000000000000000000000 68000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000001 0001 0a000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000d 00000000000000 03000000000000000000000000000000 - commitment_signed and mac
740                 // - client should now respond with revoke_and_ack and commitment_signed (CHECK 5/6 duplicates)
741                 //
742                 // 030012 - inbound read from peer id 0 of len 18
743                 // 0063 03000000000000000000000000000000 - message header indicating message length 99
744                 // 030073 - inbound read from peer id 0 of len 115
745                 // 0085 3d00000000000000000000000000000000000000000000000000000000000000 0400000000000000000000000000000000000000000000000000000000000000 030600000000000000000000000000000000000000000000000000000000000000 03000000000000000000000000000000 - revoke_and_ack and mac
746                 //
747                 // 07 - process the now-pending HTLC forward
748                 // - client now sends id 1 update_add_htlc and commitment_signed (CHECK 7 duplicate)
749                 //
750                 // 0c007d - connect a block with one transaction of len 125
751                 // 02000000013f00000000000000000000000000000000000000000000000000000000000000000000000000000080020001000000000000220020ed000000000000000000000000000000000000000000000000000000000000006cc10000000000001600142b88e0198963bf4c37de498583a3ccdb9d67e97405000020 - the funding transaction
752                 // 00fd - A feerate request (returning min feerate, which our open_channel also uses)
753                 // 0c005e - connect a block with one transaction of len 94
754                 // 0200000001ec00000000000000000000000000000000000000000000000000000000000000000000000000000000014f00000000000000220020f60000000000000000000000000000000000000000000000000000000000000000000000 - the funding transaction
755
756                 let logger = Arc::new(TrackingLogger { lines: Mutex::new(HashMap::new()) });
757                 super::do_test(&::hex::decode("00000000000000000000000000000000000000000000000000000000000000000000000001000300000000000000000000000000000000000000000000000000000000000000000300320003000000000000000000000000000000000000000000000000000000000000000003000000000000000000000000000000030012000603000000000000000000000000000000030016001000000000030000000000000000000000000000000300120141030000000000000000000000000000000300fe00207500000000000000000000000000000000000000000000000000000000000000ff4f00f805273c1b203bb5ebf8436bfde57b3be8c2f5e95d9491dbb181909679000000000000c35000000000000000000000000000000222ffffffffffffffff00000000000002220000000000000000000000fd000601e3030000000000000000000000000000000000000000000000000000000000000001030000000000000000000000000000000000000000000000000000000000000002030000000000000000000000000000000000000000000000000000000000000003030000000000000000000000000000000000000000000000000000000000000004030053030000000000000000000000000000000000000000000000000000000000000005030000000000000000000000000000000000000000000000000000000000000000010300000000000000000000000000000000fd00fd00fd0300120084030000000000000000000000000000000300940022ff4f00f805273c1b203bb5ebf8436bfde57b3be8c2f5e95d9491dbb1819096793d00000000000000000000000000000000000000000000000000000000000000000036000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000001030000000000000000000000000000000c005e020000000100000000000000000000000000000000000000000000000000000000000000000000000000ffffffff0150c3000000000000220020ae00000000000000000000000000000000000000000000000000000000000000000000000c00000c00000c00000c00000c00000c00000c00000c00000c00000c00000c00000c000003001200430300000000000000000000000000000003005300243d000000000000000000000000000000000000000000000000000000000000000301000000000000000000000000000000000000000000000000000000000000000300000000000000000000000000000001030132000300000000000000000000000000000000000000000000000000000000000000000300000000000000000000000000000003014200030200000000000000000000000000000000000000000000000000000000000000030000000000000000000000000000000300000000000000000000000000000003011200060100000000000000000000000000000003011600100000000001000000000000000000000000000000050103020000000000000000000000000000000000000000000000000000000000000000c3500003e800fd00fd00fd0301120110010000000000000000000000000000000301ff00210200000000000000020000000000000002000000000000000200000000000000000000000000001a00000000004c4b4000000000000003e800000000000003e80000000203f00005030000000000000000000000000000000000000000000000000000000000000100030000000000000000000000000000000000000000000000000000000000000200030000000000000000000000000000000000000000000000000000000000000300030000000000000000000000000000000000000000000000000000000000000400030000000000000000000000000000000000000000000000000000000000000500030000000000000000000000000000000301210000000000000000000000000000000000010000000000000000000000000000000a03011200620100000000000000000000000000000003017200233f00000000000000000000000000000000000000000000000000000000000000f6000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000100010000000000000000000000000000000b03011200430100000000000000000000000000000003015300243f000000000000000000000000000000000000000000000000000000000000000301000000000000000000000000000000000000000000000000000000000000000100000000000000000000000000000003001205ac030000000000000000000000000000000300ff00803d0000000000000000000000000000000000000000000000000000000000000000000000000000000000000000003e80ff0000000000000000000000000000000000000000000000000000000000000000000121000300000000000000000000000000000000000000000000000000000000000005550000000e000001000000000000000003e8000000010000000000000000000000000a00000000000000000000000000000000000000000000000000000000000000ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff0300ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff0300ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff0300ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff0300ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff0300c1ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffef000000000000000000000000000000000000000000000000000000000000000300000000000000000000000000000000fd03001200640300000000000000000000000000000003007400843d000000000000000000000000000000000000000000000000000000000000002700000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000100000300000000000000000000000000000003001200630300000000000000000000000000000003007300853d000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000030200000000000000000000000000000000000000000000000000000000000000030000000000000000000000000000000703011200640100000000000000000000000000000003017400843f00000000000000000000000000000000000000000000000000000000000000f700000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000010000000100000000000000000000000000000003011200630100000000000000000000000000000003017300853f00000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000003020000000000000000000000000000000000000000000000000000000000000001000000000000000000000000000000030112004a0100000000000000000000000000000003015a00823f000000000000000000000000000000000000000000000000000000000000000000000000000000ff008888888888888888888888888888888888888888888888888888888888880100000000000000000000000000000003011200640100000000000000000000000000000003017400843f00000000000000000000000000000000000000000000000000000000000000fb00000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000010000000100000000000000000000000000000003011200630100000000000000000000000000000003017300853f0000000000000000000000000000000000000000000000000000000000000001000000000000000000000000000000000000000000000000000000000000000303000000000000000000000000000000000000000000000000000000000000000100000000000000000000000000000003001205ac030000000000000000000000000000000300ff00803d0000000000000000000000000000000000000000000000000000000000000000000000000000010000000000003e80ff0000000000000000000000000000000000000000000000000000000000000000000121000300000000000000000000000000000000000000000000000000000000000005550000000e000001000000000000000003e8000000010000000000000000000000000a00000000000000000000000000000000000000000000000000000000000000ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff0300ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff0300ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff0300ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff0300ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff0300c1ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffef000000000000000000000000000000000000000000000000000000000000000300000000000000000000000000000000fd03001200630300000000000000000000000000000003007300853d0000000000000000000000000000000000000000000000000000000000000001000000000000000000000000000000000000000000000000000000000000000303000000000000000000000000000000000000000000000000000000000000000300000000000000000000000000000003001200640300000000000000000000000000000003007400843d00000000000000000000000000000000000000000000000000000000000000d400000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000100000300000000000000000000000000000003001200630300000000000000000000000000000003007300853d000000000000000000000000000000000000000000000000000000000000000200000000000000000000000000000000000000000000000000000000000000030400000000000000000000000000000000000000000000000000000000000000030000000000000000000000000000000703011200640100000000000000000000000000000003017400843f00000000000000000000000000000000000000000000000000000000000000fa00000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000010000000100000000000000000000000000000003011200630100000000000000000000000000000003017300853f00000000000000000000000000000000000000000000000000000000000000020000000000000000000000000000000000000000000000000000000000000003040000000000000000000000000000000000000000000000000000000000000001000000000000000000000000000000030112002c0100000000000000000000000000000003013c00833f00000000000000000000000000000000000000000000000000000000000000000000000000000100000100000000000000000000000000000003011200640100000000000000000000000000000003017400843f00000000000000000000000000000000000000000000000000000000000000fd00000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000010000000100000000000000000000000000000003011200630100000000000000000000000000000003017300853f0000000000000000000000000000000000000000000000000000000000000003000000000000000000000000000000000000000000000000000000000000000305000000000000000000000000000000000000000000000000000000000000000100000000000000000000000000000003001200630300000000000000000000000000000003007300853d0000000000000000000000000000000000000000000000000000000000000003000000000000000000000000000000000000000000000000000000000000000305000000000000000000000000000000000000000000000000000000000000000300000000000000000000000000000003001200640300000000000000000000000000000003007400843d000000000000000000000000000000000000000000000000000000000000002500000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000100000300000000000000000000000000000003001205ac030000000000000000000000000000000300ff00803d000000000000000000000000000000000000000000000000000000000000000000000000000002000000000003e900ff0000000000000000000000000000000000000000000000000000000000000000000121000300000000000000000000000000000000000000000000000000000000000005550000000e0000010000000000000003e800000000010000000000000000000000000a00000000000000000000000000000000000000000000000000000000000000ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff0300ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff0300ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff0300ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff0300ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffff0300c1ffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffffef000000000000000000000000000000000000000000000000000000000000000300000000000000000000000000000000fd03001200a4030000000000000000000000000000000300b400843d000000000000000000000000000000000000000000000000000000000000006800000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000100010a000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000d000000000000000300000000000000000000000000000003001200630300000000000000000000000000000003007300853d00000000000000000000000000000000000000000000000000000000000000040000000000000000000000000000000000000000000000000000000000000003060000000000000000000000000000000000000000000000000000000000000003000000000000000000000000000000070c007d02000000013f00000000000000000000000000000000000000000000000000000000000000000000000000000080020001000000000000220020ed000000000000000000000000000000000000000000000000000000000000006cc10000000000001600142b88e0198963bf4c37de498583a3ccdb9d67e9740500002000fd0c005e0200000001ec00000000000000000000000000000000000000000000000000000000000000000000000000000000014f00000000000000220020f60000000000000000000000000000000000000000000000000000000000000000000000").unwrap(), &(Arc::clone(&logger) as Arc<Logger>));
758
759                 let log_entries = logger.lines.lock().unwrap();
760                 assert_eq!(log_entries.get(&("lightning::ln::peer_handler".to_string(), "Encoding and sending message of type 33 to 030000000000000000000000000000000000000000000000000000000000000000".to_string())), Some(&1)); // 1
761                 assert_eq!(log_entries.get(&("lightning::ln::peer_handler".to_string(), "Encoding and sending message of type 35 to 030000000000000000000000000000000000000000000000000000000000000000".to_string())), Some(&1)); // 2
762                 assert_eq!(log_entries.get(&("lightning::ln::peer_handler".to_string(), "Handling SendFundingLocked event in peer_handler for node 030000000000000000000000000000000000000000000000000000000000000000 for channel 3d00000000000000000000000000000000000000000000000000000000000000".to_string())), Some(&1)); // 3
763                 assert_eq!(log_entries.get(&("lightning::ln::peer_handler".to_string(), "Handling SendFundingLocked event in peer_handler for node 030200000000000000000000000000000000000000000000000000000000000000 for channel 3f00000000000000000000000000000000000000000000000000000000000000".to_string())), Some(&1)); // 4
764                 assert_eq!(log_entries.get(&("lightning::ln::peer_handler".to_string(), "Encoding and sending message of type 133 to 030000000000000000000000000000000000000000000000000000000000000000".to_string())), Some(&4)); // 5
765                 assert_eq!(log_entries.get(&("lightning::ln::peer_handler".to_string(), "Encoding and sending message of type 132 to 030000000000000000000000000000000000000000000000000000000000000000".to_string())), Some(&3)); // 6
766                 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 3f00000000000000000000000000000000000000000000000000000000000000".to_string())), Some(&3)); // 7
767                 assert_eq!(log_entries.get(&("lightning::ln::peer_handler".to_string(), "Handling UpdateHTLCs event in peer_handler for node 030000000000000000000000000000000000000000000000000000000000000000 with 0 adds, 1 fulfills, 0 fails for channel 3d00000000000000000000000000000000000000000000000000000000000000".to_string())), Some(&1)); // 8
768                 assert_eq!(log_entries.get(&("lightning::ln::peer_handler".to_string(), "Handling UpdateHTLCs event in peer_handler for node 030000000000000000000000000000000000000000000000000000000000000000 with 0 adds, 0 fulfills, 1 fails for channel 3d00000000000000000000000000000000000000000000000000000000000000".to_string())), Some(&1)); // 9
769         }
770 }