Oct 14 23:22:16.186 INFO testing davidpeklak/rust-algorithms against try#40d4795669493f1965de7c44029c69552134fe1f for rustdoc-test-static-cling-1
Oct 14 23:22:16.186 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling-1/worker-4/try#40d4795669493f1965de7c44029c69552134fe1f:/target:rw,Z" "-v" "/mnt/big/crater/./work/local/test-source/worker-4/rustdoc-test-static-cling-1/try#40d4795669493f1965de7c44029c69552134fe1f:/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 +40d4795669493f1965de7c44029c69552134fe1f-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"`
Oct 14 23:22:16.640 INFO blam! 9366277f481b679761d3d2ef909d87a5797d014a7a01ced9247c168eee9a8c16
Oct 14 23:22:16.651 INFO running `"docker" "start" "-a" "9366277f481b679761d3d2ef909d87a5797d014a7a01ced9247c168eee9a8c16"`
Oct 14 23:22:17.713 INFO kablam! usermod: no changes
Oct 14 23:22:17.804 INFO kablam!    Compiling rand v0.5.0
Oct 14 23:22:17.815 INFO kablam!    Compiling num-complex v0.1.43
Oct 14 23:22:17.815 INFO kablam!    Compiling num-bigint v0.1.44
Oct 14 23:22:22.655 INFO kablam!    Compiling num-rational v0.1.42
Oct 14 23:22:24.459 INFO kablam!    Compiling num v0.1.42
Oct 14 23:22:24.808 INFO kablam!    Compiling stopwatch v0.0.7
Oct 14 23:22:25.355 INFO kablam!    Compiling algorithms v0.1.0 (/source)
Oct 14 23:22:27.767 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 10.03s
Oct 14 23:22:27.772 INFO kablam! su: No module specific data is present
Oct 14 23:22:28.897 INFO running `"docker" "rm" "-f" "9366277f481b679761d3d2ef909d87a5797d014a7a01ced9247c168eee9a8c16"`
Oct 14 23:22:29.238 INFO blam! 9366277f481b679761d3d2ef909d87a5797d014a7a01ced9247c168eee9a8c16
Oct 14 23:22:29.241 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling-1/worker-4/try#40d4795669493f1965de7c44029c69552134fe1f:/target:rw,Z" "-v" "/mnt/big/crater/./work/local/test-source/worker-4/rustdoc-test-static-cling-1/try#40d4795669493f1965de7c44029c69552134fe1f:/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 +40d4795669493f1965de7c44029c69552134fe1f-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"`
Oct 14 23:22:29.789 INFO blam! baac7f5355e840b98ed663f423cad910360dc1dafe1343ce1cc40ec5ccc90dbc
Oct 14 23:22:29.791 INFO running `"docker" "start" "-a" "baac7f5355e840b98ed663f423cad910360dc1dafe1343ce1cc40ec5ccc90dbc"`
Oct 14 23:22:31.107 INFO kablam! usermod: no changes
Oct 14 23:22:31.267 INFO kablam!    Compiling algorithms v0.1.0 (/source)
Oct 14 23:22:39.535 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 8.41s
Oct 14 23:22:39.540 INFO kablam! su: No module specific data is present
Oct 14 23:22:40.519 INFO running `"docker" "rm" "-f" "baac7f5355e840b98ed663f423cad910360dc1dafe1343ce1cc40ec5ccc90dbc"`
Oct 14 23:22:40.821 INFO blam! baac7f5355e840b98ed663f423cad910360dc1dafe1343ce1cc40ec5ccc90dbc
Oct 14 23:22:40.833 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling-1/worker-4/try#40d4795669493f1965de7c44029c69552134fe1f:/target:rw,Z" "-v" "/mnt/big/crater/./work/local/test-source/worker-4/rustdoc-test-static-cling-1/try#40d4795669493f1965de7c44029c69552134fe1f:/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 +40d4795669493f1965de7c44029c69552134fe1f-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"`
Oct 14 23:22:41.339 INFO blam! da07f1581cc311d7121e18ee8afdd6056fa290e98973ad9649f5730c43c9adb9
Oct 14 23:22:41.342 INFO running `"docker" "start" "-a" "da07f1581cc311d7121e18ee8afdd6056fa290e98973ad9649f5730c43c9adb9"`
Oct 14 23:22:42.416 INFO kablam! usermod: no changes
Oct 14 23:22:42.515 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 0.07s
Oct 14 23:22:42.515 INFO kablam!      Running /target/debug/deps/algorithms-f2537e85ed36867f
Oct 14 23:22:42.516 INFO blam! 
Oct 14 23:22:42.516 INFO blam! running 50 tests
Oct 14 23:22:42.529 INFO blam! test deque::tests::fifo_left ... ok
Oct 14 23:22:42.529 INFO blam! test deque::tests::add_last_and_query ... ok
Oct 14 23:22:42.539 INFO blam! test deque::tests::iter ... ok
Oct 14 23:22:42.539 INFO blam! test deque::tests::sizes ... ok
Oct 14 23:22:42.540 INFO blam! test deque::tests::filo_left ... ok
Oct 14 23:22:42.540 INFO blam! test deque::tests::filo_right ... ok
Oct 14 23:22:42.547 INFO blam! test deque::tests::box_to_non_null_to_box ... ok
Oct 14 23:22:42.552 INFO blam! test deque::tests::add_first_and_query ... ok
Oct 14 23:22:42.552 INFO blam! test deque::tests::fifo_right ... ok
Oct 14 23:22:42.552 INFO blam! test deque::tests::construct_deque_and_query ... ok
Oct 14 23:22:42.552 INFO blam! test linked_list::tests::heap_construction ... ok
Oct 14 23:22:42.552 INFO blam! test linked_list::tests::stack_construction ... ok
Oct 14 23:22:42.552 INFO blam! test merge_sort::test::merge_some ... ok
Oct 14 23:22:42.552 INFO blam! test merge_sort::test::merge_sort_is_sorted ... ok
Oct 14 23:22:42.559 INFO blam! test index_ops::test::vec_length ... ok
Oct 14 23:22:42.571 INFO blam! test is_sorted::test_is_sorted ... ok
Oct 14 23:22:42.571 INFO blam! test insertion_sort::test::small_example ... ok
Oct 14 23:22:42.571 INFO blam! test merge_sort::test::merge_sort_some ... ok
Oct 14 23:22:42.579 INFO blam! test percolation::tests::open_once ... ok
Oct 14 23:22:42.581 INFO blam! test percolation::tests::open_twice ... ok
Oct 14 23:22:42.581 INFO blam! test linked_list::tests::mixed_construction ... ok
Oct 14 23:22:42.581 INFO blam! test linked_list::tests::iter_stack ... ok
Oct 14 23:22:42.581 INFO blam! test percolation::tests::new_percolation_is_full ... ok
Oct 14 23:22:42.581 INFO blam! test linked_list::tests::iter_loop ... ok
Oct 14 23:22:42.581 INFO blam! test percolation::tests::percolate_3_s ... ok
Oct 14 23:22:42.581 INFO blam! test percolation::tests::percolate_3_z ... ok
Oct 14 23:22:42.581 INFO blam! test quick_find::tests::test_3_c ... ok
Oct 14 23:22:42.581 INFO blam! test quick_find::tests::test_3 ... ok
Oct 14 23:22:42.584 INFO blam! test quick_union::tests::test_3_c ... ok
Oct 14 23:22:42.585 INFO blam! test quick_union::tests::test_3 ... ok
Oct 14 23:22:42.585 INFO blam! test quick_sort::tests::test_quick_select ... ok
Oct 14 23:22:42.585 INFO blam! test shell_sort::test::example_from_course ... ok
Oct 14 23:22:42.591 INFO blam! test shell_sort::test::handwritten ... ok
Oct 14 23:22:42.591 INFO blam! test shell_sort::test::h_generation ... ok
Oct 14 23:22:42.591 INFO blam! test selection_sort::test::vec_swap ... ok
Oct 14 23:22:42.607 INFO blam! test quick_sort::tests::partition_some ... ok
Oct 14 23:22:42.607 INFO blam! test percolation::tests::percolate_1 ... ok
Oct 14 23:22:42.607 INFO blam! test step::test::index ... ok
Oct 14 23:22:42.607 INFO blam! test shell_sort::test::sorted_step ... ok
Oct 14 23:22:42.607 INFO blam! test step::test::index_mut ... ok
Oct 14 23:22:42.607 INFO blam! test step::test::index_offset ... ok
Oct 14 23:22:42.607 INFO blam! test weighted_quick_union::tests::test_3 ... ok
Oct 14 23:22:42.607 INFO blam! test weighted_quick_union::tests::test_3_c ... ok
Oct 14 23:22:42.615 INFO blam! test step::test::len ... ok
Oct 14 23:22:42.615 INFO blam! test step::test::len_offset ... ok
Oct 14 23:22:42.615 INFO blam! test quick_sort::tests::quick_sort_some ... ok
Oct 14 23:22:42.622 INFO blam! test insertion_sort::test::insertion_sort_some ... ok
Oct 14 23:22:42.639 INFO blam! test shell_sort::test::small_example ... ok
Oct 14 23:22:42.663 INFO blam! test selection_sort::test::selection_sort_some ... ok
Oct 14 23:22:42.711 INFO blam! test shell_sort::test::shell_sort_some ... ok
Oct 14 23:22:42.711 INFO blam! 
Oct 14 23:22:42.711 INFO blam! test result: ok. 50 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Oct 14 23:22:42.711 INFO blam! 
Oct 14 23:22:42.720 INFO blam! 
Oct 14 23:22:42.720 INFO kablam!      Running /target/debug/deps/algorithms-4b89b2007dadbc4b
Oct 14 23:22:42.720 INFO blam! running 0 tests
Oct 14 23:22:42.720 INFO kablam!      Running /target/debug/deps/performance_deque-7a5fa1658b82c109
Oct 14 23:22:42.720 INFO blam! 
Oct 14 23:22:42.720 INFO blam! test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Oct 14 23:22:42.720 INFO blam! 
Oct 14 23:22:42.731 INFO blam! 
Oct 14 23:22:42.731 INFO blam! running 1 test
Oct 14 23:22:43.919 INFO blam! test test_performance ... ok
Oct 14 23:22:43.919 INFO blam! 
Oct 14 23:22:43.919 INFO blam! test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Oct 14 23:22:43.919 INFO blam! 
Oct 14 23:22:43.924 INFO kablam!      Running /target/debug/deps/performance_insertion_sort-72f17ea558660247
Oct 14 23:22:43.931 INFO blam! 
Oct 14 23:22:43.931 INFO blam! running 2 tests
Oct 14 23:22:56.267 INFO blam! test test_performance ... ok
Oct 14 23:22:59.412 INFO blam! test test_performance_partially_sorted ... FAILED
Oct 14 23:22:59.412 INFO blam! 
Oct 14 23:22:59.412 INFO blam! failures:
Oct 14 23:22:59.412 INFO blam! 
Oct 14 23:22:59.412 INFO blam! ---- test_performance_partially_sorted stdout ----
Oct 14 23:22:59.412 INFO blam! Size: 1, elapsed milliseconds: 0
Oct 14 23:22:59.412 INFO blam! Size: 2, elapsed milliseconds: 0
Oct 14 23:22:59.412 INFO blam! Size: 4, elapsed milliseconds: 0
Oct 14 23:22:59.412 INFO blam! Size: 8, elapsed milliseconds: 0
Oct 14 23:22:59.412 INFO blam! Size: 16, elapsed milliseconds: 0
Oct 14 23:22:59.412 INFO blam! Size: 32, elapsed milliseconds: 0
Oct 14 23:22:59.412 INFO blam! Size: 64, elapsed milliseconds: 0
Oct 14 23:22:59.412 INFO blam! Size: 128, elapsed milliseconds: 0
Oct 14 23:22:59.412 INFO blam! Size: 256, elapsed milliseconds: 0
Oct 14 23:22:59.412 INFO blam! Size: 512, elapsed milliseconds: 0
Oct 14 23:22:59.412 INFO blam! Size: 1024, elapsed milliseconds: 0
Oct 14 23:22:59.412 INFO blam! Size: 2048, elapsed milliseconds: 0
Oct 14 23:22:59.412 INFO blam! Size: 4096, elapsed milliseconds: 0
Oct 14 23:22:59.412 INFO blam! Size: 8192, elapsed milliseconds: 5
Oct 14 23:22:59.412 INFO blam! Size: 16384, elapsed milliseconds: 1
Oct 14 23:22:59.412 INFO blam! Ratio: 0.2
Oct 14 23:22:59.412 INFO blam! Size: 32768, elapsed milliseconds: 4
Oct 14 23:22:59.412 INFO blam! Ratio: 4
Oct 14 23:22:59.412 INFO blam! Size: 65536, elapsed milliseconds: 20
Oct 14 23:22:59.412 INFO blam! Ratio: 5
Oct 14 23:22:59.412 INFO blam! Size: 131072, elapsed milliseconds: 15
Oct 14 23:22:59.412 INFO blam! Ratio: 0.75
Oct 14 23:22:59.412 INFO blam! Size: 262144, elapsed milliseconds: 30
Oct 14 23:22:59.412 INFO blam! Ratio: 2
Oct 14 23:22:59.412 INFO blam! Size: 524288, elapsed milliseconds: 87
Oct 14 23:22:59.412 INFO blam! Ratio: 2.9
Oct 14 23:22:59.412 INFO blam! Size: 1048576, elapsed milliseconds: 209
Oct 14 23:22:59.412 INFO blam! Ratio: 2.4022988505747125
Oct 14 23:22:59.412 INFO blam! Size: 2097152, elapsed milliseconds: 305
Oct 14 23:22:59.412 INFO blam! Ratio: 1.4593301435406698
Oct 14 23:22:59.412 INFO blam! Size: 4194304, elapsed milliseconds: 568
Oct 14 23:22:59.412 INFO blam! Ratio: 1.862295081967213
Oct 14 23:22:59.412 INFO blam! Size: 8388608, elapsed milliseconds: 970
Oct 14 23:22:59.412 INFO blam! Ratio: 1.7077464788732395
Oct 14 23:22:59.412 INFO blam! Size: 16777216, elapsed milliseconds: 2156
Oct 14 23:22:59.412 INFO blam! Ratio: 2.222680412371134
Oct 14 23:22:59.412 INFO blam! Size: 33554432, elapsed milliseconds: 3789
Oct 14 23:22:59.412 INFO blam! Ratio: 1.757421150278293
Oct 14 23:22:59.412 INFO blam! Factor insertion sort = 1.757421150278293
Oct 14 23:22:59.412 INFO blam! thread 'test_performance_partially_sorted' panicked at 'assertion failed: factor > 1.8', tests/performance_insertion_sort.rs:67:5
Oct 14 23:22:59.412 INFO blam! stack backtrace:
Oct 14 23:22:59.412 INFO blam!    0:     0x564ffafa27ee - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h7fa1e90165a4b6b7
Oct 14 23:22:59.412 INFO blam!                                at libstd/sys/unix/backtrace/tracing/gcc_s.rs:49
Oct 14 23:22:59.412 INFO blam!    1:     0x564ffafab966 - std::sys_common::backtrace::print::hce31971a380b167f
Oct 14 23:22:59.412 INFO blam!                                at libstd/sys_common/backtrace.rs:71
Oct 14 23:22:59.412 INFO blam!                                at libstd/sys_common/backtrace.rs:59
Oct 14 23:22:59.412 INFO blam!    2:     0x564ffafa66bd - std::panicking::default_hook::{{closure}}::h9590c1e09d0fb8eb
Oct 14 23:22:59.412 INFO blam!                                at libstd/panicking.rs:211
Oct 14 23:22:59.412 INFO blam!    3:     0x564ffafa63cb - std::panicking::default_hook::hb1838a6568eb05e3
Oct 14 23:22:59.413 INFO blam!                                at libstd/panicking.rs:221
Oct 14 23:22:59.413 INFO blam!    4:     0x564ffafa6d6c - std::panicking::rust_panic_with_hook::h9ad880ee5fc38368
Oct 14 23:22:59.413 INFO blam!                                at libstd/panicking.rs:476
Oct 14 23:22:59.413 INFO blam!    5:     0x564ffaf96def - std::panicking::begin_panic::h36ab484365130913
Oct 14 23:22:59.413 INFO blam!                                at libstd/panicking.rs:410
Oct 14 23:22:59.413 INFO blam!    6:     0x564ffaf29b27 - performance_insertion_sort::test_performance_partially_sorted::h48f5a91feab1b850
Oct 14 23:22:59.413 INFO blam!                                at tests/performance_insertion_sort.rs:67
Oct 14 23:22:59.413 INFO blam!    7:     0x564ffaf29a19 - performance_insertion_sort::test_performance_partially_sorted::{{closure}}::h3837c6d694b811d8
Oct 14 23:22:59.413 INFO blam!                                at tests/performance_insertion_sort.rs:63
Oct 14 23:22:59.413 INFO blam!    8:     0x564ffaf290fd - core::ops::function::FnOnce::call_once::hee20c7bbde3800ad
Oct 14 23:22:59.413 INFO blam!                                at libcore/ops/function.rs:238
Oct 14 23:22:59.413 INFO blam!    9:     0x564ffaf2caee - <F as alloc::boxed::FnBox<A>>::call_box::hae226a42c8d94b19
Oct 14 23:22:59.413 INFO blam!                                at libtest/lib.rs:1468
Oct 14 23:22:59.413 INFO blam!                                at libcore/ops/function.rs:238
Oct 14 23:22:59.413 INFO blam!                                at liballoc/boxed.rs:672
Oct 14 23:22:59.413 INFO blam!   10:     0x564ffafb6e89 - __rust_maybe_catch_panic
Oct 14 23:22:59.413 INFO blam!                                at libpanic_unwind/lib.rs:102
Oct 14 23:22:59.413 INFO blam!   11:     0x564ffaf4d51d - std::sys_common::backtrace::__rust_begin_short_backtrace::ha5325a24fd096595
Oct 14 23:22:59.413 INFO blam!                                at libstd/panicking.rs:289
Oct 14 23:22:59.413 INFO blam!                                at libstd/panic.rs:392
Oct 14 23:22:59.413 INFO blam!                                at libtest/lib.rs:1423
Oct 14 23:22:59.413 INFO blam!                                at libstd/sys_common/backtrace.rs:136
Oct 14 23:22:59.413 INFO blam!   12:     0x564ffaf4e0c4 - std::panicking::try::do_call::h2c632c17829d0621
Oct 14 23:22:59.413 INFO blam!                                at libstd/thread/mod.rs:409
Oct 14 23:22:59.413 INFO blam!                                at libstd/panic.rs:313
Oct 14 23:22:59.413 INFO blam!                                at libstd/panicking.rs:310
Oct 14 23:22:59.413 INFO blam!   13:     0x564ffafb6e89 - __rust_maybe_catch_panic
Oct 14 23:22:59.413 INFO blam!                                at libpanic_unwind/lib.rs:102
Oct 14 23:22:59.413 INFO blam!   14:     0x564ffaf3b706 - <F as alloc::boxed::FnBox<A>>::call_box::h1a0832645645f090
Oct 14 23:22:59.413 INFO blam!                                at libstd/panicking.rs:289
Oct 14 23:22:59.413 INFO blam!                                at libstd/panic.rs:392
Oct 14 23:22:59.413 INFO blam!                                at libstd/thread/mod.rs:408
Oct 14 23:22:59.413 INFO blam!                                at liballoc/boxed.rs:672
Oct 14 23:22:59.413 INFO blam!   15:     0x564ffafa4afa - std::sys_common::thread::start_thread::h49b8ca61f624f031
Oct 14 23:22:59.413 INFO blam!                                at liballoc/boxed.rs:682
Oct 14 23:22:59.413 INFO blam!                                at libstd/sys_common/thread.rs:24
Oct 14 23:22:59.413 INFO blam!   16:     0x564ffaf99c45 - std::sys::unix::thread::Thread::new::thread_start::hc999bd1fb8f071a1
Oct 14 23:22:59.413 INFO blam!                                at libstd/sys/unix/thread.rs:90
Oct 14 23:22:59.413 INFO blam!   17:     0x7f64d5f296b9 - start_thread
Oct 14 23:22:59.413 INFO blam!   18:     0x7f64d5a4941c - clone
Oct 14 23:22:59.413 INFO blam!   19:                0x0 - <unknown>
Oct 14 23:22:59.413 INFO blam! 
Oct 14 23:22:59.413 INFO blam! 
Oct 14 23:22:59.413 INFO blam! failures:
Oct 14 23:22:59.413 INFO blam!     test_performance_partially_sorted
Oct 14 23:22:59.413 INFO blam! 
Oct 14 23:22:59.413 INFO blam! test result: FAILED. 1 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out
Oct 14 23:22:59.413 INFO blam! 
Oct 14 23:22:59.430 INFO kablam! error: test failed, to rerun pass '--test performance_insertion_sort'
Oct 14 23:22:59.430 INFO kablam! su: No module specific data is present
Oct 14 23:22:59.827 INFO running `"docker" "rm" "-f" "da07f1581cc311d7121e18ee8afdd6056fa290e98973ad9649f5730c43c9adb9"`
Oct 14 23:22:59.943 INFO blam! da07f1581cc311d7121e18ee8afdd6056fa290e98973ad9649f5730c43c9adb9