[INFO] crate soft-test-failures 0.1.0 is already in cache
[INFO] extracting crate soft-test-failures 0.1.0 into work/ex/pr-57967/sources/master#c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54/reg/soft-test-failures/0.1.0
[INFO] extracting crate soft-test-failures 0.1.0 into work/ex/pr-57967/sources/try#7fea0229842e79db1159ea58ad9a653b288efee9/reg/soft-test-failures/0.1.0
[INFO] validating manifest of soft-test-failures-0.1.0 on toolchain master#c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54
[INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54-alt" "read-manifest" "--manifest-path" "Cargo.toml"`
[INFO] validating manifest of soft-test-failures-0.1.0 on toolchain try#7fea0229842e79db1159ea58ad9a653b288efee9
[INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+7fea0229842e79db1159ea58ad9a653b288efee9-alt" "read-manifest" "--manifest-path" "Cargo.toml"`
[INFO] started frobbing soft-test-failures-0.1.0
[INFO] finished frobbing soft-test-failures-0.1.0
[INFO] frobbed toml for soft-test-failures-0.1.0 written to work/ex/pr-57967/sources/master#c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54/reg/soft-test-failures/0.1.0/Cargo.toml
[INFO] started frobbing soft-test-failures-0.1.0
[INFO] finished frobbing soft-test-failures-0.1.0
[INFO] frobbed toml for soft-test-failures-0.1.0 written to work/ex/pr-57967/sources/try#7fea0229842e79db1159ea58ad9a653b288efee9/reg/soft-test-failures/0.1.0/Cargo.toml
[INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54-alt" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update"`
[INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+7fea0229842e79db1159ea58ad9a653b288efee9-alt" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update"`
[INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54-alt" "fetch" "--locked" "--manifest-path" "Cargo.toml"`
[INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+7fea0229842e79db1159ea58ad9a653b288efee9-alt" "fetch" "--locked" "--manifest-path" "Cargo.toml"`
[INFO] testing soft-test-failures-0.1.0 against master#c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54 for pr-57967
[INFO] running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-57967/worker-2/master#c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54:/opt/crater/target:rw,Z" "-v" "/mnt/big/crater/work/ex/pr-57967/sources/master#c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54/reg/soft-test-failures/0.1.0:/opt/crater/workdir:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/opt/crater/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/opt/crater/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/opt/crater/workdir" "-e" "MAP_USER_ID=1000" "-e" "CARGO_TARGET_DIR=/opt/crater/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/crater/cargo-home" "-e" "RUSTUP_HOME=/opt/crater/rustup-home" "-w" "/opt/crater/workdir" "-m" "1536M" "--network" "none" "rustops/crates-build-env" "/opt/crater/cargo-home/bin/cargo" "+c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54-alt" "build" "--frozen"`
[INFO] [stdout] aa9bd230acc009c3c5b1a0ad8f3d8f6ca0f7226f4deba7657ce1f58515f42fe2
[INFO] running `"docker" "start" "-a" "aa9bd230acc009c3c5b1a0ad8f3d8f6ca0f7226f4deba7657ce1f58515f42fe2"`
[INFO] [stderr]    Compiling soft-test-failures v0.1.0 (/opt/crater/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.45s
[INFO] running `"docker" "inspect" "aa9bd230acc009c3c5b1a0ad8f3d8f6ca0f7226f4deba7657ce1f58515f42fe2"`
[INFO] running `"docker" "rm" "-f" "aa9bd230acc009c3c5b1a0ad8f3d8f6ca0f7226f4deba7657ce1f58515f42fe2"`
[INFO] [stdout] aa9bd230acc009c3c5b1a0ad8f3d8f6ca0f7226f4deba7657ce1f58515f42fe2
[INFO] running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-57967/worker-2/master#c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54:/opt/crater/target:rw,Z" "-v" "/mnt/big/crater/work/ex/pr-57967/sources/master#c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54/reg/soft-test-failures/0.1.0:/opt/crater/workdir:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/opt/crater/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/opt/crater/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/opt/crater/workdir" "-e" "MAP_USER_ID=1000" "-e" "CARGO_TARGET_DIR=/opt/crater/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/crater/cargo-home" "-e" "RUSTUP_HOME=/opt/crater/rustup-home" "-w" "/opt/crater/workdir" "-m" "1536M" "--network" "none" "rustops/crates-build-env" "/opt/crater/cargo-home/bin/cargo" "+c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54-alt" "test" "--frozen" "--no-run"`
[INFO] [stdout] a8813ada0020fc0ef27ce74783fb6aad426facd824f793d4ae2e9406a978941b
[INFO] running `"docker" "start" "-a" "a8813ada0020fc0ef27ce74783fb6aad426facd824f793d4ae2e9406a978941b"`
[INFO] [stderr]    Compiling soft-test-failures v0.1.0 (/opt/crater/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.92s
[INFO] running `"docker" "inspect" "a8813ada0020fc0ef27ce74783fb6aad426facd824f793d4ae2e9406a978941b"`
[INFO] running `"docker" "rm" "-f" "a8813ada0020fc0ef27ce74783fb6aad426facd824f793d4ae2e9406a978941b"`
[INFO] [stdout] a8813ada0020fc0ef27ce74783fb6aad426facd824f793d4ae2e9406a978941b
[INFO] running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-57967/worker-2/master#c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54:/opt/crater/target:rw,Z" "-v" "/mnt/big/crater/work/ex/pr-57967/sources/master#c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54/reg/soft-test-failures/0.1.0:/opt/crater/workdir:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/opt/crater/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/opt/crater/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/opt/crater/workdir" "-e" "MAP_USER_ID=1000" "-e" "CARGO_TARGET_DIR=/opt/crater/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/crater/cargo-home" "-e" "RUSTUP_HOME=/opt/crater/rustup-home" "-w" "/opt/crater/workdir" "-m" "1536M" "--network" "none" "rustops/crates-build-env" "/opt/crater/cargo-home/bin/cargo" "+c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54-alt" "test" "--frozen"`
[INFO] [stdout] 434812e6ad6c021f27f12858e81bf7433c0bb36daf1339e83b1274281a43ece5
[INFO] running `"docker" "start" "-a" "434812e6ad6c021f27f12858e81bf7433c0bb36daf1339e83b1274281a43ece5"`
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.04s
[INFO] [stderr]      Running /opt/crater/target/debug/deps/soft_test_failures-eefd7c100d5e6f96
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test expect_failures ... FAILED
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- expect_failures stdout ----
[INFO] [stdout] thread 'expect_failures' panicked at '`expect` test failed with 2 failed assertions:
[INFO] [stdout] 1: 4 surely is not 5
[INFO] [stdout] 2: Expected 3 - 7 == -3
[INFO] [stdout] ', src/lib.rs:48:5
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5605a9af6e73 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::hb587d2734f1dc7c5
[INFO] [stdout]                                at src/libstd/sys/unix/backtrace/tracing/gcc_s.rs:39
[INFO] [stdout]    1:     0x5605a9af2bfb - std::sys_common::backtrace::_print::he39717f7cfbd122d
[INFO] [stdout]                                at src/libstd/sys_common/backtrace.rs:70
[INFO] [stdout]    2:     0x5605a9af5b26 - std::panicking::default_hook::{{closure}}::hb7f1ae5149814e5e
[INFO] [stdout]                                at src/libstd/sys_common/backtrace.rs:58
[INFO] [stdout]                                at src/libstd/panicking.rs:200
[INFO] [stdout]    3:     0x5605a9af5827 - std::panicking::default_hook::hc2b73d8ffc772b35
[INFO] [stdout]                                at src/libstd/panicking.rs:209
[INFO] [stdout]    4:     0x5605a9af6230 - std::panicking::rust_panic_with_hook::h625072734a147d22
[INFO] [stdout]                                at src/libstd/panicking.rs:478
[INFO] [stdout]    5:     0x5605a9aa7793 - std::panicking::begin_panic::hb6d1d6267abd45d3
[INFO] [stdout]                                at /rustc/c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54/src/libstd/panicking.rs:412
[INFO] [stdout]    6:     0x5605a9aa90ce - soft_test_failures::expect_failures::{{closure}}::hb1ff96d6703abecf
[INFO] [stdout]                                at src/lib.rs:31
[INFO] [stdout]    7:     0x5605a9aa9c86 - <std::thread::local::LocalKey<T>>::try_with::hc32a033b66293bc0
[INFO] [stdout]                                at /rustc/c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54/src/libstd/thread/local.rs:296
[INFO] [stdout]    8:     0x5605a9aa9322 - <std::thread::local::LocalKey<T>>::with::h2e83bfc615c067b9
[INFO] [stdout]                                at /rustc/c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54/src/libstd/thread/local.rs:242
[INFO] [stdout]    9:     0x5605a9aa8769 - soft_test_failures::expect_failures::h3a56fd338889e06b
[INFO] [stdout]                                at src/lib.rs:48
[INFO] [stdout]   10:     0x5605a9aa8609 - soft_test_failures::expect_failures::{{closure}}::hcebd592020f1f1f0
[INFO] [stdout]                                at src/lib.rs:40
[INFO] [stdout]   11:     0x5605a9aac6ed - core::ops::function::FnOnce::call_once::h129a12824c1b0b81
[INFO] [stdout]                                at /rustc/c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54/src/libcore/ops/function.rs:231
[INFO] [stdout]   12:     0x5605a9ab56ce - <F as alloc::boxed::FnBox<A>>::call_box::h6b39ff82621fe3f5
[INFO] [stdout]                                at src/libtest/lib.rs:1474
[INFO] [stdout]                                at /rustc/c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54/src/libcore/ops/function.rs:231
[INFO] [stdout]                                at /rustc/c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54/src/liballoc/boxed.rs:734
[INFO] [stdout]   13:     0x5605a9af9399 - __rust_maybe_catch_panic
[INFO] [stdout]                                at src/libpanic_unwind/lib.rs:92
[INFO] [stdout]   14:     0x5605a9ad2957 - test::run_test::run_test_inner::{{closure}}::h57942e399d1cc807
[INFO] [stdout]                                at /rustc/c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54/src/libstd/panicking.rs:276
[INFO] [stdout]                                at /rustc/c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54/src/libstd/panic.rs:388
[INFO] [stdout]                                at src/libtest/lib.rs:1429
[INFO] [stdout]   15:     0x5605a9aae114 - std::sys_common::backtrace::__rust_begin_short_backtrace::h85c77ad9ddf8fba3
[INFO] [stdout]                                at /rustc/c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54/src/libstd/sys_common/backtrace.rs:135
[INFO] [stdout]   16:     0x5605a9aae7e4 - std::panicking::try::do_call::hf5b2072d080453d1
[INFO] [stdout]                                at /rustc/c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54/src/libstd/thread/mod.rs:469
[INFO] [stdout]                                at /rustc/c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54/src/libstd/panic.rs:309
[INFO] [stdout]                                at /rustc/c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54/src/libstd/panicking.rs:297
[INFO] [stdout]   17:     0x5605a9af9399 - __rust_maybe_catch_panic
[INFO] [stdout]                                at src/libpanic_unwind/lib.rs:92
[INFO] [stdout]   18:     0x5605a9ab594c - <F as alloc::boxed::FnBox<A>>::call_box::hf0a5ea81172c4bf3
[INFO] [stdout]                                at /rustc/c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54/src/libstd/panicking.rs:276
[INFO] [stdout]                                at /rustc/c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54/src/libstd/panic.rs:388
[INFO] [stdout]                                at /rustc/c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54/src/libstd/thread/mod.rs:468
[INFO] [stdout]                                at /rustc/c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54/src/liballoc/boxed.rs:734
[INFO] [stdout]   19:     0x5605a9af8b4d - std::sys::unix::thread::Thread::new::thread_start::h60ff5f253b4125d5
[INFO] [stdout]                                at /rustc/c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54/src/liballoc/boxed.rs:744
[INFO] [stdout]                                at src/libstd/sys_common/thread.rs:14
[INFO] [stdout]                                at src/libstd/sys/unix/thread.rs:81
[INFO] [stdout]   20:     0x7fe2fd56f493 - start_thread
[INFO] [stdout]   21:     0x7fe2fd09aace - __clone
[INFO] [stdout]   22:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     expect_failures
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 0 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass '--lib'
[INFO] running `"docker" "inspect" "434812e6ad6c021f27f12858e81bf7433c0bb36daf1339e83b1274281a43ece5"`
[INFO] running `"docker" "rm" "-f" "434812e6ad6c021f27f12858e81bf7433c0bb36daf1339e83b1274281a43ece5"`
[INFO] [stdout] 434812e6ad6c021f27f12858e81bf7433c0bb36daf1339e83b1274281a43ece5