[INFO] fetching crate solverforge-console 0.8.6...
[INFO] testing solverforge-console-0.8.6 against master#562dee4820c458d823175268e41601d4c060588a for pr-154210-2
[INFO] extracting crate solverforge-console 0.8.6 into /workspace/builds/worker-7-tc1/source
[INFO] started tweaking crates.io crate solverforge-console 0.8.6
[INFO] finished tweaking crates.io crate solverforge-console 0.8.6
[INFO] tweaked toml for crates.io crate solverforge-console 0.8.6 written to /workspace/builds/worker-7-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate solverforge-console 0.8.6 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] crate crates.io crate solverforge-console 0.8.6 already has a lockfile, it will not be regenerated
[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] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-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] 706d5b65d42ed5b49d1a0f9936f4374fd45fd5c444d59de6015ab1fd9c51f931
[INFO] running `Command { std: "docker" "start" "-a" "706d5b65d42ed5b49d1a0f9936f4374fd45fd5c444d59de6015ab1fd9c51f931", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "706d5b65d42ed5b49d1a0f9936f4374fd45fd5c444d59de6015ab1fd9c51f931", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "706d5b65d42ed5b49d1a0f9936f4374fd45fd5c444d59de6015ab1fd9c51f931", kill_on_drop: false }`
[INFO] [stdout] 706d5b65d42ed5b49d1a0f9936f4374fd45fd5c444d59de6015ab1fd9c51f931
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-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] 20343405d208996c852178feb9182a22c30264821452793ea85709bfe07c25df
[INFO] running `Command { std: "docker" "start" "-a" "20343405d208996c852178feb9182a22c30264821452793ea85709bfe07c25df", kill_on_drop: false }`
[INFO] [stderr]    Compiling tracing-core v0.1.36
[INFO] [stderr]    Compiling syn v2.0.117
[INFO] [stderr]    Compiling owo-colors v4.3.0
[INFO] [stderr]    Compiling arrayvec v0.7.6
[INFO] [stderr]    Compiling regex-automata v0.4.14
[INFO] [stderr]    Compiling tracing-log v0.2.0
[INFO] [stderr]    Compiling num-format v0.4.4
[INFO] [stderr]    Compiling matchers v0.2.0
[INFO] [stderr]    Compiling tracing-attributes v0.1.31
[INFO] [stderr]    Compiling tracing v0.1.44
[INFO] [stderr]    Compiling tracing-subscriber v0.3.23
[INFO] [stderr]    Compiling solverforge-console v0.8.6 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 10.47s
[INFO] running `Command { std: "docker" "inspect" "20343405d208996c852178feb9182a22c30264821452793ea85709bfe07c25df", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "20343405d208996c852178feb9182a22c30264821452793ea85709bfe07c25df", kill_on_drop: false }`
[INFO] [stdout] 20343405d208996c852178feb9182a22c30264821452793ea85709bfe07c25df
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-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] 25b72e72080a13867c83b4c0245126aa45449398614830ce0b2ea10f4876ccfb
[INFO] running `Command { std: "docker" "start" "-a" "25b72e72080a13867c83b4c0245126aa45449398614830ce0b2ea10f4876ccfb", kill_on_drop: false }`
[INFO] [stderr]    Compiling solverforge-console v0.8.6 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.86s
[INFO] running `Command { std: "docker" "inspect" "25b72e72080a13867c83b4c0245126aa45449398614830ce0b2ea10f4876ccfb", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "25b72e72080a13867c83b4c0245126aa45449398614830ce0b2ea10f4876ccfb", kill_on_drop: false }`
[INFO] [stdout] 25b72e72080a13867c83b4c0245126aa45449398614830ce0b2ea10f4876ccfb
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-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] 522cf59344b1ce5479aa5b4b762708ee1a32a70196e8cffb8c1933c4cefb6ed8
[INFO] running `Command { std: "docker" "start" "-a" "522cf59344b1ce5479aa5b4b762708ee1a32a70196e8cffb8c1933c4cefb6ed8", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.04s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/solverforge_console-00b83a189807f12e)
[INFO] [stdout] 
[INFO] [stdout] running 5 tests
[INFO] [stdout] test format::tests::format_duration_covers_milliseconds_seconds_and_minutes ... ok
[INFO] [stdout] test format::tests::format_event_renders_progress_and_trace_steps ... ok
[INFO] [stdout] test format::tests::calculate_problem_scale_handles_zero_and_nonzero_inputs ... ok
[INFO] [stdout] test format::tests::format_event_renders_solve_start_and_end_summaries ... ok
[INFO] [stdout] test format::tests::format_score_handles_hard_soft_and_simple_scores ... 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]    Doc-tests solverforge_console
[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" "522cf59344b1ce5479aa5b4b762708ee1a32a70196e8cffb8c1933c4cefb6ed8", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "522cf59344b1ce5479aa5b4b762708ee1a32a70196e8cffb8c1933c4cefb6ed8", kill_on_drop: false }`
[INFO] [stdout] 522cf59344b1ce5479aa5b4b762708ee1a32a70196e8cffb8c1933c4cefb6ed8