[INFO] fetching crate rusty_esp_audio-core 0.1.1...
[INFO] testing rusty_esp_audio-core-0.1.1 against 1.99.0-beta.8 for beta-1.100-2
[INFO] extracting crate rusty_esp_audio-core 0.1.1 into /workspace/builds/worker-2-tc1/source
[INFO] started tweaking crates.io crate rusty_esp_audio-core 0.1.1
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate rusty_esp_audio-core 0.1.1
[INFO] tweaked toml for crates.io crate rusty_esp_audio-core 0.1.1 written to /workspace/builds/worker-2-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate rusty_esp_audio-core 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 rusty_esp_audio-core 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 rusty_esp_core v0.1.1
[INFO] [stderr]   Downloaded rusty_flac v0.1.3
[INFO] [stderr]   Downloaded rusty_esp_dsp-esp v0.1.1
[INFO] [stderr]   Downloaded rusty_esp_dsp v0.1.1
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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] d919693a0ad2dad4926a5e64ba65d400d1c8b67c410c9b4ff84b8092d6ed5032
[INFO] running `Command { std: "docker" "start" "d919693a0ad2dad4926a5e64ba65d400d1c8b67c410c9b4ff84b8092d6ed5032", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "d919693a0ad2dad4926a5e64ba65d400d1c8b67c410c9b4ff84b8092d6ed5032", 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" "d919693a0ad2dad4926a5e64ba65d400d1c8b67c410c9b4ff84b8092d6ed5032" "/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" "d919693a0ad2dad4926a5e64ba65d400d1c8b67c410c9b4ff84b8092d6ed5032", 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" "d919693a0ad2dad4926a5e64ba65d400d1c8b67c410c9b4ff84b8092d6ed5032" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling libm v0.2.16
[INFO] [stderr]    Compiling rusty_esp_core v0.1.1
[INFO] [stderr]    Compiling embedded-hal v1.0.0
[INFO] [stderr]    Compiling rusty_esp_dsp v0.1.1
[INFO] [stderr]    Compiling rusty_esp_audio-core v0.1.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 2.48s
[INFO] running `Command { std: "docker" "inspect" "d919693a0ad2dad4926a5e64ba65d400d1c8b67c410c9b4ff84b8092d6ed5032", 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" "d919693a0ad2dad4926a5e64ba65d400d1c8b67c410c9b4ff84b8092d6ed5032" "/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 rusty_esp_audio-core v0.1.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 1.71s
[INFO] running `Command { std: "docker" "inspect" "d919693a0ad2dad4926a5e64ba65d400d1c8b67c410c9b4ff84b8092d6ed5032", 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" "d919693a0ad2dad4926a5e64ba65d400d1c8b67c410c9b4ff84b8092d6ed5032" "/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.04s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/rusty_esp_audio_core-da88d4383bec9794)
[INFO] [stdout] 
[INFO] [stdout] running 62 tests
[INFO] [stdout] test chip::es7210::tests::coefficient_table_and_mic_sets ... ok
[INFO] [stdout] test chip::es7243e::tests::pga_and_the_refusals ... ok
[INFO] [stdout] test chip::es8311::tests::unsupported_clock_pair_is_refused_before_any_write ... ok
[INFO] [stdout] test chip::es7210::tests::init_reproduces_the_vendor_sequence ... ok
[INFO] [stdout] test chip::es8156::tests::init_resume_standby_are_the_vendor_tables_in_order ... ok
[INFO] [stdout] test chip::es8156::tests::mute_volume_format_and_the_refusals ... ok
[INFO] [stdout] test chip::es8388::tests::start_stop_pulse_the_state_machine_only_when_the_clock_register_changes ... ok
[INFO] [stdout] test codec::adpcm_ima::tests::decoder_rejects_bad_headers_and_sizes ... ok
[INFO] [stdout] test chip::es8388::tests::init_reproduces_the_vendor_sequence ... ok
[INFO] [stdout] test chip::es7243e::tests::init_start_stop_are_the_vendor_tables_in_order ... ok
[INFO] [stdout] test chip::es8311::tests::format_start_stop_mute_and_gain ... ok
[INFO] [stdout] test codec::adpcm_ima::tests::round_trip_tracks_a_ramp_closely ... ok
[INFO] [stdout] test chip::es8311::tests::init_reproduces_the_vendor_sequence ... ok
[INFO] [stdout] test chip::es8311::tests::master_mode_and_sclk_derived_mclk_set_their_bits ... ok
[INFO] [stdout] test codec::wav::tests::float_and_ima_headers_round_trip ... ok
[INFO] [stdout] test codec::wav::tests::parse_skips_unknown_chunks_and_rejects_garbage ... ok
[INFO] [stdout] test chip::es8388::tests::master_line2_and_a_microphone_set_their_registers ... ok
[INFO] [stdout] test codec::wav::tests::pcm16_header_is_the_canonical_44_bytes ... ok
[INFO] [stdout] test chip::es8311::tests::volume_register_is_half_db_from_zero_at_bf ... ok
[INFO] [stdout] test chip::es7210::tests::four_mics_in_master_mode_turn_on_tdm_and_the_doubler ... ok
[INFO] [stdout] test chip::es8388::tests::format_width_rate_volume_gain_and_bypass ... ok
[INFO] [stdout] test chip::tests::write_read_update_apply ... ok
[INFO] [stdout] test codec::adpcm_ima::tests::closed_loop_encoder_predictor_equals_decoder_output ... ok
[INFO] [stdout] test chip::es8311::tests::chip_id_reads_the_id_registers ... ok
[INFO] [stdout] test elements::channels::tests::wrong_channel_counts_are_refused ... ok
[INFO] [stdout] test codec::adpcm_ima::tests::sizes_match_the_wav_convention ... ok
[INFO] [stdout] test codec::adpcm_ima::tests::stereo_interleaves_by_word_and_step_index_carries ... ok
[INFO] [stdout] test codec::adpcm_ima::tests::the_two_expansions_differ_by_truncation_only ... ok
[INFO] [stdout] test elements::biquad::tests::design_rejects_out_of_band ... ok
[INFO] [stdout] test elements::channels::tests::downmix_floors_and_mix_saturates ... ok
[INFO] [stdout] test elements::channels::tests::up_and_down_round_trip ... ok
[INFO] [stdout] test chip::es7210::tests::format_start_stop_and_mute ... ok
[INFO] [stdout] test elements::gain::tests::saturates ... ok
[INFO] [stdout] test elements::gain::tests::unity_is_exact_and_half_rounds ... ok
[INFO] [stdout] test elements::dc_block::tests::passes_a_1khz_tone_within_one_percent ... ok
[INFO] [stdout] test elements::resample::tests::awkward_ratio_has_no_long_run_drift ... ok
[INFO] [stdout] test chip::es8311::tests::coefficient_table_is_complete_and_findable ... ok
[INFO] [stdout] test chip::es8388::tests::volume_and_ratio_tables ... ok
[INFO] [stdout] test elements::convert::tests::converts_inside_a_pipeline ... ok
[INFO] [stdout] test elements::dc_block::tests::removes_dc_within_a_quarter_second ... ok
[INFO] [stdout] test elements::gain::tests::processes_blocks ... ok
[INFO] [stdout] test elements::resample::tests::downsampling_a_constant_is_constant_and_counts_frames ... ok
[INFO] [stdout] test elements::vad::tests::quiet_noise_stays_below_threshold ... ok
[INFO] [stdout] test elements::resample::tests::upsampling_a_ramp_interpolates_exactly ... ok
[INFO] [stdout] test pipeline::tests::three_stages_ping_pong_and_change_format ... ok
[INFO] [stdout] test elements::resample::tests::same_rate_is_a_copy_and_wrong_rate_is_refused ... ok
[INFO] [stdout] test pipeline::tests::empty_output_and_small_scratch_are_reported ... ok
[INFO] [stdout] test pipeline::tests::zero_stages_copies ... ok
[INFO] [stdout] test ring::tests::pop_exact_waits_for_a_block ... ok
[INFO] [stdout] test ring::tests::wraps_and_counts ... ok
[INFO] [stdout] test ring::tests::rejects_misaligned_and_oversize ... ok
[INFO] [stdout] test elements::vad::tests::tone_then_silence_with_hangover ... ok
[INFO] [stdout] test ring::tests::overwrite_discards_oldest ... ok
[INFO] [stdout] test source::tests::counting_sink_holds_its_format ... ok
[INFO] [stdout] test source::tests::sine_is_periodic_and_timestamped ... ok
[INFO] [stdout] test source::tests::sine_rejects_bad_setups ... ok
[INFO] [stdout] test tests::rounding_and_saturation ... ok
[INFO] [stdout] test elements::agc::tests::loud_input_is_pulled_down_fast ... ok
[INFO] [stdout] test elements::biquad::tests::peak_and_notch_do_what_they_say ... ok
[INFO] [stdout] test elements::biquad::tests::butterworth_corner_is_minus_three_db ... ok
[INFO] [stdout] test elements::agc::tests::quiet_input_is_raised_to_target ... ok
[INFO] [stdout] test elements::agc::tests::gain_is_capped_and_silence_freezes_it ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 62 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.17s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/element_oracle.rs (/opt/rustwide/target/debug/deps/element_oracle-4eece28b6d941a1c)
[INFO] [stdout] 
[INFO] [stdout] running 6 tests
[INFO] [stdout] test resampler_arms_agree_including_the_misaligned_one ... ok
[INFO] [stdout] test resampler_is_byte_identical ... ok
[INFO] [stdout] test the_voice_front_end_composes_and_sizes_its_scratch ... ok
[INFO] [stdout] test stateless_elements_are_byte_identical ... ok
[INFO] [stdout] test stateful_elements_are_byte_identical ... ok
[INFO] [stdout] test every_two_armed_element_agrees_with_itself ... ok
[INFO] [stdout] 
[INFO] [stderr]      Running tests/no_panic.rs (/opt/rustwide/target/debug/deps/no_panic-35d18c58fd63702d)
[INFO] [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test wav_header_parse_never_panics ... ok
[INFO] [stdout] test adpcm_decoder_never_panics ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.34s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/round_sat16_exhaustive.rs (/opt/rustwide/target/debug/deps/round_sat16_exhaustive-a1ff2e1befe734ad)
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test round_sat16_matches_libm_exhaustively ... ignored, sweeps all 2^32 f32 bit patterns; run with --release --ignored
[INFO] [stdout] test round_sat16_matches_at_the_boundaries ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 1 ignored; 0 measured; 0 filtered out; finished in 0.03s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests rusty_esp_audio_core
[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" "d919693a0ad2dad4926a5e64ba65d400d1c8b67c410c9b4ff84b8092d6ed5032", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "d919693a0ad2dad4926a5e64ba65d400d1c8b67c410c9b4ff84b8092d6ed5032", kill_on_drop: false }`
[INFO] [stdout] d919693a0ad2dad4926a5e64ba65d400d1c8b67c410c9b4ff84b8092d6ed5032
