[INFO] fetching crate utter 0.0.6...
[INFO] testing utter-0.0.6 against 1.100.0-beta.1 for beta-1.100-2
[INFO] extracting crate utter 0.0.6 into /workspace/builds/worker-5-tc2/source
[INFO] started tweaking crates.io crate utter 0.0.6
[INFO] removed 0 missing examples
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate utter 0.0.6
[INFO] tweaked toml for crates.io crate utter 0.0.6 written to /workspace/builds/worker-5-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate utter 0.0.6 on toolchain 1.100.0-beta.1
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.100.0-beta.1" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate utter 0.0.6 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.100.0-beta.1" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/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] b1195c1d2e945f01526797dbabc90615c5591d40828af62e9a4ac7412366cb15
[INFO] running `Command { std: "docker" "start" "b1195c1d2e945f01526797dbabc90615c5591d40828af62e9a4ac7412366cb15", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "b1195c1d2e945f01526797dbabc90615c5591d40828af62e9a4ac7412366cb15", 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" "b1195c1d2e945f01526797dbabc90615c5591d40828af62e9a4ac7412366cb15" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "b1195c1d2e945f01526797dbabc90615c5591d40828af62e9a4ac7412366cb15", 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" "b1195c1d2e945f01526797dbabc90615c5591d40828af62e9a4ac7412366cb15" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling utter v0.0.6 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 6.55s
[INFO] running `Command { std: "docker" "inspect" "b1195c1d2e945f01526797dbabc90615c5591d40828af62e9a4ac7412366cb15", 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" "b1195c1d2e945f01526797dbabc90615c5591d40828af62e9a4ac7412366cb15" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling utter v0.0.6 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 11.29s
[INFO] running `Command { std: "docker" "inspect" "b1195c1d2e945f01526797dbabc90615c5591d40828af62e9a4ac7412366cb15", 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" "b1195c1d2e945f01526797dbabc90615c5591d40828af62e9a4ac7412366cb15" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen", kill_on_drop: false }`
[INFO] [stderr]    Compiling utter v0.0.6 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 11.66s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/build/utter/dcbbf83b58c3e4a5/out/utter-dcbbf83b58c3e4a5)
[INFO] [stdout] 
[INFO] [stdout] running 54 tests
[INFO] [stdout] test fbank::tests::a_constant_band_normalises_to_zero ... ok
[INFO] [stdout] test fst::tests::connect_drops_dead_ends ... ok
[INFO] [stdout] test recognizer::tests::a_final_read_alone_pools_every_row ... ok
[INFO] [stdout] test fbank::tests::frames_fit_wholly ... ok
[INFO] [stdout] test fbank::tests::bands_are_slaney_triangles_to_7600_hz ... ok
[INFO] [stdout] test compose::tests::pushes_label_and_weight_onto_the_epsilon_arc ... ok
[INFO] [stdout] test fbank::tests::a_zero_frame_is_the_log_floor ... ok
[INFO] [stdout] test grammar::tests::single_word_sentences_share_a_unigram_state ... ok
[INFO] [stdout] test recognizer::tests::a_fraction_of_a_count_has_a_level ... ok
[INFO] [stdout] test recognizer::tests::a_constant_signal_reads_its_own_level ... ok
[INFO] [stdout] test recognizer::tests::a_long_utterance_pools_only_the_kept_rows ... ok
[INFO] [stdout] test json::tests::every_escape_and_every_malformed_shape ... ok
[INFO] [stdout] test json::tests::parses_strings_with_escapes ... ok
[INFO] [stdout] test gemm::tests::matches_naive ... ok
[INFO] [stdout] test json::tests::writes_control_characters_as_escapes ... ok
[INFO] [stdout] test recognizer::tests::a_recognizer_can_be_shared_across_threads ... ok
[INFO] [stdout] test recognizer::tests::rows_ahead_of_the_chunk_give_every_result_as_rows_computed_eagerly ... ok
[INFO] [stdout] test recognizer::tests::full_scale_and_everything_within_it_is_accepted ... ok
[INFO] [stdout] test fft::tests::matches_naive_dft ... ok
[INFO] [stdout] test recognizer::tests::history_stays_integer_until_a_sample_is_not_one ... ok
[INFO] [stdout] test recognizer::tests::one_surviving_reading_has_no_lead_and_no_delta ... ok
[INFO] [stdout] test recognizer::tests::titanet_results_less_the_four_keys_are_the_results_without_a_model ... ok
[INFO] [stdout] test recognizer::tests::titanet_set_mid_stream_gives_the_same_evidence ... ok
[INFO] [stdout] test recognizer::tests::a_relation_is_read_off_the_labels ... ok
[INFO] [stdout] test recognizer::tests::a_reading_is_born_carried_dropped_and_born_again ... ok
[INFO] [stdout] test recognizer::tests::titanet_thread_switched_mid_stream_gives_the_same_evidence ... ok
[INFO] [stdout] test recognizer::tests::titanet_thread_lagging_or_leading_gives_the_same_results ... ok
[INFO] [stdout] test recognizer::tests::the_floor_is_absent_until_a_window_exists ... ok
[INFO] [stdout] test recognizer::tests::rows_on_demand_give_every_result_as_rows_computed_eagerly ... ok
[INFO] [stdout] test recognizer::tests::titanet_evidence_is_the_stateless_embedding_in_any_blocks_and_slices ... ok
[INFO] [stdout] test sound::tests::the_certainty_is_the_normalised_entropy ... ok
[INFO] [stdout] test recognizer::tests::the_first_sample_refused_is_named_with_what_is_wrong_with_it ... ok
[INFO] [stdout] test speaker::tests::rows_asked_for_in_any_order_pool_as_every_row_does ... ok
[INFO] [stdout] test resample::tests::output_waits_for_the_window_and_flush_completes_it ... ok
[INFO] [stdout] test gemm::tests::the_portable_kernels_keep_the_contract ... ok
[INFO] [stdout] test resample::tests::blocks_do_not_change_the_output ... ok
[INFO] [stdout] test resample::tests::passes_the_low_band_and_stops_the_high ... ok
[INFO] [stdout] test recognizer::tests::the_history_forgets_a_louder_room ... ok
[INFO] [stdout] test titanet::tests::another_front_end_or_dimension_is_refused ... ok
[INFO] [stdout] test titanet::tests::models_and_jobs_cross_threads ... ok
[INFO] [stdout] test recognizer::tests::a_quarter_second_of_digital_silence_does_not_take_the_floor ... ok
[INFO] [stdout] test titanet::tests::only_titanet_small_opens ... ok
[INFO] [stdout] test titanet::tests::malformed_weights_are_refused ... ok
[INFO] [stdout] test recognizer::tests::a_floor_of_digital_silence_is_no_floor ... ok
[INFO] [stdout] test titanet::tests::work_is_counted_in_frames_not_time ... ok
[INFO] [stdout] test titanet::tests::a_job_starts_over_on_begin ... ok
[INFO] [stdout] test sound::tests::the_exponential_is_within_two_ulps ... ok
[INFO] [stdout] test titanet::tests::a_tiny_model_embeds_the_same_bytes_in_any_slices ... ok
[INFO] [stdout] test gemm::tests::accumulation_order_is_the_contract ... ok
[INFO] [stdout] test sound::tests::the_avx2_sums_are_the_portable_sums_bit_for_bit ... ok
[INFO] [stdout] test sound::tests::each_floor_is_its_bands_hop_at_the_rank ... ok
[INFO] [stdout] test sound::tests::digital_silence_has_no_floor ... ok
[INFO] [stdout] test sound::tests::hiss_hum_and_click_are_told_apart ... ok
[INFO] [stdout] test sound::tests::fixed_decimals_are_written_as_the_formatter_writes_them ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 54 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 2.54s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/bin/g0.rs (/opt/rustwide/target/debug/build/utter/ba1f869a1a931abb/out/g0-ba1f869a1a931abb)
[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] [stderr]      Running unittests src/bin/ivector_dump.rs (/opt/rustwide/target/debug/build/utter/9e11d17a4b0e0dd7/out/ivector_dump-9e11d17a4b0e0dd7)
[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] [stderr]      Running unittests src/bin/mfcc_dump.rs (/opt/rustwide/target/debug/build/utter/b9cab0d4ba8100df/out/mfcc_dump-b9cab0d4ba8100df)
[INFO] [stderr]      Running unittests src/bin/profile.rs (/opt/rustwide/target/debug/build/utter/14e01f5b70a2dfe0/out/profile-14e01f5b70a2dfe0)
[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 0 tests
[INFO] [stderr]      Running unittests src/bin/spk_dump.rs (/opt/rustwide/target/debug/build/utter/278c5d666de82a72/out/spk_dump-278c5d666de82a72)
[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] [stderr]      Running unittests src/bin/stream.rs (/opt/rustwide/target/debug/build/utter/d426e5d66a2b570a/out/stream-d426e5d66a2b570a)
[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 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] [stderr]      Running unittests src/bin/titanet_dump.rs (/opt/rustwide/target/debug/build/utter/356e70871fab82a0/out/titanet_dump-356e70871fab82a0)
[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] [stderr]      Running tests/float_input.rs (/opt/rustwide/target/debug/build/utter/62f19da1d1c90456/out/float_input-62f19da1d1c90456)
[INFO] [stdout] 
[INFO] [stdout] running 8 tests
[INFO] [stdout] test integer_samples_as_floats_give_what_integers_give ... ok
[INFO] [stdout] test the_two_entry_points_interleave_on_one_recognizer ... ok
[INFO] [stdout] test digital_silence_has_no_level_through_either_entry_point ... ok
[INFO] [stdout] test full_scale_is_one_and_reads_zero_dbfs ... ok
[INFO] [stdout] test a_fraction_of_a_count_reaches_the_features_and_the_energy ... ok
[INFO] [stdout] test a_host_composes_an_upstream_processor_with_the_recognizer ... ok
[INFO] [stdout] test a_refused_block_changes_nothing ... ok
[INFO] [stdout] test an_empty_block_keeps_the_lifecycle_and_does_not_move_the_clock ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 8 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/model.rs (/opt/rustwide/target/debug/build/utter/e107faaec6c17dff/out/model-e107faaec6c17dff)
[INFO] [stdout] 
[INFO] [stdout] running 29 tests
[INFO] [stdout] test c_abi_names_the_runtime ... ok
[INFO] [stdout] test c_abi_round_trip ... ok
[INFO] [stdout] test a_final_word_carries_the_energy_under_its_span ... ok
[INFO] [stdout] test a_final_says_what_closed_it_and_how_long_its_words_held ... ok
[INFO] [stdout] test every_decoded_frame_is_rated_by_one_result ... ok
[INFO] [stdout] test clips_decode_to_their_words ... ok
[INFO] [stdout] test each_word_entry_certainty_does_not_depend_on_when_results_are_read ... ok
[INFO] [stdout] test c_abi_speaker_round_trip ... ok
[INFO] [stdout] test grammar_bounds_and_unknown_option ... ok
[INFO] [stdout] test batch_decoder_reads_the_clip ... ok
[INFO] [stdout] test one_grammar_is_composed_once_and_the_cache_has_a_bound ... ok
[INFO] [stdout] test model_opens_and_reports_its_shape ... ok
[INFO] [stdout] test silence_weighting_can_be_turned_off_for_the_kaldi_comparison ... ok
[INFO] [stdout] test speaker_evidence_needs_the_models_rate_and_goes_when_removed ... ok
[INFO] [stdout] test speaker_evidence_rides_every_word_list_whatever_the_blocks ... ok
[INFO] [stdout] test silence_reads_as_sil_and_the_stream_is_deterministic ... ok
[INFO] [stdout] test readings_carry_their_relation_and_their_leads_motion ... ok
[INFO] [stdout] test stream_tool_writes_one_line_per_take ... ok
[INFO] [stdout] test the_alternatives_shape_carries_its_first_alternatives_rating_at_its_top ... ok
[INFO] [stdout] test one_accept_over_several_chunks_samples_each_of_them ... ok
[INFO] [stdout] test noise_held_as_an_open_speech_entry_carries_the_sound ... ok
[INFO] [stdout] test the_bound_with_a_floor_margin_closes_a_wordless_stretch_without_a_word ... ok
[INFO] [stdout] test the_certainty_does_not_depend_on_the_grammar ... ok
[INFO] [stdout] test the_certainty_holds_across_input_levels ... ok
[INFO] [stdout] test the_floor_outlives_a_final_and_the_rebuild_after_it ... ok
[INFO] [stdout] test the_readings_are_sampled_once_per_chunk_whatever_is_asked_of_them ... ok
[INFO] [stdout] test a_speaker_model_set_after_a_final_replays_the_utterance_kept ... ok
[INFO] [stdout] test a_wordless_reading_names_what_its_tail_is ... ok
[INFO] [stdout] test an_unimplemented_component_is_refused_at_load ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 29 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/titanet.rs (/opt/rustwide/target/debug/build/utter/b6320b4d14dbca1d/out/titanet-b6320b4d14dbca1d)
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test embeddings_match_the_references ... ok
[INFO] [stdout] test a_speaker_model_opens_titanet_and_embeds_through_the_c_abi ... ok
[INFO] [stdout] test spans_it_cannot_embed_are_refused ... ok
[INFO] [stdout] test slices_give_the_bytes_of_one_call ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/units.rs (/opt/rustwide/target/debug/build/utter/53ed5ea96b4e68f0/out/units-53ed5ea96b4e68f0)
[INFO] [stdout] 
[INFO] [stdout] running 10 tests
[INFO] [stdout] test a_transition_model_claims_no_more_tuples_than_it_holds ... ok
[INFO] [stdout] test an_ivector_extractor_without_matrices_is_an_error ... ok
[INFO] [stdout] test json_writer_escapes ... ok
[INFO] [stdout] test silence_weighting_deltas ... ok
[INFO] [stdout] test a_kaldi_length_buys_no_more_than_the_bytes_behind_it ... ok
[INFO] [stdout] test an_fst_states_what_it_holds_and_points_only_inside_it ... ok
[INFO] [stdout] test decoder_traces_words_phones_and_trailing_silence ... ok
[INFO] [stdout] test wav_reader_reads_pcm16 ... ok
[INFO] [stdout] test kaldi_binary_and_text_forms ... ok
[INFO] [stdout] test mfcc_frame_count_and_shape ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 10 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.09s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests utter
[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.06s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "b1195c1d2e945f01526797dbabc90615c5591d40828af62e9a4ac7412366cb15", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "b1195c1d2e945f01526797dbabc90615c5591d40828af62e9a4ac7412366cb15", kill_on_drop: false }`
[INFO] [stdout] b1195c1d2e945f01526797dbabc90615c5591d40828af62e9a4ac7412366cb15
