Nov 17 18:14:22.338 INFO testing wemo-0.0.12 against master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c for pr-55982 Nov 17 18:14:22.338 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-2/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/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 17 18:14:22.747 INFO [stdout] 1a1e10230c1f8e14ae40aa5b145a23dbc28b556df621bfcbf6a2436bfcb6b430 Nov 17 18:14:22.755 INFO running `"docker" "start" "-a" "1a1e10230c1f8e14ae40aa5b145a23dbc28b556df621bfcbf6a2436bfcb6b430"` Nov 17 18:14:23.847 INFO [stderr] usermod: no changes Nov 17 18:14:24.019 INFO [stderr] Compiling c_linked_list v1.1.1 Nov 17 18:14:24.025 INFO [stderr] Compiling net2 v0.2.33 Nov 17 18:14:24.025 INFO [stderr] Compiling url v1.4.1 Nov 17 18:14:24.535 INFO [stderr] Compiling get_if_addrs v0.4.1 Nov 17 18:14:25.587 INFO [stderr] Compiling miow v0.1.5 Nov 17 18:14:26.495 INFO [stderr] Compiling mio v0.5.1 Nov 17 18:14:28.147 INFO [stderr] Compiling cookie v0.2.5 Nov 17 18:14:29.525 INFO [stderr] Compiling hyper v0.9.18 Nov 17 18:14:37.659 INFO [stderr] Compiling iron v0.4.0 Nov 17 18:14:40.285 INFO [stderr] Compiling persistent v0.2.1 Nov 17 18:14:42.102 INFO [stderr] Compiling bodyparser v0.4.1 Nov 17 18:14:44.560 INFO [stderr] Compiling urlencoded v0.4.1 Nov 17 18:14:46.814 INFO [stderr] Compiling wemo v0.0.12 (/source) Nov 17 18:14:52.843 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 28.97s Nov 17 18:14:52.857 INFO [stderr] su: No module specific data is present Nov 17 18:14:53.613 INFO running `"docker" "inspect" "1a1e10230c1f8e14ae40aa5b145a23dbc28b556df621bfcbf6a2436bfcb6b430"` Nov 17 18:14:53.871 INFO running `"docker" "rm" "-f" "1a1e10230c1f8e14ae40aa5b145a23dbc28b556df621bfcbf6a2436bfcb6b430"` Nov 17 18:14:54.095 INFO [stdout] 1a1e10230c1f8e14ae40aa5b145a23dbc28b556df621bfcbf6a2436bfcb6b430 Nov 17 18:14:54.099 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-2/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/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 17 18:14:54.449 INFO [stdout] 3ca0b594b3005ba04efbe4d21f9465712df3b3638dd7bb6fd2442681be368412 Nov 17 18:14:54.456 INFO running `"docker" "start" "-a" "3ca0b594b3005ba04efbe4d21f9465712df3b3638dd7bb6fd2442681be368412"` Nov 17 18:14:55.886 INFO [stderr] usermod: no changes Nov 17 18:14:56.031 INFO [stderr] Compiling wemo v0.0.12 (/source) Nov 17 18:15:10.230 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 14.32s Nov 17 18:15:10.246 INFO [stderr] su: No module specific data is present Nov 17 18:15:10.555 INFO running `"docker" "inspect" "3ca0b594b3005ba04efbe4d21f9465712df3b3638dd7bb6fd2442681be368412"` Nov 17 18:15:10.643 INFO running `"docker" "rm" "-f" "3ca0b594b3005ba04efbe4d21f9465712df3b3638dd7bb6fd2442681be368412"` Nov 17 18:15:10.776 INFO [stdout] 3ca0b594b3005ba04efbe4d21f9465712df3b3638dd7bb6fd2442681be368412 Nov 17 18:15:10.778 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-2/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/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 17 18:15:10.955 INFO [stdout] 19b60e8ce0119382c3227a3f66caec27b324beefc436302068ad21c7059d1faf Nov 17 18:15:10.958 INFO running `"docker" "start" "-a" "19b60e8ce0119382c3227a3f66caec27b324beefc436302068ad21c7059d1faf"` Nov 17 18:15:11.415 INFO [stderr] usermod: no changes Nov 17 18:15:11.539 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 0.10s Nov 17 18:15:11.557 INFO [stderr] Running /target/debug/deps/wemo-40b0ac2447a3223a Nov 17 18:15:11.561 INFO [stdout] Nov 17 18:15:11.561 INFO [stdout] running 16 tests Nov 17 18:15:11.562 INFO [stdout] test device::switch::tests::test_get_ip_address_with_no_ip ... ok Nov 17 18:15:11.562 INFO [stdout] test device::switch::tests::test_get_port_with_port_set ... ok Nov 17 18:15:11.562 INFO [stdout] test device::switch::tests::test_get_ip_address_with_dynamic_ip ... ok Nov 17 18:15:11.562 INFO [stdout] test device::switch::tests::test_get_port_without_port_set ... ok Nov 17 18:15:11.562 INFO [stdout] test device::switch::tests::test_name_without_ip ... ok Nov 17 18:15:11.562 INFO [stdout] test device::switch::tests::test_name_with_ip_and_port ... ok Nov 17 18:15:11.562 INFO [stdout] test device::switch::tests::test_name_with_ip ... ok Nov 17 18:15:11.562 INFO [stdout] test device::switch::tests::test_update_location_with_dynamic_ip ... ok Nov 17 18:15:11.566 INFO [stdout] test device::switch::tests::test_update_location_with_static_ip ... ok Nov 17 18:15:11.568 INFO [stdout] test parsing::tests::switch_notifications ... ok Nov 17 18:15:11.568 INFO [stdout] test parsing::tests::insight_notifications ... ok Nov 17 18:15:11.572 INFO [stdout] test device::switch::tests::test_get_ip_address_with_static_ip ... ok Nov 17 18:15:11.616 INFO [stdout] test xml::tests::test_find_tag_value_failure ... ok Nov 17 18:15:11.628 INFO [stdout] test xml::tests::test_find_tag_value ... ok Nov 17 18:15:11.640 INFO [stdout] test subscriptions::tests::test_send_subscribe ... ok Nov 17 18:15:11.834 INFO [stdout] test subscriptions::tests::test_callback_invocation ... FAILED Nov 17 18:15:11.834 INFO [stderr] error: test failed, to rerun pass '--lib' Nov 17 18:15:11.834 INFO [stdout] Nov 17 18:15:11.834 INFO [stdout] failures: Nov 17 18:15:11.834 INFO [stdout] Nov 17 18:15:11.834 INFO [stdout] ---- subscriptions::tests::test_callback_invocation stdout ---- Nov 17 18:15:11.834 INFO [stdout] thread 'subscriptions::tests::test_callback_invocation' panicked at 'assertion failed: notice.is_some()', src/subscriptions.rs:400:5 Nov 17 18:15:11.834 INFO [stdout] stack backtrace: Nov 17 18:15:11.834 INFO [stdout] 0: 0x5561e12c8d83 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h1a944b80a560daa8 Nov 17 18:15:11.834 INFO [stdout] at libstd/sys/unix/backtrace/tracing/gcc_s.rs:49 Nov 17 18:15:11.834 INFO [stdout] 1: 0x5561e12c2da8 - std::sys_common::backtrace::_print::h18f735b4c3a49bdb Nov 17 18:15:11.834 INFO [stdout] at libstd/sys_common/backtrace.rs:71 Nov 17 18:15:11.834 INFO [stdout] 2: 0x5561e12c75f4 - std::panicking::default_hook::{{closure}}::h97ab3548a215c097 Nov 17 18:15:11.834 INFO [stdout] at libstd/sys_common/backtrace.rs:59 Nov 17 18:15:11.834 INFO [stdout] at libstd/panicking.rs:211 Nov 17 18:15:11.834 INFO [stdout] 3: 0x5561e12c72dc - std::panicking::default_hook::h98c2a01ef734e4b0 Nov 17 18:15:11.834 INFO [stdout] at libstd/panicking.rs:221 Nov 17 18:15:11.834 INFO [stdout] 4: 0x5561e12c7d00 - std::panicking::rust_panic_with_hook::hb4b3f28eb79ea752 Nov 17 18:15:11.834 INFO [stdout] at libstd/panicking.rs:476 Nov 17 18:15:11.834 INFO [stdout] 5: 0x5561e11bf9f7 - std::panicking::begin_panic::h500b84b677a2ac34 Nov 17 18:15:11.834 INFO [stdout] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panicking.rs:410 Nov 17 18:15:11.834 INFO [stdout] 6: 0x5561e0f84e09 - wemo::subscriptions::tests::test_callback_invocation::h55f4cfc5b71618dd Nov 17 18:15:11.834 INFO [stdout] at src/subscriptions.rs:400 Nov 17 18:15:11.834 INFO [stdout] 7: 0x5561e0f84899 - wemo::subscriptions::tests::test_callback_invocation::{{closure}}::h3c7537de8358c374 Nov 17 18:15:11.834 INFO [stdout] at src/subscriptions.rs:365 Nov 17 18:15:11.834 INFO [stdout] 8: 0x5561e0f7d6cd - core::ops::function::FnOnce::call_once::h511fcdbbcb6a2411 Nov 17 18:15:11.834 INFO [stdout] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libcore/ops/function.rs:238 Nov 17 18:15:11.834 INFO [stdout] 9: 0x5561e0fccefe - >::call_box::h9dcb7fc9b8bb4240 Nov 17 18:15:11.834 INFO [stdout] at libtest/lib.rs:1471 Nov 17 18:15:11.834 INFO [stdout] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libcore/ops/function.rs:238 Nov 17 18:15:11.834 INFO [stdout] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/liballoc/boxed.rs:673 Nov 17 18:15:11.834 INFO [stdout] 10: 0x5561e12d77d9 - __rust_maybe_catch_panic Nov 17 18:15:11.834 INFO [stdout] at libpanic_unwind/lib.rs:102 Nov 17 18:15:11.834 INFO [stdout] 11: 0x5561e0fc4f63 - std::sys_common::backtrace::__rust_begin_short_backtrace::h89ea8afe71e36f86 Nov 17 18:15:11.834 INFO [stdout] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panicking.rs:289 Nov 17 18:15:11.835 INFO [stdout] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panic.rs:398 Nov 17 18:15:11.835 INFO [stdout] at libtest/lib.rs:1426 Nov 17 18:15:11.835 INFO [stdout] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/sys_common/backtrace.rs:136 Nov 17 18:15:11.835 INFO [stdout] 12: 0x5561e0fc5e14 - std::panicking::try::do_call::h4132042e0cbea3ae Nov 17 18:15:11.835 INFO [stdout] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/thread/mod.rs:477 Nov 17 18:15:11.835 INFO [stdout] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panic.rs:319 Nov 17 18:15:11.835 INFO [stdout] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panicking.rs:310 Nov 17 18:15:11.835 INFO [stdout] 13: 0x5561e12d77d9 - __rust_maybe_catch_panic Nov 17 18:15:11.835 INFO [stdout] at libpanic_unwind/lib.rs:102 Nov 17 18:15:11.835 INFO [stdout] 14: 0x5561e0fcd02c - >::call_box::hab7f616f05880bf6 Nov 17 18:15:11.835 INFO [stdout] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panicking.rs:289 Nov 17 18:15:11.835 INFO [stdout] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panic.rs:398 Nov 17 18:15:11.835 INFO [stdout] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/thread/mod.rs:476 Nov 17 18:15:11.835 INFO [stdout] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/liballoc/boxed.rs:673 Nov 17 18:15:11.835 INFO [stdout] 15: 0x5561e12cb2ed - std::sys::unix::thread::Thread::new::thread_start::h5f8d67158a1ca038 Nov 17 18:15:11.835 INFO [stdout] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/liballoc/boxed.rs:683 Nov 17 18:15:11.835 INFO [stdout] at libstd/sys_common/thread.rs:24 Nov 17 18:15:11.835 INFO [stdout] at libstd/sys/unix/thread.rs:90 Nov 17 18:15:11.835 INFO [stdout] 16: 0x7f3896dd16b9 - start_thread Nov 17 18:15:11.835 INFO [stdout] 17: 0x7f38968f141c - clone Nov 17 18:15:11.835 INFO [stdout] 18: 0x0 - Nov 17 18:15:11.835 INFO [stdout] Nov 17 18:15:11.835 INFO [stdout] Nov 17 18:15:11.835 INFO [stdout] failures: Nov 17 18:15:11.835 INFO [stdout] subscriptions::tests::test_callback_invocation Nov 17 18:15:11.835 INFO [stdout] Nov 17 18:15:11.835 INFO [stdout] test result: FAILED. 15 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out Nov 17 18:15:11.835 INFO [stdout] Nov 17 18:15:11.837 INFO [stderr] su: No module specific data is present Nov 17 18:15:12.137 INFO running `"docker" "inspect" "19b60e8ce0119382c3227a3f66caec27b324beefc436302068ad21c7059d1faf"` Nov 17 18:15:12.255 INFO running `"docker" "rm" "-f" "19b60e8ce0119382c3227a3f66caec27b324beefc436302068ad21c7059d1faf"` Nov 17 18:15:12.347 INFO [stdout] 19b60e8ce0119382c3227a3f66caec27b324beefc436302068ad21c7059d1faf