Nov 17 22:06:13.462 INFO testing spin-0.4.9 against master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c for pr-55982 Nov 17 22:06:13.464 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-1/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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"` Nov 17 22:06:13.963 INFO [stdout] 3425f8d66ee7e4171d4ce0eb2645795eba4a6113cbd238317accbf419f0a579a Nov 17 22:06:13.971 INFO running `"docker" "start" "-a" "3425f8d66ee7e4171d4ce0eb2645795eba4a6113cbd238317accbf419f0a579a"` Nov 17 22:06:15.235 INFO [stderr] usermod: no changes Nov 17 22:06:15.318 INFO [stderr] Compiling spin v0.4.9 (/source) Nov 17 22:06:15.619 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 0.35s Nov 17 22:06:15.625 INFO [stderr] su: No module specific data is present Nov 17 22:06:16.451 INFO running `"docker" "inspect" "3425f8d66ee7e4171d4ce0eb2645795eba4a6113cbd238317accbf419f0a579a"` Nov 17 22:06:16.672 INFO running `"docker" "rm" "-f" "3425f8d66ee7e4171d4ce0eb2645795eba4a6113cbd238317accbf419f0a579a"` Nov 17 22:06:16.931 INFO [stdout] 3425f8d66ee7e4171d4ce0eb2645795eba4a6113cbd238317accbf419f0a579a Nov 17 22:06:16.939 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-1/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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"` Nov 17 22:06:17.334 INFO [stdout] 0dc12a993f542d4e660ee1d7ca04acdbe5f379800afcf0c75e19d69ae4f94577 Nov 17 22:06:17.335 INFO running `"docker" "start" "-a" "0dc12a993f542d4e660ee1d7ca04acdbe5f379800afcf0c75e19d69ae4f94577"` Nov 17 22:06:19.230 INFO [stderr] usermod: no changes Nov 17 22:06:19.351 INFO [stderr] Compiling spin v0.4.9 (/source) Nov 17 22:06:22.014 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 2.74s Nov 17 22:06:22.026 INFO [stderr] su: No module specific data is present Nov 17 22:06:22.863 INFO running `"docker" "inspect" "0dc12a993f542d4e660ee1d7ca04acdbe5f379800afcf0c75e19d69ae4f94577"` Nov 17 22:06:23.119 INFO running `"docker" "rm" "-f" "0dc12a993f542d4e660ee1d7ca04acdbe5f379800afcf0c75e19d69ae4f94577"` Nov 17 22:06:23.423 INFO [stdout] 0dc12a993f542d4e660ee1d7ca04acdbe5f379800afcf0c75e19d69ae4f94577 Nov 17 22:06:23.425 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-1/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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"` Nov 17 22:06:23.986 INFO [stdout] 28fd71b252ffa9feaab242c37ee9ab6edb5b6213c807ebb4eea7927fe4bce1d5 Nov 17 22:06:23.989 INFO running `"docker" "start" "-a" "28fd71b252ffa9feaab242c37ee9ab6edb5b6213c807ebb4eea7927fe4bce1d5"` Nov 17 22:06:24.536 INFO [stderr] usermod: no changes Nov 17 22:06:24.561 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 0.01s Nov 17 22:06:24.564 INFO [stderr] Running /target/debug/deps/spin-2ad9449498776f95 Nov 17 22:06:24.566 INFO [stdout] Nov 17 22:06:24.566 INFO [stdout] running 25 tests Nov 17 22:06:24.567 INFO [stdout] test mutex::tests::test_mutex_force_lock ... ok Nov 17 22:06:24.567 INFO [stderr] thread '' panicked at 'explicit panic', src/mutex.rs:391:13 Nov 17 22:06:24.568 INFO [stdout] test mutex::tests::test_into_inner_drop ... ok Nov 17 22:06:24.568 INFO [stderr] stack backtrace: Nov 17 22:06:24.568 INFO [stdout] test mutex::tests::test_into_inner ... ok Nov 17 22:06:24.568 INFO [stdout] test mutex::tests::smoke ... ok Nov 17 22:06:24.568 INFO [stdout] test mutex::tests::try_lock ... ok Nov 17 22:06:24.568 INFO [stdout] test mutex::tests::test_mutex_arc_nested ... ok Nov 17 22:06:24.569 INFO [stdout] test mutex::tests::test_mutex_unsized ... ok Nov 17 22:06:24.569 INFO [stdout] test once::tests::smoke_once ... ok Nov 17 22:06:24.575 INFO [stdout] test once::tests::smoke_once_value ... ok Nov 17 22:06:24.587 INFO [stdout] test once::tests::try_no_wait ... ok Nov 17 22:06:24.587 INFO [stdout] test once::tests::wait ... ok Nov 17 22:06:24.587 INFO [stdout] test once::tests::try ... ok Nov 17 22:06:24.591 INFO [stdout] test mutex::tests::lots_and_lots ... ok Nov 17 22:06:24.595 INFO [stdout] test rw_lock::tests::test_force_read_decrement ... ok Nov 17 22:06:24.595 INFO [stderr] 0: 0x559fa7cd9c33 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h1a944b80a560daa8 Nov 17 22:06:24.595 INFO [stdout] test rw_lock::tests::test_force_write_unlock ... ok Nov 17 22:06:24.595 INFO [stdout] test rw_lock::tests::test_into_inner_drop ... ok Nov 17 22:06:24.596 INFO [stdout] test rw_lock::tests::test_into_inner ... ok Nov 17 22:06:24.596 INFO [stdout] test rw_lock::tests::smoke ... ok Nov 17 22:06:24.611 INFO [stdout] test rw_lock::tests::test_rwlock_try_write ... ok Nov 17 22:06:24.611 INFO [stdout] test rw_lock::tests::test_rwlock_unsized ... ok Nov 17 22:06:24.611 INFO [stderr] thread '' panicked at 'explicit panic', src/rw_lock.rs:491:13 Nov 17 22:06:24.612 INFO [stdout] test once::tests::stampede_once ... ok Nov 17 22:06:24.615 INFO [stderr] at libstd/sys/unix/backtrace/tracing/gcc_s.rs:49 Nov 17 22:06:24.615 INFO [stderr] 1: 0x559fa7cd43c8 - std::sys_common::backtrace::_print::h18f735b4c3a49bdb Nov 17 22:06:24.615 INFO [stderr] at libstd/sys_common/backtrace.rs:71 Nov 17 22:06:24.615 INFO [stderr] 2: 0x559fa7cd8ba4 - std::panicking::default_hook::{{closure}}::h97ab3548a215c097 Nov 17 22:06:24.616 INFO [stderr] at libstd/sys_common/backtrace.rs:59 Nov 17 22:06:24.616 INFO [stderr] at libstd/panicking.rs:211 Nov 17 22:06:24.616 INFO [stderr] 3: 0x559fa7cd890d - std::panicking::default_hook::h98c2a01ef734e4b0 Nov 17 22:06:24.616 INFO [stderr] at libstd/panicking.rs:227 Nov 17 22:06:24.616 INFO [stderr] 4: 0x559fa7cd92b0 - std::panicking::rust_panic_with_hook::hb4b3f28eb79ea752 Nov 17 22:06:24.616 INFO [stderr] at libstd/panicking.rs:476 Nov 17 22:06:24.617 INFO [stderr] 5: 0x559fa7c633b3 - std::panicking::begin_panic::haacaa5963bbe13e3 Nov 17 22:06:24.617 INFO [stderr] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panicking.rs:410 Nov 17 22:06:24.617 INFO [stderr] 6: 0x559fa7c62e4a - spin::mutex::tests::test_mutex_arc_access_in_unwind::{{closure}}::ha4ac13be5fce9e01 Nov 17 22:06:24.617 INFO [stderr] at src/mutex.rs:391 Nov 17 22:06:24.617 INFO [stderr] 7: 0x559fa7c64fb2 - std::sys_common::backtrace::__rust_begin_short_backtrace::ha6c0cb16b4e36f31 Nov 17 22:06:24.617 INFO [stderr] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/sys_common/backtrace.rs:136 Nov 17 22:06:24.617 INFO [stderr] 8: 0x559fa7c7e00d - std::thread::Builder::spawn_unchecked::{{closure}}::{{closure}}::hfeafe8dc19ae9d25 Nov 17 22:06:24.618 INFO [stderr] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/thread/mod.rs:477 Nov 17 22:06:24.618 INFO [stderr] 9: 0x559fa7c5c7e0 - as core::ops::function::FnOnce<()>>::call_once::h69ee7e5823fa97f3 Nov 17 22:06:24.618 INFO [stderr] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panic.rs:319 Nov 17 22:06:24.618 INFO [stderr] 10: 0x559fa7c64034 - std::panicking::try::do_call::hdcab2544304e2687 Nov 17 22:06:24.618 INFO [stderr] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panicking.rs:310 Nov 17 22:06:24.618 INFO [stderr] 11: 0x559fa7ce7a29 - __rust_maybe_catch_panic Nov 17 22:06:24.619 INFO [stderr] at libpanic_unwind/lib.rs:102 Nov 17 22:06:24.619 INFO [stderr] 12: 0x559fa7c63c1d - std::panicking::try::hd54a56ab63fdb644 Nov 17 22:06:24.619 INFO [stderr] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panicking.rs:289 Nov 17 22:06:24.619 INFO [stderr] 13: 0x559fa7c5cb32 - std::panic::catch_unwind::h65728cd315cc9682 Nov 17 22:06:24.619 INFO [stderr] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panic.rs:398 Nov 17 22:06:24.619 INFO [stderr] 14: 0x559fa7c7d6fc - std::thread::Builder::spawn_unchecked::{{closure}}::ha04c0c72aa934d29 Nov 17 22:06:24.620 INFO [stderr] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/thread/mod.rs:476 Nov 17 22:06:24.620 INFO [stderr] 15: 0x559fa7c7e6f6 - >::call_box::h9888c8abc42d4afe Nov 17 22:06:24.620 INFO [stderr] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/liballoc/boxed.rs:673 Nov 17 22:06:24.620 INFO [stderr] 16: 0x559fa7cdb86d - std::sys::unix::thread::Thread::new::thread_start::h5f8d67158a1ca038 Nov 17 22:06:24.620 INFO [stderr] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/liballoc/boxed.rs:683 Nov 17 22:06:24.620 INFO [stderr] at libstd/sys_common/thread.rs:24 Nov 17 22:06:24.620 INFO [stderr] at libstd/sys/unix/thread.rs:90 Nov 17 22:06:24.621 INFO [stderr] 17: 0x7f2ffd2896b9 - start_thread Nov 17 22:06:24.621 INFO [stderr] 18: 0x7f2ffcda941c - clone Nov 17 22:06:24.621 INFO [stderr] 19: 0x0 - Nov 17 22:06:24.628 INFO [stderr] stack backtrace: Nov 17 22:06:24.628 INFO [stderr] 0: 0x559fa7cd9c33 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h1a944b80a560daa8 Nov 17 22:06:24.628 INFO [stderr] at libstd/sys/unix/backtrace/tracing/gcc_s.rs:49 Nov 17 22:06:24.628 INFO [stderr] 1: 0x559fa7cd43c8 - std::sys_common::backtrace::_print::h18f735b4c3a49bdb Nov 17 22:06:24.628 INFO [stderr] at libstd/sys_common/backtrace.rs:71 Nov 17 22:06:24.628 INFO [stderr] 2: 0x559fa7cd8ba4 - std::panicking::default_hook::{{closure}}::h97ab3548a215c097 Nov 17 22:06:24.628 INFO [stderr] at libstd/sys_common/backtrace.rs:59 Nov 17 22:06:24.628 INFO [stderr] at libstd/panicking.rs:211 Nov 17 22:06:24.628 INFO [stderr] 3: 0x559fa7cd890d - std::panicking::default_hook::h98c2a01ef734e4b0 Nov 17 22:06:24.628 INFO [stderr] at libstd/panicking.rs:227 Nov 17 22:06:24.628 INFO [stderr] 4: 0x559fa7cd92b0 - std::panicking::rust_panic_with_hook::hb4b3f28eb79ea752 Nov 17 22:06:24.628 INFO [stderr] at libstd/panicking.rs:476 Nov 17 22:06:24.628 INFO [stderr] 5: 0x559fa7c633b3 - std::panicking::begin_panic::haacaa5963bbe13e3 Nov 17 22:06:24.628 INFO [stderr] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panicking.rs:410 Nov 17 22:06:24.628 INFO [stderr] 6: 0x559fa7c67d6a - spin::rw_lock::tests::test_rw_arc_access_in_unwind::{{closure}}::h6c1ac1b770e3f5c8 Nov 17 22:06:24.628 INFO [stderr] at src/rw_lock.rs:491 Nov 17 22:06:24.628 INFO [stderr] 7: 0x559fa7c64f62 - std::sys_common::backtrace::__rust_begin_short_backtrace::h88164f7700d4369c Nov 17 22:06:24.628 INFO [stderr] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/sys_common/backtrace.rs:136 Nov 17 22:06:24.628 INFO [stderr] 8: 0x559fa7c7df6d - std::thread::Builder::spawn_unchecked::{{closure}}::{{closure}}::hb86653c78bb69867 Nov 17 22:06:24.628 INFO [stderr] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/thread/mod.rs:477 Nov 17 22:06:24.628 INFO [stderr] 9: 0x559fa7c5c860 - as core::ops::function::FnOnce<()>>::call_once::h81f6371b97972660 Nov 17 22:06:24.628 INFO [stderr] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panic.rs:319 Nov 17 22:06:24.628 INFO [stderr] 10: 0x559fa7c63fe4 - std::panicking::try::do_call::hd537bdad302a551a Nov 17 22:06:24.628 INFO [stderr] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panicking.rs:310 Nov 17 22:06:24.628 INFO [stderr] 11: 0x559fa7ce7a29 - __rust_maybe_catch_panic Nov 17 22:06:24.628 INFO [stderr] at libpanic_unwind/lib.rs:102 Nov 17 22:06:24.628 INFO [stderr] 12: 0x559fa7c6363d - std::panicking::try::h4a3483857b37b06a Nov 17 22:06:24.628 INFO [stderr] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panicking.rs:289 Nov 17 22:06:24.628 INFO [stderr] 13: 0x559fa7c5cbe2 - std::panic::catch_unwind::hd54599fd2d83f42d Nov 17 22:06:24.628 INFO [stderr] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panic.rs:398 Nov 17 22:06:24.628 INFO [stderr] 14: 0x559fa7c7c4cc - std::thread::Builder::spawn_unchecked::{{closure}}::h0b7778d8bac877d7 Nov 17 22:06:24.628 INFO [stderr] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/thread/mod.rs:476 Nov 17 22:06:24.628 INFO [stderr] 15: 0x559fa7c7e8e6 - >::call_box::hcb906723a6a28522 Nov 17 22:06:24.628 INFO [stderr] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/liballoc/boxed.rs:673 Nov 17 22:06:24.628 INFO [stderr] 16: 0x559fa7cdb86d - std::sys::unix::thread::Thread::new::thread_start::h5f8d67158a1ca038 Nov 17 22:06:24.628 INFO [stderr] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/liballoc/boxed.rs:683 Nov 17 22:06:24.628 INFO [stderr] at libstd/sys_common/thread.rs:24 Nov 17 22:06:24.629 INFO [stderr] at libstd/sys/unix/thread.rs:90 Nov 17 22:06:24.629 INFO [stderr] 17: 0x7f2ffd2896b9 - start_thread Nov 17 22:06:24.629 INFO [stderr] 18: 0x7f2ffcda941c - clone Nov 17 22:06:24.629 INFO [stderr] 19: 0x0 - Nov 17 22:06:24.629 INFO [stdout] test mutex::tests::test_mutex_arc_access_in_unwind ... ok Nov 17 22:06:24.629 INFO [stdout] test rw_lock::tests::test_rw_arc_access_in_unwind ... ok Nov 17 22:06:24.629 INFO [stdout] test rw_lock::tests::test_rw_arc ... ok Nov 17 22:06:24.629 INFO [stdout] test once::tests::panic ... ok Nov 17 22:06:24.629 INFO [stdout] Nov 17 22:06:24.629 INFO [stdout] test result: ok. 25 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Nov 17 22:06:24.629 INFO [stdout] Nov 17 22:06:24.631 INFO [stderr] Doc-tests spin Nov 17 22:06:24.702 INFO [stdout] Nov 17 22:06:24.702 INFO [stdout] running 12 tests Nov 17 22:06:25.851 INFO [stdout] test src/mutex.rs - mutex::Mutex::new (line 103) ... ok Nov 17 22:06:26.292 INFO [stdout] test src/once.rs - once::Once (line 11) ... ok Nov 17 22:06:26.359 INFO [stdout] test src/once.rs - once::Once::call_once (line 70) ... ok Nov 17 22:06:26.387 INFO [stdout] test src/mutex.rs - mutex::Mutex::lock (line 177) ... ok Nov 17 22:06:26.799 INFO [stdout] test src/rw_lock.rs - rw_lock::RwLock::new (line 82) ... ok Nov 17 22:06:26.944 INFO [stdout] test src/mutex.rs - mutex::Mutex (line 26) ... ok Nov 17 22:06:27.079 INFO [stdout] test src/rw_lock.rs - rw_lock::RwLock (line 25) ... ok Nov 17 22:06:27.331 INFO [stdout] test src/mutex.rs - mutex::Mutex (line 48) ... ok Nov 17 22:06:27.548 INFO [stdout] test src/rw_lock.rs - rw_lock::RwLock::read (line 154) ... ok Nov 17 22:06:27.766 INFO [stdout] test src/rw_lock.rs - rw_lock::RwLock::write (line 269) ... ok Nov 17 22:06:27.768 INFO [stdout] test src/rw_lock.rs - rw_lock::RwLock::try_write (line 310) ... ok Nov 17 22:06:27.868 INFO [stdout] test src/rw_lock.rs - rw_lock::RwLock::try_read (line 205) ... ok Nov 17 22:06:27.868 INFO [stdout] Nov 17 22:06:27.868 INFO [stdout] test result: ok. 12 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Nov 17 22:06:27.868 INFO [stdout] Nov 17 22:06:27.887 INFO [stderr] su: No module specific data is present Nov 17 22:06:28.112 INFO running `"docker" "inspect" "28fd71b252ffa9feaab242c37ee9ab6edb5b6213c807ebb4eea7927fe4bce1d5"` Nov 17 22:06:28.212 INFO running `"docker" "rm" "-f" "28fd71b252ffa9feaab242c37ee9ab6edb5b6213c807ebb4eea7927fe4bce1d5"` Nov 17 22:06:28.294 INFO [stdout] 28fd71b252ffa9feaab242c37ee9ab6edb5b6213c807ebb4eea7927fe4bce1d5