Dec 06 21:55:09.071 INFO testing spin-0.4.10 against try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468 for pr-56514 Dec 06 21:55:09.071 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-5/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/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 21:55:09.363 INFO [stdout] 205cdc87085c0c997d615811d913db3b7c9c200c806ec318b38eaf6baaadab1b Dec 06 21:55:09.367 INFO running `"docker" "start" "-a" "205cdc87085c0c997d615811d913db3b7c9c200c806ec318b38eaf6baaadab1b"` Dec 06 21:55:10.150 INFO [stderr] usermod: no changes Dec 06 21:55:10.192 INFO [stderr] Compiling spin v0.4.10 (/source) Dec 06 21:55:10.479 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 0.30s Dec 06 21:55:10.481 INFO [stderr] su: No module specific data is present Dec 06 21:55:10.946 INFO running `"docker" "inspect" "205cdc87085c0c997d615811d913db3b7c9c200c806ec318b38eaf6baaadab1b"` Dec 06 21:55:11.194 INFO running `"docker" "rm" "-f" "205cdc87085c0c997d615811d913db3b7c9c200c806ec318b38eaf6baaadab1b"` Dec 06 21:55:11.560 INFO [stdout] 205cdc87085c0c997d615811d913db3b7c9c200c806ec318b38eaf6baaadab1b Dec 06 21:55:11.563 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-5/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/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 21:55:11.956 INFO [stdout] f40e6eef2cf93e244ee32492944315ddd10a8f0d9852b31ec5d791f7eda0917d Dec 06 21:55:11.960 INFO running `"docker" "start" "-a" "f40e6eef2cf93e244ee32492944315ddd10a8f0d9852b31ec5d791f7eda0917d"` Dec 06 21:55:12.767 INFO [stderr] usermod: no changes Dec 06 21:55:12.824 INFO [stderr] Compiling spin v0.4.10 (/source) Dec 06 21:55:15.796 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 2.98s Dec 06 21:55:15.796 INFO [stderr] su: No module specific data is present Dec 06 21:55:16.423 INFO running `"docker" "inspect" "f40e6eef2cf93e244ee32492944315ddd10a8f0d9852b31ec5d791f7eda0917d"` Dec 06 21:55:16.714 INFO running `"docker" "rm" "-f" "f40e6eef2cf93e244ee32492944315ddd10a8f0d9852b31ec5d791f7eda0917d"` Dec 06 21:55:17.079 INFO [stdout] f40e6eef2cf93e244ee32492944315ddd10a8f0d9852b31ec5d791f7eda0917d Dec 06 21:55:17.081 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-5/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/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 21:55:17.598 INFO [stdout] 179a30d9eb17381c2b48e1a35dd61ae1659fea024d6a3363eea7172dcbcb147a Dec 06 21:55:17.604 INFO running `"docker" "start" "-a" "179a30d9eb17381c2b48e1a35dd61ae1659fea024d6a3363eea7172dcbcb147a"` Dec 06 21:55:18.483 INFO [stderr] usermod: no changes Dec 06 21:55:18.516 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 0.01s Dec 06 21:55:18.522 INFO [stderr] Running /target/debug/deps/spin-27ed66c2e99bc4af Dec 06 21:55:18.524 INFO [stdout] Dec 06 21:55:18.524 INFO [stdout] running 26 tests Dec 06 21:55:18.526 INFO [stdout] test mutex::tests::test_into_inner ... ok Dec 06 21:55:18.526 INFO [stdout] test mutex::tests::smoke ... ok Dec 06 21:55:18.526 INFO [stdout] test mutex::tests::try_lock ... ok Dec 06 21:55:18.540 INFO [stderr] thread '' panicked at 'explicit panic', src/mutex.rs:386:13 Dec 06 21:55:18.548 INFO [stdout] test mutex::tests::test_into_inner_drop ... ok Dec 06 21:55:18.549 INFO [stdout] test once::tests::init_constant ... ok Dec 06 21:55:18.549 INFO [stdout] test mutex::tests::test_mutex_unsized ... ok Dec 06 21:55:18.549 INFO [stdout] test mutex::tests::test_mutex_force_lock ... ok Dec 06 21:55:18.549 INFO [stdout] test mutex::tests::test_mutex_arc_nested ... ok Dec 06 21:55:18.549 INFO [stdout] test once::tests::try_no_wait ... ok Dec 06 21:55:18.552 INFO [stdout] test once::tests::smoke_once_value ... ok Dec 06 21:55:18.572 INFO [stdout] test once::tests::try ... ok Dec 06 21:55:18.572 INFO [stdout] test once::tests::smoke_once ... ok Dec 06 21:55:18.592 INFO [stdout] test mutex::tests::lots_and_lots ... ok Dec 06 21:55:18.592 INFO [stdout] test once::tests::wait ... ok Dec 06 21:55:18.592 INFO [stdout] test rw_lock::tests::smoke ... ok Dec 06 21:55:18.593 INFO [stdout] test rw_lock::tests::test_force_read_decrement ... ok Dec 06 21:55:18.600 INFO [stdout] test once::tests::stampede_once ... ok Dec 06 21:55:18.600 INFO [stdout] test rw_lock::tests::test_rwlock_try_write ... ok Dec 06 21:55:18.600 INFO [stdout] test rw_lock::tests::test_rwlock_unsized ... ok Dec 06 21:55:18.640 INFO [stdout] test rw_lock::tests::test_force_write_unlock ... ok Dec 06 21:55:18.640 INFO [stdout] test rw_lock::tests::test_into_inner ... ok Dec 06 21:55:18.640 INFO [stdout] test rw_lock::tests::test_into_inner_drop ... ok Dec 06 21:55:18.672 INFO [stderr] thread '' panicked at 'explicit panic', src/rw_lock.rs:493:13 Dec 06 21:55:18.720 INFO [stderr] stack backtrace: Dec 06 21:55:18.720 INFO [stderr] 0: 0x55873dff1883 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h82c998a0e635a5c4 Dec 06 21:55:18.720 INFO [stderr] at src/libstd/sys/unix/backtrace/tracing/gcc_s.rs:49 Dec 06 21:55:18.720 INFO [stderr] 1: 0x55873dfebfa8 - std::sys_common::backtrace::_print::h5fbc0bc3cf12d9bf Dec 06 21:55:18.720 INFO [stderr] at src/libstd/sys_common/backtrace.rs:71 Dec 06 21:55:18.720 INFO [stderr] 2: 0x55873dff07f2 - std::panicking::default_hook::{{closure}}::h42b81c3dd1a50e32 Dec 06 21:55:18.720 INFO [stderr] at src/libstd/sys_common/backtrace.rs:59 Dec 06 21:55:18.720 INFO [stderr] at src/libstd/panicking.rs:211 Dec 06 21:55:18.721 INFO [stderr] 3: 0x55873dff055d - std::panicking::default_hook::h6c8d8e1c12a669e5 Dec 06 21:55:18.721 INFO [stderr] at src/libstd/panicking.rs:227 Dec 06 21:55:18.721 INFO [stderr] 4: 0x55873dff0f00 - std::panicking::rust_panic_with_hook::hce93a56e38c2b0aa Dec 06 21:55:18.721 INFO [stderr] at src/libstd/panicking.rs:491 Dec 06 21:55:18.721 INFO [stderr] 5: 0x55873df7b253 - std::panicking::begin_panic::hd6f34f0abeafd450 Dec 06 21:55:18.721 INFO [stderr] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panicking.rs:425 Dec 06 21:55:18.721 INFO [stderr] 6: 0x55873df7acea - spin::mutex::tests::test_mutex_arc_access_in_unwind::{{closure}}::hc1570dfe93355ade Dec 06 21:55:18.721 INFO [stderr] at src/mutex.rs:386 Dec 06 21:55:18.721 INFO [stderr] 7: 0x55873df7f752 - std::sys_common::backtrace::__rust_begin_short_backtrace::haf447bd4461699b6 Dec 06 21:55:18.721 INFO [stderr] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/sys_common/backtrace.rs:136 Dec 06 21:55:18.721 INFO [stderr] 8: 0x55873df957ed - std::thread::Builder::spawn_unchecked::{{closure}}::{{closure}}::h9362b7d1d4e38b20 Dec 06 21:55:18.721 INFO [stderr] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/thread/mod.rs:477 Dec 06 21:55:18.721 INFO [stderr] 9: 0x55873df74740 - as core::ops::function::FnOnce<()>>::call_once::h162ee964377b7bba Dec 06 21:55:18.721 INFO [stderr] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panic.rs:319 Dec 06 21:55:18.721 INFO [stderr] 10: 0x55873df7be64 - std::panicking::try::do_call::h70bf6db33aa581cd Dec 06 21:55:18.721 INFO [stderr] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panicking.rs:310 Dec 06 21:55:18.721 INFO [stderr] 11: 0x55873dfff569 - __rust_maybe_catch_panic Dec 06 21:55:18.721 INFO [stderr] at src/libpanic_unwind/lib.rs:102 Dec 06 21:55:18.721 INFO [stderr] 12: 0x55873df7b80d - std::panicking::try::h988c7e0192de7474 Dec 06 21:55:18.721 INFO [stderr] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panicking.rs:289 Dec 06 21:55:18.721 INFO [stderr] 13: 0x55873df74b72 - std::panic::catch_unwind::hecbb928b4a7cdc7e Dec 06 21:55:18.721 INFO [stderr] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panic.rs:398 Dec 06 21:55:18.721 INFO [stderr] 14: 0x55873df943e5 - std::thread::Builder::spawn_unchecked::{{closure}}::h66a73ca0303ef449 Dec 06 21:55:18.721 INFO [stderr] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/thread/mod.rs:476 Dec 06 21:55:18.721 INFO [stderr] 15: 0x55873df96156 - >::call_box::hc53daf76720a9adb Dec 06 21:55:18.721 INFO [stderr] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/liballoc/boxed.rs:673 Dec 06 21:55:18.721 INFO [stderr] 16: 0x55873dff348d - std::sys::unix::thread::Thread::new::thread_start::h0fe9c527147fd5f5 Dec 06 21:55:18.721 INFO [stderr] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/liballoc/boxed.rs:683 Dec 06 21:55:18.721 INFO [stderr] at src/libstd/sys_common/thread.rs:24 Dec 06 21:55:18.721 INFO [stderr] at src/libstd/sys/unix/thread.rs:90 Dec 06 21:55:18.721 INFO [stderr] 17: 0x7fb98eecc6b9 - start_thread Dec 06 21:55:18.721 INFO [stderr] 18: 0x7fb98e9ec41c - clone Dec 06 21:55:18.721 INFO [stderr] 19: 0x0 - Dec 06 21:55:18.721 INFO [stdout] test once::tests::panic ... ok Dec 06 21:55:18.721 INFO [stdout] test rw_lock::tests::test_rw_arc ... ok Dec 06 21:55:18.728 INFO [stdout] test mutex::tests::test_mutex_arc_access_in_unwind ... ok Dec 06 21:55:18.728 INFO [stderr] stack backtrace: Dec 06 21:55:18.728 INFO [stderr] 0: 0x55873dff1883 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h82c998a0e635a5c4 Dec 06 21:55:18.728 INFO [stderr] at src/libstd/sys/unix/backtrace/tracing/gcc_s.rs:49 Dec 06 21:55:18.728 INFO [stderr] 1: 0x55873dfebfa8 - std::sys_common::backtrace::_print::h5fbc0bc3cf12d9bf Dec 06 21:55:18.729 INFO [stderr] at src/libstd/sys_common/backtrace.rs:71 Dec 06 21:55:18.729 INFO [stderr] 2: 0x55873dff07f2 - std::panicking::default_hook::{{closure}}::h42b81c3dd1a50e32 Dec 06 21:55:18.729 INFO [stderr] at src/libstd/sys_common/backtrace.rs:59 Dec 06 21:55:18.729 INFO [stderr] at src/libstd/panicking.rs:211 Dec 06 21:55:18.729 INFO [stderr] 3: 0x55873dff055d - std::panicking::default_hook::h6c8d8e1c12a669e5 Dec 06 21:55:18.729 INFO [stderr] at src/libstd/panicking.rs:227 Dec 06 21:55:18.729 INFO [stderr] 4: 0x55873dff0f00 - std::panicking::rust_panic_with_hook::hce93a56e38c2b0aa Dec 06 21:55:18.729 INFO [stderr] at src/libstd/panicking.rs:491 Dec 06 21:55:18.729 INFO [stderr] 5: 0x55873df7b253 - std::panicking::begin_panic::hd6f34f0abeafd450 Dec 06 21:55:18.729 INFO [stderr] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panicking.rs:425 Dec 06 21:55:18.729 INFO [stderr] 6: 0x55873df8530a - spin::rw_lock::tests::test_rw_arc_access_in_unwind::{{closure}}::ha114a97ae4b1f5ed Dec 06 21:55:18.729 INFO [stderr] at src/rw_lock.rs:493 Dec 06 21:55:18.729 INFO [stderr] 7: 0x55873df7f6f2 - std::sys_common::backtrace::__rust_begin_short_backtrace::h6b0110ea91ad1180 Dec 06 21:55:18.729 INFO [stderr] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/sys_common/backtrace.rs:136 Dec 06 21:55:18.729 INFO [stderr] 8: 0x55873df958ad - std::thread::Builder::spawn_unchecked::{{closure}}::{{closure}}::hdd6486efda01c4f2 Dec 06 21:55:18.729 INFO [stderr] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/thread/mod.rs:477 Dec 06 21:55:18.729 INFO [stderr] 9: 0x55873df74780 - as core::ops::function::FnOnce<()>>::call_once::h3ae1f7d79c508733 Dec 06 21:55:18.729 INFO [stderr] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panic.rs:319 Dec 06 21:55:18.729 INFO [stderr] 10: 0x55873df7bd74 - std::panicking::try::do_call::h5fc7005781e7aa58 Dec 06 21:55:18.729 INFO [stderr] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panicking.rs:310 Dec 06 21:55:18.729 INFO [stderr] 11: 0x55873dfff569 - __rust_maybe_catch_panic Dec 06 21:55:18.729 INFO [stderr] at src/libpanic_unwind/lib.rs:102 Dec 06 21:55:18.729 INFO [stderr] 12: 0x55873df7b2bd - std::panicking::try::h0d7ab08a1a6b0af4 Dec 06 21:55:18.729 INFO [stderr] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panicking.rs:289 Dec 06 21:55:18.729 INFO [stderr] 13: 0x55873df74a72 - std::panic::catch_unwind::h95c9ad0b6cdc70aa Dec 06 21:55:18.729 INFO [stderr] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panic.rs:398 Dec 06 21:55:18.729 INFO [stderr] 14: 0x55873df94ce5 - std::thread::Builder::spawn_unchecked::{{closure}}::h8e2ecf97b7e1a33f Dec 06 21:55:18.729 INFO [stderr] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/thread/mod.rs:476 Dec 06 21:55:18.729 INFO [stderr] 15: 0x55873df96096 - >::call_box::h72b4b0ef832e1bcd Dec 06 21:55:18.729 INFO [stderr] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/liballoc/boxed.rs:673 Dec 06 21:55:18.729 INFO [stderr] 16: 0x55873dff348d - std::sys::unix::thread::Thread::new::thread_start::h0fe9c527147fd5f5 Dec 06 21:55:18.729 INFO [stderr] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/liballoc/boxed.rs:683 Dec 06 21:55:18.729 INFO [stderr] at src/libstd/sys_common/thread.rs:24 Dec 06 21:55:18.729 INFO [stderr] at src/libstd/sys/unix/thread.rs:90 Dec 06 21:55:18.729 INFO [stderr] 17: 0x7fb98eecc6b9 - start_thread Dec 06 21:55:18.729 INFO [stderr] 18: 0x7fb98e9ec41c - clone Dec 06 21:55:18.729 INFO [stderr] 19: 0x0 - Dec 06 21:55:18.732 INFO [stdout] test rw_lock::tests::test_rw_arc_access_in_unwind ... ok Dec 06 21:55:18.732 INFO [stdout] Dec 06 21:55:18.732 INFO [stdout] test result: ok. 26 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 06 21:55:18.732 INFO [stdout] Dec 06 21:55:18.744 INFO [stderr] Doc-tests spin Dec 06 21:55:18.842 INFO [stdout] Dec 06 21:55:18.842 INFO [stdout] running 12 tests Dec 06 21:55:22.544 INFO [stdout] test src/rw_lock.rs - rw_lock::RwLock::new (line 84) ... ok Dec 06 21:55:23.164 INFO [stdout] test src/mutex.rs - mutex::Mutex::lock (line 172) ... ok Dec 06 21:55:23.284 INFO [stdout] test src/rw_lock.rs - rw_lock::RwLock (line 25) ... ok Dec 06 21:55:24.272 INFO [stdout] test src/mutex.rs - mutex::Mutex::new (line 98) ... ok Dec 06 21:55:24.278 INFO [stdout] test src/once.rs - once::Once (line 12) ... ok Dec 06 21:55:24.409 INFO [stdout] test src/once.rs - once::Once::call_once (line 90) ... ok Dec 06 21:55:24.774 INFO [stdout] test src/mutex.rs - mutex::Mutex (line 20) ... ok Dec 06 21:55:25.768 INFO [stdout] test src/mutex.rs - mutex::Mutex (line 42) ... ok Dec 06 21:55:26.664 INFO [stdout] test src/rw_lock.rs - rw_lock::RwLock::try_read (line 207) ... ok Dec 06 21:55:27.116 INFO [stdout] test src/rw_lock.rs - rw_lock::RwLock::read (line 156) ... ok Dec 06 21:55:27.561 INFO [stdout] test src/rw_lock.rs - rw_lock::RwLock::try_write (line 312) ... ok Dec 06 21:55:27.568 INFO [stdout] test src/rw_lock.rs - rw_lock::RwLock::write (line 271) ... ok Dec 06 21:55:27.568 INFO [stdout] Dec 06 21:55:27.569 INFO [stdout] test result: ok. 12 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 06 21:55:27.569 INFO [stdout] Dec 06 21:55:27.589 INFO [stderr] su: No module specific data is present Dec 06 21:55:28.592 INFO running `"docker" "inspect" "179a30d9eb17381c2b48e1a35dd61ae1659fea024d6a3363eea7172dcbcb147a"` Dec 06 21:55:28.846 INFO running `"docker" "rm" "-f" "179a30d9eb17381c2b48e1a35dd61ae1659fea024d6a3363eea7172dcbcb147a"` Dec 06 21:55:29.042 INFO [stdout] 179a30d9eb17381c2b48e1a35dd61ae1659fea024d6a3363eea7172dcbcb147a