[INFO] fetching crate capture-stdio 0.1.1...
[INFO] testing capture-stdio-0.1.1 against master#ff2c56344c764af598ad33027e9c7a48881808ef for pr-118825
[INFO] extracting crate capture-stdio 0.1.1 into /workspace/builds/worker-2-tc1/source
[INFO] validating manifest of crates.io crate capture-stdio 0.1.1 on toolchain ff2c56344c764af598ad33027e9c7a48881808ef
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+ff2c56344c764af598ad33027e9c7a48881808ef" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] removed /workspace/builds/worker-2-tc1/source/rust-toolchain
[INFO] started tweaking crates.io crate capture-stdio 0.1.1
[INFO] finished tweaking crates.io crate capture-stdio 0.1.1
[INFO] tweaked toml for crates.io crate capture-stdio 0.1.1 written to /workspace/builds/worker-2-tc1/source/Cargo.toml
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+ff2c56344c764af598ad33027e9c7a48881808ef" "generate-lockfile" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+ff2c56344c764af598ad33027e9c7a48881808ef" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded os_pipe v1.1.4
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+ff2c56344c764af598ad33027e9c7a48881808ef" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 8d8854abad6585e266279b94d8dc1d20998acf01e345896d1f62e792ce11213f
[INFO] running `Command { std: "docker" "start" "-a" "8d8854abad6585e266279b94d8dc1d20998acf01e345896d1f62e792ce11213f", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "8d8854abad6585e266279b94d8dc1d20998acf01e345896d1f62e792ce11213f", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "8d8854abad6585e266279b94d8dc1d20998acf01e345896d1f62e792ce11213f", kill_on_drop: false }`
[INFO] [stdout] 8d8854abad6585e266279b94d8dc1d20998acf01e345896d1f62e792ce11213f
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+ff2c56344c764af598ad33027e9c7a48881808ef" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] d725cc689008dfd29cefc1fb6c92baaa8f1cca95f4687a5c708fd2cec4482c52
[INFO] running `Command { std: "docker" "start" "-a" "d725cc689008dfd29cefc1fb6c92baaa8f1cca95f4687a5c708fd2cec4482c52", kill_on_drop: false }`
[INFO] [stderr]    Compiling os_pipe v1.1.4
[INFO] [stderr]    Compiling capture-stdio v0.1.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.18s
[INFO] running `Command { std: "docker" "inspect" "d725cc689008dfd29cefc1fb6c92baaa8f1cca95f4687a5c708fd2cec4482c52", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "d725cc689008dfd29cefc1fb6c92baaa8f1cca95f4687a5c708fd2cec4482c52", kill_on_drop: false }`
[INFO] [stdout] d725cc689008dfd29cefc1fb6c92baaa8f1cca95f4687a5c708fd2cec4482c52
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+ff2c56344c764af598ad33027e9c7a48881808ef" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 4e54e12b92b13a989a5977810bafb5b62d1184cc48faf8618fecb3ddb2906610
[INFO] running `Command { std: "docker" "start" "-a" "4e54e12b92b13a989a5977810bafb5b62d1184cc48faf8618fecb3ddb2906610", kill_on_drop: false }`
[INFO] [stderr]    Compiling capture-stdio v0.1.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.58s
[INFO] running `Command { std: "docker" "inspect" "4e54e12b92b13a989a5977810bafb5b62d1184cc48faf8618fecb3ddb2906610", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "4e54e12b92b13a989a5977810bafb5b62d1184cc48faf8618fecb3ddb2906610", kill_on_drop: false }`
[INFO] [stdout] 4e54e12b92b13a989a5977810bafb5b62d1184cc48faf8618fecb3ddb2906610
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+ff2c56344c764af598ad33027e9c7a48881808ef" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 3f2ffa998e2059be30bd39206a1e937c0fdebac1c2a817d94f10ba09140f7b5d
[INFO] running `Command { std: "docker" "start" "-a" "3f2ffa998e2059be30bd39206a1e937c0fdebac1c2a817d94f10ba09140f7b5d", kill_on_drop: false }`
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.01s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/capture_stdio-0d7a75b131777a10)
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[ERROR] error running command: no output for 300 seconds
[INFO] running `Command { std: "docker" "inspect" "3f2ffa998e2059be30bd39206a1e937c0fdebac1c2a817d94f10ba09140f7b5d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "3f2ffa998e2059be30bd39206a1e937c0fdebac1c2a817d94f10ba09140f7b5d", kill_on_drop: false }`
[INFO] [stdout] 3f2ffa998e2059be30bd39206a1e937c0fdebac1c2a817d94f10ba09140f7b5d
[INFO] testing capture-stdio-0.1.1 against try#5e4273472e1b2f223618a9b3def795f6224f241d for pr-118825
[INFO] extracting crate capture-stdio 0.1.1 into /workspace/builds/worker-2-tc2/source
[INFO] validating manifest of crates.io crate capture-stdio 0.1.1 on toolchain 5e4273472e1b2f223618a9b3def795f6224f241d
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+5e4273472e1b2f223618a9b3def795f6224f241d" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] removed /workspace/builds/worker-2-tc2/source/rust-toolchain
[INFO] started tweaking crates.io crate capture-stdio 0.1.1
[INFO] finished tweaking crates.io crate capture-stdio 0.1.1
[INFO] tweaked toml for crates.io crate capture-stdio 0.1.1 written to /workspace/builds/worker-2-tc2/source/Cargo.toml
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+5e4273472e1b2f223618a9b3def795f6224f241d" "generate-lockfile" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+5e4273472e1b2f223618a9b3def795f6224f241d" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/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:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+5e4273472e1b2f223618a9b3def795f6224f241d" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] e9d313ab6ca6ce68f63761c2019dc02c177601ef0813ad1c937e24ac063344f5
[INFO] running `Command { std: "docker" "start" "-a" "e9d313ab6ca6ce68f63761c2019dc02c177601ef0813ad1c937e24ac063344f5", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "e9d313ab6ca6ce68f63761c2019dc02c177601ef0813ad1c937e24ac063344f5", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "e9d313ab6ca6ce68f63761c2019dc02c177601ef0813ad1c937e24ac063344f5", kill_on_drop: false }`
[INFO] [stdout] e9d313ab6ca6ce68f63761c2019dc02c177601ef0813ad1c937e24ac063344f5
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/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:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+5e4273472e1b2f223618a9b3def795f6224f241d" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] a439fac4407b6bd0da3f471717aa3629da38c352266f3626c2c5a0ac88fd86d3
[INFO] running `Command { std: "docker" "start" "-a" "a439fac4407b6bd0da3f471717aa3629da38c352266f3626c2c5a0ac88fd86d3", kill_on_drop: false }`
[INFO] [stderr]    Compiling os_pipe v1.1.4
[INFO] [stderr]    Compiling capture-stdio v0.1.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.13s
[INFO] running `Command { std: "docker" "inspect" "a439fac4407b6bd0da3f471717aa3629da38c352266f3626c2c5a0ac88fd86d3", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "a439fac4407b6bd0da3f471717aa3629da38c352266f3626c2c5a0ac88fd86d3", kill_on_drop: false }`
[INFO] [stdout] a439fac4407b6bd0da3f471717aa3629da38c352266f3626c2c5a0ac88fd86d3
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/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:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+5e4273472e1b2f223618a9b3def795f6224f241d" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 8cb98299f528d91401d4a56172b151994e54957e57efd620e7a7395076de6035
[INFO] running `Command { std: "docker" "start" "-a" "8cb98299f528d91401d4a56172b151994e54957e57efd620e7a7395076de6035", kill_on_drop: false }`
[INFO] [stderr]    Compiling capture-stdio v0.1.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.34s
[INFO] running `Command { std: "docker" "inspect" "8cb98299f528d91401d4a56172b151994e54957e57efd620e7a7395076de6035", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "8cb98299f528d91401d4a56172b151994e54957e57efd620e7a7395076de6035", kill_on_drop: false }`
[INFO] [stdout] 8cb98299f528d91401d4a56172b151994e54957e57efd620e7a7395076de6035
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/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:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+5e4273472e1b2f223618a9b3def795f6224f241d" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 3907834ac58f8b20e901bb70101dae08b3f4d221c6dc70212d7624f1715324a3
[INFO] running `Command { std: "docker" "start" "-a" "3907834ac58f8b20e901bb70101dae08b3f4d221c6dc70212d7624f1715324a3", kill_on_drop: false }`
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.01s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/capture_stdio-0d7a75b131777a10)
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test pipe::stderr::tests::test_stderr ... ok
[INFO] [stdout] test pipe::stdin::tests::test_stdin ... ok
[INFO] [stdout] test output_capture::tests::test_output_capture ... ok
[INFO] [stdout] test pipe::stdout::tests::test_stdout ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests capture-stdio
[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" "3907834ac58f8b20e901bb70101dae08b3f4d221c6dc70212d7624f1715324a3", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "3907834ac58f8b20e901bb70101dae08b3f4d221c6dc70212d7624f1715324a3", kill_on_drop: false }`
[INFO] [stdout] 3907834ac58f8b20e901bb70101dae08b3f4d221c6dc70212d7624f1715324a3