[INFO] fetching crate ricecoder-specs 0.1.71...
[INFO] testing ricecoder-specs-0.1.71 against try#8da20828b6ea601dfd8e2448cb50ace88876bd78 for pr-150933
[INFO] extracting crate ricecoder-specs 0.1.71 into /workspace/builds/worker-4-tc2/source
[INFO] started tweaking crates.io crate ricecoder-specs 0.1.71
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate ricecoder-specs 0.1.71
[INFO] tweaked toml for crates.io crate ricecoder-specs 0.1.71 written to /workspace/builds/worker-4-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate ricecoder-specs 0.1.71 on toolchain 8da20828b6ea601dfd8e2448cb50ace88876bd78
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+8da20828b6ea601dfd8e2448cb50ace88876bd78" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate ricecoder-specs 0.1.71 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" "+8da20828b6ea601dfd8e2448cb50ace88876bd78" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-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" "+8da20828b6ea601dfd8e2448cb50ace88876bd78" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 45e4b16e1035f33c48afa62a4206e1e889938330110fd176910559aa17e9af76
[INFO] running `Command { std: "docker" "start" "-a" "45e4b16e1035f33c48afa62a4206e1e889938330110fd176910559aa17e9af76", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "45e4b16e1035f33c48afa62a4206e1e889938330110fd176910559aa17e9af76", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "45e4b16e1035f33c48afa62a4206e1e889938330110fd176910559aa17e9af76", kill_on_drop: false }`
[INFO] [stdout] 45e4b16e1035f33c48afa62a4206e1e889938330110fd176910559aa17e9af76
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-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" "+8da20828b6ea601dfd8e2448cb50ace88876bd78" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 18a5554a663c3bc7263a8b62056cc4b288df7c5a50b381a96d959348a3944be0
[INFO] running `Command { std: "docker" "start" "-a" "18a5554a663c3bc7263a8b62056cc4b288df7c5a50b381a96d959348a3944be0", kill_on_drop: false }`
[INFO] [stderr]    Compiling num-traits v0.2.19
[INFO] [stderr]    Compiling pulldown-cmark v0.9.6
[INFO] [stderr]    Compiling log v0.4.28
[INFO] [stderr]    Compiling unicode-width v0.2.2
[INFO] [stderr]    Compiling syn v2.0.111
[INFO] [stderr]    Compiling inotify-sys v0.1.5
[INFO] [stderr]    Compiling parking_lot_core v0.9.12
[INFO] [stderr]    Compiling filetime v0.2.26
[INFO] [stderr]    Compiling dirs-sys v0.4.1
[INFO] [stderr]    Compiling mio v1.1.0
[INFO] [stderr]    Compiling signal-hook-registry v1.4.7
[INFO] [stderr]    Compiling socket2 v0.6.1
[INFO] [stderr]    Compiling tracing-core v0.1.35
[INFO] [stderr]    Compiling aho-corasick v1.1.4
[INFO] [stderr]    Compiling unicase v2.8.1
[INFO] [stderr]    Compiling bitflags v2.10.0
[INFO] [stderr]    Compiling inotify v0.9.6
[INFO] [stderr]    Compiling serde_json v1.0.145
[INFO] [stderr]    Compiling mio v0.8.11
[INFO] [stderr]    Compiling dirs v5.0.1
[INFO] [stderr]    Compiling getrandom v0.3.4
[INFO] [stderr]    Compiling getopts v0.2.24
[INFO] [stderr]    Compiling parking_lot v0.12.5
[INFO] [stderr]    Compiling notify v6.1.1
[INFO] [stderr]    Compiling regex-automata v0.4.13
[INFO] [stderr]    Compiling regex v1.12.2
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling tokio-macros v2.6.0
[INFO] [stderr]    Compiling thiserror-impl v1.0.69
[INFO] [stderr]    Compiling tracing-attributes v0.1.31
[INFO] [stderr]    Compiling async-trait v0.1.89
[INFO] [stderr]    Compiling tokio v1.48.0
[INFO] [stderr]    Compiling tracing v0.1.43
[INFO] [stderr]    Compiling thiserror v1.0.69
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling toml_datetime v0.6.11
[INFO] [stderr]    Compiling serde_spanned v0.6.9
[INFO] [stderr]    Compiling serde_yaml v0.9.34+deprecated
[INFO] [stderr]    Compiling uuid v1.18.1
[INFO] [stderr]    Compiling chrono v0.4.42
[INFO] [stderr]    Compiling toml_edit v0.22.27
[INFO] [stderr]    Compiling toml v0.8.23
[INFO] [stderr]    Compiling ricecoder-storage v0.1.71
[INFO] [stderr]    Compiling ricecoder-specs v0.1.71 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 24.69s
[INFO] running `Command { std: "docker" "inspect" "18a5554a663c3bc7263a8b62056cc4b288df7c5a50b381a96d959348a3944be0", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "18a5554a663c3bc7263a8b62056cc4b288df7c5a50b381a96d959348a3944be0", kill_on_drop: false }`
[INFO] [stdout] 18a5554a663c3bc7263a8b62056cc4b288df7c5a50b381a96d959348a3944be0
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-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" "+8da20828b6ea601dfd8e2448cb50ace88876bd78" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] b341b09c42f81c38e1b3be5317dd7dcb74c71a403f36f298d1d712b663998f2a
[INFO] running `Command { std: "docker" "start" "-a" "b341b09c42f81c38e1b3be5317dd7dcb74c71a403f36f298d1d712b663998f2a", kill_on_drop: false }`
[INFO] [stderr]    Compiling async-stream-impl v0.3.6
[INFO] [stderr]    Compiling ppv-lite86 v0.2.21
[INFO] [stderr]    Compiling tokio v1.48.0
[INFO] [stderr]    Compiling tempfile v3.23.0
[INFO] [stderr]    Compiling pulldown-cmark v0.9.6
[INFO] [stderr]    Compiling uuid v1.18.1
[INFO] [stderr]    Compiling chrono v0.4.42
[INFO] [stderr]    Compiling async-stream v0.3.6
[INFO] [stderr]    Compiling rusty-fork v0.3.1
[INFO] [stderr]    Compiling rand_chacha v0.9.0
[INFO] [stderr]    Compiling proptest v1.9.0
[INFO] [stderr]    Compiling ricecoder-storage v0.1.71
[INFO] [stderr]    Compiling tokio-stream v0.1.17
[INFO] [stderr]    Compiling tokio-test v0.4.4
[INFO] [stderr]    Compiling ricecoder-specs v0.1.71 (/opt/rustwide/workdir)
[INFO] [stdout] warning: function `arb_spec_name` is never used
[INFO] [stdout]   --> tests/inheritance_precedence_property.rs:17:4
[INFO] [stdout]    |
[INFO] [stdout] 17 | fn arb_spec_name() -> impl Strategy<Value = String> {
[INFO] [stdout]    |    ^^^^^^^^^^^^^
[INFO] [stdout]    |
[INFO] [stdout]    = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: function `arb_spec_version` is never used
[INFO] [stdout]   --> tests/inheritance_precedence_property.rs:21:4
[INFO] [stdout]    |
[INFO] [stdout] 21 | fn arb_spec_version() -> impl Strategy<Value = String> {
[INFO] [stdout]    |    ^^^^^^^^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: function `arb_precedence_level` is never used
[INFO] [stdout]   --> tests/inheritance_precedence_property.rs:25:4
[INFO] [stdout]    |
[INFO] [stdout] 25 | fn arb_precedence_level() -> impl Strategy<Value = u32> {
[INFO] [stdout]    |    ^^^^^^^^^^^^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 34.69s
[INFO] running `Command { std: "docker" "inspect" "b341b09c42f81c38e1b3be5317dd7dcb74c71a403f36f298d1d712b663998f2a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "b341b09c42f81c38e1b3be5317dd7dcb74c71a403f36f298d1d712b663998f2a", kill_on_drop: false }`
[INFO] [stdout] b341b09c42f81c38e1b3be5317dd7dcb74c71a403f36f298d1d712b663998f2a
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-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" "+8da20828b6ea601dfd8e2448cb50ace88876bd78" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 92cefe29f3edd00da3c93d48dd36639ede1d30aa14421df882f50d41719aaa07
[INFO] running `Command { std: "docker" "start" "-a" "92cefe29f3edd00da3c93d48dd36639ede1d30aa14421df882f50d41719aaa07", kill_on_drop: false }`
[INFO] [stderr] warning: function `arb_spec_name` is never used
[INFO] [stderr]   --> tests/inheritance_precedence_property.rs:17:4
[INFO] [stderr]    |
[INFO] [stderr] 17 | fn arb_spec_name() -> impl Strategy<Value = String> {
[INFO] [stderr]    |    ^^^^^^^^^^^^^
[INFO] [stderr]    |
[INFO] [stderr]    = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default
[INFO] [stderr] 
[INFO] [stderr] warning: function `arb_spec_version` is never used
[INFO] [stderr]   --> tests/inheritance_precedence_property.rs:21:4
[INFO] [stderr]    |
[INFO] [stderr] 21 | fn arb_spec_version() -> impl Strategy<Value = String> {
[INFO] [stderr]    |    ^^^^^^^^^^^^^^^^
[INFO] [stderr] 
[INFO] [stderr] warning: function `arb_precedence_level` is never used
[INFO] [stderr]   --> tests/inheritance_precedence_property.rs:25:4
[INFO] [stderr]    |
[INFO] [stderr] 25 | fn arb_precedence_level() -> impl Strategy<Value = u32> {
[INFO] [stderr]    |    ^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] 
[INFO] [stderr] warning: `ricecoder-specs` (test "inheritance_precedence_property") generated 3 warnings
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.25s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/ricecoder_specs-b8b32b276a7e8f76)
[INFO] [stdout] 
[INFO] [stdout] running 226 tests
[INFO] [stdout] test ai_writer::tests::test_analyze_gaps_design_missing ... ok
[INFO] [stdout] test ai_writer::tests::test_add_multiple_messages ... ok
[INFO] [stdout] test ai_writer::tests::test_add_message_user ... ok
[INFO] [stdout] test ai_writer::tests::test_analyze_gaps_requirements_incomplete ... ok
[INFO] [stdout] test ai_writer::tests::test_analyze_gaps_tasks_missing ... ok
[INFO] [stdout] test ai_writer::tests::test_are_phases_approved_up_to ... ok
[INFO] [stdout] test ai_writer::tests::test_build_prompt_with_steering_context_includes_conversation ... ok
[INFO] [stdout] test ai_writer::tests::test_build_prompt_with_steering_context ... ok
[INFO] [stdout] test ai_writer::tests::test_can_transition_after_approval ... ok
[INFO] [stdout] test ai_writer::tests::test_can_transition_before_approval ... ok
[INFO] [stdout] test ai_writer::tests::test_analyze_gaps_discovery_empty ... ok
[INFO] [stdout] test ai_writer::tests::test_conversation_history_preserved ... ok
[INFO] [stdout] test ai_writer::tests::test_format_steering_context ... ok
[INFO] [stdout] test ai_writer::tests::test_format_steering_context_empty ... ok
[INFO] [stdout] test ai_writer::tests::test_gap_analysis_suggestions_vary_by_phase ... ok
[INFO] [stdout] test ai_writer::tests::test_analyze_gaps_requirements_missing ... ok
[INFO] [stdout] test ai_writer::tests::test_get_conversation_history ... ok
[INFO] [stdout] test ai_writer::tests::test_get_current_phase ... ok
[INFO] [stdout] test ai_writer::tests::test_get_phase_guidance_design ... ok
[INFO] [stdout] test ai_writer::tests::test_build_prompt_with_steering_context_project_precedence ... ok
[INFO] [stdout] test ai_writer::tests::test_get_phase_guidance_discovery ... ok
[INFO] [stdout] test ai_writer::tests::test_get_phase_guidance_execution ... ok
[INFO] [stdout] test ai_writer::tests::test_get_phase_guidance_requirements ... ok
[INFO] [stdout] test ai_writer::tests::test_get_phase_guidance_tasks ... ok
[INFO] [stdout] test ai_writer::tests::test_initialize_session ... ok
[INFO] [stdout] test ai_writer::tests::test_request_approval ... ok
[INFO] [stdout] test ai_writer::tests::test_transition_to_next_phase ... ok
[INFO] [stdout] test ai_writer::tests::test_sequential_phase_workflow ... ok
[INFO] [stdout] test ai_writer::tests::test_transition_fails_without_approval ... ok
[INFO] [stdout] test ai_writer::tests::test_add_message_assistant ... ok
[INFO] [stdout] test ai_writer::tests::test_validate_spec_against_steering_requirements_phase_no_requirements ... ok
[INFO] [stdout] test ai_writer::tests::test_validate_spec_against_steering_valid ... ok
[INFO] [stdout] test approval::tests::test_approval_timestamps_are_recorded ... ok
[INFO] [stdout] test approval::tests::test_approve_phase ... ok
[INFO] [stdout] test approval::tests::test_approve_phase_without_feedback ... ok
[INFO] [stdout] test approval::tests::test_are_phases_approved_up_to ... ok
[INFO] [stdout] test approval::tests::test_can_transition_after_approval ... ok
[INFO] [stdout] test approval::tests::test_cannot_transition_from_execution ... ok
[INFO] [stdout] test approval::tests::test_cannot_transition_from_final_phase ... ok
[INFO] [stdout] test approval::tests::test_cannot_transition_without_approval ... ok
[INFO] [stdout] test approval::tests::test_get_all_approvals ... ok
[INFO] [stdout] test approval::tests::test_get_phase_approval ... ok
[INFO] [stdout] test approval::tests::test_initialize_gates ... ok
[INFO] [stdout] test approval::tests::test_sequential_phase_progression ... ok
[INFO] [stdout] test approval::tests::test_transition_to_next_phase_fails_without_approval ... ok
[INFO] [stdout] test approval::tests::test_transition_to_next_phase_succeeds_with_approval ... ok
[INFO] [stdout] test ai_writer::tests::test_validate_spec_against_steering_missing_author ... ok
[INFO] [stdout] test cache::tests::test_cache_clear ... ok
[INFO] [stdout] test cache::tests::test_cache_miss ... ok
[INFO] [stdout] test cache::tests::test_cache_set_and_get ... ok
[INFO] [stdout] test cache::tests::test_cache_invalidate ... ok
[INFO] [stdout] test change_tracking::tests::test_change_timestamps_are_recent ... ok
[INFO] [stdout] test change_tracking::tests::test_change_detail_with_no_changes ... ok
[INFO] [stdout] test change_tracking::tests::test_change_tracker_clone ... ok
[INFO] [stdout] test change_tracking::tests::test_change_tracker_creation ... ok
[INFO] [stdout] test change_tracking::tests::test_change_tracker_with_multiple_specs ... ok
[INFO] [stdout] test change_tracking::tests::test_clear_history ... ok
[INFO] [stdout] test change_tracking::tests::test_get_history_preserves_order ... ok
[INFO] [stdout] test change_tracking::tests::test_record_change_with_name_change ... ok
[INFO] [stdout] test conversation::tests::test_add_approval_gate ... ok
[INFO] [stdout] test conversation::tests::test_add_message ... ok
[INFO] [stdout] test change_tracking::tests::test_record_change_with_version_change ... ok
[INFO] [stdout] test conversation::tests::test_add_message_to_nonexistent_session_fails ... ok
[INFO] [stdout] test conversation::tests::test_approve_phase ... ok
[INFO] [stdout] test conversation::tests::test_clear_history ... ok
[INFO] [stdout] test conversation::tests::test_conversation_history_preservation ... ok
[INFO] [stdout] test conversation::tests::test_create_session ... ok
[INFO] [stdout] test conversation::tests::test_create_duplicate_session_fails ... ok
[INFO] [stdout] test approval::tests::test_session_updated_at_changes_on_approval ... ok
[INFO] [stdout] test approval::tests::test_session_updated_at_changes_on_transition ... ok
[INFO] [stdout] test conversation::tests::test_delete_session ... ok
[INFO] [stdout] test conversation::tests::test_get_approval_status ... ok
[INFO] [stdout] test conversation::tests::test_get_conversation_history ... ok
[INFO] [stdout] test conversation::tests::test_get_message ... ok
[INFO] [stdout] test conversation::tests::test_get_nonexistent_message_fails ... ok
[INFO] [stdout] test conversation::tests::test_get_nonexistent_session_fails ... ok
[INFO] [stdout] test conversation::tests::test_get_session ... ok
[INFO] [stdout] test conversation::tests::test_list_sessions ... ok
[INFO] [stdout] test conversation::tests::test_message_count ... ok
[INFO] [stdout] test conversation::tests::test_session_lifecycle ... ok
[INFO] [stdout] test conversation::tests::test_update_phase ... ok
[INFO] [stdout] test format_conversion::tests::test_convert_invalid_format ... ok
[INFO] [stdout] test inheritance::tests::test_merge_invalid_precedence ... ok
[INFO] [stdout] test inheritance::tests::test_merge_updates_inheritance ... ok
[INFO] [stdout] test inheritance::tests::test_resolve_hierarchy_ordering ... ok
[INFO] [stdout] test inheritance::tests::test_resolve_single_spec ... ok
[INFO] [stdout] test inheritance::tests::test_merge_parent_overrides_child ... ok
[INFO] [stdout] test inheritance::tests::test_validate_chain_no_circular_dependency ... ok
[INFO] [stdout] test inheritance::tests::test_validate_chain_invalid_precedence ... ok
[INFO] [stdout] test inheritance::tests::test_validate_chain_circular_dependency ... ok
[INFO] [stdout] test manager::tests::test_cache_invalidation ... ok
[INFO] [stdout] test manager::tests::test_discover_specs_empty_directory ... ok
[INFO] [stdout] test manager::tests::test_save_and_load_yaml_spec ... ok
[INFO] [stdout] test manager::tests::test_discover_specs_recursive ... ok
[INFO] [stdout] test manager::tests::test_spec_manager_creation ... ok
[INFO] [stdout] test manager::tests::test_spec_manager_default ... ok
[INFO] [stdout] test manager::tests::test_save_creates_parent_directories ... ok
[INFO] [stdout] test manager::tests::test_unsupported_file_format ... ok
[INFO] [stdout] test models::tests::test_approval_gate_approved ... ok
[INFO] [stdout] test models::tests::test_acceptance_criterion_serialization ... ok
[INFO] [stdout] test manager::tests::test_clear_cache ... ok
[INFO] [stdout] test manager::tests::test_load_spec_caching ... ok
[INFO] [stdout] test inheritance::tests::test_resolve_empty_specs ... ok
[INFO] [stdout] test models::tests::test_approval_gate_creation ... ok
[INFO] [stdout] test models::tests::test_approval_gate_serialization ... ok
[INFO] [stdout] test models::tests::test_change_detail_serialization ... ok
[INFO] [stdout] test models::tests::test_change_detail_with_none_values ... ok
[INFO] [stdout] test models::tests::test_design_serialization ... ok
[INFO] [stdout] test models::tests::test_priority_serialization ... ok
[INFO] [stdout] test models::tests::test_component_serialization ... ok
[INFO] [stdout] test models::tests::test_requirement_yaml_serialization ... ok
[INFO] [stdout] test models::tests::test_property_serialization ... ok
[INFO] [stdout] test models::tests::test_requirement_serialization ... ok
[INFO] [stdout] test models::tests::test_data_model_serialization ... ok
[INFO] [stdout] test models::tests::test_spec_change_serialization ... ok
[INFO] [stdout] test models::tests::test_spec_inheritance_no_parent ... ok
[INFO] [stdout] test models::tests::test_spec_inheritance_precedence_levels ... ok
[INFO] [stdout] test manager::tests::test_discover_specs_nonexistent_directory ... ok
[INFO] [stdout] test models::tests::test_spec_inheritance_serialization ... ok
[INFO] [stdout] test models::tests::test_spec_phase_serialization ... ok
[INFO] [stdout] test models::tests::test_spec_metadata_creation ... ok
[INFO] [stdout] test models::tests::test_spec_metadata_no_author ... ok
[INFO] [stdout] test models::tests::test_spec_writing_session_creation ... ok
[INFO] [stdout] test models::tests::test_spec_serialization_minimal ... ok
[INFO] [stdout] test models::tests::test_spec_status_serialization ... ok
[INFO] [stdout] test models::tests::test_spec_query_default ... ok
[INFO] [stdout] test models::tests::test_spec_serialization_complete ... ok
[INFO] [stdout] test models::tests::test_spec_writing_session_phase_progression ... ok
[INFO] [stdout] test models::tests::test_spec_query_with_filters ... ok
[INFO] [stdout] test models::tests::test_spec_writing_session_serialization ... ok
[INFO] [stdout] test models::tests::test_spec_writing_session_with_approval_gates ... ok
[INFO] [stdout] test models::tests::test_spec_writing_session_with_messages ... ok
[INFO] [stdout] test models::tests::test_spec_writing_session_yaml_serialization ... ok
[INFO] [stdout] test models::tests::test_spec_yaml_serialization ... ok
[INFO] [stdout] test models::tests::test_standard_serialization ... ok
[INFO] [stdout] test models::tests::test_steering_rule_serialization ... ok
[INFO] [stdout] test models::tests::test_steering_serialization ... ok
[INFO] [stdout] test models::tests::test_template_ref_serialization ... ok
[INFO] [stdout] test parsers::markdown_tests::test_markdown_serialization_basic ... ok
[INFO] [stdout] test models::tests::test_task_status_serialization ... ok
[INFO] [stdout] test parsers::markdown_tests::test_markdown_serialization_with_requirements ... ok
[INFO] [stdout] test parsers::markdown_tests::test_markdown_serialization_with_tasks ... ok
[INFO] [stdout] test parsers::markdown_tests::test_markdown_serialization_with_design ... ok
[INFO] [stdout] test models::tests::test_task_serialization ... ok
[INFO] [stdout] test parsers::tests::test_yaml_parser_frontmatter_extraction ... ok
[INFO] [stdout] test parsers::tests::test_yaml_parser_no_frontmatter ... ok
[INFO] [stdout] test parsers::tests::test_yaml_parser_roundtrip ... ok
[INFO] [stdout] test parsers::tests::test_yaml_parser_with_frontmatter ... ok
[INFO] [stdout] test parsers::tests::test_yaml_parser_with_whitespace ... ok
[INFO] [stdout] test query::tests::test_detect_circular_dependencies_no_cycles ... ok
[INFO] [stdout] test query::tests::test_query_by_custom_filter_author ... ok
[INFO] [stdout] test query::tests::test_query_by_custom_filter_version ... ok
[INFO] [stdout] test query::tests::test_query_by_multiple_filters ... ok
[INFO] [stdout] test query::tests::test_query_by_name_case_insensitive ... ok
[INFO] [stdout] test query::tests::test_query_by_name_exact_match ... ok
[INFO] [stdout] test query::tests::test_query_by_name_partial_match ... ok
[INFO] [stdout] test query::tests::test_query_by_phase ... ok
[INFO] [stdout] test query::tests::test_query_by_status ... ok
[INFO] [stdout] test query::tests::test_query_consistency_multiple_executions ... ok
[INFO] [stdout] test query::tests::test_query_empty_specs ... ok
[INFO] [stdout] test format_conversion::tests::test_convert_yaml_to_markdown ... ok
[INFO] [stdout] test query::tests::test_query_no_filters ... ok
[INFO] [stdout] test query::tests::test_resolve_dependencies_no_dependencies ... ok
[INFO] [stdout] test query::tests::test_resolve_dependencies_with_task_requirements ... ok
[INFO] [stdout] test steering::tests::test_steering_loader_nonexistent_directory ... ok
[INFO] [stdout] test steering::tests::test_steering_loader_empty_directory ... ok
[INFO] [stdout] test steering::tests::test_steering_loader_yaml_file ... ok
[INFO] [stdout] test steering::tests::test_steering_merge_empty_global ... ok
[INFO] [stdout] test steering::tests::test_steering_merge_empty_project ... ok
[INFO] [stdout] test steering::tests::test_steering_merge_project_overrides_global ... ok
[INFO] [stdout] test steering::tests::test_steering_validate_duplicate_rule_ids ... ok
[INFO] [stdout] test steering::tests::test_steering_validate_empty_rule_description ... ok
[INFO] [stdout] test steering::tests::test_steering_validate_empty_template_path ... ok
[INFO] [stdout] test steering::tests::test_steering_validate_valid ... ok
[INFO] [stdout] test parsers::markdown_tests::test_markdown_parser_basic_spec ... ok
[INFO] [stdout] test parsers::markdown_tests::test_markdown_parser_missing_explicit_id ... ok
[INFO] [stdout] test format_conversion::tests::test_markdown_to_yaml_conversion ... ok
[INFO] [stdout] test validation::tests::test_validate_incose_rules_negative_statement ... ok
[INFO] [stdout] test validation::tests::test_validate_incose_rules_passive_voice ... ok
[INFO] [stdout] test validation::tests::test_validate_incose_rules_pronouns ... ok
[INFO] [stdout] test validation::tests::test_validate_incose_rules_vague_terms ... ok
[INFO] [stdout] test validation::tests::test_validate_incose_rules_valid_requirement ... ok
[INFO] [stdout] test validation::tests::test_validate_ears_compliance_ubiquitous_pattern ... ok
[INFO] [stdout] test validation::tests::test_validate_structure_criterion_empty_id ... ok
[INFO] [stdout] test validation::tests::test_validate_structure_criterion_empty_then ... ok
[INFO] [stdout] test validation::tests::test_validate_structure_criterion_empty_when ... ok
[INFO] [stdout] test validation::tests::test_validate_structure_empty_id ... ok
[INFO] [stdout] test validation::tests::test_validate_structure_empty_name ... ok
[INFO] [stdout] test validation::tests::test_validate_structure_empty_version ... ok
[INFO] [stdout] test validation::tests::test_validate_structure_error_severity ... ok
[INFO] [stdout] test validation::tests::test_validate_structure_reports_file_path_and_line ... ok
[INFO] [stdout] test validation::tests::test_validate_structure_requirement_empty_id ... ok
[INFO] [stdout] test validation::tests::test_validate_structure_requirement_empty_user_story ... ok
[INFO] [stdout] test format_conversion::tests::test_convert_case_insensitive ... ok
[INFO] [stdout] test validation::tests::test_validate_structure_task_empty_description ... ok
[INFO] [stdout] test validation::tests::test_validate_structure_requirement_no_criteria ... ok
[INFO] [stdout] test validation::tests::test_validate_structure_task_empty_id ... ok
[INFO] [stdout] test validation::tests::test_validate_structure_valid_spec ... ok
[INFO] [stdout] test workflow::tests::test_get_all_linked_requirements ... ok
[INFO] [stdout] test workflow::tests::test_get_all_linked_tasks ... ok
[INFO] [stdout] test workflow::tests::test_get_completed_tasks ... ok
[INFO] [stdout] test workflow::tests::test_get_completion_percentage ... ok
[INFO] [stdout] test format_conversion::tests::test_yaml_to_markdown_conversion ... ok
[INFO] [stdout] test workflow::tests::test_get_not_started_tasks ... ok
[INFO] [stdout] test workflow::tests::test_get_requirement_tasks ... ok
[INFO] [stdout] test workflow::tests::test_link_task_empty_requirements ... ok
[INFO] [stdout] test workflow::tests::test_link_task_empty_task_id ... ok
[INFO] [stdout] test workflow::tests::test_get_completion_percentage_empty ... ok
[INFO] [stdout] test workflow::tests::test_get_in_progress_tasks ... ok
[INFO] [stdout] test workflow::tests::test_link_task_to_requirements ... ok
[INFO] [stdout] test workflow::tests::test_update_task_status ... ok
[INFO] [stdout] test workflow::tests::test_reset ... ok
[INFO] [stdout] test workflow::tests::test_update_task_status_not_found ... ok
[INFO] [stdout] test validation::tests::test_validate_returns_all_errors ... ok
[INFO] [stdout] test format_conversion::tests::test_convert_same_format_yaml ... ok
[INFO] [stdout] test validation::tests::test_validate_ears_compliance_non_compliant ... ok
[INFO] [stdout] test validation::tests::test_validate_ears_compliance_valid_event_driven ... ok
[INFO] [stdout] test format_conversion::tests::test_convert_markdown_to_yaml ... ok
[INFO] [stdout] test validation::tests::test_validate_combines_all_checks ... ok
[INFO] [stdout] test format_conversion::tests::test_convert_same_format_markdown ... ok
[INFO] [stdout] test parsers::property_tests::prop_yaml_roundtrip_preserves_spec ... ok
[INFO] [stdout] test parsers::property_tests::prop_yaml_roundtrip_with_frontmatter ... ok
[INFO] [stdout] test parsers::property_tests::prop_markdown_roundtrip_preserves_spec ... ok
[INFO] [stdout] test format_conversion::property_tests::prop_format_conversion_preserves_semantics ... ok
[INFO] [stdout] test format_conversion::property_tests::prop_yaml_markdown_yaml_roundtrip ... ok
[INFO] [stdout] test format_conversion::property_tests::prop_markdown_yaml_markdown_roundtrip ... ok
[INFO] [stderr]      Running tests/circular_dependency_detection_property.rs (/opt/rustwide/target/debug/deps/circular_dependency_detection_property-7eb46aa8bd919468)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 226 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 24.99s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 7 tests
[INFO] [stdout] test prop_internal_references_not_cycles ... ok
[INFO] [stdout] test prop_two_spec_cycle_detected ... ok
[INFO] [stdout] test prop_cycle_detection_deterministic ... ok
[INFO] [stdout] test prop_isolated_specs_no_cycles ... ok
[INFO] [stdout] test prop_cycle_includes_all_affected_specs ... ok
[INFO] [stdout] test prop_circular_dependency_detection_finds_cycles ... ok
[INFO] [stdout] test prop_no_false_positive_cycles ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.26s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/conversation_history_preservation_property.rs (/opt/rustwide/target/debug/deps/conversation_history_preservation_property-40cbb2995b0b9873)
[INFO] [stdout] 
[INFO] [stdout] running 11 tests
[INFO] [stdout] test prop_conversation_message_timestamp_valid ... ok
[INFO] [stdout] test prop_conversation_messages_preserved ... ok
[INFO] [stdout] test prop_conversation_message_content_not_empty ... ok
[INFO] [stdout] test prop_conversation_message_spec_association ... ok
[INFO] [stdout] test prop_conversation_message_cloneable ... ok
[INFO] [stdout] test prop_conversation_message_serialization_roundtrip ... ok
[INFO] [stdout] test prop_conversation_message_debuggable ... ok
[INFO] [stdout] test prop_conversation_message_role_valid ... ok
[INFO] [stdout] test prop_conversation_message_yaml_serialization_roundtrip ... ok
[INFO] [stdout] test prop_conversation_message_order_preserved ... ok
[INFO] [stdout] test prop_conversation_message_ids_unique ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 11 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.46s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/ears_compliance_validation_property.rs (/opt/rustwide/target/debug/deps/ears_compliance_validation_property-97bf04db9065e758)
[INFO] [stdout] 
[INFO] [stdout] running 10 tests
[INFO] [stdout] test prop_ears_compliance_errors_include_suggestions ... ok
[INFO] [stdout] test prop_ubiquitous_pattern_recognized ... ok
[INFO] [stdout] test prop_state_driven_pattern_recognized ... ok
[INFO] [stdout] test prop_unwanted_event_pattern_recognized ... ok
[INFO] [stdout] test prop_event_driven_pattern_recognized ... ok
[INFO] [stdout] test prop_empty_specs_pass_ears_validation ... ok
[INFO] [stdout] test prop_optional_feature_pattern_recognized ... ok
[INFO] [stdout] test prop_ears_errors_have_location_info ... ok
[INFO] [stdout] test prop_non_ears_compliant_requirements_detected ... ok
[INFO] [stdout] test prop_ears_compliant_requirements_pass_validation ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 10 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 21.70s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/inheritance_precedence_property.rs (/opt/rustwide/target/debug/deps/inheritance_precedence_property-4d8d51ffb6ddcd50)
[INFO] [stdout] 
[INFO] [stdout] running 7 tests
[INFO] [stdout] test prop_merge_preserves_parent_precedence ... ok
[INFO] [stdout] test prop_validation_detects_invalid_precedence ... ok
[INFO] [stdout] test prop_circular_dependency_detection ... ok
[INFO] [stdout] test prop_valid_hierarchy_passes_validation ... ok
[INFO] [stdout] test prop_resolve_maintains_spec_identity ... ok
[INFO] [stdout] test prop_inheritance_respects_precedence_ordering ... ok
[INFO] [stdout] test prop_merge_applies_parent_precedence ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.44s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/integration_tests.rs (/opt/rustwide/target/debug/deps/integration_tests-a66b3018fe21db40)
[INFO] [stdout] 
[INFO] [stdout] running 10 tests
[INFO] [stdout] test integration_test_hierarchical_spec_resolution ... ok
[INFO] [stdout] test integration_test_steering_loading_and_merging ... ok
[INFO] [stdout] test integration_test_steering_context_in_ai_prompts ... ok
[INFO] [stdout] test integration_test_ai_assisted_spec_writing_with_approval_gates ... ok
[INFO] [stdout] test integration_test_change_tracking_and_history ... ok
[INFO] [stdout] test integration_test_full_spec_loading_yaml_and_markdown ... ok
[INFO] [stdout] test integration_test_spec_to_task_linking_and_traceability ... ok
[INFO] [stdout] test integration_test_complete_end_to_end_workflow ... ok
[INFO] [stdout] test integration_test_dependency_resolution_and_circular_detection ... ok
[INFO] [stdout] test integration_test_spec_discovery_and_filtering ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 10 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/phase_progression_enforcement_property.rs (/opt/rustwide/target/debug/deps/phase_progression_enforcement_property-fc59da7a090c5feb)
[INFO] [stdout] 
[INFO] [stdout] running 8 tests
[INFO] [stdout] test prop_phase_progression_is_sequential ... ok
[INFO] [stdout] test prop_phases_approved_up_to_is_accurate ... ok
[INFO] [stdout] test prop_can_transition_status_is_accurate ... ok
[INFO] [stdout] test prop_phase_transitions_require_approval ... ok
[INFO] [stdout] test prop_all_phases_have_gates ... ok
[INFO] [stdout] test prop_approval_gates_record_metadata ... ok
[INFO] [stdout] test prop_multiple_approvals_update_gate ... ok
[INFO] [stdout] test prop_session_timestamp_updates ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 8 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 5.73s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/query_consistency_property.rs (/opt/rustwide/target/debug/deps/query_consistency_property-05e595c4102b3b7e)
[INFO] [stdout] 
[INFO] [stdout] running 9 tests
[INFO] [stdout] test prop_query_status_filter_correctness ... ok
[INFO] [stdout] test prop_query_consistency_multiple_executions ... ok
[INFO] [stdout] test prop_query_phase_filter_correctness ... ok
[INFO] [stdout] test prop_query_no_matches_returns_empty ... ok
[INFO] [stdout] test prop_query_no_filters_returns_all ... ok
[INFO] [stdout] test prop_query_deterministic_across_runs ... ok
[INFO] [stdout] test prop_query_name_filter_correctness ... ok
[INFO] [stdout] test prop_query_multiple_filters_and_logic ... ok
[INFO] [stderr]      Running tests/spec_to_task_traceability_property.rs (/opt/rustwide/target/debug/deps/spec_to_task_traceability_property-5651063293e0a81f)
[INFO] [stdout] test prop_query_results_independent_of_input_order ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 9 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.44s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 9 tests
[INFO] [stdout] test prop_all_requirements_have_task_links ... ok
[INFO] [stdout] test prop_multiple_requirement_links_preserved ... ok
[INFO] [stdout] test prop_bidirectional_traceability ... ok
[INFO] [stdout] test prop_reset_clears_all_links ... ok
[INFO] [stdout] test prop_all_tasks_have_requirement_links ... ok
[INFO] [stdout] test prop_task_has_explicit_requirement_links ... ok
[INFO] [stdout] test prop_requirement_has_task_links ... ok
[INFO] [stdout] test prop_multiple_task_links_preserved ... ok
[INFO] [stdout] test prop_task_status_change_preserves_links ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 9 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.41s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/steering_context_integration_property.rs (/opt/rustwide/target/debug/deps/steering_context_integration_property-fcbde6ba79b8b1fa)
[INFO] [stdout] 
[INFO] [stdout] running 6 tests
[INFO] [stdout] test prop_steering_context_handles_empty_steering ... ok
[INFO] [stdout] test prop_steering_context_deterministic ... ok
[INFO] [stdout] test prop_steering_context_complete ... ok
[INFO] [stdout] test prop_steering_context_well_formatted ... ok
[INFO] [stdout] test prop_project_steering_takes_precedence ... ok
[INFO] [stdout] test prop_steering_context_included_in_prompt ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 3.40s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/steering_merge_consistency_property.rs (/opt/rustwide/target/debug/deps/steering_merge_consistency_property-c2748dd235f21742)
[INFO] [stdout] 
[INFO] [stdout] running 8 tests
[INFO] [stdout] test prop_merge_empty_global_preserves_project ... ok
[INFO] [stdout] test prop_merge_idempotent_for_identical_steering ... ok
[INFO] [stdout] test prop_merge_empty_project_preserves_global ... ok
[INFO] [stdout] test prop_merge_project_overrides_global ... ok
[INFO] [stdout] test prop_merge_standards_with_precedence ... ok
[INFO] [stdout] test prop_merge_preserves_rule_properties ... ok
[INFO] [stdout] test prop_merge_templates_with_precedence ... ok
[INFO] [stdout] test prop_merge_no_duplicate_ids ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 8 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 3.45s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]      Running tests/validation_completeness_property.rs (/opt/rustwide/target/debug/deps/validation_completeness_property-c2aae0f3eec36a87)
[INFO] [stdout] running 6 tests
[INFO] [stdout] test prop_valid_specs_pass_validation ... ok
[INFO] [stdout] test prop_validation_detects_invalid_requirements ... ok
[INFO] [stdout] test prop_validation_detects_missing_required_fields ... ok
[INFO] [stdout] test prop_validation_detects_invalid_criteria ... ok
[INFO] [stdout] test prop_validation_reports_file_paths_and_line_numbers ... ok
[INFO] [stdout] test prop_validation_errors_have_severity ... ok
[INFO] [stderr]    Doc-tests ricecoder_specs
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.10s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "92cefe29f3edd00da3c93d48dd36639ede1d30aa14421df882f50d41719aaa07", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "92cefe29f3edd00da3c93d48dd36639ede1d30aa14421df882f50d41719aaa07", kill_on_drop: false }`
[INFO] [stdout] 92cefe29f3edd00da3c93d48dd36639ede1d30aa14421df882f50d41719aaa07