[INFO] cloning repository https://github.com/bbgdo/lol_chat_parser
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/bbgdo/lol_chat_parser" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fbbgdo%2Flol_chat_parser", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fbbgdo%2Flol_chat_parser'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] d6930e7cd9f14c82c5df0cb9d07f2fdab8d4998c
[INFO] testing bbgdo/lol_chat_parser against try#a7ee93192a766fb0dafa23def7154fc03cfcfc3e for pr-146989
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fbbgdo%2Flol_chat_parser" "/workspace/builds/worker-4-tc2/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-4-tc2/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/bbgdo/lol_chat_parser
[INFO] finished tweaking git repo https://github.com/bbgdo/lol_chat_parser
[INFO] tweaked toml for git repo https://github.com/bbgdo/lol_chat_parser written to /workspace/builds/worker-4-tc2/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/bbgdo/lol_chat_parser on toolchain a7ee93192a766fb0dafa23def7154fc03cfcfc3e
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+a7ee93192a766fb0dafa23def7154fc03cfcfc3e" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/bbgdo/lol_chat_parser 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" "+a7ee93192a766fb0dafa23def7154fc03cfcfc3e" "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
[ERROR] error running command: no output for 300 seconds
[INFO] testing bbgdo/lol_chat_parser against try#a7ee93192a766fb0dafa23def7154fc03cfcfc3e for pr-146989
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fbbgdo%2Flol_chat_parser" "/workspace/builds/worker-4-tc2/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-4-tc2/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/bbgdo/lol_chat_parser
[INFO] finished tweaking git repo https://github.com/bbgdo/lol_chat_parser
[INFO] tweaked toml for git repo https://github.com/bbgdo/lol_chat_parser written to /workspace/builds/worker-4-tc2/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/bbgdo/lol_chat_parser on toolchain a7ee93192a766fb0dafa23def7154fc03cfcfc3e
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+a7ee93192a766fb0dafa23def7154fc03cfcfc3e" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/bbgdo/lol_chat_parser 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" "+a7ee93192a766fb0dafa23def7154fc03cfcfc3e" "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] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded pest_derive v2.8.3
[INFO] [stderr]   Downloaded pest_generator v2.8.3
[INFO] [stderr]   Downloaded pest_meta v2.8.3
[INFO] [stderr]   Downloaded pest v2.8.3
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/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:98afbf2d16093dec2546ff8915fddc74e65664aa03fc460b3712b1c2c54a33e4" "/opt/rustwide/cargo-home/bin/cargo" "+a7ee93192a766fb0dafa23def7154fc03cfcfc3e" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] b354fe1976e297164494f0685761b28409536ede7ebaaee387fc8b097ed8c519
[INFO] running `Command { std: "docker" "start" "-a" "b354fe1976e297164494f0685761b28409536ede7ebaaee387fc8b097ed8c519", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "b354fe1976e297164494f0685761b28409536ede7ebaaee387fc8b097ed8c519", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "b354fe1976e297164494f0685761b28409536ede7ebaaee387fc8b097ed8c519", kill_on_drop: false }`
[INFO] [stdout] b354fe1976e297164494f0685761b28409536ede7ebaaee387fc8b097ed8c519
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/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:98afbf2d16093dec2546ff8915fddc74e65664aa03fc460b3712b1c2c54a33e4" "/opt/rustwide/cargo-home/bin/cargo" "+a7ee93192a766fb0dafa23def7154fc03cfcfc3e" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 8be910b1fde05b05bd77093950d37707ac9449aa6668f0e99b1ac7e05b997233
[INFO] running `Command { std: "docker" "start" "-a" "8be910b1fde05b05bd77093950d37707ac9449aa6668f0e99b1ac7e05b997233", kill_on_drop: false }`
[INFO] [stderr]    Compiling quote v1.0.41
[INFO] [stderr]    Compiling serde_core v1.0.228
[INFO] [stderr]    Compiling ucd-trie v0.1.7
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling anyhow v1.0.100
[INFO] [stderr]    Compiling pest v2.8.3
[INFO] [stderr]    Compiling syn v2.0.108
[INFO] [stderr]    Compiling pest_meta v2.8.3
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling pest_generator v2.8.3
[INFO] [stderr]    Compiling serde_json v1.0.145
[INFO] [stderr]    Compiling pest_derive v2.8.3
[INFO] [stderr]    Compiling lol_chat_parser v0.2.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 10.12s
[INFO] running `Command { std: "docker" "inspect" "8be910b1fde05b05bd77093950d37707ac9449aa6668f0e99b1ac7e05b997233", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "8be910b1fde05b05bd77093950d37707ac9449aa6668f0e99b1ac7e05b997233", kill_on_drop: false }`
[INFO] [stdout] 8be910b1fde05b05bd77093950d37707ac9449aa6668f0e99b1ac7e05b997233
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/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:98afbf2d16093dec2546ff8915fddc74e65664aa03fc460b3712b1c2c54a33e4" "/opt/rustwide/cargo-home/bin/cargo" "+a7ee93192a766fb0dafa23def7154fc03cfcfc3e" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 43c793d056323bc350f573aa9b6b379332c7f0a370c416273e0053184a1ae272
[INFO] running `Command { std: "docker" "start" "-a" "43c793d056323bc350f573aa9b6b379332c7f0a370c416273e0053184a1ae272", kill_on_drop: false }`
[INFO] [stderr]    Compiling lol_chat_parser v0.2.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 1.20s
[INFO] running `Command { std: "docker" "inspect" "43c793d056323bc350f573aa9b6b379332c7f0a370c416273e0053184a1ae272", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "43c793d056323bc350f573aa9b6b379332c7f0a370c416273e0053184a1ae272", kill_on_drop: false }`
[INFO] [stdout] 43c793d056323bc350f573aa9b6b379332c7f0a370c416273e0053184a1ae272
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/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:98afbf2d16093dec2546ff8915fddc74e65664aa03fc460b3712b1c2c54a33e4" "/opt/rustwide/cargo-home/bin/cargo" "+a7ee93192a766fb0dafa23def7154fc03cfcfc3e" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 16dd237131e508870bc5f048ffeca05fadab78c33dc0fd72ac4fe3d1922a1018
[INFO] running `Command { std: "docker" "start" "-a" "16dd237131e508870bc5f048ffeca05fadab78c33dc0fd72ac4fe3d1922a1018", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.07s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/lol_chat_parser-2070e520acf1a664)
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/lol_chat_parser-2d2751c0b932fd3a)
[INFO] [stdout] 
[INFO] [stderr]      Running tests/grammar_rules_spec.rs (/opt/rustwide/target/debug/deps/grammar_rules_spec-4b125f5f25e4d21d)
[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] [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] [stdout] 
[INFO] [stdout] running 31 tests
[INFO] [stdout] test rule_champion_name_multi_word ... ok
[INFO] [stdout] test rule_bare_chat_message_parses_player_chat ... ok
[INFO] [stdout] test rule_champion_name_single_word ... ok
[INFO] [stdout] test rule_channel_chat_message_parses_party_chat ... ok
[INFO] [stdout] test rule_channel_tag_parses_all_team_party ... ok
[INFO] [stdout] test rule_channel_chat_message_parses_team_chat ... ok
[INFO] [stdout] test rule_generic_player_event_parses ... ok
[INFO] [stdout] test rule_chat_text_parses_full_message ... ok
[INFO] [stdout] test rule_channel_chat_message_parses_all_chat ... ok
[INFO] [stdout] test rule_generic_text_parses_any_text ... ok
[INFO] [stdout] test rule_kill_shutdown_event_parses ... ok
[INFO] [stdout] test rule_percentage_parses_percentage_digits ... ok
[INFO] [stdout] test rule_ping_on_the_way_event_parses ... ok
[INFO] [stdout] test rule_line_body_parses_body_only ... ok
[INFO] [stdout] test rule_rampage_event_parses ... ok
[INFO] [stdout] test rule_line_parses_full_line ... ok
[INFO] [stdout] test rule_name_phrase_parses_multi_word ... ok
[INFO] [stdout] test rule_target_objective_event_parses ... ok
[INFO] [stdout] test rule_player_name_parses_simple_name ... ok
[INFO] [stdout] test rule_team_name_parses_enemy_team ... ok
[INFO] [stdout] test rule_number_parses_integer ... ok
[INFO] [stdout] test rule_player_with_champion_parses_pair ... ok
[INFO] [stdout] test rule_team_feat_event_parses ... ok
[INFO] [stdout] test rule_kill_first_blood_event_parses ... ok
[INFO] [stdout] test rule_generic_tail_parses_rest ... ok
[INFO] [stdout] test rule_time_parses_timestamp ... ok
[INFO] [stdout] test rule_word_parses_word_with_apostrophe ... ok
[INFO] [stdout] test rule_shutdown_bounty_parses ... ok
[INFO] [stdout] test rule_target_player_event_parses ... ok
[INFO] [stderr]      Running tests/json_integration_spec.rs (/opt/rustwide/target/debug/deps/json_integration_spec-dbfdd72fa1c9ff0d)
[INFO] [stdout] test rule_chat_message_parses_any_chat ... ok
[INFO] [stdout] test rule_purchase_event_parses ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 31 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.03s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests lol_chat_parser
[INFO] [stdout] running 1 test
[INFO] [stdout] test parses_sample_log_and_produces_expected_json_shape ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[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" "16dd237131e508870bc5f048ffeca05fadab78c33dc0fd72ac4fe3d1922a1018", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "16dd237131e508870bc5f048ffeca05fadab78c33dc0fd72ac4fe3d1922a1018", kill_on_drop: false }`
[INFO] [stdout] 16dd237131e508870bc5f048ffeca05fadab78c33dc0fd72ac4fe3d1922a1018