Oct 15 21:27:54.908 INFO testing rs_lockfree-0.1.1 against master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513 for rustdoc-test-static-cling-1
Oct 15 21:27:54.908 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling-1/worker-3/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/target:rw,Z" "-v" "/mnt/big/crater/./work/local/test-source/worker-3/rustdoc-test-static-cling-1/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/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 +0e07c4281c343e9e15a0a8fca79538ad1a8eb513-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:55.405 INFO blam! 28aa9471d5b9322a35c15065993125d323658f1e51ba729bc9e5fe3e0b711862
Oct 15 21:27:55.407 INFO running `"docker" "start" "-a" "28aa9471d5b9322a35c15065993125d323658f1e51ba729bc9e5fe3e0b711862"`
Oct 15 21:27:57.546 INFO kablam! usermod: no changes
Oct 15 21:27:57.631 INFO kablam!    Compiling rs_lockfree v0.1.1 (/source)
Oct 15 21:27:59.371 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 1.79s
Oct 15 21:27:59.372 INFO kablam! su: No module specific data is present
Oct 15 21:28:00.311 INFO running `"docker" "rm" "-f" "28aa9471d5b9322a35c15065993125d323658f1e51ba729bc9e5fe3e0b711862"`
Oct 15 21:28:00.558 INFO blam! 28aa9471d5b9322a35c15065993125d323658f1e51ba729bc9e5fe3e0b711862
Oct 15 21:28:00.563 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling-1/worker-3/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/target:rw,Z" "-v" "/mnt/big/crater/./work/local/test-source/worker-3/rustdoc-test-static-cling-1/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/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 +0e07c4281c343e9e15a0a8fca79538ad1a8eb513-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:28:01.019 INFO blam! d6897111db52f053f346d0cc6d5755e4a997503d1c20519330396eb62d7495d5
Oct 15 21:28:01.021 INFO running `"docker" "start" "-a" "d6897111db52f053f346d0cc6d5755e4a997503d1c20519330396eb62d7495d5"`
Oct 15 21:28:02.134 INFO kablam! usermod: no changes
Oct 15 21:28:02.261 INFO kablam!    Compiling core_affinity v0.5.9
Oct 15 21:28:03.075 INFO kablam!    Compiling rs_lockfree v0.1.1 (/source)
Oct 15 21:28:16.235 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 14.08s
Oct 15 21:28:16.240 INFO kablam! su: No module specific data is present
Oct 15 21:28:16.593 INFO running `"docker" "rm" "-f" "d6897111db52f053f346d0cc6d5755e4a997503d1c20519330396eb62d7495d5"`
Oct 15 21:28:16.723 INFO blam! d6897111db52f053f346d0cc6d5755e4a997503d1c20519330396eb62d7495d5
Oct 15 21:28:16.729 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling-1/worker-3/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/target:rw,Z" "-v" "/mnt/big/crater/./work/local/test-source/worker-3/rustdoc-test-static-cling-1/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/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 +0e07c4281c343e9e15a0a8fca79538ad1a8eb513-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:28:17.043 INFO blam! 68a112f152a330279381274b5d9c3a665e2eb4825a55ce25d2b3418a3a0c0f7c
Oct 15 21:28:17.049 INFO running `"docker" "start" "-a" "68a112f152a330279381274b5d9c3a665e2eb4825a55ce25d2b3418a3a0c0f7c"`
Oct 15 21:28:18.166 INFO kablam! usermod: no changes
Oct 15 21:28:18.263 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 0.08s
Oct 15 21:28:18.279 INFO kablam!      Running /target/debug/deps/rs_lockfree-d97e7426c558e7d3
Oct 15 21:28:18.279 INFO blam! 
Oct 15 21:28:18.279 INFO blam! running 7 tests
Oct 15 21:28:18.284 INFO blam! test lockfree_queue::test::test_base ... ok
Oct 15 21:28:18.284 INFO blam! test lockfree_stack::test::test_base ... ok
Oct 15 21:28:18.284 INFO blam! test lockfree_queue::test::test_memory_leak ... ok
Oct 15 21:28:18.284 INFO blam! test lockfree_stack::test::test_memory_leak ... ok
Oct 15 21:28:18.284 INFO blam! test spin_rwlock::test::test_rwlock ... ok
Oct 15 21:28:18.284 INFO blam! test error::test::test_base ... ok
Oct 15 21:28:18.290 INFO blam! test spin_lock::test::test_spin_lock ... ok
Oct 15 21:28:18.290 INFO blam! 
Oct 15 21:28:18.290 INFO blam! test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Oct 15 21:28:18.290 INFO blam! 
Oct 15 21:28:18.291 INFO kablam!      Running /target/debug/deps/test_hazard_epoch-ca3748f3aad8cd85
Oct 15 21:28:18.295 INFO blam! 
Oct 15 21:28:18.295 INFO blam! running 2 tests
Oct 15 21:28:18.295 INFO blam! test test_base ... ok
Oct 15 21:28:18.295 INFO blam! set_cpu_affinity 6 6
Oct 15 21:28:18.295 INFO blam! set_cpu_affinity 2 2
Oct 15 21:28:18.295 INFO blam! set_cpu_affinity 4 4
Oct 15 21:28:18.311 INFO blam! hazard_waiting_count=767
Oct 15 21:28:18.314 INFO blam! set_cpu_affinity 3 3
Oct 15 21:28:18.315 INFO blam! set_cpu_affinity 7 7
Oct 15 21:28:18.315 INFO blam! set_cpu_affinity 8 0
Oct 15 21:28:18.315 INFO blam! set_cpu_affinity 5 5
Oct 15 21:28:18.315 INFO blam! set_cpu_affinity 9 1
Oct 15 21:28:19.323 INFO blam! hazard_waiting_count=2446
Oct 15 21:28:20.327 INFO blam! hazard_waiting_count=6932
Oct 15 21:28:21.331 INFO blam! hazard_waiting_count=36872
Oct 15 21:28:22.355 INFO blam! hazard_waiting_count=8693
Oct 15 21:28:23.361 INFO blam! hazard_waiting_count=10616
Oct 15 21:28:24.383 INFO blam! hazard_waiting_count=6758
Oct 15 21:28:25.387 INFO blam! hazard_waiting_count=36751
Oct 15 21:28:26.399 INFO blam! hazard_waiting_count=5463
Oct 15 21:28:27.430 INFO blam! hazard_waiting_count=197
Oct 15 21:28:28.447 INFO blam! hazard_waiting_count=71349
Oct 15 21:28:29.459 INFO blam! hazard_waiting_count=50658
Oct 15 21:28:30.478 INFO blam! hazard_waiting_count=466
Oct 15 21:28:31.479 INFO blam! hazard_waiting_count=60913
Oct 15 21:28:32.495 INFO blam! hazard_waiting_count=203
Oct 15 21:28:33.495 INFO blam! hazard_waiting_count=9806
Oct 15 21:28:34.503 INFO blam! hazard_waiting_count=476
Oct 15 21:28:35.511 INFO blam! hazard_waiting_count=11132
Oct 15 21:28:36.509 INFO blam! hazard_waiting_count=10720
Oct 15 21:28:37.515 INFO blam! hazard_waiting_count=8642
Oct 15 21:28:38.515 INFO blam! hazard_waiting_count=0
Oct 15 21:28:39.515 INFO blam! test test_multi_thread ... ok
Oct 15 21:28:39.516 INFO blam! 
Oct 15 21:28:39.516 INFO blam! test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Oct 15 21:28:39.516 INFO blam! 
Oct 15 21:28:39.517 INFO kablam!      Running /target/debug/deps/test_lockfree_queue-64b15bbfd5e0f509
Oct 15 21:28:39.524 INFO blam! 
Oct 15 21:28:39.524 INFO blam! running 1 test
Oct 15 21:28:39.531 INFO blam! debug_thread produced 0 consumed 0
Oct 15 21:28:39.535 INFO blam! set_cpu_affinity 1 1
Oct 15 21:28:39.535 INFO blam! set_cpu_affinity 3 3
Oct 15 21:28:39.535 INFO blam! set_cpu_affinity 6 6
Oct 15 21:28:39.538 INFO blam! set_cpu_affinity 2 2
Oct 15 21:28:39.539 INFO blam! set_cpu_affinity 5 5
Oct 15 21:28:39.539 INFO blam! set_cpu_affinity 4 4
Oct 15 21:28:39.545 INFO blam! set_cpu_affinity 8 0
Oct 15 21:28:39.547 INFO blam! set_cpu_affinity 7 7
Oct 15 21:28:40.551 INFO blam! debug_thread produced 472068 consumed 428032
Oct 15 21:28:41.563 INFO blam! debug_thread produced 1033732 consumed 840192
Oct 15 21:28:42.575 INFO blam! debug_thread produced 1594372 consumed 1356800
Oct 15 21:28:43.579 INFO blam! debug_thread produced 2201092 consumed 1809408
Oct 15 21:28:44.579 INFO blam! debug_thread produced 2778628 consumed 2378752
Oct 15 21:28:45.583 INFO blam! debug_thread produced 3308036 consumed 3064832
Oct 15 21:28:46.591 INFO blam! debug_thread produced 3790852 consumed 3684352
Oct 15 21:28:47.611 INFO blam! debug_thread produced 4453380 consumed 4195840
Oct 15 21:28:48.644 INFO blam! debug_thread produced 4983812 consumed 4685824
Oct 15 21:28:49.659 INFO blam! debug_thread produced 5498372 consumed 5105152
Oct 15 21:28:50.663 INFO blam! debug_thread produced 5959684 consumed 5532160
Oct 15 21:28:51.687 INFO blam! debug_thread produced 6481924 consumed 5998592
Oct 15 21:28:52.714 INFO blam! debug_thread produced 7255044 consumed 6565888
Oct 15 21:28:53.715 INFO blam! debug_thread produced 8049156 consumed 7141888
Oct 15 21:28:54.711 INFO blam! debug_thread produced 8945156 consumed 7691776
Oct 15 21:28:55.719 INFO blam! debug_thread produced 10176004 consumed 8203776
Oct 15 21:28:56.719 INFO blam! debug_thread produced 10999295 consumed 8979456
Oct 15 21:28:58.179 INFO blam! test test_multi_threads ... ok
Oct 15 21:28:58.179 INFO blam! 
Oct 15 21:28:58.179 INFO blam! test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Oct 15 21:28:58.179 INFO blam! 
Oct 15 21:28:58.179 INFO blam! 
Oct 15 21:28:58.179 INFO blam! running 1 test
Oct 15 21:28:58.179 INFO kablam!      Running /target/debug/deps/test_lockfree_stack-7b4a623e877d2b06
Oct 15 21:28:58.183 INFO blam! set_cpu_affinity 2 2
Oct 15 21:28:58.183 INFO blam! set_cpu_affinity 1 1
Oct 15 21:28:58.187 INFO blam! set_cpu_affinity 5 5
Oct 15 21:28:58.187 INFO blam! set_cpu_affinity 6 6
Oct 15 21:28:58.187 INFO blam! set_cpu_affinity 3 3
Oct 15 21:28:58.187 INFO blam! set_cpu_affinity 7 7
Oct 15 21:28:58.195 INFO blam! set_cpu_affinity 4 4
Oct 15 21:28:58.195 INFO blam! set_cpu_affinity 8 0
Oct 15 21:28:58.200 INFO blam! debug_thread produced 22020 consumed 10752
Oct 15 21:28:59.203 INFO blam! debug_thread produced 651780 consumed 636928
Oct 15 21:29:00.203 INFO blam! debug_thread produced 1506820 consumed 1300992
Oct 15 21:29:01.211 INFO blam! debug_thread produced 2359300 consumed 2065408
Oct 15 21:29:02.208 INFO blam! debug_thread produced 3264004 consumed 2882560
Oct 15 21:29:03.218 INFO blam! debug_thread produced 4238340 consumed 3876864
Oct 15 21:29:04.209 INFO blam! debug_thread produced 5240324 consumed 4519424
Oct 15 21:29:05.230 INFO blam! debug_thread produced 6151684 consumed 5362688
Oct 15 21:29:06.247 INFO blam! debug_thread produced 7018500 consumed 6036992
Oct 15 21:29:07.251 INFO blam! debug_thread produced 7922692 consumed 6657536
Oct 15 21:29:08.259 INFO blam! debug_thread produced 9337860 consumed 7094272
Oct 15 21:29:09.260 INFO blam! debug_thread produced 10390701 consumed 7973888
Oct 15 21:29:10.259 INFO blam! debug_thread produced 11105791 consumed 9271808
Oct 15 21:29:11.863 INFO kablam!    Doc-tests rs_lockfree
Oct 15 21:29:11.863 INFO blam! test test_multi_threads ... ok
Oct 15 21:29:11.863 INFO blam! 
Oct 15 21:29:11.863 INFO blam! test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Oct 15 21:29:11.863 INFO blam! 
Oct 15 21:29:12.914 INFO blam! 
Oct 15 21:29:12.914 INFO blam! running 8 tests
Oct 15 21:29:15.753 INFO blam! test src/hazard_epoch.rs - hazard_epoch::HazardEpoch::new_in_heap (line 119) ... ok
Oct 15 21:29:16.014 INFO blam! test src/hazard_epoch.rs - hazard_epoch::HazardEpoch::new_in_stack (line 82) ... ok
Oct 15 21:29:16.379 INFO blam! test src/hazard_epoch.rs - hazard_epoch::HazardEpoch::retire (line 157) ... ok
Oct 15 21:29:16.573 INFO blam! test src/hazard_epoch.rs - hazard_epoch::HazardEpoch::acquire (line 263) ... ok
Oct 15 21:29:16.767 INFO blam! test src/hazard_epoch.rs - hazard_epoch::HazardEpoch::add_node (line 193) ... ok
Oct 15 21:29:16.788 INFO blam! test src/lockfree_queue.rs - lockfree_queue::LockFreeQueue (line 58) ... ok
Oct 15 21:29:16.799 INFO blam! test src/hazard_pointer.rs - hazard_pointer::HazardNodeT::get_base_hazard_node (line 95) ... ok
Oct 15 21:29:16.911 INFO blam! test src/lockfree_stack.rs - lockfree_stack::LockFreeStack (line 58) ... ok
Oct 15 21:29:16.911 INFO blam! 
Oct 15 21:29:16.911 INFO blam! test result: ok. 8 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Oct 15 21:29:16.911 INFO blam! 
Oct 15 21:29:16.922 INFO kablam! su: No module specific data is present
Oct 15 21:29:17.240 INFO running `"docker" "rm" "-f" "68a112f152a330279381274b5d9c3a665e2eb4825a55ce25d2b3418a3a0c0f7c"`
Oct 15 21:29:17.344 INFO blam! 68a112f152a330279381274b5d9c3a665e2eb4825a55ce25d2b3418a3a0c0f7c