[INFO] cloning repository https://github.com/radotsvetkov/soma
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/radotsvetkov/soma" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fradotsvetkov%2Fsoma", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fradotsvetkov%2Fsoma'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 622e4b7dca6c18b2ef9e99fe0ec4dc3286ee3012
[INFO] testing radotsvetkov/soma against 1.98.0-beta.1 for beta-1.98-1
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fradotsvetkov%2Fsoma" "/workspace/builds/worker-6-tc2/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-6-tc2/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/radotsvetkov/soma
[INFO] finished tweaking git repo https://github.com/radotsvetkov/soma
[INFO] tweaked toml for git repo https://github.com/radotsvetkov/soma written to /workspace/builds/worker-6-tc2/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/radotsvetkov/soma on toolchain 1.98.0-beta.1
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.98.0-beta.1" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/radotsvetkov/soma already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.98.0-beta.1" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-m" "1610612736" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:3d5ced03c013a94a2f102a4510f48a6e9184255caf5fd8244f58017bde7f5210" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] 7db58a643578398d9cf849d47684fb76fab1fa4f83a9a4486bc3c0e02ff58a0d
[INFO] running `Command { std: "docker" "start" "7db58a643578398d9cf849d47684fb76fab1fa4f83a9a4486bc3c0e02ff58a0d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "7db58a643578398d9cf849d47684fb76fab1fa4f83a9a4486bc3c0e02ff58a0d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-w" "/opt/rustwide/workdir" "--user" "0:0" "7db58a643578398d9cf849d47684fb76fab1fa4f83a9a4486bc3c0e02ff58a0d" "/opt/rustwide/cargo-home/bin/cargo" "+1.98.0-beta.1" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "7db58a643578398d9cf849d47684fb76fab1fa4f83a9a4486bc3c0e02ff58a0d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-w" "/opt/rustwide/workdir" "--user" "0:0" "7db58a643578398d9cf849d47684fb76fab1fa4f83a9a4486bc3c0e02ff58a0d" "/opt/rustwide/cargo-home/bin/cargo" "+1.98.0-beta.1" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling soma v0.2.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 4.68s
[INFO] running `Command { std: "docker" "inspect" "7db58a643578398d9cf849d47684fb76fab1fa4f83a9a4486bc3c0e02ff58a0d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-w" "/opt/rustwide/workdir" "--user" "0:0" "7db58a643578398d9cf849d47684fb76fab1fa4f83a9a4486bc3c0e02ff58a0d" "/opt/rustwide/cargo-home/bin/cargo" "+1.98.0-beta.1" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling soma v0.2.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 5.96s
[INFO] running `Command { std: "docker" "inspect" "7db58a643578398d9cf849d47684fb76fab1fa4f83a9a4486bc3c0e02ff58a0d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-w" "/opt/rustwide/workdir" "--user" "0:0" "7db58a643578398d9cf849d47684fb76fab1fa4f83a9a4486bc3c0e02ff58a0d" "/opt/rustwide/cargo-home/bin/cargo" "+1.98.0-beta.1" "test" "--frozen", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.04s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/soma-029b6eb804b2116d)
[INFO] [stdout] 
[INFO] [stdout] running 219 tests
[INFO] [stdout] test anchor::tests::host_extraction ... ok
[INFO] [stdout] test anchor::tests::needle_required_even_when_granted ... ok
[INFO] [stdout] test anchor::tests::parser_apple_status_string_shape ... ok
[INFO] [stdout] test anchor::tests::parser_minimal_granted_response ... ok
[INFO] [stdout] test anchor::tests::parser_rejection_status ... ok
[INFO] [stdout] test anchor::tests::parser_status_integer_overrunning_pkistatusinfo_rejected ... ok
[INFO] [stdout] test anchor::tests::parser_truncated_garbage_rejected ... ok
[INFO] [stdout] test anchor::tests::tsq_matches_openssl_verified_vector ... ok
[INFO] [stdout] test anchor::tests::tsq_only_hash_bytes_vary ... ok
[INFO] [stdout] test aiact::tests::caveats_on_page_one_and_both_dates_present ... ok
[INFO] [stdout] test aiact::tests::json_sibling_parses_with_stable_keys ... ok
[INFO] [stdout] test aiact::tests::histogram_counts_and_journaled_generation ... ok
[INFO] [stdout] test aiact::tests::granted_anchor_listed_with_openssl_instructions ... ok
[INFO] [stdout] test aiact::tests::missing_operator_fields_render_placeholder ... ok
[INFO] [stdout] test aiact::tests::concurrent_same_label_wraps_pair_by_pid_not_swapped ... ok
[INFO] [stdout] test aiact::tests::operator_fields_render_when_configured ... ok
[INFO] [stdout] test aiact::tests::period_table_label_with_pipe_and_newline_stays_one_row ... ok
[INFO] [stdout] test cache::tests::keys_differ_by_provider_model_prompt ... ok
[INFO] [stdout] test aiact::tests::period_of_use_from_wrap_pair_and_skill_run ... ok
[INFO] [stdout] test cache::tests::put_get_hit_counting ... ok
[INFO] [stdout] test anchor::tests::anchor_now_refuses_broken_chain ... ok
[INFO] [stdout] test anchor::tests::auto_due_decision_logic ... ok
[INFO] [stdout] test cli::json_tests::anchor_now_refused_under_local_only_via_cli ... ok
[INFO] [stdout] test anchor::tests::list_returns_anchor_events_in_order ... ok
[INFO] [stdout] test anchor::tests::chain_head_at_matches_full_verify ... ok
[INFO] [stdout] test cli::json_tests::cache_stats_json ... ok
[INFO] [stdout] test aiact::tests::refuses_broken_chain ... ok
[INFO] [stdout] test anchor::tests::anchor_now_refused_under_local_only ... ok
[INFO] [stdout] test attest::tests::journaled_as_export_bundle_format_attestation ... ok
[INFO] [stdout] test attest::tests::statement_shape_exact_keys ... ok
[INFO] [stdout] test attest::tests::anchors_granted_pass_through_verbatim_failed_excluded ... ok
[INFO] [stdout] test cli::json_tests::config_refused_identity_field_journals_decision ... ok
[INFO] [stdout] test attest::tests::refuses_broken_chain ... ok
[INFO] [stdout] test attest::tests::subject_digest_equals_live_head ... ok
[INFO] [stdout] test attest::tests::subject_flag_overrides_name_not_digest ... ok
[INFO] [stdout] test cli::json_tests::anchor_now_rejects_bad_url_before_any_gate ... ok
[INFO] [stdout] test cli::json_tests::anchor_list_json_shape ... ok
[INFO] [stdout] test cache::tests::lru_eviction_respects_cap ... ok
[INFO] [stdout] test cli::json_tests::config_set_journals_change_event ... ok
[INFO] [stdout] test cli::json_tests::config_aiact_roundtrip_and_validation ... ok
[INFO] [stdout] test cli::json_tests::config_anchor_auto_roundtrip_and_validation ... ok
[INFO] [stdout] test anchor::tests::verify_synthetic_granted_anchor ... ok
[INFO] [stdout] test cli::json_tests::config_anchor_tsa_url_roundtrip_and_validation ... ok
[INFO] [stdout] test cli::json_tests::knowledge_list_json ... ok
[INFO] [stdout] test cli::json_tests::init_honors_project_flag_and_positional_precedence ... ok
[INFO] [stdout] test cli::json_tests::log_show_json ... ok
[INFO] [stdout] test cli::json_tests::config_refused_write_leaves_field_unchanged ... ok
[INFO] [stdout] test cli::json_tests::issues_list_json ... ok
[INFO] [stdout] test cli::json_tests::log_tail_n_negative_errors ... ok
[INFO] [stdout] test cli::json_tests::config_set_get_numeric_stays_number ... ok
[INFO] [stdout] test cli::json_tests::config_set_get_string_roundtrip ... ok
[INFO] [stdout] test cli::json_tests::config_set_guardrail_project_field ... ok
[INFO] [stdout] test cli::json_tests::anchor_verify_errors_without_granted_anchor ... ok
[INFO] [stdout] test cli::json_tests::cron_list_json ... ok
[INFO] [stdout] test cli::json_tests::dashdash_tail_only_special_for_wrap ... ok
[INFO] [stdout] test cli::json_tests::export_eu_ai_act_via_cli_writes_both_files ... ok
[INFO] [stdout] test cli::json_tests::goal_list_json ... ok
[INFO] [stdout] test cli::json_tests::policy_refused_write_leaves_field_unchanged ... ok
[INFO] [stdout] test cli::json_tests::knowledge_search_json ... ok
[INFO] [stdout] test cli::json_tests::config_get_json_path ... ok
[INFO] [stdout] test cli::json_tests::log_tail_json_ndjson ... ok
[INFO] [stdout] test cli::json_tests::policy_set_autonomy_roundtrip_and_journal ... ok
[INFO] [stdout] test cli::json_tests::policy_set_unknown_field_refused ... ok
[INFO] [stdout] test cli::json_tests::log_tail_n_non_numeric_errors ... ok
[INFO] [stdout] test cli::json_tests::log_tail_n_zero_returns_no_events ... ok
[INFO] [stdout] test cli::json_tests::select_json ... ok
[INFO] [stdout] test cli::json_tests::log_verify_json_ok ... ok
[INFO] [stdout] test cli::json_tests::model_probe_json ... ok
[INFO] [stdout] test cli::json_tests::model_route_json ... ok
[INFO] [stdout] test cli::json_tests::policy_refused_bad_autonomy_journals_decision ... ok
[INFO] [stdout] test cli::json_tests::policy_refused_legacy_autonomy_journals_decision ... ok
[INFO] [stdout] test cli::json_tests::policy_set_bad_autonomy_value_refused ... ok
[INFO] [stdout] test cli::json_tests::version_json ... ok
[INFO] [stdout] test cli::json_tests::policy_set_deny_commands_json_array ... ok
[INFO] [stdout] --help --json
[INFO] [stdout] test cache::tests::clear_removes_everything ... ok
[INFO] [stdout] test cli::json_tests::policy_show_json ... ok
[INFO] [stdout] test cli::json_tests::project_list_json ... ok
[INFO] [stdout] test cli::json_tests::proposals_list_json ... ok
[INFO] [stdout] test cron::tests::cadence_detection ... ok
[INFO] [stdout] test cron::tests::dow_seven_alias_does_not_corrupt_steps_or_ranges ... ok
[INFO] [stdout] test cron::tests::matching_and_vixie_dom_dow ... ok
[INFO] [stdout] test cli::json_tests::select_json_empty_project_returns_representable_shape ... ok
[INFO] [stdout] test cron::tests::parse_and_expand_fields ... ok
[INFO] [stdout] test cli::json_tests::log_verify_json_broken_exits_nonzero_and_emits_json ... ok
[INFO] [stdout] test cli::json_tests::proposals_show_apply_dismiss_json ... ok
[INFO] [stdout] test cli::json_tests::wrap_child_args_after_dashdash_are_verbatim ... ok
[INFO] [stdout] test cli::json_tests::select_json_incompatible_with_ask_model ... ok
[INFO] [stdout] test events::tests::head_survives_missing_headfile ... ok
[INFO] [stdout] test events::tests::chain_appends_and_verifies ... ok
[INFO] [stdout] test cli::json_tests::skill_list_json ... ok
[INFO] [stdout] test events::tests::render_is_single_line ... ok
[INFO] [stdout] test cli::json_tests::select_json_incompatible_with_run ... ok
[INFO] [stdout] test cli::json_tests::skill_show_json ... ok
[INFO] [stdout] test events::tests::secrets_redacted_before_disk ... ok
[INFO] [stdout] test cli::json_tests::status_json ... ok
[INFO] [stdout] test events::tests::tamper_detected ... ok
[INFO] [stdout] test export::tests::otlp_export_full_pipeline_integration ... ok
[INFO] [stdout] --json hi
[INFO] [stdout] test cli::json_tests::goal_show_json ... ok
[INFO] [stdout] test cli::json_tests::wrap_child_literal_json_not_consumed_by_soma ... ok
[INFO] [stdout] test cron::tests::tick_runs_due_entry_and_journals ... ok
[INFO] [stdout] test export::tests::otlp_out_outside_writable_paths_refused_and_journaled ... ok
[INFO] [stdout] test cron::tests::add_due_and_same_minute_guard ... ok
[INFO] [stdout] test export::tests::aiact_out_outside_writable_paths_refused_and_journaled ... ok
[INFO] [stdout] test cli::json_tests::wrap_refuses_empty_flag_looking_and_stray_args ... ok
[INFO] [stdout] test export::tests::export_bundles_anchors_with_verify_instructions ... ok
[INFO] [stdout] test export::tests::attestation_out_outside_writable_paths_refused_and_journaled ... ok
[INFO] [stdout] test http::tests::chunked_response ... ok
[INFO] [stdout] test http::tests::connection_refused_is_fast_clean_error ... ok
[INFO] [stdout] test http::tests::content_length_response ... ok
[INFO] [stdout] test http::tests::url_parsing ... ok
[INFO] [stdout] test export::tests::export_refuses_broken_journal ... ok
[INFO] [stdout] test improve::tests::config_path_setter ... ok
[INFO] [stdout] test cli::json_tests::tick_daily_auto_anchor_attempts_once_then_backs_off ... ok
[INFO] [stdout] test export::tests::otlp_export_refuses_broken_journal ... ok
[INFO] [stdout] test export::tests::otlp_export_document_shape_three_events ... ok
[INFO] [stdout] hi
[INFO] [stdout] test export::tests::export_then_verify_roundtrip ... ok
[INFO] [stdout] test export::tests::subformat_out_inside_writable_paths_succeeds ... ok
[INFO] [stdout] test cli::json_tests::wrap_json_summary_is_wrap_end_data ... ok
[INFO] [stdout] test goals::tests::observe_mode_blocks_goal_run ... ok
[INFO] [stdout] test goals::tests::model_step_routes_through_echo ... ok
[INFO] [stdout] test goals::tests::validation_rejects_bad_steps ... ok
[INFO] [stdout] test cron::tests::success_detection_uses_exit_code_not_note_text ... ok
[INFO] [stdout] test json::tests::control_chars_serialized_safely ... ok
[INFO] [stdout] test json::tests::depth_limit ... ok
[INFO] [stdout] test json::tests::escapes_and_unicode ... ok
[INFO] [stdout] test json::tests::numbers ... ok
[INFO] [stdout] test json::tests::pretty_parses_back ... ok
[INFO] [stdout] test json::tests::rejects_garbage ... ok
[INFO] [stdout] test json::tests::roundtrip_basic ... ok
[INFO] [stdout] test json::tests::set_and_get ... ok
[INFO] [stdout] test goals::tests::add_run_verify_roundtrip ... ok
[INFO] [stdout] test improve::tests::auto_apply_only_under_auto_autonomy ... ok
[INFO] [stdout] test improve::tests::dismiss_and_status_tracking ... ok
[INFO] [stdout] test improve::tests::timeout_issues_produce_applicable_tune_timeout ... ok
[INFO] [stdout] test mcp::tests::add_server_creates_mcp_json_when_absent ... ok
[INFO] [stdout] test goals::tests::skill_step_executes_registered_skill ... ok
[INFO] [stdout] test improve::tests::fix_skill_apply_rewrites_cmd_bumps_version_resolves_issues ... ok
[INFO] [stdout] test knowledge::tests::add_search_and_lesson_boost ... ok
[INFO] [stdout] test improve::tests::fix_skill_echo_provider_invalid_response_no_proposal_and_valid_path ... ok
[INFO] [stdout] test improve::tests::optimizer_flags_simple_to_cloud_routing ... ok
[INFO] [stdout] test mcp::tests::add_server_round_trips_through_list_servers ... ok
[INFO] [stdout] test mcp::tests::add_and_remove_both_journal ... ok
[INFO] [stdout] test models::tests::classifier_levels ... ok
[INFO] [stdout] test improve::tests::fix_skill_skips_archived_skills ... ok
[INFO] [stdout] test improve::tests::fix_skill_not_auto_applied_under_auto_autonomy ... ok
[INFO] [stdout] test models::tests::render_route_mentions_fallback ... ok
[INFO] [stdout] test improve::tests::scan_flags_flaky_skill_and_dedupes ... ok
[INFO] [stdout] test improve::tests::fix_skill_deny_list_discards_suggestion ... ok
[INFO] [stdout] test goals::tests::failing_verification_halts_and_marks_failed ... ok
[INFO] [stdout] test mcp::tests::shell_injection_server_is_denied_before_spawn ... ok
[INFO] [stdout] test improve::tests::cron_proposal_flow_via_detect ... ok
[INFO] [stdout] test mcp::tests::mcp_add_denied_command_refused_and_journaled_not_persisted ... ok
[INFO] [stdout] test mcp::tests::missing_config_and_unknown_server_error_cleanly ... ok
[INFO] [stdout] test policy::tests::autonomy_gates_execution ... ok
[INFO] [stdout] test policy::tests::defaults_block_destructive_commands ... ok
[INFO] [stdout] test policy::tests::deny_wins_over_allow ... ok
[INFO] [stdout] test policy::tests::empty_allow_list_denies_everything ... ok
[INFO] [stdout] test policy::tests::json_roundtrip ... ok
[INFO] [stdout] test policy::tests::load_missing_file_gives_defaults ... ok
[INFO] [stdout] test mcp::tests::remove_missing_server_errors ... ok
[INFO] [stdout] test models::tests::anthropic_requires_network_policy_and_key ... ok
[INFO] [stdout] test policy::tests::load_wrong_typed_field_refuses_at_file_level ... ok
[INFO] [stdout] test policy::tests::mcp_allowlist_blocks_shell_injection ... ok
[INFO] [stdout] test policy::tests::network_default_localhost_only ... ok
[INFO] [stdout] test policy::tests::path_boundaries ... ok
[INFO] [stdout] test policy::tests::load_unparseable_file_fails_closed ... ok
[INFO] [stdout] test policy::tests::load_valid_file_roundtrips ... ok
[INFO] [stdout] test policy::tests::valid_and_partial_policies_still_load_defaults ... ok
[INFO] [stdout] test policy::tests::wrong_typed_array_field_fails_closed_not_empty ... ok
[INFO] [stdout] test policy::tests::wrong_typed_bool_and_int_fail_closed ... ok
[INFO] [stdout] test models::tests::echo_ask_and_cache_hit ... ok
[INFO] [stdout] test models::tests::direct_ask_cannot_bypass_network_policy ... ok
[INFO] [stdout] test models::tests::route_errors_when_nothing_ready ... ok
[INFO] [stdout] test sha256::tests::boundary_lengths ... ok
[INFO] [stdout] test sha256::tests::incremental_equals_oneshot ... ok
[INFO] [stdout] test sha256::tests::fips_vectors ... ok
[INFO] [stdout] test models::tests::route_uses_config_and_falls_back_with_reason ... ok
[INFO] [stdout] test neuro::tests::empty_inputs_error_clearly ... ok
[INFO] [stdout] test neuro::rerank_tests::echo_rerank_keeps_or_promotes_named_candidate ... ok
[INFO] [stdout] test skills::tests::builtins_are_valid ... ok
[INFO] [stdout] test skills::tests::lint_catches_bad_manifests ... ok
[INFO] [stdout] test mcp::tests::duplicate_add_errors ... ok
[INFO] [stdout] test neuro::tests::lessons_boost_selection ... ok
[INFO] [stdout] test cron::tests::next_after_finds_next_slot ... ok
[INFO] [stdout] test neuro::tests::picks_obvious_match_and_explains ... ok
[INFO] [stdout] test util::tests::atomic_write_roundtrip ... ok
[INFO] [stdout] test project::tests::double_init_rejected ... ok
[INFO] [stdout] test util::tests::glob ... ok
[INFO] [stdout] test util::tests::ids_unique_and_sortable ... ok
[INFO] [stdout] test util::tests::iso_format ... ok
[INFO] [stdout] test util::tests::tail_and_append ... ok
[INFO] [stdout] test util::tests::tokenizer ... ok
[INFO] [stdout] test wrap::tests::default_redact_keys_cover_auth_and_bearer ... ok
[INFO] [stdout] test util::tests::civil_roundtrip_and_known_dates ... ok
[INFO] [stdout] test neuro::tests::reliability_separates_equal_text_matches ... ok
[INFO] [stdout] test project::tests::init_and_load_roundtrip ... ok
[INFO] [stdout] hi
[INFO] [stdout] test project::tests::presets_manage_tsa_hosts ... ok
[INFO] [stdout] test project::tests::preset_apply_changes_config_policy_and_journals ... ok
[INFO] [stdout] test wrap::tests::excerpt_windows ... ok
[INFO] [stdout] test mcp::tests::handshake_list_call_against_mock_server ... ok
[INFO] [stdout] test wrap::tests::observe_refuses_journaled_nothing_spawned ... ok
[INFO] [stdout] test wrap::tests::redact_text_shapes ... ok
[INFO] [stdout] test skills::tests::shadowing_project_over_global ... ok
[INFO] [stdout] test skills::tests::observe_mode_blocks_execution ... ok
[INFO] [stdout] test skills::tests::policy_denies_journal_and_issue ... ok
[INFO] [stdout] test wrap::tests::env_sensitive_journals_names_never_values ... ok
[INFO] [stdout] test wrap::tests::deny_listed_command_refused_at_spawn ... ok
[INFO] [stdout] test wrap::tests::empty_and_flag_looking_cmd_refused ... ok
[INFO] [stdout] test skills::tests::add_run_metrics_roundtrip ... ok
[INFO] [stdout] test skills::tests::input_substitution_is_quoted ... ok
[INFO] [stdout] api_key=sk-ant-VERYSECRET123
[INFO] [stdout] test skills::tests::failure_files_issue_and_resolution_writes_lesson ... ok
[INFO] [stdout] test sha256::tests::million_a ... ok
[INFO] [stdout] present-123
[INFO] [stdout] missing
[INFO] [stdout] hello
[INFO] [stdout] present-123
[INFO] [stdout] test wrap::tests::excerpt_redacts_secret_values ... ok
[INFO] [stdout] test wrap::tests::exit_code_propagates ... ok
[INFO] [stdout] test wrap::tests::sha256_bytes_and_receipts_for_echo ... ok
[INFO] [stdout] test mcp::tests::import_makes_selectable_skills ... ok
[INFO] [stdout] test wrap::tests::env_strict_drops_and_env_pass_restores ... ok
[INFO] [stdout] test wrap::tests::timeout_kills_and_flags ... ok
[INFO] [stdout] test skills::tests::timeout_kills_and_records_failure ... FAILED
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- skills::tests::timeout_kills_and_records_failure stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'skills::tests::timeout_kills_and_records_failure' (480) panicked at src/skills.rs:624:9:
[INFO] [stdout] assertion failed: started.elapsed().as_secs() < 10
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5f3ff4b4abc1 - std[73adb7dc35730857]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x5f3ff4b4abc1 - std[73adb7dc35730857]::backtrace_rs::backtrace::trace_unsynchronized::<std[73adb7dc35730857]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x5f3ff4b4abc1 - std[73adb7dc35730857]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x5f3ff4b4abc1 - <<std[73adb7dc35730857]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[6883ba1bc0fe4ed1]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x5f3ff4b6262a - <core[6883ba1bc0fe4ed1]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x5f3ff4b6262a - core[6883ba1bc0fe4ed1]::fmt::write
[INFO] [stdout]    6:     0x5f3ff4b5054c - std[73adb7dc35730857]::io::default_write_fmt::<alloc[55a36b64bcbf2c0d]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/io/mod.rs:626:11
[INFO] [stdout]    7:     0x5f3ff4b5054c - <alloc[55a36b64bcbf2c0d]::vec::Vec<u8> as std[73adb7dc35730857]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/io/mod.rs:1730:13
[INFO] [stdout]    8:     0x5f3ff4b23fd6 - <std[73adb7dc35730857]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x5f3ff4b23fd6 - std[73adb7dc35730857]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x5f3ff4b40fd9 - std[73adb7dc35730857]::panicking::default_hook
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x5f3ff4ad7b40 - <alloc[55a36b64bcbf2c0d]::boxed::Box<dyn for<'a, 'b> core[6883ba1bc0fe4ed1]::ops::function::Fn<(&'a std[73adb7dc35730857]::panic::PanicHookInfo<'b>,), Output = ()> + core[6883ba1bc0fe4ed1]::marker::Send + core[6883ba1bc0fe4ed1]::marker::Sync> as core[6883ba1bc0fe4ed1]::ops::function::Fn<(&std[73adb7dc35730857]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/alloc/src/boxed.rs:2333:9
[INFO] [stdout]   12:     0x5f3ff4ad7b40 - test[980ffaebd391d06d]::test_main_inner::<test[980ffaebd391d06d]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x5f3ff4b41192 - <alloc[55a36b64bcbf2c0d]::boxed::Box<dyn for<'a, 'b> core[6883ba1bc0fe4ed1]::ops::function::Fn<(&'a std[73adb7dc35730857]::panic::PanicHookInfo<'b>,), Output = ()> + core[6883ba1bc0fe4ed1]::marker::Send + core[6883ba1bc0fe4ed1]::marker::Sync> as core[6883ba1bc0fe4ed1]::ops::function::Fn<(&std[73adb7dc35730857]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/alloc/src/boxed.rs:2333:9
[INFO] [stdout]   14:     0x5f3ff4b41192 - std[73adb7dc35730857]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:823:13
[INFO] [stdout]   15:     0x5f3ff4b240b4 - std[73adb7dc35730857]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:681:13
[INFO] [stdout]   16:     0x5f3ff4b1b1e9 - std[73adb7dc35730857]::sys::backtrace::__rust_end_short_backtrace::<std[73adb7dc35730857]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x5f3ff4b252bd - __rustc[a7b7b02e776dd976]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:679:5
[INFO] [stdout]   18:     0x5f3ff4b62dfc - core[6883ba1bc0fe4ed1]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x5f3ff4b62dc2 - core[6883ba1bc0fe4ed1]::panicking::panic
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/panicking.rs:150:5
[INFO] [stdout]   20:     0x5f3ff49b22f0 - soma[5f8e69b91ae85620]::skills::tests::timeout_kills_and_records_failure
[INFO] [stdout]                                at /opt/rustwide/workdir/src/skills.rs:624:9
[INFO] [stdout]   21:     0x5f3ff499eab7 - soma[5f8e69b91ae85620]::skills::tests::timeout_kills_and_records_failure::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/skills.rs:615:43
[INFO] [stdout]   22:     0x5f3ff4991316 - <soma[5f8e69b91ae85620]::skills::tests::timeout_kills_and_records_failure::{closure#0} as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   23:     0x5f3ff4acae7b - <fn() -> core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   24:     0x5f3ff4acae7b - test[980ffaebd391d06d]::__rust_begin_short_backtrace::<core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>, fn() -> core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:724:18
[INFO] [stdout]   25:     0x5f3ff4ad84c5 - test[980ffaebd391d06d]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:747:74
[INFO] [stdout]   26:     0x5f3ff4ad84c5 - <core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   27:     0x5f3ff4ad84c5 - std[73adb7dc35730857]::panicking::catch_unwind::do_call::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}>, core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:576:43
[INFO] [stdout]   28:     0x5f3ff4ad84c5 - std[73adb7dc35730857]::panicking::catch_unwind::<core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>, core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:544:19
[INFO] [stdout]   29:     0x5f3ff4ad84c5 - std[73adb7dc35730857]::panic::catch_unwind::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}>, core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panic.rs:359:14
[INFO] [stdout]   30:     0x5f3ff4ad84c5 - test[980ffaebd391d06d]::run_test_in_process
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:747:27
[INFO] [stdout]   31:     0x5f3ff4ad84c5 - test[980ffaebd391d06d]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:668:43
[INFO] [stdout]   32:     0x5f3ff4ad2f74 - test[980ffaebd391d06d]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:698:41
[INFO] [stdout]   33:     0x5f3ff4ad2f74 - std[73adb7dc35730857]::sys::backtrace::__rust_begin_short_backtrace::<test[980ffaebd391d06d]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   34:     0x5f3ff4adb612 - std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked::<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   35:     0x5f3ff4adb612 - <core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   36:     0x5f3ff4adb612 - std[73adb7dc35730857]::panicking::catch_unwind::do_call::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:576:43
[INFO] [stdout]   37:     0x5f3ff4adb612 - std[73adb7dc35730857]::panicking::catch_unwind::<(), core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:544:19
[INFO] [stdout]   38:     0x5f3ff4adb612 - std[73adb7dc35730857]::panic::catch_unwind::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panic.rs:359:14
[INFO] [stdout]   39:     0x5f3ff4adb612 - std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked::<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   40:     0x5f3ff4adb612 - <std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1} as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   41:     0x5f3ff4b495ef - <alloc[55a36b64bcbf2c0d]::boxed::Box<dyn core[6883ba1bc0fe4ed1]::ops::function::FnOnce<(), Output = ()> + core[6883ba1bc0fe4ed1]::marker::Send> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/alloc/src/boxed.rs:2319:9
[INFO] [stdout]   42:     0x5f3ff4b495ef - <std[73adb7dc35730857]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/thread/unix.rs:123:17
[INFO] [stdout]   43:     0x716c32360aa4 - <unknown>
[INFO] [stdout]   44:     0x716c323eda64 - clone
[INFO] [stdout]   45:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     skills::tests::timeout_kills_and_records_failure
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 218 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 30.55s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--lib`
[INFO] running `Command { std: "docker" "inspect" "7db58a643578398d9cf849d47684fb76fab1fa4f83a9a4486bc3c0e02ff58a0d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "7db58a643578398d9cf849d47684fb76fab1fa4f83a9a4486bc3c0e02ff58a0d", kill_on_drop: false }`
[INFO] [stdout] 7db58a643578398d9cf849d47684fb76fab1fa4f83a9a4486bc3c0e02ff58a0d
