Dec 02 11:03:47.660 INFO testing wemo-0.0.12 against 1.30.1 for beta-1.31-5
Dec 02 11:03:47.660 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-6/1.30.1:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/beta-1.31-5/1.30.1:/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.1 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"`
Dec 02 11:03:48.080 INFO [stdout] 9c3ef99e6ffafcb273a114a7ec9dc30f280daac1e9f64b70e44fa910dab67455
Dec 02 11:03:48.084 INFO running `"docker" "start" "-a" "9c3ef99e6ffafcb273a114a7ec9dc30f280daac1e9f64b70e44fa910dab67455"`
Dec 02 11:03:49.015 INFO [stderr] usermod: no changes
Dec 02 11:03:49.191 INFO [stderr]    Compiling url v1.4.1
Dec 02 11:03:49.191 INFO [stderr]    Compiling num_cpus v0.2.13
Dec 02 11:03:49.191 INFO [stderr]    Compiling net2 v0.2.33
Dec 02 11:03:49.191 INFO [stderr]    Compiling memchr v0.1.11
Dec 02 11:03:49.191 INFO [stderr]    Compiling get_if_addrs v0.4.1
Dec 02 11:03:49.191 INFO [stderr]    Compiling hpack v0.2.0
Dec 02 11:03:49.191 INFO [stderr]    Compiling serde_json v0.8.6
Dec 02 11:03:49.191 INFO [stderr]    Compiling thread-id v2.0.0
Dec 02 11:03:52.143 INFO [stderr]    Compiling thread_local v0.2.7
Dec 02 11:03:52.191 INFO [stderr]    Compiling aho-corasick v0.5.3
Dec 02 11:03:54.649 INFO [stderr]    Compiling miow v0.1.5
Dec 02 11:03:56.019 INFO [stderr]    Compiling solicit v0.4.4
Dec 02 11:03:56.437 INFO [stderr]    Compiling mio v0.5.1
Dec 02 11:03:58.234 INFO [stderr]    Compiling regex v0.1.80
Dec 02 11:03:58.939 INFO [stderr]    Compiling cookie v0.2.5
Dec 02 11:04:05.431 INFO [stderr]    Compiling hyper v0.9.18
Dec 02 11:04:17.458 INFO [stderr]    Compiling iron v0.4.0
Dec 02 11:04:20.535 INFO [stderr]    Compiling persistent v0.2.1
Dec 02 11:04:22.975 INFO [stderr]    Compiling bodyparser v0.4.1
Dec 02 11:04:26.171 INFO [stderr]    Compiling urlencoded v0.4.1
Dec 02 11:04:29.368 INFO [stderr]    Compiling wemo v0.0.12 (/source)
Dec 02 11:04:35.357 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 46.30s
Dec 02 11:04:35.371 INFO [stderr] su: No module specific data is present
Dec 02 11:04:36.160 INFO running `"docker" "inspect" "9c3ef99e6ffafcb273a114a7ec9dc30f280daac1e9f64b70e44fa910dab67455"`
Dec 02 11:04:36.477 INFO running `"docker" "rm" "-f" "9c3ef99e6ffafcb273a114a7ec9dc30f280daac1e9f64b70e44fa910dab67455"`
Dec 02 11:04:36.803 INFO [stdout] 9c3ef99e6ffafcb273a114a7ec9dc30f280daac1e9f64b70e44fa910dab67455
Dec 02 11:04:36.811 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-6/1.30.1:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/beta-1.31-5/1.30.1:/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.1 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"`
Dec 02 11:04:37.200 INFO [stdout] 3505b0f8fe4125c80405e6930ce0d4e9d19421978614e2e24172da83d2e0349e
Dec 02 11:04:37.202 INFO running `"docker" "start" "-a" "3505b0f8fe4125c80405e6930ce0d4e9d19421978614e2e24172da83d2e0349e"`
Dec 02 11:04:38.368 INFO [stderr] usermod: no changes
Dec 02 11:04:38.526 INFO [stderr]    Compiling wemo v0.0.12 (/source)
Dec 02 11:04:54.539 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 16.15s
Dec 02 11:04:54.551 INFO [stderr] su: No module specific data is present
Dec 02 11:04:55.083 INFO running `"docker" "inspect" "3505b0f8fe4125c80405e6930ce0d4e9d19421978614e2e24172da83d2e0349e"`
Dec 02 11:04:55.328 INFO running `"docker" "rm" "-f" "3505b0f8fe4125c80405e6930ce0d4e9d19421978614e2e24172da83d2e0349e"`
Dec 02 11:04:55.661 INFO [stdout] 3505b0f8fe4125c80405e6930ce0d4e9d19421978614e2e24172da83d2e0349e
Dec 02 11:04:55.666 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-6/1.30.1:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/beta-1.31-5/1.30.1:/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.1 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"`
Dec 02 11:04:55.979 INFO [stdout] ba829f9ccfc2ee30ff989d1cf9ed276cab2747ba9599fd6ccb304912b0c79209
Dec 02 11:04:55.985 INFO running `"docker" "start" "-a" "ba829f9ccfc2ee30ff989d1cf9ed276cab2747ba9599fd6ccb304912b0c79209"`
Dec 02 11:04:56.665 INFO [stderr] usermod: no changes
Dec 02 11:04:56.789 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.09s
Dec 02 11:04:56.811 INFO [stderr]      Running /target/debug/deps/wemo-4092e5d39c8caffd
Dec 02 11:04:56.815 INFO [stdout] 
Dec 02 11:04:56.815 INFO [stdout] running 16 tests
Dec 02 11:04:56.819 INFO [stdout] test device::switch::tests::test_get_ip_address_with_dynamic_ip ... ok
Dec 02 11:04:56.819 INFO [stdout] test device::switch::tests::test_get_ip_address_with_no_ip ... ok
Dec 02 11:04:56.819 INFO [stdout] test device::switch::tests::test_get_ip_address_with_static_ip ... ok
Dec 02 11:04:56.819 INFO [stdout] test device::switch::tests::test_get_port_with_port_set ... ok
Dec 02 11:04:56.819 INFO [stdout] test device::switch::tests::test_get_port_without_port_set ... ok
Dec 02 11:04:56.828 INFO [stdout] test device::switch::tests::test_update_location_with_static_ip ... ok
Dec 02 11:04:56.828 INFO [stdout] test device::switch::tests::test_update_location_with_dynamic_ip ... ok
Dec 02 11:04:56.828 INFO [stdout] test device::switch::tests::test_name_without_ip ... ok
Dec 02 11:04:56.828 INFO [stdout] test device::switch::tests::test_name_with_ip ... ok
Dec 02 11:04:56.828 INFO [stdout] test device::switch::tests::test_name_with_ip_and_port ... ok
Dec 02 11:04:56.830 INFO [stdout] test parsing::tests::insight_notifications ... ok
Dec 02 11:04:56.830 INFO [stdout] test parsing::tests::switch_notifications ... ok
Dec 02 11:04:56.952 INFO [stdout] test subscriptions::tests::test_send_subscribe ... ok
Dec 02 11:04:57.020 INFO [stdout] test xml::tests::test_find_tag_value_failure ... ok
Dec 02 11:04:57.027 INFO [stdout] test xml::tests::test_find_tag_value ... ok
Dec 02 11:04:57.091 INFO [stdout] test subscriptions::tests::test_callback_invocation ... FAILED
Dec 02 11:04:57.091 INFO [stdout] 
Dec 02 11:04:57.091 INFO [stdout] failures:
Dec 02 11:04:57.091 INFO [stdout] 
Dec 02 11:04:57.091 INFO [stdout] ---- subscriptions::tests::test_callback_invocation stdout ----
Dec 02 11:04:57.091 INFO [stdout] thread 'subscriptions::tests::test_callback_invocation' panicked at 'assertion failed: notice.is_some()', src/subscriptions.rs:400:5
Dec 02 11:04:57.091 INFO [stdout] stack backtrace:
Dec 02 11:04:57.091 INFO [stdout]    0:     0x558d61ca86ee - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h15749a98198ba25f
Dec 02 11:04:57.091 INFO [stdout]                                at libstd/sys/unix/backtrace/tracing/gcc_s.rs:49
Dec 02 11:04:57.091 INFO [stdout]    1:     0x558d61c99996 - std::sys_common::backtrace::print::h0ba65c811294dbf0
Dec 02 11:04:57.091 INFO [stdout]                                at libstd/sys_common/backtrace.rs:71
Dec 02 11:04:57.091 INFO [stdout]                                at libstd/sys_common/backtrace.rs:59
Dec 02 11:04:57.091 INFO [stdout]    2:     0x558d61c97bed - std::panicking::default_hook::{{closure}}::h42bb31757f0fee64
Dec 02 11:04:57.091 INFO [stdout]                                at libstd/panicking.rs:211
Dec 02 11:04:57.091 INFO [stdout]    3:     0x558d61c978fb - std::panicking::default_hook::hebc8848d7aa7dbd9
Dec 02 11:04:57.091 INFO [stdout]                                at libstd/panicking.rs:221
Dec 02 11:04:57.091 INFO [stdout]    4:     0x558d61c9829c - std::panicking::rust_panic_with_hook::h86e619a638415243
Dec 02 11:04:57.091 INFO [stdout]                                at libstd/panicking.rs:477
Dec 02 11:04:57.091 INFO [stdout]    5:     0x558d61b9a7ef - std::panicking::begin_panic::h1d75b98ff56e90ea
Dec 02 11:04:57.091 INFO [stdout]                                at libstd/panicking.rs:411
Dec 02 11:04:57.091 INFO [stdout]    6:     0x558d6197050d - wemo::subscriptions::tests::test_callback_invocation::h7befd2919adeb019
Dec 02 11:04:57.091 INFO [stdout]                                at src/subscriptions.rs:400
Dec 02 11:04:57.091 INFO [stdout]    7:     0x558d6196ffb9 - wemo::subscriptions::tests::test_callback_invocation::{{closure}}::h2b01b47f588ef93f
Dec 02 11:04:57.091 INFO [stdout]                                at src/subscriptions.rs:365
Dec 02 11:04:57.091 INFO [stdout]    8:     0x558d6196b33d - core::ops::function::FnOnce::call_once::hd0c70e159f8bee1f
Dec 02 11:04:57.091 INFO [stdout]                                at libcore/ops/function.rs:238
Dec 02 11:04:57.091 INFO [stdout]    9:     0x558d619b53ae - <F as alloc::boxed::FnBox<A>>::call_box::hbea525814fa423cc
Dec 02 11:04:57.091 INFO [stdout]                                at libtest/lib.rs:1461
Dec 02 11:04:57.091 INFO [stdout]                                at libcore/ops/function.rs:238
Dec 02 11:04:57.091 INFO [stdout]                                at liballoc/boxed.rs:646
Dec 02 11:04:57.091 INFO [stdout]   10:     0x558d61cb7829 - __rust_maybe_catch_panic
Dec 02 11:04:57.091 INFO [stdout]                                at libpanic_unwind/lib.rs:103
Dec 02 11:04:57.091 INFO [stdout]   11:     0x558d619c0d0d - std::sys_common::backtrace::__rust_begin_short_backtrace::h0ff507d7422944d3
Dec 02 11:04:57.091 INFO [stdout]                                at libstd/panicking.rs:289
Dec 02 11:04:57.091 INFO [stdout]                                at libstd/panic.rs:392
Dec 02 11:04:57.091 INFO [stdout]                                at libtest/lib.rs:1416
Dec 02 11:04:57.091 INFO [stdout]                                at libstd/sys_common/backtrace.rs:136
Dec 02 11:04:57.091 INFO [stdout]   12:     0x558d619d9344 - std::panicking::try::do_call::hba0bdcc3670471cc
Dec 02 11:04:57.091 INFO [stdout]                                at libstd/thread/mod.rs:409
Dec 02 11:04:57.091 INFO [stdout]                                at libstd/panic.rs:313
Dec 02 11:04:57.091 INFO [stdout]                                at libstd/panicking.rs:310
Dec 02 11:04:57.091 INFO [stdout]   13:     0x558d61cb7829 - __rust_maybe_catch_panic
Dec 02 11:04:57.091 INFO [stdout]                                at libpanic_unwind/lib.rs:103
Dec 02 11:04:57.091 INFO [stdout]   14:     0x558d619c9c16 - <F as alloc::boxed::FnBox<A>>::call_box::hdac52587b00591af
Dec 02 11:04:57.091 INFO [stdout]                                at libstd/panicking.rs:289
Dec 02 11:04:57.091 INFO [stdout]                                at libstd/panic.rs:392
Dec 02 11:04:57.091 INFO [stdout]                                at libstd/thread/mod.rs:408
Dec 02 11:04:57.091 INFO [stdout]                                at liballoc/boxed.rs:646
Dec 02 11:04:57.091 INFO [stdout]   15:     0x558d61caa3da - std::sys_common::thread::start_thread::h75d887c7d2cc4479
Dec 02 11:04:57.091 INFO [stdout]                                at liballoc/boxed.rs:656
Dec 02 11:04:57.091 INFO [stdout]                                at libstd/sys_common/thread.rs:24
Dec 02 11:04:57.091 INFO [stdout]   16:     0x558d61c98a05 - std::sys::unix::thread::Thread::new::thread_start::h8414caee632bf9ed
Dec 02 11:04:57.091 INFO [stdout]                                at libstd/sys/unix/thread.rs:90
Dec 02 11:04:57.091 INFO [stdout]   17:     0x7f65ca38b6b9 - start_thread
Dec 02 11:04:57.091 INFO [stdout]   18:     0x7f65c9eab41c - clone
Dec 02 11:04:57.091 INFO [stdout]   19:                0x0 - <unknown>
Dec 02 11:04:57.091 INFO [stdout] 
Dec 02 11:04:57.091 INFO [stdout] 
Dec 02 11:04:57.091 INFO [stdout] failures:
Dec 02 11:04:57.091 INFO [stdout]     subscriptions::tests::test_callback_invocation
Dec 02 11:04:57.091 INFO [stdout] 
Dec 02 11:04:57.091 INFO [stdout] test result: FAILED. 15 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out
Dec 02 11:04:57.091 INFO [stdout] 
Dec 02 11:04:57.094 INFO [stderr] error: test failed, to rerun pass '--lib'
Dec 02 11:04:57.095 INFO [stderr] su: No module specific data is present
Dec 02 11:04:57.485 INFO running `"docker" "inspect" "ba829f9ccfc2ee30ff989d1cf9ed276cab2747ba9599fd6ccb304912b0c79209"`
Dec 02 11:04:57.673 INFO running `"docker" "rm" "-f" "ba829f9ccfc2ee30ff989d1cf9ed276cab2747ba9599fd6ccb304912b0c79209"`
Dec 02 11:04:57.863 INFO [stdout] ba829f9ccfc2ee30ff989d1cf9ed276cab2747ba9599fd6ccb304912b0c79209
