[INFO] cloning repository https://github.com/xaviripo/aoc2020
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/xaviripo/aoc2020" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fxaviripo%2Faoc2020", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fxaviripo%2Faoc2020'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] d2b36349b57e8a83cb2d46666e564b233d06987f
[INFO] testing xaviripo/aoc2020 against master#404c8471aba60c2d837fa728e7c729a0f52d5830 for pr-87041-1
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fxaviripo%2Faoc2020" "/workspace/builds/worker-109/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-109/source'...
[INFO] [stderr] done.
[INFO] validating manifest of git repo https://github.com/xaviripo/aoc2020 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 git repo https://github.com/xaviripo/aoc2020
[INFO] finished tweaking git repo https://github.com/xaviripo/aoc2020
[INFO] tweaked toml for git repo https://github.com/xaviripo/aoc2020 written to /workspace/builds/worker-109/source/Cargo.toml
[INFO] crate git repo https://github.com/xaviripo/aoc2020 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-109/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-109/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] 8684a6993a5ba9519f583d9d5ef179aeef8177a31a1bfa2f7401b83998af9088
[INFO] running `Command { std: "docker" "start" "-a" "8684a6993a5ba9519f583d9d5ef179aeef8177a31a1bfa2f7401b83998af9088", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "8684a6993a5ba9519f583d9d5ef179aeef8177a31a1bfa2f7401b83998af9088", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "8684a6993a5ba9519f583d9d5ef179aeef8177a31a1bfa2f7401b83998af9088", kill_on_drop: false }`
[INFO] [stdout] 8684a6993a5ba9519f583d9d5ef179aeef8177a31a1bfa2f7401b83998af9088
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-109/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-109/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] eb1bc07bb27734038a138fdc4b7b5f45c9b115eff6b986a7bb971739a216dfa1
[INFO] running `Command { std: "docker" "start" "-a" "eb1bc07bb27734038a138fdc4b7b5f45c9b115eff6b986a7bb971739a216dfa1", kill_on_drop: false }`
[INFO] [stderr]    Compiling memchr v2.3.4
[INFO] [stderr]    Compiling regex-syntax v0.6.21
[INFO] [stderr]    Compiling thread_local v1.0.1
[INFO] [stderr]    Compiling aho-corasick v0.7.15
[INFO] [stderr]    Compiling regex v1.4.2
[INFO] [stderr]    Compiling aoc2020 v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 18.60s
[INFO] running `Command { std: "docker" "inspect" "eb1bc07bb27734038a138fdc4b7b5f45c9b115eff6b986a7bb971739a216dfa1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "eb1bc07bb27734038a138fdc4b7b5f45c9b115eff6b986a7bb971739a216dfa1", kill_on_drop: false }`
[INFO] [stdout] eb1bc07bb27734038a138fdc4b7b5f45c9b115eff6b986a7bb971739a216dfa1
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-109/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-109/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] 389a70e95bc92470f073d14dc86b51fa1b8185c4c38c99e19ca70e0a9166f3a4
[INFO] running `Command { std: "docker" "start" "-a" "389a70e95bc92470f073d14dc86b51fa1b8185c4c38c99e19ca70e0a9166f3a4", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]    Compiling aoc2020 v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 8.26s
[INFO] running `Command { std: "docker" "inspect" "389a70e95bc92470f073d14dc86b51fa1b8185c4c38c99e19ca70e0a9166f3a4", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "389a70e95bc92470f073d14dc86b51fa1b8185c4c38c99e19ca70e0a9166f3a4", kill_on_drop: false }`
[INFO] [stdout] 389a70e95bc92470f073d14dc86b51fa1b8185c4c38c99e19ca70e0a9166f3a4
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-109/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-109/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] f068c556f13717214c34fbf8ff8b10594b9c52d806e2f324d5b77c6124d32bb1
[INFO] running `Command { std: "docker" "start" "-a" "f068c556f13717214c34fbf8ff8b10594b9c52d806e2f324d5b77c6124d32bb1", kill_on_drop: false }`
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.19s
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/aoc2020-28c9864f43fdc8d3)
[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 unittests (/opt/rustwide/target/debug/deps/aoc2020-26ff32802f8dfe4a)
[INFO] [stdout] 
[INFO] [stdout] running 54 tests
[INFO] [stdout] test day10::test::run1_1 ... ok
[INFO] [stdout] test day10::test::run1_2 ... ok
[INFO] [stdout] test day10::test::run2_2 ... ok
[INFO] [stdout] test day10::test::run2_1 ... ok
[INFO] [stdout] test day12::part1::test::rotate_r_1_4 ... ok
[INFO] [stdout] test day11::test::run1 ... ok
[INFO] [stdout] test day13::test::run2 ... ok
[INFO] [stdout] test day11::test::run2 ... ok
[INFO] [stdout] test day12::part1::test::run ... ok
[INFO] [stdout] test day13::test::run1 ... ok
[INFO] [stdout] test day14::test::run1 ... ok
[INFO] [stdout] test day12::part2::test::run ... ok
[INFO] [stdout] test day18::test::run1 ... ok
[INFO] [stdout] test day14::test::run2 ... ok
[INFO] [stdout] test day16::test::run1 ... ok
[INFO] [stdout] test day16::test::run2 ... ok
[INFO] [stdout] test day18::test::simple_test ... ok
[INFO] [stdout] test day15::test::run1 ... ok
[INFO] [stdout] test day18::test::run2 ... ok
[INFO] [stdout] test day18::test::test_tokenize ... ok
[INFO] [stdout] test day20::test::test_fits ... ok
[INFO] [stdout] test day19::test::run1 ... ok
[INFO] [stdout] test day20::test::run1 ... ok
[INFO] [stdout] test day17::test::run1 ... ok
[INFO] [stdout] test day21::test::run1 ... ok
[INFO] [stdout] test day21::test::run2 ... ok
[INFO] [stdout] test day21::test::test_candidates ... ok
[INFO] [stdout] test day21::test::test_solution ... ok
[INFO] [stdout] test day22::part1::test::run ... ok
[INFO] [stdout] test day23::test::run1_10 ... ok
[INFO] [stdout] test day23::test::run1_100 ... ok
[INFO] [stdout] test day25::test::run1 ... ok
[INFO] [stdout] test day3::test::run1 ... ok
[INFO] [stdout] test day3::test::run2 ... ok
[INFO] [stdout] test day24::test::run1 ... ok
[INFO] [stdout] test day22::part2::test::run ... ok
[INFO] [stdout] test day4::test::run1 ... ok
[INFO] [stdout] test day4::test::run2_invalid ... ok
[INFO] [stdout] test day5::test::run1 ... ok
[INFO] [stdout] test day4::test::run2_valid ... ok
[INFO] [stdout] test day6::test::run1 ... ok
[INFO] [stdout] test day6::test::run2 ... ok
[INFO] [stdout] test day7::test::run2 ... ok
[INFO] [stdout] test day7::test::run2_2 ... ok
[INFO] [stdout] test day7::test::run1 ... ok
[INFO] [stdout] test day8::test::run2 ... ok
[INFO] [stdout] test day9::test::run2 ... ok
[INFO] [stdout] test day8::test::run1 ... ok
[INFO] [stdout] test day9::test::run1 ... ok
[INFO] [stdout] test day19::test::run2 ... ok
[INFO] [stdout] test day17::test::run2 ... ok
[INFO] [stdout] test day24::test::run2 ... ok
[INFO] [stdout] test day15::test::run2 has been running for over 60 seconds
[INFO] [stdout] test day23::test::run2 has been running for over 60 seconds
[INFO] [stdout] test day23::test::run2 ... ok
[ERROR] error running command: no output for 300 seconds
[INFO] running `Command { std: "docker" "inspect" "f068c556f13717214c34fbf8ff8b10594b9c52d806e2f324d5b77c6124d32bb1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "f068c556f13717214c34fbf8ff8b10594b9c52d806e2f324d5b77c6124d32bb1", kill_on_drop: false }`
[INFO] [stdout] f068c556f13717214c34fbf8ff8b10594b9c52d806e2f324d5b77c6124d32bb1