[INFO] fetching crate utf8proj-solver 0.9.1...
[INFO] testing utf8proj-solver-0.9.1 against try#dec9417b8611e34e787a3e4c37686b5131f9e5c5 for pr-154210-2
[INFO] extracting crate utf8proj-solver 0.9.1 into /workspace/builds/worker-2-tc2/source
[INFO] started tweaking crates.io crate utf8proj-solver 0.9.1
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate utf8proj-solver 0.9.1
[INFO] tweaked toml for crates.io crate utf8proj-solver 0.9.1 written to /workspace/builds/worker-2-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate utf8proj-solver 0.9.1 on toolchain dec9417b8611e34e787a3e4c37686b5131f9e5c5
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate utf8proj-solver 0.9.1 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" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 7bcbf169c1f2031144b4abdab47a2c16274bd892906b96ac2475a838b01c51e0
[INFO] running `Command { std: "docker" "start" "-a" "7bcbf169c1f2031144b4abdab47a2c16274bd892906b96ac2475a838b01c51e0", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "7bcbf169c1f2031144b4abdab47a2c16274bd892906b96ac2475a838b01c51e0", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "7bcbf169c1f2031144b4abdab47a2c16274bd892906b96ac2475a838b01c51e0", kill_on_drop: false }`
[INFO] [stdout] 7bcbf169c1f2031144b4abdab47a2c16274bd892906b96ac2475a838b01c51e0
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] bbcc3870b05b87a0eef1aafe757e5e7d926cc99546708da26317954b58f8e94b
[INFO] running `Command { std: "docker" "start" "-a" "bbcc3870b05b87a0eef1aafe757e5e7d926cc99546708da26317954b58f8e94b", kill_on_drop: false }`
[INFO] [stderr]    Compiling syn v2.0.114
[INFO] [stderr]    Compiling zerocopy v0.8.33
[INFO] [stderr]    Compiling rust_decimal v1.39.0
[INFO] [stderr]    Compiling num-integer v0.1.46
[INFO] [stderr]    Compiling getrandom v0.2.17
[INFO] [stderr]    Compiling num-bigint v0.4.6
[INFO] [stderr]    Compiling rand_core v0.6.4
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling thiserror-impl v1.0.69
[INFO] [stderr]    Compiling ppv-lite86 v0.2.21
[INFO] [stderr]    Compiling rand_chacha v0.3.1
[INFO] [stderr]    Compiling thiserror v1.0.69
[INFO] [stderr]    Compiling rand v0.8.5
[INFO] [stderr]    Compiling biodivine-lib-bdd v0.5.27
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling chrono v0.4.42
[INFO] [stderr]    Compiling utf8proj-core v0.9.1
[INFO] [stderr]    Compiling utf8proj-solver v0.9.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 12.79s
[INFO] running `Command { std: "docker" "inspect" "bbcc3870b05b87a0eef1aafe757e5e7d926cc99546708da26317954b58f8e94b", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "bbcc3870b05b87a0eef1aafe757e5e7d926cc99546708da26317954b58f8e94b", kill_on_drop: false }`
[INFO] [stdout] bbcc3870b05b87a0eef1aafe757e5e7d926cc99546708da26317954b58f8e94b
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 44392babc28548d2eb2b2d312553bb69b477b4cf222aea5126f828bf65c5a904
[INFO] running `Command { std: "docker" "start" "-a" "44392babc28548d2eb2b2d312553bb69b477b4cf222aea5126f828bf65c5a904", kill_on_drop: false }`
[INFO] [stderr]    Compiling ucd-trie v0.1.7
[INFO] [stderr]    Compiling pest v2.8.5
[INFO] [stderr]    Compiling pest_meta v2.8.5
[INFO] [stderr]    Compiling pest_generator v2.8.5
[INFO] [stderr]    Compiling pest_derive v2.8.5
[INFO] [stderr]    Compiling utf8proj-parser v0.9.1
[INFO] [stderr]    Compiling utf8proj-solver v0.9.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 9.04s
[INFO] running `Command { std: "docker" "inspect" "44392babc28548d2eb2b2d312553bb69b477b4cf222aea5126f828bf65c5a904", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "44392babc28548d2eb2b2d312553bb69b477b4cf222aea5126f828bf65c5a904", kill_on_drop: false }`
[INFO] [stdout] 44392babc28548d2eb2b2d312553bb69b477b4cf222aea5126f828bf65c5a904
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 6c724e7e4c96fbb678dea903e5c7dc283b3e5e1f987c0a86b270efc7b8c07972
[INFO] running `Command { std: "docker" "start" "-a" "6c724e7e4c96fbb678dea903e5c7dc283b3e5e1f987c0a86b270efc7b8c07972", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.07s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/utf8proj_solver-23e7aa7d8bb1b7c7)
[INFO] [stdout] 
[INFO] [stdout] running 159 tests
[INFO] [stdout] test bdd::tests::analyzer_default ... ok
[INFO] [stdout] test bdd::tests::analyzer_with_custom_max_days ... ok
[INFO] [stdout] test bdd::tests::empty_project_no_conflicts ... ok
[INFO] [stdout] test bdd::tests::find_optimal_resolution_no_conflict ... ok
[INFO] [stdout] test bdd::tests::bdd_stats_recorded ... ok
[INFO] [stdout] test bdd::tests::detect_resource_conflict ... ok
[INFO] [stdout] test bdd::tests::find_optimal_resolution ... ok
[INFO] [stdout] test bdd::tests::no_conflict_when_sequential ... ok
[INFO] [stdout] test cpm::tests::test_cpm_error_display ... ok
[INFO] [stdout] test bdd::tests::suggest_resolution ... ok
[INFO] [stdout] test cpm::tests::test_cpm_scheduler_default ... ok
[INFO] [stdout] test cpm::tests::test_critical_path_has_zero_slack ... ok
[INFO] [stdout] test cpm::tests::test_empty_graph_error ... ok
[INFO] [stdout] test cpm::tests::test_dependency_type_coverage ... ok
[INFO] [stdout] test cpm::tests::test_dependency_with_lag ... ok
[INFO] [stdout] test cpm::tests::test_parallel_paths_with_slack ... ok
[INFO] [stdout] test cpm::tests::test_free_slack_calculation ... ok
[INFO] [stdout] test cpm::tests::test_single_task ... ok
[INFO] [stdout] test dag::tests::test_collect_leaves_flat ... ok
[INFO] [stdout] test dag::tests::test_collect_leaves_nested ... ok
[INFO] [stdout] test dag::tests::test_container_dependency_resolution ... ok
[INFO] [stdout] test dag::tests::test_cycle_detection ... ok
[INFO] [stdout] test dag::tests::test_deeply_nested_container ... ok
[INFO] [stdout] test cpm::tests::test_sequential_chain ... ok
[INFO] [stdout] test dag::tests::test_effort_with_no_resources ... ok
[INFO] [stdout] test dag::tests::test_effort_with_multiple_resources ... ok
[INFO] [stdout] test dag::tests::test_graph_error_display ... ok
[INFO] [stdout] test dag::tests::test_get_task_not_found ... ok
[INFO] [stdout] test dag::tests::test_milestone_task ... ok
[INFO] [stdout] test cpm::tests::test_slack_never_negative ... ok
[INFO] [stdout] test dag::tests::test_no_duration_no_effort_task ... ok
[INFO] [stdout] test dag::tests::test_qualified_id_mapping ... ok
[INFO] [stdout] test dag::tests::test_container_to_container_dependency ... ok
[INFO] [stdout] test dag::tests::test_relative_dependency_resolution ... ok
[INFO] [stdout] test dag::tests::test_relative_sibling_container_resolution ... ok
[INFO] [stdout] test dag::tests::test_topological_sort_simple ... ok
[INFO] [stdout] test dag::tests::test_relative_sibling_leaf_resolution ... ok
[INFO] [stdout] test leveling::tests::add_working_days_zero_or_negative ... ok
[INFO] [stdout] test leveling::tests::count_schedule_working_days_basic ... ok
[INFO] [stdout] test leveling::tests::calculate_utilization_basic ... ok
[INFO] [stdout] test leveling::tests::calculate_utilization_idle_resource ... ok
[INFO] [stdout] test leveling::tests::calculate_utilization_multiple_resources ... ok
[INFO] [stdout] test leveling::tests::calculate_utilization_no_resources ... ok
[INFO] [stdout] test leveling::tests::count_schedule_working_days_end_before_start ... ok
[INFO] [stdout] test leveling::tests::count_schedule_working_days_same_date ... ok
[INFO] [stdout] test leveling::tests::count_working_days_end_before_start ... ok
[INFO] [stdout] test leveling::tests::find_available_slot_basic ... ok
[INFO] [stdout] test leveling::tests::detect_overallocations_finds_conflicts ... ok
[INFO] [stdout] test dag::tests::test_duration_calculation_effort ... ok
[INFO] [stdout] test leveling::tests::level_resources_extends_project_when_needed ... ok
[INFO] [stdout] test dag::tests::test_empty_wbs ... ok
[INFO] [stdout] test dag::tests::test_duration_calculation_explicit ... ok
[INFO] [stdout] test leveling::tests::overallocated_periods_non_consecutive_creates_multiple ... ok
[INFO] [stdout] test leveling::tests::level_resources_resolves_simple_conflict ... ok
[INFO] [stdout] test leveling::tests::shift_candidate_ordering_critical_vs_non_critical ... ok
[INFO] [stdout] test leveling::tests::overallocated_periods_multiple_consecutive_days ... ok
[INFO] [stdout] test leveling::tests::recalculate_critical_path_test ... ok
[INFO] [stdout] test leveling::tests::nested_task_priority_mapping ... ok
[INFO] [stdout] test leveling::tests::level_resources_respects_dependencies ... ok
[INFO] [stdout] test leveling::tests::unresolved_conflict_no_shiftable_tasks ... ok
[INFO] [stdout] test leveling::tests::timeline_detects_overallocation ... ok
[INFO] [stdout] test leveling::tests::timeline_removes_usage ... ok
[INFO] [stdout] test tests::abstract_assignment_without_rate_shows_unknown_cost ... ok
[INFO] [stdout] test tests::abstract_profile_with_no_rate_in_cost_calculation ... ok
[INFO] [stdout] test tests::aggregate_cost_ranges_multiple ... ok
[INFO] [stdout] test leveling::tests::timeline_tracks_usage ... ok
[INFO] [stdout] test tests::analysis_config_builder ... ok
[INFO] [stdout] test tests::aggregate_cost_ranges_single ... ok
[INFO] [stdout] test tests::analyze_detects_inverted_rate_range ... ok
[INFO] [stdout] test tests::analyze_detects_abstract_assignment ... ok
[INFO] [stdout] test tests::analyze_detects_circular_specialization ... ok
[INFO] [stdout] test tests::analyze_detects_mixed_abstraction ... ok
[INFO] [stdout] test tests::analyze_detects_profile_without_rate ... ok
[INFO] [stdout] test tests::analyze_detects_trait_multiplier_stack_exceeds_threshold ... ok
[INFO] [stdout] test tests::analyze_detects_unknown_profile_reference ... ok
[INFO] [stdout] test tests::analyze_detects_unknown_trait ... ok
[INFO] [stdout] test tests::analyze_detects_unused_profile ... ok
[INFO] [stdout] test tests::analyze_detects_unused_trait ... ok
[INFO] [stdout] test tests::analyze_equal_rate_range_no_error ... ok
[INFO] [stdout] test tests::analyze_detects_wide_cost_range ... ok
[INFO] [stdout] test tests::analyze_emits_project_summary ... ok
[INFO] [stdout] test tests::analyze_no_warning_for_acceptable_trait_multiplier_stack ... ok
[INFO] [stdout] test tests::analyze_valid_profile_specialization_no_error ... ok
[INFO] [stdout] test tests::analyze_valid_rate_range_no_error ... ok
[INFO] [stdout] test tests::calculate_cost_abstract ... ok
[INFO] [stdout] test tests::analyze_no_wide_cost_range_under_threshold ... ok
[INFO] [stdout] test tests::calculate_cost_concrete ... ok
[INFO] [stdout] test tests::calculate_cost_with_partial_allocation ... ok
[INFO] [stdout] test tests::classify_duration_only_project ... ok
[INFO] [stdout] test tests::classify_effort_based_project ... ok
[INFO] [stdout] test tests::classify_empty_project_is_duration_based ... ok
[INFO] [stdout] test tests::classify_resource_loaded_project ... ok
[INFO] [stdout] test tests::detect_circular_dependency ... ok
[INFO] [stdout] test tests::e002_with_many_tasks_truncates_list ... ok
[INFO] [stdout] test tests::deeply_nested_tasks ... ok
[INFO] [stdout] test tests::effort_with_full_allocation ... ok
[INFO] [stdout] test tests::effort_chain_with_different_allocations ... ok
[INFO] [stdout] test tests::effort_with_mixed_allocations ... ok
[INFO] [stdout] test tests::effort_with_multiple_resources ... ok
[INFO] [stdout] test tests::effort_with_partial_allocation ... ok
[INFO] [stdout] test tests::explain_constraint_finish_no_later_than_capped ... ok
[INFO] [stdout] test tests::explain_constraint_finish_no_earlier_than_pushed ... ok
[INFO] [stdout] test tests::explain_constraint_multiple_constraints ... ok
[INFO] [stdout] test tests::effort_with_no_resource_assumes_100_percent ... ok
[INFO] [stdout] test tests::explain_nonexistent_task ... ok
[INFO] [stdout] test tests::explain_constraint_redundant_snet ... ok
[INFO] [stdout] test tests::explain_task ... ok
[INFO] [stdout] test tests::explain_includes_related_diagnostics_field ... ok
[INFO] [stdout] test tests::explain_constraint_start_no_later_than_capped ... ok
[INFO] [stdout] test tests::explain_constraint_must_finish_on_pinned ... ok
[INFO] [stdout] test tests::explain_constraint_format_all_types ... ok
[INFO] [stdout] test tests::explain_task_with_calendar_impact ... ok
[INFO] [stdout] test tests::explain_task_with_holiday_impact ... ok
[INFO] [stdout] test tests::explain_task_with_dependencies_shows_constraints ... ok
[INFO] [stdout] test tests::explain_task_with_no_dependencies ... ok
[INFO] [stdout] test tests::extract_task_from_infeasible_message_works ... ok
[INFO] [stdout] test tests::explain_task_with_redundant_constraint ... ok
[INFO] [stdout] test tests::fixed_duration_ignores_allocation ... ok
[INFO] [stdout] test tests::feasibility_check_constraint_conflict ... ok
[INFO] [stdout] test tests::feasibility_check ... ok
[INFO] [stdout] test tests::explain_task_with_temporal_constraint_shows_effects ... ok
[INFO] [stdout] test tests::explain_task_without_constraints_has_empty_effects ... ok
[INFO] [stdout] test tests::feasibility_check_valid_constraints ... ok
[INFO] [stdout] test tests::isolated_task_no_predecessors_or_successors ... ok
[INFO] [stdout] test tests::explain_task_with_pinned_constraint ... ok
[INFO] [stdout] test tests::isolated_task_no_dependencies ... ok
[INFO] [stdout] test tests::feasibility_check_circular_dependency ... ok
[INFO] [stdout] test tests::nested_child_task_with_profile_assignment ... ok
[INFO] [stdout] test tests::milestone_has_zero_duration ... ok
[INFO] [stdout] test tests::parallel_tasks_no_dependencies ... ok
[INFO] [stdout] test tests::profile_with_fixed_rate_converts_to_range ... ok
[INFO] [stdout] test tests::resolve_assignment_concrete_resource ... ok
[INFO] [stdout] test tests::nested_tasks_are_flattened ... ok
[INFO] [stdout] test tests::resolve_assignment_abstract_profile ... ok
[INFO] [stdout] test tests::relative_resolution_empty_container ... ok
[INFO] [stdout] test tests::resolve_profile_rate_basic ... ok
[INFO] [stdout] test tests::resolve_profile_rate_inherited ... ok
[INFO] [stdout] test tests::resolve_profile_rate_with_trait_multiplier ... ok
[INFO] [stdout] test tests::resolve_assignment_unknown_id ... ok
[INFO] [stdout] test tests::schedule_empty_project ... ok
[INFO] [stdout] test tests::schedule_aggregates_total_cost_range ... ok
[INFO] [stdout] test tests::schedule_linear_chain ... ok
[INFO] [stdout] test tests::schedule_parallel_tasks ... ok
[INFO] [stdout] test tests::schedule_single_task ... ok
[INFO] [stdout] test tests::schedule_with_concrete_assignment ... ok
[INFO] [stdout] test tests::solver_creation ... ok
[INFO] [stdout] test tests::task_with_no_successors_uses_project_end ... ok
[INFO] [stdout] test tests::schedule_with_relative_sibling_dependency ... ok
[INFO] [stdout] test tests::schedule_with_dependency_on_container ... ok
[INFO] [stdout] test tests::solver_default ... ok
[INFO] [stdout] test tests::schedule_with_profile_assignment ... ok
[INFO] [stdout] test tests::specializes_inherits_rate_from_parent ... ok
[INFO] [stdout] test tests::trait_multiplier_multiplicative ... ok
[INFO] [stdout] test tests::trait_multiplier_single ... ok
[INFO] [stdout] test tests::trait_multiplier_unknown_trait_ignored ... ok
[INFO] [stdout] test tests::summary_unknown_cost_when_no_rate_data ... ok
[INFO] [stdout] test tests::working_day_cache_large_project ... ok
[INFO] [stdout] test tests::working_day_cache_beyond_limit ... ok
[INFO] [stdout] test tests::w002_with_trait_multiplier_contributor ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 159 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/calendar_diagnostics.rs (/opt/rustwide/target/debug/deps/calendar_diagnostics-55d4432c0c8a4d8e)
[INFO] [stdout] 
[INFO] [stdout] running 13 tests
[INFO] [stdout] test c001_zero_working_hours ... ok
[INFO] [stdout] test c010_non_working_day ... ok
[INFO] [stdout] test c020_low_availability ... ok
[INFO] [stdout] test c023_redundant_holiday ... ok
[INFO] [stdout] test c002_no_working_days ... ok
[INFO] [stdout] test c022_suspicious_seven_day_week ... ok
[INFO] [stdout] test c022_suspicious_hours_high ... ok
[INFO] [stdout] test filter_diagnostics_c010_links_to_task ... ok
[INFO] [stdout] test filter_diagnostics_excludes_calendar_level ... ok
[INFO] [stdout] test filter_diagnostics_empty_for_unrelated_task ... ok
[INFO] [stdout] test filter_diagnostics_c011_links_to_task ... ok
[INFO] [stdout] test c011_calendar_mismatch ... ok
[INFO] [stdout] test valid_calendar_no_diagnostics ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 13 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/constraint_wiring.rs (/opt/rustwide/target/debug/deps/constraint_wiring-8b8e34f675365222)
[INFO] [stdout] 
[INFO] [stdout] running 13 tests
[INFO] [stdout] test finish_no_earlier_than_pushes_ef ... ok
[INFO] [stdout] test finish_no_later_than_caps_lf ... ok
[INFO] [stdout] test infeasible_constraint_dependency_conflict ... ok
[INFO] [stdout] test feasible_window_fits ... ok
[INFO] [stdout] test infeasible_floor_ceiling_collapse ... ok
[INFO] [stdout] test must_finish_on_has_zero_slack ... ok
[INFO] [stdout] test must_finish_on_sets_dates ... ok
[INFO] [stdout] test infeasible_finish_before_start ... ok
[INFO] [stdout] test must_start_on_has_zero_slack ... ok
[INFO] [stdout] test start_no_earlier_than_pushes_es ... ok
[INFO] [stdout] test must_start_on_already_works ... ok
[INFO] [stdout] test start_no_later_than_caps_ls ... ok
[INFO] [stdout] test start_no_earlier_than_respects_dependencies ... ok
[INFO] [stdout] 
[INFO] [stderr]      Running tests/container_dependency_diagnostics.rs (/opt/rustwide/target/debug/deps/container_dependency_diagnostics-c52d418a8ccbde4e)
[INFO] [stdout] test result: ok. 13 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 13 tests
[INFO] [stderr]      Running tests/cpm_correctness.rs (/opt/rustwide/target/debug/deps/cpm_correctness-a5e1dde20c9fc243)
[INFO] [stdout] test fix_handles_multiple_container_deps ... ok
[INFO] [stdout] test fix_is_idempotent ... ok
[INFO] [stdout] test fix_handles_nested_containers ... ok
[INFO] [stdout] test fix_preserves_existing_child_deps ... ok
[INFO] [stdout] test fix_adds_missing_container_dep ... ok
[INFO] [stdout] test fix_w014_count_goes_to_zero ... ok
[INFO] [stdout] test w014_does_not_trigger_for_empty_container_deps ... ok
[INFO] [stdout] test w014_does_not_trigger_for_leaf_tasks ... ok
[INFO] [stdout] test w014_does_not_trigger_when_child_has_dep ... ok
[INFO] [stdout] test w014_message_includes_task_names ... ok
[INFO] [stdout] test w014_nested_containers ... ok
[INFO] [stdout] test w014_triggers_for_each_missing_child ... ok
[INFO] [stdout] test w014_triggers_when_child_missing_container_dep ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 13 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 13 tests
[INFO] [stdout] test critical_path_has_zero_slack ... ok
[INFO] [stdout] test crm_migration_simplified ... ok
[INFO] [stdout] test ff_forward_pass_accounts_for_successor_duration ... ok
[INFO] [stdout] test cross_container_dependencies_flat ... ok
[INFO] [stdout] test mixed_dependency_types_correct_critical_path ... ok
[INFO] [stdout] test ff_with_lag_forward_pass ... ok
[INFO] [stdout] test hierarchical_tasks_derive_dates ... ok
[INFO] [stdout] test early_start_respects_predecessors ... ok
[INFO] [stdout] test slack_is_never_negative_parallel ... ok
[INFO] [stdout] test slack_is_never_negative_complex ... ok
[INFO] [stdout] test ss_backward_pass_accounts_for_predecessor_duration ... ok
[INFO] [stdout] test slack_is_never_negative_simple ... ok
[INFO] [stdout] test ss_with_lag_forward_pass ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 13 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/earned_value.rs (/opt/rustwide/target/debug/deps/earned_value-5d5dc36086cbbcc7)
[INFO] [stdout] 
[INFO] [stdout] running 10 tests
[INFO] [stdout] test behind_schedule_spi_below_one ... ok
[INFO] [stdout] test completed_project_full_ev ... ok
[INFO] [stdout] test ahead_of_schedule_spi_above_one ... ok
[INFO] [stdout] test empty_project_default_ev ... ok
[INFO] [stdout] test container_tasks_excluded ... ok
[INFO] [stdout] test multiple_tasks_weighted_ev ... ok
[INFO] [stdout] test i005_diagnostic_emitted ... ok
[INFO] [stdout] test on_schedule_spi_one ... ok
[INFO] [stdout] test project_before_start_pv_zero ... ok
[INFO] [stdout] test spi_capped_at_two ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 10 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/hierarchical_scheduling.rs (/opt/rustwide/target/debug/deps/hierarchical_scheduling-f8f2d511d1904a6e)
[INFO] [stdout] 
[INFO] [stdout] running 27 tests
[INFO] [stdout] test circular_dependency_detection ... ok
[INFO] [stdout] test circular_dependency_across_containers ... ok
[INFO] [stdout] test circular_dependency_in_nested_tasks ... ok
[INFO] [stdout] test container_dependency_waits_for_all_children ... ok
[INFO] [stdout] test container_start_derived_from_earliest_child ... ok
[INFO] [stdout] test container_finish_derived_from_latest_child ... ok
[INFO] [stdout] test critical_path_with_ff_dependencies ... ok
[INFO] [stdout] test critical_path_with_parallel_ss_chains ... ok
[INFO] [stdout] test critical_path_with_ss_dependencies ... ok
[INFO] [stdout] test empty_container_treated_as_milestone ... ok
[INFO] [stdout] test schedule_flat_tasks_with_constraints ... ok
[INFO] [stdout] test ff_dependency_aligns_finishes ... ok
[INFO] [stdout] test schedule_ttg_hierarchy ... ok
[INFO] [stdout] test empty_container_with_dependencies ... ok
[INFO] [stdout] test nested_empty_containers ... ok
[INFO] [stdout] test negative_lag_allows_overlap ... ok
[INFO] [stdout] test ss_dependency_aligns_starts ... ok
[INFO] [stdout] test three_task_cycle_detection ... ok
[INFO] [stdout] test ss_negative_lag_acts_as_lead ... ok
[INFO] [stdout] test sf_dependency_finish_after_start ... ok
[INFO] [stdout] test complex_dependency_graph_performance ... ok
[INFO] [stdout] test schedule_matches_tj3_output ... ok
[INFO] [stdout] test ss_dependency_with_positive_lag ... ok
[INFO] [stdout] test task_depends_on_empty_container ... ok
[INFO] [stdout] test schedule_flat_tasks_with_fs_dependencies ... ok
[INFO] [stdout] test deep_nesting_performance ... ok
[INFO] [stdout] test very_wide_hierarchy_performance ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 27 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/progress_aware_cpm.rs (/opt/rustwide/target/debug/deps/progress_aware_cpm-f5e0e4fe724938c9)
[INFO] [stdout] 
[INFO] [stdout] running 12 tests
[INFO] [stdout] test test_02b_explicit_remaining_duration_precedence ... ok
[INFO] [stdout] test test_02_partial_progress_schedules_from_status_date ... ok
[INFO] [stdout] test test_01_completed_task_locks_to_actual_dates ... ok
[INFO] [stdout] test test_03_future_tasks_schedule_from_predecessors ... ok
[INFO] [stdout] test test_06_status_date_resolution ... ok
[INFO] [stdout] test test_06b_cli_as_of_override ... ok
[INFO] [stdout] test test_05_container_weighted_rollup ... ok
[INFO] [stdout] test test_04_dependency_chain_mixed_progress ... ok
[INFO] [stdout] test test_07_remaining_vs_complete_conflict_p005 ... ok
[INFO] [stdout] test test_07b_complete_100_forces_remaining_zero ... ok
[INFO] [stdout] test test_08b_container_explicit_within_threshold_no_p006 ... ok
[INFO] [stdout] test test_08_container_explicit_complete_p006 ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 12 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/progress_tracking.rs (/opt/rustwide/target/debug/deps/progress_tracking-fecfca8b162981b2)
[INFO] [stdout] 
[INFO] [stdout] running 7 tests
[INFO] [stdout] test actual_start_used_for_forecast ... ok
[INFO] [stdout] test explicit_status_overrides_derived ... ok
[INFO] [stdout] test container_progress_derived_from_children ... ok
[INFO] [stdout] test nested_container_progress ... ok
[INFO] [stdout] test schedule_includes_progress_tracking ... ok
[INFO] [stdout] test completed_task_has_zero_remaining ... ok
[INFO] [stdout] test container_complete_when_all_children_complete ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/resource_leveling.rs (/opt/rustwide/target/debug/deps/resource_leveling-8edf1130090786ff)
[INFO] [stdout] 
[INFO] [stdout] running 25 tests
[INFO] [stdout] test leveling_empty_project ... ok
[INFO] [stdout] test detect_overallocations_api ... ok
[INFO] [stdout] test level_resources_api ... ok
[INFO] [stdout] test leveling_emits_l003_on_duration_increase ... ok
[INFO] [stdout] test leveling_extends_project_duration ... ok
[INFO] [stdout] test leveling_emits_l001_on_resolution ... ok
[INFO] [stdout] test leveling_metrics_calculated ... ok
[INFO] [stdout] test leveling_milestone_tracking ... ok
[INFO] [stdout] test leveling_is_deterministic ... ok
[INFO] [stdout] test leveling_multiple_resources_independent ... ok
[INFO] [stdout] test leveling_no_resources_assigned ... ok
[INFO] [stdout] test leveling_multiple_resources_with_conflict ... ok
[INFO] [stdout] test leveling_noop_when_no_conflict ... ok
[INFO] [stdout] test leveling_reason_display_dependency_chain ... ok
[INFO] [stdout] test leveling_reason_display_resource_overallocated ... ok
[INFO] [stdout] test leveling_preserves_original_schedule ... ok
[INFO] [stdout] test leveling_preserves_critical_path_priority ... ok
[INFO] [stdout] test leveling_respects_priority ... ok
[INFO] [stdout] test leveling_reason_resource_overallocated ... ok
[INFO] [stdout] test leveling_single_task ... ok
[INFO] [stdout] test leveling_with_partial_allocation ... ok
[INFO] [stdout] test leveling_result_schedule_alias ... ok
[INFO] [stdout] test leveling_respects_dependencies ... ok
[INFO] [stdout] test solver_with_leveling_enabled ... ok
[INFO] [stdout] test leveling_respects_max_delay_factor ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 25 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/variance_reporting.rs (/opt/rustwide/target/debug/deps/variance_reporting-9152c9a70f100e22)
[INFO] [stdout] 
[INFO] [stdout] running 12 tests
[INFO] [stdout] test completed_task_uses_actual_finish ... ok
[INFO] [stdout] test early_start_negative_variance ... ok
[INFO] [stdout] test actual_start_shows_start_variance ... ok
[INFO] [stdout] test partial_progress_shows_finish_variance ... ok
[INFO] [stdout] test project_baseline_forecast_finish ... ok
[INFO] [stdout] test project_progress_empty_project ... ok
[INFO] [stdout] test no_progress_zero_variance ... ok
[INFO] [stdout] test project_progress_weighted_by_duration ... ok
[INFO] [stdout] test project_progress_single_task ... ok
[INFO] [stdout] test project_variance_ahead_of_schedule ... ok
[INFO] [stdout] test project_variance_behind_schedule ... ok
[INFO] [stdout] test resource_parallelism_correct_variance ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 12 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests utf8proj_solver
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test src/lib.rs - filter_task_diagnostics (line 1106) ... ok
[INFO] [stdout] test src/lib.rs - (line 13) ... ok
[INFO] [stdout] test src/lib.rs - fix_container_dependencies (line 2149) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.30s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "6c724e7e4c96fbb678dea903e5c7dc283b3e5e1f987c0a86b270efc7b8c07972", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "6c724e7e4c96fbb678dea903e5c7dc283b3e5e1f987c0a86b270efc7b8c07972", kill_on_drop: false }`
[INFO] [stdout] 6c724e7e4c96fbb678dea903e5c7dc283b3e5e1f987c0a86b270efc7b8c07972