Nov 17 20:35:55.859 INFO testing timely_sort-0.1.6 against master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c for pr-55982
Nov 17 20:35:55.860 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-2/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/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 20:35:56.156 INFO [stdout] 43effee4ec4e2ac53a319f277bd0900f0889852e784b8605dfb0fe3b038e9d9b
Nov 17 20:35:56.161 INFO running `"docker" "start" "-a" "43effee4ec4e2ac53a319f277bd0900f0889852e784b8605dfb0fe3b038e9d9b"`
Nov 17 20:35:56.551 INFO [stderr] usermod: no changes
Nov 17 20:35:56.586 INFO [stderr]    Compiling timely_sort v0.1.6 (/source)
Nov 17 20:35:56.933 INFO [stderr] warning: unused macro definition
Nov 17 20:35:56.933 INFO [stderr]   --> src/msb.rs:22:1
Nov 17 20:35:56.933 INFO [stderr]    |
Nov 17 20:35:56.933 INFO [stderr] 22 | / macro_rules! per_cache_line {
Nov 17 20:35:56.933 INFO [stderr] 23 | |     ($t:ty) => {{ ::std::cmp::min(64 / ::std::mem::size_of::<$t>(), 4) }}
Nov 17 20:35:56.933 INFO [stderr] 24 | | }
Nov 17 20:35:56.933 INFO [stderr]    | |_^
Nov 17 20:35:56.933 INFO [stderr]    |
Nov 17 20:35:56.933 INFO [stderr]    = note: #[warn(unused_macros)] on by default
Nov 17 20:35:56.933 INFO [stderr] 
Nov 17 20:35:56.933 INFO [stderr] warning: unused macro definition
Nov 17 20:35:56.933 INFO [stderr]   --> src/msb.rs:26:1
Nov 17 20:35:56.933 INFO [stderr]    |
Nov 17 20:35:56.933 INFO [stderr] 26 | / macro_rules! lines_per_page {
Nov 17 20:35:56.933 INFO [stderr] 27 | |     () => {{ 1024 }}
Nov 17 20:35:56.933 INFO [stderr] 28 | | }
Nov 17 20:35:56.933 INFO [stderr]    | |_^
Nov 17 20:35:56.933 INFO [stderr] 
Nov 17 20:35:57.128 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.56s
Nov 17 20:35:57.132 INFO [stderr] su: No module specific data is present
Nov 17 20:35:57.353 INFO running `"docker" "inspect" "43effee4ec4e2ac53a319f277bd0900f0889852e784b8605dfb0fe3b038e9d9b"`
Nov 17 20:35:57.405 INFO running `"docker" "rm" "-f" "43effee4ec4e2ac53a319f277bd0900f0889852e784b8605dfb0fe3b038e9d9b"`
Nov 17 20:35:57.531 INFO [stdout] 43effee4ec4e2ac53a319f277bd0900f0889852e784b8605dfb0fe3b038e9d9b
Nov 17 20:35:57.532 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-2/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/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 20:35:57.783 INFO [stdout] ee61979939367cc4008a256b2e33d0160cdecf8683e11d67da42126a57348767
Nov 17 20:35:57.784 INFO running `"docker" "start" "-a" "ee61979939367cc4008a256b2e33d0160cdecf8683e11d67da42126a57348767"`
Nov 17 20:35:58.183 INFO [stderr] usermod: no changes
Nov 17 20:35:58.213 INFO [stderr]    Compiling timely_sort v0.1.6 (/source)
Nov 17 20:35:58.586 INFO [stderr] warning: unused macro definition
Nov 17 20:35:58.586 INFO [stderr]   --> src/msb.rs:22:1
Nov 17 20:35:58.587 INFO [stderr]    |
Nov 17 20:35:58.587 INFO [stderr] 22 | / macro_rules! per_cache_line {
Nov 17 20:35:58.587 INFO [stderr] 23 | |     ($t:ty) => {{ ::std::cmp::min(64 / ::std::mem::size_of::<$t>(), 4) }}
Nov 17 20:35:58.588 INFO [stderr] 24 | | }
Nov 17 20:35:58.588 INFO [stderr]    | |_^
Nov 17 20:35:58.588 INFO [stderr]    |
Nov 17 20:35:58.589 INFO [stderr]    = note: #[warn(unused_macros)] on by default
Nov 17 20:35:58.589 INFO [stderr] 
Nov 17 20:35:58.589 INFO [stderr] warning: unused macro definition
Nov 17 20:35:58.590 INFO [stderr]   --> src/msb.rs:26:1
Nov 17 20:35:58.590 INFO [stderr]    |
Nov 17 20:35:58.590 INFO [stderr] 26 | / macro_rules! lines_per_page {
Nov 17 20:35:58.591 INFO [stderr] 27 | |     () => {{ 1024 }}
Nov 17 20:35:58.591 INFO [stderr] 28 | | }
Nov 17 20:35:58.591 INFO [stderr]    | |_^
Nov 17 20:35:58.591 INFO [stderr] 
Nov 17 20:35:59.631 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1.41s
Nov 17 20:35:59.631 INFO [stderr] su: No module specific data is present
Nov 17 20:35:59.984 INFO running `"docker" "inspect" "ee61979939367cc4008a256b2e33d0160cdecf8683e11d67da42126a57348767"`
Nov 17 20:36:00.063 INFO running `"docker" "rm" "-f" "ee61979939367cc4008a256b2e33d0160cdecf8683e11d67da42126a57348767"`
Nov 17 20:36:00.183 INFO [stdout] ee61979939367cc4008a256b2e33d0160cdecf8683e11d67da42126a57348767
Nov 17 20:36:00.186 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-2/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/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 20:36:00.375 INFO [stdout] 713fdd538741c3dc085b6e3d8eade0e2081f73b89682813645b7ab4e70c2fbee
Nov 17 20:36:00.379 INFO running `"docker" "start" "-a" "713fdd538741c3dc085b6e3d8eade0e2081f73b89682813645b7ab4e70c2fbee"`
Nov 17 20:36:00.895 INFO [stderr] usermod: no changes
Nov 17 20:36:00.945 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.02s
Nov 17 20:36:00.945 INFO [stderr]      Running /target/debug/deps/timely_sort-b6c341bbed8af958
Nov 17 20:36:00.951 INFO [stdout] 
Nov 17 20:36:00.951 INFO [stdout] running 5 tests
Nov 17 20:36:00.977 INFO [stdout] test lsb_swc::test::test1 ... ok
Nov 17 20:36:02.467 INFO [stdout] test lsb_swc::test::test_large ... FAILED
Nov 17 20:36:03.979 INFO [stdout] test msb::test::test_msb ... ok
Nov 17 20:36:05.965 INFO [stdout] test lsb::test::test1 ... ok
Nov 17 20:36:08.987 INFO [stdout] test lsb::test::test_large ... ok
Nov 17 20:36:08.987 INFO [stdout] 
Nov 17 20:36:08.987 INFO [stdout] failures:
Nov 17 20:36:08.987 INFO [stdout] 
Nov 17 20:36:08.987 INFO [stdout] ---- lsb_swc::test::test_large stdout ----
Nov 17 20:36:08.987 INFO [stdout] thread 'lsb_swc::test::test_large' panicked at 'assertion failed: `(left == right)`
Nov 17 20:36:08.987 INFO [stdout]   left: `16`,
Nov 17 20:36:08.987 INFO [stdout]  right: `0`', src/lsb_swc.rs:81:9
Nov 17 20:36:08.987 INFO [stdout] stack backtrace:
Nov 17 20:36:08.987 INFO [stdout]    0:     0x55af635dd883 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h1a944b80a560daa8
Nov 17 20:36:08.987 INFO [stdout]                                at libstd/sys/unix/backtrace/tracing/gcc_s.rs:49
Nov 17 20:36:08.987 INFO [stdout]    1:     0x55af635d7fa8 - std::sys_common::backtrace::_print::h18f735b4c3a49bdb
Nov 17 20:36:08.987 INFO [stdout]                                at libstd/sys_common/backtrace.rs:71
Nov 17 20:36:08.987 INFO [stdout]    2:     0x55af635dc7f4 - std::panicking::default_hook::{{closure}}::h97ab3548a215c097
Nov 17 20:36:08.987 INFO [stdout]                                at libstd/sys_common/backtrace.rs:59
Nov 17 20:36:08.987 INFO [stdout]                                at libstd/panicking.rs:211
Nov 17 20:36:08.987 INFO [stdout]    3:     0x55af635dc4dc - std::panicking::default_hook::h98c2a01ef734e4b0
Nov 17 20:36:08.987 INFO [stdout]                                at libstd/panicking.rs:221
Nov 17 20:36:08.987 INFO [stdout]    4:     0x55af635dcf00 - std::panicking::rust_panic_with_hook::hb4b3f28eb79ea752
Nov 17 20:36:08.987 INFO [stdout]                                at libstd/panicking.rs:476
Nov 17 20:36:08.987 INFO [stdout]    5:     0x55af635dca81 - std::panicking::continue_panic_fmt::h61fd5a7b1a268752
Nov 17 20:36:08.987 INFO [stdout]                                at libstd/panicking.rs:390
Nov 17 20:36:08.987 INFO [stdout]    6:     0x55af635dc9ce - std::panicking::begin_panic_fmt::hfa6f19d29682fcb6
Nov 17 20:36:08.987 INFO [stdout]                                at libstd/panicking.rs:345
Nov 17 20:36:08.987 INFO [stdout]    7:     0x55af63583b59 - <timely_sort::lsb_swc::Shuffler<T>>::new::h704aee2de6785036
Nov 17 20:36:08.987 INFO [stdout]                                at src/lsb_swc.rs:81
Nov 17 20:36:08.987 INFO [stdout]    8:     0x55af6358319f - <timely_sort::lsb_swc::Sorter<T> as timely_sort::RadixSorterBase<T>>::new::he81a177936b0f73d
Nov 17 20:36:08.988 INFO [stdout]                                at src/lsb_swc.rs:45
Nov 17 20:36:08.988 INFO [stdout]    9:     0x55af6357005c - timely_sort::lsb_swc::test::test_large::h1c7e42a6d1ffeab8
Nov 17 20:36:08.988 INFO [stdout]                                at src/lsb_swc.rs:197
Nov 17 20:36:08.988 INFO [stdout]   10:     0x55af6356fdd9 - timely_sort::lsb_swc::test::test_large::{{closure}}::h1e1188830a1caab3
Nov 17 20:36:08.988 INFO [stdout]                                at src/lsb_swc.rs:184
Nov 17 20:36:08.988 INFO [stdout]   11:     0x55af63573b2d - core::ops::function::FnOnce::call_once::h4ddc6271446f8c5c
Nov 17 20:36:08.988 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libcore/ops/function.rs:238
Nov 17 20:36:08.988 INFO [stdout]   12:     0x55af6359987e - <F as alloc::boxed::FnBox<A>>::call_box::h9dcb7fc9b8bb4240
Nov 17 20:36:08.988 INFO [stdout]                                at libtest/lib.rs:1471
Nov 17 20:36:08.988 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libcore/ops/function.rs:238
Nov 17 20:36:08.988 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/liballoc/boxed.rs:673
Nov 17 20:36:08.988 INFO [stdout]   13:     0x55af635eb5d9 - __rust_maybe_catch_panic
Nov 17 20:36:08.988 INFO [stdout]                                at libpanic_unwind/lib.rs:102
Nov 17 20:36:08.988 INFO [stdout]   14:     0x55af635918e3 - std::sys_common::backtrace::__rust_begin_short_backtrace::h89ea8afe71e36f86
Nov 17 20:36:08.988 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panicking.rs:289
Nov 17 20:36:08.988 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panic.rs:398
Nov 17 20:36:08.988 INFO [stdout]                                at libtest/lib.rs:1426
Nov 17 20:36:08.988 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/sys_common/backtrace.rs:136
Nov 17 20:36:08.988 INFO [stdout]   15:     0x55af63592794 - std::panicking::try::do_call::h4132042e0cbea3ae
Nov 17 20:36:08.988 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/thread/mod.rs:477
Nov 17 20:36:08.988 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panic.rs:319
Nov 17 20:36:08.988 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panicking.rs:310
Nov 17 20:36:08.988 INFO [stdout]   16:     0x55af635eb5d9 - __rust_maybe_catch_panic
Nov 17 20:36:08.988 INFO [stdout]                                at libpanic_unwind/lib.rs:102
Nov 17 20:36:08.988 INFO [stdout]   17:     0x55af635999ac - <F as alloc::boxed::FnBox<A>>::call_box::hab7f616f05880bf6
Nov 17 20:36:08.988 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panicking.rs:289
Nov 17 20:36:08.988 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panic.rs:398
Nov 17 20:36:08.988 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/thread/mod.rs:476
Nov 17 20:36:08.988 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/liballoc/boxed.rs:673
Nov 17 20:36:08.988 INFO [stdout]   18:     0x55af635df4bd - std::sys::unix::thread::Thread::new::thread_start::h5f8d67158a1ca038
Nov 17 20:36:08.988 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/liballoc/boxed.rs:683
Nov 17 20:36:08.988 INFO [stdout]                                at libstd/sys_common/thread.rs:24
Nov 17 20:36:08.988 INFO [stdout]                                at libstd/sys/unix/thread.rs:90
Nov 17 20:36:08.988 INFO [stdout]   19:     0x7f45972116b9 - start_thread
Nov 17 20:36:08.988 INFO [stdout]   20:     0x7f4596d3141c - clone
Nov 17 20:36:08.988 INFO [stdout]   21:                0x0 - <unknown>
Nov 17 20:36:08.988 INFO [stdout] 
Nov 17 20:36:08.988 INFO [stdout] 
Nov 17 20:36:08.988 INFO [stdout] failures:
Nov 17 20:36:08.988 INFO [stdout]     lsb_swc::test::test_large
Nov 17 20:36:08.988 INFO [stdout] 
Nov 17 20:36:08.988 INFO [stdout] test result: FAILED. 4 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 20:36:08.988 INFO [stdout] 
Nov 17 20:36:09.012 INFO [stderr] error: test failed, to rerun pass '--lib'
Nov 17 20:36:09.012 INFO [stderr] su: No module specific data is present
Nov 17 20:36:09.232 INFO running `"docker" "inspect" "713fdd538741c3dc085b6e3d8eade0e2081f73b89682813645b7ab4e70c2fbee"`
Nov 17 20:36:09.316 INFO running `"docker" "rm" "-f" "713fdd538741c3dc085b6e3d8eade0e2081f73b89682813645b7ab4e70c2fbee"`
Nov 17 20:36:09.390 INFO [stdout] 713fdd538741c3dc085b6e3d8eade0e2081f73b89682813645b7ab4e70c2fbee