[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 try#e212b7d492c96d3837691ba29d32d5f468933fb8 for pr-61629
[INFO] running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-61629/worker-3/try#e212b7d492c96d3837691ba29d32d5f468933fb8:/opt/crater/target:rw,Z" "-v" "/mnt/big/crater/work/ex/pr-61629/sources/try#e212b7d492c96d3837691ba29d32d5f468933fb8/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" "+e212b7d492c96d3837691ba29d32d5f468933fb8-alt" "build" "--frozen"`
[INFO] [stdout] 74fde2aadaea9e1dceba9965fa677e2e4c3c358605e100a1978396b51aa64eb7
[INFO] running `"docker" "start" "-a" "74fde2aadaea9e1dceba9965fa677e2e4c3c358605e100a1978396b51aa64eb7"`
[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.65s
[INFO] running `"docker" "inspect" "74fde2aadaea9e1dceba9965fa677e2e4c3c358605e100a1978396b51aa64eb7"`
[INFO] running `"docker" "rm" "-f" "74fde2aadaea9e1dceba9965fa677e2e4c3c358605e100a1978396b51aa64eb7"`
[INFO] [stdout] 74fde2aadaea9e1dceba9965fa677e2e4c3c358605e100a1978396b51aa64eb7
[INFO] running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-61629/worker-3/try#e212b7d492c96d3837691ba29d32d5f468933fb8:/opt/crater/target:rw,Z" "-v" "/mnt/big/crater/work/ex/pr-61629/sources/try#e212b7d492c96d3837691ba29d32d5f468933fb8/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" "+e212b7d492c96d3837691ba29d32d5f468933fb8-alt" "test" "--frozen" "--no-run"`
[INFO] [stdout] 37a2dff7a633a9279c70b331093a473ae0a08dac5d569e24fca30cf562bec11a
[INFO] running `"docker" "start" "-a" "37a2dff7a633a9279c70b331093a473ae0a08dac5d569e24fca30cf562bec11a"`
[INFO] [stderr]    Compiling env_logger v0.5.13
[INFO] [stderr]    Compiling criterion v0.2.11
[INFO] [stderr]    Compiling voluntary-servitude v4.0.7 (/opt/crater/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 17.90s
[INFO] running `"docker" "inspect" "37a2dff7a633a9279c70b331093a473ae0a08dac5d569e24fca30cf562bec11a"`
[INFO] running `"docker" "rm" "-f" "37a2dff7a633a9279c70b331093a473ae0a08dac5d569e24fca30cf562bec11a"`
[INFO] [stdout] 37a2dff7a633a9279c70b331093a473ae0a08dac5d569e24fca30cf562bec11a
[INFO] running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-61629/worker-3/try#e212b7d492c96d3837691ba29d32d5f468933fb8:/opt/crater/target:rw,Z" "-v" "/mnt/big/crater/work/ex/pr-61629/sources/try#e212b7d492c96d3837691ba29d32d5f468933fb8/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" "+e212b7d492c96d3837691ba29d32d5f468933fb8-alt" "test" "--frozen"`
[INFO] [stdout] 59d579d872b1759c84a4cfb795d9b6df952ac2fdbc48f93b877eeff4d6da7e3c
[INFO] running `"docker" "start" "-a" "59d579d872b1759c84a4cfb795d9b6df952ac2fdbc48f93b877eeff4d6da7e3c"`
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.09s
[INFO] [stderr]      Running /opt/crater/target/debug/deps/voluntary_servitude-0dc08402a9ae1277
[INFO] [stdout] 
[INFO] [stdout] running 23 tests
[INFO] [stdout] test atomics::atomic_option::tests::test_send ... ok
[INFO] [stdout] test atomics::atomic::tests::test_sync ... ok
[INFO] [stdout] test atomics::atomic::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] test atomics::fill_once_atomic_option::tests::fill_once_ref ... ok
[INFO] [stdout] test iterator::tests::iter_after_use ... ok
[INFO] [stdout] test atomics::fill_once_atomic_option::tests::test_send ... ok
[INFO] [stdout] test atomics::fill_once_atomic_option::tests::test_sync ... ok
[INFO] [stdout] test iterator::tests::iter_doesnt_clear ... ok
[INFO] [stdout] test iterator::tests::iter_all ... ok
[INFO] [stdout] test iterator::tests::iter_drop ... ok
[INFO] [stdout] test iterator::tests::iter_drop_many ... ok
[INFO] [stdout] test iterator::tests::iter_isnt_growable_when_consumed ... 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::extend_partial_eq ... ok
[INFO] [stdout] test iterator::tests::iter_grows ... ok
[INFO] [stdout] test iterator::tests::iter_many ... ok
[INFO] [stdout] test voluntary_servitude::tests::test_sync ... ok
[INFO] [stdout] test voluntary_servitude::tests::test_send ... ok
[INFO] [stdout] test voluntary_servitude::tests::voluntary_servitude_len_append_clear ... 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-5d20d8598094b1bc
[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>::get_raw (line 179) ... 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>::store (line 69) ... ok
[INFO] [stdout] test src/atomics/atomic.rs - atomics::atomic::Atomic<T>::from_raw (line 129) ... 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>::into_inner (line 107) ... 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>::from_raw_unchecked (line 152) ... 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>::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/atomic_option.rs - atomics::atomic_option::AtomicOption<T>::take (line 125) ... 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>::load (line 61) ... 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>::new (line 15) ... 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_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>::get_ref (line 108) ... 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>::take (line 70) ... 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>::into_inner (line 128) ... 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/lib.rs - voluntary_servitude (line 136) ... FAILED
[INFO] [stdout] test src/iterator.rs - iterator::Iter (line 16) ... ok
[INFO] [stdout] test src/iterator.rs - iterator::Iter<T>::index (line 169) ... ok
[INFO] [stdout] test src/iterator.rs - iterator::Iter (line 27) ... ok
[INFO] [stdout] test src/lib.rs -  (line 54) ... ok
[INFO] [stdout] test src/iterator.rs - iterator::Iter<T>::is_empty (line 140) ... ok
[INFO] [stdout] test src/iterator.rs - iterator::Iter<T>::last_node (line 85) ... 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/voluntary_servitude.rs - voluntary_servitude::VoluntaryServitude<T>::is_empty (line 321) ... 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>::extend (line 390) ... 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>::empty (line 354) ... 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<T>::iter (line 282) ... 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<T>::len (line 302) ... 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 (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" "59d579d872b1759c84a4cfb795d9b6df952ac2fdbc48f93b877eeff4d6da7e3c"`
[INFO] running `"docker" "rm" "-f" "59d579d872b1759c84a4cfb795d9b6df952ac2fdbc48f93b877eeff4d6da7e3c"`
[INFO] [stdout] 59d579d872b1759c84a4cfb795d9b6df952ac2fdbc48f93b877eeff4d6da7e3c