[INFO] cloning repository https://github.com/david-a-wheeler/metamath-knife
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/david-a-wheeler/metamath-knife" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fdavid-a-wheeler%2Fmetamath-knife", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fdavid-a-wheeler%2Fmetamath-knife'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 071292ef3a10350d0dae9e409b4921f91a08ce95
[INFO] testing david-a-wheeler/metamath-knife against try#61cd42370816fd135c0f3b9debf2f5dfcf194594 for pr-98839
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fdavid-a-wheeler%2Fmetamath-knife" "/workspace/builds/worker-2/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-2/source'...
[INFO] [stderr] done.
[INFO] validating manifest of git repo https://github.com/david-a-wheeler/metamath-knife on toolchain 61cd42370816fd135c0f3b9debf2f5dfcf194594
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+61cd42370816fd135c0f3b9debf2f5dfcf194594" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking git repo https://github.com/david-a-wheeler/metamath-knife
[INFO] finished tweaking git repo https://github.com/david-a-wheeler/metamath-knife
[INFO] tweaked toml for git repo https://github.com/david-a-wheeler/metamath-knife written to /workspace/builds/worker-2/source/Cargo.toml
[INFO] crate git repo https://github.com/david-a-wheeler/metamath-knife already has a lockfile, it will not be regenerated
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+61cd42370816fd135c0f3b9debf2f5dfcf194594" "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-2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2/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:5873ed620acfc3295992aedd9294443d62a478a48ba243bc5e6c4f5abaffa05f" "/opt/rustwide/cargo-home/bin/cargo" "+61cd42370816fd135c0f3b9debf2f5dfcf194594" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 4f086386a7383ecbbe84b1c656993c527e966cf1051620fab6185891155c18b8
[INFO] running `Command { std: "docker" "start" "-a" "4f086386a7383ecbbe84b1c656993c527e966cf1051620fab6185891155c18b8", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "4f086386a7383ecbbe84b1c656993c527e966cf1051620fab6185891155c18b8", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "4f086386a7383ecbbe84b1c656993c527e966cf1051620fab6185891155c18b8", kill_on_drop: false }`
[INFO] [stdout] 4f086386a7383ecbbe84b1c656993c527e966cf1051620fab6185891155c18b8
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2/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:5873ed620acfc3295992aedd9294443d62a478a48ba243bc5e6c4f5abaffa05f" "/opt/rustwide/cargo-home/bin/cargo" "+61cd42370816fd135c0f3b9debf2f5dfcf194594" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] fe315107f8a9f5d8127aebb38bae36fe27e0fe640918388c1e2b3eb454acbb4b
[INFO] running `Command { std: "docker" "start" "-a" "fe315107f8a9f5d8127aebb38bae36fe27e0fe640918388c1e2b3eb454acbb4b", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]    Compiling libc v0.2.103
[INFO] [stderr]    Compiling autocfg v1.0.1
[INFO] [stderr]    Compiling memchr v2.4.1
[INFO] [stderr]    Compiling cfg-if v1.0.0
[INFO] [stderr]    Compiling unicode-width v0.1.9
[INFO] [stderr]    Compiling log v0.4.14
[INFO] [stderr]    Compiling lazy_static v1.4.0
[INFO] [stderr]    Compiling either v1.6.1
[INFO] [stderr]    Compiling yansi-term v0.1.2
[INFO] [stderr]    Compiling regex-syntax v0.6.25
[INFO] [stderr]    Compiling vec_map v0.8.2
[INFO] [stderr]    Compiling ansi_term v0.11.0
[INFO] [stderr]    Compiling strsim v0.8.0
[INFO] [stderr]    Compiling bitflags v1.3.2
[INFO] [stderr]    Compiling tinyvec v1.5.0
[INFO] [stderr]    Compiling fnv v1.0.7
[INFO] [stderr]    Compiling typed-arena v2.0.1
[INFO] [stderr]    Compiling textwrap v0.11.0
[INFO] [stderr]    Compiling itertools v0.10.3
[INFO] [stderr]    Compiling annotate-snippets v0.9.1
[INFO] [stderr]    Compiling num-traits v0.2.14
[INFO] [stderr]    Compiling num-integer v0.1.44
[INFO] [stderr]    Compiling atty v0.2.14
[INFO] [stderr]    Compiling time v0.1.44
[INFO] [stderr]    Compiling filetime v0.2.15
[INFO] [stderr]    Compiling aho-corasick v0.7.18
[INFO] [stderr]    Compiling colored v1.9.3
[INFO] [stderr]    Compiling clap v2.33.3
[INFO] [stderr]    Compiling chrono v0.4.19
[INFO] [stderr]    Compiling regex v1.5.4
[INFO] [stderr]    Compiling simple_logger v1.13.0
[INFO] [stderr]    Compiling metamath-knife v0.3.6 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [optimized + debuginfo] target(s) in 1m 03s
[INFO] running `Command { std: "docker" "inspect" "fe315107f8a9f5d8127aebb38bae36fe27e0fe640918388c1e2b3eb454acbb4b", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "fe315107f8a9f5d8127aebb38bae36fe27e0fe640918388c1e2b3eb454acbb4b", kill_on_drop: false }`
[INFO] [stdout] fe315107f8a9f5d8127aebb38bae36fe27e0fe640918388c1e2b3eb454acbb4b
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2/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:5873ed620acfc3295992aedd9294443d62a478a48ba243bc5e6c4f5abaffa05f" "/opt/rustwide/cargo-home/bin/cargo" "+61cd42370816fd135c0f3b9debf2f5dfcf194594" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] bbdd1cbea7f99202fd6ba6bcb8715a26fe5a8807d7a4e5331d77ff24d2afcf38
[INFO] running `Command { std: "docker" "start" "-a" "bbdd1cbea7f99202fd6ba6bcb8715a26fe5a8807d7a4e5331d77ff24d2afcf38", kill_on_drop: false }`
[INFO] [stderr]    Compiling metamath-knife v0.3.6 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [optimized + debuginfo] target(s) in 21.96s
[INFO] running `Command { std: "docker" "inspect" "bbdd1cbea7f99202fd6ba6bcb8715a26fe5a8807d7a4e5331d77ff24d2afcf38", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "bbdd1cbea7f99202fd6ba6bcb8715a26fe5a8807d7a4e5331d77ff24d2afcf38", kill_on_drop: false }`
[INFO] [stdout] bbdd1cbea7f99202fd6ba6bcb8715a26fe5a8807d7a4e5331d77ff24d2afcf38
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2/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:5873ed620acfc3295992aedd9294443d62a478a48ba243bc5e6c4f5abaffa05f" "/opt/rustwide/cargo-home/bin/cargo" "+61cd42370816fd135c0f3b9debf2f5dfcf194594" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] c60a99efbd6d6c93ed7b62c2d13b594d9dd1c12ceca73302585c2a72576848be
[INFO] running `Command { std: "docker" "start" "-a" "c60a99efbd6d6c93ed7b62c2d13b594d9dd1c12ceca73302585c2a72576848be", kill_on_drop: false }`
[INFO] [stderr]     Finished test [optimized + debuginfo] target(s) in 0.11s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/metamath_knife-93ecf8c78ebf5dd9)
[INFO] [stdout] 
[INFO] [stdout] running 45 tests
[INFO] [stdout] test comment_parser_tests::edge_cases ... ok
[INFO] [stdout] test comment_parser_tests::test_bib ... ok
[INFO] [stdout] test comment_parser_tests::test_html ... ok
[INFO] [stdout] test comment_parser_tests::test_discouragements ... ok
[INFO] [stdout] test comment_parser_tests::test_basic ... ok
[INFO] [stdout] test comment_parser_tests::test_italic ... ok
[INFO] [stdout] test comment_parser_tests::test_label ... ok
[INFO] [stdout] test grammar_tests::test_db_formula ... ok
[INFO] [stdout] test comment_parser_tests::test_subscript ... ok
[INFO] [stdout] test formula_tests::test_substitute ... ok
[INFO] [stdout] test comment_parser_tests::test_para ... ok
[INFO] [stdout] test grammar_tests::test_db_stmt_parse ... ok
[INFO] [stdout] test comment_parser_tests::test_math ... ok
[INFO] [stdout] test grammar_tests::test_float_not_var ... ok
[INFO] [stdout] test grammar_tests::test_ambiguous ... ok
[INFO] [stdout] test grammar_tests::test_missing_float ... ok
[INFO] [stdout] test comment_parser_tests::test_three_subscript ... ok
[INFO] [stdout] test comment_parser_tests::test_two_subscript ... ok
[INFO] [stdout] test grammar_tests::test_db_43_formula ... ok
[INFO] [stdout] test formula_tests::test_unify ... ok
[INFO] [stdout] test grammar_tests::test_db_32_formula ... ok
[INFO] [stdout] test grammar_tests::test_parse_formula ... ok
[INFO] [stdout] test grammar_tests::test_lookup ... ok
[INFO] [stdout] test comment_parser_tests::test_parentheticals ... ok
[INFO] [stdout] test grammar_tests::test_garden_path_2 ... ok
[INFO] [stdout] test formula_tests::test_unify_fail ... ok
[INFO] [stdout] test grammar_tests::test_parse_string ... ok
[INFO] [stdout] test grammar_tests::test_setvar_as_class ... ok
[INFO] [stdout] test parser_tests::test_segment_order ... ok
[INFO] [stdout] test parser_tests::test_invalid_c0 ... ok
[INFO] [stdout] test parser_tests::test_segref ... ok
[INFO] [stdout] test util_tests::test_fast_clear ... ok
[INFO] [stdout] test parser_tests::test_inclusion ... ok
[INFO] [stdout] test parser_tests::test_stref_v ... ok
[INFO] [stdout] test grammar_tests::test_unknown_token_2 ... ok
[INFO] [stdout] test parser_tests::test_invalid_del ... ok
[INFO] [stdout] test grammar_tests::test_unknown_token_1 ... ok
[INFO] [stdout] test util_tests::test_fast_extend ... ok
[INFO] [stdout] test parser_tests::test_valid_whitespace ... ok
[INFO] [stdout] test util_tests::test_copy_portion ... ok
[INFO] [stdout] test grammar_tests::test_garden_path_1 ... ok
[INFO] [stdout] test grammar_tests::test_garden_path_3 ... ok
[INFO] [stdout] test util_tests::test_ptr_eq ... ok
[INFO] [stdout] test util_tests::test_find_chapter ... ok
[INFO] [stdout] test util_tests::test_copy_portion_oob - should panic ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 45 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.10s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/metamath_knife-1a16c650977315ef)
[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]    Doc-tests metamath-knife
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test src/grammar.rs - grammar::StmtParse (line 1646) ... ok
[INFO] [stdout] test src/grammar.rs - grammar::Grammar (line 141) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.15s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "c60a99efbd6d6c93ed7b62c2d13b594d9dd1c12ceca73302585c2a72576848be", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "c60a99efbd6d6c93ed7b62c2d13b594d9dd1c12ceca73302585c2a72576848be", kill_on_drop: false }`
[INFO] [stdout] c60a99efbd6d6c93ed7b62c2d13b594d9dd1c12ceca73302585c2a72576848be