Nov 18 07:13:32.487 INFO testing lightning-0.0.5 against try#2e026d6e5d7621634147ad4c8074f1d964dbb1be for pr-55982
Nov 18 07:13:32.488 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-1/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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:32.652 INFO [stdout] 477f906c3c5a963de267b878b44b7afd5e470dce8dabd5e3c5c4457dd13cc820
Nov 18 07:13:32.658 INFO running `"docker" "start" "-a" "477f906c3c5a963de267b878b44b7afd5e470dce8dabd5e3c5c4457dd13cc820"`
Nov 18 07:13:33.121 INFO [stderr] usermod: no changes
Nov 18 07:13:33.181 INFO [stderr]    Compiling gcc v0.3.55
Nov 18 07:13:33.183 INFO [stderr]    Compiling libc v0.2.43
Nov 18 07:13:33.183 INFO [stderr]    Compiling cc v1.0.25
Nov 18 07:13:33.183 INFO [stderr]    Compiling bech32 v0.5.0
Nov 18 07:13:33.183 INFO [stderr]    Compiling rustc-serialize v0.3.24
Nov 18 07:13:33.184 INFO [stderr]    Compiling hex v0.3.2
Nov 18 07:13:33.184 INFO [stderr]    Compiling byteorder v1.2.7
Nov 18 07:13:36.836 INFO [stderr]    Compiling rand v0.4.3
Nov 18 07:13:36.871 INFO [stderr]    Compiling time v0.1.40
Nov 18 07:13:38.104 INFO [stderr]    Compiling bitcoin-bech32 v0.8.1
Nov 18 07:14:01.051 INFO [stderr]    Compiling rand v0.3.22
Nov 18 07:14:02.211 INFO [stderr]    Compiling secp256k1 v0.11.5
Nov 18 07:14:02.295 INFO [stderr]    Compiling bitcoinconsensus v0.16.3
Nov 18 07:14:02.315 INFO [stderr]    Compiling lightning v0.0.5 (/source)
Nov 18 07:14:04.639 INFO [stderr]    Compiling secp256k1 v0.9.2
Nov 18 07:14:04.647 INFO [stderr]    Compiling rust-crypto v0.2.36
Nov 18 07:14:34.181 INFO [stderr]    Compiling bitcoin v0.14.2
Nov 18 07:14:44.459 INFO [stderr] warning: unused macro definition
Nov 18 07:14:44.459 INFO [stderr]   --> src/util/macro_logger.rs:83:1
Nov 18 07:14:44.459 INFO [stderr]    |
Nov 18 07:14:44.459 INFO [stderr] 83 | / macro_rules! log_warn {
Nov 18 07:14:44.459 INFO [stderr] 84 | |     ($self: ident, $($arg:tt)*) => (
Nov 18 07:14:44.459 INFO [stderr] 85 | |         #[cfg(not(any(feature = "max_level_off", feature = "max_level_error")))]
Nov 18 07:14:44.459 INFO [stderr] 86 | |         log_internal!($self, $crate::util::logger::Level::Warn, $($arg)*);
Nov 18 07:14:44.459 INFO [stderr] 87 | |     )
Nov 18 07:14:44.459 INFO [stderr] 88 | | }
Nov 18 07:14:44.459 INFO [stderr]    | |_^
Nov 18 07:14:44.459 INFO [stderr]    |
Nov 18 07:14:44.459 INFO [stderr]    = note: #[warn(unused_macros)] on by default
Nov 18 07:14:44.459 INFO [stderr] 
Nov 18 07:14:48.162 INFO [stderr] warning: function is never used: `sort_inputs`
Nov 18 07:14:48.163 INFO [stderr]   --> src/util/transaction_utils.rs:21:1
Nov 18 07:14:48.163 INFO [stderr]    |
Nov 18 07:14:48.163 INFO [stderr] 21 | pub fn sort_inputs<T>(inputs: &mut Vec<(TxIn, T)>) {
Nov 18 07:14:48.163 INFO [stderr]    | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Nov 18 07:14:48.163 INFO [stderr]    |
Nov 18 07:14:48.163 INFO [stderr]    = note: #[warn(dead_code)] on by default
Nov 18 07:14:48.163 INFO [stderr] 
Nov 18 07:14:48.163 INFO [stderr] warning: field is never used: `logger`
Nov 18 07:14:48.163 INFO [stderr]    --> src/chain/chaininterface.rs:198:2
Nov 18 07:14:48.163 INFO [stderr]     |
Nov 18 07:14:48.163 INFO [stderr] 198 |     logger: Arc<Logger>,
Nov 18 07:14:48.163 INFO [stderr]     |     ^^^^^^^^^^^^^^^^^^^
Nov 18 07:14:48.163 INFO [stderr] 
Nov 18 07:14:48.163 INFO [stderr] warning: variant is never constructed: `SigsMode`
Nov 18 07:14:48.163 INFO [stderr]    --> src/ln/channelmonitor.rs:158:2
Nov 18 07:14:48.163 INFO [stderr]     |
Nov 18 07:14:48.163 INFO [stderr] 158 |       SigsMode {
Nov 18 07:14:48.163 INFO [stderr]     |  _____^
Nov 18 07:14:48.163 INFO [stderr] 159 | |         revocation_base_key: PublicKey,
Nov 18 07:14:48.163 INFO [stderr] 160 | |         htlc_base_key: PublicKey,
Nov 18 07:14:48.163 INFO [stderr] 161 | |         sigs: HashMap<Sha256dHash, Signature>,
Nov 18 07:14:48.163 INFO [stderr] 162 | |     }
Nov 18 07:14:48.163 INFO [stderr]     | |_____^
Nov 18 07:14:48.163 INFO [stderr] 
Nov 18 07:14:48.165 INFO [stderr] warning: field is never used: `time_created`
Nov 18 07:14:48.165 INFO [stderr]    --> src/ln/channel.rs:195:3
Nov 18 07:14:48.165 INFO [stderr]     |
Nov 18 07:14:48.165 INFO [stderr] 195 |         time_created: Instant, //TODO: Some kind of timeout thing-a-majig
Nov 18 07:14:48.165 INFO [stderr]     |         ^^^^^^^^^^^^^^^^^^^^^
Nov 18 07:14:48.165 INFO [stderr] 
Nov 18 07:15:10.704 INFO [stderr]     Finished dev [optimized + debuginfo] target(s) in 1m 37s
Nov 18 07:15:10.705 INFO [stderr] su: No module specific data is present
Nov 18 07:15:11.277 INFO running `"docker" "inspect" "477f906c3c5a963de267b878b44b7afd5e470dce8dabd5e3c5c4457dd13cc820"`
Nov 18 07:15:11.559 INFO running `"docker" "rm" "-f" "477f906c3c5a963de267b878b44b7afd5e470dce8dabd5e3c5c4457dd13cc820"`
Nov 18 07:15:12.013 INFO [stdout] 477f906c3c5a963de267b878b44b7afd5e470dce8dabd5e3c5c4457dd13cc820
Nov 18 07:15:12.015 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-1/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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:12.463 INFO [stdout] 4d8faa27204d919c6f33b8eab4ecb5194fca4fc76c4c63806527d42ddbd03a3c
Nov 18 07:15:12.471 INFO running `"docker" "start" "-a" "4d8faa27204d919c6f33b8eab4ecb5194fca4fc76c4c63806527d42ddbd03a3c"`
Nov 18 07:15:13.445 INFO [stderr] usermod: no changes
Nov 18 07:15:13.546 INFO [stderr]    Compiling lightning v0.0.5 (/source)
Nov 18 07:15:22.494 INFO [stderr] warning: field is never used: `logger`
Nov 18 07:15:22.494 INFO [stderr]    --> src/chain/chaininterface.rs:198:2
Nov 18 07:15:22.494 INFO [stderr]     |
Nov 18 07:15:22.494 INFO [stderr] 198 |     logger: Arc<Logger>,
Nov 18 07:15:22.494 INFO [stderr]     |     ^^^^^^^^^^^^^^^^^^^
Nov 18 07:15:22.494 INFO [stderr]     |
Nov 18 07:15:22.494 INFO [stderr]     = note: #[warn(dead_code)] on by default
Nov 18 07:15:22.494 INFO [stderr] 
Nov 18 07:15:22.494 INFO [stderr] warning: variant is never constructed: `SigsMode`
Nov 18 07:15:22.494 INFO [stderr]    --> src/ln/channelmonitor.rs:158:2
Nov 18 07:15:22.494 INFO [stderr]     |
Nov 18 07:15:22.494 INFO [stderr] 158 |       SigsMode {
Nov 18 07:15:22.494 INFO [stderr]     |  _____^
Nov 18 07:15:22.494 INFO [stderr] 159 | |         revocation_base_key: PublicKey,
Nov 18 07:15:22.494 INFO [stderr] 160 | |         htlc_base_key: PublicKey,
Nov 18 07:15:22.494 INFO [stderr] 161 | |         sigs: HashMap<Sha256dHash, Signature>,
Nov 18 07:15:22.494 INFO [stderr] 162 | |     }
Nov 18 07:15:22.494 INFO [stderr]     | |_____^
Nov 18 07:15:22.494 INFO [stderr] 
Nov 18 07:15:22.499 INFO [stderr] warning: field is never used: `time_created`
Nov 18 07:15:22.499 INFO [stderr]    --> src/ln/channel.rs:195:3
Nov 18 07:15:22.499 INFO [stderr]     |
Nov 18 07:15:22.499 INFO [stderr] 195 |         time_created: Instant, //TODO: Some kind of timeout thing-a-majig
Nov 18 07:15:22.499 INFO [stderr]     |         ^^^^^^^^^^^^^^^^^^^^^
Nov 18 07:15:22.499 INFO [stderr] 
Nov 18 07:15:31.953 INFO [stderr]     Finished dev [optimized + debuginfo] target(s) in 18.47s
Nov 18 07:15:31.960 INFO [stderr] su: No module specific data is present
Nov 18 07:15:32.509 INFO running `"docker" "inspect" "4d8faa27204d919c6f33b8eab4ecb5194fca4fc76c4c63806527d42ddbd03a3c"`
Nov 18 07:15:32.711 INFO running `"docker" "rm" "-f" "4d8faa27204d919c6f33b8eab4ecb5194fca4fc76c4c63806527d42ddbd03a3c"`
Nov 18 07:15:32.880 INFO [stdout] 4d8faa27204d919c6f33b8eab4ecb5194fca4fc76c4c63806527d42ddbd03a3c
Nov 18 07:15:32.882 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-1/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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:33.253 INFO [stdout] dc46687807d86bd99259e8c06660170f2a6a83a95dd91649e08f229e174ca392
Nov 18 07:15:33.259 INFO running `"docker" "start" "-a" "dc46687807d86bd99259e8c06660170f2a6a83a95dd91649e08f229e174ca392"`
Nov 18 07:15:34.055 INFO [stderr] usermod: no changes
Nov 18 07:15:34.211 INFO [stderr]     Finished dev [optimized + debuginfo] target(s) in 0.13s
Nov 18 07:15:34.227 INFO [stderr]      Running /target/debug/deps/lightning-60cd94b585de28ba
Nov 18 07:15:34.283 INFO [stdout] 
Nov 18 07:15:34.283 INFO [stdout] running 33 tests
Nov 18 07:15:34.287 INFO [stdout] test chain::transaction::tests::test_channel_id_calculation ... ok
Nov 18 07:15:34.303 INFO [stdout] test ln::channel::tests::test_max_funding_satoshis ... ok
Nov 18 07:15:34.304 INFO [stdout] test ln::channel::tests::test_per_commitment_secret_gen ... ok
Nov 18 07:15:34.371 INFO [stdout] test ln::channel::tests::test_key_derivation ... ok
Nov 18 07:15:34.443 INFO [stdout] test ln::channelmanager::tests::test_failure_packet_onion ... ok
Nov 18 07:15:34.519 INFO [stdout] test ln::channelmanager::tests::onion_vectors ... ok
Nov 18 07:15:34.835 INFO [stdout] test ln::channel::tests::outbound_commitment_test ... ok
Nov 18 07:15:35.328 INFO [stdout] test ln::channelmanager::tests::test_invalid_channel_announcement ... ok
Nov 18 07:15:35.506 INFO [stdout] test ln::channelmanager::tests::test_unconf_chan ... ok
Nov 18 07:15:35.555 INFO [stdout] test ln::channelmanager::tests::test_htlc_ignore_latest_remote_commitment ... ok
Nov 18 07:15:35.559 INFO [stdout] test ln::msgs::tests::encoding_channel_reestablish_no_secret ... ok
Nov 18 07:15:35.636 INFO [stdout] test ln::msgs::tests::encoding_channel_reestablish_with_secret ... ok
Nov 18 07:15:35.652 INFO [stdout] test ln::channelmonitor::tests::test_prune_preimages ... ok
Nov 18 07:15:35.719 INFO [stdout] test ln::peer_channel_encryptor::tests::noise_initiator_test_vectors ... ok
Nov 18 07:15:35.749 INFO [stdout] test ln::peer_channel_encryptor::tests::noise_responder_test_vectors ... ok
Nov 18 07:15:35.795 INFO [stdout] test ln::channelmonitor::tests::test_per_commitment_storage ... ok
Nov 18 07:15:35.851 INFO [stdout] test ln::peer_handler::tests::test_disconnect_peer ... ok
Nov 18 07:15:35.867 INFO [stdout] test ln::peer_channel_encryptor::tests::message_encryption_decryption_test_vectors ... ok
Nov 18 07:15:35.873 INFO [stdout] test util::logger::tests::test_level_show ... ok
Nov 18 07:15:35.874 INFO [stdout] test util::logger::tests::test_logging_macros ... ok
Nov 18 07:15:35.874 INFO [stdout] test util::transaction_utils::tests::bip69_txin_test_1 ... ok
Nov 18 07:15:35.874 INFO [stdout] test util::transaction_utils::tests::bip69_txin_test_2 ... ok
Nov 18 07:15:35.875 INFO [stdout] test util::transaction_utils::tests::bip69_txout_test_1 ... ok
Nov 18 07:15:35.875 INFO [stdout] test util::transaction_utils::tests::bip69_txout_test_2 ... ok
Nov 18 07:15:35.875 INFO [stdout] test util::transaction_utils::tests::sort_output_by_bip_test ... ok
Nov 18 07:15:35.875 INFO [stdout] test util::transaction_utils::tests::sort_output_by_script_pubkey ... ok
Nov 18 07:15:35.880 INFO [stdout] test util::transaction_utils::tests::sort_output_by_value ... ok
Nov 18 07:15:35.885 INFO [stdout] test ln::router::tests::route_test ... ok
Nov 18 07:15:36.267 INFO [stdout] test ln::channelmanager::tests::test_force_close_fail_back ... ok
Nov 18 07:15:38.794 INFO [stdout] test ln::channelmanager::tests::duplicate_htlc_test ... ok
Nov 18 07:15:39.131 INFO [stdout] test ln::channelmanager::tests::test_simple_peer_disconnect ... ok
Nov 18 07:15:41.973 INFO [stdout] test ln::channelmanager::tests::channel_monitor_network_test ... ok
Nov 18 07:15:46.558 INFO [stdout] test ln::channelmanager::tests::fake_network_test ... ok
Nov 18 07:15:46.561 INFO [stdout] 
Nov 18 07:15:46.562 INFO [stdout] test result: ok. 33 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 07:15:46.562 INFO [stdout] 
Nov 18 07:15:46.565 INFO [stderr]    Doc-tests lightning
Nov 18 07:15:48.371 INFO [stdout] 
Nov 18 07:15:48.371 INFO [stdout] running 0 tests
Nov 18 07:15:48.371 INFO [stdout] 
Nov 18 07:15:48.371 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 07:15:48.371 INFO [stdout] 
Nov 18 07:15:48.387 INFO [stderr] su: No module specific data is present
Nov 18 07:15:49.260 INFO running `"docker" "inspect" "dc46687807d86bd99259e8c06660170f2a6a83a95dd91649e08f229e174ca392"`
Nov 18 07:15:49.505 INFO running `"docker" "rm" "-f" "dc46687807d86bd99259e8c06660170f2a6a83a95dd91649e08f229e174ca392"`
Nov 18 07:15:49.791 INFO [stdout] dc46687807d86bd99259e8c06660170f2a6a83a95dd91649e08f229e174ca392