[INFO] fetching crate log_err 1.1.1...
[INFO] testing log_err-1.1.1 against master#77d155973c6c22a0e1af49a4a9bac024f697851d for pr-85530-1
[INFO] extracting crate log_err 1.1.1 into /workspace/builds/worker-6/source
[INFO] validating manifest of crates.io crate log_err 1.1.1 on toolchain 77d155973c6c22a0e1af49a4a9bac024f697851d
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+77d155973c6c22a0e1af49a4a9bac024f697851d" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking crates.io crate log_err 1.1.1
[INFO] finished tweaking crates.io crate log_err 1.1.1
[INFO] tweaked toml for crates.io crate log_err 1.1.1 written to /workspace/builds/worker-6/source/Cargo.toml
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+77d155973c6c22a0e1af49a4a9bac024f697851d" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update", kill_on_drop: false }`
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+77d155973c6c22a0e1af49a4a9bac024f697851d" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6/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:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+77d155973c6c22a0e1af49a4a9bac024f697851d" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 27334804826dd87defcf84dac34f6a7a8eba33dc2debb6a5736eea786c282abc
[INFO] running `Command { std: "docker" "start" "-a" "27334804826dd87defcf84dac34f6a7a8eba33dc2debb6a5736eea786c282abc", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "27334804826dd87defcf84dac34f6a7a8eba33dc2debb6a5736eea786c282abc", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "27334804826dd87defcf84dac34f6a7a8eba33dc2debb6a5736eea786c282abc", kill_on_drop: false }`
[INFO] [stdout] 27334804826dd87defcf84dac34f6a7a8eba33dc2debb6a5736eea786c282abc
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6/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:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+77d155973c6c22a0e1af49a4a9bac024f697851d" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] e9ab4d7fd0c4abdceb0a471029c91dec3c52435d2aa6ebd97ba69f995a6e86a8
[INFO] running `Command { std: "docker" "start" "-a" "e9ab4d7fd0c4abdceb0a471029c91dec3c52435d2aa6ebd97ba69f995a6e86a8", kill_on_drop: false }`
[INFO] [stderr]    Compiling log v0.4.14
[INFO] [stderr]    Compiling cfg-if v1.0.0
[INFO] [stderr]    Compiling log_err v1.1.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.97s
[INFO] running `Command { std: "docker" "inspect" "e9ab4d7fd0c4abdceb0a471029c91dec3c52435d2aa6ebd97ba69f995a6e86a8", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "e9ab4d7fd0c4abdceb0a471029c91dec3c52435d2aa6ebd97ba69f995a6e86a8", kill_on_drop: false }`
[INFO] [stdout] e9ab4d7fd0c4abdceb0a471029c91dec3c52435d2aa6ebd97ba69f995a6e86a8
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6/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:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+77d155973c6c22a0e1af49a4a9bac024f697851d" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 63303d6efaa0f719c6e645e5fac063068ff5d09bebbbc10b10eb067fcd7332bf
[INFO] running `Command { std: "docker" "start" "-a" "63303d6efaa0f719c6e645e5fac063068ff5d09bebbbc10b10eb067fcd7332bf", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]    Compiling autocfg v1.0.1
[INFO] [stderr]    Compiling termcolor v1.1.2
[INFO] [stderr]    Compiling time v0.1.44
[INFO] [stderr]    Compiling num-traits v0.2.14
[INFO] [stderr]    Compiling num-integer v0.1.44
[INFO] [stderr]    Compiling chrono v0.4.19
[INFO] [stderr]    Compiling simplelog v0.10.0
[INFO] [stderr]    Compiling log_err v1.1.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 6.68s
[INFO] running `Command { std: "docker" "inspect" "63303d6efaa0f719c6e645e5fac063068ff5d09bebbbc10b10eb067fcd7332bf", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "63303d6efaa0f719c6e645e5fac063068ff5d09bebbbc10b10eb067fcd7332bf", kill_on_drop: false }`
[INFO] [stdout] 63303d6efaa0f719c6e645e5fac063068ff5d09bebbbc10b10eb067fcd7332bf
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6/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:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+77d155973c6c22a0e1af49a4a9bac024f697851d" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] ec0b8e0c41dc32c3065cb19b0d267692003dcdece865647a8f049d4975ad25a2
[INFO] running `Command { std: "docker" "start" "-a" "ec0b8e0c41dc32c3065cb19b0d267692003dcdece865647a8f049d4975ad25a2", kill_on_drop: false }`
[INFO] [stdout] 
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.03s
[INFO] [stdout] running 3 tests
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/log_err-c1457edf499817ec)
[INFO] [stderr] 20:26:00 [ERROR] A wild error SHOULD appear
[INFO] [stderr] 20:26:00 [ERROR] called `Result::unwrap()` on an `Err` value: "a wild error has appeared!"
[INFO] [stderr] 20:26:00 [ERROR] called `Option::unwrap()` on a `None` value
[INFO] [stdout] test test::test_option_log_expect - should panic ... ok
[INFO] [stderr]    Doc-tests log_err
[INFO] [stdout] test test::test_log_unwrap - should panic ... ok
[INFO] [stdout] test test::test_option_log_unwrap - should panic ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.10s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test src/lib.rs - (line 17) ... ok
[INFO] [stdout] test src/lib.rs - (line 8) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.47s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "ec0b8e0c41dc32c3065cb19b0d267692003dcdece865647a8f049d4975ad25a2", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "ec0b8e0c41dc32c3065cb19b0d267692003dcdece865647a8f049d4975ad25a2", kill_on_drop: false }`
[INFO] [stdout] ec0b8e0c41dc32c3065cb19b0d267692003dcdece865647a8f049d4975ad25a2