[INFO] fetching crate radio-utils-keyer 0.1.1...
[INFO] testing radio-utils-keyer-0.1.1 against 1.99.0-beta.8 for beta-1.100-2
[INFO] extracting crate radio-utils-keyer 0.1.1 into /workspace/builds/worker-0-tc1/source
[INFO] started tweaking crates.io crate radio-utils-keyer 0.1.1
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate radio-utils-keyer 0.1.1
[INFO] tweaked toml for crates.io crate radio-utils-keyer 0.1.1 written to /workspace/builds/worker-0-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate radio-utils-keyer 0.1.1 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 radio-utils-keyer 0.1.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" "+1.99.0-beta.8" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded io-kit-sys v0.4.1
[INFO] [stderr]   Downloaded libudev v0.3.0
[INFO] [stderr]   Downloaded windows-implement v0.56.0
[INFO] [stderr]   Downloaded gloo-timers v0.3.0
[INFO] [stderr]   Downloaded windows-interface v0.56.0
[INFO] [stderr]   Downloaded coremidi v0.9.0
[INFO] [stderr]   Downloaded coremidi-sys v3.2.0
[INFO] [stderr]   Downloaded unescaper v0.1.8
[INFO] [stderr]   Downloaded midir v0.10.4
[INFO] [stderr]   Downloaded windows-core v0.56.0
[INFO] [stderr]   Downloaded heapless v0.8.0
[INFO] [stderr]   Downloaded serialport v4.9.0
[INFO] [stderr]   Downloaded nix v0.26.4
[INFO] [stderr]   Downloaded windows v0.56.0
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-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] c58f86ad31b7a1d247e9bcceabb498ddae69c6767aa197e2c8aebe1397e7abc4
[INFO] running `Command { std: "docker" "start" "c58f86ad31b7a1d247e9bcceabb498ddae69c6767aa197e2c8aebe1397e7abc4", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "c58f86ad31b7a1d247e9bcceabb498ddae69c6767aa197e2c8aebe1397e7abc4", 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" "c58f86ad31b7a1d247e9bcceabb498ddae69c6767aa197e2c8aebe1397e7abc4" "/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" "c58f86ad31b7a1d247e9bcceabb498ddae69c6767aa197e2c8aebe1397e7abc4", 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" "c58f86ad31b7a1d247e9bcceabb498ddae69c6767aa197e2c8aebe1397e7abc4" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling pkg-config v0.3.33
[INFO] [stderr]    Compiling libc v0.2.186
[INFO] [stderr]    Compiling thiserror v2.0.18
[INFO] [stderr]    Compiling serde_core v1.0.228
[INFO] [stderr]    Compiling bitflags v2.11.1
[INFO] [stderr]    Compiling heapless v0.8.0
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling scopeguard v1.2.0
[INFO] [stderr]    Compiling hash32 v0.3.1
[INFO] [stderr]    Compiling radio-utils-keyer v0.1.1 (/opt/rustwide/workdir)
[INFO] [stderr]    Compiling web-time v1.1.0
[INFO] [stderr]    Compiling syn v2.0.117
[INFO] [stderr]    Compiling crossbeam-channel v0.5.15
[INFO] [stderr]    Compiling alsa-sys v0.3.1
[INFO] [stderr]    Compiling libudev-sys v0.1.4
[INFO] [stderr]    Compiling thiserror-impl v2.0.18
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling nix v0.26.4
[INFO] [stderr]    Compiling libudev v0.3.0
[INFO] [stderr]    Compiling alsa v0.9.1
[INFO] [stderr]    Compiling unescaper v0.1.8
[INFO] [stderr]    Compiling serialport v4.9.0
[INFO] [stderr]    Compiling midir v0.10.4
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 13.91s
[INFO] running `Command { std: "docker" "inspect" "c58f86ad31b7a1d247e9bcceabb498ddae69c6767aa197e2c8aebe1397e7abc4", 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" "c58f86ad31b7a1d247e9bcceabb498ddae69c6767aa197e2c8aebe1397e7abc4" "/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 memchr v2.8.0
[INFO] [stderr]    Compiling serde_json v1.0.149
[INFO] [stderr]    Compiling radio-utils-keyer v0.1.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 3.79s
[INFO] running `Command { std: "docker" "inspect" "c58f86ad31b7a1d247e9bcceabb498ddae69c6767aa197e2c8aebe1397e7abc4", 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" "c58f86ad31b7a1d247e9bcceabb498ddae69c6767aa197e2c8aebe1397e7abc4" "/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.08s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/radio_utils_keyer-9cd9517423e14e6b)
[INFO] [stdout] 
[INFO] [stdout] running 86 tests
[INFO] [stdout] test config::tests::config_serialization_roundtrip ... ok
[INFO] [stdout] test config::tests::farnsworth_disabled_when_zero ... ok
[INFO] [stdout] test config::tests::keying_compensation_extends_elements ... ok
[INFO] [stdout] test config::tests::default_config_has_sane_values ... ok
[INFO] [stdout] test config::tests::default_new_fields ... ok
[INFO] [stdout] test config::tests::dot_duration_at_standard_speeds ... ok
[INFO] [stdout] test config::tests::midi_config_defaults_to_none ... ok
[INFO] [stdout] test config::tests::dynamic_ratio_disabled_uses_weight ... ok
[INFO] [stdout] test config::tests::farnsworth_disabled_when_gte_speed ... ok
[INFO] [stdout] test config::tests::midi_binding_roundtrip ... ok
[INFO] [stdout] test config::tests::new_fields_serde_backward_compat ... ok
[INFO] [stdout] test config::tests::config_missing_timing_field_gets_default ... ok
[INFO] [stdout] test config::tests::new_modes_serde_roundtrip ... ok
[INFO] [stdout] test config::tests::farnsworth_stretches_gaps ... ok
[INFO] [stdout] test config::tests::dynamic_ratio_shortens_dash_at_high_wpm ... ok
[INFO] [stdout] test engine::tests::farnsworth_gap_applied_in_engine ... ok
[INFO] [stdout] test engine::tests::iambic_a_stops_on_release ... ok
[INFO] [stdout] test engine::tests::hang_time_emits_ptt_off ... ok
[INFO] [stdout] test engine::tests::iambic_b_dit_duration ... ok
[INFO] [stdout] test engine::tests::iambic_b_squeeze_alternates ... ok
[INFO] [stdout] test engine::tests::dit_duration_jitter_within_tick_quantum_250us ... ok
[INFO] [stdout] test engine::tests::macro_sends_text ... ok
[INFO] [stdout] test engine::tests::network_transitions_captured ... ok
[INFO] [stdout] test engine::tests::no_accumulated_drift_at_28_wpm ... ok
[INFO] [stdout] test engine::tests::paddle_interrupts_macro ... ok
[INFO] [stdout] test engine::tests::idle_no_output ... ok
[INFO] [stdout] test engine::tests::keying_compensation_extends_engine_keydown ... ok
[INFO] [stdout] test engine::tests::no_accumulated_drift_single_paddle ... ok
[INFO] [stdout] test engine::tests::timing_100_pct_no_element_memory ... ok
[INFO] [stdout] test engine::tests::timing_gate_does_not_affect_gap_latching ... ok
[INFO] [stdout] test engine::tests::no_accumulated_drift_across_modes_and_wpms ... ok
[INFO] [stdout] test engine::tests::timing_0_pct_full_iambic_b ... ok
[INFO] [stdout] test engine::tests::single_paddle_either_paddle_works ... ok
[INFO] [stdout] test engine::tests::single_paddle_long_press_is_dash ... ok
[INFO] [stdout] test engine::tests::straight_key_debounces_release_press_bounce_on_initial_press ... ok
[INFO] [stdout] test engine::tests::straight_key_follows_input ... ok
[INFO] [stdout] test engine::tests::timing_33_pct_dash_first_symmetry ... ok
[INFO] [stdout] test engine::tests::timing_33_pct_early_squeeze_latches ... ok
[INFO] [stdout] test engine::tests::timing_does_not_affect_iambic_a ... ok
[INFO] [stdout] test engine::tests::single_paddle_short_press_is_dit ... ok
[INFO] [stdout] test config::tests::dash_duration_respects_weight ... ok
[INFO] [stdout] test engine::tests::timing_33_pct_late_squeeze_blocked ... ok
[INFO] [stdout] test engine::tests::no_accumulated_drift_at_28_wpm_250us_tick ... ok
[INFO] [stdout] test engine::tests::ultimatic_stops_on_release ... ok
[INFO] [stdout] test engine::tests::ultimatic_stops_on_release_during_active_element ... ok
[INFO] [stdout] test input::midi::tests::from_bytes_one_byte_note_off_accepted ... ok
[INFO] [stdout] test engine::tests::timing_set_paddle_rising_edge_gated ... ok
[INFO] [stdout] test engine::tests::ultimatic_alternates_on_late_paddle_change ... ok
[INFO] [stdout] test engine::tests::ultimatic_no_cross_session_state_leak ... ok
[INFO] [stdout] test engine::tests::ultimatic_single_paddle_repeats ... ok
[INFO] [stdout] test engine::tests::ultimatic_squeeze_repeats_last_paddle ... ok
[INFO] [stdout] test input::midi::tests::channel_match_does_match ... ok
[INFO] [stdout] test input::midi::tests::channel_mismatch_does_not_match ... ok
[INFO] [stdout] test input::midi::tests::feed_is_noop_when_inactive ... ok
[INFO] [stdout] test input::midi::tests::from_bytes_one_byte_note_on_accepted ... ok
[INFO] [stdout] test input::midi::tests::control_change_zero_is_down_false ... ok
[INFO] [stdout] test input::midi::tests::control_change_nonzero_is_down_true ... ok
[INFO] [stdout] test input::midi::tests::from_bytes_unsupported_returns_none ... ok
[INFO] [stdout] test input::midi::tests::learn_state_cancel_clears_everything ... ok
[INFO] [stdout] test input::midi::tests::learn_state_deduplicates_candidates ... ok
[INFO] [stdout] test input::midi::tests::from_bytes_empty_returns_none ... ok
[INFO] [stdout] test input::midi::tests::learn_state_does_not_deduplicate_different_channels ... ok
[INFO] [stdout] test input::midi::tests::learn_state_does_not_resolve_before_500ms ... ok
[INFO] [stdout] test input::midi::tests::note_on_velocity_nonzero_is_down_true ... ok
[INFO] [stdout] test input::midi::tests::learn_state_prefers_noteon_over_cc ... ok
[INFO] [stdout] test input::midi::tests::learn_state_resolves_after_500ms ... ok
[INFO] [stdout] test morse::tests::lookup_is_case_insensitive ... ok
[INFO] [stdout] test morse::tests::lookup_standard_characters ... ok
[INFO] [stdout] test morse::tests::text_to_elements_simple ... ok
[INFO] [stdout] test morse::tests::unknown_chars_are_skipped ... ok
[INFO] [stdout] test input::midi::tests::from_bytes_one_byte_realtime_returns_none ... ok
[INFO] [stdout] test input::midi::tests::note_on_velocity_zero_is_down_false ... ok
[INFO] [stdout] test input::midi::tests::from_bytes_truncated_two_bytes_returns_none ... ok
[INFO] [stdout] test sidetone::tests::sidetone_ramps_smoothly ... ok
[INFO] [stdout] test morse::tests::unknown_char_returns_none ... ok
[INFO] [stdout] test input::midi::tests::matches_binding ... ok
[INFO] [stdout] test input::serial::tests::port_config_description ... ok
[INFO] [stdout] test sidetone::tests::sidetone_produces_tone_when_keyed ... ok
[INFO] [stdout] test sidetone::tests::sidetone_silent_when_not_keyed ... ok
[INFO] [stdout] test input::midi::tests::note_off_status_byte_is_down_false ... ok
[INFO] [stdout] test morse::tests::text_to_elements_with_space ... ok
[INFO] [stdout] test input::keyboard::tests::keyboard_input_wakes_on_event ... ok
[INFO] [stdout] test input::keyboard::tests::keyboard_input_timeout_returns_current_state ... ok
[INFO] [stdout] test input::merged::tests::merged_timeout_no_event ... ok
[INFO] [stdout] test input::merged::tests::merged_wakes_on_keyboard_event ... ok
[INFO] [stdout] test input::serial::tests::available_ports_returns_vec ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 86 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.22s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/integration.rs (/opt/rustwide/target/debug/deps/integration-2725786e4571e285)
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stderr] ALSA lib seq_hw.c:540:(snd_seq_hw_open) [error.sequencer] open /dev/snd/seq failed: No such file or directory
[INFO] [stdout] test midi_input_device_list_returns_vec ... ok
[INFO] [stdout] test config_update_changes_speed ... ok
[INFO] [stdout] test macro_sends_and_completes ... ok
[INFO] [stderr]    Doc-tests radio_utils_keyer
[INFO] [stdout] test full_keyer_cycle_keyboard_iambic_b ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.03s
[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] running `Command { std: "docker" "inspect" "c58f86ad31b7a1d247e9bcceabb498ddae69c6767aa197e2c8aebe1397e7abc4", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "c58f86ad31b7a1d247e9bcceabb498ddae69c6767aa197e2c8aebe1397e7abc4", kill_on_drop: false }`
[INFO] [stdout] c58f86ad31b7a1d247e9bcceabb498ddae69c6767aa197e2c8aebe1397e7abc4
