[INFO] fetching crate perl-content-length-framing 0.12.1...
[INFO] testing perl-content-length-framing-0.12.1 against master#562dee4820c458d823175268e41601d4c060588a for pr-154210-1
[INFO] extracting crate perl-content-length-framing 0.12.1 into /workspace/builds/worker-7-tc1/source
[INFO] started tweaking crates.io crate perl-content-length-framing 0.12.1
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate perl-content-length-framing 0.12.1
[INFO] tweaked toml for crates.io crate perl-content-length-framing 0.12.1 written to /workspace/builds/worker-7-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate perl-content-length-framing 0.12.1 on toolchain 562dee4820c458d823175268e41601d4c060588a
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate perl-content-length-framing 0.12.1 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" "+562dee4820c458d823175268e41601d4c060588a" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 2b33d2a8f976044368238cc5a7a4882779635a05417f16b1a600cfe17d1436e7
[INFO] running `Command { std: "docker" "start" "-a" "2b33d2a8f976044368238cc5a7a4882779635a05417f16b1a600cfe17d1436e7", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "2b33d2a8f976044368238cc5a7a4882779635a05417f16b1a600cfe17d1436e7", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "2b33d2a8f976044368238cc5a7a4882779635a05417f16b1a600cfe17d1436e7", kill_on_drop: false }`
[INFO] [stdout] 2b33d2a8f976044368238cc5a7a4882779635a05417f16b1a600cfe17d1436e7
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 715bcabd2e9d6b057b929fdb928d4dcdedaa10083d1367676576f345bdd0bb66
[INFO] running `Command { std: "docker" "start" "-a" "715bcabd2e9d6b057b929fdb928d4dcdedaa10083d1367676576f345bdd0bb66", kill_on_drop: false }`
[INFO] [stderr]    Compiling perl-content-length-framing v0.12.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 0.40s
[INFO] running `Command { std: "docker" "inspect" "715bcabd2e9d6b057b929fdb928d4dcdedaa10083d1367676576f345bdd0bb66", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "715bcabd2e9d6b057b929fdb928d4dcdedaa10083d1367676576f345bdd0bb66", kill_on_drop: false }`
[INFO] [stdout] 715bcabd2e9d6b057b929fdb928d4dcdedaa10083d1367676576f345bdd0bb66
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 765de16ead8ea3750a71a1c96e3fa9c70c068d08cb73569b8a119495fa3f93af
[INFO] running `Command { std: "docker" "start" "-a" "765de16ead8ea3750a71a1c96e3fa9c70c068d08cb73569b8a119495fa3f93af", kill_on_drop: false }`
[INFO] [stderr]    Compiling perl-content-length-framing v0.12.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 1.13s
[INFO] running `Command { std: "docker" "inspect" "765de16ead8ea3750a71a1c96e3fa9c70c068d08cb73569b8a119495fa3f93af", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "765de16ead8ea3750a71a1c96e3fa9c70c068d08cb73569b8a119495fa3f93af", kill_on_drop: false }`
[INFO] [stdout] 765de16ead8ea3750a71a1c96e3fa9c70c068d08cb73569b8a119495fa3f93af
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] c3079109aed05f00c869076a259a6d2a0d3f91b4f58d020c740b203c79e2d1bb
[INFO] running `Command { std: "docker" "start" "-a" "c3079109aed05f00c869076a259a6d2a0d3f91b4f58d020c740b203c79e2d1bb", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.02s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/perl_content_length_framing-90683c6cc071c978)
[INFO] [stdout] 
[INFO] [stdout] running 9 tests
[INFO] [stdout] test tests::extracts_multiple_frames_back_to_back ... ok
[INFO] [stdout] test tests::handles_split_header_and_body ... ok
[INFO] [stdout] test tests::rejects_missing_content_length ... ok
[INFO] [stdout] test tests::rejects_non_numeric_content_length ... ok
[INFO] [stdout] test tests::extracts_single_frame ... ok
[INFO] [stdout] test tests::drains_garbage_prefix_before_header ... ok
[INFO] [stdout] test tests::rejects_oversized_frame ... ok
[INFO] [stdout] test tests::supports_case_insensitive_header_name ... ok
[INFO] [stderr]      Running tests/comprehensive_unit_tests.rs (/opt/rustwide/target/debug/deps/comprehensive_unit_tests-a9a883770c3e5987)
[INFO] [stdout] test tests::rejects_invalid_utf8_in_header ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 9 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.05s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 56 tests
[INFO] [stdout] test accepts_additional_headers_alongside_content_length ... ok
[INFO] [stdout] test case_insensitive_header_name_lower ... ok
[INFO] [stdout] test case_insensitive_header_name_mixed ... ok
[INFO] [stdout] test case_insensitive_header_name_upper ... ok
[INFO] [stdout] test body_containing_content_length_header ... ok
[INFO] [stdout] test byte_at_a_time_delivery ... ok
[INFO] [stdout] test chunked_delivery_of_multiple_frames ... ok
[INFO] [stdout] test content_length_not_first_header ... ok
[INFO] [stdout] test debug_output_is_non_empty ... ok
[INFO] [stdout] test display_frame_too_large ... ok
[INFO] [stdout] test display_invalid_content_length ... ok
[INFO] [stdout] test display_invalid_header_utf8 ... ok
[INFO] [stdout] test display_missing_content_length ... ok
[INFO] [stdout] test display_invalid_header ... ok
[INFO] [stdout] test handles_utf8_body ... ok
[INFO] [stdout] test drains_garbage_prefix ... ok
[INFO] [stdout] test header_complete_but_body_incomplete_is_pending ... ok
[INFO] [stdout] test extracts_empty_body_frame ... ok
[INFO] [stdout] test extracts_single_frame ... ok
[INFO] [stdout] test frame_simple_json ... ok
[INFO] [stdout] test frame_at_exact_max_size ... ok
[INFO] [stdout] test extracts_multiple_frames_back_to_back ... ok
[INFO] [stdout] test frame_with_zero_content_length ... ok
[INFO] [stdout] test frame_large_body_roundtrip ... ok
[INFO] [stdout] test frame_binary_payload ... ok
[INFO] [stdout] test header_without_terminator_is_pending ... ok
[INFO] [stdout] test partial_body_then_rest ... ok
[INFO] [stdout] test interleaved_push_and_extract ... ok
[INFO] [stdout] test recovers_after_missing_content_length_error ... ok
[INFO] [stdout] test max_frame_size_is_16mb ... ok
[INFO] [stdout] test partial_sentinel_is_pending ... ok
[INFO] [stdout] test rejects_empty_content_length_value ... ok
[INFO] [stdout] test recovers_after_utf8_error ... ok
[INFO] [stdout] test new_and_default_are_equivalent ... ok
[INFO] [stdout] test many_frames_in_sequence ... ok
[INFO] [stdout] test rejects_floating_point_content_length ... ok
[INFO] [stdout] test rejects_frame_exactly_at_max_plus_one ... ok
[INFO] [stdout] test rejects_header_block_without_content_length_sentinel ... ok
[INFO] [stdout] test recovers_after_error ... ok
[INFO] [stdout] test no_space_after_colon ... ok
[INFO] [stdout] test empty_buffer_is_pending ... ok
[INFO] [stdout] test frame_roundtrip ... ok
[INFO] [stdout] test error_trait_is_implemented ... ok
[INFO] [stdout] test frame_empty_body ... ok
[INFO] [stdout] test rejects_invalid_utf8_in_header ... ok
[INFO] [stdout] test rejects_header_without_colon ... ok
[INFO] [stdout] test rejects_negative_content_length ... ok
[INFO] [stdout] test rejects_non_numeric_content_length ... ok
[INFO] [stdout] test rejects_oversized_frame ... ok
[INFO] [stdout] test split_within_crlf_sequence ... ok
[INFO] [stdout] test split_header_and_body ... ok
[INFO] [stdout] test split_within_header_sentinel ... ok
[INFO] [stdout] test trims_whitespace_around_content_length_value ... ok
[INFO] [stdout] test resync_trims_oversized_garbage ... ok
[INFO] [stdout] test clone_and_eq ... ok
[INFO] [stdout] test rejects_missing_content_length_header ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 56 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.04s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/extended_unit_tests.rs (/opt/rustwide/target/debug/deps/extended_unit_tests-2512b8c3833f35c2)
[INFO] [stdout] 
[INFO] [stdout] running 61 tests
[INFO] [stdout] test alternating_push_extract_pattern ... ok
[INFO] [stdout] test body_with_escaped_quotes ... ok
[INFO] [stdout] test body_with_only_whitespace ... ok
[INFO] [stdout] test content_length_exactly_matches_remaining_bytes ... ok
[INFO] [stdout] test content_length_one ... ok
[INFO] [stdout] test content_length_only_header ... ok
[INFO] [stdout] test content_length_with_leading_zeros ... ok
[INFO] [stdout] test deliver_and_extract_incrementally ... ok
[INFO] [stdout] test content_length_with_both_whitespace ... ok
[INFO] [stdout] test frame_all_nulls ... ok
[INFO] [stdout] test frame_all_ones ... ok
[INFO] [stdout] test frame_containing_content_length_substring ... ok
[INFO] [stdout] test extract_multiple_times_on_empty_buffer ... ok
[INFO] [stdout] test error_then_garbage_then_recovery ... ok
[INFO] [stdout] test body_with_only_binary ... ok
[INFO] [stdout] test clone_preserves_state ... ok
[INFO] [stdout] test content_length_with_trailing_whitespace ... ok
[INFO] [stdout] test content_length_zero ... ok
[INFO] [stdout] test body_with_json_containing_content_length ... ok
[INFO] [stdout] test five_small_frames_with_interleaved_extraction ... ok
[INFO] [stdout] test frame_with_embedded_crlf ... ok
[INFO] [stdout] test framing_error_clone_eq ... ok
[INFO] [stdout] test frame_with_embedded_newlines ... ok
[INFO] [stdout] test framing_error_is_error_trait ... ok
[INFO] [stdout] test framing_error_display_all_variants ... ok
[INFO] [stdout] test frame_with_body_longer_than_reported_consumed_correctly ... ok
[INFO] [stdout] test frame_single_byte_body ... ok
[INFO] [stdout] test header_with_many_extra_headers ... ok
[INFO] [stdout] test header_with_multiple_colons_in_value ... ok
[INFO] [stdout] test header_with_multiple_content_length_lines ... ok
[INFO] [stdout] test header_without_colon ... ok
[INFO] [stdout] test header_case_variations ... ok
[INFO] [stdout] test header_with_tabs_as_whitespace ... ok
[INFO] [stdout] test frame_containing_header_end_marker ... ok
[INFO] [stdout] test body_that_looks_like_valid_json ... ok
[INFO] [stdout] test default_creates_equivalent_to_new ... ok
[INFO] [stdout] test content_length_with_leading_whitespace ... ok
[INFO] [stdout] test large_body_with_repeating_pattern ... ok
[INFO] [stdout] test reject_blank_content_length ... ok
[INFO] [stdout] test recover_after_oversized_error ... ok
[INFO] [stdout] test reject_hex_prefix ... ok
[INFO] [stdout] test reject_negative_sign_prefix ... ok
[INFO] [stdout] test reject_scientific_notation ... ok
[INFO] [stdout] test multiple_errors_in_sequence_then_recovery ... ok
[INFO] [stdout] test one_byte_at_a_time_slow_delivery ... ok
[INFO] [stdout] test oversized_frame_error_has_correct_length_value ... ok
[INFO] [stdout] test push_empty_bytes_multiple_times ... ok
[INFO] [stdout] test push_many_then_extract_all ... ok
[INFO] [stdout] test push_partial_then_error_then_recover ... ok
[INFO] [stdout] test recover_after_invalid_content_length_error ... ok
[INFO] [stdout] test split_in_body_multiple_times ... ok
[INFO] [stdout] test medium_frame_half_megabyte ... ok
[INFO] [stdout] test resync_maintains_tail_bytes ... ok
[INFO] [stdout] test large_frame_one_megabyte ... ok
[INFO] [stdout] test recover_after_invalid_utf8_error ... ok
[INFO] [stdout] test recover_after_malformed_header_then_good_frame ... ok
[INFO] [stdout] test very_large_garbage_prefix_triggers_resync ... ok
[INFO] [stdout] test frame_max_minus_one_size ... ok
[INFO] [stdout] test huge_frame_ten_megabytes ... ok
[INFO] [stdout] test frame_exact_max_size ... ok
[INFO] [stdout] test three_frames_of_different_sizes ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 61 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.18s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests perl_content_length_framing
[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" "c3079109aed05f00c869076a259a6d2a0d3f91b4f58d020c740b203c79e2d1bb", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "c3079109aed05f00c869076a259a6d2a0d3f91b4f58d020c740b203c79e2d1bb", kill_on_drop: false }`
[INFO] [stdout] c3079109aed05f00c869076a259a6d2a0d3f91b4f58d020c740b203c79e2d1bb