[INFO] fetching crate sonora 0.1.0...
[INFO] testing sonora-0.1.0 against try#dec9417b8611e34e787a3e4c37686b5131f9e5c5 for pr-154210-2
[INFO] extracting crate sonora 0.1.0 into /workspace/builds/worker-7-tc2/source
[INFO] started tweaking crates.io crate sonora 0.1.0
[INFO] removed 0 missing examples
[INFO] finished tweaking crates.io crate sonora 0.1.0
[INFO] tweaked toml for crates.io crate sonora 0.1.0 written to /workspace/builds/worker-7-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate sonora 0.1.0 on toolchain dec9417b8611e34e787a3e4c37686b5131f9e5c5
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate sonora 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" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/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" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 2f9864b82ae8cfaa777f37485c002dd4c7669b4523d71457f4ab0efc457a6032
[INFO] running `Command { std: "docker" "start" "-a" "2f9864b82ae8cfaa777f37485c002dd4c7669b4523d71457f4ab0efc457a6032", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "2f9864b82ae8cfaa777f37485c002dd4c7669b4523d71457f4ab0efc457a6032", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "2f9864b82ae8cfaa777f37485c002dd4c7669b4523d71457f4ab0efc457a6032", kill_on_drop: false }`
[INFO] [stdout] 2f9864b82ae8cfaa777f37485c002dd4c7669b4523d71457f4ab0efc457a6032
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/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" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 73dfc36a9fcfed3ffd963d3f1c088e1623cba46c819edfc5543c8c590b7843d2
[INFO] running `Command { std: "docker" "start" "-a" "73dfc36a9fcfed3ffd963d3f1c088e1623cba46c819edfc5543c8c590b7843d2", kill_on_drop: false }`
[INFO] [stderr]    Compiling unicode-ident v1.0.23
[INFO] [stderr]    Compiling cpufeatures v0.3.0
[INFO] [stderr]    Compiling sonora-fft v0.1.0
[INFO] [stderr]    Compiling proc-macro2 v1.0.106
[INFO] [stderr]    Compiling sonora-simd v0.1.0
[INFO] [stderr]    Compiling quote v1.0.44
[INFO] [stderr]    Compiling sonora-ns v0.1.0
[INFO] [stderr]    Compiling syn v2.0.114
[INFO] [stderr]    Compiling derive_more-impl v2.1.1
[INFO] [stderr]    Compiling bytemuck_derive v1.10.2
[INFO] [stderr]    Compiling tracing-attributes v0.1.31
[INFO] [stderr]    Compiling derive_more v2.1.1
[INFO] [stderr]    Compiling bytemuck v1.25.0
[INFO] [stderr]    Compiling sonora-common-audio v0.1.0
[INFO] [stderr]    Compiling tracing v0.1.44
[INFO] [stderr]    Compiling sonora-aec3 v0.1.0
[INFO] [stderr]    Compiling sonora-agc2 v0.1.0
[INFO] [stderr]    Compiling sonora v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 11.74s
[INFO] running `Command { std: "docker" "inspect" "73dfc36a9fcfed3ffd963d3f1c088e1623cba46c819edfc5543c8c590b7843d2", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "73dfc36a9fcfed3ffd963d3f1c088e1623cba46c819edfc5543c8c590b7843d2", kill_on_drop: false }`
[INFO] [stdout] 73dfc36a9fcfed3ffd963d3f1c088e1623cba46c819edfc5543c8c590b7843d2
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/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" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] f57b25d5b0672f4437b5173eb74584073c10654b492114812d42639806f44d54
[INFO] running `Command { std: "docker" "start" "-a" "f57b25d5b0672f4437b5173eb74584073c10654b492114812d42639806f44d54", kill_on_drop: false }`
[INFO] [stderr]    Compiling zerocopy v0.8.39
[INFO] [stderr]    Compiling regex-syntax v0.8.9
[INFO] [stderr]    Compiling zmij v1.0.20
[INFO] [stderr]    Compiling tracing-core v0.1.36
[INFO] [stderr]    Compiling cc v1.2.55
[INFO] [stderr]    Compiling libc v0.2.181
[INFO] [stderr]    Compiling clap_lex v0.7.7
[INFO] [stderr]    Compiling itertools v0.13.0
[INFO] [stderr]    Compiling zerocopy-derive v0.8.39
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling plotters v0.3.7
[INFO] [stderr]    Compiling clap_builder v4.5.57
[INFO] [stderr]    Compiling tracing v0.1.44
[INFO] [stderr]    Compiling tracing-log v0.2.0
[INFO] [stderr]    Compiling serde_json v1.0.149
[INFO] [stderr]    Compiling sonora-agc2 v0.1.0
[INFO] [stderr]    Compiling sonora v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]    Compiling criterion-plot v0.8.2
[INFO] [stderr]    Compiling page_size v0.6.0
[INFO] [stderr]    Compiling alloca v0.4.0
[INFO] [stderr]    Compiling regex-automata v0.4.14
[INFO] [stderr]    Compiling clap v4.5.57
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling tinytemplate v1.2.1
[INFO] [stderr]    Compiling regex v1.12.3
[INFO] [stderr]    Compiling matchers v0.2.0
[INFO] [stderr]    Compiling tracing-subscriber v0.3.22
[INFO] [stderr]    Compiling half v2.7.1
[INFO] [stderr]    Compiling ciborium-ll v0.2.2
[INFO] [stderr]    Compiling ciborium v0.2.2
[INFO] [stderr]    Compiling criterion v0.8.2
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 26.82s
[INFO] running `Command { std: "docker" "inspect" "f57b25d5b0672f4437b5173eb74584073c10654b492114812d42639806f44d54", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "f57b25d5b0672f4437b5173eb74584073c10654b492114812d42639806f44d54", kill_on_drop: false }`
[INFO] [stdout] f57b25d5b0672f4437b5173eb74584073c10654b492114812d42639806f44d54
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/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" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] f8f1dc781b1fce7f8f9cc4583b2cc06578e0bca33e403ee89ef446c622b13de0
[INFO] running `Command { std: "docker" "start" "-a" "f8f1dc781b1fce7f8f9cc4583b2cc06578e0bca33e403ee89ef446c622b13de0", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.19s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/sonora-8a07520012650681)
[INFO] [stdout] 
[INFO] [stdout] running 198 tests
[INFO] [stdout] test audio_buffer::tests::single_band_no_splitting_filter ... ok
[INFO] [stdout] test audio_buffer::tests::two_band_32khz ... ok
[INFO] [stdout] test audio_buffer::tests::band_splitting ... ok
[INFO] [stdout] test audio_buffer::tests::copy_without_resampling ... ok
[INFO] [stdout] test audio_buffer::tests::downmix_by_averaging ... ok
[INFO] [stdout] test audio_converter::tests::downmix_averages_channels ... ok
[INFO] [stdout] test audio_converter::tests::copy_preserves_signal ... ok
[INFO] [stdout] test audio_converter::tests::upmix_duplicates_mono ... ok
[INFO] [stdout] test audio_buffer::tests::downmix_specific_channel ... ok
[INFO] [stdout] test audio_processing::tests::builder_creates_default_instance ... ok
[INFO] [stdout] test audio_processing::tests::error_display ... ok
[INFO] [stdout] test audio_processing::tests::format_handling_valid_formats_proceed ... ok
[INFO] [stdout] test audio_processing::tests::format_handling_i16_error_and_broadcast_first_channel ... ok
[INFO] [stdout] test audio_processing::tests::format_handling_i16_error_and_silence ... ok
[INFO] [stdout] test audio_buffer::tests::copy_with_resampling ... ok
[INFO] [stdout] test audio_processing::tests::format_handling_i16_error_and_exact_copy ... ok
[INFO] [stdout] test audio_processing::tests::format_handling_zero_channels_is_invalid ... ok
[INFO] [stdout] test audio_processing::tests::no_processing_when_all_disabled_float ... ok
[INFO] [stdout] test audio_processing::tests::format_handling_unsupported_rate ... ok
[INFO] [stdout] test audio_processing::tests::all_processing_disabled_by_default ... ok
[INFO] [stdout] test audio_processing::tests::no_processing_when_all_disabled_float_reverse ... ok
[INFO] [stdout] test audio_processing::tests::config_change_mid_stream ... ok
[INFO] [stdout] test audio_processing::tests::format_handling_f32_error_and_exact_copy ... ok
[INFO] [stdout] test audio_processing::tests::high_pass_filter_processes_multiple_frames ... ok
[INFO] [stdout] test audio_processing::tests::format_handling_f32_error_and_copy_of_first_channel ... ok
[INFO] [stdout] test audio_processing::tests::format_handling_f32_error_and_silence_rate_mismatch ... ok
[INFO] [stdout] test audio_processing::tests::process_capture_f32_with_config_silence_passthrough ... ok
[INFO] [stdout] test audio_processing::tests::process_capture_f32_with_config_validates_bad_rate ... ok
[INFO] [stdout] test audio_processing::tests::process_capture_f32_with_config_validates_channel_mismatch ... ok
[INFO] [stdout] test audio_processing::tests::process_render_f32_with_config_passthrough ... ok
[INFO] [stdout] test audio_processing::tests::process_capture_f32_with_config_validates_bad_channels ... ok
[INFO] [stdout] test audio_processing::tests::pre_amplifier_applies_gain ... ok
[INFO] [stdout] test audio_processing::tests::process_capture_i16_with_config_silence_passthrough ... ok
[INFO] [stdout] test audio_processing::tests::sample_rate_change_mid_stream ... ok
[INFO] [stdout] test audio_processing::tests::set_stream_delay_clamps_high ... ok
[INFO] [stdout] test audio_processing::tests::no_processing_when_all_disabled_i16 ... ok
[INFO] [stdout] test audio_processing::tests::process_capture_i16_with_config_accepts_non_native_rate ... ok
[INFO] [stdout] test audio_processing::tests::set_stream_delay_clamps_negative ... ok
[INFO] [stdout] test audio_processing::tests::runtime_setting_capture_pre_gain ... ok
[INFO] [stdout] test audio_processing::tests::set_stream_delay_valid_range ... ok
[INFO] [stdout] test audio_processing_impl::tests::create_with_capture_level_adjustment ... ok
[INFO] [stdout] test audio_processing_impl::tests::apply_config_disables_noise_suppression ... ok
[INFO] [stdout] test audio_processing_impl::tests::create_with_gain_controller2 ... ok
[INFO] [stdout] test audio_processing_impl::tests::suitable_process_rate_basic ... ok
[INFO] [stdout] test audio_processing_impl::tests::statistics_default ... ok
[INFO] [stdout] test audio_processing_impl::tests::create_with_noise_suppression ... ok
[INFO] [stdout] test audio_processing_impl::tests::create_with_echo_canceller ... ok
[INFO] [stdout] test audio_processing_impl::tests::create_default ... ok
[INFO] [stdout] test audio_processing_impl::tests::initialize_with_different_rates ... ok
[INFO] [stdout] test audio_processing_impl::tests::high_pass_filter_enforced_by_aec ... ok
[INFO] [stdout] test audio_processing_impl::tests::suitable_process_rate_48k_max ... ok
[INFO] [stdout] test audio_processing_impl::tests::apply_config_enables_echo_canceller ... ok
[INFO] [stdout] test audio_processing_impl::tests::suitable_process_rate_no_splitting ... ok
[INFO] [stdout] test audio_samples_scaler::tests::downwards_clamping ... ok
[INFO] [stdout] test audio_samples_scaler::tests::upwards_clamping ... ok
[INFO] [stdout] test config::tests::capture_level_adjustment_equality ... ok
[INFO] [stdout] test config::tests::max_processing_rate_as_hz ... ok
[INFO] [stdout] test config_selector::tests::correct_config_update_behavior ... ok
[INFO] [stdout] test config_selector::tests::mono_config_selected_when_no_multichannel_config_present ... ok
[INFO] [stdout] test echo_canceller3::tests::block_delay_buffer_basic ... ok
[INFO] [stdout] test echo_canceller3::tests::block_delay_buffer_zero_delay ... ok
[INFO] [stdout] test echo_canceller3::tests::construct_default_config_16khz ... ok
[INFO] [stdout] test echo_canceller3::tests::construct_default_config_48khz ... ok
[INFO] [stdout] test echo_canceller3::tests::construct_stereo ... ok
[INFO] [stdout] test echo_canceller3::tests::get_metrics_returns_valid ... ok
[INFO] [stdout] test audio_samples_scaler::tests::initial_gain_is_respected ... ok
[INFO] [stdout] test echo_canceller3::tests::saturation_detection ... ok
[INFO] [stdout] test echo_canceller3::tests::set_audio_buffer_delay ... ok
[INFO] [stdout] test config_selector::tests::correct_initial_config_is_selected ... ok
[INFO] [stdout] test echo_canceller3::tests::stereo_content_detection ... ok
[INFO] [stdout] test echo_detector::circular_buffer::tests::fill ... ok
[INFO] [stdout] test echo_detector::circular_buffer::tests::less_than_max ... ok
[INFO] [stdout] test echo_detector::mean_variance_estimator::tests::alternating_value ... ok
[INFO] [stdout] test echo_canceller3::tests::set_capture_output_usage ... ok
[INFO] [stdout] test echo_detector::mean_variance_estimator::tests::constant_value ... ok
[INFO] [stdout] test echo_detector::mean_variance_estimator::tests::insert_zeroes ... ok
[INFO] [stdout] test echo_detector::moving_max::tests::clear_test ... ok
[INFO] [stdout] test echo_detector::moving_max::tests::decay ... ok
[INFO] [stdout] test echo_detector::moving_max::tests::simple ... ok
[INFO] [stdout] test echo_detector::circular_buffer::tests::read_from_empty ... ok
[INFO] [stdout] test echo_detector::moving_max::tests::sliding_window ... ok
[INFO] [stdout] test echo_detector::normalized_covariance_estimator::tests::identical_signal ... ok
[INFO] [stdout] test gain_controller2::tests::apply_default_config ... ok
[INFO] [stdout] test gain_controller2::tests::change_fixed_gain_should_be_fast_and_time_invariant ... ok
[INFO] [stdout] test gain_controller2::tests::check_adaptive_digital_max_gain_change_speed_config ... ok
[INFO] [stdout] test echo_detector::normalized_covariance_estimator::tests::opposite_signal ... ok
[INFO] [stdout] test gain_controller2::tests::check_adaptive_digital_max_output_noise_level_config ... ok
[INFO] [stdout] test gain_controller2::tests::check_default_config ... ok
[INFO] [stdout] test gain_controller2::tests::check_fixed_digital_config ... ok
[INFO] [stdout] test gain_controller2::tests::check_headroom_db ... ok
[INFO] [stdout] test gain_controller2::tests::check_initial_gain_db ... ok
[INFO] [stdout] test gain_controller2::tests::check_max_gain_db ... ok
[INFO] [stdout] test audio_processing::tests::noise_suppression_processes_multiple_frames ... ok
[INFO] [stdout] test gain_controller2::tests::check_recommended_input_volume_when_input_volume_controller_not_enabled ... ok
[INFO] [stdout] test echo_detector::circular_buffer::tests::overflow ... ok
[INFO] [stdout] test gain_controller2::tests::check_recommended_input_volume_when_not_enabled_and_specific_config ... ok
[INFO] [stdout] test gain_controller2::tests::gain_should_change_on_set_gain ... ok
[INFO] [stdout] test high_pass_filter::tests::dc_signal_attenuation_16 ... ok
[INFO] [stdout] test gain_controller2::tests::check_recommended_input_volume_when_enabled_and_specific_config ... ok
[INFO] [stdout] test high_pass_filter::tests::dc_signal_attenuation_32 ... ok
[INFO] [stdout] test high_pass_filter::tests::dc_signal_attenuation_48 ... ok
[INFO] [stdout] test high_pass_filter::tests::mono_converged ... ok
[INFO] [stdout] test audio_samples_scaler::tests::verify_gain_adjustment ... ok
[INFO] [stdout] test high_pass_filter::tests::mono_initial ... ok
[INFO] [stdout] test input_volume_controller::tests::check_clipped_level_min_is_effective ... ok
[INFO] [stdout] test gain_controller2::tests::check_saturation_behavior_with_limiter ... ok
[INFO] [stdout] test input_volume_controller::tests::check_min_mic_level_is_effective ... ok
[INFO] [stdout] test input_volume_controller::tests::check_process_empty_rms_error_does_not_lower_volume ... ok
[INFO] [stdout] test gain_controller2::tests::check_recommended_input_volume_when_input_volume_controller_enabled ... ok
[INFO] [stdout] test input_volume_controller::tests::check_process_negative_rms_error_decreases_input_volume ... ok
[INFO] [stdout] test input_volume_controller::tests::check_process_positive_rms_error_increases_input_volume_repeatedly ... ok
[INFO] [stdout] test input_volume_controller::tests::check_process_negative_rms_error_decreases_input_volume_with_limit ... ok
[INFO] [stdout] test input_volume_controller::tests::check_speech_probability_threshold_is_effective ... ok
[INFO] [stdout] test input_volume_controller::tests::check_process_positive_rms_error_increases_input_volume_with_limit ... ok
[INFO] [stdout] test input_volume_controller::tests::check_update_input_volume_wait_frames_is_effective ... ok
[INFO] [stdout] test input_volume_controller::tests::check_handle_clipping_lowers_volume ... ok
[INFO] [stdout] test input_volume_controller::tests::check_process_positive_rms_error_increases_input_volume ... ok
[INFO] [stdout] test input_volume_controller::tests::check_speech_ratio_threshold_is_effective ... ok
[INFO] [stdout] test input_volume_controller::tests::clipped_level_min_enforced_with_clipping_when_above_min_input_volume ... ok
[INFO] [stdout] test input_volume_controller::tests::clipping_does_not_pull_low_volume_back_up ... ok
[INFO] [stdout] test input_volume_controller::tests::clipping_lowers_volume ... ok
[INFO] [stdout] test input_volume_controller::tests::check_process_rms_error_decreases_input_volume_repeatedly ... ok
[INFO] [stdout] test input_volume_controller::tests::clipping_under_threshold_has_no_impact ... ok
[INFO] [stdout] test input_volume_controller::tests::clipping_parameters_verified ... ok
[INFO] [stdout] test input_volume_controller::tests::disable_clipping_predictor_disables_clipping_predictor ... ok
[INFO] [stdout] test input_volume_controller::tests::enable_clipping_predictor_enables_clipping_predictor ... ok
[INFO] [stdout] test input_volume_controller::tests::mic_volume_response_to_rms_error ... ok
[INFO] [stdout] test input_volume_controller::tests::min_volume_repeatedly_respected_when_applied_volume_non_zero ... ok
[INFO] [stdout] test input_volume_controller::tests::mic_volume_is_limited ... ok
[INFO] [stdout] test input_volume_controller::tests::startup_min_volume_configuration_respected_when_applied_input_volume_above_min ... ok
[INFO] [stdout] test input_volume_controller::tests::startup_min_volume_configuration_respected_when_applied_input_volume_maybe_below_min ... ok
[INFO] [stdout] test input_volume_controller::tests::startup_min_volume_respected_once_when_applied_volume_zero ... ok
[INFO] [stdout] test input_volume_controller::tests::startup_min_volume_respected_when_applied_volume_non_zero ... ok
[INFO] [stdout] test input_volume_controller::tests::takes_no_action_on_zero_mic_volume ... ok
[INFO] [stdout] test input_volume_controller::tests::no_action_while_muted ... ok
[INFO] [stdout] test input_volume_controller::tests::unmuting_raises_too_low_volume ... ok
[INFO] [stdout] test input_volume_controller::tests::unmuting_checks_volume_without_raising ... ok
[INFO] [stdout] test input_volume_controller::tests::min_input_volume_enforced_with_clipping_when_above_clipped_level_min ... ok
[INFO] [stdout] test audio_processing::tests::builder_with_config ... ok
[INFO] [stdout] test input_volume_controller::tests::waiting_period_between_clipping_checks ... ok
[INFO] [stdout] test input_volume_controller::tests::clipping_lowering_is_limited ... ok
[INFO] [stdout] test rms_level::tests::no_samples ... ok
[INFO] [stdout] test rms_level::tests::no_samples_average_and_peak ... ok
[INFO] [stdout] test rms_level::tests::only_digital_silence_is_127 ... ok
[INFO] [stdout] test rms_level::tests::poll_twice ... ok
[INFO] [stdout] test rms_level::tests::process_muted ... ok
[INFO] [stdout] test rms_level::tests::reset_on_block_size_change ... ok
[INFO] [stdout] test rms_level::tests::reset_test ... ok
[INFO] [stdout] test rms_level::tests::run_1000hz_full_scale ... ok
[INFO] [stdout] test echo_canceller3::tests::analyze_and_process_mono_16khz ... ok
[INFO] [stdout] test rms_level::tests::run_1000hz_full_scale_average_and_peak ... ok
[INFO] [stdout] test rms_level::tests::run_1000hz_half_scale ... ok
[INFO] [stdout] test rms_level::tests::run_zeros ... ok
[INFO] [stdout] test rms_level::tests::run_zeros_average_and_peak ... ok
[INFO] [stdout] test rms_level::tests::run_half_scale_and_insert_full_scale ... ok
[INFO] [stdout] test splitting_filter::tests::multi_channel_three_band ... ok
[INFO] [stdout] test splitting_filter::tests::multi_channel_two_band ... ok
[INFO] [stdout] test splitting_filter::tests::qmf_analysis_splits_signal ... ok
[INFO] [stdout] test splitting_filter::tests::qmf_synthesis_reconstructs ... ok
[INFO] [stdout] test rms_level::tests::verify_identity_between_float_and_fix ... ok
[INFO] [stdout] test splitting_filter::tests::three_band_zero_input ... ok
[INFO] [stdout] test splitting_filter::tests::two_band_analysis_and_synthesis ... ok
[INFO] [stdout] test splitting_filter::tests::two_band_zero_input ... ok
[INFO] [stdout] test submodule_states::tests::capture_multi_band_with_echo_controller_inactive ... ok
[INFO] [stdout] test audio_processing::tests::stereo_processing_does_not_crash ... ok
[INFO] [stdout] test submodule_states::tests::capture_multi_band_with_hpf ... ok
[INFO] [stdout] test submodule_states::tests::enabling_module_returns_changed ... ok
[INFO] [stdout] test echo_detector::mean_variance_estimator::tests::insert_two_values ... ok
[INFO] [stdout] test submodule_states::tests::first_update_always_returns_changed ... ok
[INFO] [stdout] test submodule_states::tests::high_pass_filtering_required ... ok
[INFO] [stdout] test audio_processing::tests::gain_controller2_processes_multiple_frames ... ok
[INFO] [stdout] test swap_queue::tests::clear_empties_queue ... ok
[INFO] [stdout] test submodule_states::tests::render_multi_band_with_echo_controller ... ok
[INFO] [stdout] test swap_queue::tests::empty_queue_rejects_remove ... ok
[INFO] [stdout] test swap_queue::tests::fifo_ordering ... ok
[INFO] [stdout] test swap_queue::tests::full_queue_rejects_insert ... ok
[INFO] [stdout] test swap_queue::tests::runtime_setting_style_usage ... ok
[INFO] [stdout] test swap_queue::tests::size_at_least ... ok
[INFO] [stdout] test swap_queue::tests::with_prototype ... ok
[INFO] [stdout] test swap_queue::tests::wraparound ... ok
[INFO] [stdout] test swap_queue::tests::basic_insert_remove ... ok
[INFO] [stdout] test three_band_filter_bank::tests::synthesis_produces_output ... ok
[INFO] [stdout] test splitting_filter::tests::splits_into_three_bands_and_reconstructs ... ok
[INFO] [stdout] test three_band_filter_bank::tests::zero_input_produces_zero_output ... ok
[INFO] [stdout] test three_band_filter_bank::tests::analysis_synthesis_roundtrip ... ok
[INFO] [stdout] test three_band_filter_bank::tests::analysis_produces_output ... ok
[INFO] [stdout] test submodule_states::tests::second_update_no_change ... ok
[INFO] [stdout] test residual_echo_detector::tests::echo ... ok
[INFO] [stdout] test residual_echo_detector::tests::no_echo ... ok
[INFO] [stdout] test residual_echo_detector::tests::echo_with_capture_clock_drift ... ok
[INFO] [stdout] test audio_converter::tests::conversions_pass_snr_threshold ... ok
[INFO] [stdout] test residual_echo_detector::tests::echo_with_render_clock_drift ... ok
[INFO] [stdout] test echo_canceller3::tests::analyze_and_process_mono_48khz ... ok
[INFO] [stdout] test input_volume_controller::tests::no_clipping_has_no_impact ... ok
[INFO] [stdout] test capture_levels_adjuster::tests::initial_gain_is_instantly_achieved ... ok
[INFO] [stdout] test capture_levels_adjuster::tests::new_gains_are_achieved ... ok
[INFO] [stdout] test audio_processing::tests::echo_canceller_processes_multiple_frames ... ok
[INFO] [stdout] test audio_processing::tests::all_components_enabled_processes_multiple_frames ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 198 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.24s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests sonora
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test src/audio_processing.rs - audio_processing::AudioProcessingBuilder (line 294) ... ok
[INFO] [stdout] test src/config.rs - config::Config (line 18) ... ok
[INFO] [stdout] test src/../README.md - (line 17) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stdout] all doctests ran in 0.62s; merged doctests compilation took 0.59s
[INFO] running `Command { std: "docker" "inspect" "f8f1dc781b1fce7f8f9cc4583b2cc06578e0bca33e403ee89ef446c622b13de0", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "f8f1dc781b1fce7f8f9cc4583b2cc06578e0bca33e403ee89ef446c622b13de0", kill_on_drop: false }`
[INFO] [stdout] f8f1dc781b1fce7f8f9cc4583b2cc06578e0bca33e403ee89ef446c622b13de0