Nov 02 00:04:45.532 INFO testing wemo-0.0.12 against 1.30.0 for beta-1.31-1
Nov 02 00:04:45.532 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-1/worker-2/1.30.0:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/beta-1.31-1/1.30.0:/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 +1.30.0 build --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Nov 02 00:04:45.878 INFO blam! ec249bfa47e74fd331b60374d0e17d9a6b63c4708447b3fd886ef106c61d5dd8
Nov 02 00:04:45.880 INFO running `"docker" "start" "-a" "ec249bfa47e74fd331b60374d0e17d9a6b63c4708447b3fd886ef106c61d5dd8"`
Nov 02 00:04:46.551 INFO kablam! usermod: no changes
Nov 02 00:04:46.739 INFO kablam!    Compiling c_linked_list v1.1.1
Nov 02 00:04:46.741 INFO kablam!    Compiling num_cpus v0.2.13
Nov 02 00:04:46.741 INFO kablam!    Compiling net2 v0.2.33
Nov 02 00:04:46.742 INFO kablam!    Compiling url v1.4.1
Nov 02 00:04:46.742 INFO kablam!    Compiling hpack v0.2.0
Nov 02 00:04:46.755 INFO kablam!    Compiling serde_json v0.8.6
Nov 02 00:04:48.211 INFO kablam!    Compiling get_if_addrs v0.4.1
Nov 02 00:04:49.519 INFO kablam!    Compiling miow v0.1.5
Nov 02 00:04:49.591 INFO kablam!    Compiling solicit v0.4.4
Nov 02 00:04:51.195 INFO kablam!    Compiling mio v0.5.1
Nov 02 00:04:51.712 INFO kablam!    Compiling cookie v0.2.5
Nov 02 00:04:56.183 INFO kablam!    Compiling hyper v0.9.18
Nov 02 00:05:04.543 INFO kablam!    Compiling iron v0.4.0
Nov 02 00:05:07.035 INFO kablam!    Compiling persistent v0.2.1
Nov 02 00:05:09.399 INFO kablam!    Compiling bodyparser v0.4.1
Nov 02 00:05:12.139 INFO kablam!    Compiling urlencoded v0.4.1
Nov 02 00:05:14.975 INFO kablam!    Compiling wemo v0.0.12 (/source)
Nov 02 00:05:20.920 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 34.35s
Nov 02 00:05:20.935 INFO kablam! su: No module specific data is present
Nov 02 00:05:21.564 INFO running `"docker" "rm" "-f" "ec249bfa47e74fd331b60374d0e17d9a6b63c4708447b3fd886ef106c61d5dd8"`
Nov 02 00:05:21.790 INFO blam! ec249bfa47e74fd331b60374d0e17d9a6b63c4708447b3fd886ef106c61d5dd8
Nov 02 00:05:21.807 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-1/worker-2/1.30.0:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/beta-1.31-1/1.30.0:/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 +1.30.0 test --frozen --no-run" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Nov 02 00:05:22.164 INFO blam! d358fa8047beacee1c5414087bbb21b4ff6aee6bbd954a69a534675d54ab6cd2
Nov 02 00:05:22.167 INFO running `"docker" "start" "-a" "d358fa8047beacee1c5414087bbb21b4ff6aee6bbd954a69a534675d54ab6cd2"`
Nov 02 00:05:23.315 INFO kablam! usermod: no changes
Nov 02 00:05:23.467 INFO kablam!    Compiling wemo v0.0.12 (/source)
Nov 02 00:05:35.586 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 12.24s
Nov 02 00:05:35.594 INFO kablam! su: No module specific data is present
Nov 02 00:05:35.937 INFO running `"docker" "rm" "-f" "d358fa8047beacee1c5414087bbb21b4ff6aee6bbd954a69a534675d54ab6cd2"`
Nov 02 00:05:36.015 INFO blam! d358fa8047beacee1c5414087bbb21b4ff6aee6bbd954a69a534675d54ab6cd2
Nov 02 00:05:36.016 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-1/worker-2/1.30.0:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/beta-1.31-1/1.30.0:/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 +1.30.0 test --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Nov 02 00:05:36.199 INFO blam! 3eeb12fb64e893245af8ea89c663fc8c083960abc229e9247a35802cf9c8cfbb
Nov 02 00:05:36.204 INFO running `"docker" "start" "-a" "3eeb12fb64e893245af8ea89c663fc8c083960abc229e9247a35802cf9c8cfbb"`
Nov 02 00:05:36.872 INFO kablam! usermod: no changes
Nov 02 00:05:37.028 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 0.13s
Nov 02 00:05:37.052 INFO kablam!      Running /target/debug/deps/wemo-4e84bc408397880f
Nov 02 00:05:37.053 INFO blam! 
Nov 02 00:05:37.053 INFO blam! running 16 tests
Nov 02 00:05:37.053 INFO blam! test device::switch::tests::test_name_without_ip ... ok
Nov 02 00:05:37.053 INFO blam! test device::switch::tests::test_name_with_ip_and_port ... ok
Nov 02 00:05:37.053 INFO blam! test device::switch::tests::test_name_with_ip ... ok
Nov 02 00:05:37.053 INFO blam! test device::switch::tests::test_get_port_without_port_set ... ok
Nov 02 00:05:37.053 INFO blam! test device::switch::tests::test_get_ip_address_with_static_ip ... ok
Nov 02 00:05:37.053 INFO blam! test device::switch::tests::test_update_location_with_dynamic_ip ... ok
Nov 02 00:05:37.053 INFO blam! test device::switch::tests::test_update_location_with_static_ip ... ok
Nov 02 00:05:37.053 INFO blam! test device::switch::tests::test_get_ip_address_with_dynamic_ip ... ok
Nov 02 00:05:37.056 INFO blam! test parsing::tests::switch_notifications ... ok
Nov 02 00:05:37.056 INFO blam! test device::switch::tests::test_get_port_with_port_set ... ok
Nov 02 00:05:37.056 INFO blam! test device::switch::tests::test_get_ip_address_with_no_ip ... ok
Nov 02 00:05:37.063 INFO blam! test parsing::tests::insight_notifications ... ok
Nov 02 00:05:37.115 INFO blam! test subscriptions::tests::test_send_subscribe ... ok
Nov 02 00:05:37.115 INFO blam! test xml::tests::test_find_tag_value_failure ... ok
Nov 02 00:05:37.119 INFO blam! test xml::tests::test_find_tag_value ... ok
Nov 02 00:05:37.301 INFO blam! test subscriptions::tests::test_callback_invocation ... FAILED
Nov 02 00:05:37.301 INFO blam! 
Nov 02 00:05:37.301 INFO blam! failures:
Nov 02 00:05:37.301 INFO blam! 
Nov 02 00:05:37.301 INFO blam! ---- subscriptions::tests::test_callback_invocation stdout ----
Nov 02 00:05:37.301 INFO blam! thread 'subscriptions::tests::test_callback_invocation' panicked at 'assertion failed: notice.is_some()', src/subscriptions.rs:400:5
Nov 02 00:05:37.301 INFO blam! stack backtrace:
Nov 02 00:05:37.301 INFO blam!    0:     0x5626fdd5215e - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::hf5316c67202e23c8
Nov 02 00:05:37.301 INFO blam!                                at libstd/sys/unix/backtrace/tracing/gcc_s.rs:49
Nov 02 00:05:37.301 INFO blam!    1:     0x5626fdd43456 - std::sys_common::backtrace::print::h3068ed2a6dc7ac86
Nov 02 00:05:37.301 INFO blam!                                at libstd/sys_common/backtrace.rs:71
Nov 02 00:05:37.301 INFO blam!                                at libstd/sys_common/backtrace.rs:59
Nov 02 00:05:37.301 INFO blam!    2:     0x5626fdd4165d - std::panicking::default_hook::{{closure}}::h1b9d29cc09d41897
Nov 02 00:05:37.301 INFO blam!                                at libstd/panicking.rs:211
Nov 02 00:05:37.301 INFO blam!    3:     0x5626fdd4136b - std::panicking::default_hook::h3a27b204a313406b
Nov 02 00:05:37.301 INFO blam!                                at libstd/panicking.rs:221
Nov 02 00:05:37.301 INFO blam!    4:     0x5626fdd41d0c - std::panicking::rust_panic_with_hook::h0e12cb2fc86d00fa
Nov 02 00:05:37.301 INFO blam!                                at libstd/panicking.rs:477
Nov 02 00:05:37.301 INFO blam!    5:     0x5626fdc548bf - std::panicking::begin_panic::h2b7abe2475e1d43c
Nov 02 00:05:37.301 INFO blam!                                at libstd/panicking.rs:411
Nov 02 00:05:37.301 INFO blam!    6:     0x5626fda2137d - wemo::subscriptions::tests::test_callback_invocation::h150cfc79107c75a1
Nov 02 00:05:37.301 INFO blam!                                at src/subscriptions.rs:400
Nov 02 00:05:37.301 INFO blam!    7:     0x5626fda20e29 - wemo::subscriptions::tests::test_callback_invocation::{{closure}}::h8e5125a956d224cf
Nov 02 00:05:37.301 INFO blam!                                at src/subscriptions.rs:365
Nov 02 00:05:37.301 INFO blam!    8:     0x5626fda1bd8d - core::ops::function::FnOnce::call_once::h2b622e8e8fd46f30
Nov 02 00:05:37.301 INFO blam!                                at libcore/ops/function.rs:238
Nov 02 00:05:37.301 INFO blam!    9:     0x5626fda6626e - <F as alloc::boxed::FnBox<A>>::call_box::ha9910eac2d17ff23
Nov 02 00:05:37.301 INFO blam!                                at libtest/lib.rs:1461
Nov 02 00:05:37.301 INFO blam!                                at libcore/ops/function.rs:238
Nov 02 00:05:37.301 INFO blam!                                at liballoc/boxed.rs:646
Nov 02 00:05:37.301 INFO blam!   10:     0x5626fdd612a9 - __rust_maybe_catch_panic
Nov 02 00:05:37.301 INFO blam!                                at libpanic_unwind/lib.rs:103
Nov 02 00:05:37.301 INFO blam!   11:     0x5626fda71b7d - std::sys_common::backtrace::__rust_begin_short_backtrace::h470b05235fe5e5ad
Nov 02 00:05:37.301 INFO blam!                                at libstd/panicking.rs:289
Nov 02 00:05:37.301 INFO blam!                                at libstd/panic.rs:392
Nov 02 00:05:37.301 INFO blam!                                at libtest/lib.rs:1416
Nov 02 00:05:37.301 INFO blam!                                at libstd/sys_common/backtrace.rs:136
Nov 02 00:05:37.301 INFO blam!   12:     0x5626fda8a1b4 - std::panicking::try::do_call::h1f0578498122cc00
Nov 02 00:05:37.301 INFO blam!                                at libstd/thread/mod.rs:409
Nov 02 00:05:37.301 INFO blam!                                at libstd/panic.rs:313
Nov 02 00:05:37.301 INFO blam!                                at libstd/panicking.rs:310
Nov 02 00:05:37.301 INFO blam!   13:     0x5626fdd612a9 - __rust_maybe_catch_panic
Nov 02 00:05:37.301 INFO blam!                                at libpanic_unwind/lib.rs:103
Nov 02 00:05:37.301 INFO blam!   14:     0x5626fda7aa86 - <F as alloc::boxed::FnBox<A>>::call_box::h7b129f9f235ab460
Nov 02 00:05:37.301 INFO blam!                                at libstd/panicking.rs:289
Nov 02 00:05:37.302 INFO blam!                                at libstd/panic.rs:392
Nov 02 00:05:37.302 INFO blam!                                at libstd/thread/mod.rs:408
Nov 02 00:05:37.302 INFO blam!                                at liballoc/boxed.rs:646
Nov 02 00:05:37.302 INFO blam!   15:     0x5626fdd53e5a - std::sys_common::thread::start_thread::hb77935aee02382da
Nov 02 00:05:37.302 INFO blam!                                at liballoc/boxed.rs:656
Nov 02 00:05:37.302 INFO blam!                                at libstd/sys_common/thread.rs:24
Nov 02 00:05:37.302 INFO blam!   16:     0x5626fdd424c5 - std::sys::unix::thread::Thread::new::thread_start::hd61f7429b3de1d75
Nov 02 00:05:37.302 INFO blam!                                at libstd/sys/unix/thread.rs:90
Nov 02 00:05:37.302 INFO blam!   17:     0x7fe67370d6b9 - start_thread
Nov 02 00:05:37.302 INFO blam!   18:     0x7fe67322d41c - clone
Nov 02 00:05:37.302 INFO blam!   19:                0x0 - <unknown>
Nov 02 00:05:37.302 INFO blam! 
Nov 02 00:05:37.302 INFO blam! 
Nov 02 00:05:37.302 INFO blam! failures:
Nov 02 00:05:37.302 INFO blam!     subscriptions::tests::test_callback_invocation
Nov 02 00:05:37.302 INFO blam! 
Nov 02 00:05:37.302 INFO blam! test result: FAILED. 15 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out
Nov 02 00:05:37.302 INFO blam! 
Nov 02 00:05:37.306 INFO kablam! error: test failed, to rerun pass '--lib'
Nov 02 00:05:37.308 INFO kablam! su: No module specific data is present
Nov 02 00:05:37.650 INFO running `"docker" "rm" "-f" "3eeb12fb64e893245af8ea89c663fc8c083960abc229e9247a35802cf9c8cfbb"`
Nov 02 00:05:37.795 INFO blam! 3eeb12fb64e893245af8ea89c663fc8c083960abc229e9247a35802cf9c8cfbb
