Oct 15 21:27:27.882 INFO testing rs_lockfree-0.1.1 against try#40d4795669493f1965de7c44029c69552134fe1f for rustdoc-test-static-cling-1
Oct 15 21:27:27.884 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling-1/worker-7/try#40d4795669493f1965de7c44029c69552134fe1f:/target:rw,Z" "-v" "/mnt/big/crater/./work/local/test-source/worker-7/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 15 21:27:28.446 INFO blam! 1ffc4a1a894d7284f3ee6a89cb4c2ef4d6a3b53e177e9af7ced1cacc85164fb2
Oct 15 21:27:28.448 INFO running `"docker" "start" "-a" "1ffc4a1a894d7284f3ee6a89cb4c2ef4d6a3b53e177e9af7ced1cacc85164fb2"`
Oct 15 21:27:30.227 INFO kablam! usermod: no changes
Oct 15 21:27:30.347 INFO kablam!    Compiling rs_lockfree v0.1.1 (/source)
Oct 15 21:27:31.595 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 1.34s
Oct 15 21:27:31.603 INFO kablam! su: No module specific data is present
Oct 15 21:27:32.377 INFO running `"docker" "rm" "-f" "1ffc4a1a894d7284f3ee6a89cb4c2ef4d6a3b53e177e9af7ced1cacc85164fb2"`
Oct 15 21:27:32.627 INFO blam! 1ffc4a1a894d7284f3ee6a89cb4c2ef4d6a3b53e177e9af7ced1cacc85164fb2
Oct 15 21:27:32.640 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling-1/worker-7/try#40d4795669493f1965de7c44029c69552134fe1f:/target:rw,Z" "-v" "/mnt/big/crater/./work/local/test-source/worker-7/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 15 21:27:33.097 INFO blam! cb006a19a4e3f9a547a3013ec94b90e346b3eaf2a1bcb58c9436ed88f813712f
Oct 15 21:27:33.099 INFO running `"docker" "start" "-a" "cb006a19a4e3f9a547a3013ec94b90e346b3eaf2a1bcb58c9436ed88f813712f"`
Oct 15 21:27:34.847 INFO kablam! usermod: no changes
Oct 15 21:27:35.007 INFO kablam!    Compiling core_affinity v0.5.9
Oct 15 21:27:35.801 INFO kablam!    Compiling rs_lockfree v0.1.1 (/source)
Oct 15 21:27:50.487 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 15.61s
Oct 15 21:27:50.499 INFO kablam! su: No module specific data is present
Oct 15 21:27:52.041 INFO running `"docker" "rm" "-f" "cb006a19a4e3f9a547a3013ec94b90e346b3eaf2a1bcb58c9436ed88f813712f"`
Oct 15 21:27:52.413 INFO blam! cb006a19a4e3f9a547a3013ec94b90e346b3eaf2a1bcb58c9436ed88f813712f
Oct 15 21:27:52.427 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling-1/worker-7/try#40d4795669493f1965de7c44029c69552134fe1f:/target:rw,Z" "-v" "/mnt/big/crater/./work/local/test-source/worker-7/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 15 21:27:52.889 INFO blam! 7e4f07e99e825ce76bd6eca4893404758f26c9955d25ab4356c8c612ad3f5f25
Oct 15 21:27:52.892 INFO running `"docker" "start" "-a" "7e4f07e99e825ce76bd6eca4893404758f26c9955d25ab4356c8c612ad3f5f25"`
Oct 15 21:27:54.543 INFO kablam! usermod: no changes
Oct 15 21:27:54.634 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 0.07s
Oct 15 21:27:54.648 INFO kablam!      Running /target/debug/deps/rs_lockfree-a25124279ee2dd8d
Oct 15 21:27:54.648 INFO blam! 
Oct 15 21:27:54.648 INFO blam! running 7 tests
Oct 15 21:27:54.655 INFO blam! test lockfree_stack::test::test_base ... ok
Oct 15 21:27:54.655 INFO kablam!      Running /target/debug/deps/test_hazard_epoch-ac424d50232cb50b
Oct 15 21:27:54.655 INFO blam! test lockfree_queue::test::test_memory_leak ... ok
Oct 15 21:27:54.655 INFO blam! test error::test::test_base ... ok
Oct 15 21:27:54.655 INFO blam! test lockfree_stack::test::test_memory_leak ... ok
Oct 15 21:27:54.655 INFO blam! test lockfree_queue::test::test_base ... ok
Oct 15 21:27:54.655 INFO blam! test spin_lock::test::test_spin_lock ... ok
Oct 15 21:27:54.655 INFO blam! test spin_rwlock::test::test_rwlock ... ok
Oct 15 21:27:54.655 INFO blam! 
Oct 15 21:27:54.655 INFO blam! test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Oct 15 21:27:54.655 INFO blam! 
Oct 15 21:27:54.655 INFO blam! 
Oct 15 21:27:54.655 INFO blam! running 2 tests
Oct 15 21:27:54.656 INFO blam! test test_base ... ok
Oct 15 21:27:54.656 INFO blam! set_cpu_affinity 2 2
Oct 15 21:27:54.675 INFO blam! set_cpu_affinity 3 3
Oct 15 21:27:54.675 INFO blam! set_cpu_affinity 4 4
Oct 15 21:27:54.675 INFO blam! set_cpu_affinity 5 5
Oct 15 21:27:54.683 INFO blam! hazard_waiting_count=19716
Oct 15 21:27:54.683 INFO blam! set_cpu_affinity 7 7
Oct 15 21:27:54.683 INFO blam! set_cpu_affinity 9 1
Oct 15 21:27:54.683 INFO blam! set_cpu_affinity 8 0
Oct 15 21:27:54.683 INFO blam! set_cpu_affinity 6 6
Oct 15 21:27:55.679 INFO blam! hazard_waiting_count=7547
Oct 15 21:27:56.726 INFO blam! hazard_waiting_count=266184
Oct 15 21:27:57.727 INFO blam! hazard_waiting_count=143504
Oct 15 21:27:58.750 INFO blam! hazard_waiting_count=68441
Oct 15 21:27:59.779 INFO blam! hazard_waiting_count=6
Oct 15 21:28:00.779 INFO blam! hazard_waiting_count=198247
Oct 15 21:28:01.791 INFO blam! hazard_waiting_count=9408
Oct 15 21:28:02.803 INFO blam! hazard_waiting_count=13976
Oct 15 21:28:03.817 INFO blam! hazard_waiting_count=27008
Oct 15 21:28:04.819 INFO blam! hazard_waiting_count=199408
Oct 15 21:28:05.834 INFO blam! hazard_waiting_count=18760
Oct 15 21:28:06.843 INFO blam! hazard_waiting_count=10948
Oct 15 21:28:07.871 INFO blam! hazard_waiting_count=21322
Oct 15 21:28:08.886 INFO blam! hazard_waiting_count=118910
Oct 15 21:28:09.891 INFO blam! hazard_waiting_count=4
Oct 15 21:28:10.891 INFO blam! hazard_waiting_count=254031
Oct 15 21:28:11.911 INFO blam! hazard_waiting_count=201774
Oct 15 21:28:12.923 INFO blam! hazard_waiting_count=202
Oct 15 21:28:13.917 INFO blam! hazard_waiting_count=8027
Oct 15 21:28:14.923 INFO blam! hazard_waiting_count=0
Oct 15 21:28:15.923 INFO blam! hazard_waiting_count=0
Oct 15 21:28:16.922 INFO blam! test test_multi_thread ... ok
Oct 15 21:28:16.922 INFO blam! 
Oct 15 21:28:16.922 INFO blam! test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Oct 15 21:28:16.922 INFO blam! 
Oct 15 21:28:16.923 INFO kablam!      Running /target/debug/deps/test_lockfree_queue-abe14db98e925d87
Oct 15 21:28:16.924 INFO blam! 
Oct 15 21:28:16.924 INFO blam! running 1 test
Oct 15 21:28:16.925 INFO blam! set_cpu_affinity 3 3
Oct 15 21:28:16.927 INFO blam! set_cpu_affinity 1 1
Oct 15 21:28:16.927 INFO blam! set_cpu_affinity 2 2
Oct 15 21:28:16.935 INFO blam! set_cpu_affinity 5 5
Oct 15 21:28:16.935 INFO blam! debug_thread produced 1 consumed 0
Oct 15 21:28:16.939 INFO blam! set_cpu_affinity 6 6
Oct 15 21:28:16.939 INFO blam! set_cpu_affinity 7 7
Oct 15 21:28:16.939 INFO blam! set_cpu_affinity 4 4
Oct 15 21:28:16.939 INFO blam! set_cpu_affinity 8 0
Oct 15 21:28:17.947 INFO blam! debug_thread produced 737284 consumed 688640
Oct 15 21:28:18.938 INFO blam! debug_thread produced 1253892 consumed 1253888
Oct 15 21:28:19.950 INFO blam! debug_thread produced 1781764 consumed 1781760
Oct 15 21:28:20.951 INFO blam! debug_thread produced 2425860 consumed 2302464
Oct 15 21:28:21.952 INFO blam! debug_thread produced 3037188 consumed 2925568
Oct 15 21:28:22.959 INFO blam! debug_thread produced 3495428 consumed 3487744
Oct 15 21:28:23.967 INFO blam! debug_thread produced 4097028 consumed 4090368
Oct 15 21:28:24.979 INFO blam! debug_thread produced 4720132 consumed 4639232
Oct 15 21:28:25.983 INFO blam! debug_thread produced 5404676 consumed 5174784
Oct 15 21:28:26.987 INFO blam! debug_thread produced 5974020 consumed 5974016
Oct 15 21:28:27.991 INFO blam! debug_thread produced 6534148 consumed 6456320
Oct 15 21:28:28.992 INFO blam! debug_thread produced 7311364 consumed 7009792
Oct 15 21:28:29.995 INFO blam! debug_thread produced 8123908 consumed 7777792
Oct 15 21:28:30.987 INFO blam! debug_thread produced 8721924 consumed 8629760
Oct 15 21:28:32.015 INFO blam! debug_thread produced 9433773 consumed 9184768
Oct 15 21:28:33.011 INFO blam! debug_thread produced 10020525 consumed 9852416
Oct 15 21:28:34.015 INFO blam! debug_thread produced 10451286 consumed 10445824
Oct 15 21:28:35.016 INFO blam! debug_thread produced 10947926 consumed 10947072
Oct 15 21:28:36.022 INFO blam! test test_multi_threads ... ok
Oct 15 21:28:36.022 INFO blam! 
Oct 15 21:28:36.022 INFO blam! test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Oct 15 21:28:36.022 INFO blam! 
Oct 15 21:28:36.027 INFO blam! 
Oct 15 21:28:36.027 INFO kablam!      Running /target/debug/deps/test_lockfree_stack-adba1cea0f9e888d
Oct 15 21:28:36.027 INFO blam! running 1 test
Oct 15 21:28:36.035 INFO blam! debug_thread produced 0 consumed 0
Oct 15 21:28:36.038 INFO blam! set_cpu_affinity 1 1
Oct 15 21:28:36.043 INFO blam! set_cpu_affinity 2 2
Oct 15 21:28:36.043 INFO blam! set_cpu_affinity 5 5
Oct 15 21:28:36.051 INFO blam! set_cpu_affinity 3 3
Oct 15 21:28:36.051 INFO blam! set_cpu_affinity 4 4
Oct 15 21:28:36.051 INFO blam! set_cpu_affinity 6 6
Oct 15 21:28:36.051 INFO blam! set_cpu_affinity 8 0
Oct 15 21:28:36.051 INFO blam! set_cpu_affinity 7 7
Oct 15 21:28:37.055 INFO blam! debug_thread produced 609284 consumed 439296
Oct 15 21:28:38.068 INFO blam! debug_thread produced 1233412 consumed 979968
Oct 15 21:28:39.087 INFO blam! debug_thread produced 1782276 consumed 1448448
Oct 15 21:28:40.120 INFO blam! debug_thread produced 2358276 consumed 1923072
Oct 15 21:28:41.123 INFO blam! debug_thread produced 2827780 consumed 2371072
Oct 15 21:28:42.131 INFO blam! debug_thread produced 3337732 consumed 2933760
Oct 15 21:28:43.147 INFO blam! debug_thread produced 3950596 consumed 3401216
Oct 15 21:28:44.148 INFO blam! debug_thread produced 4561412 consumed 3882496
Oct 15 21:28:45.162 INFO blam! debug_thread produced 5186052 consumed 4367360
Oct 15 21:28:46.162 INFO blam! debug_thread produced 5824516 consumed 4938752
Oct 15 21:28:47.175 INFO blam! debug_thread produced 6386180 consumed 5479936
Oct 15 21:28:48.179 INFO blam! debug_thread produced 6898180 consumed 5992448
Oct 15 21:28:49.183 INFO blam! debug_thread produced 7359492 consumed 6441472
Oct 15 21:28:50.187 INFO blam! debug_thread produced 7812612 consumed 6900736
Oct 15 21:28:51.195 INFO blam! debug_thread produced 8280068 consumed 7333376
Oct 15 21:28:52.211 INFO blam! debug_thread produced 8913924 consumed 7868416
Oct 15 21:28:53.219 INFO blam! debug_thread produced 9516548 consumed 8545792
Oct 15 21:28:54.215 INFO blam! debug_thread produced 10228740 consumed 9214976
Oct 15 21:28:55.231 INFO blam! debug_thread produced 10877439 consumed 9872384
Oct 15 21:28:56.667 INFO blam! test test_multi_threads ... ok
Oct 15 21:28:56.667 INFO blam! 
Oct 15 21:28:56.667 INFO blam! test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Oct 15 21:28:56.667 INFO blam! 
Oct 15 21:28:56.667 INFO kablam!    Doc-tests rs_lockfree
Oct 15 21:28:57.703 INFO blam! 
Oct 15 21:28:57.703 INFO blam! running 8 tests
Oct 15 21:29:04.114 INFO blam! test src/lockfree_queue.rs - lockfree_queue::LockFreeQueue (line 58) ... ok
Oct 15 21:29:04.120 INFO blam! test src/hazard_epoch.rs - hazard_epoch::HazardEpoch::retire (line 157) ... ok
Oct 15 21:29:04.135 INFO blam! test src/hazard_epoch.rs - hazard_epoch::HazardEpoch::acquire (line 263) ... ok
Oct 15 21:29:04.695 INFO blam! test src/hazard_pointer.rs - hazard_pointer::HazardNodeT::get_base_hazard_node (line 95) ... ok
Oct 15 21:29:04.959 INFO blam! test src/hazard_epoch.rs - hazard_epoch::HazardEpoch::new_in_heap (line 119) ... ok
Oct 15 21:29:05.131 INFO blam! test src/hazard_epoch.rs - hazard_epoch::HazardEpoch::add_node (line 193) ... ok
Oct 15 21:29:05.135 INFO blam! test src/lockfree_stack.rs - lockfree_stack::LockFreeStack (line 58) ... ok
Oct 15 21:29:05.266 INFO blam! test src/hazard_epoch.rs - hazard_epoch::HazardEpoch::new_in_stack (line 82) ... ok
Oct 15 21:29:05.267 INFO blam! 
Oct 15 21:29:05.267 INFO blam! test result: ok. 8 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Oct 15 21:29:05.267 INFO blam! 
Oct 15 21:29:05.278 INFO kablam! su: No module specific data is present
Oct 15 21:29:05.942 INFO running `"docker" "rm" "-f" "7e4f07e99e825ce76bd6eca4893404758f26c9955d25ab4356c8c612ad3f5f25"`
Oct 15 21:29:06.223 INFO blam! 7e4f07e99e825ce76bd6eca4893404758f26c9955d25ab4356c8c612ad3f5f25