[INFO] fetching crate refix-message 0.2.0...
[INFO] testing refix-message-0.2.0 against 1.100.0-beta.1 for beta-1.100-2
[INFO] extracting crate refix-message 0.2.0 into /workspace/builds/worker-4-tc2/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-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate refix-message 0.2.0 on toolchain 1.100.0-beta.1
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.100.0-beta.1" "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.100.0-beta.1" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr] warning: `package.homepage` is redundant with `package.repository`
[INFO] [stderr]   --> Cargo.toml:19:12
[INFO] [stderr]    |
[INFO] [stderr] 19 | homepage = "https://github.com/refixhq/refix"
[INFO] [stderr]    |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] ...
[INFO] [stderr] 24 | repository = "https://github.com/refixhq/refix"
[INFO] [stderr]    |              ----------------------------------
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::redundant_homepage` is set to `warn` by default
[INFO] [stderr] help: consider removing `package.homepage`
[INFO] [stderr] warning: `refix-message` (manifest) generated 1 warning
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/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] e69e2d5f3caaf7fca6717748a6e05af3876e8bcfa17f3e981ea3c3bb15398f45
[INFO] running `Command { std: "docker" "start" "e69e2d5f3caaf7fca6717748a6e05af3876e8bcfa17f3e981ea3c3bb15398f45", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "e69e2d5f3caaf7fca6717748a6e05af3876e8bcfa17f3e981ea3c3bb15398f45", 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" "e69e2d5f3caaf7fca6717748a6e05af3876e8bcfa17f3e981ea3c3bb15398f45" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "e69e2d5f3caaf7fca6717748a6e05af3876e8bcfa17f3e981ea3c3bb15398f45", 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" "e69e2d5f3caaf7fca6717748a6e05af3876e8bcfa17f3e981ea3c3bb15398f45" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr] warning: `package.homepage` is redundant with `package.repository`
[INFO] [stderr]   --> Cargo.toml:19:12
[INFO] [stderr]    |
[INFO] [stderr] 19 | homepage = "https://github.com/refixhq/refix"
[INFO] [stderr]    |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] ...
[INFO] [stderr] 24 | repository = "https://github.com/refixhq/refix"
[INFO] [stderr]    |              ----------------------------------
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::redundant_homepage` is set to `warn` by default
[INFO] [stderr] help: consider removing `package.homepage`
[INFO] [stderr] warning: `refix-message` (manifest) generated 1 warning
[INFO] [stderr]    Compiling refix-message v0.2.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 0.32s
[INFO] running `Command { std: "docker" "inspect" "e69e2d5f3caaf7fca6717748a6e05af3876e8bcfa17f3e981ea3c3bb15398f45", 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" "e69e2d5f3caaf7fca6717748a6e05af3876e8bcfa17f3e981ea3c3bb15398f45" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr] warning: `package.homepage` is redundant with `package.repository`
[INFO] [stderr]   --> Cargo.toml:19:12
[INFO] [stderr]    |
[INFO] [stderr] 19 | homepage = "https://github.com/refixhq/refix"
[INFO] [stderr]    |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] ...
[INFO] [stderr] 24 | repository = "https://github.com/refixhq/refix"
[INFO] [stderr]    |              ----------------------------------
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::redundant_homepage` is set to `warn` by default
[INFO] [stderr] help: consider removing `package.homepage`
[INFO] [stderr] warning: `refix-message` (manifest) generated 1 warning
[INFO] [stderr]    Compiling refix-message v0.2.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.82s
[INFO] running `Command { std: "docker" "inspect" "e69e2d5f3caaf7fca6717748a6e05af3876e8bcfa17f3e981ea3c3bb15398f45", 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" "e69e2d5f3caaf7fca6717748a6e05af3876e8bcfa17f3e981ea3c3bb15398f45" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen", kill_on_drop: false }`
[INFO] [stderr] warning: `package.homepage` is redundant with `package.repository`
[INFO] [stderr]   --> Cargo.toml:19:12
[INFO] [stderr]    |
[INFO] [stderr] 19 | homepage = "https://github.com/refixhq/refix"
[INFO] [stderr]    |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] ...
[INFO] [stderr] 24 | repository = "https://github.com/refixhq/refix"
[INFO] [stderr]    |              ----------------------------------
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::redundant_homepage` is set to `warn` by default
[INFO] [stderr] help: consider removing `package.homepage`
[INFO] [stderr] warning: `refix-message` (manifest) generated 1 warning
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.02s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/build/refix-message/c7cac0af7a3d26f3/out/refix_message-c7cac0af7a3d26f3)
[INFO] [stdout] 
[INFO] [stdout] running 73 tests
[INFO] [stdout] test framing::tests::happy_path::valid_frame_scans ... ok
[INFO] [stdout] test framing::tests::post_structural::empty_first_field ... ok
[INFO] [stdout] test framing::tests::post_structural::malformed_checksum_non_digit ... ok
[INFO] [stdout] test framing::tests::incomplete::body_not_yet_arrived ... ok
[INFO] [stdout] test framing::tests::post_structural::missing_msg_type ... 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::incomplete::empty ... ok
[INFO] [stdout] test framing::tests::preamble::fixt_begin_string ... ok
[INFO] [stdout] test framing::tests::preamble::begin_string_and_body_length ... ok
[INFO] [stdout] test framing::tests::post_structural::checksum_mismatch ... ok
[INFO] [stdout] test framing::tests::preamble::msg_type ... ok
[INFO] [stdout] test framing::tests::chunking::garbage_before_frame_is_chunk_invariant ... ok
[INFO] [stdout] test framing::tests::preamble::msg_type_read_regardless_of_trailing_fields ... ok
[INFO] [stdout] test framing::tests::preamble::unknown_begin_string_is_other ... ok
[INFO] [stdout] test framing::tests::post_structural::missing_msg_type_on_empty_body ... ok
[INFO] [stdout] test framing::tests::post_structural::skip_does_not_resync_into_body ... ok
[INFO] [stdout] test framing::tests::resync::no_match_skips_whole_buffer ... ok
[INFO] [stdout] test framing::tests::resync::preserves_longest_partial_tail ... ok
[INFO] [stdout] test framing::tests::resync::preserves_short_partial_tail ... ok
[INFO] [stdout] test framing::tests::chunking::clean_stream_is_chunk_invariant ... ok
[INFO] [stdout] test framing::tests::structural::frame_too_large_rejected_before_waiting ... ok
[INFO] [stdout] test framing::tests::structural::malformed_begin_string_overruns_bound ... ok
[INFO] [stdout] test framing::tests::structural::malformed_body_length_non_digit ... ok
[INFO] [stdout] test framing::tests::structural::malformed_body_length_overruns_cap ... ok
[INFO] [stdout] test framing::tests::structural::max_digit_body_length_does_not_panic ... ok
[INFO] [stdout] test framing::tests::structural::missing_begin_string ... ok
[INFO] [stdout] test framing::tests::resync::skip_is_always_in_range ... ok
[INFO] [stdout] test framing::tests::structural::missing_body_length ... ok
[INFO] [stdout] test framing::tests::structural::body_length_mismatch ... ok
[INFO] [stdout] test framing::tests::structural::resync_lands_on_next_message ... ok
[INFO] [stdout] test framing::tests::truncation::every_prefix_of_a_valid_frame_is_incomplete ... ok
[INFO] [stdout] test stream::tests::empty_stream_is_incomplete ... ok
[INFO] [stdout] test stream::tests::dialect_extras_flow_through ... ok
[INFO] [stdout] test stream::tests::garbage_between_messages ... ok
[INFO] [stdout] test message::tests::get_returns_first_occurrence ... ok
[INFO] [stdout] test stream::tests::several_messages_in_one_feed ... ok
[INFO] [stdout] test stream::tests::garbled_frame_does_not_stall_the_stream ... 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 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 stream::tests::message_completes_across_feeds_at_every_split ... ok
[INFO] [stdout] test tokenizer::tests::data_fields::short_length_surfaces_junk_after_the_data ... ok
[INFO] [stdout] test tokenizer::tests::data_fields::extra_below_the_standard_minimum ... ok
[INFO] [stdout] test tokenizer::tests::data_fields::dialect_extra_delimits_data ... ok
[INFO] [stdout] test stream::tests::oversized_frame_is_skipped_not_fatal ... ok
[INFO] [stdout] test framing::tests::resync::finds_needle_after_offset_zero ... ok
[INFO] [stdout] test framing::tests::resync::finds_needle_at_offset_one ... ok
[INFO] [stdout] test framing::tests::structural::non_fix_prefix_is_missing_begin_string ... ok
[INFO] [stdout] test length_tags::tests::standard_is_sorted_and_unique ... ok
[INFO] [stdout] test tokenizer::tests::data_fields::value_with_embedded_soh ... ok
[INFO] [stdout] test tokenizer::tests::data_fields::zero_length_value ... ok
[INFO] [stdout] test tokenizer::tests::data_fields::value_with_several_sohs ... ok
[INFO] [stdout] test message::tests::get_absent_tag ... ok
[INFO] [stdout] test tokenizer::tests::errors::two_messages_are_trailing_bytes ... ok
[INFO] [stdout] test tokenizer::tests::sentinel_runs::consecutive_runs ... ok
[INFO] [stdout] test tokenizer::tests::errors::absurd_body_length_is_too_large_to_index ... ok
[INFO] [stdout] test tokenizer::tests::data_fields::tag_zero_extra_is_ignored ... ok
[INFO] [stdout] test tokenizer::tests::errors::truncated_frame_is_incomplete ... ok
[INFO] [stdout] test tokenizer::tests::data_fields::standard_set_survives_extras ... ok
[INFO] [stdout] test tokenizer::tests::well_formed::value_containing_equals ... ok
[INFO] [stdout] test tokenizer::tests::sentinel_runs::non_numeric_tag ... 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::sentinel_runs::tag_overflowing_u32 ... ok
[INFO] [stdout] test tokenizer::tests::sentinel_runs::run_without_equals ... 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::sentinel_runs::empty_tag ... ok
[INFO] [stdout] test tokenizer::tests::sentinel_runs::fields_after_a_fault_remain_readable ... ok
[INFO] [stdout] test tokenizer::tests::sentinel_runs::literal_tag_zero_is_a_sentinel ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 73 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests refix_message
[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.01s
[INFO] [stdout] 
[INFO] [stdout] all doctests ran in 0.38s; merged doctests compilation took 0.36s
[INFO] running `Command { std: "docker" "inspect" "e69e2d5f3caaf7fca6717748a6e05af3876e8bcfa17f3e981ea3c3bb15398f45", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "e69e2d5f3caaf7fca6717748a6e05af3876e8bcfa17f3e981ea3c3bb15398f45", kill_on_drop: false }`
[INFO] [stdout] e69e2d5f3caaf7fca6717748a6e05af3876e8bcfa17f3e981ea3c3bb15398f45
