Dec 06 17:20:52.526 INFO testing wemo-0.0.12 against master#906deae0790bd18681b937fe9a141a3c26cf1855 for pr-56514 Dec 06 17:20:52.526 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-1/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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.787 INFO [stdout] ccdec72bd0478976bcf9124929ef96f78f7da5e6a9d70aea1928f1f88068d8c6 Dec 06 17:20:52.794 INFO running `"docker" "start" "-a" "ccdec72bd0478976bcf9124929ef96f78f7da5e6a9d70aea1928f1f88068d8c6"` Dec 06 17:20:53.399 INFO [stderr] usermod: no changes Dec 06 17:20:53.643 INFO [stderr] Compiling url v1.4.1 Dec 06 17:20:53.643 INFO [stderr] Compiling num_cpus v0.2.13 Dec 06 17:20:53.643 INFO [stderr] Compiling net2 v0.2.33 Dec 06 17:20:53.643 INFO [stderr] Compiling get_if_addrs v0.4.1 Dec 06 17:20:53.643 INFO [stderr] Compiling serde_json v0.8.6 Dec 06 17:20:56.052 INFO [stderr] Compiling miow v0.1.5 Dec 06 17:20:56.572 INFO [stderr] Compiling mio v0.5.1 Dec 06 17:20:58.632 INFO [stderr] Compiling cookie v0.2.5 Dec 06 17:21:01.356 INFO [stderr] Compiling hyper v0.9.18 Dec 06 17:21:10.416 INFO [stderr] Compiling iron v0.4.0 Dec 06 17:21:12.349 INFO [stderr] Compiling persistent v0.2.1 Dec 06 17:21:13.684 INFO [stderr] Compiling bodyparser v0.4.1 Dec 06 17:21:15.630 INFO [stderr] Compiling urlencoded v0.4.1 Dec 06 17:21:17.294 INFO [stderr] Compiling wemo v0.0.12 (/source) Dec 06 17:21:21.294 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 27.87s Dec 06 17:21:21.303 INFO [stderr] su: No module specific data is present Dec 06 17:21:22.520 INFO running `"docker" "inspect" "ccdec72bd0478976bcf9124929ef96f78f7da5e6a9d70aea1928f1f88068d8c6"` Dec 06 17:21:22.673 INFO running `"docker" "rm" "-f" "ccdec72bd0478976bcf9124929ef96f78f7da5e6a9d70aea1928f1f88068d8c6"` Dec 06 17:21:22.790 INFO [stdout] ccdec72bd0478976bcf9124929ef96f78f7da5e6a9d70aea1928f1f88068d8c6 Dec 06 17:21:22.794 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-1/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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:22.992 INFO [stdout] cc11deaebf20e195d3565b291f2eb8ae803f67c477da435c82c3d99b86b9c376 Dec 06 17:21:22.994 INFO running `"docker" "start" "-a" "cc11deaebf20e195d3565b291f2eb8ae803f67c477da435c82c3d99b86b9c376"` Dec 06 17:21:24.300 INFO [stderr] usermod: no changes Dec 06 17:21:24.534 INFO [stderr] Compiling wemo v0.0.12 (/source) Dec 06 17:21:42.680 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 18.35s Dec 06 17:21:42.687 INFO [stderr] su: No module specific data is present Dec 06 17:21:43.045 INFO running `"docker" "inspect" "cc11deaebf20e195d3565b291f2eb8ae803f67c477da435c82c3d99b86b9c376"` Dec 06 17:21:43.158 INFO running `"docker" "rm" "-f" "cc11deaebf20e195d3565b291f2eb8ae803f67c477da435c82c3d99b86b9c376"` Dec 06 17:21:43.239 INFO [stdout] cc11deaebf20e195d3565b291f2eb8ae803f67c477da435c82c3d99b86b9c376 Dec 06 17:21:43.241 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-1/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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.548 INFO [stdout] 74e56963ee762d4f1b64bc33dc3bb8cee31bb90209cbdd005b83ae07757ea3d5 Dec 06 17:21:43.549 INFO running `"docker" "start" "-a" "74e56963ee762d4f1b64bc33dc3bb8cee31bb90209cbdd005b83ae07757ea3d5"` Dec 06 17:21:43.983 INFO [stderr] usermod: no changes Dec 06 17:21:44.134 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 0.13s Dec 06 17:21:44.153 INFO [stderr] Running /target/debug/deps/wemo-fdb69de3e72359b8 Dec 06 17:21:44.156 INFO [stdout] Dec 06 17:21:44.156 INFO [stdout] running 16 tests Dec 06 17:21:44.173 INFO [stdout] test device::switch::tests::test_get_ip_address_with_no_ip ... ok Dec 06 17:21:44.173 INFO [stdout] test device::switch::tests::test_get_ip_address_with_dynamic_ip ... ok Dec 06 17:21:44.173 INFO [stdout] test device::switch::tests::test_get_port_with_port_set ... ok Dec 06 17:21:44.173 INFO [stdout] test device::switch::tests::test_name_with_ip ... ok Dec 06 17:21:44.173 INFO [stdout] test device::switch::tests::test_name_with_ip_and_port ... ok Dec 06 17:21:44.173 INFO [stdout] test device::switch::tests::test_get_port_without_port_set ... ok Dec 06 17:21:44.173 INFO [stdout] test device::switch::tests::test_update_location_with_static_ip ... ok Dec 06 17:21:44.173 INFO [stdout] test device::switch::tests::test_update_location_with_dynamic_ip ... ok Dec 06 17:21:44.173 INFO [stdout] test device::switch::tests::test_get_ip_address_with_static_ip ... ok Dec 06 17:21:44.173 INFO [stdout] test device::switch::tests::test_name_without_ip ... ok Dec 06 17:21:44.180 INFO [stdout] test parsing::tests::switch_notifications ... ok Dec 06 17:21:44.180 INFO [stdout] test parsing::tests::insight_notifications ... ok Dec 06 17:21:44.198 INFO [stdout] test subscriptions::tests::test_send_subscribe ... ok Dec 06 17:21:44.230 INFO [stdout] test xml::tests::test_find_tag_value ... ok Dec 06 17:21:44.268 INFO [stdout] test xml::tests::test_find_tag_value_failure ... ok Dec 06 17:21:44.464 INFO [stdout] test subscriptions::tests::test_callback_invocation ... FAILED Dec 06 17:21:44.464 INFO [stderr] error: test failed, to rerun pass '--lib' Dec 06 17:21:44.464 INFO [stdout] Dec 06 17:21:44.464 INFO [stdout] failures: Dec 06 17:21:44.464 INFO [stdout] Dec 06 17:21:44.464 INFO [stdout] ---- subscriptions::tests::test_callback_invocation stdout ---- Dec 06 17:21:44.464 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.464 INFO [stdout] stack backtrace: Dec 06 17:21:44.464 INFO [stdout] 0: 0x564d66221ed3 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h3c2ada0a2db8c5e7 Dec 06 17:21:44.464 INFO [stdout] at src/libstd/sys/unix/backtrace/tracing/gcc_s.rs:49 Dec 06 17:21:44.464 INFO [stdout] 1: 0x564d6621be58 - std::sys_common::backtrace::_print::h95865c77318e98b7 Dec 06 17:21:44.464 INFO [stdout] at src/libstd/sys_common/backtrace.rs:71 Dec 06 17:21:44.464 INFO [stdout] 2: 0x564d66220732 - std::panicking::default_hook::{{closure}}::hb3d1f299fcea42e5 Dec 06 17:21:44.464 INFO [stdout] at src/libstd/sys_common/backtrace.rs:59 Dec 06 17:21:44.464 INFO [stdout] at src/libstd/panicking.rs:211 Dec 06 17:21:44.464 INFO [stdout] 3: 0x564d6622041c - std::panicking::default_hook::h4eae6f26f73244c8 Dec 06 17:21:44.464 INFO [stdout] at src/libstd/panicking.rs:221 Dec 06 17:21:44.464 INFO [stdout] 4: 0x564d66220e40 - std::panicking::rust_panic_with_hook::h368d37fd53b59464 Dec 06 17:21:44.464 INFO [stdout] at src/libstd/panicking.rs:491 Dec 06 17:21:44.464 INFO [stdout] 5: 0x564d661f4737 - std::panicking::begin_panic::h94f4189a5eb9b44a Dec 06 17:21:44.464 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/panicking.rs:425 Dec 06 17:21:44.464 INFO [stdout] 6: 0x564d65ede039 - wemo::subscriptions::tests::test_callback_invocation::h5d39999bd4d6d453 Dec 06 17:21:44.464 INFO [stdout] at src/subscriptions.rs:400 Dec 06 17:21:44.464 INFO [stdout] 7: 0x564d65eddac9 - wemo::subscriptions::tests::test_callback_invocation::{{closure}}::h5ac5d860dd27ae70 Dec 06 17:21:44.464 INFO [stdout] at src/subscriptions.rs:365 Dec 06 17:21:44.464 INFO [stdout] 8: 0x564d65ed6a7d - core::ops::function::FnOnce::call_once::h82a9e83523282845 Dec 06 17:21:44.464 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libcore/ops/function.rs:238 Dec 06 17:21:44.464 INFO [stdout] 9: 0x564d65f26b2e - >::call_box::h994167e0fc25f327 Dec 06 17:21:44.464 INFO [stdout] at src/libtest/lib.rs:1471 Dec 06 17:21:44.464 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libcore/ops/function.rs:238 Dec 06 17:21:44.464 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/liballoc/boxed.rs:673 Dec 06 17:21:44.464 INFO [stdout] 10: 0x564d662307e9 - __rust_maybe_catch_panic Dec 06 17:21:44.464 INFO [stdout] at src/libpanic_unwind/lib.rs:102 Dec 06 17:21:44.464 INFO [stdout] 11: 0x564d65f1ea33 - std::sys_common::backtrace::__rust_begin_short_backtrace::h5d34cfb78512c8c3 Dec 06 17:21:44.464 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/panicking.rs:289 Dec 06 17:21:44.464 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/panic.rs:398 Dec 06 17:21:44.464 INFO [stdout] at src/libtest/lib.rs:1426 Dec 06 17:21:44.464 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/sys_common/backtrace.rs:136 Dec 06 17:21:44.465 INFO [stdout] 12: 0x564d65f1f954 - std::panicking::try::do_call::h7a86b6211ebb5e72 Dec 06 17:21:44.465 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/thread/mod.rs:477 Dec 06 17:21:44.465 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/panic.rs:319 Dec 06 17:21:44.465 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/panicking.rs:310 Dec 06 17:21:44.465 INFO [stdout] 13: 0x564d662307e9 - __rust_maybe_catch_panic Dec 06 17:21:44.465 INFO [stdout] at src/libpanic_unwind/lib.rs:102 Dec 06 17:21:44.465 INFO [stdout] 14: 0x564d65f26a0c - >::call_box::h877dbe22cb3997f4 Dec 06 17:21:44.465 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/panicking.rs:289 Dec 06 17:21:44.465 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/panic.rs:398 Dec 06 17:21:44.465 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/thread/mod.rs:476 Dec 06 17:21:44.465 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/liballoc/boxed.rs:673 Dec 06 17:21:44.465 INFO [stdout] 15: 0x564d6622440d - std::sys::unix::thread::Thread::new::thread_start::hea5f7f9c570dc4cd Dec 06 17:21:44.465 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/liballoc/boxed.rs:683 Dec 06 17:21:44.465 INFO [stdout] at src/libstd/sys_common/thread.rs:24 Dec 06 17:21:44.465 INFO [stdout] at src/libstd/sys/unix/thread.rs:90 Dec 06 17:21:44.465 INFO [stdout] 16: 0x7fa5a53066b9 - start_thread Dec 06 17:21:44.465 INFO [stdout] 17: 0x7fa5a4e2641c - clone Dec 06 17:21:44.465 INFO [stdout] 18: 0x0 - Dec 06 17:21:44.465 INFO [stdout] Dec 06 17:21:44.465 INFO [stdout] Dec 06 17:21:44.465 INFO [stdout] failures: Dec 06 17:21:44.465 INFO [stdout] subscriptions::tests::test_callback_invocation Dec 06 17:21:44.465 INFO [stdout] Dec 06 17:21:44.465 INFO [stdout] test result: FAILED. 15 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out Dec 06 17:21:44.465 INFO [stdout] Dec 06 17:21:44.467 INFO [stderr] su: No module specific data is present Dec 06 17:21:44.790 INFO running `"docker" "inspect" "74e56963ee762d4f1b64bc33dc3bb8cee31bb90209cbdd005b83ae07757ea3d5"` Dec 06 17:21:44.898 INFO running `"docker" "rm" "-f" "74e56963ee762d4f1b64bc33dc3bb8cee31bb90209cbdd005b83ae07757ea3d5"` Dec 06 17:21:44.985 INFO [stdout] 74e56963ee762d4f1b64bc33dc3bb8cee31bb90209cbdd005b83ae07757ea3d5