Dec 06 21:55:09.135 INFO testing spin-0.4.10 against master#906deae0790bd18681b937fe9a141a3c26cf1855 for pr-56514 Dec 06 21:55:09.135 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-1/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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.400 INFO [stdout] 3f367c12b8a5ecf5bc3828a77865fb736215677d99c219a82ef1783717742668 Dec 06 21:55:09.404 INFO running `"docker" "start" "-a" "3f367c12b8a5ecf5bc3828a77865fb736215677d99c219a82ef1783717742668"` Dec 06 21:55:10.161 INFO [stderr] usermod: no changes Dec 06 21:55:10.212 INFO [stderr] Compiling spin v0.4.10 (/source) Dec 06 21:55:10.491 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 0.31s Dec 06 21:55:10.494 INFO [stderr] su: No module specific data is present Dec 06 21:55:10.902 INFO running `"docker" "inspect" "3f367c12b8a5ecf5bc3828a77865fb736215677d99c219a82ef1783717742668"` Dec 06 21:55:11.158 INFO running `"docker" "rm" "-f" "3f367c12b8a5ecf5bc3828a77865fb736215677d99c219a82ef1783717742668"` Dec 06 21:55:11.554 INFO [stdout] 3f367c12b8a5ecf5bc3828a77865fb736215677d99c219a82ef1783717742668 Dec 06 21:55:11.560 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-1/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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.912 INFO [stdout] e5a9504b7e7c17c7203f121caf8c901ef484a9d3bef9e517c6e83382e5d4ee68 Dec 06 21:55:11.914 INFO running `"docker" "start" "-a" "e5a9504b7e7c17c7203f121caf8c901ef484a9d3bef9e517c6e83382e5d4ee68"` Dec 06 21:55:13.008 INFO [stderr] usermod: no changes Dec 06 21:55:13.088 INFO [stderr] Compiling spin v0.4.10 (/source) Dec 06 21:55:15.994 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 2.96s Dec 06 21:55:16.004 INFO [stderr] su: No module specific data is present Dec 06 21:55:16.590 INFO running `"docker" "inspect" "e5a9504b7e7c17c7203f121caf8c901ef484a9d3bef9e517c6e83382e5d4ee68"` Dec 06 21:55:16.865 INFO running `"docker" "rm" "-f" "e5a9504b7e7c17c7203f121caf8c901ef484a9d3bef9e517c6e83382e5d4ee68"` Dec 06 21:55:17.100 INFO [stdout] e5a9504b7e7c17c7203f121caf8c901ef484a9d3bef9e517c6e83382e5d4ee68 Dec 06 21:55:17.108 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-1/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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.550 INFO [stdout] 0f1f3f92f2a71fb88048660f0a670a7dccbed7299158dd309e2c26239152fb17 Dec 06 21:55:17.554 INFO running `"docker" "start" "-a" "0f1f3f92f2a71fb88048660f0a670a7dccbed7299158dd309e2c26239152fb17"` Dec 06 21:55:18.562 INFO [stderr] usermod: no changes Dec 06 21:55:18.608 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 0.02s Dec 06 21:55:18.608 INFO [stderr] Running /target/debug/deps/spin-67f6a2469d850624 Dec 06 21:55:18.624 INFO [stdout] Dec 06 21:55:18.624 INFO [stdout] running 26 tests Dec 06 21:55:18.628 INFO [stdout] test mutex::tests::smoke ... ok Dec 06 21:55:18.628 INFO [stdout] test mutex::tests::test_into_inner ... ok Dec 06 21:55:18.628 INFO [stdout] test mutex::tests::test_into_inner_drop ... ok Dec 06 21:55:18.628 INFO [stdout] test mutex::tests::test_mutex_unsized ... ok Dec 06 21:55:18.628 INFO [stdout] test mutex::tests::test_mutex_arc_nested ... ok Dec 06 21:55:18.628 INFO [stdout] test mutex::tests::try_lock ... ok Dec 06 21:55:18.628 INFO [stdout] test once::tests::init_constant ... ok Dec 06 21:55:18.632 INFO [stderr] thread '' panicked at 'explicit panic', src/mutex.rs:386:13 Dec 06 21:55:18.632 INFO [stdout] test mutex::tests::test_mutex_force_lock ... ok Dec 06 21:55:18.632 INFO [stdout] test mutex::tests::lots_and_lots ... ok Dec 06 21:55:18.632 INFO [stdout] test once::tests::smoke_once ... ok Dec 06 21:55:18.632 INFO [stdout] test once::tests::smoke_once_value ... ok Dec 06 21:55:18.632 INFO [stdout] test once::tests::try ... ok Dec 06 21:55:18.632 INFO [stdout] test once::tests::try_no_wait ... ok Dec 06 21:55:18.632 INFO [stdout] test once::tests::wait ... ok Dec 06 21:55:18.640 INFO [stdout] test rw_lock::tests::smoke ... ok Dec 06 21:55:18.640 INFO [stdout] test rw_lock::tests::test_force_read_decrement ... ok Dec 06 21:55:18.640 INFO [stdout] test rw_lock::tests::test_force_write_unlock ... ok Dec 06 21:55:18.642 INFO [stdout] test rw_lock::tests::test_rwlock_try_write ... ok Dec 06 21:55:18.642 INFO [stdout] test rw_lock::tests::test_into_inner ... ok Dec 06 21:55:18.642 INFO [stdout] test rw_lock::tests::test_rwlock_unsized ... ok Dec 06 21:55:18.642 INFO [stdout] test rw_lock::tests::test_into_inner_drop ... ok Dec 06 21:55:18.644 INFO [stdout] test rw_lock::tests::test_rw_arc ... ok Dec 06 21:55:18.652 INFO [stderr] thread '' panicked at 'explicit panic', src/rw_lock.rs:493:13 Dec 06 21:55:18.664 INFO [stdout] test once::tests::stampede_once ... ok Dec 06 21:55:18.676 INFO [stdout] test once::tests::panic ... ok Dec 06 21:55:18.704 INFO [stderr] stack backtrace: Dec 06 21:55:18.704 INFO [stderr] 0: 0x560a0cc95883 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h3c2ada0a2db8c5e7 Dec 06 21:55:18.704 INFO [stderr] at src/libstd/sys/unix/backtrace/tracing/gcc_s.rs:49 Dec 06 21:55:18.704 INFO [stderr] 1: 0x560a0cc8ffa8 - std::sys_common::backtrace::_print::h95865c77318e98b7 Dec 06 21:55:18.704 INFO [stderr] at src/libstd/sys_common/backtrace.rs:71 Dec 06 21:55:18.704 INFO [stderr] 2: 0x560a0cc947f2 - std::panicking::default_hook::{{closure}}::hb3d1f299fcea42e5 Dec 06 21:55:18.704 INFO [stderr] at src/libstd/sys_common/backtrace.rs:59 Dec 06 21:55:18.704 INFO [stderr] at src/libstd/panicking.rs:211 Dec 06 21:55:18.704 INFO [stderr] 3: 0x560a0cc9455d - std::panicking::default_hook::h4eae6f26f73244c8 Dec 06 21:55:18.704 INFO [stderr] at src/libstd/panicking.rs:227 Dec 06 21:55:18.704 INFO [stderr] 4: 0x560a0cc94f00 - std::panicking::rust_panic_with_hook::h368d37fd53b59464 Dec 06 21:55:18.704 INFO [stderr] at src/libstd/panicking.rs:491 Dec 06 21:55:18.704 INFO [stderr] 5: 0x560a0cc1f253 - std::panicking::begin_panic::haa6c5f3909d34a64 Dec 06 21:55:18.704 INFO [stderr] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/panicking.rs:425 Dec 06 21:55:18.704 INFO [stderr] 6: 0x560a0cc1ecea - spin::mutex::tests::test_mutex_arc_access_in_unwind::{{closure}}::hccce93f9cdc9e925 Dec 06 21:55:18.704 INFO [stderr] at src/mutex.rs:386 Dec 06 21:55:18.704 INFO [stderr] 7: 0x560a0cc23752 - std::sys_common::backtrace::__rust_begin_short_backtrace::hcbb0e958f548a4fd Dec 06 21:55:18.705 INFO [stderr] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/sys_common/backtrace.rs:136 Dec 06 21:55:18.705 INFO [stderr] 8: 0x560a0cc397cd - std::thread::Builder::spawn_unchecked::{{closure}}::{{closure}}::ha6d6d22283ff1629 Dec 06 21:55:18.705 INFO [stderr] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/thread/mod.rs:477 Dec 06 21:55:18.705 INFO [stderr] 9: 0x560a0cc187c0 - as core::ops::function::FnOnce<()>>::call_once::h743f6866193927b3 Dec 06 21:55:18.705 INFO [stderr] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/panic.rs:319 Dec 06 21:55:18.705 INFO [stderr] 10: 0x560a0cc1fe14 - std::panicking::try::do_call::hc7baad16357ab4a1 Dec 06 21:55:18.705 INFO [stderr] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/panicking.rs:310 Dec 06 21:55:18.705 INFO [stderr] 11: 0x560a0cca3569 - __rust_maybe_catch_panic Dec 06 21:55:18.705 INFO [stderr] at src/libpanic_unwind/lib.rs:102 Dec 06 21:55:18.705 INFO [stderr] 12: 0x560a0cc1f93d - std::panicking::try::ha9f5f8a4ee8bbfd9 Dec 06 21:55:18.705 INFO [stderr] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/panicking.rs:289 Dec 06 21:55:18.705 INFO [stderr] 13: 0x560a0cc189d2 - std::panic::catch_unwind::h1f54d9e2098d1a4c Dec 06 21:55:18.705 INFO [stderr] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/panic.rs:398 Dec 06 21:55:18.705 INFO [stderr] 14: 0x560a0cc383e5 - std::thread::Builder::spawn_unchecked::{{closure}}::hbdf0458e36b717e9 Dec 06 21:55:18.705 INFO [stderr] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/thread/mod.rs:476 Dec 06 21:55:18.705 INFO [stderr] 15: 0x560a0cc3a046 - >::call_box::h750bea9018895cb5 Dec 06 21:55:18.705 INFO [stderr] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/liballoc/boxed.rs:673 Dec 06 21:55:18.705 INFO [stderr] 16: 0x560a0cc9748d - std::sys::unix::thread::Thread::new::thread_start::hea5f7f9c570dc4cd Dec 06 21:55:18.705 INFO [stderr] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/liballoc/boxed.rs:683 Dec 06 21:55:18.705 INFO [stderr] at src/libstd/sys_common/thread.rs:24 Dec 06 21:55:18.705 INFO [stderr] at src/libstd/sys/unix/thread.rs:90 Dec 06 21:55:18.705 INFO [stderr] 17: 0x7f6243d0c6b9 - start_thread Dec 06 21:55:18.705 INFO [stderr] 18: 0x7f624382c41c - clone Dec 06 21:55:18.705 INFO [stderr] 19: 0x0 - Dec 06 21:55:18.708 INFO [stderr] stack backtrace: Dec 06 21:55:18.708 INFO [stdout] test mutex::tests::test_mutex_arc_access_in_unwind ... ok Dec 06 21:55:18.708 INFO [stderr] 0: 0x560a0cc95883 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h3c2ada0a2db8c5e7 Dec 06 21:55:18.708 INFO [stderr] at src/libstd/sys/unix/backtrace/tracing/gcc_s.rs:49 Dec 06 21:55:18.708 INFO [stderr] 1: 0x560a0cc8ffa8 - std::sys_common::backtrace::_print::h95865c77318e98b7 Dec 06 21:55:18.708 INFO [stderr] at src/libstd/sys_common/backtrace.rs:71 Dec 06 21:55:18.708 INFO [stderr] 2: 0x560a0cc947f2 - std::panicking::default_hook::{{closure}}::hb3d1f299fcea42e5 Dec 06 21:55:18.708 INFO [stderr] at src/libstd/sys_common/backtrace.rs:59 Dec 06 21:55:18.708 INFO [stderr] at src/libstd/panicking.rs:211 Dec 06 21:55:18.708 INFO [stderr] 3: 0x560a0cc9455d - std::panicking::default_hook::h4eae6f26f73244c8 Dec 06 21:55:18.708 INFO [stderr] at src/libstd/panicking.rs:227 Dec 06 21:55:18.708 INFO [stderr] 4: 0x560a0cc94f00 - std::panicking::rust_panic_with_hook::h368d37fd53b59464 Dec 06 21:55:18.708 INFO [stderr] at src/libstd/panicking.rs:491 Dec 06 21:55:18.708 INFO [stderr] 5: 0x560a0cc1f253 - std::panicking::begin_panic::haa6c5f3909d34a64 Dec 06 21:55:18.708 INFO [stderr] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/panicking.rs:425 Dec 06 21:55:18.709 INFO [stderr] 6: 0x560a0cc2930a - spin::rw_lock::tests::test_rw_arc_access_in_unwind::{{closure}}::h8c780d5232db9f69 Dec 06 21:55:18.709 INFO [stderr] at src/rw_lock.rs:493 Dec 06 21:55:18.709 INFO [stderr] 7: 0x560a0cc23712 - std::sys_common::backtrace::__rust_begin_short_backtrace::h6a83b249a6fb0290 Dec 06 21:55:18.709 INFO [stderr] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/sys_common/backtrace.rs:136 Dec 06 21:55:18.709 INFO [stderr] 8: 0x560a0cc3978d - std::thread::Builder::spawn_unchecked::{{closure}}::{{closure}}::h276b3723e971da80 Dec 06 21:55:18.709 INFO [stderr] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/thread/mod.rs:477 Dec 06 21:55:18.709 INFO [stderr] 9: 0x560a0cc187a0 - as core::ops::function::FnOnce<()>>::call_once::h6479c8d86afbb0b7 Dec 06 21:55:18.709 INFO [stderr] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/panic.rs:319 Dec 06 21:55:18.709 INFO [stderr] 10: 0x560a0cc1ff14 - std::panicking::try::do_call::he302c099b17b3c5a Dec 06 21:55:18.709 INFO [stderr] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/panicking.rs:310 Dec 06 21:55:18.709 INFO [stderr] 11: 0x560a0cca3569 - __rust_maybe_catch_panic Dec 06 21:55:18.709 INFO [stderr] at src/libpanic_unwind/lib.rs:102 Dec 06 21:55:18.709 INFO [stderr] 12: 0x560a0cc1f48d - std::panicking::try::h22ef45d518e9246d Dec 06 21:55:18.709 INFO [stderr] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/panicking.rs:289 Dec 06 21:55:18.709 INFO [stderr] 13: 0x560a0cc18b02 - std::panic::catch_unwind::he78cac37168e7a55 Dec 06 21:55:18.709 INFO [stderr] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/panic.rs:398 Dec 06 21:55:18.709 INFO [stderr] 14: 0x560a0cc380f5 - std::thread::Builder::spawn_unchecked::{{closure}}::h594f1cfaeb9af5fc Dec 06 21:55:18.709 INFO [stderr] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/thread/mod.rs:476 Dec 06 21:55:18.709 INFO [stderr] 15: 0x560a0cc3a166 - >::call_box::hec2a6831e29b683f Dec 06 21:55:18.709 INFO [stderr] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/liballoc/boxed.rs:673 Dec 06 21:55:18.709 INFO [stderr] 16: 0x560a0cc9748d - std::sys::unix::thread::Thread::new::thread_start::hea5f7f9c570dc4cd Dec 06 21:55:18.709 INFO [stderr] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/liballoc/boxed.rs:683 Dec 06 21:55:18.709 INFO [stderr] at src/libstd/sys_common/thread.rs:24 Dec 06 21:55:18.709 INFO [stderr] at src/libstd/sys/unix/thread.rs:90 Dec 06 21:55:18.709 INFO [stderr] 17: 0x7f6243d0c6b9 - start_thread Dec 06 21:55:18.709 INFO [stderr] 18: 0x7f624382c41c - clone Dec 06 21:55:18.709 INFO [stderr] 19: 0x0 - Dec 06 21:55:18.716 INFO [stderr] Doc-tests spin Dec 06 21:55:18.716 INFO [stdout] test rw_lock::tests::test_rw_arc_access_in_unwind ... ok Dec 06 21:55:18.716 INFO [stdout] Dec 06 21:55:18.716 INFO [stdout] test result: ok. 26 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 06 21:55:18.716 INFO [stdout] Dec 06 21:55:18.821 INFO [stdout] Dec 06 21:55:18.821 INFO [stdout] running 12 tests Dec 06 21:55:20.909 INFO [stdout] test src/once.rs - once::Once::call_once (line 90) ... ok Dec 06 21:55:22.018 INFO [stdout] test src/rw_lock.rs - rw_lock::RwLock::new (line 84) ... ok Dec 06 21:55:22.594 INFO [stdout] test src/once.rs - once::Once (line 12) ... ok Dec 06 21:55:23.220 INFO [stdout] test src/mutex.rs - mutex::Mutex::new (line 98) ... ok Dec 06 21:55:23.476 INFO [stdout] test src/mutex.rs - mutex::Mutex (line 20) ... ok Dec 06 21:55:23.478 INFO [stdout] test src/mutex.rs - mutex::Mutex::lock (line 172) ... ok Dec 06 21:55:24.116 INFO [stdout] test src/rw_lock.rs - rw_lock::RwLock (line 25) ... ok Dec 06 21:55:24.796 INFO [stdout] test src/mutex.rs - mutex::Mutex (line 42) ... ok Dec 06 21:55:24.818 INFO [stdout] test src/rw_lock.rs - rw_lock::RwLock::read (line 156) ... ok Dec 06 21:55:25.320 INFO [stdout] test src/rw_lock.rs - rw_lock::RwLock::try_read (line 207) ... ok Dec 06 21:55:25.700 INFO [stdout] test src/rw_lock.rs - rw_lock::RwLock::try_write (line 312) ... ok Dec 06 21:55:25.786 INFO [stdout] test src/rw_lock.rs - rw_lock::RwLock::write (line 271) ... ok Dec 06 21:55:25.786 INFO [stdout] Dec 06 21:55:25.786 INFO [stdout] test result: ok. 12 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 06 21:55:25.786 INFO [stdout] Dec 06 21:55:25.816 INFO [stderr] su: No module specific data is present Dec 06 21:55:26.636 INFO running `"docker" "inspect" "0f1f3f92f2a71fb88048660f0a670a7dccbed7299158dd309e2c26239152fb17"` Dec 06 21:55:26.952 INFO running `"docker" "rm" "-f" "0f1f3f92f2a71fb88048660f0a670a7dccbed7299158dd309e2c26239152fb17"` Dec 06 21:55:27.252 INFO [stdout] 0f1f3f92f2a71fb88048660f0a670a7dccbed7299158dd309e2c26239152fb17