[INFO] cloning repository https://github.com/yyjeqhc/packfix-rs
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/yyjeqhc/packfix-rs" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fyyjeqhc%2Fpackfix-rs", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fyyjeqhc%2Fpackfix-rs'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 15efb746eabd00b20344772702485898af1cfd63
[INFO] testing yyjeqhc/packfix-rs against 1.98.0-beta.1 for beta-1.98-1
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fyyjeqhc%2Fpackfix-rs" "/workspace/builds/worker-6-tc2/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-6-tc2/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/yyjeqhc/packfix-rs
[INFO] finished tweaking git repo https://github.com/yyjeqhc/packfix-rs
[INFO] tweaked toml for git repo https://github.com/yyjeqhc/packfix-rs written to /workspace/builds/worker-6-tc2/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/yyjeqhc/packfix-rs on toolchain 1.98.0-beta.1
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.98.0-beta.1" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/yyjeqhc/packfix-rs already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.98.0-beta.1" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-m" "1610612736" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:3d5ced03c013a94a2f102a4510f48a6e9184255caf5fd8244f58017bde7f5210" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] c1cd075ff6677ebf6f444a3bfd194333d1257f211c99c1a928137bd99e38d076
[INFO] running `Command { std: "docker" "start" "c1cd075ff6677ebf6f444a3bfd194333d1257f211c99c1a928137bd99e38d076", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "c1cd075ff6677ebf6f444a3bfd194333d1257f211c99c1a928137bd99e38d076", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-w" "/opt/rustwide/workdir" "--user" "0:0" "c1cd075ff6677ebf6f444a3bfd194333d1257f211c99c1a928137bd99e38d076" "/opt/rustwide/cargo-home/bin/cargo" "+1.98.0-beta.1" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "c1cd075ff6677ebf6f444a3bfd194333d1257f211c99c1a928137bd99e38d076", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-w" "/opt/rustwide/workdir" "--user" "0:0" "c1cd075ff6677ebf6f444a3bfd194333d1257f211c99c1a928137bd99e38d076" "/opt/rustwide/cargo-home/bin/cargo" "+1.98.0-beta.1" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.186
[INFO] [stderr]    Compiling openssl-sys v0.9.114
[INFO] [stderr]    Compiling ring v0.17.14
[INFO] [stderr]    Compiling rustls-pki-types v1.14.1
[INFO] [stderr]    Compiling openssl v0.10.78
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling tracing v0.1.44
[INFO] [stderr]    Compiling openssl-macros v0.1.1
[INFO] [stderr]    Compiling rustls v0.23.38
[INFO] [stderr]    Compiling rustix v1.1.4
[INFO] [stderr]    Compiling serde_json v1.0.149
[INFO] [stderr]    Compiling simd-adler32 v0.3.9
[INFO] [stderr]    Compiling idna_adapter v1.2.2
[INFO] [stderr]    Compiling serde_derive_internals v0.29.1
[INFO] [stderr]    Compiling ref-cast-impl v1.0.25
[INFO] [stderr]    Compiling bzip2-sys v0.1.13+1.0.8
[INFO] [stderr]    Compiling idna v1.1.0
[INFO] [stderr]    Compiling lzma-sys v0.1.20
[INFO] [stderr]    Compiling nix v0.29.0
[INFO] [stderr]    Compiling miniz_oxide v0.8.9
[INFO] [stderr]    Compiling async-stream-impl v0.3.6
[INFO] [stderr]    Compiling webpki-roots v1.0.7
[INFO] [stderr]    Compiling url v2.5.8
[INFO] [stderr]    Compiling native-tls v0.2.18
[INFO] [stderr]    Compiling serde_spanned v0.6.9
[INFO] [stderr]    Compiling toml_datetime v0.6.11
[INFO] [stderr]    Compiling ref-cast v1.0.25
[INFO] [stderr]    Compiling serde_urlencoded v0.7.1
[INFO] [stderr]    Compiling bumpalo v3.20.2
[INFO] [stderr]    Compiling zip v2.4.2
[INFO] [stderr]    Compiling clap_builder v4.6.0
[INFO] [stderr]    Compiling clap_derive v4.6.1
[INFO] [stderr]    Compiling toml_edit v0.22.27
[INFO] [stderr]    Compiling flate2 v1.1.9
[INFO] [stderr]    Compiling zopfli v0.8.3
[INFO] [stderr]    Compiling tracing-subscriber v0.3.23
[INFO] [stderr]    Compiling async-recursion v1.1.1
[INFO] [stderr]    Compiling async-stream v0.3.6
[INFO] [stderr]    Compiling similar v2.7.0
[INFO] [stderr]    Compiling schemars_derive v1.2.1
[INFO] [stderr]    Compiling xattr v1.6.1
[INFO] [stderr]    Compiling parking_lot_core v0.9.12
[INFO] [stderr]    Compiling errno v0.3.14
[INFO] [stderr]    Compiling mio v1.2.0
[INFO] [stderr]    Compiling socket2 v0.6.3
[INFO] [stderr]    Compiling getrandom v0.2.17
[INFO] [stderr]    Compiling filetime v0.2.27
[INFO] [stderr]    Compiling getrandom v0.4.2
[INFO] [stderr]    Compiling schemars v1.2.1
[INFO] [stderr]    Compiling signal-hook-registry v1.4.8
[INFO] [stderr]    Compiling xz2 v0.1.7
[INFO] [stderr]    Compiling bzip2 v0.4.4
[INFO] [stderr]    Compiling parking_lot v0.12.5
[INFO] [stderr]    Compiling tar v0.4.45
[INFO] [stderr]    Compiling tempfile v3.27.0
[INFO] [stderr]    Compiling toml v0.8.23
[INFO] [stderr]    Compiling tokio v1.52.1
[INFO] [stderr]    Compiling clap v4.6.1
[INFO] [stderr]    Compiling rustls-webpki v0.103.13
[INFO] [stderr]    Compiling hyper v1.9.0
[INFO] [stderr]    Compiling tower v0.5.3
[INFO] [stderr]    Compiling tokio-native-tls v0.3.1
[INFO] [stderr]    Compiling tower-http v0.6.8
[INFO] [stderr]    Compiling tokio-rustls v0.26.4
[INFO] [stderr]    Compiling hyper-util v0.1.20
[INFO] [stderr]    Compiling hyper-tls v0.6.0
[INFO] [stderr]    Compiling hyper-rustls v0.27.7
[INFO] [stderr]    Compiling reqwest v0.12.28
[INFO] [stderr]    Compiling ollama-rs v0.3.4
[INFO] [stderr]    Compiling packfix-rs v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 50.75s
[INFO] running `Command { std: "docker" "inspect" "c1cd075ff6677ebf6f444a3bfd194333d1257f211c99c1a928137bd99e38d076", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-w" "/opt/rustwide/workdir" "--user" "0:0" "c1cd075ff6677ebf6f444a3bfd194333d1257f211c99c1a928137bd99e38d076" "/opt/rustwide/cargo-home/bin/cargo" "+1.98.0-beta.1" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling packfix-rs v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 8.29s
[INFO] running `Command { std: "docker" "inspect" "c1cd075ff6677ebf6f444a3bfd194333d1257f211c99c1a928137bd99e38d076", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-w" "/opt/rustwide/workdir" "--user" "0:0" "c1cd075ff6677ebf6f444a3bfd194333d1257f211c99c1a928137bd99e38d076" "/opt/rustwide/cargo-home/bin/cargo" "+1.98.0-beta.1" "test" "--frozen", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.23s
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/packfix_rs-5a0d9e865945f89e)
[INFO] [stdout] 
[INFO] [stdout] running 253 tests
[INFO] [stdout] test config::tests::description_num_predict_default ... ok
[INFO] [stdout] test config::tests::description_max_context_chars_default ... ok
[INFO] [stdout] test config::tests::description_system_prompt_default ... ok
[INFO] [stdout] test config::tests::description_prompt_default_when_section_missing ... ok
[INFO] [stdout] test config::tests::description_prompt_from_config ... ok
[INFO] [stdout] test config::tests::description_temperature_default ... ok
[INFO] [stdout] test config::tests::description_user_prompt_default ... ok
[INFO] [stdout] test config::tests::description_timeout_secs_from_config ... ok
[INFO] [stdout] test config::tests::description_timeout_secs_default ... ok
[INFO] [stdout] test config::tests::load_config_returns_none_when_no_files_exist ... ok
[INFO] [stdout] test config::tests::load_config_errors_on_invalid_toml ... ok
[INFO] [stdout] test config::tests::parse_empty_config ... ok
[INFO] [stdout] test config::tests::load_from_file_errors_on_invalid_toml ... ok
[INFO] [stdout] test config::tests::resolve_obs_api_url_cli_wins ... ok
[INFO] [stdout] test config::tests::parse_partial_config ... ok
[INFO] [stdout] test config::tests::resolve_repo_url_default ... ok
[INFO] [stdout] test config::tests::resolve_obs_api_url_config_fallback ... ok
[INFO] [stdout] test config::tests::parse_full_config ... ok
[INFO] [stdout] test config::tests::load_from_file_parses_valid_toml ... ok
[INFO] [stdout] test config::tests::load_from_file_errors_on_read_failure ... ok
[INFO] [stdout] test core::engine::tests::failed_outcome_preserves_report ... ok
[INFO] [stdout] test core::engine::tests::build_pypi_missing_package_can_generate ... ok
[INFO] [stdout] test core::engine::tests::branch_checkout_happens_before_prepare_spec_writes_repo_files ... ok
[INFO] [stdout] test cli::tests::llm_description_defaults_to_true ... ok
[INFO] [stdout] test core::engine::tests::local_workdir_package_name_falls_back_to_spec_filename_when_name_uses_macro ... ok
[INFO] [stdout] test core::engine::tests::prepare_spec_mode_for_pypi_missing_package_preserves_version ... ok
[INFO] [stdout] test core::engine::tests::remote_build_log_path_includes_attempt_and_package ... ok
[INFO] [stdout] test core::engine::tests::needs_deps_outcome_preserves_report ... ok
[INFO] [stdout] test core::engine::tests::build_existing_missing_package_errors ... ok
[INFO] [stdout] test core::engine::tests::await_prefetched_checkout_returns_prefetched_result ... ok
[INFO] [stdout] test core::graph::tests::cycle_detection_works ... ok
[INFO] [stdout] test core::engine::tests::local_workdir_source_runs_without_takopack_or_git ... ok
[INFO] [stdout] test cli::tests::no_llm_description_flag_disables ... ok
[INFO] [stdout] test core::engine::tests::checkout_prefetch_uses_package_workspace_as_root ... ok
[INFO] [stdout] test core::graph::tests::feature_merge_returns_needs_rebuild ... ok
[INFO] [stdout] test core::graph::tests::parse_dependency_targets_merges_same_base_package ... ok
[INFO] [stdout] test core::graph::tests::python_dependency_info_parses_extras ... ok
[INFO] [stdout] test core::engine::tests::local_workdir_unresolvable_returns_needs_dependencies ... ok
[INFO] [stdout] test core::graph::tests::unresolved_deps_filters_success_nodes ... ok
[INFO] [stdout] test core::graph::tests::add_edge_existing_success_with_new_feature_marks_pending ... ok
[INFO] [stdout] test core::graph::tests::add_edge_new_node_preserves_features ... ok
[INFO] [stdout] test core::graph::tests::python_dependency_info_supports_python3_13dist_format ... ok
[INFO] [stdout] test cli::tests::both_flags_can_coexist ... ok
[INFO] [stdout] test core::graph::tests::feature_merge_no_change_when_already_has_feature ... ok
[INFO] [stdout] test core::resources::tests::create_dir_all_failure_does_not_leak_slot ... ok
[INFO] [stdout] test core::resources::tests::sanitize_empty_string_returns_unknown ... ok
[INFO] [stdout] test core::resources::tests::sanitize_preserves_alphanumeric_dot_dash_underscore ... ok
[INFO] [stdout] test core::resources::tests::sanitize_all_special_chars_becomes_underscores ... ok
[INFO] [stdout] test core::resources::tests::root_naming_includes_packfix_n_repo_arch ... ok
[INFO] [stdout] test core::resources::tests::pool_default_config_is_none ... ok
[INFO] [stdout] test core::resources::tests::sanitize_replaces_semicolon_with_underscore ... ok
[INFO] [stdout] test core::scheduler::tests::dependency_node_uses_rpm_key_but_pypi_source_name ... ok
[INFO] [stdout] test core::scheduler::tests::local_workdir_nodes_enter_local_building_state_when_started ... ok
[INFO] [stdout] test core::scheduler::tests::scheduler_engine_error_marks_node_failed ... ok
[INFO] [stdout] test core::scheduler::tests::success_can_immediately_resume_waiting_parent ... ok
[INFO] [stdout] test describe::tests::build_user_prompt_substitutes_context ... ok
[INFO] [stdout] test core::resources::tests::sanitize_replaces_slash_with_underscore ... ok
[INFO] [stdout] test core::resources::tests::slot_reuse_after_drop ... ok
[INFO] [stdout] test core::scheduler::tests::scheduler_can_resume_local_workdir_after_dependency_success ... ok
[INFO] [stdout] test core::scheduler::tests::waiting_parent_failure_reason_includes_failed_dependency_name ... ok
[INFO] [stdout] test describe::tests::format_spec_description_strips_markdown ... ok
[INFO] [stdout] test describe::tests::build_json_context_caps_at_max ... ok
[INFO] [stdout] test describe::tests::normalize_ollama_host_strips_trailing_slash ... ok
[INFO] [stdout] test describe::tests::format_spec_description_wraps_long_lines ... ok
[INFO] [stdout] test describe::tests::normalize_ollama_host_strips_api_paths ... ok
[INFO] [stdout] test describe::tests::format_spec_description_truncates_to_5_lines ... ok
[INFO] [stdout] test describe::tests::normalize_ollama_host_preserves_base_url ... ok
[INFO] [stdout] test extract::tests::archive_stem_strips_extensions ... ok
[INFO] [stdout] test extract::tests::is_safe_entry_path_rejects_traversal ... ok
[INFO] [stdout] test extract::tests::is_safe_entry_path_rejects_windows_absolute ... ok
[INFO] [stdout] test extract::tests::compression_default_is_runtime_checkable ... ok
[INFO] [stdout] test describe::tests::readme_excerpt_skips_badges ... ok
[INFO] [stdout] test describe::tests::collects_core_modules ... ok
[INFO] [stdout] test describe::tests::parses_setup_cfg ... ok
[INFO] [stdout] test describe::tests::parses_pkg_info_metadata ... ok
[INFO] [stdout] test describe::tests::readme_excerpt_capped_at_2500 ... ok
[INFO] [stdout] test extract::tests::ignores_patch_and_sig_files ... ok
[INFO] [stdout] test describe::tests::parses_pyproject_toml ... ok
[INFO] [stdout] test describe::tests::skips_test_modules ... ok
[INFO] [stdout] test extract::tests::no_archives_returns_skipped_no_error ... ok
[INFO] [stdout] test extract::tests::finds_tar_gz_archive ... ok
[INFO] [stdout] test extract::tests::state_written_to_state_root_not_source_dir ... ok
[INFO] [stdout] test extract::tests::picks_tar_gz_over_zip_regardless_of_size ... ok
[INFO] [stdout] test core::engine::tests::remote_status_unresolvable_returns_dependencies_without_log_download ... ok
[INFO] [stdout] test core::engine::tests::remote_status_without_dependencies_keeps_empty_dependency_list ... ok
[INFO] [stdout] test fix::analyzer::tests::empty_import_check_becomes_empty_check_issue ... ok
[INFO] [stdout] test fix::analyzer::tests::c_extension_compile_error_is_need_human ... ok
[INFO] [stdout] test fix::analyzer::tests::c_extension_module_not_found_if_has_fatal_error_is_c_extension ... ok
[INFO] [stdout] test fix::analyzer::tests::install_module_mismatch_ignores_traceback_source_line ... ok
[INFO] [stdout] test fix::analyzer::tests::install_module_mismatch ... ok
[INFO] [stdout] test fix::analyzer::tests::failed_build_dependencies_to_add_buildrequires ... ok
[INFO] [stdout] test fix::analyzer::tests::import_check_failures_are_grouped_by_parent_module ... ok
[INFO] [stdout] test fix::analyzer::tests::import_check_failed_imports_directly_excluded ... ok
[INFO] [stdout] test fix::analyzer::tests::import_check_multidb_whitelist_excluded ... ok
[INFO] [stdout] test fix::analyzer::tests::arch_dependent_to_remove_noarch ... ok
[INFO] [stdout] test fix::analyzer::tests::fonttools_optional_imports_excluded ... ok
[INFO] [stdout] test fix::analyzer::tests::import_check_tests_module_excluded ... ok
[INFO] [stdout] test fix::analyzer::tests::normal_module_not_found_is_missing_python_module ... ok
[INFO] [stdout] test fix::analyzer::tests::pytest_failure_is_need_human ... ok
[INFO] [stdout] test fix::analyzer::tests::pep639_to_buildoption_install_l ... ok
[INFO] [stdout] test fix::analyzer::tests::patch_apply_error_is_need_human ... ok
[INFO] [stdout] test fix::analyzer::tests::normal_module_not_found_is_need_human ... ok
[INFO] [stdout] test fix::analyzer::tests::optional_integration_import_failure_to_buildoption_check ... ok
[INFO] [stdout] test fix::analyzer::tests::optional_integration_dedup_missing_modules ... ok
[INFO] [stdout] test fix::analyzer::tests::osc_not_a_working_copy_is_unknown_with_evidence ... ok
[INFO] [stdout] test obs::api::tests::commit_comment_is_url_encoded ... ok
[INFO] [stdout] test fix::tests::contextualize_issue_passes_through_non_mismatch ... ok
[INFO] [stdout] test fix::tests::contextualize_issue_preserves_mismatch_when_no_source ... ok
[INFO] [stdout] test obs::api::tests::commit_comment_url_encodes_special_chars ... ok
[INFO] [stdout] test obs::api::tests::package_meta_xml_escapes_special_chars ... ok
[INFO] [stdout] test fix::tests::contextualize_issue_refines_module_from_egg_info ... ok
[INFO] [stdout] test fix::analyzer::tests::unresolvable_dep_strips_punctuation_or_matches_exact_dist ... ok
[INFO] [stdout] test fix::analyzer::tests::unresolvable_supports_pkgconfig ... ok
[INFO] [stdout] test obs::api::tests::package_meta_xml_plain_names ... ok
[INFO] [stdout] test obs::api::tests::xml_escape_handles_special_characters ... ok
[INFO] [stdout] test obs::local::tests::checkout_root_semantics_documented_by_test ... ok
[INFO] [stdout] test obs::local::tests::osc_api_status_args ... ok
[INFO] [stdout] test obs::local::tests::osc_build_args_contain_spec ... ok
[INFO] [stdout] test obs::local::tests::osc_build_args_with_root ... ok
[INFO] [stdout] test obs::api::tests::read_osc_credentials_from_sample ... ok
[INFO] [stdout] test obs::local::tests::osc_build_args_without_root_does_not_contain_root_flag ... ok
[INFO] [stdout] test fix::analyzer::tests::pytest_module_not_found_is_test_failure ... ok
[INFO] [stdout] test obs::api::tests::read_osc_credentials_missing_file ... ok
[INFO] [stdout] test obs::local::tests::osc_build_release_is_zero ... ok
[INFO] [stdout] test obs::local::tests::osc_build_flags_include_no_hooks ... ok
[INFO] [stdout] test obs::local::tests::osc_checkout_args_do_not_confuse_cwd_and_target ... ok
[INFO] [stdout] test obs::local::tests::osc_build_args_with_root_and_project ... ok
[INFO] [stdout] test obs::local::tests::osc_checkout_args_with_package ... ok
[INFO] [stdout] test obs::local::tests::osc_checkout_args_project_only ... ok
[INFO] [stdout] test obs::local::tests::osc_update_args ... ok
[INFO] [stdout] test obs::local::tests::osc_checkout_log_path_includes_package ... ok
[INFO] [stdout] test obs::local::tests::root_flag_absent_when_not_in_pool ... ok
[INFO] [stdout] test obs::local::tests::root_flag_present_when_pool_configured ... ok
[INFO] [stdout] test pipeline::tests::build_existing_normalizes_plain_name_to_rpm_name ... ok
[INFO] [stdout] test obs::local::tests::root_flag_comes_before_positional_args ... ok
[INFO] [stdout] test pipeline::tests::build_existing_scheduler_requests_normalize_multiple_packages ... ok
[INFO] [stdout] test pipeline::tests::classify_status_broken ... ok
[INFO] [stdout] test pipeline::tests::classify_status_broken_takes_priority_over_succeeded ... ok
[INFO] [stdout] test pipeline::tests::classify_status_building_is_pending ... ok
[INFO] [stdout] test pipeline::tests::classify_status_disabled_is_failed ... ok
[INFO] [stdout] test pipeline::tests::classify_status_failed ... ok
[INFO] [stdout] test pipeline::tests::build_request_uses_rpm_key_but_pypi_source_name ... ok
[INFO] [stdout] test pipeline::tests::classify_status_excluded_is_failed ... ok
[INFO] [stdout] test pipeline::tests::classify_status_empty_is_pending ... ok
[INFO] [stdout] test fix::analyzer::tests::unresolvable_supports_python3_13dist ... ok
[INFO] [stdout] test fix::analyzer::tests::unresolvable_supports_python313dist ... ok
[INFO] [stdout] test pipeline::tests::classify_status_failed_takes_priority_over_succeeded ... ok
[INFO] [stdout] test pipeline::tests::classify_status_running_is_pending ... ok
[INFO] [stdout] test pipeline::tests::classify_status_scheduled_is_pending ... ok
[INFO] [stdout] test pipeline::tests::compare_python_versions_detects_newer_release ... ok
[INFO] [stdout] test pipeline::tests::classify_status_succeeded ... ok
[INFO] [stdout] test fix::analyzer::tests::unpackaged_files_to_append_files ... ok
[INFO] [stdout] test pipeline::tests::classify_status_succeeded_with_extra_text ... ok
[INFO] [stdout] test pipeline::tests::classify_status_unknown_output_is_pending ... ok
[INFO] [stdout] test pipeline::tests::default_multi_build_revision_has_batch_prefix ... ok
[INFO] [stdout] test pipeline::tests::ensure_ebf_success_returns_err_on_failure ... ok
[INFO] [stdout] test pipeline::tests::classify_status_unresolvable ... ok
[INFO] [stdout] test pipeline::tests::ensure_pythonhosted_source_accepts_source_without_index_suffix ... ok
[INFO] [stdout] test fix::analyzer::tests::unresolvable_to_need_human ... ok
[INFO] [stdout] test fix::analyzer::tests::unresolvable_with_prefix ... ok
[INFO] [stdout] test pipeline::tests::infer_update_pypi_name_prefers_pypi_name_macro ... ok
[INFO] [stdout] test pipeline::tests::multi_root_reports_skip_other_roots_in_dependency_notes ... ok
[INFO] [stdout] test pipeline::tests::node_results_to_report_finds_pypi_root_result ... ok
[INFO] [stdout] test pipeline::tests::node_results_missing_root_produces_error ... ok
[INFO] [stdout] test pipeline::tests::fix_command_build_request_uses_local_workdir_source ... ok
[INFO] [stdout] test pipeline::tests::poll_delay_schedule_is_0_5_10_15_20 ... ok
[INFO] [stdout] test pipeline::tests::infer_local_workdir_package_name_strips_service_prefix ... ok
[INFO] [stdout] test pipeline::tests::node_results_to_reports_maps_each_root_to_its_own_report ... ok
[INFO] [stdout] test pipeline::tests::source_checkout_ready_keeps_current_semantics ... ok
[INFO] [stdout] test pipeline::tests::update_branch_name_uses_package_prefix ... ok
[INFO] [stdout] test pipeline::tests::fix_scheduler_requests_for_multiple_workdirs ... ok
[INFO] [stdout] test pipeline::tests::update_commit_message_reflects_version_change ... ok
[INFO] [stdout] test pipeline::tests::copy_spec_back_targets_repo_package_dir ... ok
[INFO] [stdout] test pipeline::tests::stage_generated_creates_target_when_absent ... ok
[INFO] [stdout] test pipeline::tests::stage_generated_errors_when_target_is_nonempty ... ok
[INFO] [stdout] test pipeline::tests::stage_generated_succeeds_when_target_is_empty ... ok
[INFO] [stdout] test pipeline::tests::stage_generated_error_preserves_existing_files ... ok
[INFO] [stdout] test spec::tests::add_empty_check_section_inserts_before_files ... ok
[INFO] [stdout] test spec::tests::add_empty_check_section_keeps_existing_check ... ok
[INFO] [stdout] test spec::tests::declared_package_name_ignores_macro_expansion_names ... ok
[INFO] [stdout] test spec::tests::append_files_entries_ignores_relative_paths ... ok
[INFO] [stdout] test spec::tests::add_buildoption_check_adds_one_line_per_exclusion ... ok
[INFO] [stdout] test spec::tests::declared_package_name_skips_lines_without_colons ... ok
[INFO] [stdout] test spec::tests::ensure_autochangelog_macro_replaces_conditional_form ... ok
[INFO] [stdout] test spec::tests::ensure_buildarch_noarch_inserts_missing_tag ... ok
[INFO] [stdout] test spec::tests::declared_package_name_skips_comment_and_blank_lines_before_name ... ok
[INFO] [stdout] test spec::tests::ensure_remote_asset_replaces_or_inserts_line ... ok
[INFO] [stdout] test spec::tests::ensure_versioned_python_provides_preserves_literal_position ... ok
[INFO] [stdout] test spec::tests::ensure_versioned_python_provides_preserves_original_position ... ok
[INFO] [stdout] test spec::tests::ensure_versioned_python_provides_replaces_literal_pair ... ok
[INFO] [stdout] test spec::tests::ensure_versioned_python_provides_replaces_literal_package_name ... ok
[INFO] [stdout] test spec::tests::ensure_versioned_python_provides_replaces_unversioned_pair ... ok
[INFO] [stdout] test spec::tests::ensure_versioned_python_provides_skips_already_versioned_literal ... ok
[INFO] [stdout] test spec::tests::add_buildoption_check_skips_when_existing_pattern_is_broader ... ok
[INFO] [stdout] test spec::tests::add_buildoption_check_merge_multiple_existing_exclusions ... ok
[INFO] [stdout] test spec::tests::fix_buildarch_remove_noarch_adds_arch_provides ... ok
[INFO] [stdout] test spec::tests::add_buildoption_check_idempotent ... ok
[INFO] [stdout] test spec::tests::spec_file_basename_no_extension ... ok
[INFO] [stdout] test spec::tests::spec_file_basename_plain_name ... ok
[INFO] [stdout] test spec::tests::spec_file_basename_strips_download_files_prefix ... ok
[INFO] [stdout] test spec::tests::fix_buildoption_install_no_line_no_panic ... ok
[INFO] [stdout] test spec::tests::spec_file_basename_strips_service_prefix ... ok
[INFO] [stdout] test spec::tests::update_description_no_section_returns_unchanged ... ok
[INFO] [stdout] test spec::tests::add_buildoption_check_merge ... ok
[INFO] [stdout] test spec::tests::update_version_replaces_existing_value ... ok
[INFO] [stdout] test spec::tests::fix_buildoption_install_replace_l_arg_preserve_other_args ... ok
[INFO] [stdout] test upstream::tests::falls_back_to_listing_when_no_top_level_exists ... ok
[INFO] [stdout] test spec::tests::fix_buildoption_install_l_idempotent ... ok
[INFO] [stdout] test spec::tests::fix_buildarch_remove_noarch_removes_buildarch ... ok
[INFO] [stdout] test upstream::tests::handles_case_mismatch_in_file_listing ... ok
[INFO] [stdout] test upstream::tests::handles_case_mismatch_in_top_level_txt ... ok
[INFO] [stdout] test upstream::tests::infers_module_when_wrong_module_completely_different ... ok
[INFO] [stdout] test spec::tests::fix_buildarch_remove_noarch_idempotent ... ok
[INFO] [stdout] test upstream::tests::prefers_filesystem_top_level_over_tarball ... ok
[INFO] [stdout] test upstream::tests::takopack_output_does_not_pick_existing_unrelated_spec ... ok
[INFO] [stdout] test upstream::tests::prefers_top_level_module_from_tarball ... ok
[INFO] [stdout] test spec::tests::add_buildrequires_with_version_constraint_idempotent ... ok
[INFO] [stdout] test utils::command::tests::async_run_command_denies_unlisted_program ... ok
[INFO] [stdout] test upstream::tests::prefers_primary_module_from_multiline_top_level ... ok
[INFO] [stdout] test spec::tests::add_buildrequires_idempotent ... ok
[INFO] [stdout] test spec::tests::add_extras_subpackages_preserves_description_body_and_surrounding_blank_lines ... ok
[INFO] [stdout] test spec::tests::update_description_empty_clears_body_but_keeps_tag ... ok
[INFO] [stdout] test utils::command::tests::async_run_command_log_contains_all_fields ... ok
[INFO] [stdout] test spec::tests::update_description_replaces_content ... ok
[INFO] [stdout] test utils::command::tests::async_run_command_collects_stdout_and_stderr ... ok
[INFO] [stdout] test spec::tests::append_files_entries_idempotent ... ok
[INFO] [stdout] test utils::command::tests::async_run_command_preserves_nonzero_exit_code ... ok
[INFO] [stdout] test utils::llm::tests::clean_json_extracts_first_valid_json ... ok
[INFO] [stdout] test utils::llm::tests::clean_json_handles_trailing_thoughts ... ok
[INFO] [stdout] test spec::tests::append_files_entries_maps_common_paths ... ok
[INFO] [stdout] test utils::llm::tests::clean_json_strips_markdown_fences ... ok
[INFO] [stdout] test utils::ops_log::tests::append_operation_creates_log_and_appends_entry ... ok
[INFO] [stdout] test workflow::tests::llm_description_can_be_disabled ... ok
[INFO] [stdout] test workflow::tests::llm_description_defaults_to_true ... ok
[INFO] [stdout] test workflow::tests::llm_semaphore_has_one_permit ... ok
[INFO] [stdout] test utils::command::tests::async_run_command_large_output_does_not_deadlock ... ok
[INFO] [stdout] test workflow::tests::workflow_config_from_cli_defaults_to_no_local_build_pool ... ok
[INFO] [stdout] test workflow::tests::workflow_action_no_spec_change_returns_need_human ... ok
[INFO] [stdout] test workflow::tests::workflow_dry_run_does_not_modify_spec ... ok
[INFO] [stdout] test git::tests::commit_if_staged_no_changes_returns_false ... ok
[INFO] [stdout] test utils::command::tests::static_allowed_list_respects_rules ... ok
[INFO] [stdout] test utils::llm::tests::clean_json_returns_error_on_no_json ... ok
[INFO] [stdout] test utils::command::tests::run_command_blocking_collects_stdout_and_stderr ... FAILED
[INFO] [stdout] test git::tests::commit_if_staged_with_changes_returns_true ... ok
[INFO] [stdout] test git::tests::checkout_or_create_branch_existing_branch ... ok
[INFO] [stdout] test upstream::tests::generate_python_spec_passes_version_argument ... ok
[INFO] [stdout] test upstream::tests::takopack_receives_pypi_name_without_python_prefix ... ok
[INFO] [stdout] test utils::command::tests::run_command_blocking_log_contains_header_and_returncode ... ok
[INFO] [stdout] test core::resources::tests::pool_allocates_up_to_max_slots ... ok
[INFO] [stdout] test utils::command::tests::async_run_command_kills_on_timeout ... ok
[INFO] [stdout] test utils::command::tests::async_run_command_timeout_kills_despite_continuous_output ... ok
[INFO] [stdout] test utils::command::tests::async_run_command_timeout_kills_background_children ... ok
[INFO] [stdout] test utils::command::tests::async_run_command_timeout_with_heavy_output_does_not_hang ... ok
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- utils::command::tests::run_command_blocking_collects_stdout_and_stderr stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'utils::command::tests::run_command_blocking_collects_stdout_and_stderr' (1840) panicked at src/utils/command.rs:692:10:
[INFO] [stdout] run command: failed to start "/tmp/.tmp8UP5Y3/osc"
[INFO] [stdout] 
[INFO] [stdout] Caused by:
[INFO] [stdout]     Text file busy (os error 26)
[INFO] [stdout] 
[INFO] [stdout] Stack backtrace:
[INFO] [stdout]    0: <core::io::error::Error as anyhow::context::ext::StdError>::ext_context::<alloc::string::String>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/anyhow-1.0.102/src/backtrace.rs:10:14
[INFO] [stdout]    1: <core::result::Result<std::process::Child, core::io::error::Error> as anyhow::Context<std::process::Child, core::io::error::Error>>::with_context::<alloc::string::String, packfix_rs::utils::command::run_command_blocking::{closure#2}>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/anyhow-1.0.102/src/context.rs:65:37
[INFO] [stdout]    2: packfix_rs::utils::command::run_command_blocking
[INFO] [stdout]              at ./src/utils/command.rs:389:10
[INFO] [stdout]    3: packfix_rs::utils::command::tests::run_command_blocking_collects_stdout_and_stderr
[INFO] [stdout]              at ./src/utils/command.rs:685:22
[INFO] [stdout]    4: packfix_rs::utils::command::tests::run_command_blocking_collects_stdout_and_stderr::{closure#0}
[INFO] [stdout]              at ./src/utils/command.rs:678:57
[INFO] [stdout]    5: <packfix_rs::utils::command::tests::run_command_blocking_collects_stdout_and_stderr::{closure#0} as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/ops/function.rs:250:5
[INFO] [stdout]    6: <fn() -> core::result::Result<(), alloc::string::String> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/ops/function.rs:250:5
[INFO] [stdout]    7: test::__rust_begin_short_backtrace::<core::result::Result<(), alloc::string::String>, fn() -> core::result::Result<(), alloc::string::String>>
[INFO] [stdout]              at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:724:18
[INFO] [stdout]    8: test::run_test_in_process::{closure#0}
[INFO] [stdout]              at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:747:74
[INFO] [stdout]    9: <core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   10: std::panicking::catch_unwind::do_call::<core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}>, core::result::Result<(), alloc::string::String>>
[INFO] [stdout]              at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:576:43
[INFO] [stdout]   11: std::panicking::catch_unwind::<core::result::Result<(), alloc::string::String>, core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}>>
[INFO] [stdout]              at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:544:19
[INFO] [stdout]   12: std::panic::catch_unwind::<core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}>, core::result::Result<(), alloc::string::String>>
[INFO] [stdout]              at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panic.rs:359:14
[INFO] [stdout]   13: test::run_test_in_process
[INFO] [stdout]              at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:747:27
[INFO] [stdout]   14: test::run_test::{closure#0}
[INFO] [stdout]              at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:668:43
[INFO] [stdout]   15: test::run_test::{closure#1}
[INFO] [stdout]              at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:698:41
[INFO] [stdout]   16: std::sys::backtrace::__rust_begin_short_backtrace::<test::run_test::{closure#1}, ()>
[INFO] [stdout]              at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   17: std::thread::lifecycle::spawn_unchecked::<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]              at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   18: <core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   19: std::panicking::catch_unwind::do_call::<core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]              at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:576:43
[INFO] [stdout]   20: std::panicking::catch_unwind::<(), core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]              at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:544:19
[INFO] [stdout]   21: std::panic::catch_unwind::<core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]              at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panic.rs:359:14
[INFO] [stdout]   22: std::thread::lifecycle::spawn_unchecked::<test::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]              at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   23: <std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1} as core::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]              at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   24: <alloc::boxed::Box<dyn core::ops::function::FnOnce<(), Output = ()> + core::marker::Send> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/alloc/src/boxed.rs:2319:9
[INFO] [stdout]   25: <std::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]              at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/thread/unix.rs:123:17
[INFO] [stdout]   26: <unknown>
[INFO] [stdout]   27: clone
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x62e0221d1121 - std[73adb7dc35730857]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x62e0221d1121 - std[73adb7dc35730857]::backtrace_rs::backtrace::trace_unsynchronized::<std[73adb7dc35730857]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x62e0221d1121 - std[73adb7dc35730857]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x62e0221d1121 - <<std[73adb7dc35730857]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[6883ba1bc0fe4ed1]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x62e0221ea9ea - <core[6883ba1bc0fe4ed1]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x62e0221ea9ea - core[6883ba1bc0fe4ed1]::fmt::write
[INFO] [stdout]    6:     0x62e0221d662c - std[73adb7dc35730857]::io::default_write_fmt::<alloc[55a36b64bcbf2c0d]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/io/mod.rs:626:11
[INFO] [stdout]    7:     0x62e0221d662c - <alloc[55a36b64bcbf2c0d]::vec::Vec<u8> as std[73adb7dc35730857]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/io/mod.rs:1730:13
[INFO] [stdout]    8:     0x62e0221a8e46 - <std[73adb7dc35730857]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x62e0221a8e46 - std[73adb7dc35730857]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x62e0221c62c9 - std[73adb7dc35730857]::panicking::default_hook
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x62e021759680 - <alloc[55a36b64bcbf2c0d]::boxed::Box<dyn for<'a, 'b> core[6883ba1bc0fe4ed1]::ops::function::Fn<(&'a std[73adb7dc35730857]::panic::PanicHookInfo<'b>,), Output = ()> + core[6883ba1bc0fe4ed1]::marker::Send + core[6883ba1bc0fe4ed1]::marker::Sync> as core[6883ba1bc0fe4ed1]::ops::function::Fn<(&std[73adb7dc35730857]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/alloc/src/boxed.rs:2333:9
[INFO] [stdout]   12:     0x62e021759680 - test[980ffaebd391d06d]::test_main_inner::<test[980ffaebd391d06d]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x62e0221c65f2 - <alloc[55a36b64bcbf2c0d]::boxed::Box<dyn for<'a, 'b> core[6883ba1bc0fe4ed1]::ops::function::Fn<(&'a std[73adb7dc35730857]::panic::PanicHookInfo<'b>,), Output = ()> + core[6883ba1bc0fe4ed1]::marker::Send + core[6883ba1bc0fe4ed1]::marker::Sync> as core[6883ba1bc0fe4ed1]::ops::function::Fn<(&std[73adb7dc35730857]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/alloc/src/boxed.rs:2333:9
[INFO] [stdout]   14:     0x62e0221c65f2 - std[73adb7dc35730857]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:823:13
[INFO] [stdout]   15:     0x62e0221a8ef2 - std[73adb7dc35730857]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:688:13
[INFO] [stdout]   16:     0x62e02219fd59 - std[73adb7dc35730857]::sys::backtrace::__rust_end_short_backtrace::<std[73adb7dc35730857]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x62e0221aa17d - __rustc[a7b7b02e776dd976]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:679:5
[INFO] [stdout]   18:     0x62e0221eb29c - core[6883ba1bc0fe4ed1]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x62e0221eafe2 - core[6883ba1bc0fe4ed1]::result::unwrap_failed
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/result.rs:1870:5
[INFO] [stdout]   20:     0x62e0215aa153 - <core[6883ba1bc0fe4ed1]::result::Result<packfix_rs[c15819b4ed9d8448]::utils::command::CommandResult, anyhow[3b9a07a6948cd063]::Error>>::expect
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/result.rs:1183:23
[INFO] [stdout]   21:     0x62e02155c91b - packfix_rs[c15819b4ed9d8448]::utils::command::tests::run_command_blocking_collects_stdout_and_stderr
[INFO] [stdout]                                at /opt/rustwide/workdir/src/utils/command.rs:692:10
[INFO] [stdout]   22:     0x62e0215534f7 - packfix_rs[c15819b4ed9d8448]::utils::command::tests::run_command_blocking_collects_stdout_and_stderr::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/utils/command.rs:678:57
[INFO] [stdout]   23:     0x62e021540586 - <packfix_rs[c15819b4ed9d8448]::utils::command::tests::run_command_blocking_collects_stdout_and_stderr::{closure#0} as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   24:     0x62e02174c9bb - <fn() -> core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   25:     0x62e02174c9bb - test[980ffaebd391d06d]::__rust_begin_short_backtrace::<core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>, fn() -> core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:724:18
[INFO] [stdout]   26:     0x62e02175a005 - test[980ffaebd391d06d]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:747:74
[INFO] [stdout]   27:     0x62e02175a005 - <core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   28:     0x62e02175a005 - std[73adb7dc35730857]::panicking::catch_unwind::do_call::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}>, core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:576:43
[INFO] [stdout]   29:     0x62e02175a005 - std[73adb7dc35730857]::panicking::catch_unwind::<core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>, core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:544:19
[INFO] [stdout]   30:     0x62e02175a005 - std[73adb7dc35730857]::panic::catch_unwind::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}>, core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panic.rs:359:14
[INFO] [stdout]   31:     0x62e02175a005 - test[980ffaebd391d06d]::run_test_in_process
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:747:27
[INFO] [stdout]   32:     0x62e02175a005 - test[980ffaebd391d06d]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:668:43
[INFO] [stdout]   33:     0x62e021754ab4 - test[980ffaebd391d06d]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:698:41
[INFO] [stdout]   34:     0x62e021754ab4 - std[73adb7dc35730857]::sys::backtrace::__rust_begin_short_backtrace::<test[980ffaebd391d06d]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   35:     0x62e02175d152 - std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked::<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   36:     0x62e02175d152 - <core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   37:     0x62e02175d152 - std[73adb7dc35730857]::panicking::catch_unwind::do_call::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:576:43
[INFO] [stdout]   38:     0x62e02175d152 - std[73adb7dc35730857]::panicking::catch_unwind::<(), core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:544:19
[INFO] [stdout]   39:     0x62e02175d152 - std[73adb7dc35730857]::panic::catch_unwind::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panic.rs:359:14
[INFO] [stdout]   40:     0x62e02175d152 - std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked::<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   41:     0x62e02175d152 - <std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1} as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   42:     0x62e0221cfc9f - <alloc[55a36b64bcbf2c0d]::boxed::Box<dyn core[6883ba1bc0fe4ed1]::ops::function::FnOnce<(), Output = ()> + core[6883ba1bc0fe4ed1]::marker::Send> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/alloc/src/boxed.rs:2319:9
[INFO] [stdout]   43:     0x62e0221cfc9f - <std[73adb7dc35730857]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/thread/unix.rs:123:17
[INFO] [stdout]   44:     0x769c90236aa4 - <unknown>
[INFO] [stdout]   45:     0x769c902c3a64 - clone
[INFO] [stdout]   46:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     utils::command::tests::run_command_blocking_collects_stdout_and_stderr
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 252 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 2.07s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--bin packfix-rs`
[INFO] running `Command { std: "docker" "inspect" "c1cd075ff6677ebf6f444a3bfd194333d1257f211c99c1a928137bd99e38d076", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "c1cd075ff6677ebf6f444a3bfd194333d1257f211c99c1a928137bd99e38d076", kill_on_drop: false }`
[INFO] [stdout] c1cd075ff6677ebf6f444a3bfd194333d1257f211c99c1a928137bd99e38d076
