[INFO] fetching crate valverig-nam 0.0.1...
[INFO] testing valverig-nam-0.0.1 against 1.100.0-beta.1 for beta-1.100-2
[INFO] extracting crate valverig-nam 0.0.1 into /workspace/builds/worker-5-tc2/source
[INFO] started tweaking crates.io crate valverig-nam 0.0.1
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate valverig-nam 0.0.1
[INFO] tweaked toml for crates.io crate valverig-nam 0.0.1 written to /workspace/builds/worker-5-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate valverig-nam 0.0.1 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 valverig-nam 0.0.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.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] 95bb43843aad49861f49a58318620e74a8b4d91bd510a97570fc3d7e0e8975a7
[INFO] running `Command { std: "docker" "start" "95bb43843aad49861f49a58318620e74a8b4d91bd510a97570fc3d7e0e8975a7", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "95bb43843aad49861f49a58318620e74a8b4d91bd510a97570fc3d7e0e8975a7", 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" "95bb43843aad49861f49a58318620e74a8b4d91bd510a97570fc3d7e0e8975a7" "/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" "95bb43843aad49861f49a58318620e74a8b4d91bd510a97570fc3d7e0e8975a7", 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" "95bb43843aad49861f49a58318620e74a8b4d91bd510a97570fc3d7e0e8975a7" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling serde_json v1.0.151
[INFO] [stderr]    Compiling valverig-nam v0.0.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 5.02s
[INFO] running `Command { std: "docker" "inspect" "95bb43843aad49861f49a58318620e74a8b4d91bd510a97570fc3d7e0e8975a7", 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" "95bb43843aad49861f49a58318620e74a8b4d91bd510a97570fc3d7e0e8975a7" "/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 valverig-nam v0.0.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 5.78s
[INFO] running `Command { std: "docker" "inspect" "95bb43843aad49861f49a58318620e74a8b4d91bd510a97570fc3d7e0e8975a7", 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" "95bb43843aad49861f49a58318620e74a8b4d91bd510a97570fc3d7e0e8975a7" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.06s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/build/valverig-nam/3aab82cd433c0b48/out/valverig_nam-3aab82cd433c0b48)
[INFO] [stdout] 
[INFO] [stdout] running 120 tests
[INFO] [stdout] test activations::tests::fast_tanh_matches_pinned_reference_bits ... ok
[INFO] [stdout] test buffer::tests::column_major_indexing ... ok
[INFO] [stdout] test container::tests::a_sequential_prewarm_length_is_the_sum_of_its_children ... ok
[INFO] [stdout] test container::tests::a_sequential_refuses_children_whose_channels_do_not_meet ... ok
[INFO] [stdout] test buffer::tests::channel_slices_round_trip_and_surplus_channels_are_ignored ... ok
[INFO] [stdout] test container::tests::a_sequential_chains_children_in_order ... ok
[INFO] [stdout] test container::tests::each_submodel_owns_a_half_open_range_and_the_last_catches_the_rest ... ok
[INFO] [stdout] test container::tests::a_sequential_of_one_child_matches_that_child_bit_for_bit ... ok
[INFO] [stdout] test activations::tests::fast_tanh_tracks_tanh_within_its_documented_error ... ok
[INFO] [stdout] test conv::tests::a_shape_the_file_cannot_fill_is_refused_before_allocating ... ok
[INFO] [stdout] test conv::tests::depthwise_is_selected_when_groups_equal_channels ... ok
[INFO] [stdout] test conv::tests::conv1d_matches_direct_definition ... ok
[INFO] [stdout] test conv::tests::grouped_weights_land_on_the_block_diagonal ... ok
[INFO] [stdout] test conv::tests::groups_must_divide_the_channel_counts ... ok
[INFO] [stdout] test film::tests::depthwise_generator_matches_the_reference ... ok
[INFO] [stdout] test film::tests::scale_and_shift_split_across_the_generator_output ... ok
[INFO] [stdout] test film::tests::output_is_as_wide_as_the_modulated_signal_not_the_generator ... ok
[INFO] [stdout] test film::tests::combine_step_is_input_times_scale_plus_shift ... ok
[INFO] [stdout] test film::tests::in_place_matches_the_reference_and_leaves_the_tail_alone ... ok
[INFO] [stdout] test film::tests::grouped_with_shift_matches_the_reference ... ok
[INFO] [stdout] test activations::tests::prelu_indexes_by_channel ... ok
[INFO] [stdout] test film::tests::scale_and_shift_match_the_reference ... ok
[INFO] [stdout] test film::tests::scale_only_and_grouped_matches_the_reference ... ok
[INFO] [stdout] test film::tests::strided_top_rows_input_matches_the_reference ... ok
[INFO] [stdout] test film::tests::weight_count_follows_the_generator_shape ... ok
[INFO] [stdout] test format::de::tests::a_default_covers_absence_but_never_a_present_value ... ok
[INFO] [stdout] test format::de::tests::bool_conversion_is_strict ... ok
[INFO] [stdout] test format::de::tests::counts_are_capped ... ok
[INFO] [stdout] test format::tests::a2_ships_null_secondary_activations_next_to_ungated_layers ... ok
[INFO] [stdout] test format::tests::a_sample_rate_must_be_one_a_host_could_run_at ... ok
[INFO] [stdout] test format::tests::a_slimmable_block_needs_nothing_but_its_method ... ok
[INFO] [stdout] test format::tests::activation_objects_get_the_references_parameter_defaults ... ok
[INFO] [stdout] test format::tests::activation_rejects_what_the_reference_rejects ... ok
[INFO] [stdout] test format::de::tests::errors_say_where_in_the_tree_they_happened ... ok
[INFO] [stdout] test format::de::tests::present_is_what_separates_a_missing_key_from_a_null_one ... ok
[INFO] [stdout] test format::tests::an_inactive_film_object_keeps_its_shift_flag ... ok
[INFO] [stdout] test format::de::tests::int_conversion_truncates_floats_like_static_cast ... ok
[INFO] [stdout] test format::tests::an_absent_or_sentinel_sample_rate_is_unknown ... ok
[INFO] [stdout] test format::tests::a_slimmable_file_is_read_at_full_width ... ok
[INFO] [stdout] test format::tests::container_submodels_must_be_ordered_and_cover_one ... ok
[INFO] [stdout] test format::tests::film_false_is_shorthand_for_inactive ... ok
[INFO] [stdout] test format::tests::condition_dsp_sample_rate_must_match ... ok
[INFO] [stdout] test format::tests::feature_test_model_exercises_every_wavenet_field ... ok
[INFO] [stdout] test format::tests::film_keys_are_in_site_order ... ok
[INFO] [stdout] test format::tests::a_legacy_layer_array_gets_the_reference_defaults ... ok
[INFO] [stdout] test format::tests::gating_defaults_the_secondary_activation_to_sigmoid ... ok
[INFO] [stdout] test format::tests::kernel_size_and_kernel_sizes_are_exclusive ... ok
[INFO] [stdout] test format::tests::layer1x1_post_film_requires_layer1x1 ... ok
[INFO] [stdout] test format::tests::gating_mode_names_are_checked ... ok
[INFO] [stdout] test format::tests::load_file_reports_a_missing_path ... ok
[INFO] [stdout] test format::tests::lstm_linear_and_convnet_defaults_and_requirements ... ok
[INFO] [stdout] test format::tests::post_stack_head_is_checked_against_the_last_layer_arrays_head_size ... ok
[INFO] [stdout] test format::tests::root_must_be_an_object_with_the_four_required_keys ... ok
[INFO] [stdout] test format::tests::sequential_children_must_agree_on_a_sample_rate ... ok
[INFO] [stdout] test format::tests::metadata_is_read_the_way_the_reference_reads_it ... ok
[INFO] [stdout] test format::tests::upstream_example_models_parse ... ok
[INFO] [stdout] test format::tests::per_layer_array_lengths_must_match_the_dilations ... ok
[INFO] [stdout] test format::tests::wavenet_needs_a_head_scale_and_at_least_one_layer_array ... ok
[INFO] [stdout] test format::tests::wavenet_weights_round_exactly_as_python_does ... ok
[INFO] [stdout] test format::tests::weights_must_be_an_array_of_numbers ... ok
[INFO] [stdout] test format::version::tests::one_point_zero_is_rejected_even_though_a_lexicographic_test_would_pass_minor ... ok
[INFO] [stdout] test format::version::tests::parse_version_rejects_what_stoi_rejects ... ok
[INFO] [stdout] test format::version::tests::parse_version_splits_like_getline ... ok
[INFO] [stdout] test format::version::tests::support_matches_the_reference_table ... ok
[INFO] [stdout] test format::tests::slimmable_method_must_be_the_one_the_reference_implements ... ok
[INFO] [stdout] test format::tests::sequential_requires_empty_weights_and_complete_children ... ok
[INFO] [stdout] test format::tests::unknown_architecture_is_reported_by_name ... ok
[INFO] [stdout] test format::tests::version_range_is_enforced_at_the_file_level ... ok
[INFO] [stdout] test gating::tests::blending_endpoints_are_the_activation_and_the_identity ... ok
[INFO] [stdout] test gating::tests::blending_prelu_matches_the_reference ... ok
[INFO] [stdout] test gating::tests::blending_interpolates_towards_the_unactivated_signal ... ok
[INFO] [stdout] test gating::tests::blending_matches_the_reference ... ok
[INFO] [stdout] test gating::tests::gating_matches_the_reference ... ok
[INFO] [stdout] test gating::tests::gating_multiplies_the_two_activated_halves ... ok
[INFO] [stdout] test gating::tests::plain_touches_only_the_first_n_columns ... ok
[INFO] [stdout] test format::version::tests::verify_rejects_only_unsupported ... ok
[INFO] [stdout] test gating::tests::blending_with_other_activations_matches_the_reference ... ok
[INFO] [stdout] test gating::tests::the_bottom_half_and_the_tail_survive ... ok
[INFO] [stdout] test history::tests::fill_with_sample_is_seen_at_every_lookback ... ok
[INFO] [stdout] test history::tests::storage_is_lookback_plus_buffer ... ok
[INFO] [stdout] test gating::tests::prelu_slopes_follow_the_channel_not_the_frame ... ok
[INFO] [stdout] test history::tests::reads_match_a_naive_full_history ... ok
[INFO] [stdout] test kernels::tests::depthwise_matches_the_definition ... ok
[INFO] [stdout] test kernels::tests::kernel_bench ... ignored, measures rather than checks; run with --release --ignored
[INFO] [stdout] test kernels::tests::every_tabulated_shape_resolves ... ok
[INFO] [stdout] test kernels::tests::the_bundled_captures_stay_on_the_table ... ok
[INFO] [stdout] test kernels::tests::specialised_and_dynamic_kernels_agree_bit_for_bit ... ok
[INFO] [stdout] test loader::tests::a_dropped_architecture_is_refused_clearly_even_when_nested ... ok
[INFO] [stdout] test loader::tests::a_model_is_send_and_sync ... ok
[INFO] [stdout] test loader::tests::an_old_file_without_a_sample_rate_reports_none ... ok
[INFO] [stdout] test gating::tests::gating_prelu_indexes_each_half_from_channel_zero ... ok
[INFO] [stdout] test gating::tests::gating_with_other_activations_matches_the_reference ... ok
[INFO] [stdout] test activations::tests::scalar_activations_match_pinned_reference_bits ... ok
[INFO] [stdout] test buffer::tests::add_top_takes_leading_rows_of_each_column ... ok
[INFO] [stdout] test loader::tests::a_model_reports_the_sample_rate_it_was_trained_at ... ok
[INFO] [stdout] test format::tests::a_head_is_required_in_one_of_its_two_spellings ... ok
[INFO] [stdout] test format::tests::a_key_the_taken_branch_never_reads_is_never_converted ... ok
[INFO] [stdout] test lstm::tests::an_absurd_shape_is_refused_before_anything_is_allocated ... ok
[INFO] [stdout] test lstm::tests::initial_states_come_from_the_weights ... ok
[INFO] [stdout] test lstm::tests::rejects_channel_counts_the_reference_would_break_on ... ok
[INFO] [stdout] test lstm::tests::prewarm_samples_follows_the_reference ... ok
[INFO] [stdout] test lstm::tests::gate_order_and_row_major_layout ... ok
[INFO] [stdout] test lstm::tests::weight_count_is_enforced_in_both_directions ... ok
[INFO] [stdout] test lstm::tests::matches_direct_definition ... ok
[INFO] [stdout] test lstm::tests::ragged_blocks_match_one_at_a_time ... ok
[INFO] [stdout] test wavenet::tests::histories_are_capped_in_total ... ok
[INFO] [stdout] test lstm::tests::zero_layers_pass_input_through ... ok
[INFO] [stdout] test wavenet::tests::a_shape_the_file_cannot_fill_is_refused_before_it_is_built ... ok
[INFO] [stdout] test weights::tests::check_refuses_a_shape_the_file_cannot_fill ... ok
[INFO] [stdout] test weights::tests::reports_shortfall_and_surplus ... ok
[INFO] [stdout] test wavenet::tests::the_weight_array_must_be_exactly_consumed ... ok
[INFO] [stdout] test format::tests::bundled_model_shapes_are_what_the_json_says ... ok
[INFO] [stdout] test a2_fast::tests::the_narrow_child_takes_the_fast_path_and_the_wide_one_keeps_the_generic ... ok
[INFO] [stdout] test kernels::tests::batched_kernels_are_bit_identical_to_the_per_frame_ones ... ok
[INFO] [stdout] test a2_fast::tests::the_fast_path_reports_what_the_model_does ... ok
[INFO] [stdout] test loader::tests::every_bundled_fixture_loads ... ok
[INFO] [stdout] test loader::tests::a_bare_prewarm_reports_the_size_it_actually_settled_at ... ok
[INFO] [stdout] test format::tests::every_bundled_model_parses_and_agrees_with_its_json ... ok
[INFO] [stdout] test container::tests::selecting_a_child_settles_it_once_on_selection_and_once_on_reset ... ok
[INFO] [stdout] test a2_fast::tests::the_fast_path_is_the_generic_path_bit_for_bit has been running for over 60 seconds
[ERROR] error running command: no output for 300 seconds
[INFO] running `Command { std: "docker" "inspect" "95bb43843aad49861f49a58318620e74a8b4d91bd510a97570fc3d7e0e8975a7", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "95bb43843aad49861f49a58318620e74a8b4d91bd510a97570fc3d7e0e8975a7", kill_on_drop: false }`
[INFO] [stdout] 95bb43843aad49861f49a58318620e74a8b4d91bd510a97570fc3d7e0e8975a7
