[INFO] cloning repository https://github.com/hoangnb24/logit
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/hoangnb24/logit" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fhoangnb24%2Flogit", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fhoangnb24%2Flogit'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 176ea17add6d9459953484fea5ee082025173782
[INFO] testing hoangnb24/logit against beta-2026-03-05 for beta-1.95-1
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fhoangnb24%2Flogit" "/workspace/builds/worker-5-tc2/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-5-tc2/source'...
[INFO] [stderr] done.
[INFO] removed /workspace/builds/worker-5-tc2/source/rust-toolchain.toml
[INFO] started tweaking git repo https://github.com/hoangnb24/logit
[INFO] finished tweaking git repo https://github.com/hoangnb24/logit
[INFO] tweaked toml for git repo https://github.com/hoangnb24/logit written to /workspace/builds/worker-5-tc2/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/hoangnb24/logit on toolchain beta-2026-03-05
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+beta-2026-03-05" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/hoangnb24/logit 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" "+beta-2026-03-05" "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/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-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" "+beta-2026-03-05" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 78e9caae706f25d9a852d4fd257f3ad259097b764f9bcc913a966aecb6d005cf
[INFO] running `Command { std: "docker" "start" "-a" "78e9caae706f25d9a852d4fd257f3ad259097b764f9bcc913a966aecb6d005cf", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "78e9caae706f25d9a852d4fd257f3ad259097b764f9bcc913a966aecb6d005cf", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "78e9caae706f25d9a852d4fd257f3ad259097b764f9bcc913a966aecb6d005cf", kill_on_drop: false }`
[INFO] [stdout] 78e9caae706f25d9a852d4fd257f3ad259097b764f9bcc913a966aecb6d005cf
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-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=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-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" "+beta-2026-03-05" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 2c5544b0b59edda1a144e9377a302b00bf1bd7cf11577742f6c9bb52aca1b929
[INFO] running `Command { std: "docker" "start" "-a" "2c5544b0b59edda1a144e9377a302b00bf1bd7cf11577742f6c9bb52aca1b929", kill_on_drop: false }`
[INFO] [stderr]    Compiling unicode-ident v1.0.24
[INFO] [stderr]    Compiling memchr v2.8.0
[INFO] [stderr]    Compiling serde_core v1.0.228
[INFO] [stderr]    Compiling zmij v1.0.21
[INFO] [stderr]    Compiling anstyle v1.0.13
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling serde_json v1.0.149
[INFO] [stderr]    Compiling cc v1.2.56
[INFO] [stderr]    Compiling anstyle-parse v0.2.7
[INFO] [stderr]    Compiling colorchoice v1.0.4
[INFO] [stderr]    Compiling anstyle-query v1.1.5
[INFO] [stderr]    Compiling is_terminal_polyfill v1.70.2
[INFO] [stderr]    Compiling ref-cast v1.0.25
[INFO] [stderr]    Compiling hashbrown v0.15.5
[INFO] [stderr]    Compiling anyhow v1.0.102
[INFO] [stderr]    Compiling regex-syntax v0.8.10
[INFO] [stderr]    Compiling proc-macro2 v1.0.106
[INFO] [stderr]    Compiling clap_lex v1.0.0
[INFO] [stderr]    Compiling deranged v0.5.8
[INFO] [stderr]    Compiling num-conv v0.2.0
[INFO] [stderr]    Compiling dyn-clone v1.0.20
[INFO] [stderr]    Compiling anstream v0.6.21
[INFO] [stderr]    Compiling bitflags v2.11.0
[INFO] [stderr]    Compiling time-core v0.1.8
[INFO] [stderr]    Compiling fallible-iterator v0.3.0
[INFO] [stderr]    Compiling smallvec v1.15.1
[INFO] [stderr]    Compiling fallible-streaming-iterator v0.1.9
[INFO] [stderr]    Compiling aho-corasick v1.1.4
[INFO] [stderr]    Compiling hashlink v0.10.0
[INFO] [stderr]    Compiling clap_builder v4.5.60
[INFO] [stderr]    Compiling quote v1.0.44
[INFO] [stderr]    Compiling syn v2.0.117
[INFO] [stderr]    Compiling libsqlite3-sys v0.35.0
[INFO] [stderr]    Compiling time v0.3.47
[INFO] [stderr]    Compiling regex-automata v0.4.14
[INFO] [stderr]    Compiling serde_derive_internals v0.29.1
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling ref-cast-impl v1.0.25
[INFO] [stderr]    Compiling clap_derive v4.5.55
[INFO] [stderr]    Compiling schemars_derive v1.2.1
[INFO] [stderr]    Compiling regex v1.12.3
[INFO] [stderr]    Compiling clap v4.5.60
[INFO] [stderr]    Compiling schemars v1.2.1
[INFO] [stderr]    Compiling rusqlite v0.37.0
[INFO] [stderr]    Compiling logit v0.1.0 (/opt/rustwide/workdir/crates/logit)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 39.82s
[INFO] running `Command { std: "docker" "inspect" "2c5544b0b59edda1a144e9377a302b00bf1bd7cf11577742f6c9bb52aca1b929", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "2c5544b0b59edda1a144e9377a302b00bf1bd7cf11577742f6c9bb52aca1b929", kill_on_drop: false }`
[INFO] [stdout] 2c5544b0b59edda1a144e9377a302b00bf1bd7cf11577742f6c9bb52aca1b929
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-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=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-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" "+beta-2026-03-05" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 255b6049d8430c531453978811b407f3063598bbb783b8a23490d3e082ddda5b
[INFO] running `Command { std: "docker" "start" "-a" "255b6049d8430c531453978811b407f3063598bbb783b8a23490d3e082ddda5b", kill_on_drop: false }`
[INFO] [stderr]    Compiling bitflags v2.11.0
[INFO] [stderr]    Compiling libc v0.2.182
[INFO] [stderr]    Compiling getrandom v0.4.1
[INFO] [stderr]    Compiling rustix v1.1.4
[INFO] [stderr]    Compiling linux-raw-sys v0.12.1
[INFO] [stderr]    Compiling similar v2.7.0
[INFO] [stderr]    Compiling rusqlite v0.37.0
[INFO] [stderr]    Compiling console v0.15.11
[INFO] [stderr]    Compiling logit v0.1.0 (/opt/rustwide/workdir/crates/logit)
[INFO] [stderr]    Compiling tempfile v3.26.0
[INFO] [stderr]    Compiling insta v1.46.3
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 32.31s
[INFO] running `Command { std: "docker" "inspect" "255b6049d8430c531453978811b407f3063598bbb783b8a23490d3e082ddda5b", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "255b6049d8430c531453978811b407f3063598bbb783b8a23490d3e082ddda5b", kill_on_drop: false }`
[INFO] [stdout] 255b6049d8430c531453978811b407f3063598bbb783b8a23490d3e082ddda5b
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-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=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-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" "+beta-2026-03-05" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] db0b2f9b24bce1f2160a73c6196cb9b90ed7e811c5b7ed6ce7754664f3ceb6da
[INFO] running `Command { std: "docker" "start" "-a" "db0b2f9b24bce1f2160a73c6196cb9b90ed7e811c5b7ed6ce7754664f3ceb6da", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.19s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/logit-1d6516a24c806625)
[INFO] [stdout] 
[INFO] [stdout] running 57 tests
[INFO] [stdout] test cli::commands::query::tests::rejects_empty_multi_statement_and_mutating_sql ... ok
[INFO] [stdout] test config::tests::defaults_out_dir_under_logit_output ... ok
[INFO] [stdout] test cli::commands::query::tests::allows_select_with_optional_trailing_semicolon ... ok
[INFO] [stdout] test cli::commands::query::tests::release_gate_fails_when_domain_and_total_scores_drop ... ok
[INFO] [stdout] test cli::commands::query::tests::release_gate_passes_for_high_scoring_summary ... ok
[INFO] [stdout] test config::tests::expands_tilde_override_against_home_dir ... ok
[INFO] [stdout] test config::tests::resolves_relative_override_against_cwd ... ok
[INFO] [stdout] test discovery::tests::candidate_path_projection_matches_detailed_candidates ... ok
[INFO] [stdout] test discovery::tests::classifies_binary_when_null_bytes_present ... ok
[INFO] [stdout] test cli::commands::query::tests::allows_with_and_explain_select_forms ... ok
[INFO] [stdout] test cli::commands::query::tests::params_parser_accepts_scalar_and_array_inputs ... ok
[INFO] [stdout] test discovery::tests::classifies_by_extension_when_available ... ok
[INFO] [stdout] test config::tests::rejects_tilde_username_syntax ... ok
[INFO] [stdout] test config::tests::rejects_non_absolute_home_dir ... ok
[INFO] [stdout] test discovery::tests::registry_covers_all_adapters ... ok
[INFO] [stdout] test discovery::tests::precedence_is_strictly_increasing_per_adapter ... ok
[INFO] [stdout] test discovery::tests::computes_zsh_history_scores_for_each_adapter ... ok
[INFO] [stdout] test utils::history::tests::parses_extended_and_plain_history_lines ... ok
[INFO] [stdout] test sqlite::tests::ensure_schema_preserves_legacy_schema_versions_and_adds_current_version ... ok
[INFO] [stdout] test utils::history::tests::counts_matching_lines ... ok
[INFO] [stdout] test discovery::tests::falls_back_to_text_log_for_plain_text ... ok
[INFO] [stdout] test discovery::tests::classifies_jsonl_from_content ... ok
[INFO] [stdout] test discovery::tests::classifies_json_document_from_content ... ok
[INFO] [stdout] test utils::history::tests::ignores_blank_or_commandless_extended_lines ... ok
[INFO] [stdout] test utils::redaction::tests::exposes_catalog_classes_in_deterministic_order ... ok
[INFO] [stdout] test utils::redaction::tests::keeps_empty_values_empty ... ok
[INFO] [stdout] test utils::history::tests::returns_zero_when_no_match ... ok
[INFO] [stdout] test utils::redaction::tests::redacts_non_empty_values ... ok
[INFO] [stdout] test utils::time::tests::derived_timestamp_adds_offset ... ok
[INFO] [stdout] test utils::time::tests::duration_derivation_exposes_source_and_quality_markers ... ok
[INFO] [stdout] test utils::time::tests::duration_derivation_rejects_inverted_pair_and_uses_heuristic ... ok
[INFO] [stdout] test utils::time::tests::duration_derivation_returns_none_when_no_inputs_available ... ok
[INFO] [stdout] test utils::time::tests::duration_derivation_uses_explicit_first ... ok
[INFO] [stdout] test utils::hash::tests::hash_differs_for_different_inputs ... ok
[INFO] [stdout] test utils::hash::tests::hash_is_stable_for_same_input ... ok
[INFO] [stdout] test utils::time::tests::duration_derivation_uses_paired_when_explicit_missing ... ok
[INFO] [stdout] test utils::time::tests::infers_epoch_millis ... ok
[INFO] [stdout] test utils::time::tests::infers_epoch_nanos ... ok
[INFO] [stdout] test utils::time::tests::fallback_timestamp_uses_run_anchor ... ok
[INFO] [stdout] test utils::time::tests::parses_rfc3339_utc ... ok
[INFO] [stdout] test utils::time::tests::infers_epoch_micros ... ok
[INFO] [stdout] test utils::time::tests::infers_epoch_seconds ... ok
[INFO] [stdout] test utils::time::tests::rejects_unsupported_string ... ok
[INFO] [stdout] test utils::time::tests::rejects_negative_epoch ... ok
[INFO] [stdout] test utils::history::tests::scores_adapter_frequency_and_keeps_zeroes ... ok
[INFO] [stdout] test sqlite::tests::ensure_schema_keeps_existing_local_tables_and_data ... ok
[INFO] [stdout] test utils::time::tests::parses_rfc3339_with_offset ... ok
[INFO] [stdout] test sqlite::tests::ensure_schema_is_idempotent_and_preserves_schema_version_metadata ... ok
[INFO] [stdout] test sqlite::tests::ensure_schema_creates_mart_tables ... ok
[INFO] [stdout] test utils::time::tests::duration_derivation_falls_back_to_heuristic ... ok
[INFO] [stdout] test utils::redaction::tests::redacts_binary_like_text_via_heuristic_matcher ... ok
[INFO] [stdout] test utils::redaction::tests::truncates_deterministically_after_redaction ... ok
[INFO] [stdout] test utils::redaction::tests::redacts_sensitive_patterns_and_tracks_classes ... ok
[INFO] [stdout] test utils::redaction::tests::redacts_nested_json_strings ... ok
[INFO] [stdout] test utils::redaction::tests::applies_catalog_order_for_overlapping_token_patterns ... ok
[INFO] [stdout] test cli::commands::query::tests::rejects_explain_non_select_statements ... ok
[INFO] [stdout] test cli::commands::query::tests::sql_profile_reports_shape_and_scan_hints ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 57 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.10s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/logit-77b0a89e02b8c5e1)
[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/adapter_expectation_tables.rs (/opt/rustwide/target/debug/deps/adapter_expectation_tables-2263e628f33924a9)
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test fixture_corpus_canonical_expectation_table_is_stable ... ok
[INFO] [stdout] test fixture_corpus_metadata_expectation_table_is_stable ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/adapter_mapping_matrix.rs (/opt/rustwide/target/debug/deps/adapter_mapping_matrix-5cce30286ffd4615)
[INFO] [stderr]      Running tests/agentlog_schema.rs (/opt/rustwide/target/debug/deps/agentlog_schema-d52246fc1a11c714)
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test amp_fixture_expectation_table_matches_thread_envelope_contract ... ok
[INFO] [stdout] test adapter_mapping_matrix_matches_fixture_expectation_table ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test serialization_omits_optional_fields_when_empty ... ok
[INFO] [stdout] test schema_marks_core_fields_as_required ... ok
[INFO] [stdout] test normalize_writes_schema_artifact_json ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/amp_content_parts.rs (/opt/rustwide/target/debug/deps/amp_content_parts-bedf938940ebf043)
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stderr]      Running tests/amp_file_change_parser.rs (/opt/rustwide/target/debug/deps/amp_file_change_parser-82e915824c2f009f)
[INFO] [stdout] test surfaces_no_content_parts_for_malformed_fixture_shapes ... ok
[INFO] [stdout] test flattens_nested_typed_content_arrays_without_text_duplication ... ok
[INFO] [stdout] test concatenates_text_bearing_parts_in_path_order_for_full_text_and_excerpt ... ok
[INFO] [stdout] test extracts_canonical_text_excerpt_and_parts_from_amp_fixture ... 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] [stdout] 
[INFO] [stdout] running 6 tests
[INFO] [stdout] test parses_attachment_blob_limit_fixture_into_telemetry ... ok
[INFO] [stdout] test parses_file_change_record_from_disk ... ok
[INFO] [stdout] test handles_malformed_file_change_shapes_without_crashing ... ok
[INFO] [stdout] test parses_root_style_file_change_record_into_canonical_event ... ok
[INFO] [stdout] test parses_file_change_rows_and_truncates_large_blobs ... ok
[INFO] [stdout] test supports_alias_fields_and_string_blob_limit_values ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.03s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/amp_thread_parser.rs (/opt/rustwide/target/debug/deps/amp_thread_parser-ccde2ffcb59b249a)
[INFO] [stdout] 
[INFO] [stdout] running 7 tests
[INFO] [stdout] test parses_amp_auxiliary_history_session_file_from_disk ... ok
[INFO] [stdout] test surfaces_warnings_for_malformed_parts_and_null_timestamps ... ok
[INFO] [stdout] test rejects_envelopes_without_messages_array ... ok
[INFO] [stdout] test defaults_non_string_part_type_to_unknown_with_warning ... ok
[INFO] [stdout] test skips_messages_with_non_string_id_or_role_without_failing ... ok
[INFO] [stdout] test parses_amp_thread_fixture_metadata ... ok
[INFO] [stdout] test parses_amp_auxiliary_history_session_rows ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/claude_session_parser.rs (/opt/rustwide/target/debug/deps/claude_session_parser-7f26d0e4a2c3503f)
[INFO] [stdout] 
[INFO] [stdout] running 17 tests
[INFO] [stdout] test history_parser_prefers_message_content_fallback_chain_after_prompt_response ... ok
[INFO] [stdout] test maps_core_project_session_kinds_to_canonical_families ... ok
[INFO] [stdout] test handles_malformed_subagent_trace_records_without_crashing ... ok
[INFO] [stdout] test maps_subagent_and_history_core_roles_to_canonical_families ... ok
[INFO] [stdout] test maps_system_kind_to_system_notice ... ok
[INFO] [stdout] test infers_assistant_role_for_history_rows_with_response_only ... ok
[INFO] [stdout] test dispatches_history_and_mcp_cache_files_in_parse_project_session_file ... ok
[INFO] [stdout] test handles_malformed_and_unknown_kinds_without_crashing ... ok
[INFO] [stdout] test handles_mcp_cache_lines_without_timestamp_prefix ... ok
[INFO] [stdout] test parses_claude_project_session_file_from_disk ... ok
[INFO] [stdout] test parses_history_jsonl_as_auxiliary_events ... ok
[INFO] [stdout] test parses_mcp_cache_debug_log_as_diagnostic_events ... ok
[INFO] [stdout] test prefers_message_content_then_message_text_then_message_serialization ... ok
[INFO] [stdout] test parses_claude_project_session_fixture_to_canonical_events ... ok
[INFO] [stdout] test parses_file_history_snapshot_rows_as_artifact_references ... ok
[INFO] [stdout] test parses_modern_type_rows_and_tool_blocks ... ok
[INFO] [stdout] test parses_subagent_trace_fixture_with_delegated_tags ... ok
[INFO] [stderr]      Running tests/cli_progress.rs (/opt/rustwide/target/debug/deps/cli_progress-f5957e24f9152e78)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 17 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 14 tests
[INFO] [stdout] test query_catalog_emits_domain_concepts_for_agent_planning ... ok
[INFO] [stdout] test ingest_failure_prints_start_and_failure_progress ... ok
[INFO] [stdout] test query_multi_statement_is_rejected_with_guardrail_error_envelope ... ok
[INFO] [stdout] test validate_prints_success_summary ... ok
[INFO] [stdout] test validate_prints_failure_summary_on_stderr ... ok
[INFO] [stdout] test query_catalog_verbose_includes_field_catalog_details ... ok
[INFO] [stdout] test query_benchmark_invalid_corpus_path_emits_error_envelope ... ok
[INFO] [stdout] test query_params_and_row_cap_emit_bound_and_truncation_metadata ... ok
[INFO] [stdout] test query_success_emits_runtime_metadata_envelope ... ok
[INFO] [stdout] test normalize_prints_stage_progress_and_summary ... ok
[INFO] [stdout] test query_schema_emits_machine_readable_table_and_view_metadata ... ok
[INFO] [stdout] test query_schema_include_internal_surfaces_schema_meta_table ... ok
[INFO] [stdout] test ingest_success_emits_machine_readable_json_report ... ok
[INFO] [stdout] test query_benchmark_emits_per_question_results_and_artifact ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 14 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.22s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/cli_surface.rs (/opt/rustwide/target/debug/deps/cli_surface-ffb539fd0d79aa63)
[INFO] [stdout] 
[INFO] [stdout] running 6 tests
[INFO] [stdout] test parses_global_runtime_flags_for_snapshot ... ok
[INFO] [stdout] test parses_inspect_json_flag ... ok
[INFO] [stdout] test parses_normalize_fail_fast_flag ... ok
[INFO] [stdout] test parses_validate_strict_flag ... ok
[INFO] [stdout] test parses_ingest_refresh_with_global_runtime_flags ... ok
[INFO] [stdout] test parses_query_namespace_subcommands ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/codex_edge_cases.rs (/opt/rustwide/target/debug/deps/codex_edge_cases-da6b36dfa90af35e)
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test history_null_role_and_sparse_fields_fall_back_without_crashing ... ok
[INFO] [stdout] test diagnostic_log_unknown_level_is_warned_and_mapped_to_debug_log ... ok
[INFO] [stdout] test rollout_unknown_event_type_and_missing_message_text_are_explicitly_warned ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/codex_event_msg_mapping.rs (/opt/rustwide/target/debug/deps/codex_event_msg_mapping-ef8d31691ade504a)
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stderr]      Running tests/codex_history_parser.rs (/opt/rustwide/target/debug/deps/codex_history_parser-f410d341a87e3dce)
[INFO] [stdout] test maps_event_msg_meta_family_to_system_notice_with_meta_category ... ok
[INFO] [stdout] test maps_unknown_event_msg_suffix_to_generic_progress_category ... ok
[INFO] [stdout] test maps_event_msg_progress_family_to_status_update_with_progress_category ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test conversational_canonical_hash_matches_rollout_for_exact_duplicate_messages ... ok
[INFO] [stdout] test parses_history_fixture_as_auxiliary_prompt_events ... ok
[INFO] [stdout] test parses_history_file_from_disk ... ok
[INFO] [stdout] test handles_malformed_history_lines_without_crashing ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/codex_log_parser.rs (/opt/rustwide/target/debug/deps/codex_log_parser-3204e660594cfa06)
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test handles_malformed_and_invalid_timestamp_log_lines_without_crashing ... ok
[INFO] [stdout] test parses_diagnostic_log_file_from_disk ... ok
[INFO] [stdout] test parses_tui_and_desktop_diagnostic_logs_to_runtime_events ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/codex_response_item_variants.rs (/opt/rustwide/target/debug/deps/codex_response_item_variants-895820395346e64e)
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test sparse_response_item_still_emits_message_with_explicit_warning ... ok
[INFO] [stdout] test assistant_response_uses_response_items_array_content ... ok
[INFO] [stdout] test assistant_response_uses_response_item_object_content ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/codex_rollout_parser.rs (/opt/rustwide/target/debug/deps/codex_rollout_parser-120855528b0448b5)
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test maps_event_msg_family_to_status_updates ... ok
[INFO] [stdout] test parses_rollout_file_from_disk ... ok
[INFO] [stdout] test parses_primary_rollout_fixture_to_canonical_events ... ok
[INFO] [stdout] test handles_malformed_lines_and_missing_timestamps_without_crashing ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stderr]      Running tests/content_extraction.rs (/opt/rustwide/target/debug/deps/content_extraction-10eb6e0734d309d7)
[INFO] [stdout] 
[INFO] [stderr]      Running tests/discovery_artifacts.rs (/opt/rustwide/target/debug/deps/discovery_artifacts-a7cdda943021e337)
[INFO] [stdout] 
[INFO] [stdout] running 7 tests
[INFO] [stdout] test excerpt_returns_none_for_empty_or_zero_limit ... ok
[INFO] [stdout] test extracts_text_from_priority_keys ... ok
[INFO] [stdout] test excerpt_is_deterministic_and_truncated ... ok
[INFO] [stdout] test extract_text_and_excerpt_return_consistent_results ... ok
[INFO] [stdout] test extracts_and_joins_text_from_content_array ... ok
[INFO] [stdout] test returns_none_for_metadata_only_object ... ok
[INFO] [stdout] test falls_back_to_sorted_non_priority_keys ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test discovery_artifact_layout_uses_contract_filenames ... ok
[INFO] [stdout] test normalize_command_emits_discovery_evidence_artifacts ... ok
[INFO] [stdout] test normalize_smoke_handles_realistic_local_home_and_adapter_coverage ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.07s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/exit_code_contract.rs (/opt/rustwide/target/debug/deps/exit_code_contract-4b3ef582e508d0f0)
[INFO] [stdout] 
[INFO] [stdout] running 9 tests
[INFO] [stderr] error: the following required arguments were not provided:
[INFO] [stdout] {"ok":false,"command":"query.benchmark","generated_at_utc":"2026-03-10T06:28:27.000Z","meta":{"corpus_path":"/definitely/missing/answerability_question_corpus_v1.json","implemented":true,"schema_version":"logit.query-envelope.v1"},"warnings":[],"error":{"code":"query_benchmark_corpus_invalid","message":"failed to load answerability benchmark corpus","details":{"cause":"failed to read answerability corpus: /definitely/missing/answerability_question_corpus_v1.json: No such file or directory (os error 2)"}}}
[INFO] [stderr]   <INPUT>
[INFO] [stdout] {"ok":false,"command":"ingest.refresh","generated_at_utc":"2026-03-10T06:28:27.000Z","meta":{"events_jsonl_path":"/tmp/logit-exit-ingest-runtime-fail-1773124107759799211/out/events.jsonl","fail_fast":true,"schema_version":"logit.query-envelope.v1","sqlite_path":"/tmp/logit-exit-ingest-runtime-fail-1773124107759799211/out/mart.sqlite"},"warnings":[],"error":{"code":"ingest_events_missing","message":"ingest refresh failed","details":{"cause":"failed to read normalized events file: /tmp/logit-exit-ingest-runtime-fail-1773124107759799211/out/events.jsonl: No such file or directory (os error 2)"}}}
[INFO] [stderr] 
[INFO] [stderr] Usage: logit validate <INPUT>
[INFO] [stderr] 
[INFO] [stderr] For more information, try '--help'.
[INFO] [stdout] {"ok":true,"command":"query.sql","generated_at_utc":"2026-03-10T06:28:27.000Z","data":{"rows":[{"1":1}]},"meta":{"diagnostics":{"has_group_by":false,"has_limit":false,"has_order_by":false,"has_where":false,"latency_bucket":"fast","likely_full_scan":true,"returned_rows":1,"row_cap":1000,"sql_length_bytes":8,"statement_kind":"select","truncation_reason":"none","uses_explain":false},"duration_ms":0,"guardrail_checked":true,"implemented":true,"params_count":0,"row_cap":1000,"row_count":1,"schema_version":"logit.query-envelope.v1","truncated":false},"warnings":[]}
[INFO] [stdout] logit: starting `validate`
[INFO] [stdout] validate: start mode=baseline input=/tmp/logit-exit-success-1773124107772534390/events.jsonl out_dir=/tmp/logit-exit-success-1773124107772534390/out
[INFO] [stdout] test missing_required_args_exits_with_usage_code ... ok
[INFO] [stdout] test ingest_refresh_missing_events_exits_runtime_failure_code ... ok
[INFO] [stdout] test query_benchmark_with_missing_corpus_exits_runtime_failure ... ok
[INFO] [stdout] test query_sql_read_only_success_exits_zero ... ok
[INFO] [stdout] validate: report status=warn records=1 validated=1 errors=0 warnings=2 report=/tmp/logit-exit-success-1773124107772534390/out/validate/report.json
[INFO] [stdout] validate: complete exit_code=0 next=review /tmp/logit-exit-success-1773124107772534390/out/validate/report.json
[INFO] [stdout] logit: completed `validate` (exit_code=0)
[INFO] [stdout] test successful_validate_exits_zero ... ok
[INFO] [stderr] logit: failed `validate` (exit_code=1)
[INFO] [stdout] logit: starting `validate`
[INFO] [stderr] home_dir must be absolute: relative
[INFO] [stdout] test runtime_path_resolution_failures_exit_with_runtime_code ... ok
[INFO] [stdout] logit: starting `validate`
[INFO] [stderr] validate: failed errors=1 warnings=0 next=inspect_report
[INFO] [stdout] validate: start mode=baseline input=/tmp/logit-exit-validation-fail-1773124107779754010/invalid-events.jsonl out_dir=/tmp/logit-exit-validation-fail-1773124107779754010/out
[INFO] [stderr] logit: failed `validate` (exit_code=2)
[INFO] [stdout] validate: report status=fail records=1 validated=0 errors=1 warnings=0 report=/tmp/logit-exit-validation-fail-1773124107779754010/out/validate/report.json
[INFO] [stderr] validation failed with 1 error(s). line 1: invalid JSON: expected ident at line 1 column 2
[INFO] [stdout] test validation_failures_exit_with_validation_code ... ok
[INFO] [stdout] {"ok":true,"command":"ingest.refresh","generated_at_utc":"2026-03-10T06:28:27.000Z","data":{"counts":{"inserted":1,"read":1,"skipped":0,"updated":0},"duration_ms":130,"finished_at_utc":"2026-03-10T06:28:27.886941902Z","ingest_run_id":"ingest-189b66fb4a38d141","schema_version":"logit.ingest-report.v1","source_root":"/tmp/logit-exit-ingest-success-1773124107759858411/cwd","started_at_utc":"2026-03-10T06:28:27.768433001Z","status":"success","warnings":[],"watermarks":{"sources_upserted":1,"staleness_state":"fresh"}},"meta":{"artifact_path":"/tmp/logit-exit-ingest-success-1773124107759858411/out/ingest/report.json","fail_fast":false,"schema_version":"logit.query-envelope.v1"},"warnings":[]}
[INFO] [stdout] test ingest_refresh_with_valid_events_exits_zero ... ok
[INFO] [stdout] {"ok":true,"command":"ingest.refresh","generated_at_utc":"2026-03-10T06:28:27.000Z","data":{"counts":{"inserted":1,"read":1,"skipped":0,"updated":0},"duration_ms":125,"finished_at_utc":"2026-03-10T06:28:27.897605831Z","ingest_run_id":"ingest-189b66fb4b0c106e","schema_version":"logit.ingest-report.v1","source_root":"/tmp/logit-exit-query-benchmark-success-1773124107770025471/cwd","started_at_utc":"2026-03-10T06:28:27.78228919Z","status":"success","warnings":[],"watermarks":{"sources_upserted":1,"staleness_state":"fresh"}},"meta":{"artifact_path":"/tmp/logit-exit-query-benchmark-success-1773124107770025471/out/ingest/report.json","fail_fast":false,"schema_version":"logit.query-envelope.v1"},"warnings":[]}
[INFO] [stdout] {"ok":true,"command":"query.benchmark","generated_at_utc":"2026-03-10T06:28:27.000Z","data":{"all_data_synthetic":true,"corpus_domains":["usage","performance","freshness","reliability"],"corpus_generated_at_utc":"2026-02-26T00:00:00Z","corpus_id":"canonical-user-question-corpus-v1","corpus_schema_version":"logit.answerability-corpus.v1","preflight":{"schema_table_count":3,"schema_view_count":4,"semantic_concept_count":4,"semantic_relation_count":6},"questions":[{"answer_kind":"table","column_names":["source_kind","last_successful_refresh_at_utc"],"domain":"freshness","duration_ms":0,"failure_code":null,"failure_message":null,"id":"q-freshness-001","missing_required_fields":[],"must_include":["source_kind","last_successful_refresh_at_utc"],"ordering":null,"passed":true,"query_interface":"query.sql","queryability_assumptions":["ingest run metadata is queryable","source-level freshness metadata exists"],"question":"When did each source kind last refresh successfully?","rationale":"Directly validates freshness introspection per source family.","row_count":1,"sql":"\nSELECT source_kind,\n       MAX(refreshed_at_utc) AS last_successful_refresh_at_utc\nFROM ingest_watermarks\nGROUP BY source_kind\nORDER BY source_kind ASC\n","threshold":null,"truncated":false,"warnings":[]},{"answer_kind":"table","column_names":["source_key","staleness_state","staleness_age_ms"],"domain":"freshness","duration_ms":0,"failure_code":null,"failure_message":null,"id":"q-freshness-002","missing_required_fields":[],"must_include":["source_key","staleness_state","staleness_age_ms"],"ordering":null,"passed":true,"query_interface":"query.sql","queryability_assumptions":["watermark staleness state is materialized","age can be derived from refreshed timestamps"],"question":"Which sources are currently marked stale and by how much?","rationale":"Confirms the system can surface stale inputs before analysis.","row_count":0,"sql":"\nWITH latest AS (\n    SELECT MAX(CAST(strftime('%s', refreshed_at_utc) AS INTEGER)) AS max_refreshed_s\n    FROM ingest_watermarks\n)\nSELECT\n    source_key,\n    staleness_state,\n    CASE\n        WHEN latest.max_refreshed_s IS NULL THEN 0\n        ELSE (\n            latest.max_refreshed_s - COALESCE(CAST(strftime('%s', refreshed_at_utc) AS INTEGER), latest.max_refreshed_s)\n        ) * 1000\n    END AS staleness_age_ms\nFROM ingest_watermarks\nCROSS JOIN latest\nWHERE staleness_state = 'stale'\nORDER BY staleness_age_ms DESC, source_key ASC\n","threshold":null,"truncated":false,"warnings":["query returned zero rows; shape validated by projected columns"]},{"answer_kind":"scalar","column_names":["refresh_success_rate_pct"],"domain":"freshness","duration_ms":0,"failure_code":null,"failure_message":null,"id":"q-freshness-003","missing_required_fields":[],"must_include":["refresh_success_rate_pct"],"ordering":null,"passed":true,"query_interface":"query.sql","queryability_assumptions":["ingest run status history is retained","windowed success-rate computation is possible"],"question":"What is the refresh success rate over the last 30 ingest runs?","rationale":"Checks operational stability of ingest lifecycle over recent history.","row_count":1,"sql":"\nWITH recent AS (\n    SELECT status\n    FROM ingest_runs\n    ORDER BY started_at_utc DESC\n    LIMIT 30\n)\nSELECT\n    CASE\n        WHEN COUNT(*) = 0 THEN 0.0\n        ELSE ROUND(100.0 * SUM(CASE WHEN status = 'success' THEN 1 ELSE 0 END) / COUNT(*), 2)\n    END AS refresh_success_rate_pct\nFROM recent\n","threshold":null,"truncated":false,"warnings":[]},{"answer_kind":"scalar","column_names":["events_written"],"domain":"freshness","duration_ms":0,"failure_code":null,"failure_message":null,"id":"q-freshness-004","missing_required_fields":[],"must_include":["events_written"],"ordering":null,"passed":true,"query_interface":"query.sql","queryability_assumptions":["latest successful ingest run can be identified deterministically","run-level event counts are tracked"],"question":"How many queryable events were written in the most recent successful refresh?","rationale":"Validates minimal freshness-volume diagnostics for operators.","row_count":1,"sql":"\nWITH latest_success AS (\n    SELECT events_written\n    FROM ingest_runs\n    WHERE status = 'success'\n    ORDER BY started_at_utc DESC\n    LIMIT 1\n)\nSELECT COALESCE((SELECT events_written FROM latest_success), 0) AS events_written\n","threshold":null,"truncated":false,"warnings":[]},{"answer_kind":"table","column_names":["tool_name","p50_duration_ms","p95_duration_ms"],"domain":"performance","duration_ms":1,"failure_code":null,"failure_message":null,"id":"q-performance-001","missing_required_fields":[],"must_include":["tool_name","p50_duration_ms","p95_duration_ms"],"ordering":null,"passed":true,"query_interface":"query.sql","queryability_assumptions":["tool duration metrics are materialized with provenance","percentile aggregation is available"],"question":"What are p50/p95 tool execution durations by tool over the last 24 hours?","rationale":"Targets latency hotspot identification for common tooling.","row_count":0,"sql":"\nWITH bounds AS (\n    SELECT MAX(call_timestamp_unix_ms) AS max_ts\n    FROM v_tool_calls\n),\nwindowed AS (\n    SELECT COALESCE(tool_name, 'unknown') AS tool_name, duration_ms\n    FROM v_tool_calls, bounds\n    WHERE pairing_status = 'paired'\n      AND duration_ms IS NOT NULL\n      AND (\n            bounds.max_ts IS NULL\n            OR call_timestamp_unix_ms >= bounds.max_ts - 86400000\n      )\n),\nranked AS (\n    SELECT\n        tool_name,\n        duration_ms,\n        ROW_NUMBER() OVER (PARTITION BY tool_name ORDER BY duration_ms ASC) AS rn,\n        COUNT(*) OVER (PARTITION BY tool_name) AS cnt\n    FROM windowed\n)\nSELECT\n    tool_name,\n    MIN(CASE WHEN rn >= ((cnt + 1) / 2) THEN duration_ms END) AS p50_duration_ms,\n    MIN(CASE WHEN rn >= ((cnt * 95 + 99) / 100) THEN duration_ms END) AS p95_duration_ms\nFROM ranked\nGROUP BY tool_name\nORDER BY tool_name ASC\n","threshold":null,"truncated":false,"warnings":["query returned zero rows; shape validated by projected columns"]},{"answer_kind":"ranked_list","column_names":["session_id","total_tool_duration_ms"],"domain":"performance","duration_ms":0,"failure_code":null,"failure_message":null,"id":"q-performance-002","missing_required_fields":[],"must_include":["session_id","total_tool_duration_ms"],"ordering":"total_tool_duration_ms_desc","passed":true,"query_interface":"query.sql","queryability_assumptions":["session ids are stable","durations are additive with consistent units"],"question":"Which sessions have the highest cumulative tool execution time?","rationale":"Checks roll-up performance analysis at session granularity.","row_count":0,"sql":"\nSELECT session_id,\n       SUM(duration_ms) AS total_tool_duration_ms\nFROM v_tool_calls\nWHERE pairing_status = 'paired'\n  AND session_id IS NOT NULL\n  AND session_id != ''\n  AND duration_ms IS NOT NULL\nGROUP BY session_id\nORDER BY total_tool_duration_ms DESC, session_id ASC\nLIMIT 50\n","threshold":null,"truncated":false,"warnings":["query returned zero rows; shape validated by projected columns"]},{"answer_kind":"distribution","column_names":["adapter_name","slow_call_pct"],"domain":"performance","duration_ms":0,"failure_code":null,"failure_message":null,"id":"q-performance-003","missing_required_fields":[],"must_include":["adapter_name","slow_call_pct"],"ordering":null,"passed":true,"query_interface":"query.sql","queryability_assumptions":["duration fields are queryable by adapter","conditional aggregation is supported"],"question":"What percentage of tool calls exceed a 2 second latency threshold by adapter?","rationale":"Measures adapter-level performance reliability under a fixed SLO-style threshold.","row_count":0,"sql":"\nSELECT adapter_name,\n       ROUND(\n           100.0 * SUM(CASE WHEN duration_ms > 2000 THEN 1 ELSE 0 END) / COUNT(*),\n           2\n       ) AS slow_call_pct\nFROM v_tool_calls\nWHERE pairing_status = 'paired'\n  AND duration_ms IS NOT NULL\nGROUP BY adapter_name\nORDER BY slow_call_pct DESC, adapter_name ASC\n","threshold":"duration_ms > 2000","truncated":false,"warnings":["query returned zero rows; shape validated by projected columns"]},{"answer_kind":"time_series","column_names":["hour_utc","tool_name","median_duration_ms"],"domain":"performance","duration_ms":1,"failure_code":null,"failure_message":null,"id":"q-performance-004","missing_required_fields":[],"must_include":["hour_utc","tool_name","median_duration_ms"],"ordering":"hour_utc_asc","passed":true,"query_interface":"query.sql","queryability_assumptions":["hourly bucketing by timestamp is available","top-N tools can be determined from usage counts"],"question":"How does median tool latency trend by hour for the top 3 most-used tools?","rationale":"Verifies compound analytics combining usage ranking and latency trends.","row_count":0,"sql":"\nWITH top_tools AS (\n    SELECT COALESCE(tool_name, 'unknown') AS tool_name, COUNT(*) AS invocation_count\n    FROM v_tool_calls\n    WHERE pairing_status = 'paired'\n      AND duration_ms IS NOT NULL\n    GROUP BY COALESCE(tool_name, 'unknown')\n    ORDER BY invocation_count DESC, tool_name ASC\n    LIMIT 3\n),\nhourly AS (\n    SELECT\n        strftime('%Y-%m-%dT%H:00:00Z', call_timestamp_unix_ms / 1000, 'unixepoch') AS hour_utc,\n        COALESCE(tool_name, 'unknown') AS tool_name,\n        duration_ms\n    FROM v_tool_calls\n    WHERE pairing_status = 'paired'\n      AND duration_ms IS NOT NULL\n      AND COALESCE(tool_name, 'unknown') IN (SELECT tool_name FROM top_tools)\n),\nranked AS (\n    SELECT\n        hour_utc,\n        tool_name,\n        duration_ms,\n        ROW_NUMBER() OVER (PARTITION BY hour_utc, tool_name ORDER BY duration_ms ASC) AS rn,\n        COUNT(*) OVER (PARTITION BY hour_utc, tool_name) AS cnt\n    FROM hourly\n)\nSELECT\n    hour_utc,\n    tool_name,\n    MIN(CASE WHEN rn >= ((cnt + 1) / 2) THEN duration_ms END) AS median_duration_ms\nFROM ranked\nGROUP BY hour_utc, tool_name\nORDER BY hour_utc ASC, tool_name ASC\n","threshold":null,"truncated":false,"warnings":["query returned zero rows; shape validated by projected columns"]},{"answer_kind":"ranked_list","column_names":["warning_category","warning_count"],"domain":"reliability","duration_ms":0,"failure_code":null,"failure_message":null,"id":"q-reliability-001","missing_required_fields":[],"must_include":["warning_category","warning_count"],"ordering":"warning_count_desc","passed":true,"query_interface":"query.sql","queryability_assumptions":["warning metadata is preserved in queryable rows","categorization/grouping on warning payloads is supported"],"question":"What are the top warning categories emitted during normalization in the last 7 days?","rationale":"Surfaces recurrent quality issues affecting trust in outputs.","row_count":0,"sql":"\nWITH bounds AS (\n    SELECT MAX(timestamp_unix_ms) AS max_ts\n    FROM agentlog_events\n),\nwindowed AS (\n    SELECT warnings_json\n    FROM agentlog_events, bounds\n    WHERE warnings_json != '[]'\n      AND (\n            bounds.max_ts IS NULL\n            OR timestamp_unix_ms >= bounds.max_ts - 604800000\n      )\n)\nSELECT warnings_json AS warning_category, COUNT(*) AS warning_count\nFROM windowed\nGROUP BY warnings_json\nORDER BY warning_count DESC, warning_category ASC\nLIMIT 50\n","threshold":null,"truncated":false,"warnings":["query returned zero rows; shape validated by projected columns"]},{"answer_kind":"distribution","column_names":["adapter_name","fallback_timestamp_count"],"domain":"reliability","duration_ms":2,"failure_code":null,"failure_message":null,"id":"q-reliability-002","missing_required_fields":[],"must_include":["adapter_name","fallback_timestamp_count"],"ordering":null,"passed":true,"query_interface":"query.sql","queryability_assumptions":["timestamp quality markers survive materialization","adapter-level grouping is supported"],"question":"How many records have fallback timestamp quality by adapter?","rationale":"Checks temporal data quality and reliability by source.","row_count":1,"sql":"\nSELECT\n    adapter_name,\n    SUM(CASE WHEN timestamp_quality = 'fallback' THEN event_count ELSE 0 END) AS fallback_timestamp_count\nFROM v_quality\nGROUP BY adapter_name\nORDER BY fallback_timestamp_count DESC, adapter_name ASC\n","threshold":null,"truncated":false,"warnings":[]},{"answer_kind":"boolean","column_names":["has_duplicate_canonical_hashes","duplicate_count"],"domain":"reliability","duration_ms":0,"failure_code":null,"failure_message":null,"id":"q-reliability-003","missing_required_fields":[],"must_include":["has_duplicate_canonical_hashes","duplicate_count"],"ordering":null,"passed":true,"query_interface":"query.sql","queryability_assumptions":["canonical hash is queryable","dedupe diagnostics can be expressed in SQL results"],"question":"Are there duplicate canonical hashes in the latest ingest window?","rationale":"Validates integrity and idempotency expectations.","row_count":1,"sql":"\nWITH latest_run AS (\n    SELECT ingest_run_id AS run_id\n    FROM ingest_runs\n    ORDER BY started_at_utc DESC\n    LIMIT 1\n),\nduplicates AS (\n    SELECT canonical_hash, COUNT(*) AS duplicate_rows\n    FROM agentlog_events\n    WHERE run_id = (SELECT run_id FROM latest_run)\n    GROUP BY canonical_hash\n    HAVING COUNT(*) > 1\n)\nSELECT\n    CASE WHEN COUNT(*) > 0 THEN 1 ELSE 0 END AS has_duplicate_canonical_hashes,\n    COALESCE(SUM(duplicate_rows - 1), 0) AS duplicate_count\nFROM duplicates\n","threshold":null,"truncated":false,"warnings":[]},{"answer_kind":"scalar","column_names":["degraded_run_fraction_pct"],"domain":"reliability","duration_ms":0,"failure_code":null,"failure_message":null,"id":"q-reliability-004","missing_required_fields":[],"must_include":["degraded_run_fraction_pct"],"ordering":null,"passed":true,"query_interface":"query.sql","queryability_assumptions":["ingest run statuses are persisted","status-based fraction computation is supported"],"question":"What fraction of ingest runs ended in failed or partial_failure states?","rationale":"Provides an operational reliability KPI for release gating.","row_count":1,"sql":"\nSELECT\n    CASE\n        WHEN COUNT(*) = 0 THEN 0.0\n        ELSE ROUND(\n            100.0 * SUM(CASE WHEN status IN ('failed', 'partial_failure') THEN 1 ELSE 0 END) / COUNT(*),\n            2\n        )\n    END AS degraded_run_fraction_pct\nFROM ingest_runs\n","threshold":null,"truncated":false,"warnings":[]},{"answer_kind":"ranked_list","column_names":["tool_name","invocation_count"],"domain":"usage","duration_ms":0,"failure_code":null,"failure_message":null,"id":"q-usage-001","missing_required_fields":[],"must_include":["tool_name","invocation_count"],"ordering":"invocation_count_desc","passed":true,"query_interface":"query.sql","queryability_assumptions":["tool invocation events are queryable","time filtering by event timestamp is available"],"question":"Which tools are invoked most frequently in the last 7 days?","rationale":"Validates baseline ability to answer operational tool-adoption questions.","row_count":0,"sql":"\nWITH bounds AS (\n    SELECT MAX(timestamp_unix_ms) AS max_ts\n    FROM agentlog_events\n),\nwindowed AS (\n    SELECT COALESCE(tool_name, 'unknown') AS tool_name\n    FROM v_tool_calls, bounds\n    WHERE call_event_id IS NOT NULL\n      AND (\n            bounds.max_ts IS NULL\n            OR call_timestamp_unix_ms >= bounds.max_ts - 604800000\n      )\n)\nSELECT tool_name, COUNT(*) AS invocation_count\nFROM windowed\nGROUP BY tool_name\nORDER BY invocation_count DESC, tool_name ASC\nLIMIT 50\n","threshold":null,"truncated":false,"warnings":["query returned zero rows; shape validated by projected columns"]},{"answer_kind":"time_series","column_names":["day_utc","unique_sessions"],"domain":"usage","duration_ms":0,"failure_code":null,"failure_message":null,"id":"q-usage-002","missing_required_fields":[],"must_include":["day_utc","unique_sessions"],"ordering":"day_utc_asc","passed":true,"query_interface":"query.sql","queryability_assumptions":["session identity is preserved in queryable storage","date bucketing is supported"],"question":"How many unique sessions were active each day over the last 14 days?","rationale":"Checks session grouping semantics and trend reporting.","row_count":0,"sql":"\nWITH bounds AS (\n    SELECT MAX(timestamp_unix_ms) AS max_ts\n    FROM agentlog_events\n),\nwindowed AS (\n    SELECT timestamp_unix_ms, session_id\n    FROM agentlog_events, bounds\n    WHERE session_id IS NOT NULL\n      AND session_id != ''\n      AND (\n            bounds.max_ts IS NULL\n            OR timestamp_unix_ms >= bounds.max_ts - 1209600000\n      )\n)\nSELECT strftime('%Y-%m-%d', timestamp_unix_ms / 1000, 'unixepoch') AS day_utc,\n       COUNT(DISTINCT session_id) AS unique_sessions\nFROM windowed\nGROUP BY day_utc\nORDER BY day_utc ASC\n","threshold":null,"truncated":false,"warnings":["query returned zero rows; shape validated by projected columns"]},{"answer_kind":"distribution","column_names":["adapter_name","event_count","event_share_pct"],"domain":"usage","duration_ms":0,"failure_code":null,"failure_message":null,"id":"q-usage-003","missing_required_fields":[],"must_include":["adapter_name","event_count","event_share_pct"],"ordering":null,"passed":true,"query_interface":"query.sql","queryability_assumptions":["adapter attribution is preserved per event","aggregate counts and percentages can be computed"],"question":"What is the distribution of event volume by adapter for the current workspace?","rationale":"Ensures adapter attribution supports cross-source usage analysis.","row_count":1,"sql":"\nWITH totals AS (\n    SELECT COUNT(*) AS total_events\n    FROM agentlog_events\n),\nby_adapter AS (\n    SELECT adapter_name, COUNT(*) AS event_count\n    FROM agentlog_events\n    GROUP BY adapter_name\n)\nSELECT by_adapter.adapter_name,\n       by_adapter.event_count,\n       CASE\n           WHEN totals.total_events = 0 THEN 0.0\n           ELSE ROUND((by_adapter.event_count * 100.0) / totals.total_events, 2)\n       END AS event_share_pct\nFROM by_adapter\nCROSS JOIN totals\nORDER BY by_adapter.event_count DESC, by_adapter.adapter_name ASC\n","threshold":null,"truncated":false,"warnings":[]},{"answer_kind":"ranked_list","column_names":["conversation_id","tool_call_count"],"domain":"usage","duration_ms":3,"failure_code":null,"failure_message":null,"id":"q-usage-004","missing_required_fields":[],"must_include":["conversation_id","tool_call_count"],"ordering":"tool_call_count_desc","passed":true,"query_interface":"query.sql","queryability_assumptions":["conversation grouping fields are queryable","tool call records are distinguishable from other records"],"question":"Which conversations contain the highest number of tool calls?","rationale":"Validates conversation-level aggregation needed for workflow diagnostics.","row_count":0,"sql":"\nSELECT conversation_id, COUNT(*) AS tool_call_count\nFROM v_tool_calls\nWHERE call_event_id IS NOT NULL\n  AND conversation_id IS NOT NULL\n  AND conversation_id != ''\nGROUP BY conversation_id\nORDER BY tool_call_count DESC, conversation_id ASC\nLIMIT 50\n","threshold":null,"truncated":false,"warnings":["query returned zero rows; shape validated by projected columns"]}],"release_gate":{"failed_checks":[],"failing_domains":[],"maximum_failed_questions":0,"minimum_domain_score_pct":90.0,"minimum_total_score_pct":95.0,"observed_failed_questions":0,"observed_total_score_pct":100.0,"passed":true},"row_cap":200,"schema_version":"logit.answerability-benchmark-report.v1","summary":{"failed_questions":0,"passed_questions":16,"per_domain":[{"domain":"freshness","failed_questions":0,"passed_questions":4,"score_pct":100.0,"total_questions":4},{"domain":"performance","failed_questions":0,"passed_questions":4,"score_pct":100.0,"total_questions":4},{"domain":"reliability","failed_questions":0,"passed_questions":4,"score_pct":100.0,"total_questions":4},{"domain":"usage","failed_questions":0,"passed_questions":4,"score_pct":100.0,"total_questions":4}],"score_pct":100.0,"total_questions":16}},"meta":{"artifact_path":"/tmp/logit-exit-query-benchmark-success-1773124107770025471/out/benchmarks/answerability_report_v1.json","corpus_path":"/opt/rustwide/workdir/crates/logit/../../fixtures/benchmarks/answerability_question_corpus_v1.json","failed_count":0,"implemented":true,"passed_count":16,"question_count":16,"release_gate_failed_checks_count":0,"release_gate_passed":true,"row_cap":200,"schema_version":"logit.query-envelope.v1","score_pct":100.0},"warnings":[]}
[INFO] [stdout] test query_benchmark_with_valid_corpus_exits_zero ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 9 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.18s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/gemini_chat_parser.rs (/opt/rustwide/target/debug/deps/gemini_chat_parser-8488c7fcfa11cc21)
[INFO] [stdout] 
[INFO] [stdout] running 7 tests
[INFO] [stdout] test chat_parser_propagates_root_and_message_session_context ... ok
[INFO] [stdout] test handles_content_variants_and_sparse_message_data ... ok
[INFO] [stdout] test chat_parser_extracts_heterogeneous_message_content_containers ... ok
[INFO] [stdout] test parses_chat_messages_fixture_to_canonical_events ... ok
[INFO] [stdout] test parses_chat_file_from_disk ... ok
[INFO] [stdout] test chat_parser_rejects_payload_without_messages_array ... ok
[INFO] [stdout] test chat_parser_handles_root_style_type_fields_and_tool_calls ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.06s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/gemini_edge_cases.rs (/opt/rustwide/target/debug/deps/gemini_edge_cases-201f93887f27cd7d)
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test logs_missing_timestamp_and_blank_id_use_fallbacks ... ok
[INFO] [stdout] test chat_unknown_and_missing_roles_map_to_runtime_with_warnings ... ok
[INFO] [stdout] test logs_classify_level_kind_and_role_precedence_explicitly ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/gemini_logs_parser.rs (/opt/rustwide/target/debug/deps/gemini_logs_parser-08dcb77d4d522413)
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test parses_empty_logs_array_without_errors ... ok
[INFO] [stdout] test parses_root_style_log_rows_with_type_and_numeric_message_id ... ok
[INFO] [stdout] test parses_logs_file_from_disk ... ok
[INFO] [stdout] test parses_sparse_log_entries_with_fallbacks_and_warnings ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/ingest_pipeline.rs (/opt/rustwide/target/debug/deps/ingest_pipeline-ec765ee1ddc6de80)
[INFO] [stdout] 
[INFO] [stdout] running 6 tests
[INFO] [stdout] test writes_machine_readable_ingest_report_artifact ... ok
[INFO] [stdout] test refresh_pipeline_is_idempotent_for_repeat_artifact_replay ... ok
[INFO] [stdout] test refresh_pipeline_writes_events_run_stats_and_watermarks ... ok
[INFO] [stdout] test refresh_pipeline_marks_missing_sources_stale_in_followup_refresh ... ok
[INFO] [stdout] test refresh_pipeline_preserves_duration_rollups_in_semantic_views ... ok
[INFO] [stderr]      Running tests/inspect_command.rs (/opt/rustwide/target/debug/deps/inspect_command-bb1f2a4a1359f473)
[INFO] [stdout] test refresh_pipeline_tolerates_invalid_rows_when_fail_fast_is_false ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.41s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test inspect_returns_error_for_missing_target ... ok
[INFO] [stdout] test inspect_tracks_invalid_jsonl_rows_as_warnings ... ok
[INFO] [stdout] test inspect_reports_normalized_jsonl_summary ... ok
[INFO] [stdout] test inspect_json_document_reports_root_shape ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/local_environment_smoke.rs (/opt/rustwide/target/debug/deps/local_environment_smoke-bd6c4743ae73eec6)
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test orchestrator_smoke_handles_local_layout_and_adapter_coverage ... ok
[INFO] [stdout] test normalize_command_smoke_writes_artifacts_for_local_layout ... 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]      Running tests/normalize_artifacts.rs (/opt/rustwide/target/debug/deps/normalize_artifacts-c13352f55e824625)
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test artifact_layout_uses_contract_filenames ... ok
[INFO] [stdout] test normalize_stats_aggregate_counts_and_breakdowns ... ok
[INFO] [stdout] test events_writer_emits_jsonl_rows_in_input_order ... ok
[INFO] [stdout] test normalize_command_emits_all_normalize_artifacts ... 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/normalize_dedupe.rs (/opt/rustwide/target/debug/deps/normalize_dedupe-d18fabb32f87416b)
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test dedupes_by_canonical_hash_and_tracks_metadata ... ok
[INFO] [stdout] test falls_back_to_source_locator_key_when_canonical_hash_missing ... ok
[INFO] [stdout] test sorts_by_timestamp_then_quality_then_tiebreakers ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/normalize_orchestrator.rs (/opt/rustwide/target/debug/deps/normalize_orchestrator-b811dd19eb1f85c7)
[INFO] [stdout] 
[INFO] [stdout] running 6 tests
[INFO] [stdout] test orchestrator_surfaces_non_fatal_warnings_for_unsupported_adapters ... ok
[INFO] [stdout] test orchestrator_includes_codex_history_auxiliary_events ... ok
[INFO] [stdout] test orchestrator_dedupes_exact_history_rollout_message_duplicates ... ok
[INFO] [stdout] test orchestrator_fans_in_codex_and_claude_sources ... ok
[INFO] [stdout] test normalize_command_emits_artifacts_from_orchestrated_sources ... ok
[INFO] [stdout] test orchestrator_marks_partial_failure_when_adapter_emits_events_and_errors ... FAILED
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- orchestrator_marks_partial_failure_when_adapter_emits_events_and_errors stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'orchestrator_marks_partial_failure_when_adapter_emits_events_and_errors' (271) panicked at crates/logit/tests/normalize_orchestrator.rs:214:5:
[INFO] [stdout] assertion `left == right` failed
[INFO] [stdout]   left: "success"
[INFO] [stdout]  right: "partial_failure"
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x624e28ab431a - std[b80a194dd3c418bb]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x624e28ab431a - std[b80a194dd3c418bb]::backtrace_rs::backtrace::trace_unsynchronized::<std[b80a194dd3c418bb]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x624e28ab431a - std[b80a194dd3c418bb]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x624e28ab431a - <<std[b80a194dd3c418bb]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[10b6fa85044e1869]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x624e28ac95ba - <core[10b6fa85044e1869]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x624e28ac95ba - core[10b6fa85044e1869]::fmt::write
[INFO] [stdout]    6:     0x624e28ab93a2 - std[b80a194dd3c418bb]::io::default_write_fmt::<alloc[dd269455e567d8e9]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/io/mod.rs:639:11
[INFO] [stdout]    7:     0x624e28ab93a2 - <alloc[dd269455e567d8e9]::vec::Vec<u8> as std[b80a194dd3c418bb]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/io/mod.rs:1994:13
[INFO] [stdout]    8:     0x624e28a9136f - <std[b80a194dd3c418bb]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x624e28a9136f - std[b80a194dd3c418bb]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x624e28aac069 - std[b80a194dd3c418bb]::panicking::default_hook
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x624e288baaec - <alloc[dd269455e567d8e9]::boxed::Box<dyn for<'a, 'b> core[10b6fa85044e1869]::ops::function::Fn<(&'a std[b80a194dd3c418bb]::panic::PanicHookInfo<'b>,), Output = ()> + core[10b6fa85044e1869]::marker::Sync + core[10b6fa85044e1869]::marker::Send> as core[10b6fa85044e1869]::ops::function::Fn<(&std[b80a194dd3c418bb]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/alloc/src/boxed.rs:2254:9
[INFO] [stdout]   12:     0x624e288baaec - test[826cbc6ef54ab466]::test_main_with_exit_callback::<test[826cbc6ef54ab466]::test_main::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/test/src/lib.rs:145:21
[INFO] [stdout]   13:     0x624e28aac222 - <alloc[dd269455e567d8e9]::boxed::Box<dyn for<'a, 'b> core[10b6fa85044e1869]::ops::function::Fn<(&'a std[b80a194dd3c418bb]::panic::PanicHookInfo<'b>,), Output = ()> + core[10b6fa85044e1869]::marker::Sync + core[10b6fa85044e1869]::marker::Send> as core[10b6fa85044e1869]::ops::function::Fn<(&std[b80a194dd3c418bb]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/alloc/src/boxed.rs:2254:9
[INFO] [stdout]   14:     0x624e28aac222 - std[b80a194dd3c418bb]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x624e28a91428 - std[b80a194dd3c418bb]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x624e28a885a9 - std[b80a194dd3c418bb]::sys::backtrace::__rust_end_short_backtrace::<std[b80a194dd3c418bb]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x624e28a922bd - __rustc[9698a3e60dd14283]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x624e28ac9e6c - core[10b6fa85044e1869]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x624e28ac9d23 - core[10b6fa85044e1869]::panicking::assert_failed_inner
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/core/src/panicking.rs:439:17
[INFO] [stdout]   20:     0x624e28a312c0 - core::panicking::assert_failed::hef816d5d756a7513
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/core/src/panicking.rs:394:5
[INFO] [stdout]   21:     0x624e288ab636 - normalize_orchestrator::orchestrator_marks_partial_failure_when_adapter_emits_events_and_errors::h0f62afabad96919a
[INFO] [stdout]                                at /opt/rustwide/workdir/crates/logit/tests/normalize_orchestrator.rs:214:5
[INFO] [stdout]   22:     0x624e288ab987 - normalize_orchestrator::orchestrator_marks_partial_failure_when_adapter_emits_events_and_errors::{{closure}}::hc06d5fadf1957358
[INFO] [stdout]                                at /opt/rustwide/workdir/crates/logit/tests/normalize_orchestrator.rs:182:77
[INFO] [stdout]   23:     0x624e288abf26 - core::ops::function::FnOnce::call_once::hfa9c2547b422afca
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   24:     0x624e288aebcb - <fn() -> core[10b6fa85044e1869]::result::Result<(), alloc[dd269455e567d8e9]::string::String> as core[10b6fa85044e1869]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   25:     0x624e288aebcb - test[826cbc6ef54ab466]::__rust_begin_short_backtrace::<core[10b6fa85044e1869]::result::Result<(), alloc[dd269455e567d8e9]::string::String>, fn() -> core[10b6fa85044e1869]::result::Result<(), alloc[dd269455e567d8e9]::string::String>>
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/test/src/lib.rs:663:18
[INFO] [stdout]   26:     0x624e288bb5bb - test[826cbc6ef54ab466]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/test/src/lib.rs:686:74
[INFO] [stdout]   27:     0x624e288bb5bb - <core[10b6fa85044e1869]::panic::unwind_safe::AssertUnwindSafe<test[826cbc6ef54ab466]::run_test_in_process::{closure#0}> as core[10b6fa85044e1869]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/core/src/panic/unwind_safe.rs:274:9
[INFO] [stdout]   28:     0x624e288bb5bb - std[b80a194dd3c418bb]::panicking::catch_unwind::do_call::<core[10b6fa85044e1869]::panic::unwind_safe::AssertUnwindSafe<test[826cbc6ef54ab466]::run_test_in_process::{closure#0}>, core[10b6fa85044e1869]::result::Result<(), alloc[dd269455e567d8e9]::string::String>>
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/panicking.rs:581:40
[INFO] [stdout]   29:     0x624e288bb5bb - std[b80a194dd3c418bb]::panicking::catch_unwind::<core[10b6fa85044e1869]::result::Result<(), alloc[dd269455e567d8e9]::string::String>, core[10b6fa85044e1869]::panic::unwind_safe::AssertUnwindSafe<test[826cbc6ef54ab466]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/panicking.rs:544:19
[INFO] [stdout]   30:     0x624e288bb5bb - std[b80a194dd3c418bb]::panic::catch_unwind::<core[10b6fa85044e1869]::panic::unwind_safe::AssertUnwindSafe<test[826cbc6ef54ab466]::run_test_in_process::{closure#0}>, core[10b6fa85044e1869]::result::Result<(), alloc[dd269455e567d8e9]::string::String>>
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/panic.rs:359:14
[INFO] [stdout]   31:     0x624e288bb5bb - test[826cbc6ef54ab466]::run_test_in_process
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/test/src/lib.rs:686:27
[INFO] [stdout]   32:     0x624e288bb5bb - test[826cbc6ef54ab466]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/test/src/lib.rs:607:43
[INFO] [stdout]   33:     0x624e288b6cd4 - test[826cbc6ef54ab466]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/test/src/lib.rs:637:41
[INFO] [stdout]   34:     0x624e288b6cd4 - std[b80a194dd3c418bb]::sys::backtrace::__rust_begin_short_backtrace::<test[826cbc6ef54ab466]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   35:     0x624e288be1c2 - std[b80a194dd3c418bb]::thread::lifecycle::spawn_unchecked::<test[826cbc6ef54ab466]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/thread/lifecycle.rs:91:13
[INFO] [stdout]   36:     0x624e288be1c2 - <core[10b6fa85044e1869]::panic::unwind_safe::AssertUnwindSafe<std[b80a194dd3c418bb]::thread::lifecycle::spawn_unchecked<test[826cbc6ef54ab466]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[10b6fa85044e1869]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/core/src/panic/unwind_safe.rs:274:9
[INFO] [stdout]   37:     0x624e288be1c2 - std[b80a194dd3c418bb]::panicking::catch_unwind::do_call::<core[10b6fa85044e1869]::panic::unwind_safe::AssertUnwindSafe<std[b80a194dd3c418bb]::thread::lifecycle::spawn_unchecked<test[826cbc6ef54ab466]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/panicking.rs:581:40
[INFO] [stdout]   38:     0x624e288be1c2 - std[b80a194dd3c418bb]::panicking::catch_unwind::<(), core[10b6fa85044e1869]::panic::unwind_safe::AssertUnwindSafe<std[b80a194dd3c418bb]::thread::lifecycle::spawn_unchecked<test[826cbc6ef54ab466]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/panicking.rs:544:19
[INFO] [stdout]   39:     0x624e288be1c2 - std[b80a194dd3c418bb]::panic::catch_unwind::<core[10b6fa85044e1869]::panic::unwind_safe::AssertUnwindSafe<std[b80a194dd3c418bb]::thread::lifecycle::spawn_unchecked<test[826cbc6ef54ab466]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/panic.rs:359:14
[INFO] [stdout]   40:     0x624e288be1c2 - std[b80a194dd3c418bb]::thread::lifecycle::spawn_unchecked::<test[826cbc6ef54ab466]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/thread/lifecycle.rs:89:26
[INFO] [stdout]   41:     0x624e288be1c2 - <std[b80a194dd3c418bb]::thread::lifecycle::spawn_unchecked<test[826cbc6ef54ab466]::run_test::{closure#1}, ()>::{closure#1} as core[10b6fa85044e1869]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   42:     0x624e28ab370f - <alloc[dd269455e567d8e9]::boxed::Box<dyn core[10b6fa85044e1869]::ops::function::FnOnce<(), Output = ()> + core[10b6fa85044e1869]::marker::Send> as core[10b6fa85044e1869]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/alloc/src/boxed.rs:2240:9
[INFO] [stdout]   43:     0x624e28ab370f - <std[b80a194dd3c418bb]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   44:     0x7939f4447aa4 - <unknown>
[INFO] [stdout]   45:     0x7939f44d4a64 - clone
[INFO] [stdout]   46:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     orchestrator_marks_partial_failure_when_adapter_emits_events_and_errors
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 5 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.17s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--test normalize_orchestrator`
[INFO] running `Command { std: "docker" "inspect" "db0b2f9b24bce1f2160a73c6196cb9b90ed7e811c5b7ed6ce7754664f3ceb6da", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "db0b2f9b24bce1f2160a73c6196cb9b90ed7e811c5b7ed6ce7754664f3ceb6da", kill_on_drop: false }`
[INFO] [stdout] db0b2f9b24bce1f2160a73c6196cb9b90ed7e811c5b7ed6ce7754664f3ceb6da
