Dec 14 18:50:01.040 INFO testing spin-0.4.10 against master#bd47d6825bf4090517549d33cfef10d3300b4a75 for pr-56550 Dec 14 18:50:01.040 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-6/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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:01.504 INFO [stdout] abcbd09388179341466f0f2b0b20bd9a108c859cfc6a3ef17917ced892340d56 Dec 14 18:50:01.506 INFO running `"docker" "start" "-a" "abcbd09388179341466f0f2b0b20bd9a108c859cfc6a3ef17917ced892340d56"` Dec 14 18:50:02.558 INFO [stderr] usermod: no changes Dec 14 18:50:02.643 INFO [stderr] Compiling spin v0.4.10 (/source) Dec 14 18:50:02.987 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 0.40s Dec 14 18:50:02.991 INFO [stderr] su: No module specific data is present Dec 14 18:50:03.591 INFO running `"docker" "inspect" "abcbd09388179341466f0f2b0b20bd9a108c859cfc6a3ef17917ced892340d56"` Dec 14 18:50:03.887 INFO running `"docker" "rm" "-f" "abcbd09388179341466f0f2b0b20bd9a108c859cfc6a3ef17917ced892340d56"` Dec 14 18:50:04.365 INFO [stdout] abcbd09388179341466f0f2b0b20bd9a108c859cfc6a3ef17917ced892340d56 Dec 14 18:50:04.369 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-6/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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.825 INFO [stdout] 80635e27a5a861ffbe5c97db34ca3003d1f49b75ce9db4e31ecff4be2dc57f37 Dec 14 18:50:04.830 INFO running `"docker" "start" "-a" "80635e27a5a861ffbe5c97db34ca3003d1f49b75ce9db4e31ecff4be2dc57f37"` Dec 14 18:50:05.774 INFO [stderr] usermod: no changes Dec 14 18:50:05.819 INFO [stderr] Compiling spin v0.4.10 (/source) Dec 14 18:50:08.587 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 2.76s Dec 14 18:50:08.587 INFO [stderr] su: No module specific data is present Dec 14 18:50:09.304 INFO running `"docker" "inspect" "80635e27a5a861ffbe5c97db34ca3003d1f49b75ce9db4e31ecff4be2dc57f37"` Dec 14 18:50:09.593 INFO running `"docker" "rm" "-f" "80635e27a5a861ffbe5c97db34ca3003d1f49b75ce9db4e31ecff4be2dc57f37"` Dec 14 18:50:09.871 INFO [stdout] 80635e27a5a861ffbe5c97db34ca3003d1f49b75ce9db4e31ecff4be2dc57f37 Dec 14 18:50:09.875 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-6/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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.308 INFO [stdout] 8dd12c720a9c91ef84d595ce3d788fab49a3f0467e87621287dfde17f5d58524 Dec 14 18:50:10.315 INFO running `"docker" "start" "-a" "8dd12c720a9c91ef84d595ce3d788fab49a3f0467e87621287dfde17f5d58524"` Dec 14 18:50:11.517 INFO [stderr] usermod: no changes Dec 14 18:50:11.572 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 0.01s Dec 14 18:50:11.579 INFO [stderr] Running /target/debug/deps/spin-f87a48fd16145071 Dec 14 18:50:11.579 INFO [stdout] Dec 14 18:50:11.579 INFO [stderr] thread '' panicked at 'explicit panic', src/mutex.rs:386:13 Dec 14 18:50:11.579 INFO [stdout] running 26 tests Dec 14 18:50:11.579 INFO [stderr] stack backtrace: Dec 14 18:50:11.579 INFO [stdout] test mutex::tests::test_mutex_force_lock ... ok Dec 14 18:50:11.579 INFO [stdout] test mutex::tests::test_mutex_unsized ... ok Dec 14 18:50:11.579 INFO [stdout] test mutex::tests::test_into_inner_drop ... ok Dec 14 18:50:11.579 INFO [stdout] test mutex::tests::test_into_inner ... ok Dec 14 18:50:11.579 INFO [stdout] test mutex::tests::try_lock ... ok Dec 14 18:50:11.579 INFO [stdout] test once::tests::init_constant ... ok Dec 14 18:50:11.579 INFO [stdout] test mutex::tests::smoke ... ok Dec 14 18:50:11.579 INFO [stdout] test mutex::tests::test_mutex_arc_nested ... ok Dec 14 18:50:11.587 INFO [stdout] test once::tests::smoke_once ... ok Dec 14 18:50:11.587 INFO [stdout] test once::tests::smoke_once_value ... ok Dec 14 18:50:11.587 INFO [stdout] test once::tests::wait ... ok Dec 14 18:50:11.589 INFO [stdout] test once::tests::try_no_wait ... ok Dec 14 18:50:11.589 INFO [stdout] test once::tests::try ... ok Dec 14 18:50:11.589 INFO [stdout] test mutex::tests::lots_and_lots ... ok Dec 14 18:50:11.589 INFO [stdout] test rw_lock::tests::smoke ... ok Dec 14 18:50:11.589 INFO [stdout] test rw_lock::tests::test_force_read_decrement ... ok Dec 14 18:50:11.589 INFO [stdout] test rw_lock::tests::test_into_inner_drop ... ok Dec 14 18:50:11.589 INFO [stdout] test rw_lock::tests::test_force_write_unlock ... ok Dec 14 18:50:11.589 INFO [stdout] test rw_lock::tests::test_into_inner ... ok Dec 14 18:50:11.589 INFO [stdout] test rw_lock::tests::test_rwlock_try_write ... ok Dec 14 18:50:11.599 INFO [stdout] test once::tests::stampede_once ... ok Dec 14 18:50:11.611 INFO [stdout] test rw_lock::tests::test_rwlock_unsized ... ok Dec 14 18:50:11.611 INFO [stderr] thread '' panicked at 'explicit panic', src/rw_lock.rs:493:13 Dec 14 18:50:11.631 INFO [stderr] 0: 0x557350972ba3 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::heb4d4d98bcf46d90 Dec 14 18:50:11.631 INFO [stdout] test rw_lock::tests::test_rw_arc ... ok Dec 14 18:50:11.676 INFO [stderr] at src/libstd/sys/unix/backtrace/tracing/gcc_s.rs:49 Dec 14 18:50:11.676 INFO [stderr] 1: 0x55735096d3a8 - std::sys_common::backtrace::_print::h919420b22c2473e3 Dec 14 18:50:11.676 INFO [stderr] at src/libstd/sys_common/backtrace.rs:71 Dec 14 18:50:11.676 INFO [stderr] 2: 0x557350971b12 - std::panicking::default_hook::{{closure}}::h22d4ac021acb88d5 Dec 14 18:50:11.676 INFO [stderr] at src/libstd/sys_common/backtrace.rs:59 Dec 14 18:50:11.676 INFO [stderr] at src/libstd/panicking.rs:210 Dec 14 18:50:11.676 INFO [stderr] 3: 0x557350971884 - std::panicking::default_hook::h1134a4d34aab4428 Dec 14 18:50:11.676 INFO [stderr] at src/libstd/panicking.rs:224 Dec 14 18:50:11.676 INFO [stderr] 4: 0x557350972220 - std::panicking::rust_panic_with_hook::h9c89557c251f2d42 Dec 14 18:50:11.676 INFO [stderr] at src/libstd/panicking.rs:487 Dec 14 18:50:11.676 INFO [stderr] 5: 0x5573508fc3a3 - std::panicking::begin_panic::hf870255f1da22e68 Dec 14 18:50:11.676 INFO [stderr] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panicking.rs:421 Dec 14 18:50:11.676 INFO [stderr] 6: 0x5573508fbe3a - spin::mutex::tests::test_mutex_arc_access_in_unwind::{{closure}}::h84e07f7f0b57b9c2 Dec 14 18:50:11.677 INFO [stderr] at src/mutex.rs:386 Dec 14 18:50:11.677 INFO [stderr] 7: 0x557350900802 - std::sys_common::backtrace::__rust_begin_short_backtrace::h4354fc83d77e54f5 Dec 14 18:50:11.679 INFO [stderr] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/sys_common/backtrace.rs:136 Dec 14 18:50:11.679 INFO [stderr] 8: 0x557350916b5d - std::thread::Builder::spawn_unchecked::{{closure}}::{{closure}}::h203870906c6a4e39 Dec 14 18:50:11.679 INFO [stderr] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/thread/mod.rs:479 Dec 14 18:50:11.679 INFO [stderr] 9: 0x5573508f5940 - as core::ops::function::FnOnce<()>>::call_once::had8efc1cd534d046 Dec 14 18:50:11.679 INFO [stderr] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panic.rs:319 Dec 14 18:50:11.679 INFO [stderr] 10: 0x5573508fcf74 - std::panicking::try::do_call::hb9740baff164ccce Dec 14 18:50:11.679 INFO [stderr] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panicking.rs:306 Dec 14 18:50:11.679 INFO [stderr] 11: 0x557350980889 - __rust_maybe_catch_panic Dec 14 18:50:11.679 INFO [stderr] at src/libpanic_unwind/lib.rs:102 Dec 14 18:50:11.679 INFO [stderr] 12: 0x5573508fc40d - std::panicking::try::h0d0040bf9d745fb4 Dec 14 18:50:11.682 INFO [stderr] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panicking.rs:285 Dec 14 18:50:11.682 INFO [stderr] 13: 0x5573508f5b02 - std::panic::catch_unwind::h0f74f516a8250d4d Dec 14 18:50:11.682 INFO [stderr] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panic.rs:398 Dec 14 18:50:11.682 INFO [stderr] 14: 0x557350916925 - std::thread::Builder::spawn_unchecked::{{closure}}::hef29a7cb98aaf87f Dec 14 18:50:11.682 INFO [stderr] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/thread/mod.rs:478 Dec 14 18:50:11.682 INFO [stderr] 15: 0x557350917356 - >::call_box::h71c3204743718837 Dec 14 18:50:11.682 INFO [stderr] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/liballoc/boxed.rs:673 Dec 14 18:50:11.682 INFO [stderr] 16: 0x5573509747ad - std::sys::unix::thread::Thread::new::thread_start::h08aa646226e66c99 Dec 14 18:50:11.682 INFO [stderr] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/liballoc/boxed.rs:683 Dec 14 18:50:11.682 INFO [stderr] at src/libstd/sys_common/thread.rs:24 Dec 14 18:50:11.682 INFO [stderr] at src/libstd/sys/unix/thread.rs:91 Dec 14 18:50:11.682 INFO [stderr] 17: 0x7fbd08c076b9 - start_thread Dec 14 18:50:11.682 INFO [stderr] 18: 0x7fbd0872741c - clone Dec 14 18:50:11.682 INFO [stderr] 19: 0x0 - Dec 14 18:50:11.697 INFO [stdout] test mutex::tests::test_mutex_arc_access_in_unwind ... ok Dec 14 18:50:11.697 INFO [stderr] stack backtrace: Dec 14 18:50:11.697 INFO [stderr] 0: 0x557350972ba3 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::heb4d4d98bcf46d90 Dec 14 18:50:11.697 INFO [stderr] at src/libstd/sys/unix/backtrace/tracing/gcc_s.rs:49 Dec 14 18:50:11.697 INFO [stderr] 1: 0x55735096d3a8 - std::sys_common::backtrace::_print::h919420b22c2473e3 Dec 14 18:50:11.697 INFO [stderr] at src/libstd/sys_common/backtrace.rs:71 Dec 14 18:50:11.697 INFO [stderr] 2: 0x557350971b12 - std::panicking::default_hook::{{closure}}::h22d4ac021acb88d5 Dec 14 18:50:11.697 INFO [stderr] at src/libstd/sys_common/backtrace.rs:59 Dec 14 18:50:11.697 INFO [stderr] at src/libstd/panicking.rs:210 Dec 14 18:50:11.697 INFO [stderr] 3: 0x557350971884 - std::panicking::default_hook::h1134a4d34aab4428 Dec 14 18:50:11.697 INFO [stderr] at src/libstd/panicking.rs:224 Dec 14 18:50:11.697 INFO [stderr] 4: 0x557350972220 - std::panicking::rust_panic_with_hook::h9c89557c251f2d42 Dec 14 18:50:11.697 INFO [stderr] at src/libstd/panicking.rs:487 Dec 14 18:50:11.697 INFO [stderr] 5: 0x5573508fc3a3 - std::panicking::begin_panic::hf870255f1da22e68 Dec 14 18:50:11.697 INFO [stderr] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panicking.rs:421 Dec 14 18:50:11.697 INFO [stderr] 6: 0x55735090645a - spin::rw_lock::tests::test_rw_arc_access_in_unwind::{{closure}}::he834a4505f1d8186 Dec 14 18:50:11.697 INFO [stderr] at src/rw_lock.rs:493 Dec 14 18:50:11.697 INFO [stderr] 7: 0x557350900822 - std::sys_common::backtrace::__rust_begin_short_backtrace::h6918e269d0d2c7e4 Dec 14 18:50:11.697 INFO [stderr] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/sys_common/backtrace.rs:136 Dec 14 18:50:11.697 INFO [stderr] 8: 0x557350916c2d - std::thread::Builder::spawn_unchecked::{{closure}}::{{closure}}::hc30760b393fc7231 Dec 14 18:50:11.697 INFO [stderr] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/thread/mod.rs:479 Dec 14 18:50:11.697 INFO [stderr] 9: 0x5573508f58c0 - as core::ops::function::FnOnce<()>>::call_once::h75956343eefc8ce8 Dec 14 18:50:11.697 INFO [stderr] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panic.rs:319 Dec 14 18:50:11.697 INFO [stderr] 10: 0x5573508fce94 - std::panicking::try::do_call::h2f04a27467a5b1b4 Dec 14 18:50:11.697 INFO [stderr] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panicking.rs:306 Dec 14 18:50:11.697 INFO [stderr] 11: 0x557350980889 - __rust_maybe_catch_panic Dec 14 18:50:11.697 INFO [stderr] at src/libpanic_unwind/lib.rs:102 Dec 14 18:50:11.697 INFO [stderr] 12: 0x5573508fc91d - std::panicking::try::h6af53d7c913bf3b9 Dec 14 18:50:11.697 INFO [stderr] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panicking.rs:285 Dec 14 18:50:11.697 INFO [stderr] 13: 0x5573508f5c42 - std::panic::catch_unwind::hca77ba9c5a983a73 Dec 14 18:50:11.697 INFO [stderr] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panic.rs:398 Dec 14 18:50:11.697 INFO [stderr] 14: 0x557350916035 - std::thread::Builder::spawn_unchecked::{{closure}}::hae5288cd72363cb1 Dec 14 18:50:11.697 INFO [stderr] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/thread/mod.rs:478 Dec 14 18:50:11.697 INFO [stderr] 15: 0x557350917286 - >::call_box::h6be5b2014bd3690d Dec 14 18:50:11.698 INFO [stderr] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/liballoc/boxed.rs:673 Dec 14 18:50:11.698 INFO [stderr] 16: 0x5573509747ad - std::sys::unix::thread::Thread::new::thread_start::h08aa646226e66c99 Dec 14 18:50:11.698 INFO [stderr] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/liballoc/boxed.rs:683 Dec 14 18:50:11.698 INFO [stderr] at src/libstd/sys_common/thread.rs:24 Dec 14 18:50:11.698 INFO [stderr] at src/libstd/sys/unix/thread.rs:91 Dec 14 18:50:11.698 INFO [stderr] 17: 0x7fbd08c076b9 - start_thread Dec 14 18:50:11.698 INFO [stderr] 18: 0x7fbd0872741c - clone Dec 14 18:50:11.698 INFO [stderr] 19: 0x0 - Dec 14 18:50:11.698 INFO [stdout] test rw_lock::tests::test_rw_arc_access_in_unwind ... ok Dec 14 18:50:11.698 INFO [stdout] test once::tests::panic ... ok Dec 14 18:50:11.698 INFO [stdout] Dec 14 18:50:11.698 INFO [stdout] test result: ok. 26 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 14 18:50:11.698 INFO [stdout] Dec 14 18:50:11.710 INFO [stderr] Doc-tests spin Dec 14 18:50:11.911 INFO [stdout] Dec 14 18:50:11.911 INFO [stdout] running 12 tests Dec 14 18:50:18.148 INFO [stdout] test src/once.rs - once::Once::call_once (line 90) ... ok Dec 14 18:50:20.019 INFO [stdout] test src/mutex.rs - mutex::Mutex::new (line 98) ... ok Dec 14 18:50:20.721 INFO [stdout] test src/mutex.rs - mutex::Mutex (line 20) ... ok Dec 14 18:50:20.721 INFO [stdout] test src/mutex.rs - mutex::Mutex::lock (line 172) ... ok Dec 14 18:50:20.739 INFO [stdout] test src/once.rs - once::Once (line 12) ... ok Dec 14 18:50:20.954 INFO [stdout] test src/mutex.rs - mutex::Mutex (line 42) ... ok Dec 14 18:50:20.999 INFO [stdout] test src/rw_lock.rs - rw_lock::RwLock (line 25) ... ok Dec 14 18:50:21.479 INFO [stdout] test src/rw_lock.rs - rw_lock::RwLock::new (line 84) ... ok Dec 14 18:50:23.043 INFO [stdout] test src/rw_lock.rs - rw_lock::RwLock::read (line 156) ... ok Dec 14 18:50:23.627 INFO [stdout] test src/rw_lock.rs - rw_lock::RwLock::try_read (line 207) ... ok Dec 14 18:50:24.055 INFO [stdout] test src/rw_lock.rs - rw_lock::RwLock::write (line 271) ... ok Dec 14 18:50:24.099 INFO [stdout] test src/rw_lock.rs - rw_lock::RwLock::try_write (line 312) ... ok Dec 14 18:50:24.099 INFO [stdout] Dec 14 18:50:24.099 INFO [stdout] test result: ok. 12 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 14 18:50:24.099 INFO [stdout] Dec 14 18:50:24.143 INFO [stderr] su: No module specific data is present Dec 14 18:50:25.012 INFO running `"docker" "inspect" "8dd12c720a9c91ef84d595ce3d788fab49a3f0467e87621287dfde17f5d58524"` Dec 14 18:50:25.335 INFO running `"docker" "rm" "-f" "8dd12c720a9c91ef84d595ce3d788fab49a3f0467e87621287dfde17f5d58524"` Dec 14 18:50:25.619 INFO [stdout] 8dd12c720a9c91ef84d595ce3d788fab49a3f0467e87621287dfde17f5d58524