[INFO] fetching crate voluntary-servitude 4.0.7...
[INFO] extracting crate voluntary-servitude 4.0.7 into work/ex/pr-61629/sources/master#c8865d8e195813ade6b84434ac9f8850e7112d1a/reg/voluntary-servitude/4.0.7
[INFO] extracting crate voluntary-servitude 4.0.7 into work/ex/pr-61629/sources/try#e212b7d492c96d3837691ba29d32d5f468933fb8/reg/voluntary-servitude/4.0.7
[INFO] validating manifest of voluntary-servitude-4.0.7 on toolchain master#c8865d8e195813ade6b84434ac9f8850e7112d1a
[INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+c8865d8e195813ade6b84434ac9f8850e7112d1a-alt" "read-manifest" "--manifest-path" "Cargo.toml"`
[INFO] validating manifest of voluntary-servitude-4.0.7 on toolchain try#e212b7d492c96d3837691ba29d32d5f468933fb8
[INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+e212b7d492c96d3837691ba29d32d5f468933fb8-alt" "read-manifest" "--manifest-path" "Cargo.toml"`
[INFO] started frobbing voluntary-servitude-4.0.7
[INFO] finished frobbing voluntary-servitude-4.0.7
[INFO] frobbed toml for voluntary-servitude-4.0.7 written to work/ex/pr-61629/sources/master#c8865d8e195813ade6b84434ac9f8850e7112d1a/reg/voluntary-servitude/4.0.7/Cargo.toml
[INFO] started frobbing voluntary-servitude-4.0.7
[INFO] finished frobbing voluntary-servitude-4.0.7
[INFO] frobbed toml for voluntary-servitude-4.0.7 written to work/ex/pr-61629/sources/try#e212b7d492c96d3837691ba29d32d5f468933fb8/reg/voluntary-servitude/4.0.7/Cargo.toml
[INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+c8865d8e195813ade6b84434ac9f8850e7112d1a-alt" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update"`
[INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+e212b7d492c96d3837691ba29d32d5f468933fb8-alt" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update"`
[INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+c8865d8e195813ade6b84434ac9f8850e7112d1a-alt" "fetch" "--locked" "--manifest-path" "Cargo.toml"`
[INFO] [stderr]     Blocking waiting for file lock on package cache lock
[INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+e212b7d492c96d3837691ba29d32d5f468933fb8-alt" "fetch" "--locked" "--manifest-path" "Cargo.toml"`
[INFO] [stderr]     Blocking waiting for file lock on package cache lock
[INFO] testing voluntary-servitude-4.0.7 against master#c8865d8e195813ade6b84434ac9f8850e7112d1a for pr-61629
[INFO] running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-61629/worker-1/master#c8865d8e195813ade6b84434ac9f8850e7112d1a:/opt/crater/target:rw,Z" "-v" "/mnt/big/crater/work/ex/pr-61629/sources/master#c8865d8e195813ade6b84434ac9f8850e7112d1a/reg/voluntary-servitude/4.0.7:/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" "+c8865d8e195813ade6b84434ac9f8850e7112d1a-alt" "build" "--frozen"`
[INFO] [stdout] aade96d8eb222b18f07544f8c30c0318dc97cad8c6dd0c4b0b5e668b9904b1aa
[INFO] running `"docker" "start" "-a" "aade96d8eb222b18f07544f8c30c0318dc97cad8c6dd0c4b0b5e668b9904b1aa"`
[INFO] [stderr]    Compiling smallvec v0.1.8
[INFO] [stderr]    Compiling parking_lot v0.2.8
[INFO] [stderr]    Compiling voluntary-servitude v4.0.7 (/opt/crater/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 2.55s
[INFO] running `"docker" "inspect" "aade96d8eb222b18f07544f8c30c0318dc97cad8c6dd0c4b0b5e668b9904b1aa"`
[INFO] running `"docker" "rm" "-f" "aade96d8eb222b18f07544f8c30c0318dc97cad8c6dd0c4b0b5e668b9904b1aa"`
[INFO] [stdout] aade96d8eb222b18f07544f8c30c0318dc97cad8c6dd0c4b0b5e668b9904b1aa
[INFO] running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-61629/worker-1/master#c8865d8e195813ade6b84434ac9f8850e7112d1a:/opt/crater/target:rw,Z" "-v" "/mnt/big/crater/work/ex/pr-61629/sources/master#c8865d8e195813ade6b84434ac9f8850e7112d1a/reg/voluntary-servitude/4.0.7:/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" "+c8865d8e195813ade6b84434ac9f8850e7112d1a-alt" "test" "--frozen" "--no-run"`
[INFO] [stdout] b51d33c509ede56b73180626964f6b73f8c78187ecc4162cb98aac56d42283a3
[INFO] running `"docker" "start" "-a" "b51d33c509ede56b73180626964f6b73f8c78187ecc4162cb98aac56d42283a3"`
[INFO] [stderr]    Compiling cast v0.2.2
[INFO] [stderr]    Compiling itertools v0.8.0
[INFO] [stderr]    Compiling aho-corasick v0.7.3
[INFO] [stderr]    Compiling rand_xoshiro v0.1.0
[INFO] [stderr]    Compiling tinytemplate v1.0.2
[INFO] [stderr]    Compiling csv v1.0.7
[INFO] [stderr]    Compiling regex v1.1.7
[INFO] [stderr]    Compiling criterion-plot v0.3.1
[INFO] [stderr]    Compiling criterion v0.2.11
[INFO] [stderr]    Compiling env_logger v0.5.13
[INFO] [stderr]    Compiling voluntary-servitude v4.0.7 (/opt/crater/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 32.70s
[INFO] running `"docker" "inspect" "b51d33c509ede56b73180626964f6b73f8c78187ecc4162cb98aac56d42283a3"`
[INFO] running `"docker" "rm" "-f" "b51d33c509ede56b73180626964f6b73f8c78187ecc4162cb98aac56d42283a3"`
[INFO] [stdout] b51d33c509ede56b73180626964f6b73f8c78187ecc4162cb98aac56d42283a3
[INFO] running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-61629/worker-1/master#c8865d8e195813ade6b84434ac9f8850e7112d1a:/opt/crater/target:rw,Z" "-v" "/mnt/big/crater/work/ex/pr-61629/sources/master#c8865d8e195813ade6b84434ac9f8850e7112d1a/reg/voluntary-servitude/4.0.7:/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" "+c8865d8e195813ade6b84434ac9f8850e7112d1a-alt" "test" "--frozen"`
[INFO] [stdout] 4c29de3e72be1fa022ed0ac05a79b80c66c1969e8d0cda14b94b92c88e606b86
[INFO] running `"docker" "start" "-a" "4c29de3e72be1fa022ed0ac05a79b80c66c1969e8d0cda14b94b92c88e606b86"`
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.09s
[INFO] [stderr]      Running /opt/crater/target/debug/deps/voluntary_servitude-e4fefb243b65d459
[INFO] [stdout] 
[INFO] [stdout] running 23 tests
[INFO] [stdout] test atomics::fill_once_atomic_option::tests::test_send ... ok
[INFO] [stdout] test atomics::fill_once_atomic_option::tests::fill_once_ref ... ok
[INFO] [stdout] test atomics::atomic::tests::test_sync ... ok
[INFO] [stdout] test atomics::fill_once_atomic_option::tests::test_sync ... ok
[INFO] [stdout] test atomics::atomic::tests::test_send ... ok
[INFO] [stdout] test iterator::tests::iter_after_use ... ok
[INFO] [stdout] test iterator::tests::iter_all ... ok
[INFO] [stdout] test iterator::tests::iter_doesnt_clear ... ok
[INFO] [stdout] test iterator::tests::iter_drop ... ok
[INFO] [stdout] test iterator::tests::iter_drop_many ... ok
[INFO] [stdout] test iterator::tests::iter_grows ... ok
[INFO] [stdout] test iterator::tests::iter_isnt_growable_when_consumed ... ok
[INFO] [stdout] test iterator::tests::iter_many ... ok
[INFO] [stdout] test voluntary_servitude::tests::extend_partial_eq ... ok
[INFO] [stdout] test voluntary_servitude::tests::iter_outlives ... ok
[INFO] [stdout] test voluntary_servitude::tests::swap_empty ... ok
[INFO] [stdout] test voluntary_servitude::tests::test_send ... ok
[INFO] [stdout] test voluntary_servitude::tests::test_sync ... ok
[INFO] [stdout] test voluntary_servitude::tests::voluntary_servitude_len_append_clear ... ok
[INFO] [stdout] test atomics::atomic_option::tests::test_send ... ok
[INFO] [stdout] test atomics::atomic_option::tests::test_sync ... ok
[INFO] [stdout] test atomics::fill_once_atomic_arc::tests::test_send ... ok
[INFO] [stdout] test atomics::fill_once_atomic_arc::tests::test_sync ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 23 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
[INFO] [stdout] 
[INFO] [stderr]      Running /opt/crater/target/debug/deps/test-838d2891eacf2b8f
[INFO] [stdout] 
[INFO] [stdout] running 11 tests
[INFO] [stdout] test elements_1b ... ignored
[INFO] [stdout] test elements_1m ... ignored
[INFO] [stdout] test elements_500m ... ignored
[INFO] [stdout] test elements_50m ... ignored
[INFO] [stdout] test clear ... ok
[INFO] [stdout] test single_thread ... ok
[INFO] [stdout] test single_producer_single_consumer ... ok
[INFO] [stdout] test single_producer_multi_consumer ... ok
[INFO] [stdout] test multi_producer_multi_consumer ... ok
[INFO] [stdout] test multi_producer_single_consumer ... ok
[INFO] [stdout] test elements_100k ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 7 passed; 0 failed; 4 ignored; 0 measured; 0 filtered out
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests voluntary_servitude
[INFO] [stdout] 
[INFO] [stdout] running 51 tests
[INFO] [stdout] test src/atomics/atomic.rs - atomics::atomic::Atomic<T>::into_inner (line 107) ... ok
[INFO] [stdout] test src/atomics/atomic.rs - atomics::atomic::Atomic<T>::get_raw (line 179) ... ok
[INFO] [stdout] test src/atomics/atomic.rs - atomics::atomic::Atomic<T>::swap (line 87) ... ok
[INFO] [stdout] test src/atomics/atomic.rs - atomics::atomic::Atomic<T>::new (line 51) ... ok
[INFO] [stdout] test src/atomics/atomic.rs - atomics::atomic::Atomic<T>::store (line 69) ... ok
[INFO] [stdout] test src/atomics/atomic_option.rs - atomics::atomic_option::AtomicOption<T>::atomic_ptr (line 147) ... ok
[INFO] [stdout] test src/atomics/atomic.rs - atomics::atomic::Atomic<T>::from_raw_unchecked (line 152) ... ok
[INFO] [stdout] test src/atomics/atomic.rs - atomics::atomic::Atomic<T>::from_raw (line 129) ... ok
[INFO] [stdout] test src/atomics/atomic_option.rs - atomics::atomic_option::AtomicOption<T>::from_raw (line 181) ... ok
[INFO] [stdout] test src/atomics/atomic_option.rs - atomics::atomic_option::AtomicOption<T>::get_raw (line 207) ... ok
[INFO] [stdout] test src/atomics/atomic_option.rs - atomics::atomic_option::AtomicOption<T>::into_inner (line 162) ... ok
[INFO] [stdout] test src/atomics/atomic_option.rs - atomics::atomic_option::AtomicOption<T>::store (line 83) ... ok
[INFO] [stdout] test src/atomics/atomic_option.rs - atomics::atomic_option::AtomicOption<T>::new (line 37) ... ok
[INFO] [stdout] test src/atomics/atomic_option.rs - atomics::atomic_option::AtomicOption<T>::swap (line 101) ... ok
[INFO] [stdout] test src/atomics/atomic_option.rs - atomics::atomic_option::AtomicOption<T>::take (line 125) ... ok
[INFO] [stdout] test src/atomics/atomic_option.rs - atomics::atomic_option::AtomicOption<T>::try_store (line 58) ... ok
[INFO] [stdout] test src/atomics/fill_once_atomic_arc.rs - atomics::fill_once_atomic_arc::FillOnceAtomicArc<T>::from_raw (line 114) ... ok
[INFO] [stdout] test src/atomics/fill_once_atomic_arc.rs - atomics::fill_once_atomic_arc::FillOnceAtomicArc<T>::get_raw (line 141) ... ok
[INFO] [stdout] test src/atomics/fill_once_atomic_arc.rs - atomics::fill_once_atomic_arc::FillOnceAtomicArc<T>::get_ref (line 78) ... ok
[INFO] [stdout] test src/atomics/fill_once_atomic_arc.rs - atomics::fill_once_atomic_arc::FillOnceAtomicArc<T>::into_inner (line 95) ... ok
[INFO] [stdout] test src/atomics/fill_once_atomic_arc.rs - atomics::fill_once_atomic_arc::FillOnceAtomicArc<T>::load (line 61) ... ok
[INFO] [stdout] test src/atomics/fill_once_atomic_arc.rs - atomics::fill_once_atomic_arc::FillOnceAtomicArc<T>::new (line 15) ... ok
[INFO] [stdout] test src/atomics/fill_once_atomic_option.rs - atomics::fill_once_atomic_option::FillOnceAtomicOption<T>::get_raw (line 172) ... ok
[INFO] [stdout] test src/atomics/fill_once_atomic_option.rs - atomics::fill_once_atomic_option::FillOnceAtomicOption<T>::get_ref (line 108) ... ok
[INFO] [stdout] test src/atomics/fill_once_atomic_arc.rs - atomics::fill_once_atomic_arc::FillOnceAtomicArc<T>::try_store (line 38) ... ok
[INFO] [stdout] test src/atomics/fill_once_atomic_option.rs - atomics::fill_once_atomic_option::FillOnceAtomicOption<T>::from_raw (line 147) ... ok
[INFO] [stdout] test src/atomics/fill_once_atomic_option.rs - atomics::fill_once_atomic_option::FillOnceAtomicOption<T>::into_inner (line 128) ... ok
[INFO] [stdout] test src/atomics/fill_once_atomic_option.rs - atomics::fill_once_atomic_option::FillOnceAtomicOption<T>::load (line 89) ... ok
[INFO] [stdout] test src/atomics/fill_once_atomic_option.rs - atomics::fill_once_atomic_option::FillOnceAtomicOption<T>::new (line 21) ... ok
[INFO] [stdout] test src/atomics/fill_once_atomic_option.rs - atomics::fill_once_atomic_option::FillOnceAtomicOption<T>::try_store (line 43) ... ok
[INFO] [stdout] test src/atomics/fill_once_atomic_option.rs - atomics::fill_once_atomic_option::FillOnceAtomicOption<T>::take (line 70) ... ok
[INFO] [stdout] test src/iterator.rs - iterator::Iter (line 16) ... ok
[INFO] [stdout] test src/iterator.rs - iterator::Iter<T>::is_empty (line 140) ... ok
[INFO] [stdout] test src/iterator.rs - iterator::Iter (line 27) ... ok
[INFO] [stdout] test src/lib.rs - voluntary_servitude (line 136) ... FAILED
[INFO] [stdout] test src/iterator.rs - iterator::Iter<T>::last_node (line 85) ... ok
[INFO] [stdout] test src/iterator.rs - iterator::Iter<T>::index (line 169) ... ok
[INFO] [stdout] test src/iterator.rs - iterator::Iter<T>::len (line 109) ... ok
[INFO] [stdout] test src/lib.rs - vs (line 110) ... ok
[INFO] [stdout] test src/lib.rs -  (line 54) ... ok
[INFO] [stdout] test src/voluntary_servitude.rs - voluntary_servitude::VoluntaryServitude<T>::empty (line 354) ... ok
[INFO] [stdout] test src/voluntary_servitude.rs - voluntary_servitude::VoluntaryServitude<T>::append (line 260) ... ok
[INFO] [stdout] test src/voluntary_servitude.rs - voluntary_servitude::VoluntaryServitude<T>::clear (line 336) ... ok
[INFO] [stdout] test src/voluntary_servitude.rs - voluntary_servitude::VoluntaryServitude (line 157) ... ok
[INFO] [stdout] test src/voluntary_servitude.rs - voluntary_servitude::VoluntaryServitude<T>::extend (line 390) ... ok
[INFO] [stdout] test src/voluntary_servitude.rs - voluntary_servitude::VoluntaryServitude<T>::iter (line 282) ... ok
[INFO] [stdout] test src/voluntary_servitude.rs - voluntary_servitude::VoluntaryServitude<T>::is_empty (line 321) ... ok
[INFO] [stdout] test src/voluntary_servitude.rs - voluntary_servitude::VoluntaryServitude<T>::new (line 245) ... ok
[INFO] [stdout] test src/voluntary_servitude.rs - voluntary_servitude::VoluntaryServitude<T>::len (line 302) ... ok
[INFO] [stdout] test src/voluntary_servitude.rs - voluntary_servitude::VoluntaryServitude<T>::swap (line 373) ... ok
[INFO] [stdout] test src/voluntary_servitude.rs - voluntary_servitude::VoluntaryServitude (line 195) ... test src/voluntary_servitude.rs - voluntary_servitude::VoluntaryServitude (line 195) has been running for over 60 seconds
[INFO] [stdout] test src/voluntary_servitude.rs - voluntary_servitude::VoluntaryServitude (line 195) ... ok
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- src/lib.rs - voluntary_servitude (line 136) stdout ----
[INFO] [stdout] error[E0468]: an `extern crate` loading macros must be at the crate root
[INFO] [stdout]  --> src/lib.rs:138:14
[INFO] [stdout]   |
[INFO] [stdout] 5 | #[macro_use] extern crate voluntary_servitude;
[INFO] [stdout]   |              ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] error: aborting due to previous error
[INFO] [stdout] 
[INFO] [stdout] For more information about this error, try `rustc --explain E0468`.
[INFO] [stdout] Couldn't compile the test.
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     src/lib.rs - voluntary_servitude (line 136)
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 50 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass '--doc'
[INFO] running `"docker" "inspect" "4c29de3e72be1fa022ed0ac05a79b80c66c1969e8d0cda14b94b92c88e606b86"`
[INFO] running `"docker" "rm" "-f" "4c29de3e72be1fa022ed0ac05a79b80c66c1969e8d0cda14b94b92c88e606b86"`
[INFO] [stdout] 4c29de3e72be1fa022ed0ac05a79b80c66c1969e8d0cda14b94b92c88e606b86