[INFO] fetching crate resonant-stream 0.2.0...
[INFO] testing resonant-stream-0.2.0 against try#dec9417b8611e34e787a3e4c37686b5131f9e5c5 for pr-154210-2
[INFO] extracting crate resonant-stream 0.2.0 into /workspace/builds/worker-7-tc2/source
[INFO] started tweaking crates.io crate resonant-stream 0.2.0
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate resonant-stream 0.2.0
[INFO] tweaked toml for crates.io crate resonant-stream 0.2.0 written to /workspace/builds/worker-7-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate resonant-stream 0.2.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 resonant-stream 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" "+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] a48a5201397f8ddbd817f9acd7cb2b041baa13ee1f6f191d88d3fec885a4674e
[INFO] running `Command { std: "docker" "start" "-a" "a48a5201397f8ddbd817f9acd7cb2b041baa13ee1f6f191d88d3fec885a4674e", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "a48a5201397f8ddbd817f9acd7cb2b041baa13ee1f6f191d88d3fec885a4674e", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "a48a5201397f8ddbd817f9acd7cb2b041baa13ee1f6f191d88d3fec885a4674e", kill_on_drop: false }`
[INFO] [stdout] a48a5201397f8ddbd817f9acd7cb2b041baa13ee1f6f191d88d3fec885a4674e
[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] 5b00dabac94b98788005e22c0f086a4cefcebffa450638c0ece315f1574cf229
[INFO] running `Command { std: "docker" "start" "-a" "5b00dabac94b98788005e22c0f086a4cefcebffa450638c0ece315f1574cf229", kill_on_drop: false }`
[INFO] [stderr]    Compiling num-traits v0.2.19
[INFO] [stderr]    Compiling strength_reduce v0.2.4
[INFO] [stderr]    Compiling num-integer v0.1.46
[INFO] [stderr]    Compiling num-complex v0.4.6
[INFO] [stderr]    Compiling resonant-core v0.2.0
[INFO] [stderr]    Compiling primal-check v0.3.4
[INFO] [stderr]    Compiling transpose v0.2.3
[INFO] [stderr]    Compiling rustfft v6.4.1
[INFO] [stderr]    Compiling resonant-fft v0.2.0
[INFO] [stderr]    Compiling resonant-filters v0.2.0
[INFO] [stderr]    Compiling resonant-stream v0.2.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 26.75s
[INFO] running `Command { std: "docker" "inspect" "5b00dabac94b98788005e22c0f086a4cefcebffa450638c0ece315f1574cf229", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "5b00dabac94b98788005e22c0f086a4cefcebffa450638c0ece315f1574cf229", kill_on_drop: false }`
[INFO] [stdout] 5b00dabac94b98788005e22c0f086a4cefcebffa450638c0ece315f1574cf229
[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] 6eafd06dba97b10789f8d32a1a8275a89cf1a7ddf944f80a947c8969be0ae54f
[INFO] running `Command { std: "docker" "start" "-a" "6eafd06dba97b10789f8d32a1a8275a89cf1a7ddf944f80a947c8969be0ae54f", kill_on_drop: false }`
[INFO] [stderr]    Compiling resonant-stream v0.2.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 1.42s
[INFO] running `Command { std: "docker" "inspect" "6eafd06dba97b10789f8d32a1a8275a89cf1a7ddf944f80a947c8969be0ae54f", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "6eafd06dba97b10789f8d32a1a8275a89cf1a7ddf944f80a947c8969be0ae54f", kill_on_drop: false }`
[INFO] [stdout] 6eafd06dba97b10789f8d32a1a8275a89cf1a7ddf944f80a947c8969be0ae54f
[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] e429e8c0ae4b437fc80ee84c7f57b8ab82c74b5c2085c0ec7630b258f6cc31ac
[INFO] running `Command { std: "docker" "start" "-a" "e429e8c0ae4b437fc80ee84c7f57b8ab82c74b5c2085c0ec7630b258f6cc31ac", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.02s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/resonant_stream-e6ba16e512b0e010)
[INFO] [stdout] 
[INFO] [stdout] running 94 tests
[INFO] [stdout] test chunk::tests::data_mut_allows_modification ... ok
[INFO] [stdout] test chunk::tests::empty_chunk ... ok
[INFO] [stdout] test chunk::tests::data_access ... ok
[INFO] [stdout] test chunk::tests::new_stereo_chunk ... ok
[INFO] [stdout] test chunk::tests::set_data_replaces_buffer ... ok
[INFO] [stdout] test error::tests::clone_and_eq ... ok
[INFO] [stdout] test error::tests::display_channel_mismatch ... ok
[INFO] [stdout] test error::tests::display_empty_input ... ok
[INFO] [stdout] test error::tests::display_processing_error ... ok
[INFO] [stdout] test error::tests::display_sample_rate_mismatch ... ok
[INFO] [stdout] test graph::tests::graph_ext_parallel_method ... ok
[INFO] [stdout] test graph::tests::graph_ext_serial_method ... ok
[INFO] [stdout] test graph::tests::graph_ext_stack_method ... ok
[INFO] [stdout] test graph::tests::parallel_identity_doubles ... ok
[INFO] [stdout] test graph::tests::parallel_reset_propagates ... ok
[INFO] [stdout] test graph::tests::parallel_sums_outputs ... ok
[INFO] [stdout] test graph::tests::serial_associativity ... ok
[INFO] [stdout] test chunk::tests::clone_and_eq ... ok
[INFO] [stdout] test graph::tests::serial_chains_nodes ... ok
[INFO] [stdout] test graph::tests::serial_error_propagates ... ok
[INFO] [stdout] test graph::tests::serial_matches_manual_pipeline ... ok
[INFO] [stdout] test graph::tests::serial_reset_propagates ... ok
[INFO] [stdout] test graph::tests::stack_output_length_is_sum ... ok
[INFO] [stdout] test node::tests::passthrough_handles_empty ... ok
[INFO] [stdout] test graph::tests::stack_concatenates_outputs ... ok
[INFO] [stdout] test node::tests::passthrough_preserves_metadata ... ok
[INFO] [stdout] test node::tests::passthrough_preserves_data ... ok
[INFO] [stdout] test node::tests::trait_is_object_safe ... ok
[INFO] [stdout] test node::tests::trait_is_send ... ok
[INFO] [stdout] test nodes::fft::tests::empty_chunk_passthrough ... ok
[INFO] [stdout] test nodes::fft::tests::default_is_magnitude ... ok
[INFO] [stdout] test nodes::fft::tests::dc_signal_energy_in_bin_zero ... ok
[INFO] [stdout] test nodes::fft::tests::non_power_of_two_fails ... ok
[INFO] [stdout] test nodes::fft::tests::power_output_mode ... ok
[INFO] [stdout] test nodes::fft::tests::preserves_metadata ... ok
[INFO] [stdout] test chunk::tests::new_mono_chunk ... ok
[INFO] [stdout] test graph::tests::stack_reset_propagates ... ok
[INFO] [stdout] test nodes::filter::tests::filter_accessor ... ok
[INFO] [stdout] test nodes::filter::tests::filter_mut_accessor ... ok
[INFO] [stdout] test nodes::filter::tests::reset_clears_state ... ok
[INFO] [stdout] test nodes::filter::tests::unity_filter_passthrough ... ok
[INFO] [stdout] test nodes::filter::tests::zero_coeffs_silences ... ok
[INFO] [stdout] test nodes::gain::tests::empty_chunk ... ok
[INFO] [stdout] test nodes::gain::tests::half_gain ... ok
[INFO] [stdout] test nodes::gain::tests::negative_gain_inverts ... ok
[INFO] [stdout] test nodes::gain::tests::preserves_metadata ... ok
[INFO] [stdout] test nodes::gain::tests::set_gain_changes_value ... ok
[INFO] [stdout] test nodes::gain::tests::stereo_data ... ok
[INFO] [stdout] test chunk::tests::into_data_returns_buffer ... ok
[INFO] [stdout] test nodes::gain::tests::unity_gain ... ok
[INFO] [stdout] test nodes::gain::tests::zero_gain_silences ... ok
[INFO] [stdout] test nodes::mix::tests::default_trait ... ok
[INFO] [stdout] test nodes::fft::tests::sine_wave_has_peak ... ok
[INFO] [stdout] test nodes::filter::tests::empty_chunk ... ok
[INFO] [stdout] test nodes::filter::tests::preserves_metadata ... ok
[INFO] [stdout] test nodes::mix::tests::empty_chunk ... ok
[INFO] [stdout] test nodes::mix::tests::preserves_sample_rate ... ok
[INFO] [stdout] test nodes::mix::tests::stereo_silence ... ok
[INFO] [stdout] test nodes::mix::tests::stereo_to_mono ... ok
[INFO] [stdout] test nodes::resample::tests::dc_preserved ... ok
[INFO] [stdout] test nodes::resample::tests::decimate_by_2 ... ok
[INFO] [stdout] test nodes::resample::tests::decimate_by_3 ... ok
[INFO] [stdout] test nodes::mix::tests::mono_passthrough ... ok
[INFO] [stdout] test nodes::mix::tests::multiple_frames_stereo ... ok
[INFO] [stdout] test nodes::mix::tests::quad_to_mono ... ok
[INFO] [stdout] test nodes::resample::tests::high_freq_attenuated ... ok
[INFO] [stdout] test nodes::resample::tests::in_pipeline ... ok
[INFO] [stdout] test nodes::resample::tests::preserves_channel_count ... ok
[INFO] [stdout] test nodes::tap::tests::tap_called_every_process ... ok
[INFO] [stdout] test nodes::tap::tests::tap_empty_chunk ... ok
[INFO] [stdout] test nodes::tap::tests::tap_in_pipeline ... ok
[INFO] [stdout] test nodes::tap::tests::tap_is_send ... ok
[INFO] [stdout] test nodes::tap::tests::tap_observes_without_modifying ... ok
[INFO] [stdout] test nodes::tap::tests::tap_preserves_metadata ... ok
[INFO] [stdout] test pipeline::tests::accessors ... ok
[INFO] [stdout] test pipeline::tests::both_validations_rate_fails_first ... ok
[INFO] [stdout] test pipeline::tests::chained_nodes ... ok
[INFO] [stdout] test pipeline::tests::channel_validation_fail ... ok
[INFO] [stdout] test pipeline::tests::channel_validation_pass ... ok
[INFO] [stdout] test pipeline::tests::empty_pipeline_passthrough ... ok
[INFO] [stdout] test pipeline::tests::len_and_is_empty ... ok
[INFO] [stdout] test pipeline::tests::no_validation_when_unconfigured ... ok
[INFO] [stdout] test pipeline::tests::node_error_propagates ... ok
[INFO] [stdout] test pipeline::tests::push_after_build ... ok
[INFO] [stdout] test pipeline::tests::reset_all_nodes ... ok
[INFO] [stdout] test pipeline::tests::sample_rate_validation_fail ... ok
[INFO] [stdout] test pipeline::tests::sample_rate_validation_pass ... ok
[INFO] [stdout] test pipeline::tests::single_node ... ok
[INFO] [stdout] test nodes::resample::tests::factor_accessor ... ok
[INFO] [stdout] test nodes::fft::tests::output_length_matches_input ... ok
[INFO] [stdout] test nodes::resample::tests::empty_chunk ... ok
[INFO] [stdout] test chunk::tests::zero_sample_rate_panics - should panic ... ok
[INFO] [stdout] test chunk::tests::zero_channels_panics - should panic ... ok
[INFO] [stdout] test nodes::resample::tests::factor_below_2_panics - should panic ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 94 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.13s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/pipeline_throughput.rs (/opt/rustwide/target/debug/deps/pipeline_throughput-72aeca85888a3369)
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test one_second_three_node_pipeline ... ok
[INFO] [stdout] test ten_seconds_chunked_processing ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests resonant_stream
[INFO] [stdout] 
[INFO] [stdout] running 15 tests
[INFO] [stdout] test src/nodes/gain.rs - nodes::gain::GainNode (line 12) ... ok
[INFO] [stdout] test src/graph.rs - graph::GraphExt (line 140) ... ok
[INFO] [stdout] test src/nodes/filter.rs - nodes::filter::FilterNode (line 15) ... ok
[INFO] [stdout] test src/nodes/fft.rs - nodes::fft::FftNode (line 32) ... ok
[INFO] [stdout] test src/lib.rs - graph (line 62) ... ok
[INFO] [stdout] test src/chunk.rs - chunk::Chunk (line 16) ... ok
[INFO] [stdout] test src/lib.rs - (line 24) ... ok
[INFO] [stdout] test src/node.rs - node::DspNode (line 19) ... ok
[INFO] [stdout] test src/nodes/resample.rs - nodes::resample::ResampleNode (line 20) ... ok
[INFO] [stdout] test src/pipeline.rs - pipeline::Pipeline (line 18) ... ok
[INFO] [stdout] test src/pipeline.rs - pipeline::Pipeline::from_graph (line 149) ... ok
[INFO] [stdout] test src/pipeline.rs - pipeline::Pipeline::process (line 58) ... ok
[INFO] [stdout] test src/nodes/mix.rs - nodes::mix::MixNode (line 16) ... ok
[INFO] [stdout] test src/nodes/tap.rs - nodes::tap::TapNode (line 17) ... ok
[INFO] [stdout] test src/pipeline.rs - pipeline::PipelineBuilder (line 180) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 15 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 2.11s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "e429e8c0ae4b437fc80ee84c7f57b8ab82c74b5c2085c0ec7630b258f6cc31ac", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "e429e8c0ae4b437fc80ee84c7f57b8ab82c74b5c2085c0ec7630b258f6cc31ac", kill_on_drop: false }`
[INFO] [stdout] e429e8c0ae4b437fc80ee84c7f57b8ab82c74b5c2085c0ec7630b258f6cc31ac