[INFO] fetching crate ricecoder-workflows 0.1.71...
[INFO] testing ricecoder-workflows-0.1.71 against master#562dee4820c458d823175268e41601d4c060588a for pr-154210-1
[INFO] extracting crate ricecoder-workflows 0.1.71 into /workspace/builds/worker-5-tc1/source
[INFO] started tweaking crates.io crate ricecoder-workflows 0.1.71
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate ricecoder-workflows 0.1.71
[INFO] tweaked toml for crates.io crate ricecoder-workflows 0.1.71 written to /workspace/builds/worker-5-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate ricecoder-workflows 0.1.71 on toolchain 562dee4820c458d823175268e41601d4c060588a
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate ricecoder-workflows 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" "+562dee4820c458d823175268e41601d4c060588a" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded wasm-bindgen-macro v0.2.106
[INFO] [stderr]   Downloaded quote v1.0.42
[INFO] [stderr]   Downloaded wasm-bindgen v0.2.106
[INFO] [stderr]   Downloaded wasm-bindgen-shared v0.2.106
[INFO] [stderr]   Downloaded uuid v1.18.1
[INFO] [stderr]   Downloaded async-stream-impl v0.3.6
[INFO] [stderr]   Downloaded rand_xorshift v0.4.0
[INFO] [stderr]   Downloaded tokio-test v0.4.4
[INFO] [stderr]   Downloaded async-stream v0.3.6
[INFO] [stderr]   Downloaded rusty-fork v0.3.1
[INFO] [stderr]   Downloaded signal-hook-registry v1.4.7
[INFO] [stderr]   Downloaded wasm-bindgen-macro-support v0.2.106
[INFO] [stderr]   Downloaded js-sys v0.3.83
[INFO] [stderr]   Downloaded tracing-core v0.1.35
[INFO] [stderr]   Downloaded cc v1.0.106
[INFO] [stderr]   Downloaded tracing v0.1.43
[INFO] [stderr]   Downloaded zerocopy-derive v0.8.31
[INFO] [stderr]   Downloaded indexmap v2.12.1
[INFO] [stderr]   Downloaded bytes v1.11.0
[INFO] [stderr]   Downloaded proptest v1.9.0
[INFO] [stderr]   Downloaded chrono v0.4.42
[INFO] [stderr]   Downloaded zerocopy v0.8.31
[INFO] [stderr]   Downloaded syn v2.0.111
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/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" "+562dee4820c458d823175268e41601d4c060588a" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] e5df4d9e48790981424aa014cece24d71df9fb3d5c687b809a1eff75420682d1
[INFO] running `Command { std: "docker" "start" "-a" "e5df4d9e48790981424aa014cece24d71df9fb3d5c687b809a1eff75420682d1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "e5df4d9e48790981424aa014cece24d71df9fb3d5c687b809a1eff75420682d1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "e5df4d9e48790981424aa014cece24d71df9fb3d5c687b809a1eff75420682d1", kill_on_drop: false }`
[INFO] [stdout] e5df4d9e48790981424aa014cece24d71df9fb3d5c687b809a1eff75420682d1
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/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" "+562dee4820c458d823175268e41601d4c060588a" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] e35185a56cc6ade107db7d1f0669b1804cff7ba1c23f0ef7fb950d65a36143ed
[INFO] running `Command { std: "docker" "start" "-a" "e35185a56cc6ade107db7d1f0669b1804cff7ba1c23f0ef7fb950d65a36143ed", kill_on_drop: false }`
[INFO] [stderr]    Compiling quote v1.0.42
[INFO] [stderr]    Compiling libc v0.2.177
[INFO] [stderr]    Compiling serde_core v1.0.228
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling getrandom v0.3.4
[INFO] [stderr]    Compiling pin-project-lite v0.2.16
[INFO] [stderr]    Compiling serde_json v1.0.145
[INFO] [stderr]    Compiling hashbrown v0.16.1
[INFO] [stderr]    Compiling itoa v1.0.15
[INFO] [stderr]    Compiling once_cell v1.21.3
[INFO] [stderr]    Compiling thiserror v1.0.69
[INFO] [stderr]    Compiling ryu v1.0.20
[INFO] [stderr]    Compiling memchr v2.7.6
[INFO] [stderr]    Compiling unsafe-libyaml v0.2.11
[INFO] [stderr]    Compiling bytes v1.11.0
[INFO] [stderr]    Compiling iana-time-zone v0.1.64
[INFO] [stderr]    Compiling tracing-core v0.1.35
[INFO] [stderr]    Compiling syn v2.0.111
[INFO] [stderr]    Compiling indexmap v2.12.1
[INFO] [stderr]    Compiling parking_lot_core v0.9.12
[INFO] [stderr]    Compiling signal-hook-registry v1.4.7
[INFO] [stderr]    Compiling mio v1.1.0
[INFO] [stderr]    Compiling socket2 v0.6.1
[INFO] [stderr]    Compiling parking_lot v0.12.5
[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 uuid v1.18.1
[INFO] [stderr]    Compiling chrono v0.4.42
[INFO] [stderr]    Compiling serde_yaml v0.9.34+deprecated
[INFO] [stderr]    Compiling ricecoder-workflows v0.1.71 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 20.12s
[INFO] running `Command { std: "docker" "inspect" "e35185a56cc6ade107db7d1f0669b1804cff7ba1c23f0ef7fb950d65a36143ed", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "e35185a56cc6ade107db7d1f0669b1804cff7ba1c23f0ef7fb950d65a36143ed", kill_on_drop: false }`
[INFO] [stdout] e35185a56cc6ade107db7d1f0669b1804cff7ba1c23f0ef7fb950d65a36143ed
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/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" "+562dee4820c458d823175268e41601d4c060588a" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] bd75a407dccd8e5c304fe8777d73827c59ae6b92eb28f26bbbddeabb3c448f1e
[INFO] running `Command { std: "docker" "start" "-a" "bd75a407dccd8e5c304fe8777d73827c59ae6b92eb28f26bbbddeabb3c448f1e", kill_on_drop: false }`
[INFO] [stderr]    Compiling getrandom v0.3.4
[INFO] [stderr]    Compiling zerocopy v0.8.31
[INFO] [stderr]    Compiling rustix v1.1.2
[INFO] [stderr]    Compiling num-traits v0.2.19
[INFO] [stderr]    Compiling bitflags v2.10.0
[INFO] [stderr]    Compiling linux-raw-sys v0.11.0
[INFO] [stderr]    Compiling fastrand v2.3.0
[INFO] [stderr]    Compiling futures-core v0.3.31
[INFO] [stderr]    Compiling tokio v1.48.0
[INFO] [stderr]    Compiling async-stream-impl v0.3.6
[INFO] [stderr]    Compiling wait-timeout v0.2.1
[INFO] [stderr]    Compiling fnv v1.0.7
[INFO] [stderr]    Compiling quick-error v1.2.3
[INFO] [stderr]    Compiling bit-vec v0.8.0
[INFO] [stderr]    Compiling unarray v0.1.4
[INFO] [stderr]    Compiling regex-syntax v0.8.8
[INFO] [stderr]    Compiling bit-set v0.8.0
[INFO] [stderr]    Compiling rand_core v0.9.3
[INFO] [stderr]    Compiling uuid v1.18.1
[INFO] [stderr]    Compiling async-stream v0.3.6
[INFO] [stderr]    Compiling rand_xorshift v0.4.0
[INFO] [stderr]    Compiling rand v0.9.2
[INFO] [stderr]    Compiling chrono v0.4.42
[INFO] [stderr]    Compiling ppv-lite86 v0.2.21
[INFO] [stderr]    Compiling tempfile v3.23.0
[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 tokio-stream v0.1.17
[INFO] [stderr]    Compiling ricecoder-workflows v0.1.71 (/opt/rustwide/workdir)
[INFO] [stderr]    Compiling tokio-test v0.4.4
[INFO] [stdout] warning: function `total_steps_strategy` is never used
[INFO] [stdout]   --> src/status_reporting_properties.rs:44:8
[INFO] [stdout]    |
[INFO] [stdout] 44 |     fn total_steps_strategy() -> impl Strategy<Value = usize> {
[INFO] [stdout]    |        ^^^^^^^^^^^^^^^^^^^^
[INFO] [stdout]    |
[INFO] [stdout]    = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 22.25s
[INFO] running `Command { std: "docker" "inspect" "bd75a407dccd8e5c304fe8777d73827c59ae6b92eb28f26bbbddeabb3c448f1e", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "bd75a407dccd8e5c304fe8777d73827c59ae6b92eb28f26bbbddeabb3c448f1e", kill_on_drop: false }`
[INFO] [stdout] bd75a407dccd8e5c304fe8777d73827c59ae6b92eb28f26bbbddeabb3c448f1e
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/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" "+562dee4820c458d823175268e41601d4c060588a" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] db57a80b8c5bd335ef8a45d9e212f5848e5b488d80167e9a0e9c5bdbf5d24baf
[INFO] running `Command { std: "docker" "start" "-a" "db57a80b8c5bd335ef8a45d9e212f5848e5b488d80167e9a0e9c5bdbf5d24baf", kill_on_drop: false }`
[INFO] [stderr] warning: function `total_steps_strategy` is never used
[INFO] [stderr]   --> src/status_reporting_properties.rs:44:8
[INFO] [stderr]    |
[INFO] [stderr] 44 |     fn total_steps_strategy() -> impl Strategy<Value = usize> {
[INFO] [stderr]    |        ^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr]    |
[INFO] [stderr]    = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default
[INFO] [stderr] 
[INFO] [stderr] warning: `ricecoder-workflows` (lib test) generated 1 warning
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.16s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/ricecoder_workflows-69ac1590da0ed472)
[INFO] [stdout] 
[INFO] [stdout] running 240 tests
[INFO] [stdout] test activity_log::tests::test_create_activity_logger ... ok
[INFO] [stdout] test activity_log::tests::test_get_entries_for_step ... ok
[INFO] [stdout] test activity_log::tests::test_get_entries_by_type ... ok
[INFO] [stdout] test activity_log::tests::test_log_workflow_started ... ok
[INFO] [stdout] test activity_log::tests::test_max_entries_limit ... ok
[INFO] [stdout] test activity_log::tests::test_log_approval_workflow ... ok
[INFO] [stdout] test activity_log::tests::test_log_step_started ... ok
[INFO] [stdout] test activity_log::tests::test_log_error ... ok
[INFO] [stdout] test activity_log::tests::test_clear_entries ... ok
[INFO] [stdout] test activity_logging_properties::tests::prop_activity_log_can_be_cleared ... ok
[INFO] [stdout] test activity_log::tests::test_log_activity ... ok
[INFO] [stdout] test agent_executor::tests::test_execute_agent_step ... ok
[INFO] [stdout] test agent_executor::tests::test_get_agent_id ... ok
[INFO] [stdout] test agent_executor::tests::test_get_task ... ok
[INFO] [stdout] test approval::tests::test_approval_gate_approve ... ok
[INFO] [stdout] test agent_executor::tests::test_execute_agent_step_with_timeout ... ok
[INFO] [stdout] test approval::tests::test_approval_gate_reject ... ok
[INFO] [stdout] test approval::tests::test_approve_request ... ok
[INFO] [stdout] test approval::tests::test_approval_gate_cannot_approve_twice ... ok
[INFO] [stdout] test approval::tests::test_reject_request ... ok
[INFO] [stdout] test approval::tests::test_approval_gate_cannot_approve_after_timeout ... ok
[INFO] [stdout] test approval::tests::test_create_approval_request ... ok
[INFO] [stdout] test approval::tests::test_approval_request_timeout ... ok
[INFO] [stdout] test approval::tests::test_approval_gate_get_step_requests ... ok
[INFO] [stdout] test approval::tests::test_approval_gate_request_approval ... ok
[INFO] [stdout] test command_executor::tests::test_execute_command_step ... ok
[INFO] [stdout] test command_executor::tests::test_get_args ... ok
[INFO] [stdout] test command_executor::tests::test_get_command ... ok
[INFO] [stdout] test command_executor::tests::test_get_timeout ... ok
[INFO] [stdout] test command_executor::tests::test_execute_command_step_with_timeout ... ok
[INFO] [stdout] test condition::tests::test_evaluate_condition_then_branch ... ok
[INFO] [stdout] test condition::tests::test_evaluate_equality_false ... ok
[INFO] [stdout] test condition::tests::test_evaluate_equality_true ... ok
[INFO] [stdout] test condition::tests::test_evaluate_greater_equal ... ok
[INFO] [stdout] test condition::tests::test_evaluate_greater_than_false ... ok
[INFO] [stdout] test condition::tests::test_evaluate_condition_else_branch ... ok
[INFO] [stdout] test condition::tests::test_evaluate_greater_than_true ... ok
[INFO] [stdout] test condition::tests::test_evaluate_less_than ... ok
[INFO] [stdout] test condition::tests::test_evaluate_not_equal ... ok
[INFO] [stdout] test condition::tests::test_parse_value_boolean ... ok
[INFO] [stdout] test condition::tests::test_parse_value_null ... ok
[INFO] [stdout] test condition::tests::test_evaluate_less_equal ... ok
[INFO] [stdout] test condition::tests::test_parse_value_number ... ok
[INFO] [stdout] test condition::tests::test_parse_value_string ... ok
[INFO] [stdout] test activity_logging_properties::tests::prop_activity_log_filtering_by_type_is_correct ... ok
[INFO] [stdout] test activity_logging_properties::tests::prop_activity_log_entries_have_timestamps ... ok
[INFO] [stdout] test engine::tests::test_can_execute_step ... ok
[INFO] [stdout] test engine::tests::test_cancel_execution ... ok
[INFO] [stdout] test engine::tests::test_create_engine ... ok
[INFO] [stdout] test condition_properties::tests::prop_condition_evaluation_is_deterministic ... ok
[INFO] [stdout] test engine::tests::test_get_active_executions ... ok
[INFO] [stdout] test engine::tests::test_get_execution_order ... ok
[INFO] [stdout] test engine::tests::test_get_next_step ... ok
[INFO] [stdout] test engine::tests::test_pause_and_resume_execution ... ok
[INFO] [stdout] test engine::tests::test_remove_execution ... ok
[INFO] [stdout] test engine::tests::test_start_execution ... ok
[INFO] [stdout] test engine::tests::test_wait_for_dependencies ... ok
[INFO] [stdout] test engine::tests::test_create_execution ... ok
[INFO] [stdout] test error_handler::tests::test_get_all_errors ... ok
[INFO] [stdout] test error_handler::tests::test_get_error_action ... ok
[INFO] [stdout] test error_handler::tests::test_get_retry_config ... ok
[INFO] [stdout] test error_handler::tests::test_retry_state_creation ... ok
[INFO] [stdout] test error_handler::tests::test_retry_state_error_history ... ok
[INFO] [stdout] test error_handler::tests::test_capture_error ... ok
[INFO] [stdout] test error_handler::tests::test_retry_state_exponential_backoff ... ok
[INFO] [stdout] test error_handler::tests::test_should_not_retry_on_fail ... ok
[INFO] [stdout] test error_handler::tests::test_should_rollback_on_error ... ok
[INFO] [stdout] test error_handler::tests::test_should_skip_on_error ... ok
[INFO] [stdout] test error_handler::tests::test_should_retry ... ok
[INFO] [stdout] test activity_logging_properties::tests::prop_activity_log_filtering_by_step_is_correct ... ok
[INFO] [stdout] test activity_logging_properties::tests::prop_activity_logger_records_all_activities ... ok
[INFO] [stdout] test activity_logging_properties::tests::prop_approval_activities_are_logged ... ok
[INFO] [stdout] test condition_properties::tests::prop_condition_branching_correctness ... ok
[INFO] [stdout] test error_handling_properties::tests::prop_retry_exponential_backoff ... ok
[INFO] [stdout] test executor::tests::test_execute_condition_step_then_branch ... ok
[INFO] [stdout] test executor::tests::test_execute_next_step ... ok
[INFO] [stdout] test executor::tests::test_execute_condition_step_else_branch ... ok
[INFO] [stdout] test executor::tests::test_execute_step ... ok
[INFO] [stdout] test error_handling_properties::tests::prop_error_action_execution ... ok
[INFO] [stdout] test activity_logging_properties::tests::prop_activity_log_maintains_order ... ok
[INFO] [stdout] test executor::tests::test_get_condition_next_steps ... ok
[INFO] [stdout] test executor::tests::test_fail_step ... ok
[INFO] [stdout] test executor::tests::test_get_step_result ... ok
[INFO] [stdout] test executor::tests::test_is_step_approved ... ok
[INFO] [stdout] test activity_logging_properties::tests::prop_activity_log_respects_max_entries ... ok
[INFO] [stdout] test executor::tests::test_is_step_rejected ... ok
[INFO] [stdout] test executor::tests::test_requires_approval ... ok
[INFO] [stdout] test parallel_executor::tests::test_execute_parallel_step ... ok
[INFO] [stdout] test parallel_executor::tests::test_execute_parallel_step_with_limit_zero_concurrency ... ok
[INFO] [stdout] test executor::tests::test_get_step_context ... ok
[INFO] [stdout] test executor::tests::test_skip_step ... ok
[INFO] [stdout] test parallel_executor::tests::test_execute_parallel_step_with_limit ... ok
[INFO] [stdout] test executor::tests::test_request_step_approval ... ok
[INFO] [stdout] test parallel_executor::tests::test_get_parallel_steps ... ok
[INFO] [stdout] test parallel_executor::tests::test_validate_parallel_step_empty_steps ... ok
[INFO] [stdout] test parallel_executor::tests::test_validate_parallel_step_valid ... ok
[INFO] [stdout] test parallel_executor::tests::test_get_max_concurrency ... ok
[INFO] [stdout] test parallel_executor::tests::test_validate_parallel_step_zero_concurrency ... ok
[INFO] [stdout] test parameter_substitution::tests::test_build_final_parameters_with_defaults ... ok
[INFO] [stdout] test parameter_substitution::tests::test_substitute_in_step_config ... ok
[INFO] [stdout] test parameter_substitution::tests::test_substitute_multiple_references_in_string ... ok
[INFO] [stdout] test parameter_substitution::tests::test_substitute_nested_parameters ... ok
[INFO] [stdout] test parameter_substitution::tests::test_substitute_with_default_parameter ... ok
[INFO] [stdout] test parameter_substitution::tests::test_substitute_unknown_parameter ... ok
[INFO] [stdout] test parameter_substitution::tests::test_substitute_missing_required_parameter ... ok
[INFO] [stdout] test activity_logging_properties::tests::prop_error_activities_contain_context ... ok
[INFO] [stdout] test parameters::tests::test_build_final_values_with_defaults ... ok
[INFO] [stdout] test parameters::tests::test_substitute_boolean_parameter ... ok
[INFO] [stdout] test parameters::tests::test_substitute_in_array ... ok
[INFO] [stdout] test parameters::tests::test_substitute_in_object ... ok
[INFO] [stdout] test parameters::tests::test_substitute_invalid_parameter_name ... ok
[INFO] [stdout] test parameters::tests::test_substitute_missing_parameter ... ok
[INFO] [stdout] test parameters::tests::test_substitute_multiple_references ... ok
[INFO] [stdout] test parameters::tests::test_substitute_nested_references ... ok
[INFO] [stdout] test parameters::tests::test_substitute_number_parameter ... ok
[INFO] [stdout] test parameters::tests::test_substitute_simple_string ... ok
[INFO] [stdout] test activity_logging_properties::tests::prop_workflow_lifecycle_activities_are_logged ... ok
[INFO] [stdout] test parameters::tests::test_validate_parameter_definitions_duplicate_names ... ok
[INFO] [stdout] test parameters::tests::test_validate_parameter_definitions_valid ... ok
[INFO] [stdout] test error_handling_properties::tests::prop_workflow_recovery ... ok
[INFO] [stdout] test parameters::tests::test_validate_parameter_values_missing_required ... ok
[INFO] [stdout] test parameters::tests::test_validate_parameter_values_type_mismatch ... ok
[INFO] [stdout] test parameters::tests::test_validate_parameter_values_unknown_parameter ... ok
[INFO] [stdout] test parameters::tests::test_validate_parameter_definitions_type_mismatch ... ok
[INFO] [stdout] test parser::tests::test_detect_circular_dependency ... ok
[INFO] [stdout] test parser::tests::test_parse_invalid_yaml_missing_id ... ok
[INFO] [stdout] test parser::tests::test_parse_valid_yaml ... ok
[INFO] [stdout] test parser::tests::test_validate_empty_step_id ... ok
[INFO] [stdout] test parser::tests::test_validate_empty_step_name ... ok
[INFO] [stdout] test parser::tests::test_validate_missing_dependency ... ok
[INFO] [stdout] test condition_properties::tests::prop_condition_returns_all_branch_steps ... ok
[INFO] [stdout] test parameter_substitution_properties::tests::prop_parameter_substitution_handles_multiple_references ... ok
[INFO] [stdout] test parameter_substitution_properties::tests::prop_parameter_substitution_requires_all_parameters ... ok
[INFO] [stdout] test approval_properties::tests::prop_approval_decisions_are_immutable ... ok
[INFO] [stdout] test parser::property_tests::prop_workflow_validation_rejects_invalid ... ok
[INFO] [stdout] test parameter_substitution_properties::tests::prop_parameter_substitution_uses_defaults ... ok
[INFO] [stdout] test progress::tests::test_calculate_progress ... ok
[INFO] [stdout] test progress::tests::test_calculate_progress_zero_steps ... ok
[INFO] [stdout] test progress::tests::test_create_progress_tracker ... ok
[INFO] [stdout] test progress::tests::test_estimate_completion_time ... ok
[INFO] [stdout] test progress::tests::test_estimate_completion_time_no_durations ... ok
[INFO] [stdout] test progress::tests::test_generate_status_report ... ok
[INFO] [stdout] test progress::tests::test_get_average_step_duration ... ok
[INFO] [stdout] test progress::tests::test_get_average_step_duration_empty ... ok
[INFO] [stdout] test progress::tests::test_get_max_step_duration ... ok
[INFO] [stdout] test progress::tests::test_get_min_step_duration ... ok
[INFO] [stdout] test progress::tests::test_record_step_duration ... ok
[INFO] [stdout] test resolver::tests::test_can_execute_step ... ok
[INFO] [stdout] test resolver::tests::test_detect_circular_dependency ... ok
[INFO] [stdout] test resolver::tests::test_get_all_dependencies ... ok
[INFO] [stdout] test resolver::tests::test_get_dependent_steps ... ok
[INFO] [stdout] test resolver::tests::test_get_ready_steps ... ok
[INFO] [stdout] test resolver::tests::test_resolve_execution_order ... ok
[INFO] [stdout] test resolver::tests::test_validate_dependencies ... ok
[INFO] [stdout] test resolver::tests::test_validate_missing_dependency ... ok
[INFO] [stdout] test risk_scoring::tests::test_approval_threshold ... ok
[INFO] [stdout] test risk_scoring::tests::test_risk_assessment ... ok
[INFO] [stdout] test risk_scoring::tests::test_risk_score_calculation_high_risk ... ok
[INFO] [stdout] test risk_scoring::tests::test_risk_score_calculation_low_risk ... ok
[INFO] [stdout] test risk_scoring::tests::test_risk_score_in_range ... ok
[INFO] [stdout] test risk_scoring_properties::prop_high_risk_requires_approval ... ok
[INFO] [stdout] test parameter_substitution_properties::tests::prop_parameter_substitution_rejects_unknown_parameters ... ok
[INFO] [stdout] test pause_resume_properties::tests::prop_pause_is_idempotent ... ok
[INFO] [stdout] test risk_scoring_properties::prop_risk_factors_affect_score ... ok
[INFO] [stdout] test risk_scoring_properties::prop_safety_constraints_enforced ... ok
[INFO] [stdout] test rollback::tests::test_add_rollback_step ... ok
[INFO] [stdout] test rollback::tests::test_add_rollback_step_to_plan ... ok
[INFO] [stdout] test rollback::tests::test_create_rollback_plan ... ok
[INFO] [stdout] test rollback::tests::test_get_rollback_order ... ok
[INFO] [stdout] test pause_resume_properties::tests::prop_pause_resume_maintains_consistency ... ok
[INFO] [stdout] test parameter_substitution_properties::tests::prop_parameter_substitution_replaces_all_references ... ok
[INFO] [stdout] test rollback::tests::test_has_rollback_steps ... ok
[INFO] [stdout] test rollback::tests::test_record_execution ... ok
[INFO] [stdout] test rollback::tests::test_record_step_execution ... ok
[INFO] [stdout] test rollback::tests::test_restore_state ... ok
[INFO] [stdout] test rollback::tests::test_get_rollback_steps ... ok
[INFO] [stdout] test rollback::tests::test_rollback_plan_creation ... ok
[INFO] [stdout] test safety_constraints::tests::test_enforce_timeout_exceeds_limit ... ok
[INFO] [stdout] test safety_constraints::tests::test_enforce_timeout_within_limit ... ok
[INFO] [stdout] test risk_scoring_properties::prop_risk_score_in_valid_range ... ok
[INFO] [stdout] test safety_constraints::tests::test_rollback_capability ... ok
[INFO] [stdout] test safety_constraints::tests::test_safety_constraints_default ... ok
[INFO] [stdout] test safety_constraints::tests::test_timeout_violation_detection ... ok
[INFO] [stdout] test state::tests::test_complete_step ... ok
[INFO] [stdout] test state::tests::test_create_state ... ok
[INFO] [stdout] test state::tests::test_get_next_step_to_execute ... ok
[INFO] [stdout] test state::tests::test_get_progress ... ok
[INFO] [stdout] test state::tests::test_is_step_completed ... ok
[INFO] [stdout] test state::tests::test_pause_non_running_workflow_fails ... ok
[INFO] [stdout] test state::tests::test_pause_resume_workflow ... ok
[INFO] [stdout] test state::tests::test_resume_non_paused_workflow_fails ... ok
[INFO] [stdout] test state::tests::test_start_workflow ... ok
[INFO] [stdout] test safety_constraints_properties::prop_timeout_enforcement ... ok
[INFO] [stdout] test state::tests::test_validate_state_success ... ok
[INFO] [stdout] test state::tests::test_validate_state_missing_result ... ok
[INFO] [stdout] test safety_constraints_properties::prop_safety_violations_detected ... ok
[INFO] [stdout] test safety_constraints_properties::prop_rollback_capability_maintained ... ok
[INFO] [stdout] test pause_resume_properties::tests::prop_cannot_resume_non_paused ... ok
[INFO] [stdout] test status_reporter::tests::test_create_status_reporter ... ok
[INFO] [stdout] test status_reporter::tests::test_format_status ... ok
[INFO] [stdout] test status_reporter::tests::test_get_last_status ... ok
[INFO] [stdout] test status_reporter::tests::test_get_status ... ok
[INFO] [stdout] test status_reporter::tests::test_record_step_duration ... ok
[INFO] [stdout] test status_reporter::tests::test_status_update_listener ... ok
[INFO] [stdout] test status_reporter::tests::test_status_update_listener_clear ... ok
[INFO] [stdout] test risk_scoring_properties::prop_risk_assessment_report_complete ... ok
[INFO] [stdout] test approval_properties::tests::prop_approval_gate_blocks_execution_when_rejected ... ok
[INFO] [stdout] test status_reporting_properties::tests::prop_average_step_duration_is_correct ... ok
[INFO] [stdout] test approval_properties::tests::prop_approval_gate_blocks_execution_until_approved ... ok
[INFO] [stdout] test pause_resume_properties::tests::prop_pause_resume_preserves_completed_steps ... ok
[INFO] [stdout] test pause_resume_properties::tests::prop_pause_resume_next_step_correct ... ok
[INFO] [stdout] test status_reporting_properties::tests::prop_min_max_step_durations_are_correct ... ok
[INFO] [stdout] test status_reporting_properties::tests::prop_last_status_is_updated ... ok
[INFO] [stdout] test status_reporting_properties::tests::prop_progress_percentage_is_accurate ... ok
[INFO] [stdout] test storage_integration::tests::test_delete_state ... ok
[INFO] [stdout] test storage_integration::tests::test_get_workflow_state_path ... ok
[INFO] [stdout] test storage_integration::tests::test_get_workflow_storage_dir ... ok
[INFO] [stdout] test approval_properties::tests::prop_multiple_approval_requests_independent ... ok
[INFO] [stdout] test storage_integration::tests::test_load_state_validated ... ok
[INFO] [stdout] test pause_resume_properties::tests::prop_pause_resume_no_reexecution ... ok
[INFO] [stdout] test approval_properties::tests::prop_approval_comments_preserved ... ok
[INFO] [stdout] test status_reporting_properties::tests::prop_status_report_is_consistent ... ok
[INFO] [stdout] test status_reporting_properties::tests::prop_step_durations_enable_estimation ... ok
[INFO] [stdout] test storage_integration::tests::test_load_nonexistent_state ... ok
[INFO] [stdout] test status_reporting_properties::tests::prop_completed_steps_count_matches_state ... ok
[INFO] [stdout] test storage_integration::tests::test_load_state_validated_with_invalid_state ... ok
[INFO] [stdout] test storage_integration::tests::test_persist_and_load_state ... ok
[INFO] [stdout] test storage_integration::tests::test_validate_state_empty_workflow_id ... ok
[INFO] [stdout] test storage_integration::tests::test_validate_state_missing_result ... ok
[INFO] [stdout] test storage_integration::tests::test_validate_state_success ... ok
[INFO] [stdout] test storage_integration::tests::test_state_exists ... ok
[INFO] [stdout] test status_reporting_properties::tests::prop_status_report_includes_required_fields ... ok
[INFO] [stdout] test status_reporting_properties::tests::prop_formatted_status_contains_required_info ... ok
[INFO] [stdout] test state_persistence_properties::tests::prop_state_persistence_json_round_trip ... ok
[INFO] [stdout] test state_persistence_properties::tests::prop_state_persistence_validation ... ok
[INFO] [stdout] test parser::property_tests::prop_workflow_parsing_round_trip ... ok
[INFO] [stdout] test error_handling_properties::tests::prop_error_history_tracking ... ok
[INFO] [stdout] test state_persistence_properties::tests::prop_state_persistence_round_trip ... ok
[INFO] [stdout] test state_persistence_properties::tests::prop_state_persistence_creates_directory ... ok
[INFO] [stdout] test error_handling_properties::tests::prop_error_capture ... ok
[INFO] [stderr]      Running tests/integration_tests.rs (/opt/rustwide/target/debug/deps/integration_tests-90379b1e4e2ce2f0)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 240 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 2.09s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 31 tests
[INFO] [stdout] test test_approval_gate_blocks_execution ... ok
[INFO] [stdout] test test_agent_step_execution ... ok
[INFO] [stdout] test test_approval_step_execution ... ok
[INFO] [stdout] test test_approval_gate_enforcement ... ok
[INFO] [stdout] test test_pause_resume_preserves_completed_steps ... ok
[INFO] [stdout] test test_pause_resume_prevents_step_reexecution ... ok
[INFO] [stdout] test test_condition_step_execution_then_branch ... ok
[INFO] [stdout] test test_pause_workflow_execution ... ok
[INFO] [stdout] test test_high_risk_step_blocks_execution_without_approval ... ok
[INFO] [stdout] test test_high_risk_approval_requirement ... ok
[INFO] [stdout] test test_mixed_step_types_execution ... ok
[INFO] [stdout] test test_multi_step_workflow_dependency_resolution ... ok
[INFO] [stdout] test test_multi_step_workflow_execution_order ... ok
[INFO] [stdout] test test_parallel_step_execution ... ok
[INFO] [stdout] test test_risk_assessment_report_generation ... ok
[INFO] [stdout] test test_risk_factors_consideration ... ok
[INFO] [stdout] test test_risk_score_calculation ... ok
[INFO] [stdout] test test_risk_score_range_validation ... ok
[INFO] [stdout] test test_safety_constraints_on_high_risk_steps ... ok
[INFO] [stdout] test test_workflow_cancellation ... ok
[INFO] [stdout] test test_workflow_error_recovery ... ok
[INFO] [stdout] test test_workflow_execution_removal ... ok
[INFO] [stdout] test test_workflow_execution_with_multiple_active_workflows ... ok
[INFO] [stdout] test test_workflow_error_handling_with_retry ... ok
[INFO] [stdout] test test_workflow_state_persistence_across_pause_resume ... ok
[INFO] [stdout] test test_workflow_with_multiple_risk_levels ... ok
[INFO] [stdout] test test_workflow_failure_handling ... ok
[INFO] [stdout] test test_command_step_execution ... ok
[INFO] [stdout] test test_resume_workflow_execution ... ok
[INFO] [stdout] test test_workflow_state_tracking ... ok
[INFO] [stdout] test test_workflow_execution_lifecycle ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 31 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests ricecoder_workflows
[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" "db57a80b8c5bd335ef8a45d9e212f5848e5b488d80167e9a0e9c5bdbf5d24baf", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "db57a80b8c5bd335ef8a45d9e212f5848e5b488d80167e9a0e9c5bdbf5d24baf", kill_on_drop: false }`
[INFO] [stdout] db57a80b8c5bd335ef8a45d9e212f5848e5b488d80167e9a0e9c5bdbf5d24baf