Dec 14 18:50:00.631 INFO testing spin-0.4.10 against try#aa49d8ef14939ddec0e34b346b60174a5673d48f for pr-56550 Dec 14 18:50:00.631 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 18:50:00.999 INFO [stdout] 9e269d0faeb2acc60aae88924b6b365ecc71ea27f471a8e3eeaf8706b7c0a11b Dec 14 18:50:01.003 INFO running `"docker" "start" "-a" "9e269d0faeb2acc60aae88924b6b365ecc71ea27f471a8e3eeaf8706b7c0a11b"` Dec 14 18:50:02.226 INFO [stderr] usermod: no changes Dec 14 18:50:02.267 INFO [stderr] Compiling spin v0.4.10 (/source) Dec 14 18:50:02.627 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 0.37s Dec 14 18:50:02.630 INFO [stderr] su: No module specific data is present Dec 14 18:50:03.467 INFO running `"docker" "inspect" "9e269d0faeb2acc60aae88924b6b365ecc71ea27f471a8e3eeaf8706b7c0a11b"` Dec 14 18:50:03.807 INFO running `"docker" "rm" "-f" "9e269d0faeb2acc60aae88924b6b365ecc71ea27f471a8e3eeaf8706b7c0a11b"` Dec 14 18:50:04.085 INFO [stdout] 9e269d0faeb2acc60aae88924b6b365ecc71ea27f471a8e3eeaf8706b7c0a11b Dec 14 18:50:04.088 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 18:50:04.456 INFO [stdout] 99d90d2941b76c330c87b46f96396f6a45a7d26a0c17994da999662b18a999d7 Dec 14 18:50:04.460 INFO running `"docker" "start" "-a" "99d90d2941b76c330c87b46f96396f6a45a7d26a0c17994da999662b18a999d7"` Dec 14 18:50:05.758 INFO [stderr] usermod: no changes Dec 14 18:50:05.841 INFO [stderr] Compiling spin v0.4.10 (/source) Dec 14 18:50:08.683 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 2.89s Dec 14 18:50:08.686 INFO [stderr] su: No module specific data is present Dec 14 18:50:09.300 INFO running `"docker" "inspect" "99d90d2941b76c330c87b46f96396f6a45a7d26a0c17994da999662b18a999d7"` Dec 14 18:50:09.517 INFO running `"docker" "rm" "-f" "99d90d2941b76c330c87b46f96396f6a45a7d26a0c17994da999662b18a999d7"` Dec 14 18:50:09.747 INFO [stdout] 99d90d2941b76c330c87b46f96396f6a45a7d26a0c17994da999662b18a999d7 Dec 14 18:50:09.755 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 18:50:10.120 INFO [stdout] f788b937e6cca12044df22c97910fa077446984595931442aadeffd85bf9d03f Dec 14 18:50:10.122 INFO running `"docker" "start" "-a" "f788b937e6cca12044df22c97910fa077446984595931442aadeffd85bf9d03f"` Dec 14 18:50:11.308 INFO [stderr] usermod: no changes Dec 14 18:50:11.383 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 0.03s Dec 14 18:50:11.395 INFO [stderr] Running /target/debug/deps/spin-3933b85d72322c03 Dec 14 18:50:11.395 INFO [stdout] Dec 14 18:50:11.395 INFO [stdout] running 26 tests Dec 14 18:50:11.395 INFO [stdout] test mutex::tests::test_mutex_unsized ... ok Dec 14 18:50:11.395 INFO [stdout] test mutex::tests::test_mutex_force_lock ... ok Dec 14 18:50:11.395 INFO [stdout] test mutex::tests::test_into_inner_drop ... ok Dec 14 18:50:11.395 INFO [stdout] test mutex::tests::test_into_inner ... ok Dec 14 18:50:11.395 INFO [stdout] test mutex::tests::smoke ... ok Dec 14 18:50:11.395 INFO [stdout] test mutex::tests::try_lock ... ok Dec 14 18:50:11.395 INFO [stdout] test once::tests::init_constant ... ok Dec 14 18:50:11.411 INFO [stdout] test once::tests::smoke_once ... ok Dec 14 18:50:11.411 INFO [stderr] thread '' panicked at 'explicit panic', src/mutex.rs:386:13 Dec 14 18:50:11.411 INFO [stdout] test once::tests::smoke_once_value ... ok Dec 14 18:50:11.411 INFO [stdout] test once::tests::try ... ok Dec 14 18:50:11.411 INFO [stdout] test rw_lock::tests::smoke ... ok Dec 14 18:50:11.419 INFO [stdout] test mutex::tests::test_mutex_arc_nested ... ok Dec 14 18:50:11.419 INFO [stdout] test once::tests::wait ... ok Dec 14 18:50:11.419 INFO [stdout] test once::tests::try_no_wait ... ok Dec 14 18:50:11.419 INFO [stdout] test rw_lock::tests::test_into_inner_drop ... ok Dec 14 18:50:11.431 INFO [stdout] test rw_lock::tests::test_force_write_unlock ... ok Dec 14 18:50:11.431 INFO [stderr] thread '' panicked at 'explicit panic', src/rw_lock.rs:493:13 Dec 14 18:50:11.431 INFO [stdout] test rw_lock::tests::test_into_inner ... ok Dec 14 18:50:11.431 INFO [stdout] test rw_lock::tests::test_force_read_decrement ... ok Dec 14 18:50:11.431 INFO [stdout] test rw_lock::tests::test_rwlock_unsized ... ok Dec 14 18:50:11.431 INFO [stdout] test rw_lock::tests::test_rwlock_try_write ... ok Dec 14 18:50:11.431 INFO [stdout] test once::tests::stampede_once ... ok Dec 14 18:50:11.431 INFO [stdout] test mutex::tests::lots_and_lots ... ok Dec 14 18:50:11.510 INFO [stdout] test rw_lock::tests::test_rw_arc ... ok Dec 14 18:50:11.572 INFO [stdout] test mutex::tests::test_mutex_arc_access_in_unwind ... ok Dec 14 18:50:11.573 INFO [stderr] stack backtrace: Dec 14 18:50:11.573 INFO [stderr] 0: 0x55b9218e0ba3 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h2c40bdfb30f25bdd Dec 14 18:50:11.573 INFO [stderr] at src/libstd/sys/unix/backtrace/tracing/gcc_s.rs:49 Dec 14 18:50:11.573 INFO [stderr] 1: 0x55b9218db3a8 - std::sys_common::backtrace::_print::he11ceebc70626ac9 Dec 14 18:50:11.573 INFO [stderr] at src/libstd/sys_common/backtrace.rs:71 Dec 14 18:50:11.573 INFO [stderr] 2: 0x55b9218dfb12 - std::panicking::default_hook::{{closure}}::h4fde3ffb19c01413 Dec 14 18:50:11.573 INFO [stderr] at src/libstd/sys_common/backtrace.rs:59 Dec 14 18:50:11.573 INFO [stderr] at src/libstd/panicking.rs:210 Dec 14 18:50:11.573 INFO [stderr] 3: 0x55b9218df884 - std::panicking::default_hook::hf0c15a583008ed0d Dec 14 18:50:11.573 INFO [stderr] at src/libstd/panicking.rs:224 Dec 14 18:50:11.573 INFO [stderr] 4: 0x55b9218e0220 - std::panicking::rust_panic_with_hook::hc1663f6bc7813185 Dec 14 18:50:11.573 INFO [stderr] at src/libstd/panicking.rs:487 Dec 14 18:50:11.573 INFO [stderr] 5: 0x55b92186a3a3 - std::panicking::begin_panic::h1dadef976be43020 Dec 14 18:50:11.573 INFO [stderr] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/panicking.rs:421 Dec 14 18:50:11.573 INFO [stderr] 6: 0x55b921869e3a - spin::mutex::tests::test_mutex_arc_access_in_unwind::{{closure}}::he3acb70c8b145416 Dec 14 18:50:11.573 INFO [stderr] at src/mutex.rs:386 Dec 14 18:50:11.573 INFO [stderr] 7: 0x55b92186e892 - std::sys_common::backtrace::__rust_begin_short_backtrace::hebe302f8e4eb35c7 Dec 14 18:50:11.573 INFO [stderr] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/sys_common/backtrace.rs:136 Dec 14 18:50:11.573 INFO [stderr] 8: 0x55b921884bfd - std::thread::Builder::spawn_unchecked::{{closure}}::{{closure}}::hb7378188982ef050 Dec 14 18:50:11.573 INFO [stderr] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/thread/mod.rs:479 Dec 14 18:50:11.573 INFO [stderr] 9: 0x55b9218638d0 - as core::ops::function::FnOnce<()>>::call_once::h8a09c583e5462603 Dec 14 18:50:11.573 INFO [stderr] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/panic.rs:319 Dec 14 18:50:11.573 INFO [stderr] 10: 0x55b92186aef4 - std::panicking::try::do_call::h4176ddc5f3944d62 Dec 14 18:50:11.573 INFO [stderr] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/panicking.rs:306 Dec 14 18:50:11.573 INFO [stderr] 11: 0x55b9218ee889 - __rust_maybe_catch_panic Dec 14 18:50:11.573 INFO [stderr] at src/libpanic_unwind/lib.rs:102 Dec 14 18:50:11.573 INFO [stderr] 12: 0x55b92186ac3d - std::panicking::try::hb838f3b6ec70ee74 Dec 14 18:50:11.573 INFO [stderr] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/panicking.rs:285 Dec 14 18:50:11.573 INFO [stderr] 13: 0x55b921863c82 - std::panic::catch_unwind::he13696ce3e99be08 Dec 14 18:50:11.573 INFO [stderr] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/panic.rs:398 Dec 14 18:50:11.573 INFO [stderr] 14: 0x55b921883a55 - std::thread::Builder::spawn_unchecked::{{closure}}::h9ad729ecfa7ecbd5 Dec 14 18:50:11.573 INFO [stderr] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/thread/mod.rs:478 Dec 14 18:50:11.573 INFO [stderr] 15: 0x55b921885486 - >::call_box::hcfd3ab00e4183d8f Dec 14 18:50:11.573 INFO [stderr] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/liballoc/boxed.rs:673 Dec 14 18:50:11.573 INFO [stderr] 16: 0x55b9218e27ad - std::sys::unix::thread::Thread::new::thread_start::h42c32b292fdd228f Dec 14 18:50:11.573 INFO [stderr] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/liballoc/boxed.rs:683 Dec 14 18:50:11.573 INFO [stderr] at src/libstd/sys_common/thread.rs:24 Dec 14 18:50:11.573 INFO [stderr] at src/libstd/sys/unix/thread.rs:91 Dec 14 18:50:11.573 INFO [stderr] 17: 0x7f6c07e556b9 - start_thread Dec 14 18:50:11.573 INFO [stderr] 18: 0x7f6c0797541c - clone Dec 14 18:50:11.573 INFO [stderr] 19: 0x0 - Dec 14 18:50:11.574 INFO [stderr] stack backtrace: Dec 14 18:50:11.574 INFO [stdout] test rw_lock::tests::test_rw_arc_access_in_unwind ... ok Dec 14 18:50:11.574 INFO [stderr] 0: 0x55b9218e0ba3 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h2c40bdfb30f25bdd Dec 14 18:50:11.574 INFO [stderr] at src/libstd/sys/unix/backtrace/tracing/gcc_s.rs:49 Dec 14 18:50:11.574 INFO [stderr] 1: 0x55b9218db3a8 - std::sys_common::backtrace::_print::he11ceebc70626ac9 Dec 14 18:50:11.574 INFO [stderr] at src/libstd/sys_common/backtrace.rs:71 Dec 14 18:50:11.574 INFO [stderr] 2: 0x55b9218dfb12 - std::panicking::default_hook::{{closure}}::h4fde3ffb19c01413 Dec 14 18:50:11.574 INFO [stderr] at src/libstd/sys_common/backtrace.rs:59 Dec 14 18:50:11.574 INFO [stderr] at src/libstd/panicking.rs:210 Dec 14 18:50:11.574 INFO [stderr] 3: 0x55b9218df884 - std::panicking::default_hook::hf0c15a583008ed0d Dec 14 18:50:11.574 INFO [stderr] at src/libstd/panicking.rs:224 Dec 14 18:50:11.574 INFO [stderr] 4: 0x55b9218e0220 - std::panicking::rust_panic_with_hook::hc1663f6bc7813185 Dec 14 18:50:11.574 INFO [stderr] at src/libstd/panicking.rs:487 Dec 14 18:50:11.574 INFO [stderr] 5: 0x55b92186a3a3 - std::panicking::begin_panic::h1dadef976be43020 Dec 14 18:50:11.574 INFO [stderr] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/panicking.rs:421 Dec 14 18:50:11.574 INFO [stderr] 6: 0x55b92187445a - spin::rw_lock::tests::test_rw_arc_access_in_unwind::{{closure}}::h9db2c170d8c4c6f7 Dec 14 18:50:11.574 INFO [stderr] at src/rw_lock.rs:493 Dec 14 18:50:11.574 INFO [stderr] 7: 0x55b92186e802 - std::sys_common::backtrace::__rust_begin_short_backtrace::h7638bd66de0b0169 Dec 14 18:50:11.574 INFO [stderr] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/sys_common/backtrace.rs:136 Dec 14 18:50:11.574 INFO [stderr] 8: 0x55b921884b8d - std::thread::Builder::spawn_unchecked::{{closure}}::{{closure}}::h814d9e361ea8432a Dec 14 18:50:11.574 INFO [stderr] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/thread/mod.rs:479 Dec 14 18:50:11.574 INFO [stderr] 9: 0x55b921863990 - as core::ops::function::FnOnce<()>>::call_once::he3d36b0b3cd46cf5 Dec 14 18:50:11.574 INFO [stderr] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/panic.rs:319 Dec 14 18:50:11.574 INFO [stderr] 10: 0x55b92186b004 - std::panicking::try::do_call::hb36f3c344a7bb31e Dec 14 18:50:11.574 INFO [stderr] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/panicking.rs:306 Dec 14 18:50:11.574 INFO [stderr] 11: 0x55b9218ee889 - __rust_maybe_catch_panic Dec 14 18:50:11.574 INFO [stderr] at src/libpanic_unwind/lib.rs:102 Dec 14 18:50:11.574 INFO [stderr] 12: 0x55b92186a7ad - std::panicking::try::h75f1fec326d09ab4 Dec 14 18:50:11.574 INFO [stderr] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/panicking.rs:285 Dec 14 18:50:11.574 INFO [stderr] 13: 0x55b921863bd2 - std::panic::catch_unwind::h53ec8f15aed527d9 Dec 14 18:50:11.574 INFO [stderr] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/panic.rs:398 Dec 14 18:50:11.574 INFO [stderr] 14: 0x55b921883475 - std::thread::Builder::spawn_unchecked::{{closure}}::h42b06d1f1db238c3 Dec 14 18:50:11.574 INFO [stderr] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/thread/mod.rs:478 Dec 14 18:50:11.574 INFO [stderr] 15: 0x55b921885286 - >::call_box::h2fd3daef511e85e3 Dec 14 18:50:11.574 INFO [stderr] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/liballoc/boxed.rs:673 Dec 14 18:50:11.574 INFO [stderr] 16: 0x55b9218e27ad - std::sys::unix::thread::Thread::new::thread_start::h42c32b292fdd228f Dec 14 18:50:11.574 INFO [stderr] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/liballoc/boxed.rs:683 Dec 14 18:50:11.574 INFO [stderr] at src/libstd/sys_common/thread.rs:24 Dec 14 18:50:11.574 INFO [stderr] at src/libstd/sys/unix/thread.rs:91 Dec 14 18:50:11.574 INFO [stderr] 17: 0x7f6c07e556b9 - start_thread Dec 14 18:50:11.574 INFO [stderr] 18: 0x7f6c0797541c - clone Dec 14 18:50:11.574 INFO [stderr] 19: 0x0 - Dec 14 18:50:11.585 INFO [stdout] test once::tests::panic ... ok Dec 14 18:50:11.585 INFO [stdout] Dec 14 18:50:11.585 INFO [stdout] test result: ok. 26 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 14 18:50:11.586 INFO [stdout] Dec 14 18:50:11.586 INFO [stderr] Doc-tests spin Dec 14 18:50:11.922 INFO [stdout] Dec 14 18:50:11.922 INFO [stdout] running 12 tests Dec 14 18:50:15.367 INFO [stdout] test src/once.rs - once::Once (line 12) ... ok Dec 14 18:50:16.831 INFO [stdout] test src/once.rs - once::Once::call_once (line 90) ... ok Dec 14 18:50:17.036 INFO [stdout] test src/mutex.rs - mutex::Mutex::new (line 98) ... ok Dec 14 18:50:17.624 INFO [stdout] test src/rw_lock.rs - rw_lock::RwLock::new (line 84) ... ok Dec 14 18:50:18.571 INFO [stdout] test src/mutex.rs - mutex::Mutex::lock (line 172) ... ok Dec 14 18:50:19.259 INFO [stdout] test src/rw_lock.rs - rw_lock::RwLock (line 25) ... ok Dec 14 18:50:19.659 INFO [stdout] test src/mutex.rs - mutex::Mutex (line 20) ... ok Dec 14 18:50:20.707 INFO [stdout] test src/mutex.rs - mutex::Mutex (line 42) ... ok Dec 14 18:50:22.251 INFO [stdout] test src/rw_lock.rs - rw_lock::RwLock::read (line 156) ... ok Dec 14 18:50:23.132 INFO [stdout] test src/rw_lock.rs - rw_lock::RwLock::write (line 271) ... ok Dec 14 18:50:23.139 INFO [stdout] test src/rw_lock.rs - rw_lock::RwLock::try_read (line 207) ... ok Dec 14 18:50:23.193 INFO [stdout] test src/rw_lock.rs - rw_lock::RwLock::try_write (line 312) ... ok Dec 14 18:50:23.193 INFO [stdout] Dec 14 18:50:23.193 INFO [stdout] test result: ok. 12 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 14 18:50:23.193 INFO [stdout] Dec 14 18:50:23.218 INFO [stderr] su: No module specific data is present Dec 14 18:50:24.563 INFO running `"docker" "inspect" "f788b937e6cca12044df22c97910fa077446984595931442aadeffd85bf9d03f"` Dec 14 18:50:24.813 INFO running `"docker" "rm" "-f" "f788b937e6cca12044df22c97910fa077446984595931442aadeffd85bf9d03f"` Dec 14 18:50:25.075 INFO [stdout] f788b937e6cca12044df22c97910fa077446984595931442aadeffd85bf9d03f