Oct 11 13:12:56.812 INFO testing rs_lockfree-0.1.1 against master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513 for rustdoc-test-static-cling
Oct 11 13:12:56.812 INFO running: cargo +0e07c4281c343e9e15a0a8fca79538ad1a8eb513-alt build --frozen
Oct 11 13:12:56.813 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/test-source/worker-3/rustdoc-test-static-cling/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/source:ro,Z" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling/worker-3/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/target:rw,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" "CMD=cargo +0e07c4281c343e9e15a0a8fca79538ad1a8eb513-alt build --frozen" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-m" "1536M" "--network" "none" "crater"`
Oct 11 13:12:57.124 INFO blam! af859db0b06efd3879606d4b2b2f47e5cec2930a8422e798a1ab01f2678dd404
Oct 11 13:12:57.124 INFO running `"docker" "start" "-a" "af859db0b06efd3879606d4b2b2f47e5cec2930a8422e798a1ab01f2678dd404"`
Oct 11 13:12:58.975 INFO kablam! usermod: no changes
Oct 11 13:12:59.112 INFO kablam!    Compiling rs_lockfree v0.1.1 (/source)
Oct 11 13:13:00.852 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 1.80s
Oct 11 13:13:00.852 INFO kablam! su: No module specific data is present
Oct 11 13:13:01.618 INFO running `"docker" "rm" "-f" "af859db0b06efd3879606d4b2b2f47e5cec2930a8422e798a1ab01f2678dd404"`
Oct 11 13:13:01.912 INFO blam! af859db0b06efd3879606d4b2b2f47e5cec2930a8422e798a1ab01f2678dd404
Oct 11 13:13:01.921 INFO running: cargo +0e07c4281c343e9e15a0a8fca79538ad1a8eb513-alt test --frozen --no-run
Oct 11 13:13:01.922 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/test-source/worker-3/rustdoc-test-static-cling/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/source:ro,Z" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling/worker-3/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/target:rw,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" "CMD=cargo +0e07c4281c343e9e15a0a8fca79538ad1a8eb513-alt test --frozen --no-run" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-m" "1536M" "--network" "none" "crater"`
Oct 11 13:13:04.930 INFO blam! c82773e3096038eb56489bd6339703d3ff58f46db6ed946e01d74ee299925d41
Oct 11 13:13:04.932 INFO running `"docker" "start" "-a" "c82773e3096038eb56489bd6339703d3ff58f46db6ed946e01d74ee299925d41"`
Oct 11 13:13:06.228 INFO kablam! usermod: no changes
Oct 11 13:13:06.380 INFO kablam!    Compiling rs_lockfree v0.1.1 (/source)
Oct 11 13:13:23.638 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 17.39s
Oct 11 13:13:23.655 INFO kablam! su: No module specific data is present
Oct 11 13:13:24.558 INFO running `"docker" "rm" "-f" "c82773e3096038eb56489bd6339703d3ff58f46db6ed946e01d74ee299925d41"`
Oct 11 13:13:25.023 INFO blam! c82773e3096038eb56489bd6339703d3ff58f46db6ed946e01d74ee299925d41
Oct 11 13:13:25.026 INFO running: cargo +0e07c4281c343e9e15a0a8fca79538ad1a8eb513-alt test --frozen
Oct 11 13:13:25.028 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/test-source/worker-3/rustdoc-test-static-cling/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/source:ro,Z" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling/worker-3/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/target:rw,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" "CMD=cargo +0e07c4281c343e9e15a0a8fca79538ad1a8eb513-alt test --frozen" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-m" "1536M" "--network" "none" "crater"`
Oct 11 13:13:25.536 INFO blam! d83cb4f758bc30ad723f650df9b9056f34350140077c0ab8bd472710d2f514d1
Oct 11 13:13:25.538 INFO running `"docker" "start" "-a" "d83cb4f758bc30ad723f650df9b9056f34350140077c0ab8bd472710d2f514d1"`
Oct 11 13:13:27.060 INFO kablam! usermod: no changes
Oct 11 13:13:27.226 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 0.11s
Oct 11 13:13:27.234 INFO kablam!      Running /target/debug/deps/rs_lockfree-d97e7426c558e7d3
Oct 11 13:13:27.239 INFO blam! 
Oct 11 13:13:27.239 INFO blam! running 7 tests
Oct 11 13:13:27.248 INFO blam! test lockfree_stack::test::test_base ... ok
Oct 11 13:13:27.248 INFO blam! test error::test::test_base ... ok
Oct 11 13:13:27.248 INFO blam! test lockfree_queue::test::test_base ... ok
Oct 11 13:13:27.248 INFO blam! test lockfree_queue::test::test_memory_leak ... ok
Oct 11 13:13:27.248 INFO blam! test lockfree_stack::test::test_memory_leak ... ok
Oct 11 13:13:27.248 INFO blam! test spin_lock::test::test_spin_lock ... ok
Oct 11 13:13:27.250 INFO blam! test spin_rwlock::test::test_rwlock ... ok
Oct 11 13:13:27.250 INFO blam! 
Oct 11 13:13:27.250 INFO blam! test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Oct 11 13:13:27.250 INFO blam! 
Oct 11 13:13:27.256 INFO kablam!      Running /target/debug/deps/test_hazard_epoch-ca3748f3aad8cd85
Oct 11 13:13:27.256 INFO blam! 
Oct 11 13:13:27.256 INFO blam! running 2 tests
Oct 11 13:13:27.256 INFO blam! test test_base ... ok
Oct 11 13:13:27.260 INFO blam! set_cpu_affinity 4 4
Oct 11 13:13:27.260 INFO blam! set_cpu_affinity 3 3
Oct 11 13:13:27.268 INFO blam! set_cpu_affinity 5 5
Oct 11 13:13:27.268 INFO blam! set_cpu_affinity 2 2
Oct 11 13:13:27.268 INFO blam! hazard_waiting_count=8314
Oct 11 13:13:27.268 INFO blam! set_cpu_affinity 7 7
Oct 11 13:13:27.268 INFO blam! set_cpu_affinity 8 0
Oct 11 13:13:27.272 INFO blam! set_cpu_affinity 6 6
Oct 11 13:13:27.292 INFO blam! set_cpu_affinity 9 1
Oct 11 13:13:28.292 INFO blam! hazard_waiting_count=25995
Oct 11 13:13:29.312 INFO blam! hazard_waiting_count=179921
Oct 11 13:13:30.308 INFO blam! hazard_waiting_count=283577
Oct 11 13:13:31.308 INFO blam! hazard_waiting_count=199893
Oct 11 13:13:32.352 INFO blam! hazard_waiting_count=9783
Oct 11 13:13:33.336 INFO blam! hazard_waiting_count=41857
Oct 11 13:13:34.340 INFO blam! hazard_waiting_count=84896
Oct 11 13:13:35.348 INFO blam! hazard_waiting_count=5475
Oct 11 13:13:36.352 INFO blam! hazard_waiting_count=10414
Oct 11 13:13:37.368 INFO blam! hazard_waiting_count=9886
Oct 11 13:13:38.372 INFO blam! hazard_waiting_count=11881
Oct 11 13:13:39.384 INFO blam! hazard_waiting_count=4908
Oct 11 13:13:40.392 INFO blam! hazard_waiting_count=5473
Oct 11 13:13:41.408 INFO blam! hazard_waiting_count=5400
Oct 11 13:13:42.409 INFO blam! hazard_waiting_count=43792
Oct 11 13:13:43.432 INFO blam! hazard_waiting_count=8951
Oct 11 13:13:44.468 INFO blam! hazard_waiting_count=13693
Oct 11 13:13:45.487 INFO blam! hazard_waiting_count=10291
Oct 11 13:13:46.500 INFO blam! hazard_waiting_count=21621
Oct 11 13:13:47.500 INFO blam! hazard_waiting_count=29607
Oct 11 13:13:48.500 INFO blam! hazard_waiting_count=0
Oct 11 13:13:49.494 INFO blam! hazard_waiting_count=0
Oct 11 13:13:50.500 INFO blam! hazard_waiting_count=0
Oct 11 13:13:51.495 INFO blam! test test_multi_thread ... ok
Oct 11 13:13:51.495 INFO blam! 
Oct 11 13:13:51.495 INFO blam! test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Oct 11 13:13:51.495 INFO blam! 
Oct 11 13:13:51.497 INFO kablam!      Running /target/debug/deps/test_lockfree_queue-64b15bbfd5e0f509
Oct 11 13:13:51.500 INFO blam! 
Oct 11 13:13:51.500 INFO blam! running 1 test
Oct 11 13:13:51.500 INFO blam! debug_thread produced 0 consumed 0
Oct 11 13:13:51.506 INFO blam! set_cpu_affinity 1 1
Oct 11 13:13:51.507 INFO blam! set_cpu_affinity 2 2
Oct 11 13:13:51.508 INFO blam! set_cpu_affinity 3 3
Oct 11 13:13:51.512 INFO blam! set_cpu_affinity 4 4
Oct 11 13:13:51.544 INFO blam! set_cpu_affinity 5 5
Oct 11 13:13:51.564 INFO blam! set_cpu_affinity 6 6
Oct 11 13:13:51.564 INFO blam! set_cpu_affinity 7 7
Oct 11 13:13:51.564 INFO blam! set_cpu_affinity 8 0
Oct 11 13:13:52.512 INFO blam! debug_thread produced 681476 consumed 555008
Oct 11 13:13:53.518 INFO blam! debug_thread produced 1410564 consumed 1165824
Oct 11 13:13:54.524 INFO blam! debug_thread produced 2071044 consumed 1864704
Oct 11 13:13:55.528 INFO blam! debug_thread produced 2616836 consumed 2555904
Oct 11 13:13:56.544 INFO blam! debug_thread produced 3364356 consumed 3218944
Oct 11 13:13:57.544 INFO blam! debug_thread produced 4276228 consumed 3999744
Oct 11 13:13:58.542 INFO blam! debug_thread produced 5272580 consumed 4691968
Oct 11 13:13:59.541 INFO blam! debug_thread produced 6154244 consumed 5683712
Oct 11 13:14:00.541 INFO blam! debug_thread produced 7144964 consumed 6297600
Oct 11 13:14:01.541 INFO blam! debug_thread produced 8014340 consumed 7316480
Oct 11 13:14:02.552 INFO blam! debug_thread produced 8713901 consumed 8125440
Oct 11 13:14:03.559 INFO blam! debug_thread produced 9262422 consumed 8849408
Oct 11 13:14:04.572 INFO blam! debug_thread produced 9962838 consumed 9702912
Oct 11 13:14:05.572 INFO blam! debug_thread produced 10398038 consumed 10397184
Oct 11 13:14:06.576 INFO blam! debug_thread produced 10831871 consumed 10830848
Oct 11 13:14:07.565 INFO blam! test test_multi_threads ... ok
Oct 11 13:14:07.566 INFO blam! 
Oct 11 13:14:07.566 INFO blam! test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Oct 11 13:14:07.566 INFO blam! 
Oct 11 13:14:07.567 INFO kablam!      Running /target/debug/deps/test_lockfree_stack-7b4a623e877d2b06
Oct 11 13:14:07.572 INFO blam! 
Oct 11 13:14:07.573 INFO blam! running 1 test
Oct 11 13:14:07.574 INFO blam! debug_thread produced 0 consumed 0
Oct 11 13:14:07.577 INFO blam! set_cpu_affinity 1 1
Oct 11 13:14:07.584 INFO blam! set_cpu_affinity 2 2
Oct 11 13:14:07.584 INFO blam! set_cpu_affinity 3 3
Oct 11 13:14:07.584 INFO blam! set_cpu_affinity 4 4
Oct 11 13:14:07.584 INFO blam! set_cpu_affinity 6 6
Oct 11 13:14:07.587 INFO blam! set_cpu_affinity 7 7
Oct 11 13:14:07.587 INFO blam! set_cpu_affinity 8 0
Oct 11 13:14:07.596 INFO blam! set_cpu_affinity 5 5
Oct 11 13:14:08.620 INFO blam! debug_thread produced 593924 consumed 591872
Oct 11 13:14:09.604 INFO blam! debug_thread produced 1604612 consumed 1076736
Oct 11 13:14:10.620 INFO blam! debug_thread produced 2233860 consumed 1811968
Oct 11 13:14:11.622 INFO blam! debug_thread produced 3130372 consumed 2529792
Oct 11 13:14:12.628 INFO blam! debug_thread produced 3993604 consumed 3234816
Oct 11 13:14:13.632 INFO blam! debug_thread produced 4735492 consumed 3771392
Oct 11 13:14:14.632 INFO blam! debug_thread produced 5511172 consumed 4282368
Oct 11 13:14:15.640 INFO blam! debug_thread produced 6133764 consumed 5047808
Oct 11 13:14:16.636 INFO blam! debug_thread produced 6768132 consumed 5821440
Oct 11 13:14:17.656 INFO blam! debug_thread produced 7796740 consumed 6519808
Oct 11 13:14:18.644 INFO blam! debug_thread produced 8558084 consumed 7348736
Oct 11 13:14:19.652 INFO blam! debug_thread produced 9237677 consumed 8066560
Oct 11 13:14:20.653 INFO blam! debug_thread produced 9715373 consumed 9059840
Oct 11 13:14:21.669 INFO blam! debug_thread produced 10572118 consumed 9754112
Oct 11 13:14:22.668 INFO blam! debug_thread produced 10788863 consumed 10676224
Oct 11 13:14:23.704 INFO blam! test test_multi_threads ... ok
Oct 11 13:14:23.704 INFO blam! 
Oct 11 13:14:23.704 INFO blam! test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Oct 11 13:14:23.704 INFO blam! 
Oct 11 13:14:23.704 INFO kablam!    Doc-tests rs_lockfree
Oct 11 13:14:25.456 INFO blam! 
Oct 11 13:14:25.456 INFO blam! running 8 tests
Oct 11 13:14:32.742 INFO blam! test src/lockfree_stack.rs - lockfree_stack::LockFreeStack (line 58) ... ok
Oct 11 13:14:33.328 INFO blam! test src/hazard_epoch.rs - hazard_epoch::HazardEpoch::acquire (line 263) ... ok
Oct 11 13:14:33.542 INFO blam! test src/hazard_epoch.rs - hazard_epoch::HazardEpoch::new_in_stack (line 82) ... ok
Oct 11 13:14:34.684 INFO blam! test src/hazard_epoch.rs - hazard_epoch::HazardEpoch::add_node (line 193) ... ok
Oct 11 13:14:34.728 INFO blam! test src/hazard_epoch.rs - hazard_epoch::HazardEpoch::retire (line 157) ... ok
Oct 11 13:14:35.088 INFO blam! test src/lockfree_queue.rs - lockfree_queue::LockFreeQueue (line 58) ... ok
Oct 11 13:14:35.238 INFO blam! test src/hazard_pointer.rs - hazard_pointer::HazardNodeT::get_base_hazard_node (line 95) ... ok
Oct 11 13:14:35.282 INFO blam! test src/hazard_epoch.rs - hazard_epoch::HazardEpoch::new_in_heap (line 119) ... ok
Oct 11 13:14:35.282 INFO blam! 
Oct 11 13:14:35.282 INFO blam! test result: ok. 8 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Oct 11 13:14:35.282 INFO blam! 
Oct 11 13:14:35.300 INFO kablam! su: No module specific data is present
Oct 11 13:14:35.949 INFO running `"docker" "rm" "-f" "d83cb4f758bc30ad723f650df9b9056f34350140077c0ab8bd472710d2f514d1"`
Oct 11 13:14:36.186 INFO blam! d83cb4f758bc30ad723f650df9b9056f34350140077c0ab8bd472710d2f514d1