[INFO] fetching crate sudoku-cli 0.2.0...
[INFO] testing sudoku-cli-0.2.0 against master#404c8471aba60c2d837fa728e7c729a0f52d5830 for pr-87041-1
[INFO] extracting crate sudoku-cli 0.2.0 into /workspace/builds/worker-108/source
[INFO] validating manifest of crates.io crate sudoku-cli 0.2.0 on toolchain 404c8471aba60c2d837fa728e7c729a0f52d5830
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+404c8471aba60c2d837fa728e7c729a0f52d5830" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking crates.io crate sudoku-cli 0.2.0
[INFO] finished tweaking crates.io crate sudoku-cli 0.2.0
[INFO] tweaked toml for crates.io crate sudoku-cli 0.2.0 written to /workspace/builds/worker-108/source/Cargo.toml
[INFO] crate crates.io crate sudoku-cli 0.2.0 already has a lockfile, it will not be regenerated
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+404c8471aba60c2d837fa728e7c729a0f52d5830" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-108/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-108/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:a429bb59b7a3b4e7270a4b4c243bb115fc561d8f62ec34ef63a0a36cabf65dfd" "/opt/rustwide/cargo-home/bin/cargo" "+404c8471aba60c2d837fa728e7c729a0f52d5830" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 9a7dfa6050c94584e0331cdb45a2c837e36663d6011f772edab4275d5e125f97
[INFO] running `Command { std: "docker" "start" "-a" "9a7dfa6050c94584e0331cdb45a2c837e36663d6011f772edab4275d5e125f97", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "9a7dfa6050c94584e0331cdb45a2c837e36663d6011f772edab4275d5e125f97", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "9a7dfa6050c94584e0331cdb45a2c837e36663d6011f772edab4275d5e125f97", kill_on_drop: false }`
[INFO] [stdout] 9a7dfa6050c94584e0331cdb45a2c837e36663d6011f772edab4275d5e125f97
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-108/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-108/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:a429bb59b7a3b4e7270a4b4c243bb115fc561d8f62ec34ef63a0a36cabf65dfd" "/opt/rustwide/cargo-home/bin/cargo" "+404c8471aba60c2d837fa728e7c729a0f52d5830" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] b4a4db842114359d130d37d725beb07dc8cd1991f8e2cc4694dd959747d4ae20
[INFO] running `Command { std: "docker" "start" "-a" "b4a4db842114359d130d37d725beb07dc8cd1991f8e2cc4694dd959747d4ae20", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]    Compiling semver-parser v0.7.0
[INFO] [stderr]    Compiling libc v0.2.65
[INFO] [stderr]    Compiling proc-macro2 v1.0.6
[INFO] [stderr]    Compiling arrayvec v0.4.12
[INFO] [stderr]    Compiling unicode-xid v0.2.0
[INFO] [stderr]    Compiling cfg-if v0.1.10
[INFO] [stderr]    Compiling syn v1.0.5
[INFO] [stderr]    Compiling nodrop v0.1.14
[INFO] [stderr]    Compiling scopeguard v1.0.0
[INFO] [stderr]    Compiling rayon-core v1.6.0
[INFO] [stderr]    Compiling unicode-segmentation v1.3.0
[INFO] [stderr]    Compiling unicode-width v0.1.6
[INFO] [stderr]    Compiling ansi_term v0.11.0
[INFO] [stderr]    Compiling vec_map v0.8.1
[INFO] [stderr]    Compiling either v1.5.3
[INFO] [stderr]    Compiling ansi-escapes v0.1.0
[INFO] [stderr]    Compiling crossbeam-utils v0.6.6
[INFO] [stderr]    Compiling textwrap v0.11.0
[INFO] [stderr]    Compiling semver v0.9.0
[INFO] [stderr]    Compiling crossbeam-queue v0.1.2
[INFO] [stderr]    Compiling heck v0.3.1
[INFO] [stderr]    Compiling rustc_version v0.2.3
[INFO] [stderr]    Compiling memoffset v0.5.1
[INFO] [stderr]    Compiling atty v0.2.13
[INFO] [stderr]    Compiling num_cpus v1.10.1
[INFO] [stderr]    Compiling clap v2.33.0
[INFO] [stderr]    Compiling quote v1.0.2
[INFO] [stderr]    Compiling crossbeam-epoch v0.7.2
[INFO] [stderr]    Compiling crossbeam-deque v0.7.1
[INFO] [stderr]    Compiling rayon v1.2.0
[INFO] [stderr]    Compiling proc-macro-error v0.2.6
[INFO] [stderr]    Compiling structopt-derive v0.3.3
[INFO] [stderr]    Compiling structopt v0.3.3
[INFO] [stderr]    Compiling sudoku-cli v0.2.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 16.18s
[INFO] running `Command { std: "docker" "inspect" "b4a4db842114359d130d37d725beb07dc8cd1991f8e2cc4694dd959747d4ae20", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "b4a4db842114359d130d37d725beb07dc8cd1991f8e2cc4694dd959747d4ae20", kill_on_drop: false }`
[INFO] [stdout] b4a4db842114359d130d37d725beb07dc8cd1991f8e2cc4694dd959747d4ae20
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-108/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-108/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:a429bb59b7a3b4e7270a4b4c243bb115fc561d8f62ec34ef63a0a36cabf65dfd" "/opt/rustwide/cargo-home/bin/cargo" "+404c8471aba60c2d837fa728e7c729a0f52d5830" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] f2a8bbd6f736b963dae66414311a805dcfb4b16408b9f54356121f98f0e272e2
[INFO] running `Command { std: "docker" "start" "-a" "f2a8bbd6f736b963dae66414311a805dcfb4b16408b9f54356121f98f0e272e2", kill_on_drop: false }`
[INFO] [stderr]    Compiling sudoku-cli v0.2.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 3.85s
[INFO] running `Command { std: "docker" "inspect" "f2a8bbd6f736b963dae66414311a805dcfb4b16408b9f54356121f98f0e272e2", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "f2a8bbd6f736b963dae66414311a805dcfb4b16408b9f54356121f98f0e272e2", kill_on_drop: false }`
[INFO] [stdout] f2a8bbd6f736b963dae66414311a805dcfb4b16408b9f54356121f98f0e272e2
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-108/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-108/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:a429bb59b7a3b4e7270a4b4c243bb115fc561d8f62ec34ef63a0a36cabf65dfd" "/opt/rustwide/cargo-home/bin/cargo" "+404c8471aba60c2d837fa728e7c729a0f52d5830" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 5c6a314d05ebd7a59040686943a979dba4ab0e4ed8a4dd1c9eae933b3fdf7778
[INFO] running `Command { std: "docker" "start" "-a" "5c6a314d05ebd7a59040686943a979dba4ab0e4ed8a4dd1c9eae933b3fdf7778", kill_on_drop: false }`
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.17s
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/sudoku_cli-a5427cd2a6fc4d9d)
[INFO] [stdout] 
[INFO] [stdout] running 20 tests
[INFO] [stdout] test sudoku::bitwise::tests::clears ... ok
[INFO] [stdout] test sudoku::bitwise::tests::inverse_shifts ... ok
[INFO] [stdout] test sudoku::data::tests::four_slot ... ok
[INFO] [stdout] test sudoku::bitwise::tests::shifts ... ok
[INFO] [stdout] test sudoku::board::tests::fillable_squares ... ok
[INFO] [stdout] test sudoku::data::tests::mark_boxes ... ok
[INFO] [stdout] test sudoku::data::tests::mark_rows ... ok
[INFO] [stdout] test sudoku::data::tests::eight_slot ... ok
[INFO] [stdout] test sudoku::data::tests::seven_slot ... ok
[INFO] [stdout] test sudoku::data::tests::five_slot ... ok
[INFO] [stdout] test sudoku::data::tests::one_slot ... ok
[INFO] [stdout] test sudoku::data::tests::mark_cols ... ok
[INFO] [stdout] test sudoku::data::tests::three_slot ... ok
[INFO] [stdout] test sudoku::data::tests::six_slot ... ok
[INFO] [stdout] test sudoku::data::tests::zero_slot ... ok
[INFO] [stdout] test sudoku::data::tests::two_slot ... ok
[INFO] [stdout] test sudoku::bitwise::tests::retrievals ... ok
[INFO] [stdout] test sudoku::board::tests::find_all ... ok
[INFO] [stdout] test sudoku::board::tests::find_one ... ok
[INFO] [stdout] test sudoku::board::tests::count_solutions ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 20 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.95s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "5c6a314d05ebd7a59040686943a979dba4ab0e4ed8a4dd1c9eae933b3fdf7778", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "5c6a314d05ebd7a59040686943a979dba4ab0e4ed8a4dd1c9eae933b3fdf7778", kill_on_drop: false }`
[INFO] [stdout] 5c6a314d05ebd7a59040686943a979dba4ab0e4ed8a4dd1c9eae933b3fdf7778