X-Git-Url: http://git.bitcoin.ninja/index.cgi?a=blobdiff_plain;f=lightning%2Fsrc%2Futil%2Fmacro_logger.rs;h=01a0d3ff45f192b4744a8d9ef20eb614bf3f169d;hb=a031ff952bee42aad38edd29ede6aaa05a4cb6cb;hp=3ac294fbff9097686d4d28df669bcab76c402f5e;hpb=7fa6a7d48e5cec139481ceee95baadb2cf06486e;p=rust-lightning diff --git a/lightning/src/util/macro_logger.rs b/lightning/src/util/macro_logger.rs index 3ac294fb..01a0d3ff 100644 --- a/lightning/src/util/macro_logger.rs +++ b/lightning/src/util/macro_logger.rs @@ -12,10 +12,11 @@ use chain::keysinterface::SpendableOutputDescriptor; use bitcoin::hash_types::Txid; use bitcoin::blockdata::transaction::Transaction; -use bitcoin::secp256k1::key::PublicKey; +use bitcoin::secp256k1::PublicKey; use routing::router::Route; -use ln::chan_utils::HTLCType; +use ln::chan_utils::HTLCClaim; +use util::logger::DebugBytes; pub(crate) struct DebugPubKey<'a>(pub &'a PublicKey); impl<'a> core::fmt::Display for DebugPubKey<'a> { @@ -32,18 +33,11 @@ macro_rules! log_pubkey { } } -pub(crate) struct DebugBytes<'a>(pub &'a [u8]); -impl<'a> core::fmt::Display for DebugBytes<'a> { - fn fmt(&self, f: &mut core::fmt::Formatter) -> Result<(), core::fmt::Error> { - for i in self.0 { - write!(f, "{:02x}", i)?; - } - Ok(()) - } -} +/// Logs a byte slice in hex format. +#[macro_export] macro_rules! log_bytes { ($obj: expr) => { - ::util::macro_logger::DebugBytes(&$obj) + $crate::util::logger::DebugBytes(&$obj) } } @@ -96,25 +90,34 @@ pub(crate) struct DebugTx<'a>(pub &'a Transaction); impl<'a> core::fmt::Display for DebugTx<'a> { fn fmt(&self, f: &mut core::fmt::Formatter) -> Result<(), core::fmt::Error> { if self.0.input.len() >= 1 && self.0.input.iter().any(|i| !i.witness.is_empty()) { - if self.0.input.len() == 1 && self.0.input[0].witness.last().unwrap().len() == 71 && - (self.0.input[0].sequence >> 8*3) as u8 == 0x80 { + let first_input = &self.0.input[0]; + let witness_script_len = first_input.witness.last().unwrap_or(&[]).len(); + if self.0.input.len() == 1 && witness_script_len == 71 && + (first_input.sequence.0 >> 8*3) as u8 == 0x80 { write!(f, "commitment tx ")?; - } else if self.0.input.len() == 1 && self.0.input[0].witness.last().unwrap().len() == 71 { + } else if self.0.input.len() == 1 && witness_script_len == 71 { write!(f, "closing tx ")?; - } else if self.0.input.len() == 1 && HTLCType::scriptlen_to_htlctype(self.0.input[0].witness.last().unwrap().len()) == Some(HTLCType::OfferedHTLC) && - self.0.input[0].witness.len() == 5 { + } else if self.0.input.len() == 1 && HTLCClaim::from_witness(&first_input.witness) == Some(HTLCClaim::OfferedTimeout) { write!(f, "HTLC-timeout tx ")?; - } else if self.0.input.len() == 1 && HTLCType::scriptlen_to_htlctype(self.0.input[0].witness.last().unwrap().len()) == Some(HTLCType::AcceptedHTLC) && - self.0.input[0].witness.len() == 5 { + } else if self.0.input.len() == 1 && HTLCClaim::from_witness(&first_input.witness) == Some(HTLCClaim::AcceptedPreimage) { write!(f, "HTLC-success tx ")?; } else { + let mut num_preimage = 0; + let mut num_timeout = 0; + let mut num_revoked = 0; for inp in &self.0.input { - if !inp.witness.is_empty() { - if HTLCType::scriptlen_to_htlctype(inp.witness.last().unwrap().len()) == Some(HTLCType::OfferedHTLC) { write!(f, "preimage-")?; break } - else if HTLCType::scriptlen_to_htlctype(inp.witness.last().unwrap().len()) == Some(HTLCType::AcceptedHTLC) { write!(f, "timeout-")?; break } + let htlc_claim = HTLCClaim::from_witness(&inp.witness); + match htlc_claim { + Some(HTLCClaim::AcceptedPreimage)|Some(HTLCClaim::OfferedPreimage) => num_preimage += 1, + Some(HTLCClaim::AcceptedTimeout)|Some(HTLCClaim::OfferedTimeout) => num_timeout += 1, + Some(HTLCClaim::Revocation) => num_revoked += 1, + None => continue, } } - write!(f, "tx ")?; + if num_preimage > 0 || num_timeout > 0 || num_revoked > 0 { + write!(f, "HTLC claim tx ({} preimage, {} timeout, {} revoked)", + num_preimage, num_timeout, num_revoked)?; + } } } else { debug_assert!(false, "We should never generate unknown transaction types"); @@ -142,7 +145,7 @@ impl<'a> core::fmt::Display for DebugSpendable<'a> { write!(f, "DelayedPaymentOutput {}:{} marked for spending", descriptor.outpoint.txid, descriptor.outpoint.index)?; } &SpendableOutputDescriptor::StaticPaymentOutput(ref descriptor) => { - write!(f, "DynamicOutputP2WPKH {}:{} marked for spending", descriptor.outpoint.txid, descriptor.outpoint.index)?; + write!(f, "StaticPaymentOutput {}:{} marked for spending", descriptor.outpoint.txid, descriptor.outpoint.index)?; } } Ok(()) @@ -157,14 +160,16 @@ macro_rules! log_spendable { /// Create a new Record and log it. You probably don't want to use this macro directly, /// but it needs to be exported so `log_trace` etc can use it in external crates. +#[doc(hidden)] #[macro_export] macro_rules! log_internal { ($logger: expr, $lvl:expr, $($arg:tt)+) => ( - $logger.log(&$crate::util::logger::Record::new($lvl, format_args!($($arg)+), module_path!(), file!(), line!())); + $logger.log(&$crate::util::logger::Record::new($lvl, format_args!($($arg)+), module_path!(), file!(), line!())) ); } /// Logs an entry at the given level. +#[doc(hidden)] #[macro_export] macro_rules! log_given_level { ($logger: expr, $lvl:expr, $($arg:tt)+) => ( @@ -179,8 +184,10 @@ macro_rules! log_given_level { $crate::util::logger::Level::Debug => log_internal!($logger, $lvl, $($arg)*), #[cfg(not(any(feature = "max_level_off", feature = "max_level_error", feature = "max_level_warn", feature = "max_level_info", feature = "max_level_debug")))] $crate::util::logger::Level::Trace => log_internal!($logger, $lvl, $($arg)*), + #[cfg(not(any(feature = "max_level_off", feature = "max_level_error", feature = "max_level_warn", feature = "max_level_info", feature = "max_level_debug", feature = "max_level_trace")))] + $crate::util::logger::Level::Gossip => log_internal!($logger, $lvl, $($arg)*), - #[cfg(any(feature = "max_level_off", feature = "max_level_error", feature = "max_level_warn", feature = "max_level_info", feature = "max_level_debug"))] + #[cfg(any(feature = "max_level_off", feature = "max_level_error", feature = "max_level_warn", feature = "max_level_info", feature = "max_level_debug", feature = "max_level_trace"))] _ => { // The level is disabled at compile-time }, @@ -188,7 +195,7 @@ macro_rules! log_given_level { ); } -/// Log an error. +/// Log at the `ERROR` level. #[macro_export] macro_rules! log_error { ($logger: expr, $($arg:tt)*) => ( @@ -196,28 +203,42 @@ macro_rules! log_error { ) } +/// Log at the `WARN` level. +#[macro_export] macro_rules! log_warn { ($logger: expr, $($arg:tt)*) => ( log_given_level!($logger, $crate::util::logger::Level::Warn, $($arg)*); ) } +/// Log at the `INFO` level. +#[macro_export] macro_rules! log_info { ($logger: expr, $($arg:tt)*) => ( log_given_level!($logger, $crate::util::logger::Level::Info, $($arg)*); ) } +/// Log at the `DEBUG` level. +#[macro_export] macro_rules! log_debug { ($logger: expr, $($arg:tt)*) => ( log_given_level!($logger, $crate::util::logger::Level::Debug, $($arg)*); ) } -/// Log a trace log. +/// Log at the `TRACE` level. #[macro_export] macro_rules! log_trace { ($logger: expr, $($arg:tt)*) => ( - log_given_level!($logger, $crate::util::logger::Level::Trace, $($arg)*); + log_given_level!($logger, $crate::util::logger::Level::Trace, $($arg)*) + ) +} + +/// Log at the `GOSSIP` level. +#[macro_export] +macro_rules! log_gossip { + ($logger: expr, $($arg:tt)*) => ( + log_given_level!($logger, $crate::util::logger::Level::Gossip, $($arg)*); ) }