[INFO] cloning repository https://github.com/philschatz/puzzlescript-rust
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/philschatz/puzzlescript-rust" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fphilschatz%2Fpuzzlescript-rust", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fphilschatz%2Fpuzzlescript-rust'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 3691f45210347904487082df1dbb316d8b67538c
[INFO] testing philschatz/puzzlescript-rust against try#ecabaf78506b7a4668d42dc20268c086b93f0fad for pr-87041
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fphilschatz%2Fpuzzlescript-rust" "/workspace/builds/worker-12/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-12/source'...
[INFO] [stderr] done.
[INFO] validating manifest of git repo https://github.com/philschatz/puzzlescript-rust on toolchain ecabaf78506b7a4668d42dc20268c086b93f0fad
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+ecabaf78506b7a4668d42dc20268c086b93f0fad" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking git repo https://github.com/philschatz/puzzlescript-rust
[INFO] finished tweaking git repo https://github.com/philschatz/puzzlescript-rust
[INFO] tweaked toml for git repo https://github.com/philschatz/puzzlescript-rust written to /workspace/builds/worker-12/source/Cargo.toml
[INFO] crate git repo https://github.com/philschatz/puzzlescript-rust already has a lockfile, it will not be regenerated
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+ecabaf78506b7a4668d42dc20268c086b93f0fad" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded tui v0.6.1
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-12/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-12/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" "+ecabaf78506b7a4668d42dc20268c086b93f0fad" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] dcdec2c124381516f0f0170af9fef65aa350c2cc9676df10f05a7e9e694d4180
[INFO] running `Command { std: "docker" "start" "-a" "dcdec2c124381516f0f0170af9fef65aa350c2cc9676df10f05a7e9e694d4180", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "dcdec2c124381516f0f0170af9fef65aa350c2cc9676df10f05a7e9e694d4180", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "dcdec2c124381516f0f0170af9fef65aa350c2cc9676df10f05a7e9e694d4180", kill_on_drop: false }`
[INFO] [stdout] dcdec2c124381516f0f0170af9fef65aa350c2cc9676df10f05a7e9e694d4180
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-12/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-12/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" "+ecabaf78506b7a4668d42dc20268c086b93f0fad" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] fdb87f4e3db5739de18cc058dcf6d236de8868aa282e8fb7d7e1d3ac04716e67
[INFO] running `Command { std: "docker" "start" "-a" "fdb87f4e3db5739de18cc058dcf6d236de8868aa282e8fb7d7e1d3ac04716e67", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.58
[INFO] [stderr]    Compiling unicode-xid v0.1.0
[INFO] [stderr]    Compiling syn v0.15.39
[INFO] [stderr]    Compiling vec_map v0.8.1
[INFO] [stderr]    Compiling cassowary v0.3.0
[INFO] [stderr]    Compiling hex v0.3.2
[INFO] [stderr]    Compiling rand_chacha v0.2.0
[INFO] [stderr]    Compiling log v0.4.6
[INFO] [stderr]    Compiling regex-syntax v0.6.7
[INFO] [stderr]    Compiling itertools v0.8.0
[INFO] [stderr]    Compiling c2-chacha v0.2.2
[INFO] [stderr]    Compiling aho-corasick v0.7.3
[INFO] [stderr]    Compiling proc-macro2 v0.4.30
[INFO] [stderr]    Compiling getrandom v0.1.6
[INFO] [stderr]    Compiling atty v0.2.11
[INFO] [stderr]    Compiling termion v1.5.3
[INFO] [stderr]    Compiling quote v0.6.12
[INFO] [stderr]    Compiling clap v2.33.0
[INFO] [stderr]    Compiling rand_core v0.5.0
[INFO] [stderr]    Compiling rand_xorshift v0.2.0
[INFO] [stderr]    Compiling rand v0.7.0
[INFO] [stderr]    Compiling tui v0.6.1
[INFO] [stderr]    Compiling regex v1.1.7
[INFO] [stderr]    Compiling serde_derive v1.0.94
[INFO] [stderr]    Compiling env_logger v0.6.2
[INFO] [stderr]    Compiling serde v1.0.94
[INFO] [stderr]    Compiling serde_json v1.0.40
[INFO] [stderr]    Compiling puzzlescript v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 37.44s
[INFO] running `Command { std: "docker" "inspect" "fdb87f4e3db5739de18cc058dcf6d236de8868aa282e8fb7d7e1d3ac04716e67", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "fdb87f4e3db5739de18cc058dcf6d236de8868aa282e8fb7d7e1d3ac04716e67", kill_on_drop: false }`
[INFO] [stdout] fdb87f4e3db5739de18cc058dcf6d236de8868aa282e8fb7d7e1d3ac04716e67
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-12/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-12/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" "+ecabaf78506b7a4668d42dc20268c086b93f0fad" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 31c238f26dc64e25a448772336c98b4ee3752221a8dca5fec77ff74a86b45e99
[INFO] running `Command { std: "docker" "start" "-a" "31c238f26dc64e25a448772336c98b4ee3752221a8dca5fec77ff74a86b45e99", kill_on_drop: false }`
[INFO] [stderr]    Compiling puzzlescript v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 6.66s
[INFO] running `Command { std: "docker" "inspect" "31c238f26dc64e25a448772336c98b4ee3752221a8dca5fec77ff74a86b45e99", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "31c238f26dc64e25a448772336c98b4ee3752221a8dca5fec77ff74a86b45e99", kill_on_drop: false }`
[INFO] [stdout] 31c238f26dc64e25a448772336c98b4ee3752221a8dca5fec77ff74a86b45e99
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-12/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-12/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" "+ecabaf78506b7a4668d42dc20268c086b93f0fad" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] f1e67822ef31d74317978856d269dc82717fb1be540564485c753a59f76720b9
[INFO] running `Command { std: "docker" "start" "-a" "f1e67822ef31d74317978856d269dc82717fb1be540564485c753a59f76720b9", kill_on_drop: false }`
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.05s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/puzzlescript-ab11a84d33090647)
[INFO] [stdout] running 50 tests
[INFO] [stdout] test bitset::tests::bitset ... ok
[INFO] [stdout] test bitset::tests::large ... ok
[INFO] [stdout] test model::board::tests::neighbor_positions ... ok
[INFO] [stdout] test bitset::tests::contains_any ... ok
[INFO] [stdout] test color::tests::alpha ... ok
[INFO] [stdout] test model::board::tests::stripe_cache ... ok
[INFO] [stdout] test model::bracket::tests::empty_ellipsis_neighbor ... ok
[INFO] [stdout] test model::game::tests::game_cancelled_move ... ok
[INFO] [stdout] test model::game::tests::winconditions_are_anded ... ok
[INFO] [stdout] test bitset::tests::check_cardinality ... ok
[INFO] [stdout] test model::neighbor::tests::matches_no_or ... ok
[INFO] [stdout] test model::rule::tests::nonchanging_neighbors_does_not_loop_forever ... ok
[INFO] [stdout] test model::neighbor::tests::matches_no_or2 ... ok
[INFO] [stdout] test model::bracket::tests::empty_ellipsis_neighbor_rule ... ok
[INFO] [stdout] test model::rule::tests::random_runs_once ... ok
[INFO] [stdout] test model::neighbor::tests::matches_two_ors ... ok
[INFO] [stdout] test model::rule::tests::permutations ... ok
[INFO] [stdout] test model::neighbor::tests::prepare_negation ... ok
[INFO] [stdout] test model::neighbor::tests::prepare_neighbor_multiple_single_sprites ... ok
[INFO] [stdout] test model::game::tests::game_player_didnt_move_because_of_collision ... ok
[INFO] [stdout] test model::game::tests::game_player_didnt_move_because_of_level_boundary ... ok
[INFO] [stdout] test model::rule::tests::command_only ... ok
[INFO] [stdout] test model::rule::tests::command_and_nonchanging_neighbors ... ok
[INFO] [stdout] test model::neighbor::tests::random_dir ... ok
[INFO] [stdout] test model::bracket::tests::empty_neighbor ... ok
[INFO] [stdout] test model::neighbor::tests::replace_direction ... ok
[INFO] [stdout] test model::neighbor::tests::replace_tile_with_random ... ok
[INFO] [stdout] test model::neighbor::tests::prepare_or_negation ... ok
[INFO] [stdout] test model::rule::tests::keeps_evaluating_rule_in_group_before_moving_to_next ... ok
[INFO] [stdout] test model::game::tests::post_resolution_does_not_lose_sprites ... ok
[INFO] [stdout] test model::game::tests::evals_late_rules_after_moving ... ok
[INFO] [stdout] test model::neighbor::tests::remove_one_or_sprite_when_condition_has_no ... ok
[INFO] [stdout] test model::game::tests::game_player_did_move_eventually ... ok
[INFO] [stdout] test model::bracket::tests::find_still_matches ... ok
[INFO] [stdout] test color::tests::variant ... ok
[INFO] [stdout] test model::game::tests::winconditions_on_works_when_late ... ok
[INFO] [stdout] test model::bracket::tests::bracket ... ok
[INFO] [stdout] test model::neighbor::tests::matches_empty_neighbor ... ok
[INFO] [stdout] test model::neighbor::tests::prepare_neighbor_or_tile ... ok
[INFO] [stdout] test model::neighbor::tests::prepare_neighbor_single_sprite ... ok
[INFO] [stdout] test model::neighbor::tests::tiles_with_modifier ... ok
[INFO] [stdout] test color::tests::on_top_of ... ok
[INFO] [stdout] test model::game::tests::winconditions_on_works ... ok
[INFO] [stdout] test model::game::tests::game_player_move_and_sent_command ... ok
[INFO] [stdout] test model::rule::tests::remove_all_or_sprites_when_there_is_no_condition_and_action_is_negated ... ok
[INFO] [stdout] test model::rule::tests::recalc_permutations ... ok
[INFO] [stdout] test model::rule::tests::rule_group_keeps_looping ... ok
[INFO] [stdout] test model::rule::tests::remove_all_or_sprites_when_there_is_no_condition ... ok
[INFO] [stdout] test model::rule::tests::rule_crawler ... ok
[INFO] [stdout] test model::rule::tests::rule_exhaustion ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 50 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.03s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "f1e67822ef31d74317978856d269dc82717fb1be540564485c753a59f76720b9", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "f1e67822ef31d74317978856d269dc82717fb1be540564485c753a59f76720b9", kill_on_drop: false }`
[INFO] [stdout] f1e67822ef31d74317978856d269dc82717fb1be540564485c753a59f76720b9