Dec 14 19:17:05.956 INFO testing soft-test-failures-0.1.0 against try#aa49d8ef14939ddec0e34b346b60174a5673d48f for pr-56550 Dec 14 19:17:05.956 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-5/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/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 +aa49d8ef14939ddec0e34b346b60174a5673d48f-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 14 19:17:06.512 INFO [stdout] a640a75033a8ea9e1c2117b2177ecca9250f9b36cc079f06f13ba57f973d35bf Dec 14 19:17:06.520 INFO running `"docker" "start" "-a" "a640a75033a8ea9e1c2117b2177ecca9250f9b36cc079f06f13ba57f973d35bf"` Dec 14 19:17:07.583 INFO [stderr] usermod: no changes Dec 14 19:17:07.651 INFO [stderr] Compiling soft-test-failures v0.1.0 (/source) Dec 14 19:17:08.375 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 0.76s Dec 14 19:17:08.375 INFO [stderr] su: No module specific data is present Dec 14 19:17:09.268 INFO running `"docker" "inspect" "a640a75033a8ea9e1c2117b2177ecca9250f9b36cc079f06f13ba57f973d35bf"` Dec 14 19:17:09.589 INFO running `"docker" "rm" "-f" "a640a75033a8ea9e1c2117b2177ecca9250f9b36cc079f06f13ba57f973d35bf"` Dec 14 19:17:09.934 INFO [stdout] a640a75033a8ea9e1c2117b2177ecca9250f9b36cc079f06f13ba57f973d35bf Dec 14 19:17:09.942 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-5/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/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 +aa49d8ef14939ddec0e34b346b60174a5673d48f-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 14 19:17:10.415 INFO [stdout] b166028678def03f306cf66a4b60692c830788fd2e5a69569189ce25deec2995 Dec 14 19:17:10.417 INFO running `"docker" "start" "-a" "b166028678def03f306cf66a4b60692c830788fd2e5a69569189ce25deec2995"` Dec 14 19:17:12.040 INFO [stderr] usermod: no changes Dec 14 19:17:12.092 INFO [stderr] Compiling soft-test-failures v0.1.0 (/source) Dec 14 19:17:13.281 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 1.21s Dec 14 19:17:13.283 INFO [stderr] su: No module specific data is present Dec 14 19:17:13.668 INFO running `"docker" "inspect" "b166028678def03f306cf66a4b60692c830788fd2e5a69569189ce25deec2995"` Dec 14 19:17:13.784 INFO running `"docker" "rm" "-f" "b166028678def03f306cf66a4b60692c830788fd2e5a69569189ce25deec2995"` Dec 14 19:17:13.906 INFO [stdout] b166028678def03f306cf66a4b60692c830788fd2e5a69569189ce25deec2995 Dec 14 19:17:13.909 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-5/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/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 +aa49d8ef14939ddec0e34b346b60174a5673d48f-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 14 19:17:14.269 INFO [stdout] 4ea95023e801c1d68e7890f9c9cae6af0b5856b0759c0946291680cbf10be7da Dec 14 19:17:14.276 INFO running `"docker" "start" "-a" "4ea95023e801c1d68e7890f9c9cae6af0b5856b0759c0946291680cbf10be7da"` Dec 14 19:17:15.495 INFO [stderr] usermod: no changes Dec 14 19:17:15.567 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 0.02s Dec 14 19:17:15.576 INFO [stderr] Running /target/debug/deps/soft_test_failures-6c89b6799cc1e7be Dec 14 19:17:15.583 INFO [stdout] Dec 14 19:17:15.583 INFO [stdout] running 1 test Dec 14 19:17:15.638 INFO [stdout] test expect_failures ... FAILED Dec 14 19:17:15.638 INFO [stdout] Dec 14 19:17:15.638 INFO [stdout] failures: Dec 14 19:17:15.638 INFO [stdout] Dec 14 19:17:15.638 INFO [stdout] ---- expect_failures stdout ---- Dec 14 19:17:15.638 INFO [stdout] thread 'expect_failures' panicked at '`expect` test failed with 2 failed assertions: Dec 14 19:17:15.638 INFO [stdout] 1: 4 surely is not 5 Dec 14 19:17:15.638 INFO [stdout] 2: Expected 3 - 7 == -3 Dec 14 19:17:15.638 INFO [stdout] ', src/lib.rs:48:5 Dec 14 19:17:15.638 INFO [stdout] stack backtrace: Dec 14 19:17:15.638 INFO [stdout] 0: 0x55a04e458de3 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h2c40bdfb30f25bdd Dec 14 19:17:15.638 INFO [stdout] at src/libstd/sys/unix/backtrace/tracing/gcc_s.rs:49 Dec 14 19:17:15.638 INFO [stdout] 1: 0x55a04e4535e8 - std::sys_common::backtrace::_print::he11ceebc70626ac9 Dec 14 19:17:15.638 INFO [stdout] at src/libstd/sys_common/backtrace.rs:71 Dec 14 19:17:15.638 INFO [stdout] 2: 0x55a04e457d52 - std::panicking::default_hook::{{closure}}::h4fde3ffb19c01413 Dec 14 19:17:15.638 INFO [stdout] at src/libstd/sys_common/backtrace.rs:59 Dec 14 19:17:15.638 INFO [stdout] at src/libstd/panicking.rs:210 Dec 14 19:17:15.638 INFO [stdout] 3: 0x55a04e457a47 - std::panicking::default_hook::hf0c15a583008ed0d Dec 14 19:17:15.638 INFO [stdout] at src/libstd/panicking.rs:218 Dec 14 19:17:15.638 INFO [stdout] 4: 0x55a04e458460 - std::panicking::rust_panic_with_hook::hc1663f6bc7813185 Dec 14 19:17:15.638 INFO [stdout] at src/libstd/panicking.rs:487 Dec 14 19:17:15.638 INFO [stdout] 5: 0x55a04e406cc3 - std::panicking::begin_panic::h3566d558826b0474 Dec 14 19:17:15.638 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/panicking.rs:421 Dec 14 19:17:15.638 INFO [stdout] 6: 0x55a04e40874f - soft_test_failures::expect_failures::{{closure}}::h228e856e7694e3fe Dec 14 19:17:15.639 INFO [stdout] at src/lib.rs:31 Dec 14 19:17:15.639 INFO [stdout] 7: 0x55a04e409526 - >::try_with::hd195c03391f94cad Dec 14 19:17:15.639 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/thread/local.rs:309 Dec 14 19:17:15.639 INFO [stdout] 8: 0x55a04e408ac2 - >::with::hb398b5ed812c6b67 Dec 14 19:17:15.639 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/thread/local.rs:255 Dec 14 19:17:15.639 INFO [stdout] 9: 0x55a04e407d89 - soft_test_failures::expect_failures::hb37db4a5ad24b5cc Dec 14 19:17:15.639 INFO [stdout] at src/lib.rs:48 Dec 14 19:17:15.639 INFO [stdout] 10: 0x55a04e407c29 - soft_test_failures::expect_failures::{{closure}}::hfb0c50d6a53fd9fb Dec 14 19:17:15.639 INFO [stdout] at src/lib.rs:40 Dec 14 19:17:15.639 INFO [stdout] 11: 0x55a04e40506d - core::ops::function::FnOnce::call_once::h6f5304961f2e63ba Dec 14 19:17:15.639 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libcore/ops/function.rs:238 Dec 14 19:17:15.639 INFO [stdout] 12: 0x55a04e414ade - >::call_box::hac337ba1af72dc2b Dec 14 19:17:15.639 INFO [stdout] at src/libtest/lib.rs:1483 Dec 14 19:17:15.639 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libcore/ops/function.rs:238 Dec 14 19:17:15.639 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/liballoc/boxed.rs:673 Dec 14 19:17:15.639 INFO [stdout] 13: 0x55a04e466a69 - __rust_maybe_catch_panic Dec 14 19:17:15.639 INFO [stdout] at src/libpanic_unwind/lib.rs:102 Dec 14 19:17:15.639 INFO [stdout] 14: 0x55a04e4322e7 - test::run_test::run_test_inner::{{closure}}::hfbfe4a58c0ebb01b Dec 14 19:17:15.639 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/panicking.rs:285 Dec 14 19:17:15.639 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/panic.rs:398 Dec 14 19:17:15.639 INFO [stdout] at src/libtest/lib.rs:1438 Dec 14 19:17:15.639 INFO [stdout] 15: 0x55a04e40d1f4 - std::sys_common::backtrace::__rust_begin_short_backtrace::hd1f0cf202092f8d7 Dec 14 19:17:15.639 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/sys_common/backtrace.rs:136 Dec 14 19:17:15.639 INFO [stdout] 16: 0x55a04e40d7e4 - std::panicking::try::do_call::h5e99f92fbf448cf6 Dec 14 19:17:15.639 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/thread/mod.rs:479 Dec 14 19:17:15.639 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/panic.rs:319 Dec 14 19:17:15.639 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/panicking.rs:306 Dec 14 19:17:15.639 INFO [stdout] 17: 0x55a04e466a69 - __rust_maybe_catch_panic Dec 14 19:17:15.639 INFO [stdout] at src/libpanic_unwind/lib.rs:102 Dec 14 19:17:15.639 INFO [stdout] 18: 0x55a04e4149bc - >::call_box::h8ae063aff93ccdb6 Dec 14 19:17:15.639 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/panicking.rs:285 Dec 14 19:17:15.639 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/panic.rs:398 Dec 14 19:17:15.639 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/thread/mod.rs:478 Dec 14 19:17:15.639 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/liballoc/boxed.rs:673 Dec 14 19:17:15.639 INFO [stdout] 19: 0x55a04e45aa2d - std::sys::unix::thread::Thread::new::thread_start::h42c32b292fdd228f Dec 14 19:17:15.639 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/liballoc/boxed.rs:683 Dec 14 19:17:15.639 INFO [stdout] at src/libstd/sys_common/thread.rs:24 Dec 14 19:17:15.639 INFO [stdout] at src/libstd/sys/unix/thread.rs:91 Dec 14 19:17:15.639 INFO [stdout] 20: 0x7f2e638726b9 - start_thread Dec 14 19:17:15.639 INFO [stdout] 21: 0x7f2e6339241c - clone Dec 14 19:17:15.639 INFO [stdout] 22: 0x0 - Dec 14 19:17:15.639 INFO [stdout] Dec 14 19:17:15.639 INFO [stdout] Dec 14 19:17:15.639 INFO [stdout] failures: Dec 14 19:17:15.639 INFO [stdout] expect_failures Dec 14 19:17:15.639 INFO [stdout] Dec 14 19:17:15.639 INFO [stdout] test result: FAILED. 0 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out Dec 14 19:17:15.639 INFO [stdout] Dec 14 19:17:15.642 INFO [stderr] error: test failed, to rerun pass '--lib' Dec 14 19:17:15.643 INFO [stderr] su: No module specific data is present Dec 14 19:17:16.204 INFO running `"docker" "inspect" "4ea95023e801c1d68e7890f9c9cae6af0b5856b0759c0946291680cbf10be7da"` Dec 14 19:17:16.437 INFO running `"docker" "rm" "-f" "4ea95023e801c1d68e7890f9c9cae6af0b5856b0759c0946291680cbf10be7da"` Dec 14 19:17:16.676 INFO [stdout] 4ea95023e801c1d68e7890f9c9cae6af0b5856b0759c0946291680cbf10be7da