[INFO] fetching crate acid_io 0.1.0...
[INFO] testing acid_io-0.1.0 against master#61469b682c2b0bf9cebc4622f1859e2bb3b7ebca for pr-91743
[INFO] extracting crate acid_io 0.1.0 into /workspace/builds/worker-1/source
[INFO] validating manifest of crates.io crate acid_io 0.1.0 on toolchain 61469b682c2b0bf9cebc4622f1859e2bb3b7ebca
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+61469b682c2b0bf9cebc4622f1859e2bb3b7ebca" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking crates.io crate acid_io 0.1.0
[INFO] finished tweaking crates.io crate acid_io 0.1.0
[INFO] tweaked toml for crates.io crate acid_io 0.1.0 written to /workspace/builds/worker-1/source/Cargo.toml
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+61469b682c2b0bf9cebc4622f1859e2bb3b7ebca" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update", kill_on_drop: false }`
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+61469b682c2b0bf9cebc4622f1859e2bb3b7ebca" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded windows_i686_msvc v0.29.0
[INFO] [stderr]   Downloaded windows_x86_64_gnu v0.29.0
[INFO] [stderr]   Downloaded windows_aarch64_msvc v0.29.0
[INFO] [stderr]   Downloaded windows_i686_gnu v0.29.0
[INFO] [stderr]   Downloaded windows_x86_64_msvc v0.29.0
[INFO] [stderr]   Downloaded windows v0.29.0
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d190cb871061d98bc6d0581d85cb2ecb09a0f8a142ba5463de30be9999fc3251" "/opt/rustwide/cargo-home/bin/cargo" "+61469b682c2b0bf9cebc4622f1859e2bb3b7ebca" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 700a8c3a9d1122ac8fd2930ede044ddb862c5b2cde4bb901f729b2772eb59473
[INFO] running `Command { std: "docker" "start" "-a" "700a8c3a9d1122ac8fd2930ede044ddb862c5b2cde4bb901f729b2772eb59473", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "700a8c3a9d1122ac8fd2930ede044ddb862c5b2cde4bb901f729b2772eb59473", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "700a8c3a9d1122ac8fd2930ede044ddb862c5b2cde4bb901f729b2772eb59473", kill_on_drop: false }`
[INFO] [stdout] 700a8c3a9d1122ac8fd2930ede044ddb862c5b2cde4bb901f729b2772eb59473
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d190cb871061d98bc6d0581d85cb2ecb09a0f8a142ba5463de30be9999fc3251" "/opt/rustwide/cargo-home/bin/cargo" "+61469b682c2b0bf9cebc4622f1859e2bb3b7ebca" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 236a0b9a6caf419cab430eaca8f3d825c85e6e1023ed2a8b5a80aa5407ad5171
[INFO] running `Command { std: "docker" "start" "-a" "236a0b9a6caf419cab430eaca8f3d825c85e6e1023ed2a8b5a80aa5407ad5171", kill_on_drop: false }`
[INFO] [stderr]    Compiling memchr v2.5.0
[INFO] [stderr]    Compiling libc v0.2.125
[INFO] [stderr]    Compiling acid_io v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 2.45s
[INFO] running `Command { std: "docker" "inspect" "236a0b9a6caf419cab430eaca8f3d825c85e6e1023ed2a8b5a80aa5407ad5171", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "236a0b9a6caf419cab430eaca8f3d825c85e6e1023ed2a8b5a80aa5407ad5171", kill_on_drop: false }`
[INFO] [stdout] 236a0b9a6caf419cab430eaca8f3d825c85e6e1023ed2a8b5a80aa5407ad5171
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d190cb871061d98bc6d0581d85cb2ecb09a0f8a142ba5463de30be9999fc3251" "/opt/rustwide/cargo-home/bin/cargo" "+61469b682c2b0bf9cebc4622f1859e2bb3b7ebca" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] efac78493b0e8e667f45b09ccc01c1c73d7a6318fe1bc04925fb1fd14c84b85f
[INFO] running `Command { std: "docker" "start" "-a" "efac78493b0e8e667f45b09ccc01c1c73d7a6318fe1bc04925fb1fd14c84b85f", kill_on_drop: false }`
[INFO] [stderr]    Compiling acid_io v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.93s
[INFO] [stderr]   Executable unittests src/lib.rs (/opt/rustwide/target/debug/deps/acid_io-7951d38c8deb1c1f)
[INFO] [stderr]   Executable tests/with_std.rs (/opt/rustwide/target/debug/deps/with_std-6017d052c6810a5f)
[INFO] running `Command { std: "docker" "inspect" "efac78493b0e8e667f45b09ccc01c1c73d7a6318fe1bc04925fb1fd14c84b85f", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "efac78493b0e8e667f45b09ccc01c1c73d7a6318fe1bc04925fb1fd14c84b85f", kill_on_drop: false }`
[INFO] [stdout] efac78493b0e8e667f45b09ccc01c1c73d7a6318fe1bc04925fb1fd14c84b85f
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d190cb871061d98bc6d0581d85cb2ecb09a0f8a142ba5463de30be9999fc3251" "/opt/rustwide/cargo-home/bin/cargo" "+61469b682c2b0bf9cebc4622f1859e2bb3b7ebca" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 7ba8ca8f0dc0f47bce7594f70011298037216f13cc5d0b8a9d1db029e6870d4f
[INFO] running `Command { std: "docker" "start" "-a" "7ba8ca8f0dc0f47bce7594f70011298037216f13cc5d0b8a9d1db029e6870d4f", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.73s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/acid_io-7951d38c8deb1c1f)
[INFO] [stdout] 
[INFO] [stdout] running 25 tests
[INFO] [stdout] test tests::chain_empty_size_hint ... ok
[INFO] [stdout] test tests::cursor::seek_past_i64 ... ok
[INFO] [stdout] test tests::cursor::test_buf_writer ... ok
[INFO] [stdout] test tests::cursor::test_buf_writer_seek ... ok
[INFO] [stdout] test tests::cursor::seek_before_0 ... ok
[INFO] [stdout] test tests::cursor::test_buf_writer_vectored ... ok
[INFO] [stdout] test tests::cursor::test_buf_writer_error ... ok
[INFO] [stdout] test tests::empty_size_hint ... ok
[INFO] [stdout] test tests::cursor::seek_past_end ... ok
[INFO] [stdout] test tests::io_slice_advance_slices ... ok
[INFO] [stdout] test tests::io_slice_advance_slices_empty_slice ... ok
[INFO] [stdout] test tests::io_slice_mut_advance_slices ... ok
[INFO] [stdout] test tests::read_exact ... ok
[INFO] [stdout] test tests::slice_size_hint ... ok
[INFO] [stdout] test tests::take_eof ... ok
[INFO] [stdout] test tests::take_size_hint ... ok
[INFO] [stdout] test tests::io_slice_mut_advance_slices_empty_slice ... ok
[INFO] [stdout] test tests::io_slice_mut_advance_slices_beyond_total_length ... ok
[INFO] [stdout] test tests::io_slice_advance_slices_beyond_total_length ... ok
[INFO] [stdout] test tests::util::empty_reads ... ok
[INFO] [stdout] test tests::util::repeat_repeats ... ok
[INFO] [stdout] test tests::util::empty_seeks ... ok
[INFO] [stdout] test tests::util::take_some_bytes ... ok
[INFO] [stdout] test tests::util::sink_sinks ... ok
[INFO] [stderr]      Running tests/with_std.rs (/opt/rustwide/target/debug/deps/with_std-6017d052c6810a5f)
[INFO] [stdout] test tests::util::copy_copies ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 25 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests acid_io
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 44 tests
[INFO] [stdout] test src/io_core.rs - io_core::BufRead (line 882) ... ok
[INFO] [stdout] test src/error.rs - error::Error::from (line 599) ... ok
[INFO] [stdout] test src/io_core.rs - io_core::Chain<T,U>::into_inner (line 260) ... ok
[INFO] [stdout] test src/io_core.rs - io_core::Write::write_all_vectored (line 1459) ... ok
[INFO] [stdout] test src/io_core.rs - io_core::Cursor<T>::get_mut (line 1860) ... ok
[INFO] [stdout] test src/io_core.rs - io_core::Read::take (line 756) ... ok
[INFO] [stdout] test src/io_core.rs - io_core::Write::write (line 1278) ... ok
[INFO] [stdout] test src/io_core.rs - io_core::Chain<T,U>::get_mut (line 306) ... ok
[INFO] [stdout] test src/io_core.rs - io_core::Take<T>::get_mut (line 180) ... ok
[INFO] [stdout] test src/io_core.rs - io_core::Cursor (line 1768) ... ok
[INFO] [stdout] test src/io_core.rs - io_core::Take<T>::get_ref (line 156) ... ok
[INFO] [stdout] test src/io_slice.rs - io_slice::IoSlice::advance_slices (line 266) ... ok
[INFO] [stdout] test src/io_core.rs - io_core::Write::flush (line 1358) ... ok
[INFO] [stdout] test src/prelude.rs - prelude (line 6) ... ok
[INFO] [stdout] test src/io_core.rs - io_core::Cursor<T>::is_empty (line 1950) ... ok
[INFO] [stdout] test src/io_core.rs - io_core::Chain<T,U>::get_ref (line 281) ... ok
[INFO] [stdout] test src/io_core.rs - io_core::Write (line 1229) ... ok
[INFO] [stdout] test src/io_core.rs - io_core::Cursor<T>::remaining_slice (line 1925) ... ok
[INFO] [stdout] test src/util.rs - util::copy (line 242) ... ok
[INFO] [stdout] test src/error.rs - error::ErrorTrait::source (line 43) ... ok
[INFO] [stdout] test src/io_core.rs - io_core::Write::by_ref (line 1585) ... ok
[INFO] [stdout] test src/io_core.rs - io_core::Cursor<T>::into_inner (line 1823) ... ok
[INFO] [stdout] test src/io_core.rs - io_core::Cursor<T>::get_ref (line 1840) ... ok
[INFO] [stdout] test src/util.rs - util::empty (line 23) ... ok
[INFO] [stdout] test src/io_core.rs - io_core::Read::chain (line 716) ... ok
[INFO] [stdout] test src/io_core.rs - io_core::Read::read_exact (line 622) ... ok
[INFO] [stdout] test src/util.rs - util::repeat (line 94) ... ok
[INFO] [stdout] test src/io_core.rs - io_core::Read::read (line 465) ... ok
[INFO] [stdout] test src/io_core.rs - io_core::Read::bytes (line 688) ... ok
[INFO] [stdout] test src/io_core.rs - io_core::Read (line 394) ... ok
[INFO] [stdout] test src/io_core.rs - io_core::Read::by_ref (line 649) ... ok
[INFO] [stdout] test src/io_slice.rs - io_slice::IoSliceMut::advance (line 121) ... ok
[INFO] [stdout] test src/io_core.rs - io_core::Write::write_all (line 1397) ... ok
[INFO] [stdout] test src/io_slice.rs - io_slice::IoSlice::advance (line 237) ... ok
[INFO] [stdout] test src/io_slice.rs - io_slice::IoSliceMut::advance_slices (line 150) ... ok
[INFO] [stdout] test src/io_core.rs - io_core::Cursor<T>::new (line 1808) ... ok
[INFO] [stdout] test src/io_core.rs - io_core::Take<T>::into_inner (line 136) ... ok
[INFO] [stdout] test src/io_core.rs - io_core::Take<T>::set_limit (line 116) ... ok
[INFO] [stdout] test src/io_core.rs - io_core::Cursor<T>::position (line 1877) ... ok
[INFO] [stdout] test src/io_core.rs - io_core::Write::write_vectored (line 1305) ... ok
[INFO] [stdout] test src/util.rs - util::sink (line 158) ... ok
[INFO] [stdout] test src/io_core.rs - io_core::Take<T>::limit (line 94) ... ok
[INFO] [stdout] test src/io_core.rs - io_core::Cursor<T>::set_position (line 1899) ... ok
[INFO] [stdout] test src/io_core.rs - io_core::Write::write_fmt (line 1525) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 44 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.79s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "7ba8ca8f0dc0f47bce7594f70011298037216f13cc5d0b8a9d1db029e6870d4f", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "7ba8ca8f0dc0f47bce7594f70011298037216f13cc5d0b8a9d1db029e6870d4f", kill_on_drop: false }`
[INFO] [stdout] 7ba8ca8f0dc0f47bce7594f70011298037216f13cc5d0b8a9d1db029e6870d4f