[INFO] crate pvm 0.1.0 is already in cache
[INFO] extracting crate pvm 0.1.0 into work/ex/pr-57967/sources/master#c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54/reg/pvm/0.1.0
[INFO] extracting crate pvm 0.1.0 into work/ex/pr-57967/sources/try#7fea0229842e79db1159ea58ad9a653b288efee9/reg/pvm/0.1.0
[INFO] validating manifest of pvm-0.1.0 on toolchain master#c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54
[INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54-alt" "read-manifest" "--manifest-path" "Cargo.toml"`
[INFO] validating manifest of pvm-0.1.0 on toolchain try#7fea0229842e79db1159ea58ad9a653b288efee9
[INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+7fea0229842e79db1159ea58ad9a653b288efee9-alt" "read-manifest" "--manifest-path" "Cargo.toml"`
[INFO] started frobbing pvm-0.1.0
[INFO] finished frobbing pvm-0.1.0
[INFO] frobbed toml for pvm-0.1.0 written to work/ex/pr-57967/sources/master#c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54/reg/pvm/0.1.0/Cargo.toml
[INFO] started frobbing pvm-0.1.0
[INFO] finished frobbing pvm-0.1.0
[INFO] frobbed toml for pvm-0.1.0 written to work/ex/pr-57967/sources/try#7fea0229842e79db1159ea58ad9a653b288efee9/reg/pvm/0.1.0/Cargo.toml
[INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54-alt" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update"`
[INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+7fea0229842e79db1159ea58ad9a653b288efee9-alt" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update"`
[INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54-alt" "fetch" "--locked" "--manifest-path" "Cargo.toml"`
[INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+7fea0229842e79db1159ea58ad9a653b288efee9-alt" "fetch" "--locked" "--manifest-path" "Cargo.toml"`
[INFO] testing pvm-0.1.0 against try#7fea0229842e79db1159ea58ad9a653b288efee9 for pr-57967
[INFO] running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-57967/worker-3/try#7fea0229842e79db1159ea58ad9a653b288efee9:/opt/crater/target:rw,Z" "-v" "/mnt/big/crater/work/ex/pr-57967/sources/try#7fea0229842e79db1159ea58ad9a653b288efee9/reg/pvm/0.1.0:/opt/crater/workdir:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/opt/crater/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/opt/crater/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/opt/crater/workdir" "-e" "MAP_USER_ID=1000" "-e" "CARGO_TARGET_DIR=/opt/crater/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/crater/cargo-home" "-e" "RUSTUP_HOME=/opt/crater/rustup-home" "-w" "/opt/crater/workdir" "-m" "1536M" "--network" "none" "rustops/crates-build-env" "/opt/crater/cargo-home/bin/cargo" "+7fea0229842e79db1159ea58ad9a653b288efee9-alt" "build" "--frozen"`
[INFO] [stdout] 0f5f5f7502820f6d2a0a4de1f696ff291f83d95d9e4eaf36c1d48353dca507de
[INFO] running `"docker" "start" "-a" "0f5f5f7502820f6d2a0a4de1f696ff291f83d95d9e4eaf36c1d48353dca507de"`
[INFO] [stderr]    Compiling pvm v0.1.0 (/opt/crater/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1.67s
[INFO] running `"docker" "inspect" "0f5f5f7502820f6d2a0a4de1f696ff291f83d95d9e4eaf36c1d48353dca507de"`
[INFO] running `"docker" "rm" "-f" "0f5f5f7502820f6d2a0a4de1f696ff291f83d95d9e4eaf36c1d48353dca507de"`
[INFO] [stdout] 0f5f5f7502820f6d2a0a4de1f696ff291f83d95d9e4eaf36c1d48353dca507de
[INFO] running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-57967/worker-3/try#7fea0229842e79db1159ea58ad9a653b288efee9:/opt/crater/target:rw,Z" "-v" "/mnt/big/crater/work/ex/pr-57967/sources/try#7fea0229842e79db1159ea58ad9a653b288efee9/reg/pvm/0.1.0:/opt/crater/workdir:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/opt/crater/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/opt/crater/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/opt/crater/workdir" "-e" "MAP_USER_ID=1000" "-e" "CARGO_TARGET_DIR=/opt/crater/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/crater/cargo-home" "-e" "RUSTUP_HOME=/opt/crater/rustup-home" "-w" "/opt/crater/workdir" "-m" "1536M" "--network" "none" "rustops/crates-build-env" "/opt/crater/cargo-home/bin/cargo" "+7fea0229842e79db1159ea58ad9a653b288efee9-alt" "test" "--frozen" "--no-run"`
[INFO] [stdout] 404ff836c84b2a2feba8b0ef1b90df0f5c4dd27393e26a74c838ae428b18a568
[INFO] running `"docker" "start" "-a" "404ff836c84b2a2feba8b0ef1b90df0f5c4dd27393e26a74c838ae428b18a568"`
[INFO] [stderr]    Compiling pvm v0.1.0 (/opt/crater/workdir)
[INFO] [stderr] warning: unused import: `std::io`
[INFO] [stderr]  --> tests/calculator1.rs:5:5
[INFO] [stderr]   |
[INFO] [stderr] 5 | use std::io;
[INFO] [stderr]   |     ^^^^^^^
[INFO] [stderr]   |
[INFO] [stderr]   = note: #[warn(unused_imports)] on by default
[INFO] [stderr] 
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 4.00s
[INFO] running `"docker" "inspect" "404ff836c84b2a2feba8b0ef1b90df0f5c4dd27393e26a74c838ae428b18a568"`
[INFO] running `"docker" "rm" "-f" "404ff836c84b2a2feba8b0ef1b90df0f5c4dd27393e26a74c838ae428b18a568"`
[INFO] [stdout] 404ff836c84b2a2feba8b0ef1b90df0f5c4dd27393e26a74c838ae428b18a568
[INFO] running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-57967/worker-3/try#7fea0229842e79db1159ea58ad9a653b288efee9:/opt/crater/target:rw,Z" "-v" "/mnt/big/crater/work/ex/pr-57967/sources/try#7fea0229842e79db1159ea58ad9a653b288efee9/reg/pvm/0.1.0:/opt/crater/workdir:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/opt/crater/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/opt/crater/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/opt/crater/workdir" "-e" "MAP_USER_ID=1000" "-e" "CARGO_TARGET_DIR=/opt/crater/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/crater/cargo-home" "-e" "RUSTUP_HOME=/opt/crater/rustup-home" "-w" "/opt/crater/workdir" "-m" "1536M" "--network" "none" "rustops/crates-build-env" "/opt/crater/cargo-home/bin/cargo" "+7fea0229842e79db1159ea58ad9a653b288efee9-alt" "test" "--frozen"`
[INFO] [stdout] 914808f6eeb537a3c87297c5faeb1c3be0ab7bbed1969fa0e84eedc1a2170a2d
[INFO] running `"docker" "start" "-a" "914808f6eeb537a3c87297c5faeb1c3be0ab7bbed1969fa0e84eedc1a2170a2d"`
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.03s
[INFO] [stderr]      Running /opt/crater/target/debug/deps/pvm-e7e6a4258dbd9f34
[INFO] [stdout] 
[INFO] [stdout] running 34 tests
[INFO] [stdout] test ast::tests::simple_iteration_grammar ... ok
[INFO] [stdout] test ast::tests::simple_char_grammar_rules ... ok
[INFO] [stdout] test ast::tests::simple_optional_subparser ... ok
[INFO] [stdout] test machine::tests::char_range_links ... ok
[INFO] [stdout] test machine::tests::direct_left_recursion ... ok
[INFO] [stdout] test machine::tests::optional_predicate ... ok
[INFO] [stdout] test machine::tests::partial_commit_zero_or_more ... ok
[INFO] [stdout] test machine::tests::ambersand_predicate ... ok
[INFO] [stdout] test machine::tests::one_or_more_chars ... ok
[INFO] [stdout] test machine::tests::not_predicate ... ok
[INFO] [stdout] test ast::tests::simple_lookahead_grammar ... ok
[INFO] [stdout] test machine::tests::self_reference_impossible_parser ... ok
[INFO] [stdout] test machine::tests::simple_char_choice ... ok
[INFO] [stdout] test machine::tests::simple_char_range ... ok
[INFO] [stdout] test machine::tests::direct_left_recursion_with_tail ... ok
[INFO] [stdout] test machine::tests::simple_char ... ok
[INFO] [stdout] test machine::tests::simple_token_stream_result ... ok
[INFO] [stdout] test machine::tests::simple_subparser ... ok
[INFO] [stdout] test machine::tests::simple_char_sequence ... ok
[INFO] [stdout] test parser::tests::indirect_left_recursion ... ok
[INFO] [stdout] test parser::tests::parenthetical_and_lookahead ... ok
[INFO] [stdout] test parser::tests::simple_char_grammar ... ok
[INFO] [stdout] test parser::tests::simple_choice_grammar ... ok
[INFO] [stdout] test parser::tests::simple_parentheticals ... ok
[INFO] [stdout] test machine::tests::skip_parser ... ok
[INFO] [stdout] test parser::tests::tokenizer ... ok
[INFO] [stdout] test machine::tests::skip_parser_with_toggle ... ok
[INFO] [stdout] test machine::tests::three_subparser ... ok
[INFO] [stdout] test parser::tests::direct_left_recursion ... ok
[INFO] [stdout] test machine::tests::zero_or_more_chars ... ok
[INFO] [stdout] test parser::tests::simple_suffix_grammar ... ok
[INFO] [stdout] test parser::tests::simple_prefix_grammar ... ok
[INFO] [stdout] test tests::add_multiply_variable_expression ... ok
[INFO] [stdout] test parser::tests::dogfood ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 34 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
[INFO] [stdout] 
[INFO] [stderr]      Running /opt/crater/target/debug/deps/calculator1-433825ff71016a77
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test constants ... ok
[INFO] [stdout] test simple_expressions ... ok
[INFO] [stdout] test random_expressions ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
[INFO] [stdout] 
[INFO] [stderr]      Running /opt/crater/target/debug/deps/simple-8ff4ece40303ab3a
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test simple ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests pvm
[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
[INFO] [stdout] 
[INFO] running `"docker" "inspect" "914808f6eeb537a3c87297c5faeb1c3be0ab7bbed1969fa0e84eedc1a2170a2d"`
[INFO] running `"docker" "rm" "-f" "914808f6eeb537a3c87297c5faeb1c3be0ab7bbed1969fa0e84eedc1a2170a2d"`
[INFO] [stdout] 914808f6eeb537a3c87297c5faeb1c3be0ab7bbed1969fa0e84eedc1a2170a2d