Dec 06 17:20:52.372 INFO testing wemo-0.0.12 against try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468 for pr-56514
Dec 06 17:20:52.373 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-0/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/pr-56514/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/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 +6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468-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"`
Dec 06 17:20:52.591 INFO [stdout] 90b3790aa17bfef383bff757c3d466a2fef8c718f58005f0f37421660b9a5db2
Dec 06 17:20:52.593 INFO running `"docker" "start" "-a" "90b3790aa17bfef383bff757c3d466a2fef8c718f58005f0f37421660b9a5db2"`
Dec 06 17:20:53.052 INFO [stderr] usermod: no changes
Dec 06 17:20:53.216 INFO [stderr]    Compiling c_linked_list v1.1.1
Dec 06 17:20:53.216 INFO [stderr]    Compiling url v1.4.1
Dec 06 17:20:53.216 INFO [stderr]    Compiling hpack v0.2.0
Dec 06 17:20:53.216 INFO [stderr]    Compiling num_cpus v0.2.13
Dec 06 17:20:53.216 INFO [stderr]    Compiling net2 v0.2.33
Dec 06 17:20:53.218 INFO [stderr]    Compiling nix v0.5.1
Dec 06 17:20:53.220 INFO [stderr]    Compiling serde_json v0.8.6
Dec 06 17:20:54.588 INFO [stderr]    Compiling get_if_addrs v0.4.1
Dec 06 17:20:55.612 INFO [stderr]    Compiling miow v0.1.5
Dec 06 17:20:57.140 INFO [stderr]    Compiling solicit v0.4.4
Dec 06 17:20:59.568 INFO [stderr]    Compiling cookie v0.2.5
Dec 06 17:21:03.104 INFO [stderr]    Compiling mio v0.5.1
Dec 06 17:21:03.189 INFO [stderr]    Compiling hyper v0.9.18
Dec 06 17:21:11.534 INFO [stderr]    Compiling iron v0.4.0
Dec 06 17:21:13.140 INFO [stderr]    Compiling persistent v0.2.1
Dec 06 17:21:14.249 INFO [stderr]    Compiling bodyparser v0.4.1
Dec 06 17:21:16.076 INFO [stderr]    Compiling urlencoded v0.4.1
Dec 06 17:21:17.462 INFO [stderr]    Compiling wemo v0.0.12 (/source)
Dec 06 17:21:22.004 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 28.92s
Dec 06 17:21:22.015 INFO [stderr] su: No module specific data is present
Dec 06 17:21:22.810 INFO running `"docker" "inspect" "90b3790aa17bfef383bff757c3d466a2fef8c718f58005f0f37421660b9a5db2"`
Dec 06 17:21:22.934 INFO running `"docker" "rm" "-f" "90b3790aa17bfef383bff757c3d466a2fef8c718f58005f0f37421660b9a5db2"`
Dec 06 17:21:23.069 INFO [stdout] 90b3790aa17bfef383bff757c3d466a2fef8c718f58005f0f37421660b9a5db2
Dec 06 17:21:23.078 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-0/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/pr-56514/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/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 +6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468-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"`
Dec 06 17:21:23.590 INFO [stdout] e6ced9c5001df10d78b4c279301409d32d7ae172a35a62a985cf86641503a84b
Dec 06 17:21:23.592 INFO running `"docker" "start" "-a" "e6ced9c5001df10d78b4c279301409d32d7ae172a35a62a985cf86641503a84b"`
Dec 06 17:21:24.704 INFO [stderr] usermod: no changes
Dec 06 17:21:24.924 INFO [stderr]    Compiling wemo v0.0.12 (/source)
Dec 06 17:21:42.547 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 17.82s
Dec 06 17:21:42.557 INFO [stderr] su: No module specific data is present
Dec 06 17:21:42.966 INFO running `"docker" "inspect" "e6ced9c5001df10d78b4c279301409d32d7ae172a35a62a985cf86641503a84b"`
Dec 06 17:21:43.079 INFO running `"docker" "rm" "-f" "e6ced9c5001df10d78b4c279301409d32d7ae172a35a62a985cf86641503a84b"`
Dec 06 17:21:43.167 INFO [stdout] e6ced9c5001df10d78b4c279301409d32d7ae172a35a62a985cf86641503a84b
Dec 06 17:21:43.168 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-0/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/pr-56514/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/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 +6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468-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"`
Dec 06 17:21:43.447 INFO [stdout] bf5d9e32bbc58768ae65753c4caa7e46345da523f97628773b47a4e6dde8039a
Dec 06 17:21:43.449 INFO running `"docker" "start" "-a" "bf5d9e32bbc58768ae65753c4caa7e46345da523f97628773b47a4e6dde8039a"`
Dec 06 17:21:43.975 INFO [stderr] usermod: no changes
Dec 06 17:21:44.113 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.12s
Dec 06 17:21:44.128 INFO [stderr]      Running /target/debug/deps/wemo-133f924d65db1b83
Dec 06 17:21:44.134 INFO [stdout] 
Dec 06 17:21:44.134 INFO [stdout] running 16 tests
Dec 06 17:21:44.136 INFO [stdout] test device::switch::tests::test_get_ip_address_with_static_ip ... ok
Dec 06 17:21:44.136 INFO [stdout] test device::switch::tests::test_get_port_with_port_set ... ok
Dec 06 17:21:44.136 INFO [stdout] test device::switch::tests::test_get_ip_address_with_no_ip ... ok
Dec 06 17:21:44.136 INFO [stdout] test device::switch::tests::test_get_ip_address_with_dynamic_ip ... ok
Dec 06 17:21:44.136 INFO [stdout] test device::switch::tests::test_get_port_without_port_set ... ok
Dec 06 17:21:44.136 INFO [stdout] test device::switch::tests::test_name_with_ip ... ok
Dec 06 17:21:44.136 INFO [stdout] test device::switch::tests::test_update_location_with_dynamic_ip ... ok
Dec 06 17:21:44.136 INFO [stdout] test device::switch::tests::test_update_location_with_static_ip ... ok
Dec 06 17:21:44.136 INFO [stdout] test device::switch::tests::test_name_with_ip_and_port ... ok
Dec 06 17:21:44.147 INFO [stdout] test device::switch::tests::test_name_without_ip ... ok
Dec 06 17:21:44.168 INFO [stdout] test parsing::tests::insight_notifications ... ok
Dec 06 17:21:44.168 INFO [stdout] test parsing::tests::switch_notifications ... ok
Dec 06 17:21:44.200 INFO [stdout] test xml::tests::test_find_tag_value ... ok
Dec 06 17:21:44.212 INFO [stdout] test subscriptions::tests::test_send_subscribe ... ok
Dec 06 17:21:44.231 INFO [stdout] test xml::tests::test_find_tag_value_failure ... ok
Dec 06 17:21:44.472 INFO [stdout] test subscriptions::tests::test_callback_invocation ... FAILED
Dec 06 17:21:44.472 INFO [stdout] 
Dec 06 17:21:44.472 INFO [stdout] failures:
Dec 06 17:21:44.472 INFO [stdout] 
Dec 06 17:21:44.472 INFO [stdout] ---- subscriptions::tests::test_callback_invocation stdout ----
Dec 06 17:21:44.472 INFO [stdout] thread 'subscriptions::tests::test_callback_invocation' panicked at 'assertion failed: notice.is_some()', src/subscriptions.rs:400:5
Dec 06 17:21:44.472 INFO [stdout] stack backtrace:
Dec 06 17:21:44.472 INFO [stdout]    0:     0x56448af7b5b3 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h82c998a0e635a5c4
Dec 06 17:21:44.472 INFO [stdout]                                at src/libstd/sys/unix/backtrace/tracing/gcc_s.rs:49
Dec 06 17:21:44.472 INFO [stdout]    1:     0x56448af75538 - std::sys_common::backtrace::_print::h5fbc0bc3cf12d9bf
Dec 06 17:21:44.472 INFO [stdout]                                at src/libstd/sys_common/backtrace.rs:71
Dec 06 17:21:44.472 INFO [stdout]    2:     0x56448af79e12 - std::panicking::default_hook::{{closure}}::h42b81c3dd1a50e32
Dec 06 17:21:44.472 INFO [stdout]                                at src/libstd/sys_common/backtrace.rs:59
Dec 06 17:21:44.472 INFO [stdout]                                at src/libstd/panicking.rs:211
Dec 06 17:21:44.472 INFO [stdout]    3:     0x56448af79afc - std::panicking::default_hook::h6c8d8e1c12a669e5
Dec 06 17:21:44.472 INFO [stdout]                                at src/libstd/panicking.rs:221
Dec 06 17:21:44.472 INFO [stdout]    4:     0x56448af7a520 - std::panicking::rust_panic_with_hook::hce93a56e38c2b0aa
Dec 06 17:21:44.472 INFO [stdout]                                at src/libstd/panicking.rs:491
Dec 06 17:21:44.472 INFO [stdout]    5:     0x56448ae1ef17 - std::panicking::begin_panic::ha48f247592b5004b
Dec 06 17:21:44.472 INFO [stdout]                                at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panicking.rs:425
Dec 06 17:21:44.473 INFO [stdout]    6:     0x56448ac3df79 - wemo::subscriptions::tests::test_callback_invocation::h3643963cc48d843a
Dec 06 17:21:44.473 INFO [stdout]                                at src/subscriptions.rs:400
Dec 06 17:21:44.473 INFO [stdout]    7:     0x56448ac3da09 - wemo::subscriptions::tests::test_callback_invocation::{{closure}}::h8b11fe5946e82a8b
Dec 06 17:21:44.473 INFO [stdout]                                at src/subscriptions.rs:365
Dec 06 17:21:44.473 INFO [stdout]    8:     0x56448ac3699d - core::ops::function::FnOnce::call_once::h8e27ea6755e7c1f0
Dec 06 17:21:44.473 INFO [stdout]                                at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libcore/ops/function.rs:238
Dec 06 17:21:44.473 INFO [stdout]    9:     0x56448ac86a9e - <F as alloc::boxed::FnBox<A>>::call_box::h9ed1658cdce7f33f
Dec 06 17:21:44.473 INFO [stdout]                                at src/libtest/lib.rs:1471
Dec 06 17:21:44.473 INFO [stdout]                                at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libcore/ops/function.rs:238
Dec 06 17:21:44.473 INFO [stdout]                                at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/liballoc/boxed.rs:673
Dec 06 17:21:44.473 INFO [stdout]   10:     0x56448af89ec9 - __rust_maybe_catch_panic
Dec 06 17:21:44.473 INFO [stdout]                                at src/libpanic_unwind/lib.rs:102
Dec 06 17:21:44.473 INFO [stdout]   11:     0x56448ac7e973 - std::sys_common::backtrace::__rust_begin_short_backtrace::h018d03d6681412f5
Dec 06 17:21:44.473 INFO [stdout]                                at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panicking.rs:289
Dec 06 17:21:44.473 INFO [stdout]                                at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panic.rs:398
Dec 06 17:21:44.473 INFO [stdout]                                at src/libtest/lib.rs:1426
Dec 06 17:21:44.473 INFO [stdout]                                at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/sys_common/backtrace.rs:136
Dec 06 17:21:44.473 INFO [stdout]   12:     0x56448ac7f904 - std::panicking::try::do_call::hf8ab3f21dcfaa7a6
Dec 06 17:21:44.473 INFO [stdout]                                at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/thread/mod.rs:477
Dec 06 17:21:44.473 INFO [stdout]                                at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panic.rs:319
Dec 06 17:21:44.473 INFO [stdout]                                at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panicking.rs:310
Dec 06 17:21:44.473 INFO [stdout]   13:     0x56448af89ec9 - __rust_maybe_catch_panic
Dec 06 17:21:44.473 INFO [stdout]                                at src/libpanic_unwind/lib.rs:102
Dec 06 17:21:44.473 INFO [stdout]   14:     0x56448ac8697c - <F as alloc::boxed::FnBox<A>>::call_box::h6465cc998309e287
Dec 06 17:21:44.473 INFO [stdout]                                at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panicking.rs:289
Dec 06 17:21:44.473 INFO [stdout]                                at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panic.rs:398
Dec 06 17:21:44.473 INFO [stdout]                                at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/thread/mod.rs:476
Dec 06 17:21:44.473 INFO [stdout]                                at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/liballoc/boxed.rs:673
Dec 06 17:21:44.473 INFO [stdout]   15:     0x56448af7daed - std::sys::unix::thread::Thread::new::thread_start::h0fe9c527147fd5f5
Dec 06 17:21:44.473 INFO [stdout]                                at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/liballoc/boxed.rs:683
Dec 06 17:21:44.473 INFO [stdout]                                at src/libstd/sys_common/thread.rs:24
Dec 06 17:21:44.473 INFO [stdout]                                at src/libstd/sys/unix/thread.rs:90
Dec 06 17:21:44.473 INFO [stdout]   16:     0x7fe41cf926b9 - start_thread
Dec 06 17:21:44.473 INFO [stdout]   17:     0x7fe41cab241c - clone
Dec 06 17:21:44.473 INFO [stdout]   18:                0x0 - <unknown>
Dec 06 17:21:44.473 INFO [stdout] 
Dec 06 17:21:44.473 INFO [stdout] 
Dec 06 17:21:44.473 INFO [stdout] failures:
Dec 06 17:21:44.473 INFO [stdout]     subscriptions::tests::test_callback_invocation
Dec 06 17:21:44.473 INFO [stdout] 
Dec 06 17:21:44.473 INFO [stdout] test result: FAILED. 15 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out
Dec 06 17:21:44.473 INFO [stdout] 
Dec 06 17:21:44.477 INFO [stderr] error: test failed, to rerun pass '--lib'
Dec 06 17:21:44.479 INFO [stderr] su: No module specific data is present
Dec 06 17:21:44.783 INFO running `"docker" "inspect" "bf5d9e32bbc58768ae65753c4caa7e46345da523f97628773b47a4e6dde8039a"`
Dec 06 17:21:44.874 INFO running `"docker" "rm" "-f" "bf5d9e32bbc58768ae65753c4caa7e46345da523f97628773b47a4e6dde8039a"`
Dec 06 17:21:44.966 INFO [stdout] bf5d9e32bbc58768ae65753c4caa7e46345da523f97628773b47a4e6dde8039a