[INFO] fetching crate sdr-acars 0.1.0...
[INFO] testing sdr-acars-0.1.0 against 1.99.0-beta.8 for beta-1.100-2
[INFO] extracting crate sdr-acars 0.1.0 into /workspace/builds/worker-7-tc1/source
[INFO] started tweaking crates.io crate sdr-acars 0.1.0
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate sdr-acars 0.1.0
[INFO] tweaked toml for crates.io crate sdr-acars 0.1.0 written to /workspace/builds/worker-7-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate sdr-acars 0.1.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 sdr-acars 0.1.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] [stderr]     Blocking waiting for file lock on package cache
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-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] d7e51db445d1693509ab9262fd837253d579e1d824555b22b8a7a0a44c6c3195
[INFO] running `Command { std: "docker" "start" "d7e51db445d1693509ab9262fd837253d579e1d824555b22b8a7a0a44c6c3195", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "d7e51db445d1693509ab9262fd837253d579e1d824555b22b8a7a0a44c6c3195", 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" "d7e51db445d1693509ab9262fd837253d579e1d824555b22b8a7a0a44c6c3195" "/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" "d7e51db445d1693509ab9262fd837253d579e1d824555b22b8a7a0a44c6c3195", 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" "d7e51db445d1693509ab9262fd837253d579e1d824555b22b8a7a0a44c6c3195" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling num-traits v0.2.19
[INFO] [stderr]    Compiling serde_core v1.0.228
[INFO] [stderr]    Compiling clap_derive v4.6.1
[INFO] [stderr]    Compiling matchers v0.2.0
[INFO] [stderr]    Compiling hound v3.5.1
[INFO] [stderr]    Compiling tracing-subscriber v0.3.23
[INFO] [stderr]    Compiling num-complex v0.4.6
[INFO] [stderr]    Compiling clap v4.6.1
[INFO] [stderr]    Compiling serde_json v1.0.149
[INFO] [stderr]    Compiling sdr-acars v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 7.83s
[INFO] running `Command { std: "docker" "inspect" "d7e51db445d1693509ab9262fd837253d579e1d824555b22b8a7a0a44c6c3195", 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" "d7e51db445d1693509ab9262fd837253d579e1d824555b22b8a7a0a44c6c3195" "/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 sdr-acars v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 1.56s
[INFO] running `Command { std: "docker" "inspect" "d7e51db445d1693509ab9262fd837253d579e1d824555b22b8a7a0a44c6c3195", 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" "d7e51db445d1693509ab9262fd837253d579e1d824555b22b8a7a0a44c6c3195" "/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.06s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/sdr_acars-bb944e7ddfc3b6fa)
[INFO] [stdout] running 112 tests
[INFO] [stderr]      Running unittests src/bin/sdr-acars-cli.rs (/opt/rustwide/target/debug/deps/sdr_acars_cli-e7a879c055fc89db)
[INFO] [stdout] test channel::tests::process_silent_iq_doesnt_decode ... ok
[INFO] [stderr]      Running tests/e2e_acarsdec_compat.rs (/opt/rustwide/target/debug/deps/e2e_acarsdec_compat-736c1d74d26c5844)
[INFO] [stdout] test channel::tests::rejects_zero_or_negative_source_rate ... ok
[INFO] [stdout] test channel::tests::accepts_valid_us_six_config ... ok
[INFO] [stdout] test channel::tests::rejects_non_integer_decimation ... ok
[INFO] [stdout] test crc::tests::crc_matches_known_test_vector ... ok
[INFO] [stdout] test frame::tests::dle_recovery_drops_stale_parity_offsets ... ok
[INFO] [stdout] test frame::tests::parses_a_known_good_downlink_frame ... ok
[INFO] [stdout] test frame::tests::rejects_a_corrupted_frame_when_fec_cant_recover ... ok
[INFO] [stdout] test json::tests::ack_serializes_as_false_when_bang ... ok
[INFO] [stdout] test json::tests::end_field_only_when_end_of_message ... ok
[INFO] [stdout] test frame::tests::parses_a_known_good_uplink_frame ... ok
[INFO] [stdout] test json::tests::omits_block_id_and_ack_when_block_id_zero ... ok
[INFO] [stdout] test json::tests::serializes_minimal_uplink_message ... ok
[INFO] [stdout] test json::tests::omits_empty_text_field ... ok
[INFO] [stdout] test json::tests::oooi_fields_appear_when_parsed_some ... ok
[INFO] [stdout] test crc::tests::crc_is_zero_after_appending_its_own_value ... ok
[INFO] [stdout] test label::tests::lookup_known_labels ... ok
[INFO] [stdout] test label::tests::lookup_numeric_labels ... ok
[INFO] [stdout] test label::tests::lookup_unknown_returns_none ... ok
[INFO] [stdout] test label_parsers::tests::byte_at_extracts_byte ... ok
[INFO] [stdout] test channel::tests::rejects_channel_outside_source_bandwidth ... ok
[INFO] [stdout] test label_parsers::tests::label_10_extracts_da_and_eta ... ok
[INFO] [stdout] test label_parsers::tests::label_10_no_arr01_prefix_returns_none ... ok
[INFO] [stdout] test label_parsers::tests::label_11_missing_separator_returns_none ... ok
[INFO] [stdout] test label_parsers::tests::label_12_extracts_sa_and_da_with_comma_at_offset_4 ... ok
[INFO] [stdout] test label_parsers::tests::byte_at_returns_none_on_short_text ... ok
[INFO] [stdout] test label_parsers::tests::label_12_no_comma_returns_none ... ok
[INFO] [stdout] test label_parsers::tests::decode_label_unknown_returns_none ... ok
[INFO] [stdout] test label_parsers::tests::label_15_extracts_sa_and_da_after_fst01_prefix ... ok
[INFO] [stdout] test json::tests::omits_flight_and_msgno_for_uplink ... ok
[INFO] [stdout] test label_parsers::tests::eight_family_unknown_second_char_returns_none ... ok
[INFO] [stdout] test label_parsers::tests::label_11_extracts_da_and_eta ... ok
[INFO] [stdout] test label_parsers::tests::label_17_extracts_eta_sa_da_with_eta_prefix_and_commas ... ok
[INFO] [stdout] test json::tests::omits_station_id_when_none_or_empty ... ok
[INFO] [stdout] test label_parsers::tests::label_1g_extracts_sa_and_da_with_comma_at_offset_4 ... ok
[INFO] [stdout] test label_parsers::tests::label_20_no_rst_prefix_returns_none ... ok
[INFO] [stdout] test json::tests::reassembled_blocks_field_only_when_gt_one ... ok
[INFO] [stdout] test label_parsers::tests::label_21_extracts_sa_da_with_commas ... ok
[INFO] [stdout] test label_parsers::tests::label_26_extracts_sa_da_eta_through_multiline_walk ... ok
[INFO] [stdout] test label_parsers::tests::label_26_no_ver_077_prefix_returns_none ... ok
[INFO] [stdout] test label_parsers::tests::label_26_second_line_not_eta_returns_none ... ok
[INFO] [stdout] test label_parsers::tests::label_44_unsupported_kind_byte_returns_none ... ok
[INFO] [stdout] test label_parsers::tests::label_45_extracts_da_after_a_prefix ... ok
[INFO] [stdout] test label_parsers::tests::label_45_no_a_prefix_returns_none ... ok
[INFO] [stdout] test label_parsers::tests::label_20_extracts_sa_da_after_rst_prefix ... ok
[INFO] [stdout] test label_parsers::tests::label_44_extracts_da_and_eta_with_pos02_prefix ... ok
[INFO] [stdout] test label_parsers::tests::label_44_with_00_prefix_shifts_base ... ok
[INFO] [stdout] test label_parsers::tests::label_80_extracts_da_after_dest_prefix ... ok
[INFO] [stdout] test label_parsers::tests::label_33_extracts_sa_da_with_three_commas ... ok
[INFO] [stdout] test label_parsers::tests::label_80_no_dest_prefix_returns_none ... ok
[INFO] [stdout] test json::tests::serializes_full_downlink_message ... ok
[INFO] [stdout] test label_parsers::tests::label_8e_extracts_da_and_eta_with_comma ... ok
[INFO] [stdout] test label_parsers::tests::label_8s_extracts_da_and_eta_with_comma ... ok
[INFO] [stdout] test label_parsers::tests::label_26_without_eta_line_still_succeeds ... ok
[INFO] [stdout] test label_parsers::tests::label_q1_extracts_six_fields ... ok
[INFO] [stdout] test label_parsers::tests::label_83_extracts_sa_and_da_with_comma ... ok
[INFO] [stdout] test label_parsers::tests::label_q2_extracts_sa_and_eta ... ok
[INFO] [stdout] test label_parsers::tests::label_qa_extracts_sa_and_gout ... ok
[INFO] [stdout] test label_parsers::tests::label_qb_extracts_sa_and_woff ... ok
[INFO] [stdout] test label_parsers::tests::label_qc_extracts_sa_and_won ... ok
[INFO] [stdout] test label_parsers::tests::label_qd_extracts_sa_and_gin ... ok
[INFO] [stdout] test label_parsers::tests::label_qe_extracts_sa_gout_da ... ok
[INFO] [stdout] test label_parsers::tests::label_qf_extracts_sa_woff_da ... ok
[INFO] [stdout] test label_parsers::tests::label_qg_extracts_sa_gout_gin ... ok
[INFO] [stdout] test label_parsers::tests::label_qh_extracts_sa_and_gout ... ok
[INFO] [stdout] test label_parsers::tests::label_qk_extracts_sa_won_da ... ok
[INFO] [stdout] test label_parsers::tests::label_ql_extracts_da_gin_sa ... ok
[INFO] [stdout] test label_parsers::tests::label_qm_extracts_da_and_sa ... ok
[INFO] [stdout] test label_parsers::tests::label_qn_extracts_da_and_eta ... ok
[INFO] [stdout] test label_parsers::tests::label_qp_extracts_sa_da_gout ... ok
[INFO] [stdout] test label_parsers::tests::label_qq_extracts_sa_da_woff ... ok
[INFO] [stdout] test label_parsers::tests::label_rb_aliases_label_26 ... ok
[INFO] [stdout] test label_parsers::tests::oooi_default_is_all_none ... ok
[INFO] [stdout] test label_parsers::tests::oooi_has_any_true_when_any_field_set ... ok
[INFO] [stdout] test label_parsers::tests::label_qs_extracts_sa_da_gin ... ok
[INFO] [stdout] test label_parsers::tests::q_family_short_text_returns_none ... ok
[INFO] [stdout] test label_parsers::tests::q_unknown_second_char_returns_none ... ok
[INFO] [stdout] test frame::tests::ignores_bytes_outside_a_frame ... ok
[INFO] [stdout] test json::tests::includes_station_id_when_set ... ok
[INFO] [stdout] test label_parsers::tests::slice4_extracts_four_chars ... ok
[INFO] [stdout] test label_parsers::tests::label_qt_extracts_sa_da_gout_gin ... ok
[INFO] [stdout] test msk::tests::matched_filter_is_half_wave_clipped_cosine ... ok
[INFO] [stdout] test reassembly::tests::distinct_keys_dont_cross_pollute ... ok
[INFO] [stdout] test reassembly::tests::first_seen_uses_observation_clock_not_msg_timestamp ... ok
[INFO] [stdout] test reassembly::tests::etb_then_etx_reassembles_in_order ... ok
[INFO] [stdout] test reassembly::tests::flush_drains_all_pending ... ok
[INFO] [stdout] test reassembly::tests::drain_timeouts_emits_only_stale_buckets ... ok
[INFO] [stdout] test reassembly::tests::out_of_order_blocks_sort_by_block_id ... ok
[INFO] [stdout] test reassembly::tests::out_of_order_etx_before_etb ... ok
[INFO] [stdout] test reassembly::tests::passthrough_when_message_no_missing ... ok
[INFO] [stdout] test syndrom::tests::fix_double_error_handles_two_bit_flip_in_one_byte ... ok
[INFO] [stdout] test msk::tests::demod_produces_no_bits_from_silence ... ok
[INFO] [stdout] test msk::tests::flen_constants_match_c_integer_division ... ok
[INFO] [stdout] test reassembly::tests::timeout_emits_partial_reassembly ... ok
[INFO] [stdout] test syndrom::tests::no_correction_when_no_parity_errors_and_zero_crc ... ok
[INFO] [stdout] test syndrom::tests::single_bit_flip_in_last_byte_recovers ... ok
[INFO] [stdout] test syndrom::tests::syndrom_first_row_matches_c ... ok
[INFO] [stdout] test syndrom::tests::syndrom_is_a_pure_static_table ... ok
[INFO] [stdout] test syndrom::tests::syndrom_table_has_expected_size ... ok
[INFO] [stdout] test syndrom::tests::syndrom_last_row_matches_c ... ok
[INFO] [stdout] test reassembly::tests::passthrough_for_single_block_etx ... ok
[INFO] [stdout] test reassembly::tests::cap_evicts_oldest_pending ... ok
[INFO] [stdout] test json::tests::oooi_fields_omitted_when_parsed_none ... ok
[INFO] [stdout] test label_parsers::tests::slice4_none_when_slice_starts_inside_multibyte_codepoint ... ok
[INFO] [stdout] test channel::tests::rejects_empty_channel_list ... ok
[INFO] [stdout] test label_parsers::tests::slice4_returns_none_on_short_text ... ok
[INFO] [stdout] test label_parsers::tests::label_qr_extracts_sa_da_won ... ok
[INFO] [stdout] test label_parsers::tests::label_2n_extracts_sa_da_after_tko01 ... ok
[INFO] [stdout] test label_parsers::tests::label_2z_extracts_da_only ... ok
[INFO] [stdout] test label_parsers::tests::label_8d_extracts_sa_da_with_three_commas ... ok
[INFO] [stdout] test msk::tests::demod_advances_phase_state ... ok
[INFO] [stdout] test label_parsers::tests::label_39_extracts_sa_da_after_gta01_with_slash ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 112 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[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] [stdout] 
[INFO] [stdout] running 5 tests
[INFO] [stdout] test tests::leaves_non_matching_brackets_alone ... ok
[INFO] [stdout] test tests::passes_non_header_lines_through_unchanged ... ok
[INFO] [stdout] test tests::strips_our_format_with_unix_timestamp ... ok
[INFO] [stdout] test tests::strips_acarsdec_format_with_two_trailing_spaces ... ok
[INFO] [stdout] test sdr_acars_cli_matches_acarsdec_on_test_wav ... ok
[INFO] [stderr]      Running tests/multichannel_synthetic.rs (/opt/rustwide/target/debug/deps/multichannel_synthetic-e036d7c6491c7621)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.11s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test pure_noise_produces_no_messages ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.81s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests sdr_acars
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test src/lib.rs - (line 8) - compile ... 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.48s; merged doctests compilation took 0.46s
[INFO] running `Command { std: "docker" "inspect" "d7e51db445d1693509ab9262fd837253d579e1d824555b22b8a7a0a44c6c3195", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "d7e51db445d1693509ab9262fd837253d579e1d824555b22b8a7a0a44c6c3195", kill_on_drop: false }`
[INFO] [stdout] d7e51db445d1693509ab9262fd837253d579e1d824555b22b8a7a0a44c6c3195
