Dec 06 22:20:02.874 INFO testing soft-test-failures-0.1.0 against try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468 for pr-56514
Dec 06 22:20:02.874 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-1/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/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 22:20:03.096 INFO [stdout] 486beb6d4871218c5a2b24eace4bbe02a6eeec4dcad98bfc288ae457c935e4e8
Dec 06 22:20:03.101 INFO running `"docker" "start" "-a" "486beb6d4871218c5a2b24eace4bbe02a6eeec4dcad98bfc288ae457c935e4e8"`
Dec 06 22:20:03.532 INFO [stderr] usermod: no changes
Dec 06 22:20:03.561 INFO [stderr]    Compiling soft-test-failures v0.1.0 (/source)
Dec 06 22:20:04.018 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.46s
Dec 06 22:20:04.020 INFO [stderr] su: No module specific data is present
Dec 06 22:20:04.363 INFO running `"docker" "inspect" "486beb6d4871218c5a2b24eace4bbe02a6eeec4dcad98bfc288ae457c935e4e8"`
Dec 06 22:20:04.506 INFO running `"docker" "rm" "-f" "486beb6d4871218c5a2b24eace4bbe02a6eeec4dcad98bfc288ae457c935e4e8"`
Dec 06 22:20:04.776 INFO [stdout] 486beb6d4871218c5a2b24eace4bbe02a6eeec4dcad98bfc288ae457c935e4e8
Dec 06 22:20:04.782 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-1/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/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 22:20:05.088 INFO [stdout] a09782e0a577bdf9f36349e530f90cec9d5b8841c7ccd215dad0e0f04b5c65f2
Dec 06 22:20:05.090 INFO running `"docker" "start" "-a" "a09782e0a577bdf9f36349e530f90cec9d5b8841c7ccd215dad0e0f04b5c65f2"`
Dec 06 22:20:06.017 INFO [stderr] usermod: no changes
Dec 06 22:20:06.080 INFO [stderr]    Compiling soft-test-failures v0.1.0 (/source)
Dec 06 22:20:07.252 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1.21s
Dec 06 22:20:07.254 INFO [stderr] su: No module specific data is present
Dec 06 22:20:07.502 INFO running `"docker" "inspect" "a09782e0a577bdf9f36349e530f90cec9d5b8841c7ccd215dad0e0f04b5c65f2"`
Dec 06 22:20:07.577 INFO running `"docker" "rm" "-f" "a09782e0a577bdf9f36349e530f90cec9d5b8841c7ccd215dad0e0f04b5c65f2"`
Dec 06 22:20:07.671 INFO [stdout] a09782e0a577bdf9f36349e530f90cec9d5b8841c7ccd215dad0e0f04b5c65f2
Dec 06 22:20:07.673 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-1/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/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 22:20:08.133 INFO [stdout] 3b77de042daf82b9cafb23ba917708ddb761ce0583051b2de17de445ff886f33
Dec 06 22:20:08.135 INFO running `"docker" "start" "-a" "3b77de042daf82b9cafb23ba917708ddb761ce0583051b2de17de445ff886f33"`
Dec 06 22:20:08.453 INFO [stderr] usermod: no changes
Dec 06 22:20:08.480 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.01s
Dec 06 22:20:08.480 INFO [stderr]      Running /target/debug/deps/soft_test_failures-37b2b349444033cf
Dec 06 22:20:08.482 INFO [stdout] 
Dec 06 22:20:08.482 INFO [stdout] running 1 test
Dec 06 22:20:08.517 INFO [stdout] test expect_failures ... FAILED
Dec 06 22:20:08.517 INFO [stdout] 
Dec 06 22:20:08.517 INFO [stdout] failures:
Dec 06 22:20:08.517 INFO [stdout] 
Dec 06 22:20:08.517 INFO [stdout] ---- expect_failures stdout ----
Dec 06 22:20:08.517 INFO [stdout] thread 'expect_failures' panicked at '`expect` test failed with 2 failed assertions:
Dec 06 22:20:08.517 INFO [stdout] 1: 4 surely is not 5
Dec 06 22:20:08.517 INFO [stdout] 2: Expected 3 - 7 == -3
Dec 06 22:20:08.517 INFO [stdout] ', src/lib.rs:48:5
Dec 06 22:20:08.517 INFO [stdout] stack backtrace:
Dec 06 22:20:08.517 INFO [stdout]    0:     0x55dee5d5bcf3 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h82c998a0e635a5c4
Dec 06 22:20:08.517 INFO [stdout]                                at src/libstd/sys/unix/backtrace/tracing/gcc_s.rs:49
Dec 06 22:20:08.517 INFO [stdout]    1:     0x55dee5d56418 - std::sys_common::backtrace::_print::h5fbc0bc3cf12d9bf
Dec 06 22:20:08.517 INFO [stdout]                                at src/libstd/sys_common/backtrace.rs:71
Dec 06 22:20:08.517 INFO [stdout]    2:     0x55dee5d5ac62 - std::panicking::default_hook::{{closure}}::h42b81c3dd1a50e32
Dec 06 22:20:08.518 INFO [stdout]                                at src/libstd/sys_common/backtrace.rs:59
Dec 06 22:20:08.518 INFO [stdout]                                at src/libstd/panicking.rs:211
Dec 06 22:20:08.518 INFO [stdout]    3:     0x55dee5d5a94c - std::panicking::default_hook::h6c8d8e1c12a669e5
Dec 06 22:20:08.518 INFO [stdout]                                at src/libstd/panicking.rs:221
Dec 06 22:20:08.518 INFO [stdout]    4:     0x55dee5d5b370 - std::panicking::rust_panic_with_hook::hce93a56e38c2b0aa
Dec 06 22:20:08.518 INFO [stdout]                                at src/libstd/panicking.rs:491
Dec 06 22:20:08.518 INFO [stdout]    5:     0x55dee5d09b73 - std::panicking::begin_panic::h31432ef26e804b4b
Dec 06 22:20:08.518 INFO [stdout]                                at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panicking.rs:425
Dec 06 22:20:08.518 INFO [stdout]    6:     0x55dee5d0b5ff - soft_test_failures::expect_failures::{{closure}}::h6f1b0b656f0f0c9c
Dec 06 22:20:08.518 INFO [stdout]                                at src/lib.rs:31
Dec 06 22:20:08.518 INFO [stdout]    7:     0x55dee5d0bb36 - <std::thread::local::LocalKey<T>>::try_with::h1d9131745d3acc39
Dec 06 22:20:08.518 INFO [stdout]                                at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/thread/local.rs:309
Dec 06 22:20:08.518 INFO [stdout]    8:     0x55dee5d0b8b2 - <std::thread::local::LocalKey<T>>::with::h335034ce35732f3d
Dec 06 22:20:08.518 INFO [stdout]                                at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/thread/local.rs:255
Dec 06 22:20:08.518 INFO [stdout]    9:     0x55dee5d0ac39 - soft_test_failures::expect_failures::h6391ac872c203ef6
Dec 06 22:20:08.518 INFO [stdout]                                at src/lib.rs:48
Dec 06 22:20:08.518 INFO [stdout]   10:     0x55dee5d0aad9 - soft_test_failures::expect_failures::{{closure}}::h977c101635f3b67e
Dec 06 22:20:08.518 INFO [stdout]                                at src/lib.rs:40
Dec 06 22:20:08.518 INFO [stdout]   11:     0x55dee5d07f1d - core::ops::function::FnOnce::call_once::hea80a1d03e46563c
Dec 06 22:20:08.518 INFO [stdout]                                at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libcore/ops/function.rs:238
Dec 06 22:20:08.518 INFO [stdout]   12:     0x55dee5d182ee - <F as alloc::boxed::FnBox<A>>::call_box::h9ed1658cdce7f33f
Dec 06 22:20:08.518 INFO [stdout]                                at src/libtest/lib.rs:1471
Dec 06 22:20:08.518 INFO [stdout]                                at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libcore/ops/function.rs:238
Dec 06 22:20:08.518 INFO [stdout]                                at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/liballoc/boxed.rs:673
Dec 06 22:20:08.518 INFO [stdout]   13:     0x55dee5d69979 - __rust_maybe_catch_panic
Dec 06 22:20:08.518 INFO [stdout]                                at src/libpanic_unwind/lib.rs:102
Dec 06 22:20:08.518 INFO [stdout]   14:     0x55dee5d101c3 - std::sys_common::backtrace::__rust_begin_short_backtrace::h018d03d6681412f5
Dec 06 22:20:08.518 INFO [stdout]                                at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panicking.rs:289
Dec 06 22:20:08.518 INFO [stdout]                                at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panic.rs:398
Dec 06 22:20:08.518 INFO [stdout]                                at src/libtest/lib.rs:1426
Dec 06 22:20:08.518 INFO [stdout]                                at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/sys_common/backtrace.rs:136
Dec 06 22:20:08.518 INFO [stdout]   15:     0x55dee5d11154 - std::panicking::try::do_call::hf8ab3f21dcfaa7a6
Dec 06 22:20:08.518 INFO [stdout]                                at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/thread/mod.rs:477
Dec 06 22:20:08.518 INFO [stdout]                                at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panic.rs:319
Dec 06 22:20:08.518 INFO [stdout]                                at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panicking.rs:310
Dec 06 22:20:08.518 INFO [stdout]   16:     0x55dee5d69979 - __rust_maybe_catch_panic
Dec 06 22:20:08.518 INFO [stdout]                                at src/libpanic_unwind/lib.rs:102
Dec 06 22:20:08.518 INFO [stdout]   17:     0x55dee5d181cc - <F as alloc::boxed::FnBox<A>>::call_box::h6465cc998309e287
Dec 06 22:20:08.518 INFO [stdout]                                at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panicking.rs:289
Dec 06 22:20:08.518 INFO [stdout]                                at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panic.rs:398
Dec 06 22:20:08.518 INFO [stdout]                                at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/thread/mod.rs:476
Dec 06 22:20:08.518 INFO [stdout]                                at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/liballoc/boxed.rs:673
Dec 06 22:20:08.518 INFO [stdout]   18:     0x55dee5d5d93d - std::sys::unix::thread::Thread::new::thread_start::h0fe9c527147fd5f5
Dec 06 22:20:08.518 INFO [stdout]                                at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/liballoc/boxed.rs:683
Dec 06 22:20:08.518 INFO [stdout]                                at src/libstd/sys_common/thread.rs:24
Dec 06 22:20:08.518 INFO [stdout]                                at src/libstd/sys/unix/thread.rs:90
Dec 06 22:20:08.518 INFO [stdout]   19:     0x7f6aeb89f6b9 - start_thread
Dec 06 22:20:08.518 INFO [stdout]   20:     0x7f6aeb3bf41c - clone
Dec 06 22:20:08.518 INFO [stdout]   21:                0x0 - <unknown>
Dec 06 22:20:08.518 INFO [stdout] 
Dec 06 22:20:08.518 INFO [stdout] 
Dec 06 22:20:08.518 INFO [stdout] failures:
Dec 06 22:20:08.518 INFO [stdout]     expect_failures
Dec 06 22:20:08.518 INFO [stdout] 
Dec 06 22:20:08.518 INFO [stdout] test result: FAILED. 0 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out
Dec 06 22:20:08.518 INFO [stdout] 
Dec 06 22:20:08.518 INFO [stderr] error: test failed, to rerun pass '--lib'
Dec 06 22:20:08.520 INFO [stderr] su: No module specific data is present
Dec 06 22:20:08.772 INFO running `"docker" "inspect" "3b77de042daf82b9cafb23ba917708ddb761ce0583051b2de17de445ff886f33"`
Dec 06 22:20:08.853 INFO running `"docker" "rm" "-f" "3b77de042daf82b9cafb23ba917708ddb761ce0583051b2de17de445ff886f33"`
Dec 06 22:20:08.919 INFO [stdout] 3b77de042daf82b9cafb23ba917708ddb761ce0583051b2de17de445ff886f33