[INFO] fetching crate soon 0.5.0...
[INFO] testing soon-0.5.0 against 1.99.0-beta.8+cargoflags=--release for beta-release-1.100-2
[INFO] extracting crate soon 0.5.0 into /workspace/builds/worker-7-tc1/source
[INFO] started tweaking crates.io crate soon 0.5.0
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate soon 0.5.0
[INFO] tweaked toml for crates.io crate soon 0.5.0 written to /workspace/builds/worker-7-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate soon 0.5.0 on toolchain 1.99.0-beta.8
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.99.0-beta.8" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate soon 0.5.0 already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.99.0-beta.8" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded clap v4.5.38
[INFO] [stderr]   Downloaded counter v0.6.0
[INFO] [stderr]   Downloaded clap_builder v4.5.38
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-m" "1610612736" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:77db811e55d90add9212f6832c23229e3de5f1c1f9905e8cb07319ea15df73ac" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] 762b97db816ea875bebddbb18b9edd7112da25633bb03c5c5d708843049f0d5f
[INFO] running `Command { std: "docker" "start" "762b97db816ea875bebddbb18b9edd7112da25633bb03c5c5d708843049f0d5f", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "762b97db816ea875bebddbb18b9edd7112da25633bb03c5c5d708843049f0d5f", 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" "762b97db816ea875bebddbb18b9edd7112da25633bb03c5c5d708843049f0d5f" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "762b97db816ea875bebddbb18b9edd7112da25633bb03c5c5d708843049f0d5f", 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" "762b97db816ea875bebddbb18b9edd7112da25633bb03c5c5d708843049f0d5f" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "build" "--frozen" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr]    Compiling httparse v1.10.1
[INFO] [stderr]    Compiling num-traits v0.2.19
[INFO] [stderr]    Compiling anstyle-parse v0.2.6
[INFO] [stderr]    Compiling rustls v0.23.37
[INFO] [stderr]    Compiling colorchoice v1.0.3
[INFO] [stderr]    Compiling anstyle v1.0.10
[INFO] [stderr]    Compiling anstyle-query v1.1.2
[INFO] [stderr]    Compiling crc32fast v1.5.0
[INFO] [stderr]    Compiling syn v2.0.101
[INFO] [stderr]    Compiling getrandom v0.2.16
[INFO] [stderr]    Compiling http v1.4.0
[INFO] [stderr]    Compiling clap_lex v0.7.4
[INFO] [stderr]    Compiling regex-automata v0.4.16
[INFO] [stderr]    Compiling dirs-sys v0.5.0
[INFO] [stderr]    Compiling utf8-zero v0.8.1
[INFO] [stderr]    Compiling colored v3.0.0
[INFO] [stderr]    Compiling flate2 v1.1.9
[INFO] [stderr]    Compiling dirs v6.0.0
[INFO] [stderr]    Compiling ring v0.17.14
[INFO] [stderr]    Compiling anstream v0.6.18
[INFO] [stderr]    Compiling clap_builder v4.5.38
[INFO] [stderr]    Compiling counter v0.6.0
[INFO] [stderr]    Compiling ureq-proto v0.6.0
[INFO] [stderr]    Compiling rustls-webpki v0.103.13
[INFO] [stderr]    Compiling regex v1.13.1
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling clap_derive v4.5.32
[INFO] [stderr]    Compiling clap v4.5.38
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling serde_spanned v0.6.9
[INFO] [stderr]    Compiling toml_datetime v0.6.11
[INFO] [stderr]    Compiling chrono v0.4.44
[INFO] [stderr]    Compiling toml_edit v0.22.27
[INFO] [stderr]    Compiling ureq v3.3.0
[INFO] [stderr]    Compiling toml v0.8.23
[INFO] [stderr]    Compiling soon v0.5.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 1m 20s
[INFO] running `Command { std: "docker" "inspect" "762b97db816ea875bebddbb18b9edd7112da25633bb03c5c5d708843049f0d5f", 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" "762b97db816ea875bebddbb18b9edd7112da25633bb03c5c5d708843049f0d5f" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "test" "--frozen" "--no-run" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr]    Compiling soon v0.5.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 9.45s
[INFO] running `Command { std: "docker" "inspect" "762b97db816ea875bebddbb18b9edd7112da25633bb03c5c5d708843049f0d5f", 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" "762b97db816ea875bebddbb18b9edd7112da25633bb03c5c5d708843049f0d5f" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "test" "--frozen" "--release", kill_on_drop: false }`
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 0.12s
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/release/deps/soon-30b20aaa74367a88)
[INFO] [stdout] 
[INFO] [stdout] running 22 tests
[INFO] [stdout] test config::tests::contextual_policy_is_the_default_after_passing_the_promotion_gate ... ok
[INFO] [stdout] test commands::update::tests::older_channel_versions_never_trigger_a_downgrade ... ok
[INFO] [stdout] test commands::update::tests::release_versions_are_read_from_the_selected_channel_only ... ok
[INFO] [stdout] test commands::update::tests::unsupported_channels_have_no_release_lookup ... ok
[INFO] [stdout] test config::tests::update_configuration_accepts_only_published_beta_channels ... ok
[INFO] [stdout] test predict::tests::rejects_sensitive_candidates_loaded_from_shell_history ... ok
[INFO] [stdout] test predict::tests::predicts_an_actionable_full_command ... ok
[INFO] [stdout] test predict::tests::newer_equivalent_evidence_wins ... ok
[INFO] [stdout] test learn::llm::tests::provider_prompt_omits_sensitive_history ... ok
[INFO] [stdout] test learn::llm::tests::sensitive_model_candidates_are_rejected_before_rendering ... ok
[INFO] [stdout] test model_prediction::tests::generated_secrets_and_control_characters_are_untrusted ... ok
[INFO] [stdout] test predict::tests::repeated_evidence_strengthens_a_candidate ... ok
[INFO] [stdout] test model_prediction::tests::provider_urls_are_normalized_without_a_vendor_branch ... ok
[INFO] [stdout] test learn::pattern::tests::rejects_sensitive_candidates_already_present_in_the_learn_database ... ok
[INFO] [stdout] test model_prediction::tests::rerank_accepts_only_safe_local_candidates ... ok
[INFO] [stdout] test learn::pattern::tests::skips_sensitive_history_entries_before_learning_patterns ... ok
[INFO] [stdout] test predict::tests::ignores_candidates_by_executable_name ... ok
[INFO] [stdout] test model_prediction::tests::fenced_json_is_still_parsed_as_untrusted_candidate_data ... ok
[INFO] [stdout] test config::tests::model_prediction_config_validates_mode_and_deadline ... ok
[INFO] [stdout] test privacy::tests::detects_common_inline_secrets_without_keyword_false_positives ... ok
[INFO] [stdout] test privacy::tests::rejects_dangerous_model_commands_without_rejecting_normal_repairs ... ok
[INFO] [stderr]      Running tests/events_cli.rs (/opt/rustwide/target/release/deps/events_cli-dda34eb77b31c0f1)
[INFO] [stdout] test learn::llm::tests::provider_request_uses_filtered_payload_and_environment_credential ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 22 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.06s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 33 tests
[INFO] [stdout] test a_truncated_final_line_does_not_corrupt_the_next_event ... ok
[INFO] [stdout] test configured_literal_exclusions_reject_commands_without_echoing_values ... ok
[INFO] [stdout] test configured_pattern_exclusions_are_validated_and_enforced ... ok
[INFO] [stdout] test clearing_events_requires_confirmation_and_empties_the_store ... ok
[INFO] [stdout] test contextual_debug_explains_signal_groups_without_claiming_confidence ... ok
[INFO] [stdout] test imported_zsh_history_can_predict_without_the_source_history_file ... ok
[INFO] [stdout] test learn_statistics_do_not_render_sensitive_legacy_entries ... ok
[INFO] [stdout] test duplicate_command_event_ids_are_idempotent ... ok
[INFO] [stdout] test contextual_policy_treats_missing_historical_metadata_as_no_evidence ... ok
[INFO] [stdout] test markov_fallback_does_not_render_sensitive_legacy_entries ... ok
[INFO] [stdout] test contextual_policy_uses_accepted_and_executed_feedback ... ok
[INFO] [stdout] test command_result_selects_repair_or_next_step_policy ... ok
[INFO] [stdout] test provider_credentials_use_an_environment_variable_name ... ok
[INFO] [stdout] test overlapping_rotated_zsh_histories_are_deduplicated ... ok
[INFO] [stdout] test contextual_policy_uses_the_original_event_hour_bucket ... ok
[INFO] [stdout] test previously_stored_sensitive_events_are_not_suggested ... ok
[INFO] [stdout] test recorded_command_is_counted_without_leaking_its_text ... ok
[INFO] [stdout] test configured_retention_keeps_only_the_newest_events ... ok
[INFO] [stdout] test contextual_policy_ordering_is_deterministic_across_processes ... ok
[INFO] [stdout] test contextual_policy_uses_second_order_command_transitions ... ok
[INFO] [stdout] test zsh_history_import_preview_is_non_mutating_and_private ... ok
[INFO] [stdout] test contextual_policy_combines_smoothed_evidence_instead_of_one_lexicographic_signal ... ok
[INFO] [stdout] test zsh_history_import_is_idempotent_and_preserves_unknown_metadata ... ok
[INFO] [stdout] test raw_prediction_can_report_the_selected_policy_source ... ok
[INFO] [stdout] test contextual_policy_uses_repository_and_branch_when_known ... ok
[INFO] [stdout] test sensitive_suggestions_are_rejected_before_persistence ... ok
[INFO] [stdout] test contextual_policy_can_be_enabled_to_rank_by_working_directory ... ok
[INFO] [stdout] test sensitive_commands_are_rejected_before_persistence ... ok
[INFO] [stdout] test contextual_policy_uses_the_previous_command_duration_bucket ... ok
[INFO] [stdout] test contextual_policy_distinguishes_failure_repair_from_success_next_step ... ok
[INFO] [stdout] test contextual_policy_uses_the_original_event_weekday ... ok
[INFO] [stdout] test suggestion_feedback_outcomes_are_counted_without_leaking_candidates ... ok
[INFO] [stdout] test similar_search_does_not_render_sensitive_legacy_entries ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 33 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.19s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/model_prediction_cli.rs (/opt/rustwide/target/release/deps/model_prediction_cli-b300734ee36fa195)
[INFO] [stdout] 
[INFO] [stdout] running 6 tests
[INFO] [stdout] test explicit_generation_uses_filtered_context_and_never_executes_the_candidate ... ok
[INFO] [stdout] test model_sources_are_off_by_default_and_do_not_contact_the_provider ... FAILED
[INFO] [stdout] test dangerous_model_output_is_rejected_before_rendering has been running for over 60 seconds
[INFO] [stdout] test opt_in_local_repair_flows_through_the_contextual_ranker has been running for over 60 seconds
[INFO] [stdout] test opt_in_rerank_can_reorder_only_local_history_candidates has been running for over 60 seconds
[INFO] [stdout] test provider_timeout_falls_back_within_the_configured_deadline has been running for over 60 seconds
[ERROR] error running command: no output for 300 seconds
[INFO] running `Command { std: "docker" "inspect" "762b97db816ea875bebddbb18b9edd7112da25633bb03c5c5d708843049f0d5f", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "762b97db816ea875bebddbb18b9edd7112da25633bb03c5c5d708843049f0d5f", kill_on_drop: false }`
[INFO] [stdout] 762b97db816ea875bebddbb18b9edd7112da25633bb03c5c5d708843049f0d5f
