[INFO] cloning repository https://github.com/louiscryan/trommel [INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/louiscryan/trommel" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Flouiscryan%2Ftrommel", kill_on_drop: false }` [INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Flouiscryan%2Ftrommel'... [INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }` [INFO] [stdout] d0a2f39f203d72c271f6db7406d1590a0b040ad0 [INFO] testing louiscryan/trommel against 1.99.0-beta.8+cargoflags=--release for beta-release-1.100-2 [INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Flouiscryan%2Ftrommel" "/workspace/builds/worker-7-tc1/source", kill_on_drop: false }` [INFO] [stderr] Cloning into '/workspace/builds/worker-7-tc1/source'... [INFO] [stderr] done. [INFO] removed /workspace/builds/worker-7-tc1/source/.cargo/config.toml [INFO] started tweaking git repo https://github.com/louiscryan/trommel [INFO] finished tweaking git repo https://github.com/louiscryan/trommel [INFO] tweaked toml for git repo https://github.com/louiscryan/trommel written to /workspace/builds/worker-7-tc1/source/Cargo.toml [INFO] validating manifest of git repo https://github.com/louiscryan/trommel 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 git repo https://github.com/louiscryan/trommel 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 lasso v0.7.3 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-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:77db811e55d90add9212f6832c23229e3de5f1c1f9905e8cb07319ea15df73ac" "sleep" "infinity", kill_on_drop: false }` [INFO] [stdout] f82505ae83e7428fe5ae1454ef87bd30b9494572784efe9aaac20fcceda6047c [INFO] running `Command { std: "docker" "start" "f82505ae83e7428fe5ae1454ef87bd30b9494572784efe9aaac20fcceda6047c", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "f82505ae83e7428fe5ae1454ef87bd30b9494572784efe9aaac20fcceda6047c", 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" "f82505ae83e7428fe5ae1454ef87bd30b9494572784efe9aaac20fcceda6047c" "/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" "f82505ae83e7428fe5ae1454ef87bd30b9494572784efe9aaac20fcceda6047c", 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" "f82505ae83e7428fe5ae1454ef87bd30b9494572784efe9aaac20fcceda6047c" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "build" "--frozen" "--message-format=json" "--release", kill_on_drop: false }` [INFO] [stderr] Compiling syn v2.0.118 [INFO] [stderr] Compiling bytes v1.12.1 [INFO] [stderr] Compiling smallvec v1.15.2 [INFO] [stderr] Compiling writeable v0.6.3 [INFO] [stderr] Compiling litemap v0.8.2 [INFO] [stderr] Compiling tracing-core v0.1.36 [INFO] [stderr] Compiling icu_normalizer_data v2.2.0 [INFO] [stderr] Compiling icu_properties_data v2.2.0 [INFO] [stderr] Compiling cc v1.2.66 [INFO] [stderr] Compiling serde_core v1.0.228 [INFO] [stderr] Compiling log v0.4.33 [INFO] [stderr] Compiling slab v0.4.12 [INFO] [stderr] Compiling futures-io v0.3.32 [INFO] [stderr] Compiling zeroize v1.9.0 [INFO] [stderr] Compiling getrandom v0.2.17 [INFO] [stderr] Compiling crossbeam-utils v0.8.22 [INFO] [stderr] Compiling rustls-pki-types v1.15.0 [INFO] [stderr] Compiling futures-channel v0.3.32 [INFO] [stderr] Compiling crossbeam-epoch v0.9.20 [INFO] [stderr] Compiling futures-util v0.3.32 [INFO] [stderr] Compiling rustls v0.23.41 [INFO] [stderr] Compiling zerocopy v0.8.54 [INFO] [stderr] Compiling ahash v0.8.12 [INFO] [stderr] Compiling tracing v0.1.44 [INFO] [stderr] Compiling crossbeam-deque v0.8.7 [INFO] [stderr] Compiling serde_json v1.0.150 [INFO] [stderr] Compiling anstyle-parse v1.0.0 [INFO] [stderr] Compiling anstyle v1.0.14 [INFO] [stderr] Compiling aho-corasick v1.1.4 [INFO] [stderr] Compiling clap_lex v1.1.0 [INFO] [stderr] Compiling anyhow v1.0.103 [INFO] [stderr] Compiling webpki-roots v1.0.8 [INFO] [stderr] Compiling regex-syntax v0.8.11 [INFO] [stderr] Compiling anstream v1.0.0 [INFO] [stderr] Compiling matchit v0.8.4 [INFO] [stderr] Compiling clap_builder v4.6.0 [INFO] [stderr] Compiling either v1.16.0 [INFO] [stderr] Compiling roaring v0.11.3 [INFO] [stderr] Compiling http v1.4.2 [INFO] [stderr] Compiling ring v0.17.14 [INFO] [stderr] Compiling rayon-core v1.13.0 [INFO] [stderr] Compiling regex-automata v0.4.14 [INFO] [stderr] Compiling rayon v1.12.0 [INFO] [stderr] Compiling http-body v1.0.1 [INFO] [stderr] Compiling http-body-util v0.1.3 [INFO] [stderr] Compiling serde_path_to_error v0.1.20 [INFO] [stderr] Compiling axum-core v0.5.6 [INFO] [stderr] Compiling hashbrown v0.14.5 [INFO] [stderr] Compiling synstructure v0.13.2 [INFO] [stderr] Compiling lasso v0.7.3 [INFO] [stderr] Compiling zerofrom-derive v0.1.7 [INFO] [stderr] Compiling yoke-derive v0.8.2 [INFO] [stderr] Compiling zerovec-derive v0.11.3 [INFO] [stderr] Compiling displaydoc v0.2.6 [INFO] [stderr] Compiling tokio-macros v2.7.0 [INFO] [stderr] Compiling serde_derive v1.0.228 [INFO] [stderr] Compiling clap_derive v4.6.1 [INFO] [stderr] Compiling regex v1.12.4 [INFO] [stderr] Compiling tokio v1.52.3 [INFO] [stderr] Compiling zerofrom v0.1.8 [INFO] [stderr] Compiling yoke v0.8.3 [INFO] [stderr] Compiling zerotrie v0.2.4 [INFO] [stderr] Compiling zerovec v0.11.6 [INFO] [stderr] Compiling clap v4.6.1 [INFO] [stderr] Compiling tinystr v0.8.3 [INFO] [stderr] Compiling potential_utf v0.1.5 [INFO] [stderr] Compiling icu_collections v2.2.0 [INFO] [stderr] Compiling icu_locale_core v2.2.0 [INFO] [stderr] Compiling serde v1.0.228 [INFO] [stderr] Compiling icu_provider v2.2.0 [INFO] [stderr] Compiling icu_normalizer v2.2.0 [INFO] [stderr] Compiling icu_properties v2.2.0 [INFO] [stderr] Compiling serde_urlencoded v0.7.1 [INFO] [stderr] Compiling hyper v1.10.1 [INFO] [stderr] Compiling tower v0.5.3 [INFO] [stderr] Compiling idna_adapter v1.2.2 [INFO] [stderr] Compiling idna v1.1.0 [INFO] [stderr] Compiling url v2.5.8 [INFO] [stderr] Compiling tower-http v0.6.11 [INFO] [stderr] Compiling hyper-util v0.1.20 [INFO] [stderr] Compiling rustls-webpki v0.103.13 [INFO] [stderr] Compiling axum v0.8.9 [INFO] [stderr] Compiling tokio-rustls v0.26.4 [INFO] [stderr] Compiling hyper-rustls v0.27.9 [INFO] [stderr] Compiling reqwest v0.12.28 [INFO] [stderr] Compiling trommel v0.1.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished `release` profile [optimized] target(s) in 1m 18s [INFO] running `Command { std: "docker" "inspect" "f82505ae83e7428fe5ae1454ef87bd30b9494572784efe9aaac20fcceda6047c", 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" "f82505ae83e7428fe5ae1454ef87bd30b9494572784efe9aaac20fcceda6047c" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "test" "--frozen" "--no-run" "--message-format=json" "--release", kill_on_drop: false }` [INFO] [stderr] Compiling bitflags v2.13.0 [INFO] [stderr] Compiling linux-raw-sys v0.12.1 [INFO] [stderr] Compiling fastrand v2.4.1 [INFO] [stderr] Compiling getrandom v0.4.3 [INFO] [stderr] Compiling tower-http v0.6.11 [INFO] [stderr] Compiling rustix v1.1.4 [INFO] [stderr] Compiling reqwest v0.12.28 [INFO] [stderr] Compiling tempfile v3.27.0 [INFO] [stderr] Compiling trommel v0.1.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished `release` profile [optimized] target(s) in 49.41s [INFO] running `Command { std: "docker" "inspect" "f82505ae83e7428fe5ae1454ef87bd30b9494572784efe9aaac20fcceda6047c", 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" "f82505ae83e7428fe5ae1454ef87bd30b9494572784efe9aaac20fcceda6047c" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "test" "--frozen" "--release", kill_on_drop: false }` [INFO] [stderr] Finished `release` profile [optimized] target(s) in 0.20s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/release/deps/trommel-81ab02ccfc8aadea) [INFO] [stdout] [INFO] [stdout] running 171 tests [INFO] [stdout] test cluster::tests::distinct_log_shapes_produce_distinct_signatures ... ok [INFO] [stdout] test cluster::tests::quoted_bracketed_and_braced_spans_each_collapse_to_their_own_marker ... ok [INFO] [stdout] test cluster::tests::signature_is_deterministic ... ok [INFO] [stdout] test cluster::tests::digit_atoms_separated_by_a_padded_dash_still_collapse_to_one_marker ... ok [INFO] [stdout] test cluster::tests::an_access_log_line_and_its_user_agent_variants_share_one_signature ... ok [INFO] [stdout] test cluster::tests::a_very_long_single_line_collapses_to_stack_even_without_frame_markers ... ok [INFO] [stdout] test cluster::tests::a_multi_frame_stack_trace_collapses_to_the_stack_sentinel ... ok [INFO] [stdout] test dict::tests::the_seam_selects_and_intersects_without_handing_out_a_bitmap ... ok [INFO] [stdout] test dict::tests::top_values_partial_selects_the_top_k_for_a_stored_dimension ... ok [INFO] [stdout] test dict::tests::top_values_orders_an_indexed_dimension_by_count_descending_and_caps_at_k ... ok [INFO] [stdout] test cluster::tests::a_short_message_containing_the_word_at_once_is_not_mistaken_for_a_stack_trace ... ok [INFO] [stdout] test cluster::tests::an_ip_and_port_is_a_single_marker_not_one_per_octet ... ok [INFO] [stdout] test dict::tests::value_postings_covers_both_storage_shapes ... ok [INFO] [stdout] test cluster::tests::spring_boot_lines_with_different_threads_and_values_collapse_by_shape ... ok [INFO] [stdout] test dict::tests::a_converted_dimension_is_filterable_via_the_seam_but_not_scoreable ... ok [INFO] [stdout] test dict::tests::compact_on_a_stored_dimension_is_a_no_op_when_watermark_does_not_advance_past_origin ... ok [INFO] [stdout] test dict::tests::dimension_indexes_values_and_orders_them_by_count ... ok [INFO] [stdout] test dict::tests::compact_on_a_stored_dimension_rebases_origin_remaps_codes_and_reclaims_evicted_strings ... ok [INFO] [stdout] test dict::tests::compact_on_an_indexed_dimension_remaps_codes_and_reclaims_evicted_strings ... ok [INFO] [stdout] test extract::tests::scalars_stringify_and_null_is_skipped ... ok [INFO] [stdout] test cluster::tests::hdfs_lines_with_different_block_ids_and_addresses_collapse_by_shape ... ok [INFO] [stdout] test column::tests::column_inserts_scans_counts_and_reports_present ... ok [INFO] [stdout] test dict::tests::null_is_always_code_zero_and_absent_from_the_sorted_list ... ok [INFO] [stdout] test extract::tests::non_object_records_are_rejected ... ok [INFO] [stdout] test extract::tests::flattens_nested_objects_to_dotted_paths ... ok [INFO] [stdout] test index::tests::a_high_cardinality_dimension_converts_to_stored ... ok [INFO] [stdout] test dict::tests::count_on_a_stored_dim_is_zero_for_an_interned_but_uninserted_code ... ok [INFO] [stdout] test index::tests::eviction_narrows_live_reports_a_timestamp_and_reclaims_on_compaction ... ok [INFO] [stdout] test extract::tests::arrays_become_repeated_values_of_one_dimension ... ok [INFO] [stdout] test ingest::tests::a_semantic_map_that_never_matches_is_byte_identical_to_semantic_off ... ok [INFO] [stdout] test index::tests::add_record_assigns_dense_ids_and_indexes_every_field ... ok [INFO] [stdout] test ingest::tests::dry_run_makes_no_generator_or_cache_calls_and_resolves_nothing ... ok [INFO] [stdout] test ingest::tests::grep_filters_before_indexing ... ok [INFO] [stdout] test ingest::tests::ingests_a_final_line_with_no_trailing_newline ... ok [INFO] [stdout] test ingest::tests::a_human_seeded_real_parser_entry_overrides_a_declined_marker_on_re_serve ... ok [INFO] [stdout] test index::tests::schema_lists_every_dimension_with_cardinality_scoreable_and_top_values ... ok [INFO] [stdout] test index::tests::schema_folds_the_null_absent_count_into_top_values_at_its_true_rank ... ok [INFO] [stdout] test ingest::tests::plan_semantic_with_grep_never_selects_or_fails_loud_on_an_excluded_signature ... ok [INFO] [stdout] test ingest::tests::ingests_records_skips_blank_and_malformed_lines ... ok [INFO] [stdout] test ingest::tests::a_record_without_the_text_field_produces_no_template ... ok [INFO] [stdout] test ingest::tests::limit_caps_the_number_of_records ... ok [INFO] [stdout] test index::tests::schema_marks_is_trace_field_only_for_the_configured_dimension ... ok [INFO] [stdout] test ingest::tests::apply_template_treats_a_zero_pair_parser_match_as_a_non_match ... ok [INFO] [stdout] test ingest::tests::a_constant_line_cluster_falls_back_to_structural_instead_of_silently_dropping_the_field ... ok [INFO] [stdout] test ingest::tests::msg_param_dimensions_are_global_across_templates ... ok [INFO] [stdout] test index::tests::dim_id_is_get_or_create ... ok [INFO] [stdout] test ingest::tests::select_and_generate_a_cache_hit_needs_no_generator_at_all ... ok [INFO] [stdout] test ingest::tests::select_and_generate_a_declined_cluster_is_cache_hit_on_a_second_run_with_zero_model_calls ... ok [INFO] [stdout] test ingest::tests::tally_signatures_respects_grep_like_pass_2_does ... ok [INFO] [stdout] test ingest::tests::tally_signatures_respects_limit_like_pass_2_does ... ok [INFO] [stdout] test ingest::tests::select_and_generate_rejects_a_slotless_parser_from_any_generator_not_just_the_stub ... ok [INFO] [stdout] test ingest::tests::select_and_generate_respects_parser_limit_and_caps_further_generation ... ok [INFO] [stdout] test ingest::tests::select_and_generate_succeeds_with_a_generator_and_caches_the_result ... ok [INFO] [stdout] test ingest::tests::select_and_generate_fails_loud_with_no_generator_and_an_empty_cache ... ok [INFO] [stdout] test ingest::tests::select_and_generate_regenerates_a_stale_cache_hit_when_a_generator_is_available ... ok [INFO] [stdout] test ingest::tests::record_ids_follow_file_order ... ok [INFO] [stdout] test ingest::tests::semantic_pipeline_extracts_named_dims_and_falls_back_for_the_uncovered_residual ... ok [INFO] [stdout] test ingest::tests::select_and_generate_rejects_a_stale_cache_hit_that_does_not_match_current_exemplars ... ok [INFO] [stdout] test ingest::tests::time_range_filters_after_parse_and_stores_nothing ... ok [INFO] [stdout] test ingest::tests::text_field_composes_with_a_time_filter ... ok [INFO] [stdout] test ingest::tests::tally_signatures_counts_and_caps_exemplars_per_cluster ... ok [INFO] [stdout] test ingest::tests::without_a_time_field_the_bounds_are_inert ... ok [INFO] [stdout] test mem::tests::realloc_is_counted_as_a_net_change ... FAILED [INFO] [stdout] test name::tests::a_saved_and_reloaded_cache_still_skips_the_inner_call ... ok [INFO] [stdout] test name::tests::anthropic_namer_parses_a_recorded_response ... ok [INFO] [stdout] test mem::tests::the_allocator_counts_a_vec ... ok [INFO] [stdout] test name::tests::anthropic_namer_parses_a_recorded_reconcile_response ... ok [INFO] [stdout] test name::tests::dry_run_inspects_without_sending ... ok [INFO] [stdout] test name::tests::dry_run_reconcile_inspects_without_sending ... ok [INFO] [stdout] test name::tests::read_key_file_rejects_a_missing_file ... ok [INFO] [stdout] test name::tests::cached_namer_skips_the_inner_call_on_a_hit ... ok [INFO] [stdout] test name::tests::real_api_names_a_small_template_cluster ... ignored, makes real, paid Anthropic API calls; run explicitly with --ignored [INFO] [stdout] test name::tests::read_key_file_trims_a_trailing_newline ... ok [INFO] [stdout] test name::tests::read_key_file_rejects_an_empty_file ... ok [INFO] [stdout] test name::tests::stability_is_one_for_identical_maps_and_low_for_conflicting ... ok [INFO] [stdout] test name::tests::stub_names_by_placeholder_kind_and_suffixes_repeats ... ok [INFO] [stdout] test name::tests::stub_reconcile_collapses_ip_synonyms_and_leaves_others_alone ... ok [INFO] [stdout] test parser::tests::a_value_containing_the_skeleton_delimiter_fails_the_naive_scan_but_a_regex_constraint_fixes_it ... ok [INFO] [stdout] test parser::tests::an_opaque_slot_swallows_the_rest_of_the_line_including_a_stack_trace ... ok [INFO] [stdout] test parser::tests::a_line_of_the_wrong_shape_does_not_match ... ok [INFO] [stdout] test parser::tests::anthropic_parser_gen_drops_an_individual_unparseable_parser_string_but_keeps_the_rest ... ok [INFO] [stdout] test parser::tests::anthropic_parser_gen_parses_a_recorded_response_into_working_parsers ... ok [INFO] [stdout] test parser::tests::anthropic_parser_gen_response_with_no_parsers_array_is_an_error ... ok [INFO] [stdout] test parser::tests::extracts_named_typed_fields_from_a_real_access_log_line ... ok [INFO] [stdout] test parser::tests::parse_rejects_an_unknown_slot_type ... ok [INFO] [stdout] test parser::tests::parse_round_trips_literal_text_that_itself_contains_percent_brace ... ok [INFO] [stdout] test parser::tests::anthropic_parser_gen_generate_returns_empty_on_zero_exemplars_without_touching_the_network ... ok [INFO] [stdout] test parser::tests::parser_cache_round_trips_through_disk ... ok [INFO] [stdout] test parser::tests::parser_cache_miss_and_a_stale_unparseable_entry_are_both_none ... ok [INFO] [stdout] test parser::tests::real_api_generates_a_parser_for_an_access_log_cluster ... ignored, makes a real, paid Anthropic API call; run explicitly with --ignored [INFO] [stdout] test parser::tests::real_api_reconciles_synonym_field_names ... ignored, makes a real, paid Anthropic API call; run explicitly with --ignored [INFO] [stdout] test parser::tests::anthropic_parser_gen_parses_a_split_response_into_multiple_parsers ... ok [INFO] [stdout] test parser::tests::reconcile_field_names_collapses_synonyms_across_clusters_and_updates_map_and_cache ... ok [INFO] [stdout] test parser::tests::reconcile_field_names_flags_a_type_mismatched_merge_as_a_conflict ... ok [INFO] [stdout] test parser::tests::reconcile_field_names_is_a_noop_on_an_empty_map ... ok [INFO] [stdout] test parser::tests::reconcile_field_names_keeps_a_cache_hit_dimension_stable_when_a_new_cluster_is_generated_incrementally ... ok [INFO] [stdout] test parser::tests::stub_gen_promotes_a_digit_free_varying_field_to_a_slot ... ok [INFO] [stdout] test parser::tests::stub_gen_rejects_a_slotless_all_literal_cluster_and_generates_nothing ... ok [INFO] [stdout] test parser::tests::stub_gen_splits_a_cluster_that_mixes_two_distinct_shapes ... ok [INFO] [stdout] test parser::tests::the_parser_string_form_round_trips ... ok [INFO] [stdout] test parser::tests::stub_gen_derives_a_parser_that_extracts_named_fields_from_a_uniform_cluster ... ok [INFO] [stdout] test name::tests::cached_namer_makes_a_second_name_templates_run_free ... ok [INFO] [stdout] test ingest::tests::templating_replaces_the_text_field_with_template_and_params ... ok [INFO] [stdout] test query::tests::absence_is_a_scoreable_value ... ok [INFO] [stdout] test query::tests::a_stored_dimension_is_filterable_but_absent_from_the_wall ... ok [INFO] [stdout] test name::tests::into_cache_after_partial_run_preserves_named_templates ... ok [INFO] [stdout] test query::tests::eviction_then_finalize_leaves_a_sane_wall_no_underflow ... ok [INFO] [stdout] test query::tests::debug_counters_prove_the_unique_dimension_was_pruned ... ok [INFO] [stdout] test query::tests::negation_selects_the_complement ... ok [INFO] [stdout] test query::tests::the_filtered_dimension_is_excluded_from_its_own_explanation ... ok [INFO] [stdout] test query::tests::an_unknown_dimension_is_an_error ... ok [INFO] [stdout] test query::tests::trace_lens::a_null_predicate_selects_traces_that_never_carry_the_dimension ... ok [INFO] [stdout] test query::tests::trace_lens::a_single_predicate_selects_the_traces_that_contain_it ... ok [INFO] [stdout] test name::tests::name_templates_maps_every_top_template_position ... ok [INFO] [stdout] test parser::tests::validate_reports_the_matched_fraction_and_is_1_for_no_exemplars ... ok [INFO] [stdout] test query::tests::trace_lens::and_across_predicates_intersects_trace_sets ... ok [INFO] [stdout] test query::tests::a_non_negated_unknown_value_yields_an_empty_slice ... ok [INFO] [stdout] test query::tests::finds_the_planted_needle_and_ranks_it_first ... ok [INFO] [stdout] test name::tests::name_limit_caps_the_number_of_namer_calls ... ok [INFO] [stdout] test query::tests::trace_lens::a_traceless_record_never_contributes_to_any_trace ... ok [INFO] [stdout] test query::tests::trace_lens::an_unsatisfiable_predicate_yields_an_empty_trace_slice ... ok [INFO] [stdout] test query::tests::trace_lens::negation_complements_over_the_trace_universe ... ok [INFO] [stdout] test query::tests::trace_lens::or_within_a_dimension_unions_the_values_presence ... ok [INFO] [stdout] test query::tests::trace_lens::record_lens_still_echoes_the_configured_trace_field ... ok [INFO] [stdout] test index::tests::trace_presence_index_memory_cost_is_measured_via_the_counting_allocator ... FAILED [INFO] [stdout] test name::tests::name_templates_merges_the_same_kind_across_different_templates_into_one_name ... ok [INFO] [stdout] test query::tests::trace_lens::empty_filter_selects_the_whole_trace_universe ... ok [INFO] [stdout] test name::tests::name_templates_applies_the_reconciliation_mapping_not_just_computes_it ... ok [INFO] [stdout] test query::tests::trace_lens::trace_lens_without_a_trace_field_is_a_clear_error ... ok [INFO] [stdout] test query::tests::trace_lens::whole_corpus_and_complement_baselines_score_differently_but_agree_on_traces_hit ... ok [INFO] [stdout] test review::tests::render_reports_nothing_to_review_when_everything_resolved ... ok [INFO] [stdout] test query::tests::trace_lens::the_null_value_is_scoreable_within_a_trace_lens_wall ... ok [INFO] [stdout] test query::tests::uniform_dimensions_score_near_zero ... ok [INFO] [stdout] test query::tests::unique_valued_dimensions_are_pruned_by_min_support ... ok [INFO] [stdout] test score::tests::jlh_is_zero_for_under_represented_values ... ok [INFO] [stdout] test score::tests::risk_ratio_matches_the_macrobase_definition ... ok [INFO] [stdout] test score::tests::risk_ratio_is_finite_when_the_value_is_exclusive_to_the_slice ... ok [INFO] [stdout] test score::tests::jlh_matches_the_published_formula ... ok [INFO] [stdout] test serve::tests::debugz_merges_memory_stats_into_the_existing_json_not_a_new_route ... ok [INFO] [stdout] test serve::tests::get_schema_route_marks_is_trace_field_when_configured ... ok [INFO] [stdout] test serve::tests::null_is_expressible_as_json_null ... ok [INFO] [stdout] test review::tests::render_caps_the_listed_clusters_and_summarizes_the_rest ... ok [INFO] [stdout] test serve::tests::lens_defaults_to_record_and_accepts_trace_over_json ... ok [INFO] [stdout] test template::tests::a_line_of_only_literals_has_no_params ... ok [INFO] [stdout] test template::tests::classification_order_is_pinned ... ok [INFO] [stdout] test name::tests::partial_corpus_reuse_only_pays_for_new_templates ... ok [INFO] [stdout] test serve::tests::post_query_record_lens_response_echoes_trace_field_when_configured ... ok [INFO] [stdout] test serve::tests::get_schema_route_returns_the_stable_contract_shape ... ok [INFO] [stdout] test template::tests::classifies_tokens_by_shape ... ok [INFO] [stdout] test template::tests::placeholders_of_lists_masked_positions_in_order ... ok [INFO] [stdout] test template::tests::placeholders_of_ignores_literal_bracket_text ... ok [INFO] [stdout] test serve::tests::post_query_routes_trace_lens_when_a_trace_field_is_configured ... ok [INFO] [stdout] test template::tests::extracts_a_readable_template_and_ordered_params ... ok [INFO] [stdout] test serve::tests::post_query_errs_clearly_for_trace_lens_without_a_trace_field ... ok [INFO] [stdout] test template::tests::placeholders_of_still_splits_adjacent_placeholders ... ok [INFO] [stdout] test timeparse::tests::bare_integers_are_disambiguated_by_magnitude ... ok [INFO] [stdout] test review::tests::write_review_doc_on_a_synthetic_mixed_corpus_has_the_expected_sections ... ok [INFO] [stdout] test query::tests::theta_still_prunes_under_jlh ... ok [INFO] [stdout] test serve::tests::a_query_spec_round_trips_through_json ... ok [INFO] [stdout] test score::tests::count_returns_the_slice_count ... ok [INFO] [stdout] test score::tests::degenerate_inputs_score_zero_rather_than_nan ... ok [INFO] [stdout] test timeparse::tests::garbage_is_none ... ok [INFO] [stdout] test trace::tests::a_dimension_with_no_trace_field_configured_has_no_trace_index ... ok [INFO] [stdout] test trace::tests::setting_an_unknown_trace_field_is_a_clear_error ... ok [INFO] [stdout] test trace::tests::presence_bitmaps_are_correct_and_traceless_records_are_excluded ... ok [INFO] [stdout] test timeparse::tests::rfc3339_parses_to_epoch_millis ... ok [INFO] [stdout] test query::tests::theta_is_ignored_under_risk_ratio ... ok [INFO] [stdout] test ingest::tests::batching_preserves_order_and_completeness_across_a_batch_boundary ... ok [INFO] [stdout] test ingest::tests::memory_budget_evicts_the_oldest_records_during_ingest ... FAILED [INFO] [stdout] test dict::tests::compact_reclaims_stored_column_capacity ... ok [INFO] [stdout] test index::tests::enforce_budget_does_not_collapse_to_one_record ... FAILED [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] [INFO] [stdout] ---- mem::tests::realloc_is_counted_as_a_net_change stdout ---- [INFO] [stdout] [INFO] [stdout] thread 'mem::tests::realloc_is_counted_as_a_net_change' (3239) panicked at src/mem.rs:94:9: [INFO] [stdout] assertion `left == right` failed: expected live_bytes to return to baseline after drop: before=18352197, after_drop=18447413 [INFO] [stdout] left: 18447413 [INFO] [stdout] right: 18352197 [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x5f17cf7a8a61 - <::print::DisplayBacktrace as core[df12db4294e9bfd3]::fmt::Display>::fmt [INFO] [stdout] 1: 0x5f17cf7c080a - core[df12db4294e9bfd3]::fmt::write [INFO] [stdout] 2: 0x5f17cf7adcec - as core[df12db4294e9bfd3]::io::write::Write>::write_fmt [INFO] [stdout] 3: 0x5f17cf781a66 - std[70759c8f55707aa4]::panicking::default_hook::{closure#0} [INFO] [stdout] 4: 0x5f17cf79f7f9 - std[70759c8f55707aa4]::panicking::default_hook [INFO] [stdout] 5: 0x5f17cf6cc160 - test[9d35eded1c95d3be]::test_main_inner::::{closure#0} [INFO] [stdout] 6: 0x5f17cf79fb22 - std[70759c8f55707aa4]::panicking::panic_with_hook [INFO] [stdout] 7: 0x5f17cf781b12 - std[70759c8f55707aa4]::panicking::panic_handler::{closure#0} [INFO] [stdout] 8: 0x5f17cf779f79 - std[70759c8f55707aa4]::sys::backtrace::__rust_end_short_backtrace:: [INFO] [stdout] 9: 0x5f17cf7828ed - __rustc[8fa7c3cbc660c2b3]::rust_begin_unwind [INFO] [stdout] 10: 0x5f17cf7c0fec - core[df12db4294e9bfd3]::panicking::panic_fmt [INFO] [stdout] 11: 0x5f17cf7c0f63 - core[df12db4294e9bfd3]::panicking::assert_failed_inner [INFO] [stdout] 12: 0x5f17cf7bb302 - core[df12db4294e9bfd3]::panicking::assert_failed:: [INFO] [stdout] 13: 0x5f17cf405313 - trommel[171620a9b650e0cc]::mem::tests::realloc_is_counted_as_a_net_change [INFO] [stdout] 14: 0x5f17cf3909f9 - >::call_once [INFO] [stdout] 15: 0x5f17cf6bf44b - test[9d35eded1c95d3be]::__rust_begin_short_backtrace::, fn() -> core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>> [INFO] [stdout] 16: 0x5f17cf6ccab5 - test[9d35eded1c95d3be]::run_test::{closure#0} [INFO] [stdout] 17: 0x5f17cf6c6374 - std[70759c8f55707aa4]::sys::backtrace::__rust_begin_short_backtrace:: [INFO] [stdout] 18: 0x5f17cf6cfc12 - ::{closure#1} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0} [INFO] [stdout] 19: 0x5f17cf7a7709 - ::new::thread_start [INFO] [stdout] 20: 0x7c22ce020dfa - [INFO] [stdout] 21: 0x7c22ce0b43d4 - clone [INFO] [stdout] 22: 0x0 - [INFO] [stdout] [INFO] [stdout] ---- index::tests::trace_presence_index_memory_cost_is_measured_via_the_counting_allocator stdout ---- [INFO] [stdout] trace-presence index: 200 traces x 200 lines/trace, 20 templates/app -- finalize() cost 880 bytes without a trace field, 19722707 bytes with one (delta 19721827 bytes, ~98609.1 bytes/trace) [INFO] [stdout] [INFO] [stdout] thread 'index::tests::trace_presence_index_memory_cost_is_measured_via_the_counting_allocator' (3191) panicked at src/index.rs:661:9: [INFO] [stdout] trace-presence index cost 19721827 bytes -- unexpectedly large for 200 traces x 20 values [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x5f17cf7a8a61 - <::print::DisplayBacktrace as core[df12db4294e9bfd3]::fmt::Display>::fmt [INFO] [stdout] 1: 0x5f17cf7c080a - core[df12db4294e9bfd3]::fmt::write [INFO] [stdout] 2: 0x5f17cf7adcec - as core[df12db4294e9bfd3]::io::write::Write>::write_fmt [INFO] [stdout] 3: 0x5f17cf781a66 - std[70759c8f55707aa4]::panicking::default_hook::{closure#0} [INFO] [stdout] 4: 0x5f17cf79f7f9 - std[70759c8f55707aa4]::panicking::default_hook [INFO] [stdout] 5: 0x5f17cf6cc160 - test[9d35eded1c95d3be]::test_main_inner::::{closure#0} [INFO] [stdout] 6: 0x5f17cf79fb22 - std[70759c8f55707aa4]::panicking::panic_with_hook [INFO] [stdout] 7: 0x5f17cf781b12 - std[70759c8f55707aa4]::panicking::panic_handler::{closure#0} [INFO] [stdout] 8: 0x5f17cf779f79 - std[70759c8f55707aa4]::sys::backtrace::__rust_end_short_backtrace:: [INFO] [stdout] 9: 0x5f17cf7828ed - __rustc[8fa7c3cbc660c2b3]::rust_begin_unwind [INFO] [stdout] 10: 0x5f17cf7c0fec - core[df12db4294e9bfd3]::panicking::panic_fmt [INFO] [stdout] 11: 0x5f17cf40fbbf - trommel[171620a9b650e0cc]::index::tests::trace_presence_index_memory_cost_is_measured_via_the_counting_allocator [INFO] [stdout] 12: 0x5f17cf390f19 - >::call_once [INFO] [stdout] 13: 0x5f17cf6bf44b - test[9d35eded1c95d3be]::__rust_begin_short_backtrace::, fn() -> core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>> [INFO] [stdout] 14: 0x5f17cf6ccab5 - test[9d35eded1c95d3be]::run_test::{closure#0} [INFO] [stdout] 15: 0x5f17cf6c6374 - std[70759c8f55707aa4]::sys::backtrace::__rust_begin_short_backtrace:: [INFO] [stdout] 16: 0x5f17cf6cfc12 - ::{closure#1} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0} [INFO] [stdout] 17: 0x5f17cf7a7709 - ::new::thread_start [INFO] [stdout] 18: 0x7c22ce020dfa - [INFO] [stdout] 19: 0x7c22ce0b43d4 - clone [INFO] [stdout] 20: 0x0 - [INFO] [stdout] [INFO] [stdout] ---- ingest::tests::memory_budget_evicts_the_oldest_records_during_ingest stdout ---- [INFO] [stdout] EVICTED 19999 records to stay under the memory budget (oldest retained epoch ~1000000000) [INFO] [stdout] [INFO] [stdout] thread 'ingest::tests::memory_budget_evicts_the_oldest_records_during_ingest' (3219) panicked at src/ingest.rs:926:9: [INFO] [stdout] live heap 19768559 exceeds budget 14985673 [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x5f17cf7a8a61 - <::print::DisplayBacktrace as core[df12db4294e9bfd3]::fmt::Display>::fmt [INFO] [stdout] 1: 0x5f17cf7c080a - core[df12db4294e9bfd3]::fmt::write [INFO] [stdout] 2: 0x5f17cf7adcec - as core[df12db4294e9bfd3]::io::write::Write>::write_fmt [INFO] [stdout] 3: 0x5f17cf781a66 - std[70759c8f55707aa4]::panicking::default_hook::{closure#0} [INFO] [stdout] 4: 0x5f17cf79f7f9 - std[70759c8f55707aa4]::panicking::default_hook [INFO] [stdout] 5: 0x5f17cf6cc160 - test[9d35eded1c95d3be]::test_main_inner::::{closure#0} [INFO] [stdout] 6: 0x5f17cf79fb22 - std[70759c8f55707aa4]::panicking::panic_with_hook [INFO] [stdout] 7: 0x5f17cf781b12 - std[70759c8f55707aa4]::panicking::panic_handler::{closure#0} [INFO] [stdout] 8: 0x5f17cf779f79 - std[70759c8f55707aa4]::sys::backtrace::__rust_end_short_backtrace:: [INFO] [stdout] 9: 0x5f17cf7828ed - __rustc[8fa7c3cbc660c2b3]::rust_begin_unwind [INFO] [stdout] 10: 0x5f17cf7c0fec - core[df12db4294e9bfd3]::panicking::panic_fmt [INFO] [stdout] 11: 0x5f17cf3cfcc5 - trommel[171620a9b650e0cc]::ingest::tests::memory_budget_evicts_the_oldest_records_during_ingest [INFO] [stdout] 12: 0x5f17cf391409 - >::call_once [INFO] [stdout] 13: 0x5f17cf6bf44b - test[9d35eded1c95d3be]::__rust_begin_short_backtrace::, fn() -> core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>> [INFO] [stdout] 14: 0x5f17cf6ccab5 - test[9d35eded1c95d3be]::run_test::{closure#0} [INFO] [stdout] 15: 0x5f17cf6c6374 - std[70759c8f55707aa4]::sys::backtrace::__rust_begin_short_backtrace:: [INFO] [stdout] 16: 0x5f17cf6cfc12 - ::{closure#1} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0} [INFO] [stdout] 17: 0x5f17cf7a7709 - ::new::thread_start [INFO] [stdout] 18: 0x7c22ce020dfa - [INFO] [stdout] 19: 0x7c22ce0b43d4 - clone [INFO] [stdout] 20: 0x0 - [INFO] [stdout] [INFO] [stdout] ---- index::tests::enforce_budget_does_not_collapse_to_one_record stdout ---- [INFO] [stdout] [INFO] [stdout] thread 'index::tests::enforce_budget_does_not_collapse_to_one_record' (3186) panicked at src/index.rs:595:9: [INFO] [stdout] the budget must have been tight enough to force eviction [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x5f17cf7a8a61 - <::print::DisplayBacktrace as core[df12db4294e9bfd3]::fmt::Display>::fmt [INFO] [stdout] 1: 0x5f17cf7c080a - core[df12db4294e9bfd3]::fmt::write [INFO] [stdout] 2: 0x5f17cf7adcec - as core[df12db4294e9bfd3]::io::write::Write>::write_fmt [INFO] [stdout] 3: 0x5f17cf781a66 - std[70759c8f55707aa4]::panicking::default_hook::{closure#0} [INFO] [stdout] 4: 0x5f17cf79f7f9 - std[70759c8f55707aa4]::panicking::default_hook [INFO] [stdout] 5: 0x5f17cf6cc160 - test[9d35eded1c95d3be]::test_main_inner::::{closure#0} [INFO] [stdout] 6: 0x5f17cf79fb22 - std[70759c8f55707aa4]::panicking::panic_with_hook [INFO] [stdout] 7: 0x5f17cf781b44 - std[70759c8f55707aa4]::panicking::panic_handler::{closure#0} [INFO] [stdout] 8: 0x5f17cf779f79 - std[70759c8f55707aa4]::sys::backtrace::__rust_end_short_backtrace:: [INFO] [stdout] 9: 0x5f17cf7828ed - __rustc[8fa7c3cbc660c2b3]::rust_begin_unwind [INFO] [stdout] 10: 0x5f17cf7c0fec - core[df12db4294e9bfd3]::panicking::panic_fmt [INFO] [stdout] 11: 0x5f17cf40c9de - trommel[171620a9b650e0cc]::index::tests::enforce_budget_does_not_collapse_to_one_record [INFO] [stdout] 12: 0x5f17cf390e39 - >::call_once [INFO] [stdout] 13: 0x5f17cf6bf44b - test[9d35eded1c95d3be]::__rust_begin_short_backtrace::, fn() -> core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>> [INFO] [stdout] 14: 0x5f17cf6ccab5 - test[9d35eded1c95d3be]::run_test::{closure#0} [INFO] [stdout] 15: 0x5f17cf6c6374 - std[70759c8f55707aa4]::sys::backtrace::__rust_begin_short_backtrace:: [INFO] [stdout] 16: 0x5f17cf6cfc12 - ::{closure#1} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0} [INFO] [stdout] 17: 0x5f17cf7a7709 - ::new::thread_start [INFO] [stdout] 18: 0x7c22ce020dfa - [INFO] [stdout] 19: 0x7c22ce0b43d4 - clone [INFO] [stdout] 20: 0x0 - [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] index::tests::enforce_budget_does_not_collapse_to_one_record [INFO] [stdout] index::tests::trace_presence_index_memory_cost_is_measured_via_the_counting_allocator [INFO] [stdout] ingest::tests::memory_budget_evicts_the_oldest_records_during_ingest [INFO] [stdout] mem::tests::realloc_is_counted_as_a_net_change [INFO] [stdout] [INFO] [stdout] test result: FAILED. 164 passed; 4 failed; 3 ignored; 0 measured; 0 filtered out; finished in 0.39s [INFO] [stdout] [INFO] [stderr] error: test failed, to rerun pass `--lib` [INFO] running `Command { std: "docker" "inspect" "f82505ae83e7428fe5ae1454ef87bd30b9494572784efe9aaac20fcceda6047c", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "f82505ae83e7428fe5ae1454ef87bd30b9494572784efe9aaac20fcceda6047c", kill_on_drop: false }` [INFO] [stdout] f82505ae83e7428fe5ae1454ef87bd30b9494572784efe9aaac20fcceda6047c