[INFO] fetching crate refix-message 0.2.0...
[INFO] testing refix-message-0.2.0 against 1.99.0-beta.8 for beta-1.100-2
[INFO] extracting crate refix-message 0.2.0 into /workspace/builds/worker-4-tc1/source
[INFO] started tweaking crates.io crate refix-message 0.2.0
[INFO] finished tweaking crates.io crate refix-message 0.2.0
[INFO] tweaked toml for crates.io crate refix-message 0.2.0 written to /workspace/builds/worker-4-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate refix-message 0.2.0 on toolchain 1.99.0-beta.8
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.99.0-beta.8" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate refix-message 0.2.0 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" "+1.99.0-beta.8" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/target:/opt/rustwide/target:rw,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" "-m" "1610612736" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:3111399a4047eeb3a02b7a90e478d715f38a8c6669b5c4b49d30a17385265909" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] a0ca8cce4f81aa3cd47babdcffbecbc37791f4f620d2175793ca9e5ff44a5b7e
[INFO] running `Command { std: "docker" "start" "a0ca8cce4f81aa3cd47babdcffbecbc37791f4f620d2175793ca9e5ff44a5b7e", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "a0ca8cce4f81aa3cd47babdcffbecbc37791f4f620d2175793ca9e5ff44a5b7e", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-w" "/opt/rustwide/workdir" "--user" "0:0" "a0ca8cce4f81aa3cd47babdcffbecbc37791f4f620d2175793ca9e5ff44a5b7e" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "a0ca8cce4f81aa3cd47babdcffbecbc37791f4f620d2175793ca9e5ff44a5b7e", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-w" "/opt/rustwide/workdir" "--user" "0:0" "a0ca8cce4f81aa3cd47babdcffbecbc37791f4f620d2175793ca9e5ff44a5b7e" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling refix-message v0.2.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 0.39s
[INFO] running `Command { std: "docker" "inspect" "a0ca8cce4f81aa3cd47babdcffbecbc37791f4f620d2175793ca9e5ff44a5b7e", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-w" "/opt/rustwide/workdir" "--user" "0:0" "a0ca8cce4f81aa3cd47babdcffbecbc37791f4f620d2175793ca9e5ff44a5b7e" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling refix-message v0.2.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.78s
[INFO] running `Command { std: "docker" "inspect" "a0ca8cce4f81aa3cd47babdcffbecbc37791f4f620d2175793ca9e5ff44a5b7e", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-w" "/opt/rustwide/workdir" "--user" "0:0" "a0ca8cce4f81aa3cd47babdcffbecbc37791f4f620d2175793ca9e5ff44a5b7e" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "test" "--frozen", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.03s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/refix_message-c7cac0af7a3d26f3)
[INFO] [stdout] 
[INFO] [stdout] running 73 tests
[INFO] [stdout] test framing::tests::post_structural::checksum_mismatch ... ok
[INFO] [stdout] test framing::tests::post_structural::empty_first_field ... ok
[INFO] [stdout] test framing::tests::chunking::garbage_before_frame_is_chunk_invariant ... ok
[INFO] [stdout] test framing::tests::incomplete::lone_8 ... ok
[INFO] [stdout] test framing::tests::incomplete::begin_string_without_soh ... ok
[INFO] [stdout] test framing::tests::happy_path::valid_frame_scans ... ok
[INFO] [stdout] test framing::tests::preamble::msg_type ... ok
[INFO] [stdout] test framing::tests::post_structural::missing_msg_type ... ok
[INFO] [stdout] test framing::tests::preamble::msg_type_read_regardless_of_trailing_fields ... ok
[INFO] [stdout] test framing::tests::resync::finds_needle_after_offset_zero ... ok
[INFO] [stdout] test framing::tests::resync::no_match_skips_whole_buffer ... ok
[INFO] [stdout] test framing::tests::post_structural::malformed_checksum_non_digit ... ok
[INFO] [stdout] test framing::tests::incomplete::empty ... ok
[INFO] [stdout] test framing::tests::post_structural::skip_does_not_resync_into_body ... ok
[INFO] [stdout] test framing::tests::structural::body_length_mismatch ... ok
[INFO] [stdout] test framing::tests::structural::frame_too_large_rejected_before_waiting ... ok
[INFO] [stdout] test framing::tests::resync::preserves_longest_partial_tail ... ok
[INFO] [stdout] test framing::tests::structural::malformed_body_length_non_digit ... ok
[INFO] [stdout] test framing::tests::structural::malformed_begin_string_overruns_bound ... ok
[INFO] [stdout] test framing::tests::structural::malformed_body_length_overruns_cap ... ok
[INFO] [stdout] test framing::tests::structural::resync_lands_on_next_message ... ok
[INFO] [stdout] test framing::tests::preamble::unknown_begin_string_is_other ... ok
[INFO] [stdout] test framing::tests::resync::preserves_short_partial_tail ... ok
[INFO] [stdout] test length_tags::tests::standard_is_sorted_and_unique ... ok
[INFO] [stdout] test framing::tests::incomplete::body_not_yet_arrived ... ok
[INFO] [stdout] test message::tests::get_absent_tag ... ok
[INFO] [stdout] test framing::tests::structural::missing_begin_string ... ok
[INFO] [stdout] test framing::tests::structural::missing_body_length ... ok
[INFO] [stdout] test stream::tests::empty_stream_is_incomplete ... ok
[INFO] [stdout] test framing::tests::structural::non_fix_prefix_is_missing_begin_string ... ok
[INFO] [stdout] test framing::tests::chunking::clean_stream_is_chunk_invariant ... ok
[INFO] [stdout] test framing::tests::truncation::every_prefix_of_a_valid_frame_is_incomplete ... ok
[INFO] [stdout] test stream::tests::leading_garbage_is_skipped ... ok
[INFO] [stdout] test stream::tests::single_message ... ok
[INFO] [stdout] test tokenizer::tests::data_fields::data_value_last_in_body ... ok
[INFO] [stdout] test framing::tests::preamble::fixt_begin_string ... ok
[INFO] [stdout] test tokenizer::tests::data_fields::dialect_extra_delimits_data ... ok
[INFO] [stdout] test message::tests::get_returns_first_occurrence ... ok
[INFO] [stdout] test stream::tests::message_completes_across_feeds_at_every_split ... ok
[INFO] [stdout] test stream::tests::several_messages_in_one_feed ... ok
[INFO] [stdout] test stream::tests::dialect_extras_flow_through ... ok
[INFO] [stdout] test tokenizer::tests::data_fields::standard_set_survives_extras ... ok
[INFO] [stdout] test stream::tests::oversized_frame_is_skipped_not_fatal ... ok
[INFO] [stdout] test tokenizer::tests::data_fields::extra_below_the_standard_minimum ... ok
[INFO] [stdout] test framing::tests::preamble::begin_string_and_body_length ... ok
[INFO] [stdout] test tokenizer::tests::data_fields::non_numeric_length_falls_back_to_scanning ... ok
[INFO] [stdout] test tokenizer::tests::data_fields::overrunning_length_is_distrusted ... ok
[INFO] [stdout] test tokenizer::tests::data_fields::short_length_surfaces_junk_after_the_data ... ok
[INFO] [stdout] test tokenizer::tests::data_fields::tag_zero_extra_is_ignored ... ok
[INFO] [stdout] test tokenizer::tests::data_fields::value_with_embedded_soh ... ok
[INFO] [stdout] test stream::tests::garbage_between_messages ... ok
[INFO] [stdout] test tokenizer::tests::errors::truncated_frame_is_incomplete ... ok
[INFO] [stdout] test tokenizer::tests::errors::absurd_body_length_is_too_large_to_index ... ok
[INFO] [stdout] test stream::tests::garbled_frame_does_not_stall_the_stream ... ok
[INFO] [stdout] test framing::tests::resync::skip_is_always_in_range ... ok
[INFO] [stdout] test tokenizer::tests::sentinel_runs::consecutive_runs ... ok
[INFO] [stdout] test tokenizer::tests::errors::two_messages_are_trailing_bytes ... ok
[INFO] [stdout] test tokenizer::tests::sentinel_runs::empty_tag ... ok
[INFO] [stdout] test tokenizer::tests::sentinel_runs::literal_tag_zero_is_a_sentinel ... ok
[INFO] [stdout] test tokenizer::tests::data_fields::value_with_several_sohs ... ok
[INFO] [stdout] test tokenizer::tests::sentinel_runs::fields_after_a_fault_remain_readable ... ok
[INFO] [stdout] test tokenizer::tests::sentinel_runs::run_without_equals ... ok
[INFO] [stdout] test tokenizer::tests::data_fields::zero_length_value ... ok
[INFO] [stdout] test tokenizer::tests::sentinel_runs::non_numeric_tag ... ok
[INFO] [stdout] test tokenizer::tests::sentinel_runs::tag_overflowing_u32 ... ok
[INFO] [stdout] test tokenizer::tests::well_formed::duplicate_tags_all_indexed ... ok
[INFO] [stdout] test tokenizer::tests::well_formed::empty_value ... ok
[INFO] [stdout] test tokenizer::tests::well_formed::preamble_and_trailer_are_ordinary_fields ... ok
[INFO] [stdout] test tokenizer::tests::well_formed::valid_message ... ok
[INFO] [stdout] test tokenizer::tests::well_formed::value_containing_equals ... ok
[INFO] [stdout] test framing::tests::resync::finds_needle_at_offset_one ... ok
[INFO] [stdout] test framing::tests::structural::max_digit_body_length_does_not_panic ... ok
[INFO] [stdout] test framing::tests::post_structural::missing_msg_type_on_empty_body ... ok
[INFO] [stderr]    Doc-tests refix_message
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 73 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.14s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test src/framing.rs - framing (line 12) ... 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] all doctests ran in 0.46s; merged doctests compilation took 0.44s
[INFO] running `Command { std: "docker" "inspect" "a0ca8cce4f81aa3cd47babdcffbecbc37791f4f620d2175793ca9e5ff44a5b7e", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "a0ca8cce4f81aa3cd47babdcffbecbc37791f4f620d2175793ca9e5ff44a5b7e", kill_on_drop: false }`
[INFO] [stdout] a0ca8cce4f81aa3cd47babdcffbecbc37791f4f620d2175793ca9e5ff44a5b7e
