[INFO] cloning repository https://github.com/chhuax/PrismTrace
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/chhuax/PrismTrace" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fchhuax%2FPrismTrace", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fchhuax%2FPrismTrace'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 741c3cb46adb6651c253a5589e34f65daac2f333
[INFO] testing chhuax/PrismTrace against try#33835004928d3bf65db4d4712e1330766263b0bd for pr-155739-1
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fchhuax%2FPrismTrace" "/workspace/builds/worker-3-tc2/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-3-tc2/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/chhuax/PrismTrace
[INFO] finished tweaking git repo https://github.com/chhuax/PrismTrace
[INFO] tweaked toml for git repo https://github.com/chhuax/PrismTrace written to /workspace/builds/worker-3-tc2/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/chhuax/PrismTrace on toolchain 33835004928d3bf65db4d4712e1330766263b0bd
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+33835004928d3bf65db4d4712e1330766263b0bd" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/chhuax/PrismTrace 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" "+33835004928d3bf65db4d4712e1330766263b0bd" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-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" "+33835004928d3bf65db4d4712e1330766263b0bd" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 5ccc9fa91957f471d6f5b25883b2e3bc5aaecd98806a095e46b6c276ca708b50
[INFO] running `Command { std: "docker" "start" "-a" "5ccc9fa91957f471d6f5b25883b2e3bc5aaecd98806a095e46b6c276ca708b50", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "5ccc9fa91957f471d6f5b25883b2e3bc5aaecd98806a095e46b6c276ca708b50", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "5ccc9fa91957f471d6f5b25883b2e3bc5aaecd98806a095e46b6c276ca708b50", kill_on_drop: false }`
[INFO] [stdout] 5ccc9fa91957f471d6f5b25883b2e3bc5aaecd98806a095e46b6c276ca708b50
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+33835004928d3bf65db4d4712e1330766263b0bd" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 970c7386288db83262b49c69602f9d681dcc75a28dbac5db722106fd62463f52
[INFO] running `Command { std: "docker" "start" "-a" "970c7386288db83262b49c69602f9d681dcc75a28dbac5db722106fd62463f52", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.185
[INFO] [stderr]    Compiling litemap v0.8.2
[INFO] [stderr]    Compiling writeable v0.6.3
[INFO] [stderr]    Compiling cc v1.2.60
[INFO] [stderr]    Compiling icu_normalizer_data v2.2.0
[INFO] [stderr]    Compiling icu_properties_data v2.2.0
[INFO] [stderr]    Compiling quote v1.0.45
[INFO] [stderr]    Compiling serde_json v1.0.149
[INFO] [stderr]    Compiling rustls v0.23.38
[INFO] [stderr]    Compiling simd-adler32 v0.3.9
[INFO] [stderr]    Compiling digest v0.10.7
[INFO] [stderr]    Compiling webpki-roots v1.0.7
[INFO] [stderr]    Compiling http v1.4.0
[INFO] [stderr]    Compiling syn v2.0.117
[INFO] [stderr]    Compiling miniz_oxide v0.8.9
[INFO] [stderr]    Compiling webpki-roots v0.26.11
[INFO] [stderr]    Compiling sha1 v0.10.6
[INFO] [stderr]    Compiling flate2 v1.1.9
[INFO] [stderr]    Compiling prismtrace-analysis v0.1.0 (/opt/rustwide/workdir/crates/prismtrace-analysis)
[INFO] [stderr]    Compiling ring v0.17.14
[INFO] [stderr]    Compiling getrandom v0.2.17
[INFO] [stderr]    Compiling rand_core v0.6.4
[INFO] [stderr]    Compiling rand_chacha v0.3.1
[INFO] [stderr]    Compiling prismtrace-api v0.1.0 (/opt/rustwide/workdir/crates/prismtrace-api)
[INFO] [stderr]    Compiling rand v0.8.6
[INFO] [stderr]    Compiling synstructure v0.13.2
[INFO] [stderr]    Compiling zerovec-derive v0.11.3
[INFO] [stderr]    Compiling displaydoc v0.2.5
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling thiserror-impl v1.0.69
[INFO] [stderr]    Compiling zerofrom-derive v0.1.7
[INFO] [stderr]    Compiling yoke-derive v0.8.2
[INFO] [stderr]    Compiling zerofrom v0.1.7
[INFO] [stderr]    Compiling yoke v0.8.2
[INFO] [stderr]    Compiling zerovec v0.11.6
[INFO] [stderr]    Compiling zerotrie v0.2.4
[INFO] [stderr]    Compiling thiserror v1.0.69
[INFO] [stderr]    Compiling tungstenite v0.24.0
[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 prismtrace-index v0.1.0 (/opt/rustwide/workdir/crates/prismtrace-index)
[INFO] [stderr]    Compiling prismtrace-core v0.1.0 (/opt/rustwide/workdir/crates/prismtrace-core)
[INFO] [stderr]    Compiling icu_provider v2.2.0
[INFO] [stderr]    Compiling rustls-webpki v0.103.12
[INFO] [stderr]    Compiling icu_normalizer v2.2.0
[INFO] [stderr]    Compiling icu_properties v2.2.0
[INFO] [stderr]    Compiling prismtrace-storage v0.1.0 (/opt/rustwide/workdir/crates/prismtrace-storage)
[INFO] [stderr]    Compiling prismtrace-sources v0.1.0 (/opt/rustwide/workdir/crates/prismtrace-sources)
[INFO] [stderr]    Compiling idna_adapter v1.2.1
[INFO] [stderr]    Compiling idna v1.1.0
[INFO] [stderr]    Compiling url v2.5.8
[INFO] [stderr]    Compiling ureq v2.12.1
[INFO] [stderr]    Compiling prismtrace-host v0.1.0 (/opt/rustwide/workdir/crates/prismtrace-host)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 34.34s
[INFO] running `Command { std: "docker" "inspect" "970c7386288db83262b49c69602f9d681dcc75a28dbac5db722106fd62463f52", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "970c7386288db83262b49c69602f9d681dcc75a28dbac5db722106fd62463f52", kill_on_drop: false }`
[INFO] [stdout] 970c7386288db83262b49c69602f9d681dcc75a28dbac5db722106fd62463f52
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+33835004928d3bf65db4d4712e1330766263b0bd" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 314b1accaa83ccd6a45bbcae20f1b8734a47bb847a9e52bf6c759b5af96f840a
[INFO] running `Command { std: "docker" "start" "-a" "314b1accaa83ccd6a45bbcae20f1b8734a47bb847a9e52bf6c759b5af96f840a", kill_on_drop: false }`
[INFO] [stderr]    Compiling prismtrace-core v0.1.0 (/opt/rustwide/workdir/crates/prismtrace-core)
[INFO] [stderr]    Compiling prismtrace-index v0.1.0 (/opt/rustwide/workdir/crates/prismtrace-index)
[INFO] [stderr]    Compiling prismtrace-api v0.1.0 (/opt/rustwide/workdir/crates/prismtrace-api)
[INFO] [stderr]    Compiling prismtrace-analysis v0.1.0 (/opt/rustwide/workdir/crates/prismtrace-analysis)
[INFO] [stderr]    Compiling prismtrace-storage v0.1.0 (/opt/rustwide/workdir/crates/prismtrace-storage)
[INFO] [stderr]    Compiling prismtrace-sources v0.1.0 (/opt/rustwide/workdir/crates/prismtrace-sources)
[INFO] [stderr]    Compiling prismtrace-host v0.1.0 (/opt/rustwide/workdir/crates/prismtrace-host)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 12.41s
[INFO] running `Command { std: "docker" "inspect" "314b1accaa83ccd6a45bbcae20f1b8734a47bb847a9e52bf6c759b5af96f840a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "314b1accaa83ccd6a45bbcae20f1b8734a47bb847a9e52bf6c759b5af96f840a", kill_on_drop: false }`
[INFO] [stdout] 314b1accaa83ccd6a45bbcae20f1b8734a47bb847a9e52bf6c759b5af96f840a
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+33835004928d3bf65db4d4712e1330766263b0bd" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] ddb965f3ea079b159af098ccd2c6c352c7321368513f16a084a8057669529780
[INFO] running `Command { std: "docker" "start" "-a" "ddb965f3ea079b159af098ccd2c6c352c7321368513f16a084a8057669529780", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.18s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/prismtrace_analysis-88e32f87b417d020)
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test tests::analysis_diffs_skill_visibility_and_diagnoses_missing_facts ... ok
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/prismtrace_api-78d9fca9d1f623df)
[INFO] [stdout] test tests::analysis_projects_prompt_diff_between_adjacent_events ... ok
[INFO] [stdout] test tests::analysis_diffs_tool_visibility_snapshots ... 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 3 tests
[INFO] [stdout] test tests::api_renders_empty_session_diagnostics_payload ... ok
[INFO] [stdout] test tests::api_renders_session_diagnostics_payload_from_prompt_and_capability_facts ... ok
[INFO] [stdout] test tests::api_renders_capability_projection_payload_with_filter_context ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/prismtrace_core-eee9cba6f7f16a70)
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 22 tests
[INFO] [stdout] test tests::ipc_message_bootstrap_report_round_trip ... ok
[INFO] [stdout] test tests::ipc_message_detach_ack_round_trip ... ok
[INFO] [stdout] test tests::ipc_message_heartbeat_round_trip ... ok
[INFO] [stdout] test tests::ipc_message_http_request_observed_parses_without_body ... ok
[INFO] [stdout] test tests::ipc_message_malformed_input_returns_invalid_json_error ... ok
[INFO] [stdout] test tests::ipc_message_trailing_newline_is_handled_correctly ... ok
[INFO] [stdout] test tests::process_sample_classifies_codex_app_server_as_node ... ok
[INFO] [stdout] test tests::process_sample_classifies_codex_main_app_as_electron ... ok
[INFO] [stdout] test tests::process_sample_classifies_electron_processes ... ok
[INFO] [stdout] test tests::process_sample_classifies_bare_opencode_process_name_as_node ... ok
[INFO] [stdout] test tests::ipc_message_unknown_variant_returns_unknown_variant_error ... ok
[INFO] [stdout] test tests::process_sample_classifies_node_processes ... ok
[INFO] [stdout] test tests::process_sample_normalizes_generic_runtime_names_to_script_name_from_command_line ... ok
[INFO] [stdout] test tests::process_target_display_name_falls_back_to_executable_name ... ok
[INFO] [stdout] test tests::runtime_kind_labels_are_stable ... ok
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/prismtrace_host-bfc137ae19ce4f66)
[INFO] [stdout] test tests::process_sample_converts_to_structured_target ... ok
[INFO] [stdout] test tests::ipc_message_http_request_observed_round_trip_with_exchange_id ... ok
[INFO] [stdout] test tests::process_sample_normalizes_generic_runtime_names_to_executable_name ... ok
[INFO] [stdout] test tests::process_sample_skips_common_node_option_value_pairs_when_finding_script_name ... ok
[INFO] [stdout] test tests::process_sample_classifies_packaged_opencode_binary_as_node ... ok
[INFO] [stdout] test tests::process_sample_keeps_unknown_when_no_runtime_matches ... ok
[INFO] [stdout] test tests::ipc_message_http_response_observed_round_trip ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 22 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 213 tests
[INFO] [stdout] test claude_observer::tests::claude_observer_session_surfaces_structured_event_when_transcript_root_is_missing ... ok
[INFO] [stdout] test claude_observer::tests::default_options_use_default_transcript_root ... ok
[INFO] [stdout] test claude_observer::tests::normalize_transcript_record_prefers_parent_uuid_for_turn_id_and_uuid_for_item_id ... ok
[INFO] [stdout] test claude_observer::tests::claude_observer_session_surfaces_unobservable_event_when_no_transcripts_are_available ... ok
[INFO] [stdout] test claude_observer::tests::run_claude_observer_emits_structured_unobservable_output_when_root_is_missing ... ok
[INFO] [stdout] test claude_observer::tests::claude_observer_artifact_writer_persists_handshake_and_event ... ok
[INFO] [stdout] test claude_observer::tests::transcript_unknown_record_falls_back_to_unknown_event ... ok
[INFO] [stdout] test claude_observer::tests::run_claude_observer_emits_structured_unobservable_output_when_no_transcripts_exist ... ok
[INFO] [stdout] test claude_observer::tests::transcript_user_record_maps_to_turn_event ... ok
[INFO] [stdout] test codex_observer::tests::capability_event_from_response_counts_codex_mcp_servers ... ok
[INFO] [stdout] test codex_observer::tests::capability_event_from_response_counts_entries ... ok
[INFO] [stdout] test codex_observer::tests::collect_capability_events_surfaces_remote_error_reply ... ok
[INFO] [stdout] test codex_observer::tests::command_looks_like_desktop_codex_owner_accepts_desktop_codex_processes ... ok
[INFO] [stdout] test claude_observer::tests::collect_capability_events_surfaces_missing_file_and_keeps_healthy_history ... ok
[INFO] [stdout] test codex_observer::tests::command_looks_like_desktop_codex_owner_rejects_vscode_extension_host ... ok
[INFO] [stdout] test codex_observer::tests::factory_prioritizes_explicit_socket_path ... ok
[INFO] [stdout] test claude_observer::tests::collect_capability_events_stops_after_max_events ... ok
[INFO] [stdout] test codex_observer::tests::normalize_server_value_maps_known_method_prefixes ... ok
[INFO] [stdout] test codex_observer::tests::normalize_server_value_preserves_raw_json_for_unknown_messages ... ok
[INFO] [stdout] test codex_observer::tests::initialize_surfaces_remote_error_message ... ok
[INFO] [stdout] test codex_observer::tests::observed_event_kind_for_method_handles_approval_and_tool_markers ... ok
[INFO] [stdout] test codex_observer::tests::discover_latest_codex_socket_returns_most_recent_socket ... ok
[INFO] [stdout] test codex_observer::tests::codex_observer_artifact_writer_persists_handshake_and_event ... ok
[INFO] [stdout] test claude_observer::tests::discover_transcript_files_skips_vanished_entries ... ok
[INFO] [stdout] test codex_observer::tests::select_latest_desktop_codex_socket_skips_non_matching_latest_candidate ... ok
[INFO] [stdout] test claude_observer::tests::next_event_reads_appended_transcript_line ... ok
[INFO] [stdout] test console::tests::collect_activity_items_returns_empty_for_no_known_activity ... ok
[INFO] [stdout] test console::tests::collect_target_summaries_filters_non_matching_targets ... ok
[INFO] [stdout] test console::tests::collect_target_summaries_marks_local_targets_as_discoverable ... ok
[INFO] [stdout] test console::tests::collect_target_summaries_uses_runtime_summary_for_single_target ... ok
[INFO] [stdout] test console::tests::console_observer_module_no_longer_owns_legacy_detail_adapters ... ok
[INFO] [stdout] test console::tests::console_route_handler_renders_health_without_tcp ... ok
[INFO] [stdout] test console::tests::collect_activity_items_filters_items_by_matching_pid ... ok
[INFO] [stdout] test console::tests::collect_activity_items_orders_request_and_error_by_time ... ok
[INFO] [stdout] test console::tests::console_script_fetches_and_renders_session_capabilities ... ok
[INFO] [stdout] test codex_observer::tests::validate_proxy_socket_endpoint_reports_immediate_eof ... ok
[INFO] [stdout] test codex_observer::tests::validate_proxy_socket_endpoint_accepts_initialize_result ... ok
[INFO] [stdout] test console::tests::console_script_prefers_event_detail_api_for_read_model_event_ids ... ok
[INFO] [stdout] test console::tests::console_script_uses_paginated_session_and_timeline_apis ... ok
[INFO] [stdout] test console::tests::console_script_fetches_and_renders_session_diagnostics ... ok
[INFO] [stdout] test claude_observer::tests::collect_capability_events_prefers_most_recent_history_when_transcript_exceeds_max_events ... ok
[INFO] [stdout] test claude_observer::tests::discover_transcript_files_orders_recent_jsonl_first ... ok
[INFO] [stdout] test claude_observer::tests::next_event_surfaces_unobservable_transcript_failure_as_structured_event_or_result ... ok
[INFO] [stdout] test console::tests::console_server_returns_health_api_payload ... ok
[INFO] [stdout] test codex_observer::tests::normalize_value_reclassifies_late_capability_response ... ok
[INFO] [stdout] test console::tests::console_server_returns_filtered_targets_api_empty_state_and_context ... ok
[INFO] [stdout] test claude_observer::tests::run_claude_observer_writes_artifact_records ... ok
[INFO] [stdout] test console::tests::console_server_returns_favicon_without_not_found ... ok
[INFO] [stdout] test console::tests::console_server_filtered_session_detail_does_not_leak_unmatched_session ... ok
[INFO] [stdout] test claude_observer::tests::collect_capability_events_handles_file_without_trailing_newline_without_replaying_history ... ok
[INFO] [stdout] test console::tests::console_server_returns_not_found_for_unknown_path ... ok
[INFO] [stdout] test claude_observer::tests::collect_capability_events_marks_existing_backlog_as_consumed_for_follow ... ok
[INFO] [stdout] test console::tests::console_server_filtered_request_detail_does_not_leak_unmatched_request ... ok
[INFO] [stdout] test claude_observer::tests::next_event_consumes_multiple_appended_lines_without_repeating ... ok
[INFO] [stdout] test claude_observer::tests::next_event_removes_failed_transcript_but_continues_following_other_files ... ok
[INFO] [stdout] test claude_observer::tests::next_event_returns_none_when_no_new_lines_arrive ... ok
[INFO] [stdout] test claude_observer::tests::discover_transcript_files_limits_results_by_recency ... ok
[INFO] [stdout] test console::tests::console_server_returns_observer_session_capabilities_api_payload ... ok
[INFO] [stdout] test console::tests::console_server_returns_observer_session_detail_api_payload ... ok
[INFO] [stdout] test console::tests::console_server_returns_targets_api_payload ... ok
[INFO] [stdout] test console::tests::console_server_returns_request_embedded_tool_capabilities_api_payload ... ok
[INFO] [stdout] test console::tests::console_server_session_events_api_applies_limit_and_cursor ... ok
[INFO] [stdout] test console::tests::console_server_sessions_api_applies_limit_and_cursor ... ok
[INFO] [stdout] test console::tests::console_target_filter_config_does_not_match_when_term_only_appears_in_console_flag_args ... ok
[INFO] [stdout] test console::tests::console_server_serves_homepage_over_http ... ok
[INFO] [stdout] test console::tests::console_target_filter_config_is_disabled_when_terms_are_empty ... ok
[INFO] [stdout] test console::tests::console_server_returns_observer_requests_api_payload ... ok
[INFO] [stdout] test console::tests::console_server_returns_observer_request_detail_api_payload ... ok
[INFO] [stdout] test console::tests::console_server_returns_json_error_when_request_read_times_out ... ok
[INFO] [stdout] test console::tests::console_target_filter_config_matches_display_name_path_and_command_line ... ok
[INFO] [stdout] test console::tests::console_target_filter_config_matches_when_any_term_hits ... ok
[INFO] [stdout] test console::tests::console_target_filter_config_rejects_non_matching_targets ... ok
[INFO] [stdout] test console::tests::filter_request_summaries_keeps_only_matching_target_display_names ... ok
[INFO] [stdout] test console::tests::load_session_detail_splits_same_pid_after_time_window ... ok
[INFO] [stdout] test console::tests::load_request_detail_prefers_exact_tool_visibility_request_match ... ok
[INFO] [stdout] test console::tests::malformed_request_returns_bad_request ... ok
[INFO] [stdout] test console::tests::read_request_path_parses_http_get_requests ... ok
[INFO] [stdout] test console::tests::load_session_summaries_groups_same_pid_even_with_interleaved_other_pid ... ok
[INFO] [stdout] test console::tests::console_server_returns_observer_session_events_api_payload ... ok
[INFO] [stdout] test console::tests::render_console_homepage_exposes_theme_switcher ... ok
[INFO] [stdout] test console::tests::render_activity_payload_uses_filtered_no_match_empty_state_when_context_is_active ... ok
[INFO] [stdout] test console::tests::render_console_homepage_hides_filter_context_when_unfiltered ... ok
[INFO] [stdout] test console::tests::console_server_returns_json_error_for_unknown_api_path ... ok
[INFO] [stdout] test console::tests::render_console_homepage_includes_title_and_heading ... ok
[INFO] [stdout] test console::tests::console_server_returns_filtered_sessions_api_without_unmatched_sessions ... ok
[INFO] [stdout] test console::tests::console_server_returns_observer_session_diagnostics_api_payload ... ok
[INFO] [stdout] test console::tests::render_console_homepage_exposes_ia_data_regions_when_context_is_active ... ok
[INFO] [stdout] test console::tests::render_console_homepage_keeps_ia_shell_when_filters_are_active ... ok
[INFO] [stdout] test console::tests::load_request_detail_returns_base_detail_for_existing_request ... ok
[INFO] [stdout] test console::tests::load_request_summaries_reads_captured_request_artifacts ... ok
[INFO] [stdout] test console::tests::console_server_sessions_api_keeps_observer_artifacts_out_of_session_list ... ok
[INFO] [stdout] test console::tests::load_session_summaries_do_not_merge_when_only_response_finishes_within_window ... ok
[INFO] [stdout] test console::tests::render_console_homepage_renders_empty_regions_and_refresh_script ... ok
[INFO] [stdout] test console::tests::render_console_homepage_renders_request_detail_and_health_panel_regions ... ok
[INFO] [stdout] test console::tests::render_health_payload_filters_errors_by_matching_pid ... ok
[INFO] [stdout] test console::tests::render_console_homepage_seeds_initial_request_selection_for_js_hydration ... ok
[INFO] [stdout] test console::tests::render_request_detail_payload_marks_missing_detail_with_status ... ok
[INFO] [stdout] test console::tests::render_health_payload_includes_source_summary_and_errors ... ok
[INFO] [stdout] test console::tests::render_console_homepage_uses_observer_first_shell_copy ... ok
[INFO] [stdout] test console::tests::render_requests_payload_exposes_compatible_list_envelope ... ok
[INFO] [stdout] test console::tests::render_requests_payload_uses_filtered_no_match_empty_state_when_context_is_active ... ok
[INFO] [stdout] test console::tests::render_sessions_payload_exposes_compatible_list_envelope ... ok
[INFO] [stdout] test console::tests::render_targets_payload_includes_empty_state_when_no_targets ... ok
[INFO] [stdout] test console::tests::render_targets_payload_includes_filter_context_when_filters_are_active ... ok
[INFO] [stdout] test console::tests::console_server_returns_requests_api_payload ... ok
[INFO] [stdout] test console::tests::console_server_returns_request_detail_api_payload ... ok
[INFO] [stdout] test console::tests::render_targets_payload_omits_filter_context_when_unfiltered ... ok
[INFO] [stdout] test console::tests::render_console_homepage_seeds_initial_session_selection_for_js_hydration ... ok
[INFO] [stdout] test console::tests::render_console_homepage_renders_health_shell_region ... ok
[INFO] [stdout] test console::tests::console_server_handles_static_asset_while_previous_connection_is_idle ... ok
[INFO] [stdout] test console::tests::render_targets_payload_uses_filtered_no_match_empty_state_when_context_is_active ... ok
[INFO] [stdout] test console::tests::run_console_server_writes_startup_report_before_serving ... ok
[INFO] [stdout] test console::tests::start_console_server_returns_addr_in_use_when_bind_fails ... ok
[INFO] [stdout] test discovery::tests::discover_targets_preserves_unknown_runtime_kind ... ok
[INFO] [stdout] test discovery::tests::parse_ps_line_extracts_pid_and_executable_path ... ok
[INFO] [stdout] test discovery::tests::discover_targets_returns_structured_process_targets ... ok
[INFO] [stdout] test discovery::tests::parse_ps_line_prefers_command_line_executable_when_comm_is_truncated ... ok
[INFO] [stdout] test discovery::tests::parse_ps_line_returns_none_when_only_pid_is_present ... ok
[INFO] [stdout] test discovery::tests::parse_ps_line_preserves_command_line_for_node_helpers ... ok
[INFO] [stdout] test ipc::tests::check_heartbeat_timeout_returns_none_within_window ... ok
[INFO] [stdout] test lifecycle::tests::app_config_uses_explicit_state_root ... ok
[INFO] [stdout] test ipc::tests::next_event_skips_non_ipc_lines_and_returns_next_valid_message ... ok
[INFO] [stdout] test console::tests::write_console_response_renders_target_summary_fields_from_controlled_snapshot ... ok
[INFO] [stdout] test ipc::tests::next_event_returns_channel_disconnected_on_eof ... ok
[INFO] [stdout] test ipc::tests::check_heartbeat_timeout_returns_some_when_timeout_exceeded ... ok
[INFO] [stdout] test ipc::tests::next_event_returns_disconnected_when_only_non_ipc_lines_then_eof ... ok
[INFO] [stdout] test console::tests::write_console_response_renders_activity_items_from_controlled_snapshot ... ok
[INFO] [stdout] test ipc::tests::next_event_parses_heartbeat_message ... ok
[INFO] [stdout] test lifecycle::tests::bootstrap_creates_storage_under_the_state_root ... ok
[INFO] [stdout] test lifecycle::tests::collect_host_snapshot_returns_discovered_targets ... ok
[INFO] [stdout] test lifecycle::tests::discovery_report_lists_targets_with_runtime_labels ... ok
[INFO] [stdout] test index::tests::host_callers_depend_on_index_facade_not_split_read_write_stores ... ok
[INFO] [stdout] test observability_read_model::tests::index_read_store_lives_outside_observability_read_model_module ... ok
[INFO] [stdout] test observability_read_model::tests::index_write_store_lives_outside_observability_read_model_module ... ok
[INFO] [stdout] test ipc::tests::next_event_returns_heartbeat_timeout_when_reader_reports_transient_timeouts ... ok
[INFO] [stdout] test lifecycle::tests::run_claude_observer_session_passes_storage_to_artifact_writer ... ok
[INFO] [stdout] test lifecycle::tests::import_legacy_workspace_artifacts_copies_missing_files_without_overwrite ... ok
[INFO] [stdout] test observability_read_model::tests::indexes_observer_artifacts_as_sessions_and_events ... ok
[INFO] [stdout] test lifecycle::tests::startup_summary_mentions_bind_address_and_storage_paths ... ok
[INFO] [stdout] test opencode_observer::tests::capability_snapshot_keeps_opencode_domains_distinct ... ok
[INFO] [stdout] test console::tests::console_server_returns_activity_api_payload ... ok
[INFO] [stdout] test opencode_observer::tests::default_opencode_observer_options_are_stable ... ok
[INFO] [stdout] test opencode_observer::tests::collect_capability_events_applies_session_and_message_limits_before_fetching_more_messages ... ok
[INFO] [stdout] test opencode_observer::tests::global_event_maps_permission_to_approval ... ok
[INFO] [stdout] test opencode_observer::tests::global_event_falls_back_to_unknown ... ok
[INFO] [stdout] test lifecycle::tests::default_user_state_root_uses_macos_application_support ... ok
[INFO] [stdout] test opencode_observer::tests::message_part_maps_tool_parts_to_tool_events ... ok
[INFO] [stdout] test opencode_observer::tests::session_snapshot_maps_to_thread_event ... ok
[INFO] [stdout] test opencode_observer::tests::truncate_adds_suffix_when_text_exceeds_limit ... ok
[INFO] [stdout] test observability_read_model::tests::writes_index_manifest_for_parsed_source_files ... ok
[INFO] [stdout] test request_capture::tests::capture_observed_request_does_not_match_provider_name_in_query_string ... ok
[INFO] [stdout] test observability_read_model::tests::indexes_codex_transcripts_and_excludes_archived_sessions ... ok
[INFO] [stdout] test observability_read_model::tests::projects_opencode_capabilities_without_codex_domain_aliases ... ok
[INFO] [stdout] test observability_read_model::tests::index_read_store_uses_persisted_index_without_rescanning_artifact_dirs ... ok
[INFO] [stdout] test lifecycle::tests::run_opencode_observer_session_passes_storage_to_artifact_writer ... ok
[INFO] [stdout] test observability_read_model::tests::writes_session_event_and_capability_index_projection_files ... ok
[INFO] [stdout] test observability_read_model::tests::projects_capabilities_from_observer_snapshots_and_tool_events ... ok
[INFO] [stdout] test opencode_observer::tests::opencode_observer_artifact_writer_persists_handshake_and_event ... ok
[INFO] [stdout] test console::tests::console_server_returns_session_detail_api_payload ... ok
[INFO] [stdout] test request_capture::tests::capture_observed_request_redacts_sensitive_headers_in_artifact ... ok
[INFO] [stdout] test request_capture::tests::capture_observed_request_persists_truncated_flag_from_probe ... ok
[INFO] [stdout] test request_capture::tests::consume_probe_events_reuses_request_provider_hint_once_for_matching_response ... ok
[INFO] [stdout] test opencode_observer::tests::run_opencode_observer_writes_artifact_records ... ok
[INFO] [stdout] test request_capture::tests::consume_probe_events_reclaims_listener_on_timeout_without_shutdown_handle ... ok
[INFO] [stdout] test request_capture::tests::capture_observed_request_persists_openai_request ... ok
[INFO] [stdout] test request_capture::tests::capture_observed_request_summary_omits_query_string ... ok
[INFO] [stdout] test console::tests::console_server_returns_observer_event_detail_api_payload ... ok
[INFO] [stdout] test response_capture::tests::capture_observed_response_persists_openai_response ... ok
[INFO] [stdout] test response_capture::tests::capture_observed_response_skips_unrecognized_provider_without_override ... ok
[INFO] [stdout] test response_capture::tests::capture_observed_response_prefers_explicit_provider_hint_override ... ok
[INFO] [stdout] test request_capture::tests::consume_probe_events_writes_summary_for_observed_requests ... ok
[INFO] [stdout] test request_capture::tests::capture_observed_request_ignores_non_llm_http_requests ... ok
[INFO] [stdout] test response_capture::tests::capture_observed_response_persists_duration_and_truncation_metadata ... ok
[INFO] [stdout] test response_capture::tests::capture_observed_response_redacts_cookie_headers ... ok
[INFO] [stdout] test response_capture::tests::capture_observed_response_persists_error_status_with_empty_body ... ok
[INFO] [stdout] test runtime::tests::classify_signal_command_error_maps_common_kill_failures ... ok
[INFO] [stdout] test runtime::tests::build_unexpected_lsof_error_includes_status_and_stderr ... ok
[INFO] [stdout] test runtime::tests::instrumentation_error_fields_are_accessible ... ok
[INFO] [stdout] test runtime::tests::instrumentation_error_kind_labels_are_stable ... ok
[INFO] [stdout] test runtime::tests::inspector_bridge_new_returns_reader_with_complete_lines ... ok
[INFO] [stdout] test runtime::tests::lsof_probe_treats_empty_exit_one_as_no_listener_yet ... ok
[INFO] [stdout] test runtime::tests::parse_listener_ports_returns_all_listening_ports_from_lsof_output ... ok
[INFO] [stdout] test runtime::tests::pick_debugger_url_from_candidates_uses_port_with_valid_json_list ... ok
[INFO] [stdout] test observability_read_model::tests::preserves_manifest_entry_for_unchanged_source_when_new_source_is_added ... ok
[INFO] [stdout] test runtime::tests::extract_eval_number_accepts_description_when_value_is_missing ... ok
[INFO] [stdout] test runtime::tests::process_pid_expression_uses_process_fallbacks_when_global_process_is_missing ... ok
[INFO] [stdout] test runtime::tests::parse_websocket_debugger_url_extracts_ws_url_from_json_list ... ok
[INFO] [stdout] test runtime::tests::scripted_runtime_inject_fails_returns_error ... ok
[INFO] [stdout] test runtime::tests::remove_active_control_if_matches_keeps_newer_replacement ... ok
[INFO] [stdout] test runtime::tests::scripted_runtime_detach_succeeds_by_default_in_success_variant ... ok
[INFO] [stdout] test runtime::tests::resolve_process_pid_retries_when_inspector_context_is_temporarily_undefined ... ok
[INFO] [stdout] test runtime::tests::scripted_runtime_inject_succeeds_in_detach_fails_variant ... ok
[INFO] [stdout] test runtime::tests::scripted_runtime_success_returns_reader_over_messages ... ok
[INFO] [stdout] test runtime::tests::scripted_runtime_detach_fails_returns_error ... ok
[INFO] [stdout] test runtime::tests::scripted_runtime_success_with_empty_messages_returns_empty_reader ... ok
[INFO] [stdout] test runtime::tests::worker_control_stop_request_terminates_worker_thread ... ok
[INFO] [stdout] test tests::analysis_projection_types_live_in_prismtrace_analysis_crate ... ok
[INFO] [stdout] test tests::api_payload_renderers_live_in_prismtrace_api_crate ... ok
[INFO] [stdout] test tests::sources_boundary_exposes_observer_options ... ok
[INFO] [stdout] test tests::lifecycle_orchestration_lives_outside_lib_module ... ok
[INFO] [stdout] test tool_visibility::tests::capture_request_embedded_tool_visibility_persists_tools_array ... ok
[INFO] [stdout] test tests::source_contracts_live_in_prismtrace_sources_crate ... ok
[INFO] [stdout] test tool_visibility::tests::capture_request_embedded_tool_visibility_falls_back_to_functions_array ... ok
[INFO] [stdout] test observability_read_model::tests::default_codex_indexing_keeps_cwd_as_metadata_not_boundary ... ok
[INFO] [stdout] test runtime::tests::send_detach_signal_keeps_control_handle_when_detach_fails ... ok
[INFO] [stdout] test tool_visibility::tests::capture_request_embedded_tool_visibility_skips_requests_without_tools ... ok
[INFO] [stdout] test observability_read_model::tests::filters_unarchived_codex_threads_by_workspace_root ... ok
[INFO] [stdout] test tests::console_startup_report_mentions_browser_entrypoint ... ok
[INFO] [stdout] test observability_read_model::tests::indexes_only_unarchived_interactive_codex_threads_from_state_db ... ok
[INFO] [stdout] test tests::collect_console_snapshot_exposes_local_console_url ... ok
[INFO] [stdout] test request_capture::tests::consume_probe_events_waits_for_worker_timeout_event_before_fallback_timeout ... FAILED
[INFO] [stdout] test request_capture::tests::consume_probe_events_requests_shutdown_when_reader_blocks_past_heartbeat_deadline ... ok
[INFO] [stdout] test request_capture::tests::consume_probe_events_refreshes_fallback_deadline_after_each_message ... ok
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- request_capture::tests::consume_probe_events_waits_for_worker_timeout_event_before_fallback_timeout stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'request_capture::tests::consume_probe_events_waits_for_worker_timeout_event_before_fallback_timeout' (302) panicked at crates/prismtrace-host/src/request_capture.rs:825:9:
[INFO] [stdout] main loop should wait for worker timeout event, elapsed=11.272018ms
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x652226f446da - std[ec419fa8110287d5]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x652226f446da - std[ec419fa8110287d5]::backtrace_rs::backtrace::trace_unsynchronized::<std[ec419fa8110287d5]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x652226f446da - std[ec419fa8110287d5]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x652226f446da - <<std[ec419fa8110287d5]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[35159d6ffb30e017]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x652226f5c27a - <core[35159d6ffb30e017]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x652226f5c27a - core[35159d6ffb30e017]::fmt::write
[INFO] [stdout]    6:     0x652226f49d82 - std[ec419fa8110287d5]::io::default_write_fmt::<alloc[a8a7aba520129d98]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/io/mod.rs:621:11
[INFO] [stdout]    7:     0x652226f49d82 - <alloc[a8a7aba520129d98]::vec::Vec<u8> as std[ec419fa8110287d5]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/io/mod.rs:1976:13
[INFO] [stdout]    8:     0x652226f1e38f - <std[ec419fa8110287d5]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x652226f1e38f - std[ec419fa8110287d5]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x652226f3a669 - std[ec419fa8110287d5]::panicking::default_hook
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x652226dc793c - <alloc[a8a7aba520129d98]::boxed::Box<dyn for<'a, 'b> core[35159d6ffb30e017]::ops::function::Fn<(&'a std[ec419fa8110287d5]::panic::PanicHookInfo<'b>,), Output = ()> + core[35159d6ffb30e017]::marker::Sync + core[35159d6ffb30e017]::marker::Send> as core[35159d6ffb30e017]::ops::function::Fn<(&std[ec419fa8110287d5]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/alloc/src/boxed.rs:2285:9
[INFO] [stdout]   12:     0x652226dc793c - test[a8007afcd3effadc]::test_main_inner::<test[a8007afcd3effadc]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x652226f3a822 - <alloc[a8a7aba520129d98]::boxed::Box<dyn for<'a, 'b> core[35159d6ffb30e017]::ops::function::Fn<(&'a std[ec419fa8110287d5]::panic::PanicHookInfo<'b>,), Output = ()> + core[35159d6ffb30e017]::marker::Sync + core[35159d6ffb30e017]::marker::Send> as core[35159d6ffb30e017]::ops::function::Fn<(&std[ec419fa8110287d5]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/alloc/src/boxed.rs:2285:9
[INFO] [stdout]   14:     0x652226f3a822 - std[ec419fa8110287d5]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x652226f1e448 - std[ec419fa8110287d5]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x652226f15a49 - std[ec419fa8110287d5]::sys::backtrace::__rust_end_short_backtrace::<std[ec419fa8110287d5]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x652226f1f31d - __rustc[cfb3622dd870db93]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x652226f5ca0c - core[35159d6ffb30e017]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x652226854a88 - prismtrace_host[a744ec7a06b11f33]::request_capture::tests::consume_probe_events_waits_for_worker_timeout_event_before_fallback_timeout
[INFO] [stdout]                                at /opt/rustwide/workdir/crates/prismtrace-host/src/request_capture.rs:825:9
[INFO] [stdout]   20:     0x652226848ba7 - prismtrace_host[a744ec7a06b11f33]::request_capture::tests::consume_probe_events_waits_for_worker_timeout_event_before_fallback_timeout::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/crates/prismtrace-host/src/request_capture.rs:809:85
[INFO] [stdout]   21:     0x652226935c16 - <prismtrace_host[a744ec7a06b11f33]::request_capture::tests::consume_probe_events_waits_for_worker_timeout_event_before_fallback_timeout::{closure#0} as core[35159d6ffb30e017]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   22:     0x652226dbac1b - <fn() -> core[35159d6ffb30e017]::result::Result<(), alloc[a8a7aba520129d98]::string::String> as core[35159d6ffb30e017]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   23:     0x652226dbac1b - test[a8007afcd3effadc]::__rust_begin_short_backtrace::<core[35159d6ffb30e017]::result::Result<(), alloc[a8a7aba520129d98]::string::String>, fn() -> core[35159d6ffb30e017]::result::Result<(), alloc[a8a7aba520129d98]::string::String>>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/test/src/lib.rs:724:18
[INFO] [stdout]   24:     0x652226dc840b - test[a8007afcd3effadc]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/test/src/lib.rs:747:74
[INFO] [stdout]   25:     0x652226dc840b - <core[35159d6ffb30e017]::panic::unwind_safe::AssertUnwindSafe<test[a8007afcd3effadc]::run_test_in_process::{closure#0}> as core[35159d6ffb30e017]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   26:     0x652226dc840b - std[ec419fa8110287d5]::panicking::catch_unwind::do_call::<core[35159d6ffb30e017]::panic::unwind_safe::AssertUnwindSafe<test[a8007afcd3effadc]::run_test_in_process::{closure#0}>, core[35159d6ffb30e017]::result::Result<(), alloc[a8a7aba520129d98]::string::String>>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panicking.rs:581:40
[INFO] [stdout]   27:     0x652226dc840b - std[ec419fa8110287d5]::panicking::catch_unwind::<core[35159d6ffb30e017]::result::Result<(), alloc[a8a7aba520129d98]::string::String>, core[35159d6ffb30e017]::panic::unwind_safe::AssertUnwindSafe<test[a8007afcd3effadc]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panicking.rs:544:19
[INFO] [stdout]   28:     0x652226dc840b - std[ec419fa8110287d5]::panic::catch_unwind::<core[35159d6ffb30e017]::panic::unwind_safe::AssertUnwindSafe<test[a8007afcd3effadc]::run_test_in_process::{closure#0}>, core[35159d6ffb30e017]::result::Result<(), alloc[a8a7aba520129d98]::string::String>>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panic.rs:359:14
[INFO] [stdout]   29:     0x652226dc840b - test[a8007afcd3effadc]::run_test_in_process
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/test/src/lib.rs:747:27
[INFO] [stdout]   30:     0x652226dc840b - test[a8007afcd3effadc]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/test/src/lib.rs:668:43
[INFO] [stdout]   31:     0x652226dc2de4 - test[a8007afcd3effadc]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/test/src/lib.rs:698:41
[INFO] [stdout]   32:     0x652226dc2de4 - std[ec419fa8110287d5]::sys::backtrace::__rust_begin_short_backtrace::<test[a8007afcd3effadc]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   33:     0x652226dcb012 - std[ec419fa8110287d5]::thread::lifecycle::spawn_unchecked::<test[a8007afcd3effadc]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   34:     0x652226dcb012 - <core[35159d6ffb30e017]::panic::unwind_safe::AssertUnwindSafe<std[ec419fa8110287d5]::thread::lifecycle::spawn_unchecked<test[a8007afcd3effadc]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[35159d6ffb30e017]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   35:     0x652226dcb012 - std[ec419fa8110287d5]::panicking::catch_unwind::do_call::<core[35159d6ffb30e017]::panic::unwind_safe::AssertUnwindSafe<std[ec419fa8110287d5]::thread::lifecycle::spawn_unchecked<test[a8007afcd3effadc]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panicking.rs:581:40
[INFO] [stdout]   36:     0x652226dcb012 - std[ec419fa8110287d5]::panicking::catch_unwind::<(), core[35159d6ffb30e017]::panic::unwind_safe::AssertUnwindSafe<std[ec419fa8110287d5]::thread::lifecycle::spawn_unchecked<test[a8007afcd3effadc]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panicking.rs:544:19
[INFO] [stdout]   37:     0x652226dcb012 - std[ec419fa8110287d5]::panic::catch_unwind::<core[35159d6ffb30e017]::panic::unwind_safe::AssertUnwindSafe<std[ec419fa8110287d5]::thread::lifecycle::spawn_unchecked<test[a8007afcd3effadc]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panic.rs:359:14
[INFO] [stdout]   38:     0x652226dcb012 - std[ec419fa8110287d5]::thread::lifecycle::spawn_unchecked::<test[a8007afcd3effadc]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   39:     0x652226dcb012 - <std[ec419fa8110287d5]::thread::lifecycle::spawn_unchecked<test[a8007afcd3effadc]::run_test::{closure#1}, ()>::{closure#1} as core[35159d6ffb30e017]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   40:     0x652226f4325f - <alloc[a8a7aba520129d98]::boxed::Box<dyn core[35159d6ffb30e017]::ops::function::FnOnce<(), Output = ()> + core[35159d6ffb30e017]::marker::Send> as core[35159d6ffb30e017]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/alloc/src/boxed.rs:2271:9
[INFO] [stdout]   41:     0x652226f4325f - <std[ec419fa8110287d5]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   42:     0x7cf4f6e6baa4 - <unknown>
[INFO] [stdout]   43:     0x7cf4f6ef8a64 - clone
[INFO] [stdout]   44:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     request_capture::tests::consume_probe_events_waits_for_worker_timeout_event_before_fallback_timeout
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 212 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.61s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `-p prismtrace-host --lib`
[INFO] running `Command { std: "docker" "inspect" "ddb965f3ea079b159af098ccd2c6c352c7321368513f16a084a8057669529780", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "ddb965f3ea079b159af098ccd2c6c352c7321368513f16a084a8057669529780", kill_on_drop: false }`
[INFO] [stdout] ddb965f3ea079b159af098ccd2c6c352c7321368513f16a084a8057669529780
