[INFO] crate rs_lockfree 0.1.1 is already in cache
[INFO] extracting crate rs_lockfree 0.1.1 into work/ex/pr-57967/sources/master#c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54/reg/rs_lockfree/0.1.1
[INFO] extracting crate rs_lockfree 0.1.1 into work/ex/pr-57967/sources/try#7fea0229842e79db1159ea58ad9a653b288efee9/reg/rs_lockfree/0.1.1
[INFO] validating manifest of rs_lockfree-0.1.1 on toolchain master#c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54
[INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54-alt" "read-manifest" "--manifest-path" "Cargo.toml"`
[INFO] validating manifest of rs_lockfree-0.1.1 on toolchain try#7fea0229842e79db1159ea58ad9a653b288efee9
[INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+7fea0229842e79db1159ea58ad9a653b288efee9-alt" "read-manifest" "--manifest-path" "Cargo.toml"`
[INFO] started frobbing rs_lockfree-0.1.1
[INFO] finished frobbing rs_lockfree-0.1.1
[INFO] frobbed toml for rs_lockfree-0.1.1 written to work/ex/pr-57967/sources/master#c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54/reg/rs_lockfree/0.1.1/Cargo.toml
[INFO] started frobbing rs_lockfree-0.1.1
[INFO] finished frobbing rs_lockfree-0.1.1
[INFO] frobbed toml for rs_lockfree-0.1.1 written to work/ex/pr-57967/sources/try#7fea0229842e79db1159ea58ad9a653b288efee9/reg/rs_lockfree/0.1.1/Cargo.toml
[INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54-alt" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update"`
[INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+7fea0229842e79db1159ea58ad9a653b288efee9-alt" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update"`
[INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54-alt" "fetch" "--locked" "--manifest-path" "Cargo.toml"`
[INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+7fea0229842e79db1159ea58ad9a653b288efee9-alt" "fetch" "--locked" "--manifest-path" "Cargo.toml"`
[INFO] testing rs_lockfree-0.1.1 against master#c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54 for pr-57967
[INFO] running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-57967/worker-2/master#c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54:/opt/crater/target:rw,Z" "-v" "/mnt/big/crater/work/ex/pr-57967/sources/master#c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54/reg/rs_lockfree/0.1.1:/opt/crater/workdir:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/opt/crater/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/opt/crater/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/opt/crater/workdir" "-e" "MAP_USER_ID=1000" "-e" "CARGO_TARGET_DIR=/opt/crater/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/crater/cargo-home" "-e" "RUSTUP_HOME=/opt/crater/rustup-home" "-w" "/opt/crater/workdir" "-m" "1536M" "--network" "none" "rustops/crates-build-env" "/opt/crater/cargo-home/bin/cargo" "+c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54-alt" "build" "--frozen"`
[INFO] [stdout] 74ac7b1b1b80009508892fe2fc004e928495c3c92b61ea87ea0e3d7626defe47
[INFO] running `"docker" "start" "-a" "74ac7b1b1b80009508892fe2fc004e928495c3c92b61ea87ea0e3d7626defe47"`
[INFO] [stderr]    Compiling rs_lockfree v0.1.1 (/opt/crater/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.99s
[INFO] running `"docker" "inspect" "74ac7b1b1b80009508892fe2fc004e928495c3c92b61ea87ea0e3d7626defe47"`
[INFO] running `"docker" "rm" "-f" "74ac7b1b1b80009508892fe2fc004e928495c3c92b61ea87ea0e3d7626defe47"`
[INFO] [stdout] 74ac7b1b1b80009508892fe2fc004e928495c3c92b61ea87ea0e3d7626defe47
[INFO] running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-57967/worker-2/master#c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54:/opt/crater/target:rw,Z" "-v" "/mnt/big/crater/work/ex/pr-57967/sources/master#c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54/reg/rs_lockfree/0.1.1:/opt/crater/workdir:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/opt/crater/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/opt/crater/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/opt/crater/workdir" "-e" "MAP_USER_ID=1000" "-e" "CARGO_TARGET_DIR=/opt/crater/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/crater/cargo-home" "-e" "RUSTUP_HOME=/opt/crater/rustup-home" "-w" "/opt/crater/workdir" "-m" "1536M" "--network" "none" "rustops/crates-build-env" "/opt/crater/cargo-home/bin/cargo" "+c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54-alt" "test" "--frozen" "--no-run"`
[INFO] [stdout] 37f2163dff28b9edbcf7645277261ec80b73305c926ed0d8c07c72d2c4725c9e
[INFO] running `"docker" "start" "-a" "37f2163dff28b9edbcf7645277261ec80b73305c926ed0d8c07c72d2c4725c9e"`
[INFO] [stderr]    Compiling core_affinity v0.5.9
[INFO] [stderr]    Compiling rs_lockfree v0.1.1 (/opt/crater/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 11.34s
[INFO] running `"docker" "inspect" "37f2163dff28b9edbcf7645277261ec80b73305c926ed0d8c07c72d2c4725c9e"`
[INFO] running `"docker" "rm" "-f" "37f2163dff28b9edbcf7645277261ec80b73305c926ed0d8c07c72d2c4725c9e"`
[INFO] [stdout] 37f2163dff28b9edbcf7645277261ec80b73305c926ed0d8c07c72d2c4725c9e
[INFO] running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-57967/worker-2/master#c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54:/opt/crater/target:rw,Z" "-v" "/mnt/big/crater/work/ex/pr-57967/sources/master#c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54/reg/rs_lockfree/0.1.1:/opt/crater/workdir:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/opt/crater/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/opt/crater/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/opt/crater/workdir" "-e" "MAP_USER_ID=1000" "-e" "CARGO_TARGET_DIR=/opt/crater/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/crater/cargo-home" "-e" "RUSTUP_HOME=/opt/crater/rustup-home" "-w" "/opt/crater/workdir" "-m" "1536M" "--network" "none" "rustops/crates-build-env" "/opt/crater/cargo-home/bin/cargo" "+c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54-alt" "test" "--frozen"`
[INFO] [stdout] bb596de8bd8b638e875958fe856a62d9ea6672fc7564cd9e76b8d5e885f7b7f1
[INFO] running `"docker" "start" "-a" "bb596de8bd8b638e875958fe856a62d9ea6672fc7564cd9e76b8d5e885f7b7f1"`
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.08s
[INFO] [stdout] 
[INFO] [stderr]      Running /opt/crater/target/debug/deps/rs_lockfree-1ca70dfb08cdba05
[INFO] [stdout] running 7 tests
[INFO] [stdout] test error::test::test_base ... ok
[INFO] [stdout] test spin_rwlock::test::test_rwlock ... ok
[INFO] [stdout] test lockfree_stack::test::test_base ... ok
[INFO] [stdout] test lockfree_stack::test::test_memory_leak ... ok
[INFO] [stdout] test lockfree_queue::test::test_base ... ok
[INFO] [stdout] test spin_lock::test::test_spin_lock ... ok
[INFO] [stdout] test lockfree_queue::test::test_memory_leak ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
[INFO] [stdout] 
[INFO] [stderr]      Running /opt/crater/target/debug/deps/test_hazard_epoch-16957279a2bd41e9
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test test_base ... ok
[INFO] [stdout] set_cpu_affinity 5 5
[INFO] [stdout] set_cpu_affinity 3 3
[INFO] [stdout] set_cpu_affinity 4 4
[INFO] [stdout] set_cpu_affinity 2 2
[INFO] [stdout] set_cpu_affinity 6 6
[INFO] [stdout] set_cpu_affinity 9 1
[INFO] [stdout] set_cpu_affinity 8 0
[INFO] [stdout] set_cpu_affinity 7 7
[INFO] [stdout] hazard_waiting_count=1059
[INFO] [stdout] hazard_waiting_count=13794
[INFO] [stdout] hazard_waiting_count=30388
[INFO] [stdout] hazard_waiting_count=24567
[INFO] [stdout] hazard_waiting_count=11450
[INFO] [stdout] hazard_waiting_count=8779
[INFO] [stdout] hazard_waiting_count=12139
[INFO] [stdout] hazard_waiting_count=32073
[INFO] [stdout] hazard_waiting_count=516
[INFO] [stdout] hazard_waiting_count=6322
[INFO] [stdout] hazard_waiting_count=16917
[INFO] [stdout] hazard_waiting_count=34135
[INFO] [stdout] hazard_waiting_count=8128
[INFO] [stdout] hazard_waiting_count=12024
[INFO] [stdout] hazard_waiting_count=38787
[INFO] [stdout] hazard_waiting_count=259510
[INFO] [stdout] hazard_waiting_count=254957
[INFO] [stdout] hazard_waiting_count=290969
[INFO] [stdout] hazard_waiting_count=6795
[INFO] [stdout] hazard_waiting_count=11107
[INFO] [stdout] hazard_waiting_count=598
[INFO] [stdout] hazard_waiting_count=5151
[INFO] [stdout] hazard_waiting_count=3969
[INFO] [stdout] hazard_waiting_count=249
[INFO] [stdout] hazard_waiting_count=249
[INFO] [stdout] test test_multi_thread ... ok
[INFO] [stderr]      Running /opt/crater/target/debug/deps/test_lockfree_queue-f3ac3bf1e362c61a
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] debug_thread produced 0 consumed 0
[INFO] [stdout] set_cpu_affinity 1 1
[INFO] [stdout] set_cpu_affinity 4 4
[INFO] [stdout] set_cpu_affinity 5 5
[INFO] [stdout] set_cpu_affinity 3 3
[INFO] [stdout] set_cpu_affinity 6 6
[INFO] [stdout] set_cpu_affinity 7 7
[INFO] [stdout] set_cpu_affinity 2 2
[INFO] [stdout] set_cpu_affinity 8 0
[INFO] [stdout] debug_thread produced 683012 consumed 672768
[INFO] [stdout] debug_thread produced 1265668 consumed 1146880
[INFO] [stdout] debug_thread produced 1761796 consumed 1601536
[INFO] [stdout] debug_thread produced 2246148 consumed 2056704
[INFO] [stdout] debug_thread produced 2716676 consumed 2516480
[INFO] [stdout] debug_thread produced 3160068 consumed 3070464
[INFO] [stdout] debug_thread produced 3569668 consumed 3509248
[INFO] [stdout] debug_thread produced 4019716 consumed 3937280
[INFO] [stdout] debug_thread produced 4551684 consumed 4512768
[INFO] [stdout] debug_thread produced 5106180 consumed 5092864
[INFO] [stdout] debug_thread produced 5519364 consumed 5518848
[INFO] [stdout] debug_thread produced 6040068 consumed 6022656
[INFO] [stdout] debug_thread produced 6519812 consumed 6519296
[INFO] [stdout] debug_thread produced 6924292 consumed 6922752
[INFO] [stdout] debug_thread produced 7429293 consumed 7426048
[INFO] [stdout] debug_thread produced 7862445 consumed 7862272
[INFO] [stdout] debug_thread produced 8357037 consumed 8339456
[INFO] [stdout] debug_thread produced 8964781 consumed 8964608
[INFO] [stdout] debug_thread produced 9563821 consumed 9564160
[INFO] [stdout] debug_thread produced 10181805 consumed 10180608
[INFO] [stdout] debug_thread produced 10689709 consumed 10689024
[INFO] [stdout] test test_multi_threads ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]      Running /opt/crater/target/debug/deps/test_lockfree_stack-58590eaa4cbcf5cf
[INFO] [stdout] running 1 test
[INFO] [stdout] debug_thread produced 0 consumed 0
[INFO] [stdout] set_cpu_affinity 4 4
[INFO] [stdout] set_cpu_affinity 2 2
[INFO] [stdout] set_cpu_affinity 1 1
[INFO] [stdout] set_cpu_affinity 5 5
[INFO] [stdout] set_cpu_affinity 6 6
[INFO] [stdout] set_cpu_affinity 3 3
[INFO] [stdout] set_cpu_affinity 8 0
[INFO] [stdout] set_cpu_affinity 7 7
[INFO] [stdout] debug_thread produced 628740 consumed 597504
[INFO] [stdout] debug_thread produced 1244164 consumed 1234944
[INFO] [stdout] debug_thread produced 1907204 consumed 1888256
[INFO] [stdout] debug_thread produced 2579460 consumed 2579456
[INFO] [stdout] debug_thread produced 3330564 consumed 3257856
[INFO] [stdout] debug_thread produced 3956740 consumed 3877376
[INFO] [stdout] debug_thread produced 4477444 consumed 4407808
[INFO] [stdout] debug_thread produced 5159940 consumed 4992000
[INFO] [stdout] debug_thread produced 5886980 consumed 5609984
[INFO] [stdout] debug_thread produced 6461444 consumed 6262784
[INFO] [stdout] debug_thread produced 7203332 consumed 6965248
[INFO] [stdout] debug_thread produced 7934980 consumed 7670784
[INFO] [stdout] debug_thread produced 8548013 consumed 8475648
[INFO] [stdout] debug_thread produced 8882006 consumed 8881664
[INFO] [stdout] debug_thread produced 9240918 consumed 9240576
[INFO] [stdout] debug_thread produced 9605462 consumed 9605120
[INFO] [stdout] debug_thread produced 10094422 consumed 10093056
[INFO] [stdout] debug_thread produced 10467158 consumed 10466816
[INFO] [stdout] debug_thread produced 10993663 consumed 10993152
[INFO] [stdout] test test_multi_threads ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests rs_lockfree
[INFO] [stdout] 
[INFO] [stdout] running 8 tests
[INFO] [stdout] test src/hazard_epoch.rs - hazard_epoch::HazardEpoch::retire (line 157) ... ok
[INFO] [stdout] test src/hazard_pointer.rs - hazard_pointer::HazardNodeT::get_base_hazard_node (line 95) ... ok
[INFO] [stdout] test src/hazard_epoch.rs - hazard_epoch::HazardEpoch::acquire (line 263) ... ok
[INFO] [stdout] test src/hazard_epoch.rs - hazard_epoch::HazardEpoch::new_in_heap (line 119) ... ok
[INFO] [stdout] test src/hazard_epoch.rs - hazard_epoch::HazardEpoch::new_in_stack (line 82) ... ok
[INFO] [stdout] test src/lockfree_queue.rs - lockfree_queue::LockFreeQueue (line 58) ... ok
[INFO] [stdout] test src/hazard_epoch.rs - hazard_epoch::HazardEpoch::add_node (line 193) ... ok
[INFO] [stdout] test src/lockfree_stack.rs - lockfree_stack::LockFreeStack (line 58) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 8 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
[INFO] [stdout] 
[INFO] running `"docker" "inspect" "bb596de8bd8b638e875958fe856a62d9ea6672fc7564cd9e76b8d5e885f7b7f1"`
[INFO] running `"docker" "rm" "-f" "bb596de8bd8b638e875958fe856a62d9ea6672fc7564cd9e76b8d5e885f7b7f1"`
[INFO] [stdout] bb596de8bd8b638e875958fe856a62d9ea6672fc7564cd9e76b8d5e885f7b7f1