Nov 01 11:56:27.117 INFO testing davidpeklak/rust-algorithms against beta-2018-10-30 for beta-1.31-1
Nov 01 11:56:27.117 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-1/worker-0/beta-2018-10-30:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/beta-1.31-1/beta-2018-10-30:/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 +beta-2018-10-30 build --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Nov 01 11:56:27.446 INFO blam! 915a38d13d1d0f8729c696d09fd81fa9c5570e2d12cc49c3413c0f007e1a7249
Nov 01 11:56:27.448 INFO running `"docker" "start" "-a" "915a38d13d1d0f8729c696d09fd81fa9c5570e2d12cc49c3413c0f007e1a7249"`
Nov 01 11:56:27.800 INFO kablam! usermod: no changes
Nov 01 11:56:27.866 INFO kablam!    Compiling num-bigint v0.1.44
Nov 01 11:56:31.211 INFO kablam!    Compiling num-rational v0.1.42
Nov 01 11:56:33.219 INFO kablam!    Compiling num v0.1.42
Nov 01 11:56:33.738 INFO kablam!    Compiling stopwatch v0.0.7
Nov 01 11:56:34.539 INFO kablam!    Compiling algorithms v0.1.0 (/source)
Nov 01 11:56:37.697 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 9.88s
Nov 01 11:56:37.702 INFO kablam! su: No module specific data is present
Nov 01 11:56:38.603 INFO running `"docker" "rm" "-f" "915a38d13d1d0f8729c696d09fd81fa9c5570e2d12cc49c3413c0f007e1a7249"`
Nov 01 11:56:38.863 INFO blam! 915a38d13d1d0f8729c696d09fd81fa9c5570e2d12cc49c3413c0f007e1a7249
Nov 01 11:56:38.873 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-1/worker-0/beta-2018-10-30:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/beta-1.31-1/beta-2018-10-30:/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 +beta-2018-10-30 test --frozen --no-run" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Nov 01 11:56:39.431 INFO blam! 68fa373208d47a796c57c5f674bd1078e3f8c183c6233482b138f14bd1618fc8
Nov 01 11:56:39.439 INFO running `"docker" "start" "-a" "68fa373208d47a796c57c5f674bd1078e3f8c183c6233482b138f14bd1618fc8"`
Nov 01 11:56:40.498 INFO kablam! usermod: no changes
Nov 01 11:56:40.583 INFO kablam!    Compiling algorithms v0.1.0 (/source)
Nov 01 11:56:50.474 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 9.93s
Nov 01 11:56:50.482 INFO kablam! su: No module specific data is present
Nov 01 11:56:51.259 INFO running `"docker" "rm" "-f" "68fa373208d47a796c57c5f674bd1078e3f8c183c6233482b138f14bd1618fc8"`
Nov 01 11:56:51.574 INFO blam! 68fa373208d47a796c57c5f674bd1078e3f8c183c6233482b138f14bd1618fc8
Nov 01 11:56:51.576 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-1/worker-0/beta-2018-10-30:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/beta-1.31-1/beta-2018-10-30:/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 +beta-2018-10-30 test --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Nov 01 11:56:51.972 INFO blam! 2698fed07a42f1f053357c8199d33baa9b5cc779cc870a9e2ba54bf7a54cf6c6
Nov 01 11:56:51.979 INFO running `"docker" "start" "-a" "2698fed07a42f1f053357c8199d33baa9b5cc779cc870a9e2ba54bf7a54cf6c6"`
Nov 01 11:56:52.569 INFO kablam! usermod: no changes
Nov 01 11:56:52.657 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 0.06s
Nov 01 11:56:52.661 INFO kablam!      Running /target/debug/deps/algorithms-8152bf4a6bc84c3f
Nov 01 11:56:52.665 INFO blam! 
Nov 01 11:56:52.665 INFO blam! running 50 tests
Nov 01 11:56:52.666 INFO blam! test deque::tests::fifo_right ... ok
Nov 01 11:56:52.666 INFO blam! test deque::tests::add_first_and_query ... ok
Nov 01 11:56:52.666 INFO blam! test deque::tests::sizes ... ok
Nov 01 11:56:52.668 INFO blam! test index_ops::test::vec_length ... ok
Nov 01 11:56:52.675 INFO blam! test deque::tests::add_last_and_query ... ok
Nov 01 11:56:52.675 INFO blam! test deque::tests::filo_left ... ok
Nov 01 11:56:52.675 INFO blam! test deque::tests::iter ... ok
Nov 01 11:56:52.675 INFO blam! test insertion_sort::test::small_example ... ok
Nov 01 11:56:52.675 INFO blam! test is_sorted::test_is_sorted ... ok
Nov 01 11:56:52.675 INFO blam! test deque::tests::fifo_left ... ok
Nov 01 11:56:52.675 INFO blam! test linked_list::tests::heap_construction ... ok
Nov 01 11:56:52.675 INFO blam! test linked_list::tests::iter_stack ... ok
Nov 01 11:56:52.675 INFO blam! test linked_list::tests::mixed_construction ... ok
Nov 01 11:56:52.675 INFO blam! test linked_list::tests::stack_construction ... ok
Nov 01 11:56:52.675 INFO blam! test merge_sort::test::merge_some ... ok
Nov 01 11:56:52.675 INFO blam! test merge_sort::test::merge_sort_is_sorted ... ok
Nov 01 11:56:52.675 INFO blam! test percolation::tests::open_once ... ok
Nov 01 11:56:52.683 INFO blam! test deque::tests::construct_deque_and_query ... ok
Nov 01 11:56:52.683 INFO blam! test deque::tests::box_to_non_null_to_box ... ok
Nov 01 11:56:52.683 INFO blam! test percolation::tests::percolate_3_s ... ok
Nov 01 11:56:52.683 INFO blam! test percolation::tests::new_percolation_is_full ... ok
Nov 01 11:56:52.684 INFO blam! test percolation::tests::open_twice ... ok
Nov 01 11:56:52.684 INFO blam! test quick_find::tests::test_3_c ... ok
Nov 01 11:56:52.684 INFO blam! test merge_sort::test::merge_sort_some ... ok
Nov 01 11:56:52.684 INFO blam! test deque::tests::filo_right ... ok
Nov 01 11:56:52.684 INFO blam! test quick_sort::tests::test_quick_select ... ok
Nov 01 11:56:52.687 INFO blam! test linked_list::tests::iter_loop ... ok
Nov 01 11:56:52.687 INFO blam! test quick_union::tests::test_3_c ... ok
Nov 01 11:56:52.687 INFO blam! test quick_sort::tests::quick_sort_some ... ok
Nov 01 11:56:52.691 INFO blam! test percolation::tests::percolate_3_z ... ok
Nov 01 11:56:52.691 INFO blam! test quick_find::tests::test_3 ... ok
Nov 01 11:56:52.691 INFO blam! test quick_sort::tests::partition_some ... ok
Nov 01 11:56:52.691 INFO blam! test quick_union::tests::test_3 ... ok
Nov 01 11:56:52.695 INFO blam! test percolation::tests::percolate_1 ... ok
Nov 01 11:56:52.695 INFO blam! test shell_sort::test::small_example ... ok
Nov 01 11:56:52.695 INFO blam! test shell_sort::test::h_generation ... ok
Nov 01 11:56:52.695 INFO blam! test shell_sort::test::handwritten ... ok
Nov 01 11:56:52.695 INFO blam! test step::test::index_mut ... ok
Nov 01 11:56:52.695 INFO blam! test step::test::index_offset ... ok
Nov 01 11:56:52.699 INFO blam! test shell_sort::test::sorted_step ... ok
Nov 01 11:56:52.699 INFO blam! test step::test::index ... ok
Nov 01 11:56:52.699 INFO blam! test weighted_quick_union::tests::test_3 ... ok
Nov 01 11:56:52.699 INFO blam! test shell_sort::test::example_from_course ... ok
Nov 01 11:56:52.699 INFO blam! test insertion_sort::test::insertion_sort_some ... ok
Nov 01 11:56:52.700 INFO blam! test selection_sort::test::vec_swap ... ok
Nov 01 11:56:52.707 INFO blam! test step::test::len_offset ... ok
Nov 01 11:56:52.707 INFO blam! test step::test::len ... ok
Nov 01 11:56:52.707 INFO blam! test weighted_quick_union::tests::test_3_c ... ok
Nov 01 11:56:52.724 INFO blam! test selection_sort::test::selection_sort_some ... ok
Nov 01 11:56:52.753 INFO blam! test shell_sort::test::shell_sort_some ... ok
Nov 01 11:56:52.753 INFO blam! 
Nov 01 11:56:52.753 INFO blam! test result: ok. 50 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 01 11:56:52.753 INFO blam! 
Nov 01 11:56:52.755 INFO kablam!      Running /target/debug/deps/algorithms-a21858e65e5e02bf
Nov 01 11:56:52.755 INFO blam! 
Nov 01 11:56:52.755 INFO blam! running 0 tests
Nov 01 11:56:52.755 INFO blam! 
Nov 01 11:56:52.755 INFO blam! test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 01 11:56:52.755 INFO blam! 
Nov 01 11:56:52.755 INFO kablam!      Running /target/debug/deps/performance_deque-aa925cb5910cedfc
Nov 01 11:56:52.757 INFO blam! 
Nov 01 11:56:52.757 INFO blam! running 1 test
Nov 01 11:56:53.823 INFO blam! test test_performance ... ok
Nov 01 11:56:53.823 INFO kablam!      Running /target/debug/deps/performance_insertion_sort-cf724bc9ee058ba6
Nov 01 11:56:53.823 INFO blam! 
Nov 01 11:56:53.823 INFO blam! test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 01 11:56:53.823 INFO blam! 
Nov 01 11:56:53.827 INFO blam! 
Nov 01 11:56:53.827 INFO blam! running 2 tests
Nov 01 11:57:01.699 INFO blam! test test_performance_partially_sorted ... ok
Nov 01 11:57:04.106 INFO blam! test test_performance ... ok
Nov 01 11:57:04.106 INFO blam! 
Nov 01 11:57:04.107 INFO kablam!      Running /target/debug/deps/performance_merge_sort-e8c8c55efabc38c0
Nov 01 11:57:04.107 INFO blam! test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 01 11:57:04.107 INFO blam! 
Nov 01 11:57:04.108 INFO blam! 
Nov 01 11:57:04.108 INFO blam! running 1 test
Nov 01 11:57:10.383 INFO blam! test test_performance ... ok
Nov 01 11:57:10.383 INFO blam! 
Nov 01 11:57:10.383 INFO blam! test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 01 11:57:10.383 INFO blam! 
Nov 01 11:57:10.395 INFO blam! 
Nov 01 11:57:10.395 INFO kablam!      Running /target/debug/deps/performance_quick_sort-d1f5e023c5e55caf
Nov 01 11:57:10.395 INFO blam! running 1 test
Nov 01 11:57:17.941 INFO blam! test test_performance ... FAILED
Nov 01 11:57:17.941 INFO kablam! error: test failed, to rerun pass '--test performance_quick_sort'
Nov 01 11:57:17.941 INFO blam! 
Nov 01 11:57:17.941 INFO blam! failures:
Nov 01 11:57:17.941 INFO blam! 
Nov 01 11:57:17.941 INFO blam! ---- test_performance stdout ----
Nov 01 11:57:17.941 INFO blam! Size: 1, elapsed milliseconds: 0
Nov 01 11:57:17.941 INFO blam! Size: 2, elapsed milliseconds: 0
Nov 01 11:57:17.941 INFO blam! Size: 4, elapsed milliseconds: 0
Nov 01 11:57:17.941 INFO blam! Size: 8, elapsed milliseconds: 0
Nov 01 11:57:17.941 INFO blam! Size: 16, elapsed milliseconds: 0
Nov 01 11:57:17.941 INFO blam! Size: 32, elapsed milliseconds: 0
Nov 01 11:57:17.941 INFO blam! Size: 64, elapsed milliseconds: 0
Nov 01 11:57:17.941 INFO blam! Size: 128, elapsed milliseconds: 0
Nov 01 11:57:17.941 INFO blam! Size: 256, elapsed milliseconds: 0
Nov 01 11:57:17.941 INFO blam! Size: 512, elapsed milliseconds: 0
Nov 01 11:57:17.941 INFO blam! Size: 1024, elapsed milliseconds: 6
Nov 01 11:57:17.941 INFO blam! Size: 2048, elapsed milliseconds: 8
Nov 01 11:57:17.941 INFO blam! Ratio: 1.3333333333333333
Nov 01 11:57:17.941 INFO blam! Size: 4096, elapsed milliseconds: 14
Nov 01 11:57:17.941 INFO blam! Ratio: 1.75
Nov 01 11:57:17.941 INFO blam! Size: 8192, elapsed milliseconds: 18
Nov 01 11:57:17.941 INFO blam! Ratio: 1.2857142857142858
Nov 01 11:57:17.941 INFO blam! Size: 16384, elapsed milliseconds: 45
Nov 01 11:57:17.941 INFO blam! Ratio: 2.5
Nov 01 11:57:17.941 INFO blam! Size: 32768, elapsed milliseconds: 83
Nov 01 11:57:17.941 INFO blam! Ratio: 1.8444444444444446
Nov 01 11:57:17.941 INFO blam! Size: 65536, elapsed milliseconds: 177
Nov 01 11:57:17.941 INFO blam! Ratio: 2.1325301204819276
Nov 01 11:57:17.941 INFO blam! Size: 131072, elapsed milliseconds: 360
Nov 01 11:57:17.941 INFO blam! Ratio: 2.0338983050847457
Nov 01 11:57:17.941 INFO blam! Size: 262144, elapsed milliseconds: 811
Nov 01 11:57:17.941 INFO blam! Ratio: 2.2527777777777778
Nov 01 11:57:17.941 INFO blam! Size: 524288, elapsed milliseconds: 1802
Nov 01 11:57:17.941 INFO blam! Ratio: 2.221948212083847
Nov 01 11:57:17.941 INFO blam! Size: 1048576, elapsed milliseconds: 3412
Nov 01 11:57:17.941 INFO blam! Ratio: 1.893451720310766
Nov 01 11:57:17.941 INFO blam! Factor selection sort = 1.893451720310766
Nov 01 11:57:17.941 INFO blam! thread 'test_performance' panicked at 'assertion failed: factor > 2.0', tests/performance_quick_sort.rs:31:5
Nov 01 11:57:17.941 INFO blam! stack backtrace:
Nov 01 11:57:17.941 INFO blam!    0:     0x55c8be63b1bf - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h3c55afa80a996a32
Nov 01 11:57:17.941 INFO blam!                                at libstd/sys/unix/backtrace/tracing/gcc_s.rs:49
Nov 01 11:57:17.941 INFO blam!    1:     0x55c8be6441e7 - std::sys_common::backtrace::print::hcef907ea7a07cd40
Nov 01 11:57:17.941 INFO blam!                                at libstd/sys_common/backtrace.rs:71
Nov 01 11:57:17.941 INFO blam!                                at libstd/sys_common/backtrace.rs:59
Nov 01 11:57:17.941 INFO blam!    2:     0x55c8be63eecf - std::panicking::default_hook::{{closure}}::hf541876c65f36ea7
Nov 01 11:57:17.941 INFO blam!                                at libstd/panicking.rs:211
Nov 01 11:57:17.941 INFO blam!    3:     0x55c8be63ebce - std::panicking::default_hook::h4ecc06b30ae4459d
Nov 01 11:57:17.941 INFO blam!                                at libstd/panicking.rs:221
Nov 01 11:57:17.941 INFO blam!    4:     0x55c8be63f5ae - std::panicking::rust_panic_with_hook::h0cc6d9cc9f34a947
Nov 01 11:57:17.942 INFO blam!                                at libstd/panicking.rs:476
Nov 01 11:57:17.942 INFO blam!    5:     0x55c8be62f1b7 - std::panicking::begin_panic::ha6b091552e5bf130
Nov 01 11:57:17.942 INFO blam!                                at libstd/panicking.rs:410
Nov 01 11:57:17.942 INFO blam!    6:     0x55c8be5c0edc - performance_quick_sort::test_performance::hfd1e15d9535c0066
Nov 01 11:57:17.942 INFO blam!                                at tests/performance_quick_sort.rs:31
Nov 01 11:57:17.942 INFO blam!    7:     0x55c8be5c0dc9 - performance_quick_sort::test_performance::{{closure}}::hd8cbb203c16cbd95
Nov 01 11:57:17.942 INFO blam!                                at tests/performance_quick_sort.rs:27
Nov 01 11:57:17.942 INFO blam!    8:     0x55c8be5c058d - core::ops::function::FnOnce::call_once::h934feec69f093736
Nov 01 11:57:17.942 INFO blam!                                at libcore/ops/function.rs:238
Nov 01 11:57:17.942 INFO blam!    9:     0x55c8be5c36ee - <F as alloc::boxed::FnBox<A>>::call_box::hf32558bd83684b60
Nov 01 11:57:17.942 INFO blam!                                at libtest/lib.rs:1468
Nov 01 11:57:17.942 INFO blam!                                at libcore/ops/function.rs:238
Nov 01 11:57:17.942 INFO blam!                                at liballoc/boxed.rs:672
Nov 01 11:57:17.942 INFO blam!   10:     0x55c8be64f419 - __rust_maybe_catch_panic
Nov 01 11:57:17.942 INFO blam!                                at libpanic_unwind/lib.rs:102
Nov 01 11:57:17.942 INFO blam!   11:     0x55c8be5e5352 - std::sys_common::backtrace::__rust_begin_short_backtrace::h1bb71a7386fd3269
Nov 01 11:57:17.942 INFO blam!                                at libstd/panicking.rs:289
Nov 01 11:57:17.942 INFO blam!                                at libstd/panic.rs:392
Nov 01 11:57:17.942 INFO blam!                                at libtest/lib.rs:1423
Nov 01 11:57:17.942 INFO blam!                                at libstd/sys_common/backtrace.rs:136
Nov 01 11:57:17.942 INFO blam!   12:     0x55c8be5e5e04 - std::panicking::try::do_call::h9b94719c3074bb5c
Nov 01 11:57:17.942 INFO blam!                                at libstd/thread/mod.rs:409
Nov 01 11:57:17.942 INFO blam!                                at libstd/panic.rs:313
Nov 01 11:57:17.942 INFO blam!                                at libstd/panicking.rs:310
Nov 01 11:57:17.942 INFO blam!   13:     0x55c8be64f419 - __rust_maybe_catch_panic
Nov 01 11:57:17.942 INFO blam!                                at libpanic_unwind/lib.rs:102
Nov 01 11:57:17.942 INFO blam!   14:     0x55c8be5d302c - <F as alloc::boxed::FnBox<A>>::call_box::hb3bcd66d16fd3e84
Nov 01 11:57:17.942 INFO blam!                                at libstd/panicking.rs:289
Nov 01 11:57:17.942 INFO blam!                                at libstd/panic.rs:392
Nov 01 11:57:17.942 INFO blam!                                at libstd/thread/mod.rs:408
Nov 01 11:57:17.942 INFO blam!                                at liballoc/boxed.rs:672
Nov 01 11:57:17.942 INFO blam!   15:     0x55c8be63cdcd - std::sys_common::thread::start_thread::had0c80d121669d29
Nov 01 11:57:17.942 INFO blam!                                at liballoc/boxed.rs:682
Nov 01 11:57:17.942 INFO blam!                                at libstd/sys_common/thread.rs:24
Nov 01 11:57:17.942 INFO blam!   16:     0x55c8be632265 - std::sys::unix::thread::Thread::new::thread_start::h4a5b66a70d76f47f
Nov 01 11:57:17.942 INFO blam!                                at libstd/sys/unix/thread.rs:90
Nov 01 11:57:17.942 INFO blam!   17:     0x7feb81fc36b9 - start_thread
Nov 01 11:57:17.942 INFO blam!   18:     0x7feb81ae341c - clone
Nov 01 11:57:17.942 INFO blam!   19:                0x0 - <unknown>
Nov 01 11:57:17.942 INFO blam! 
Nov 01 11:57:17.942 INFO blam! 
Nov 01 11:57:17.942 INFO blam! failures:
Nov 01 11:57:17.942 INFO blam!     test_performance
Nov 01 11:57:17.942 INFO blam! 
Nov 01 11:57:17.942 INFO blam! test result: FAILED. 0 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out
Nov 01 11:57:17.942 INFO blam! 
Nov 01 11:57:17.967 INFO kablam! su: No module specific data is present
Nov 01 11:57:18.403 INFO running `"docker" "rm" "-f" "2698fed07a42f1f053357c8199d33baa9b5cc779cc870a9e2ba54bf7a54cf6c6"`
Nov 01 11:57:18.643 INFO blam! 2698fed07a42f1f053357c8199d33baa9b5cc779cc870a9e2ba54bf7a54cf6c6
