Nov 02 00:04:42.433 INFO testing wemo-0.0.12 against beta-2018-10-30 for beta-1.31-1
Nov 02 00:04:42.433 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-1/worker-5/beta-2018-10-30:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/beta-1.31-1/beta-2018-10-30:/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 +beta-2018-10-30 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:42.603 INFO blam! 86fb7cbe927f0a1f9e443328f057cb141ea3c9935989171a62f6d1e1e55f2d9a
Nov 02 00:04:42.606 INFO running `"docker" "start" "-a" "86fb7cbe927f0a1f9e443328f057cb141ea3c9935989171a62f6d1e1e55f2d9a"`
Nov 02 00:04:43.094 INFO kablam! usermod: no changes
Nov 02 00:04:43.219 INFO kablam!    Compiling c_linked_list v1.1.1
Nov 02 00:04:43.220 INFO kablam!    Compiling net2 v0.2.33
Nov 02 00:04:43.222 INFO kablam!    Compiling url v1.4.1
Nov 02 00:04:43.222 INFO kablam!    Compiling hpack v0.2.0
Nov 02 00:04:43.919 INFO kablam!    Compiling get_if_addrs v0.4.1
Nov 02 00:04:45.056 INFO kablam!    Compiling miow v0.1.5
Nov 02 00:04:45.659 INFO kablam!    Compiling solicit v0.4.4
Nov 02 00:04:45.835 INFO kablam!    Compiling mio v0.5.1
Nov 02 00:04:47.456 INFO kablam!    Compiling cookie v0.2.5
Nov 02 00:04:50.491 INFO kablam!    Compiling hyper v0.9.18
Nov 02 00:04:58.831 INFO kablam!    Compiling iron v0.4.0
Nov 02 00:05:01.467 INFO kablam!    Compiling persistent v0.2.1
Nov 02 00:05:03.529 INFO kablam!    Compiling bodyparser v0.4.1
Nov 02 00:05:05.958 INFO kablam!    Compiling urlencoded v0.4.1
Nov 02 00:05:08.257 INFO kablam!    Compiling wemo v0.0.12 (/source)
Nov 02 00:05:13.712 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 30.59s
Nov 02 00:05:13.726 INFO kablam! su: No module specific data is present
Nov 02 00:05:14.665 INFO running `"docker" "rm" "-f" "86fb7cbe927f0a1f9e443328f057cb141ea3c9935989171a62f6d1e1e55f2d9a"`
Nov 02 00:05:14.972 INFO blam! 86fb7cbe927f0a1f9e443328f057cb141ea3c9935989171a62f6d1e1e55f2d9a
Nov 02 00:05:14.978 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-1/worker-5/beta-2018-10-30:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/beta-1.31-1/beta-2018-10-30:/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 +beta-2018-10-30 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:15.413 INFO blam! 6c9d1f4222d7a9f74eb304bb6e3d9cb7bc76356efeb5c93cf160f92d0187c4fc
Nov 02 00:05:15.415 INFO running `"docker" "start" "-a" "6c9d1f4222d7a9f74eb304bb6e3d9cb7bc76356efeb5c93cf160f92d0187c4fc"`
Nov 02 00:05:16.983 INFO kablam! usermod: no changes
Nov 02 00:05:17.247 INFO kablam!    Compiling wemo v0.0.12 (/source)
Nov 02 00:05:30.715 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 13.69s
Nov 02 00:05:30.727 INFO kablam! su: No module specific data is present
Nov 02 00:05:31.191 INFO running `"docker" "rm" "-f" "6c9d1f4222d7a9f74eb304bb6e3d9cb7bc76356efeb5c93cf160f92d0187c4fc"`
Nov 02 00:05:31.555 INFO blam! 6c9d1f4222d7a9f74eb304bb6e3d9cb7bc76356efeb5c93cf160f92d0187c4fc
Nov 02 00:05:31.557 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-1/worker-5/beta-2018-10-30:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/beta-1.31-1/beta-2018-10-30:/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 +beta-2018-10-30 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:31.968 INFO blam! 13aee935b359591c45de01e4874ee28e0c16cf3da049f2de6c5a61ca32b7d243
Nov 02 00:05:31.972 INFO running `"docker" "start" "-a" "13aee935b359591c45de01e4874ee28e0c16cf3da049f2de6c5a61ca32b7d243"`
Nov 02 00:05:33.423 INFO kablam! usermod: no changes
Nov 02 00:05:33.727 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 0.26s
Nov 02 00:05:33.737 INFO kablam!      Running /target/debug/deps/wemo-91bcabef719a26a4
Nov 02 00:05:33.741 INFO blam! 
Nov 02 00:05:33.741 INFO blam! running 16 tests
Nov 02 00:05:33.741 INFO blam! test device::switch::tests::test_get_ip_address_with_dynamic_ip ... ok
Nov 02 00:05:33.742 INFO blam! test device::switch::tests::test_get_port_without_port_set ... ok
Nov 02 00:05:33.743 INFO blam! test device::switch::tests::test_get_ip_address_with_static_ip ... ok
Nov 02 00:05:33.743 INFO blam! test device::switch::tests::test_update_location_with_dynamic_ip ... ok
Nov 02 00:05:33.743 INFO blam! test device::switch::tests::test_get_ip_address_with_no_ip ... ok
Nov 02 00:05:33.743 INFO blam! test device::switch::tests::test_get_port_with_port_set ... ok
Nov 02 00:05:33.763 INFO blam! test parsing::tests::insight_notifications ... ok
Nov 02 00:05:33.763 INFO blam! test device::switch::tests::test_name_with_ip ... ok
Nov 02 00:05:33.763 INFO blam! test subscriptions::tests::test_send_subscribe ... ok
Nov 02 00:05:33.763 INFO blam! test device::switch::tests::test_name_with_ip_and_port ... ok
Nov 02 00:05:33.763 INFO blam! test device::switch::tests::test_name_without_ip ... ok
Nov 02 00:05:33.764 INFO blam! test device::switch::tests::test_update_location_with_static_ip ... ok
Nov 02 00:05:33.771 INFO blam! test parsing::tests::switch_notifications ... ok
Nov 02 00:05:33.923 INFO blam! test xml::tests::test_find_tag_value ... ok
Nov 02 00:05:33.933 INFO blam! test xml::tests::test_find_tag_value_failure ... ok
Nov 02 00:05:34.029 INFO blam! test subscriptions::tests::test_callback_invocation ... FAILED
Nov 02 00:05:34.029 INFO blam! 
Nov 02 00:05:34.029 INFO blam! failures:
Nov 02 00:05:34.029 INFO blam! 
Nov 02 00:05:34.029 INFO blam! ---- subscriptions::tests::test_callback_invocation stdout ----
Nov 02 00:05:34.029 INFO blam! thread 'subscriptions::tests::test_callback_invocation' panicked at 'assertion failed: notice.is_some()', src/subscriptions.rs:400:5
Nov 02 00:05:34.029 INFO blam! stack backtrace:
Nov 02 00:05:34.029 INFO blam!    0:     0x55de725f7aef - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h3c55afa80a996a32
Nov 02 00:05:34.029 INFO blam!                                at libstd/sys/unix/backtrace/tracing/gcc_s.rs:49
Nov 02 00:05:34.029 INFO blam!    1:     0x55de72601a67 - std::sys_common::backtrace::print::hcef907ea7a07cd40
Nov 02 00:05:34.029 INFO blam!                                at libstd/sys_common/backtrace.rs:71
Nov 02 00:05:34.029 INFO blam!                                at libstd/sys_common/backtrace.rs:59
Nov 02 00:05:34.029 INFO blam!    2:     0x55de725fc5ff - std::panicking::default_hook::{{closure}}::hf541876c65f36ea7
Nov 02 00:05:34.029 INFO blam!                                at libstd/panicking.rs:211
Nov 02 00:05:34.029 INFO blam!    3:     0x55de725fc2fe - std::panicking::default_hook::h4ecc06b30ae4459d
Nov 02 00:05:34.029 INFO blam!                                at libstd/panicking.rs:221
Nov 02 00:05:34.029 INFO blam!    4:     0x55de725fccde - std::panicking::rust_panic_with_hook::h0cc6d9cc9f34a947
Nov 02 00:05:34.029 INFO blam!                                at libstd/panicking.rs:476
Nov 02 00:05:34.029 INFO blam!    5:     0x55de72463057 - std::panicking::begin_panic::hf48b66c290d4b945
Nov 02 00:05:34.029 INFO blam!                                at libstd/panicking.rs:410
Nov 02 00:05:34.029 INFO blam!    6:     0x55de722b9d89 - wemo::subscriptions::tests::test_callback_invocation::h35e55dcf15d44c24
Nov 02 00:05:34.029 INFO blam!                                at src/subscriptions.rs:400
Nov 02 00:05:34.029 INFO blam!    7:     0x55de722b9819 - wemo::subscriptions::tests::test_callback_invocation::{{closure}}::h6aace7b13e8fae54
Nov 02 00:05:34.029 INFO blam!                                at src/subscriptions.rs:365
Nov 02 00:05:34.029 INFO blam!    8:     0x55de722b237d - core::ops::function::FnOnce::call_once::h0b9c4b3f8fe75e7a
Nov 02 00:05:34.029 INFO blam!                                at libcore/ops/function.rs:238
Nov 02 00:05:34.029 INFO blam!    9:     0x55de722fa6be - <F as alloc::boxed::FnBox<A>>::call_box::hf32558bd83684b60
Nov 02 00:05:34.029 INFO blam!                                at libtest/lib.rs:1468
Nov 02 00:05:34.030 INFO blam!                                at libcore/ops/function.rs:238
Nov 02 00:05:34.030 INFO blam!                                at liballoc/boxed.rs:672
Nov 02 00:05:34.030 INFO blam!   10:     0x55de7260cc99 - __rust_maybe_catch_panic
Nov 02 00:05:34.030 INFO blam!                                at libpanic_unwind/lib.rs:102
Nov 02 00:05:34.030 INFO blam!   11:     0x55de7231c322 - std::sys_common::backtrace::__rust_begin_short_backtrace::h1bb71a7386fd3269
Nov 02 00:05:34.030 INFO blam!                                at libstd/panicking.rs:289
Nov 02 00:05:34.030 INFO blam!                                at libstd/panic.rs:392
Nov 02 00:05:34.030 INFO blam!                                at libtest/lib.rs:1423
Nov 02 00:05:34.030 INFO blam!                                at libstd/sys_common/backtrace.rs:136
Nov 02 00:05:34.030 INFO blam!   12:     0x55de7231cdd4 - std::panicking::try::do_call::h9b94719c3074bb5c
Nov 02 00:05:34.030 INFO blam!                                at libstd/thread/mod.rs:409
Nov 02 00:05:34.030 INFO blam!                                at libstd/panic.rs:313
Nov 02 00:05:34.030 INFO blam!                                at libstd/panicking.rs:310
Nov 02 00:05:34.030 INFO blam!   13:     0x55de7260cc99 - __rust_maybe_catch_panic
Nov 02 00:05:34.030 INFO blam!                                at libpanic_unwind/lib.rs:102
Nov 02 00:05:34.030 INFO blam!   14:     0x55de72309ffc - <F as alloc::boxed::FnBox<A>>::call_box::hb3bcd66d16fd3e84
Nov 02 00:05:34.030 INFO blam!                                at libstd/panicking.rs:289
Nov 02 00:05:34.030 INFO blam!                                at libstd/panic.rs:392
Nov 02 00:05:34.030 INFO blam!                                at libstd/thread/mod.rs:408
Nov 02 00:05:34.030 INFO blam!                                at liballoc/boxed.rs:672
Nov 02 00:05:34.030 INFO blam!   15:     0x55de725fa36d - std::sys_common::thread::start_thread::had0c80d121669d29
Nov 02 00:05:34.030 INFO blam!                                at liballoc/boxed.rs:682
Nov 02 00:05:34.030 INFO blam!                                at libstd/sys_common/thread.rs:24
Nov 02 00:05:34.030 INFO blam!   16:     0x55de725eb175 - std::sys::unix::thread::Thread::new::thread_start::h4a5b66a70d76f47f
Nov 02 00:05:34.030 INFO blam!                                at libstd/sys/unix/thread.rs:90
Nov 02 00:05:34.030 INFO blam!   17:     0x7f78ce03d6b9 - start_thread
Nov 02 00:05:34.030 INFO blam!   18:     0x7f78cdb5d41c - clone
Nov 02 00:05:34.030 INFO blam!   19:                0x0 - <unknown>
Nov 02 00:05:34.030 INFO blam! 
Nov 02 00:05:34.030 INFO blam! 
Nov 02 00:05:34.030 INFO blam! failures:
Nov 02 00:05:34.030 INFO blam!     subscriptions::tests::test_callback_invocation
Nov 02 00:05:34.030 INFO blam! 
Nov 02 00:05:34.030 INFO blam! test result: FAILED. 15 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out
Nov 02 00:05:34.030 INFO blam! 
Nov 02 00:05:34.043 INFO kablam! error: test failed, to rerun pass '--lib'
Nov 02 00:05:34.055 INFO kablam! su: No module specific data is present
Nov 02 00:05:34.835 INFO running `"docker" "rm" "-f" "13aee935b359591c45de01e4874ee28e0c16cf3da049f2de6c5a61ca32b7d243"`
Nov 02 00:05:34.963 INFO blam! 13aee935b359591c45de01e4874ee28e0c16cf3da049f2de6c5a61ca32b7d243
