Nov 18 07:13:34.725 INFO testing lightning-0.0.5 against master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c for pr-55982
Nov 18 07:13:34.725 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-7/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-alt build --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Nov 18 07:13:35.069 INFO [stdout] d130c1e69ee17b96e8f47a53b4159bd92143a2993fc5ce0689c8585a7e8d1ee8
Nov 18 07:13:35.069 INFO running `"docker" "start" "-a" "d130c1e69ee17b96e8f47a53b4159bd92143a2993fc5ce0689c8585a7e8d1ee8"`
Nov 18 07:13:36.209 INFO [stderr] usermod: no changes
Nov 18 07:13:36.298 INFO [stderr]    Compiling gcc v0.3.55
Nov 18 07:13:36.312 INFO [stderr]    Compiling libc v0.2.43
Nov 18 07:13:36.312 INFO [stderr]    Compiling cc v1.0.25
Nov 18 07:13:36.312 INFO [stderr]    Compiling bech32 v0.5.0
Nov 18 07:13:36.312 INFO [stderr]    Compiling rustc-serialize v0.3.24
Nov 18 07:13:36.312 INFO [stderr]    Compiling hex v0.3.2
Nov 18 07:13:36.312 INFO [stderr]    Compiling byteorder v1.2.7
Nov 18 07:14:01.279 INFO [stderr]    Compiling rand v0.4.3
Nov 18 07:14:01.519 INFO [stderr]    Compiling time v0.1.40
Nov 18 07:14:01.809 INFO [stderr]    Compiling bitcoin-bech32 v0.8.1
Nov 18 07:14:01.951 INFO [stderr]    Compiling secp256k1 v0.9.2
Nov 18 07:14:02.039 INFO [stderr]    Compiling rust-crypto v0.2.36
Nov 18 07:14:04.250 INFO [stderr]    Compiling bitcoinconsensus v0.16.3
Nov 18 07:14:04.250 INFO [stderr]    Compiling secp256k1 v0.11.5
Nov 18 07:14:10.425 INFO [stderr]    Compiling lightning v0.0.5 (/source)
Nov 18 07:14:10.863 INFO [stderr]    Compiling rand v0.3.22
Nov 18 07:14:37.055 INFO [stderr]    Compiling bitcoin v0.14.2
Nov 18 07:14:48.470 INFO [stderr] warning: unused macro definition
Nov 18 07:14:48.470 INFO [stderr]   --> src/util/macro_logger.rs:83:1
Nov 18 07:14:48.470 INFO [stderr]    |
Nov 18 07:14:48.470 INFO [stderr] 83 | / macro_rules! log_warn {
Nov 18 07:14:48.470 INFO [stderr] 84 | |     ($self: ident, $($arg:tt)*) => (
Nov 18 07:14:48.470 INFO [stderr] 85 | |         #[cfg(not(any(feature = "max_level_off", feature = "max_level_error")))]
Nov 18 07:14:48.470 INFO [stderr] 86 | |         log_internal!($self, $crate::util::logger::Level::Warn, $($arg)*);
Nov 18 07:14:48.470 INFO [stderr] 87 | |     )
Nov 18 07:14:48.470 INFO [stderr] 88 | | }
Nov 18 07:14:48.470 INFO [stderr]    | |_^
Nov 18 07:14:48.470 INFO [stderr]    |
Nov 18 07:14:48.470 INFO [stderr]    = note: #[warn(unused_macros)] on by default
Nov 18 07:14:48.471 INFO [stderr] 
Nov 18 07:14:52.448 INFO [stderr] warning: function is never used: `sort_inputs`
Nov 18 07:14:52.449 INFO [stderr]   --> src/util/transaction_utils.rs:21:1
Nov 18 07:14:52.449 INFO [stderr]    |
Nov 18 07:14:52.450 INFO [stderr] 21 | pub fn sort_inputs<T>(inputs: &mut Vec<(TxIn, T)>) {
Nov 18 07:14:52.450 INFO [stderr]    | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Nov 18 07:14:52.451 INFO [stderr]    |
Nov 18 07:14:52.451 INFO [stderr]    = note: #[warn(dead_code)] on by default
Nov 18 07:14:52.451 INFO [stderr] 
Nov 18 07:14:52.451 INFO [stderr] warning: field is never used: `logger`
Nov 18 07:14:52.452 INFO [stderr]    --> src/chain/chaininterface.rs:198:2
Nov 18 07:14:52.452 INFO [stderr]     |
Nov 18 07:14:52.452 INFO [stderr] 198 |     logger: Arc<Logger>,
Nov 18 07:14:52.453 INFO [stderr]     |     ^^^^^^^^^^^^^^^^^^^
Nov 18 07:14:52.453 INFO [stderr] 
Nov 18 07:14:52.453 INFO [stderr] warning: variant is never constructed: `SigsMode`
Nov 18 07:14:52.454 INFO [stderr]    --> src/ln/channelmonitor.rs:158:2
Nov 18 07:14:52.454 INFO [stderr]     |
Nov 18 07:14:52.454 INFO [stderr] 158 |       SigsMode {
Nov 18 07:14:52.455 INFO [stderr]     |  _____^
Nov 18 07:14:52.455 INFO [stderr] 159 | |         revocation_base_key: PublicKey,
Nov 18 07:14:52.455 INFO [stderr] 160 | |         htlc_base_key: PublicKey,
Nov 18 07:14:52.456 INFO [stderr] 161 | |         sigs: HashMap<Sha256dHash, Signature>,
Nov 18 07:14:52.456 INFO [stderr] 162 | |     }
Nov 18 07:14:52.456 INFO [stderr]     | |_____^
Nov 18 07:14:52.457 INFO [stderr] 
Nov 18 07:14:52.457 INFO [stderr] warning: field is never used: `time_created`
Nov 18 07:14:52.457 INFO [stderr]    --> src/ln/channel.rs:195:3
Nov 18 07:14:52.458 INFO [stderr]     |
Nov 18 07:14:52.458 INFO [stderr] 195 |         time_created: Instant, //TODO: Some kind of timeout thing-a-majig
Nov 18 07:14:52.458 INFO [stderr]     |         ^^^^^^^^^^^^^^^^^^^^^
Nov 18 07:14:52.458 INFO [stderr] 
Nov 18 07:15:12.707 INFO [stderr]     Finished dev [optimized + debuginfo] target(s) in 1m 36s
Nov 18 07:15:12.707 INFO [stderr] su: No module specific data is present
Nov 18 07:15:13.207 INFO running `"docker" "inspect" "d130c1e69ee17b96e8f47a53b4159bd92143a2993fc5ce0689c8585a7e8d1ee8"`
Nov 18 07:15:13.411 INFO running `"docker" "rm" "-f" "d130c1e69ee17b96e8f47a53b4159bd92143a2993fc5ce0689c8585a7e8d1ee8"`
Nov 18 07:15:13.594 INFO [stdout] d130c1e69ee17b96e8f47a53b4159bd92143a2993fc5ce0689c8585a7e8d1ee8
Nov 18 07:15:13.596 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-7/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-alt test --frozen --no-run" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Nov 18 07:15:13.952 INFO [stdout] f63f1799afbd9f194d16800f4cf24090c87b8adaaa3314975cf324aff69a191e
Nov 18 07:15:13.954 INFO running `"docker" "start" "-a" "f63f1799afbd9f194d16800f4cf24090c87b8adaaa3314975cf324aff69a191e"`
Nov 18 07:15:14.840 INFO [stderr] usermod: no changes
Nov 18 07:15:14.920 INFO [stderr]    Compiling lightning v0.0.5 (/source)
Nov 18 07:15:24.268 INFO [stderr] warning: field is never used: `logger`
Nov 18 07:15:24.268 INFO [stderr]    --> src/chain/chaininterface.rs:198:2
Nov 18 07:15:24.268 INFO [stderr]     |
Nov 18 07:15:24.268 INFO [stderr] 198 |     logger: Arc<Logger>,
Nov 18 07:15:24.269 INFO [stderr]     |     ^^^^^^^^^^^^^^^^^^^
Nov 18 07:15:24.269 INFO [stderr]     |
Nov 18 07:15:24.269 INFO [stderr]     = note: #[warn(dead_code)] on by default
Nov 18 07:15:24.269 INFO [stderr] 
Nov 18 07:15:24.269 INFO [stderr] warning: variant is never constructed: `SigsMode`
Nov 18 07:15:24.270 INFO [stderr]    --> src/ln/channelmonitor.rs:158:2
Nov 18 07:15:24.270 INFO [stderr]     |
Nov 18 07:15:24.270 INFO [stderr] 158 |       SigsMode {
Nov 18 07:15:24.271 INFO [stderr]     |  _____^
Nov 18 07:15:24.271 INFO [stderr] 159 | |         revocation_base_key: PublicKey,
Nov 18 07:15:24.271 INFO [stderr] 160 | |         htlc_base_key: PublicKey,
Nov 18 07:15:24.271 INFO [stderr] 161 | |         sigs: HashMap<Sha256dHash, Signature>,
Nov 18 07:15:24.272 INFO [stderr] 162 | |     }
Nov 18 07:15:24.272 INFO [stderr]     | |_____^
Nov 18 07:15:24.272 INFO [stderr] 
Nov 18 07:15:24.272 INFO [stderr] warning: field is never used: `time_created`
Nov 18 07:15:24.273 INFO [stderr]    --> src/ln/channel.rs:195:3
Nov 18 07:15:24.273 INFO [stderr]     |
Nov 18 07:15:24.273 INFO [stderr] 195 |         time_created: Instant, //TODO: Some kind of timeout thing-a-majig
Nov 18 07:15:24.274 INFO [stderr]     |         ^^^^^^^^^^^^^^^^^^^^^
Nov 18 07:15:24.274 INFO [stderr] 
Nov 18 07:15:33.507 INFO [stderr]     Finished dev [optimized + debuginfo] target(s) in 18.62s
Nov 18 07:15:33.508 INFO [stderr] su: No module specific data is present
Nov 18 07:15:34.027 INFO running `"docker" "inspect" "f63f1799afbd9f194d16800f4cf24090c87b8adaaa3314975cf324aff69a191e"`
Nov 18 07:15:34.342 INFO running `"docker" "rm" "-f" "f63f1799afbd9f194d16800f4cf24090c87b8adaaa3314975cf324aff69a191e"`
Nov 18 07:15:34.598 INFO [stdout] f63f1799afbd9f194d16800f4cf24090c87b8adaaa3314975cf324aff69a191e
Nov 18 07:15:34.607 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-7/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-alt test --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Nov 18 07:15:35.055 INFO [stdout] 83eceaa5b4b1305eab87bb7beb766f1e8089670a7ea9036caa6661e6b4a549d4
Nov 18 07:15:35.062 INFO running `"docker" "start" "-a" "83eceaa5b4b1305eab87bb7beb766f1e8089670a7ea9036caa6661e6b4a549d4"`
Nov 18 07:15:36.003 INFO [stderr] usermod: no changes
Nov 18 07:15:36.092 INFO [stderr]     Finished dev [optimized + debuginfo] target(s) in 0.05s
Nov 18 07:15:36.107 INFO [stderr]      Running /target/debug/deps/lightning-00cc06a448a16be3
Nov 18 07:15:36.124 INFO [stdout] 
Nov 18 07:15:36.124 INFO [stdout] running 33 tests
Nov 18 07:15:36.131 INFO [stdout] test ln::channel::tests::test_max_funding_satoshis ... ok
Nov 18 07:15:36.167 INFO [stdout] test ln::channel::tests::test_per_commitment_secret_gen ... ok
Nov 18 07:15:36.167 INFO [stdout] test chain::transaction::tests::test_channel_id_calculation ... ok
Nov 18 07:15:36.300 INFO [stdout] test ln::channelmanager::tests::onion_vectors ... ok
Nov 18 07:15:36.307 INFO [stdout] test ln::channel::tests::test_key_derivation ... ok
Nov 18 07:15:36.323 INFO [stdout] test ln::channelmanager::tests::test_failure_packet_onion ... ok
Nov 18 07:15:36.671 INFO [stdout] test ln::channel::tests::outbound_commitment_test ... ok
Nov 18 07:15:38.119 INFO [stdout] test ln::channelmanager::tests::test_invalid_channel_announcement ... ok
Nov 18 07:15:38.359 INFO [stdout] test ln::channelmanager::tests::test_unconf_chan ... ok
Nov 18 07:15:38.499 INFO [stdout] test ln::channelmanager::tests::test_htlc_ignore_latest_remote_commitment ... ok
Nov 18 07:15:38.499 INFO [stdout] test ln::msgs::tests::encoding_channel_reestablish_no_secret ... ok
Nov 18 07:15:38.598 INFO [stdout] test ln::msgs::tests::encoding_channel_reestablish_with_secret ... ok
Nov 18 07:15:38.643 INFO [stdout] test ln::channelmonitor::tests::test_prune_preimages ... ok
Nov 18 07:15:38.919 INFO [stdout] test ln::peer_channel_encryptor::tests::noise_initiator_test_vectors ... ok
Nov 18 07:15:38.929 INFO [stdout] test ln::channelmonitor::tests::test_per_commitment_storage ... ok
Nov 18 07:15:38.998 INFO [stdout] test ln::peer_channel_encryptor::tests::message_encryption_decryption_test_vectors ... ok
Nov 18 07:15:39.032 INFO [stdout] test ln::router::tests::route_test ... ok
Nov 18 07:15:39.040 INFO [stdout] test util::logger::tests::test_level_show ... ok
Nov 18 07:15:39.048 INFO [stdout] test util::logger::tests::test_logging_macros ... ok
Nov 18 07:15:39.062 INFO [stdout] test ln::peer_channel_encryptor::tests::noise_responder_test_vectors ... ok
Nov 18 07:15:39.062 INFO [stdout] test util::transaction_utils::tests::bip69_txin_test_1 ... ok
Nov 18 07:15:39.066 INFO [stdout] test util::transaction_utils::tests::bip69_txin_test_2 ... ok
Nov 18 07:15:39.107 INFO [stdout] test util::transaction_utils::tests::bip69_txout_test_2 ... ok
Nov 18 07:15:39.119 INFO [stdout] test util::transaction_utils::tests::sort_output_by_bip_test ... ok
Nov 18 07:15:39.124 INFO [stdout] test util::transaction_utils::tests::sort_output_by_script_pubkey ... ok
Nov 18 07:15:39.124 INFO [stdout] test util::transaction_utils::tests::sort_output_by_value ... ok
Nov 18 07:15:39.124 INFO [stdout] test util::transaction_utils::tests::bip69_txout_test_1 ... ok
Nov 18 07:15:39.142 INFO [stdout] test ln::peer_handler::tests::test_disconnect_peer ... ok
Nov 18 07:15:39.544 INFO [stdout] test ln::channelmanager::tests::test_force_close_fail_back ... ok
Nov 18 07:15:41.607 INFO [stdout] test ln::channelmanager::tests::duplicate_htlc_test ... ok
Nov 18 07:15:42.194 INFO [stdout] test ln::channelmanager::tests::test_simple_peer_disconnect ... ok
Nov 18 07:15:44.739 INFO [stdout] test ln::channelmanager::tests::channel_monitor_network_test ... ok
Nov 18 07:15:49.862 INFO [stdout] test ln::channelmanager::tests::fake_network_test ... ok
Nov 18 07:15:49.863 INFO [stdout] 
Nov 18 07:15:49.863 INFO [stdout] test result: ok. 33 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 07:15:49.863 INFO [stdout] 
Nov 18 07:15:49.871 INFO [stderr]    Doc-tests lightning
Nov 18 07:15:52.506 INFO [stdout] 
Nov 18 07:15:52.506 INFO [stdout] running 0 tests
Nov 18 07:15:52.506 INFO [stdout] 
Nov 18 07:15:52.507 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 07:15:52.507 INFO [stdout] 
Nov 18 07:15:52.531 INFO [stderr] su: No module specific data is present
Nov 18 07:15:53.308 INFO running `"docker" "inspect" "83eceaa5b4b1305eab87bb7beb766f1e8089670a7ea9036caa6661e6b4a549d4"`
Nov 18 07:15:53.513 INFO running `"docker" "rm" "-f" "83eceaa5b4b1305eab87bb7beb766f1e8089670a7ea9036caa6661e6b4a549d4"`
Nov 18 07:15:53.687 INFO [stdout] 83eceaa5b4b1305eab87bb7beb766f1e8089670a7ea9036caa6661e6b4a549d4