[INFO] fetching crate poreader 1.1.1...
[INFO] testing poreader-1.1.1 against beta-2022-04-10 for beta-1.61-1
[INFO] extracting crate poreader 1.1.1 into /workspace/builds/worker-8/source
[INFO] validating manifest of crates.io crate poreader 1.1.1 on toolchain beta-2022-04-10
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+beta-2022-04-10" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking crates.io crate poreader 1.1.1
[INFO] finished tweaking crates.io crate poreader 1.1.1
[INFO] tweaked toml for crates.io crate poreader 1.1.1 written to /workspace/builds/worker-8/source/Cargo.toml
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+beta-2022-04-10" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+beta-2022-04-10" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-8/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-8/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:b0c94ce3c1162fcb8e57cac5b65ec2f72eabb1eebea4fcc35e269e823f681646" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2022-04-10" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 7655d690591f13e9a4fa30ba74e7af137b751a1eac6eb1e388592cea3a5a36d6
[INFO] running `Command { std: "docker" "start" "-a" "7655d690591f13e9a4fa30ba74e7af137b751a1eac6eb1e388592cea3a5a36d6", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "7655d690591f13e9a4fa30ba74e7af137b751a1eac6eb1e388592cea3a5a36d6", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "7655d690591f13e9a4fa30ba74e7af137b751a1eac6eb1e388592cea3a5a36d6", kill_on_drop: false }`
[INFO] [stdout] 7655d690591f13e9a4fa30ba74e7af137b751a1eac6eb1e388592cea3a5a36d6
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-8/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-8/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=warn" "-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:b0c94ce3c1162fcb8e57cac5b65ec2f72eabb1eebea4fcc35e269e823f681646" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2022-04-10" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] a97d721b5298e46c6323ed2691710b01093eeff19a83bb27679ff25f659877ae
[INFO] running `Command { std: "docker" "start" "-a" "a97d721b5298e46c6323ed2691710b01093eeff19a83bb27679ff25f659877ae", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]    Compiling unicode-xid v0.2.2
[INFO] [stderr]    Compiling dirs-sys-next v0.1.2
[INFO] [stderr]    Compiling regex v1.5.5
[INFO] [stderr]    Compiling string_cache v0.8.4
[INFO] [stderr]    Compiling dirs-next v2.0.0
[INFO] [stderr]    Compiling term v0.7.0
[INFO] [stderr]    Compiling ascii-canvas v3.0.0
[INFO] [stderr]    Compiling lalrpop-util v0.19.7
[INFO] [stderr]    Compiling locale_config v0.3.0
[INFO] [stderr]    Compiling lalrpop v0.19.7
[INFO] [stderr]    Compiling poreader v1.1.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 18.65s
[INFO] running `Command { std: "docker" "inspect" "a97d721b5298e46c6323ed2691710b01093eeff19a83bb27679ff25f659877ae", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "a97d721b5298e46c6323ed2691710b01093eeff19a83bb27679ff25f659877ae", kill_on_drop: false }`
[INFO] [stdout] a97d721b5298e46c6323ed2691710b01093eeff19a83bb27679ff25f659877ae
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-8/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-8/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=warn" "-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:b0c94ce3c1162fcb8e57cac5b65ec2f72eabb1eebea4fcc35e269e823f681646" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2022-04-10" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] d0a9270aea6d41b8da0363e9f8c59c0025e1ed0cb08e7cea1bf94442fd93aed0
[INFO] running `Command { std: "docker" "start" "-a" "d0a9270aea6d41b8da0363e9f8c59c0025e1ed0cb08e7cea1bf94442fd93aed0", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]    Compiling poreader v1.1.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 2.78s
[INFO] [stderr]   Executable unittests src/lib.rs (/opt/rustwide/target/debug/deps/poreader-4742b3d0f87ccf2a)
[INFO] [stderr]   Executable tests/integration_test.rs (/opt/rustwide/target/debug/deps/integration_test-c80df042081a7729)
[INFO] running `Command { std: "docker" "inspect" "d0a9270aea6d41b8da0363e9f8c59c0025e1ed0cb08e7cea1bf94442fd93aed0", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "d0a9270aea6d41b8da0363e9f8c59c0025e1ed0cb08e7cea1bf94442fd93aed0", kill_on_drop: false }`
[INFO] [stdout] d0a9270aea6d41b8da0363e9f8c59c0025e1ed0cb08e7cea1bf94442fd93aed0
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-8/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-8/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=warn" "-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:b0c94ce3c1162fcb8e57cac5b65ec2f72eabb1eebea4fcc35e269e823f681646" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2022-04-10" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 7c5f7327075286598852aaf8dd286b46baff5e222def10e14e11e06f502c8a12
[INFO] running `Command { std: "docker" "start" "-a" "7c5f7327075286598852aaf8dd286b46baff5e222def10e14e11e06f502c8a12", kill_on_drop: false }`
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.03s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/poreader-4742b3d0f87ccf2a)
[INFO] [stdout] running 112 tests
[INFO] [stdout] test comment::tests::test_func_kind ... ok
[INFO] [stdout] test comment::tests::test_func_content ... ok
[INFO] [stdout] test comment::tests::test_func_new ... ok
[INFO] [stdout] test enums::message::tests::test_func_get_plural_id ... ok
[INFO] [stdout] test enums::message::tests::test_func_get_id ... ok
[INFO] [stdout] test enums::message::tests::test_func_get_plural_text ... ok
[INFO] [stdout] test enums::message::tests::test_enum ... ok
[INFO] [stdout] test enums::message::tests::test_func_get_text ... ok
[INFO] [stdout] test enums::message::tests::test_func_is_blank ... ok
[INFO] [stdout] test enums::message::tests::test_func_is_empty ... ok
[INFO] [stdout] test enums::message::tests::test_func_is_plural ... ok
[INFO] [stdout] test enums::message::tests::test_func_is_simple ... ok
[INFO] [stdout] test enums::message::tests::test_func_plural ... ok
[INFO] [stdout] test enums::message::tests::test_trait_default ... ok
[INFO] [stdout] test enums::origin::tests::test_enum ... ok
[INFO] [stdout] test enums::origin::tests::test_hash ... ok
[INFO] [stdout] test enums::state::tests::test_default ... ok
[INFO] [stdout] test enums::state::tests::test_enum ... ok
[INFO] [stdout] test enums::state::tests::test_hash ... ok
[INFO] [stdout] test error::tests::test_func_source ... ok
[INFO] [stdout] test error::tests::test_format ... ok
[INFO] [stdout] test error::tests::test_trait_debug ... ok
[INFO] [stdout] test error::tests::test_eq ... ok
[INFO] [stdout] test error::tests::test_trait_display ... ok
[INFO] [stdout] test error::tests::test_trait_from ... ok
[INFO] [stdout] test note::tests::test_func_origin ... ok
[INFO] [stdout] test note::tests::test_struct ... ok
[INFO] [stdout] test note::tests::test_func_value ... ok
[INFO] [stdout] test plural::formula::node::tests::execute_nodes ... ok
[INFO] [stdout] test plural::formula::node::tests::test_func_bool_to_num ... ok
[INFO] [stdout] test plural::formula::node::tests::test_enum_unop ... ok
[INFO] [stdout] test plural::formula::node::tests::test_enum_node ... ok
[INFO] [stdout] test plural::formula::node::tests::test_enum_binop ... ok
[INFO] [stdout] test plural::formula::node::tests::test_func_get_infinity ... ok
[INFO] [stdout] test plural::formula::tests::test_struct_formula ... ok
[INFO] [stdout] test po::line::tests::test_default ... ok
[INFO] [stdout] test po::line::tests::test_enum ... ok
[INFO] [stdout] test po::message_extractor::tests::test_func_new_previous ... ok
[INFO] [stdout] test po::message_extractor::tests::test_func_new_message_for_error_from_none ... ok
[INFO] [stdout] test po::line::tests::test_func_line ... ok
[INFO] [stdout] test po::message_extractor::tests::test_func_new_message_for_singular ... ok
[INFO] [stdout] test po::message_extractor::tests::test_func_new_message_for_error_from_singular ... ok
[INFO] [stdout] test po::message_extractor::tests::test_func_expected ... ok
[INFO] [stdout] test po::message_extractor::tests::test_func_parse_message_fields_singular ... ok
[INFO] [stdout] test po::message_extractor::tests::test_func_new_message_for_error_from_plural_on_expected ... ok
[INFO] [stdout] test po::message_extractor::tests::test_func_new_message_for_no_plural_forms ... ok
[INFO] [stdout] test po::message_extractor::tests::test_func_parse_msg ... ok
[INFO] [stdout] test unit::tests::test_func_is_translated ... ok
[INFO] [stdout] test unit::tests::test_func_comments ... ok
[INFO] [stdout] test unit::tests::test_func_context ... ok
[INFO] [stdout] test unit::tests::test_func_locations ... ok
[INFO] [stdout] test unit::tests::test_func_message ... ok
[INFO] [stdout] test unit::tests::test_func_prev_context ... ok
[INFO] [stdout] test unit::tests::test_func_prev_message ... ok
[INFO] [stdout] test unit::tests::test_func_state ... ok
[INFO] [stdout] test unit::tests::test_trait_debug ... ok
[INFO] [stdout] test unit::tests::test_trait_default ... ok
[INFO] [stdout] test unit::tests::test_trait_clone ... ok
[INFO] [stdout] test unit::tests::test_func_notes ... ok
[INFO] [stdout] test unit::tests::test_func_flags ... ok
[INFO] [stdout] test plural::forms::tests::test_erro_parse_on_nplurals ... ok
[INFO] [stdout] test unit::tests::test_func_is_obsolete ... ok
[INFO] [stdout] test po::unescape::tests::test_func_replace_char ... ok
[INFO] [stdout] test po::reader::tests::test_func_read_line ... ok
[INFO] [stdout] test po::unescape::tests::test_func_unescape ... ok
[INFO] [stdout] test po::line_iter::tests::blank ... ok
[INFO] [stdout] test plural::forms::tests::test_func_get_value ... ok
[INFO] [stdout] test po::reader::tests::test_trait_iterator_with_error ... ok
[INFO] [stdout] test po::decoder::tests::test_func_fetch_next ... ok
[INFO] [stdout] test po::reader::tests::test_func_parse_comments ... ok
[INFO] [stdout] test plural::forms::tests::test_forms ... ok
[INFO] [stdout] test po::reader::tests::test_func_new_with_error_on_plural ... ok
[INFO] [stdout] test po::reader::tests::test_func_read_unit_normal ... ok
[INFO] [stdout] test po::line_iter::tests::two_strict_lines ... ok
[INFO] [stdout] test po::parser::tests::test_func_parse_line ... ok
[INFO] [stdout] test po::line_iter::tests::empty ... ok
[INFO] [stdout] test po::reader::tests::test_func_read_unit_with_exceptions ... ok
[INFO] [stdout] test po::decoder::tests::test_func_expected ... ok
[INFO] [stdout] test po::reader::tests::test_func_next_unit ... ok
[INFO] [stdout] test po::reader::tests::test_func_new_normal ... ok
[INFO] [stdout] test po::reader::tests::test_trait_iterator_normal ... ok
[INFO] [stdout] test po::parser::tests::test_func_parse_map ... ok
[INFO] [stdout] test po::reader::tests::test_func_parse_unit ... ok
[INFO] [stdout] test plural::forms::tests::test_func_get_definition ... ok
[INFO] [stdout] test po::line_iter::tests::two_normal_lines ... ok
[INFO] [stdout] test po::reader::tests::test_func_new_with_error_on_entry ... ok
[INFO] [stdout] test plural::plural::tests::test_func_new ... ok
[INFO] [stdout] test plural::forms::tests::test_func_get_formula ... ok
[INFO] [stdout] test po::line_iter::tests::with_error ... ok
[INFO] [stdout] test po::decoder::tests::test_func_parse_msg ... ok
[INFO] [stdout] test plural::plural::tests::test_func_get ... ok
[INFO] [stdout] test plural::plural::tests::test_func_plural ... ok
[INFO] [stdout] test plural::plural::tests::test_func_is_blank ... ok
[INFO] [stdout] test plural::forms::tests::test_func_get_count ... ok
[INFO] [stdout] test plural::plural::tests::test_func_first ... ok
[INFO] [stdout] test plural::forms::tests::test_struct ... ok
[INFO] [stdout] test plural::plural::tests::test_func_singular ... ok
[INFO] [stdout] test po::message_extractor::tests::test_func_plural_forms ... ok
[INFO] [stdout] test po::reader::tests::test_func_parse_po_header_normal ... ok
[INFO] [stdout] test po::reader::tests::test_func_parse_po_header_with_bad_language ... ok
[INFO] [stdout] test plural::plural::tests::test_func_values ... ok
[INFO] [stdout] test plural::plural::tests::test_func_get_forms ... ok
[INFO] [stdout] test po::message_extractor::tests::test_func_parse_message_fields_plural ... ok
[INFO] [stdout] test po::message_extractor::tests::test_func_new_message_for_plural ... ok
[INFO] [stdout] test po::message_extractor::tests::test_func_parse_message_fields_with_errors_on_new_message ... ok
[INFO] [stdout] test po::reader::tests::test_func_parse_po_header_with_error ... ok
[INFO] [stdout] test po::message_extractor::tests::test_func_parse_message_fields_with_errors_on_expected ... ok
[INFO] [stdout] test po::message_extractor::tests::test_func_parse_message_fields_returns_none ... ok
[INFO] [stdout] test po::message_extractor::tests::test_func_new_message_for_error_from_plural_on_parse ... ok
[INFO] [stdout] test po::message_extractor::tests::test_func_parse_message_fields_for_empty_messages ... ok
[INFO] [stdout] test po::message_extractor::tests::test_func_parse_message_fields_with_errors_on_parse_msg ... ok
[INFO] [stdout] test plural::formula::tests::formula ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 112 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.36s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/integration_test.rs (/opt/rustwide/target/debug/deps/integration_test-c80df042081a7729)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stderr]    Doc-tests poreader
[INFO] [stdout] test integration_test ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test src/lib.rs - (line 17) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.08s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "7c5f7327075286598852aaf8dd286b46baff5e222def10e14e11e06f502c8a12", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "7c5f7327075286598852aaf8dd286b46baff5e222def10e14e11e06f502c8a12", kill_on_drop: false }`
[INFO] [stdout] 7c5f7327075286598852aaf8dd286b46baff5e222def10e14e11e06f502c8a12