[INFO] fetching crate system-interface 0.27.3...
[INFO] testing system-interface-0.27.3 against master#562dee4820c458d823175268e41601d4c060588a for pr-154210-1
[INFO] extracting crate system-interface 0.27.3 into /workspace/builds/worker-3-tc1/source
[INFO] started tweaking crates.io crate system-interface 0.27.3
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate system-interface 0.27.3
[INFO] tweaked toml for crates.io crate system-interface 0.27.3 written to /workspace/builds/worker-3-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate system-interface 0.27.3 on toolchain 562dee4820c458d823175268e41601d4c060588a
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "generate-lockfile" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]      Locking 144 packages to latest compatible versions
[INFO] [stderr]       Adding cap-fs-ext v3.4.5 (available: v4.0.2)
[INFO] [stderr]       Adding cap-std v3.4.5 (available: v4.0.2)
[INFO] [stderr]       Adding cap-tempfile v3.4.5 (available: v4.0.2)
[INFO] [stderr]       Adding io-lifetimes v2.0.4 (available: v3.0.1)
[INFO] [stderr]       Adding rustix v0.38.44 (available: v1.1.4)
[INFO] [stderr]       Adding windows-sys v0.59.0 (available: v0.61.2)
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded io-extras v0.18.4
[INFO] [stderr]   Downloaded winx v0.36.4
[INFO] [stderr]   Downloaded fs-set-times v0.20.3
[INFO] [stderr]   Downloaded cap-primitives v3.4.5
[INFO] [stderr]   Downloaded ambient-authority v0.0.2
[INFO] [stderr]   Downloaded cap-async-std v3.4.5
[INFO] [stderr]   Downloaded cap-fs-ext v3.4.5
[INFO] [stderr]   Downloaded cap-std v3.4.5
[INFO] [stderr]   Downloaded rustix-linux-procfs v0.1.1
[INFO] [stderr]   Downloaded cap-tempfile v3.4.5
[INFO] [stderr]   Downloaded char-device v0.16.5
[INFO] [stderr]   Downloaded io-lifetimes v2.0.4
[INFO] [stderr]   Downloaded socketpair v0.19.8
[INFO] [stderr]   Downloaded maybe-owned v0.3.4
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] a288d877fdcd5a0d9bf8628ee33b094b4470dda3bf1070a5082e8ab4e72901cf
[INFO] running `Command { std: "docker" "start" "-a" "a288d877fdcd5a0d9bf8628ee33b094b4470dda3bf1070a5082e8ab4e72901cf", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "a288d877fdcd5a0d9bf8628ee33b094b4470dda3bf1070a5082e8ab4e72901cf", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "a288d877fdcd5a0d9bf8628ee33b094b4470dda3bf1070a5082e8ab4e72901cf", kill_on_drop: false }`
[INFO] [stdout] a288d877fdcd5a0d9bf8628ee33b094b4470dda3bf1070a5082e8ab4e72901cf
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/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" "RUSTDOCFLAGS=--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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 4af1daf52a2ca1fe85272257cdb2c24e4459ca1cea232878904f9bb236b47e9b
[INFO] running `Command { std: "docker" "start" "-a" "4af1daf52a2ca1fe85272257cdb2c24e4459ca1cea232878904f9bb236b47e9b", kill_on_drop: false }`
[INFO] [stderr]    Compiling rustix v0.38.44
[INFO] [stderr]    Compiling io-lifetimes v2.0.4
[INFO] [stderr]    Compiling system-interface v0.27.3 (/opt/rustwide/workdir)
[INFO] [stderr]    Compiling linux-raw-sys v0.4.15
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 6.32s
[INFO] running `Command { std: "docker" "inspect" "4af1daf52a2ca1fe85272257cdb2c24e4459ca1cea232878904f9bb236b47e9b", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "4af1daf52a2ca1fe85272257cdb2c24e4459ca1cea232878904f9bb236b47e9b", kill_on_drop: false }`
[INFO] [stdout] 4af1daf52a2ca1fe85272257cdb2c24e4459ca1cea232878904f9bb236b47e9b
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/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" "RUSTDOCFLAGS=--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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 903e313f632cd908be6e140c8f967806d735f1fc71916205820b76e0a4d04996
[INFO] running `Command { std: "docker" "start" "-a" "903e313f632cd908be6e140c8f967806d735f1fc71916205820b76e0a4d04996", kill_on_drop: false }`
[INFO] [stderr]    Compiling io-lifetimes v2.0.4
[INFO] [stderr]    Compiling rustix v1.1.4
[INFO] [stderr]    Compiling linux-raw-sys v0.12.1
[INFO] [stderr]    Compiling io-extras v0.18.4
[INFO] [stderr]    Compiling cap-primitives v3.4.5
[INFO] [stderr]    Compiling ambient-authority v0.0.2
[INFO] [stderr]    Compiling cap-std v3.4.5
[INFO] [stderr]    Compiling maybe-owned v0.3.4
[INFO] [stderr]    Compiling cap-fs-ext v3.4.5
[INFO] [stderr]    Compiling getrandom v0.4.2
[INFO] [stderr]    Compiling uuid v1.23.0
[INFO] [stderr]    Compiling system-interface v0.27.3 (/opt/rustwide/workdir)
[INFO] [stderr]    Compiling fs-set-times v0.20.3
[INFO] [stderr]    Compiling rustix-linux-procfs v0.1.1
[INFO] [stderr]    Compiling tempfile v3.27.0
[INFO] [stderr]    Compiling cap-tempfile v3.4.5
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 9.08s
[INFO] running `Command { std: "docker" "inspect" "903e313f632cd908be6e140c8f967806d735f1fc71916205820b76e0a4d04996", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "903e313f632cd908be6e140c8f967806d735f1fc71916205820b76e0a4d04996", kill_on_drop: false }`
[INFO] [stdout] 903e313f632cd908be6e140c8f967806d735f1fc71916205820b76e0a4d04996
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/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" "RUSTDOCFLAGS=--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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 87525420e820ccd468f9fcf6fb82d66b387ff5df3d63bc2e1cc400b3b11f93a9
[INFO] running `Command { std: "docker" "start" "-a" "87525420e820ccd468f9fcf6fb82d66b387ff5df3d63bc2e1cc400b3b11f93a9", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.14s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/system_interface-be58d52bf0b67346)
[INFO] [stdout] 
[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] [stderr]      Running tests/allocate.rs (/opt/rustwide/target/debug/deps/allocate-7838046d656211c7)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stderr]      Running tests/append.rs (/opt/rustwide/target/debug/deps/append-c73bf25c38151579)
[INFO] [stdout] test allocate ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 5 tests
[INFO] [stdout] test append ... ok
[INFO] [stdout] test append_all_vectored ... ok
[INFO] [stdout] test append_all ... ok
[INFO] [stdout] test append_vectored ... ok
[INFO] [stdout] test cap_append_all_vectored ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/fd_flags.rs (/opt/rustwide/target/debug/deps/fd_flags-739e25f79d175595)
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test test_get_set_fd_flags ... ok
[INFO] [stdout] test test_append ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/is_read_write.rs (/opt/rustwide/target/debug/deps/is_read_write-5b2dcd795bffaa42)
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test socket_is_read_write ... ok
[INFO] [stdout] test socket_is_write_read ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/live_rename.rs (/opt/rustwide/target/debug/deps/live_rename-fb14c7bec582ec9b)
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test live_rename ... ok
[INFO] [stdout] test concurrent_rename ... ok
[INFO] [stdout] 
[INFO] [stderr]      Running tests/peek.rs (/opt/rustwide/target/debug/deps/peek-f1e577e35b6b6a60)
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.29s
[INFO] [stderr]      Running tests/read_ready.rs (/opt/rustwide/target/debug/deps/read_ready-387eff1c9ee39b9d)
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test test_peek ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test file_is_read_write ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/read_to_end.rs (/opt/rustwide/target/debug/deps/read_to_end-2b2910e6907e4785)
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test read_to_string_at ... ok
[INFO] [stderr]      Running tests/vectored_at.rs (/opt/rustwide/target/debug/deps/vectored_at-fd248c66ed024e21)
[INFO] [stdout] test read_to_end_at ... ok
[INFO] [stderr]    Doc-tests system_interface
[INFO] [stdout] test read_to_string_at_error ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.17s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 27 tests
[INFO] [stdout] test cap_read_exact_vectored_at ... ok
[INFO] [stdout] test cap_read_exact_vectored_at_unexpected_eof ... ok
[INFO] [stdout] test cap_write_all_vectored_after_end ... ok
[INFO] [stdout] test read_at ... ok
[INFO] [stdout] test read_exact_at_unexpected_eof ... ok
[INFO] [stdout] test read ... ok
[INFO] [stdout] test read_exact_vectored_at_unexpected_eof ... ok
[INFO] [stdout] test read_exact_vectored ... ok
[INFO] [stdout] test read_exact_at ... ok
[INFO] [stdout] test read_vectored ... ok
[INFO] [stdout] test read_vectored_at ... ok
[INFO] [stdout] test write ... ok
[INFO] [stdout] test write_after_end ... ok
[INFO] [stdout] test write_all_vectored ... ok
[INFO] [stdout] test read_exact ... ok
[INFO] [stdout] test read_exact_vectored_unexpected_eof ... ok
[INFO] [stdout] test read_exact_vectored_at ... ok
[INFO] [stdout] test write_at ... ok
[INFO] [stdout] test write_vectored ... ok
[INFO] [stdout] test cap_write_all_vectored_at ... ok
[INFO] [stdout] test write_all_at ... ok
[INFO] [stdout] test write_all_vectored_at ... ok
[INFO] [stdout] test write_all ... ok
[INFO] [stdout] test write_vectored_at ... ok
[INFO] [stdout] test write_all_after_end ... ok
[INFO] [stdout] test write_all_vectored_after_end ... ok
[INFO] [stdout] test write_vectored_after_end ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 27 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stdout] 
[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] running `Command { std: "docker" "inspect" "87525420e820ccd468f9fcf6fb82d66b387ff5df3d63bc2e1cc400b3b11f93a9", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "87525420e820ccd468f9fcf6fb82d66b387ff5df3d63bc2e1cc400b3b11f93a9", kill_on_drop: false }`
[INFO] [stdout] 87525420e820ccd468f9fcf6fb82d66b387ff5df3d63bc2e1cc400b3b11f93a9