[INFO] fetching crate nishikaze 0.3.2...
[INFO] testing nishikaze-0.3.2 against try#dec9417b8611e34e787a3e4c37686b5131f9e5c5 for pr-154210-2
[INFO] extracting crate nishikaze 0.3.2 into /workspace/builds/worker-2-tc2/source
[INFO] removed /workspace/builds/worker-2-tc2/source/.cargo/config.toml
[INFO] started tweaking crates.io crate nishikaze 0.3.2
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate nishikaze 0.3.2
[INFO] tweaked toml for crates.io crate nishikaze 0.3.2 written to /workspace/builds/worker-2-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate nishikaze 0.3.2 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 nishikaze 0.3.2 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] 11d9d3d0565f527c6217b0f776ff6b38a499b3090b1963b49a0551081d5556e2
[INFO] running `Command { std: "docker" "start" "-a" "11d9d3d0565f527c6217b0f776ff6b38a499b3090b1963b49a0551081d5556e2", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "11d9d3d0565f527c6217b0f776ff6b38a499b3090b1963b49a0551081d5556e2", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "11d9d3d0565f527c6217b0f776ff6b38a499b3090b1963b49a0551081d5556e2", kill_on_drop: false }`
[INFO] [stdout] 11d9d3d0565f527c6217b0f776ff6b38a499b3090b1963b49a0551081d5556e2
[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] 9a7bfa98f0d6ab0d8b140dc28e826eb68dae7159b6b389f0833c3d8c22294b11
[INFO] running `Command { std: "docker" "start" "-a" "9a7bfa98f0d6ab0d8b140dc28e826eb68dae7159b6b389f0833c3d8c22294b11", kill_on_drop: false }`
[INFO] [stderr]    Compiling quote v1.0.44
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling clap_lex v1.0.0
[INFO] [stderr]    Compiling winnow v0.7.14
[INFO] [stderr]    Compiling maplit v1.0.2
[INFO] [stderr]    Compiling unsafe-libyaml v0.2.11
[INFO] [stderr]    Compiling ryu v1.0.23
[INFO] [stderr]    Compiling toml_writer v1.0.6+spec-1.1.0
[INFO] [stderr]    Compiling indexmap v2.13.0
[INFO] [stderr]    Compiling serde_spanned v1.0.4
[INFO] [stderr]    Compiling toml_datetime v1.0.0+spec-1.1.0
[INFO] [stderr]    Compiling clap_builder v4.5.60
[INFO] [stderr]    Compiling syn v2.0.117
[INFO] [stderr]    Compiling syn v1.0.109
[INFO] [stderr]    Compiling toml_parser v1.0.9+spec-1.1.0
[INFO] [stderr]    Compiling toml v1.0.3+spec-1.1.0
[INFO] [stderr]    Compiling strum_macros v0.24.3
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling clap_derive v4.5.55
[INFO] [stderr]    Compiling strum v0.24.1
[INFO] [stderr]    Compiling spinners v4.1.1
[INFO] [stderr]    Compiling clap v4.5.60
[INFO] [stderr]    Compiling serde_yaml v0.9.34+deprecated
[INFO] [stderr]    Compiling nishikaze v0.3.2 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 13.81s
[INFO] running `Command { std: "docker" "inspect" "9a7bfa98f0d6ab0d8b140dc28e826eb68dae7159b6b389f0833c3d8c22294b11", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "9a7bfa98f0d6ab0d8b140dc28e826eb68dae7159b6b389f0833c3d8c22294b11", kill_on_drop: false }`
[INFO] [stdout] 9a7bfa98f0d6ab0d8b140dc28e826eb68dae7159b6b389f0833c3d8c22294b11
[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] a4012df4721b8e0a0d9f5b7cd15eef56fa460e5fdda4aeaefa50e28df1afc105
[INFO] running `Command { std: "docker" "start" "-a" "a4012df4721b8e0a0d9f5b7cd15eef56fa460e5fdda4aeaefa50e28df1afc105", kill_on_drop: false }`
[INFO] [stderr]    Compiling zerocopy v0.8.40
[INFO] [stderr]    Compiling cc v1.2.56
[INFO] [stderr]    Compiling num-traits v0.2.19
[INFO] [stderr]    Compiling libc v0.2.182
[INFO] [stderr]    Compiling either v1.15.0
[INFO] [stderr]    Compiling zerocopy-derive v0.8.40
[INFO] [stderr]    Compiling ciborium-io v0.2.2
[INFO] [stderr]    Compiling regex-syntax v0.8.10
[INFO] [stderr]    Compiling plotters-backend v0.3.7
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling serde_json v1.0.149
[INFO] [stderr]    Compiling same-file v1.0.6
[INFO] [stderr]    Compiling cast v0.3.0
[INFO] [stderr]    Compiling yansi v1.0.1
[INFO] [stderr]    Compiling anes v0.1.6
[INFO] [stderr]    Compiling oorandom v11.1.5
[INFO] [stderr]    Compiling diff v0.1.13
[INFO] [stderr]    Compiling walkdir v2.5.0
[INFO] [stderr]    Compiling itertools v0.13.0
[INFO] [stderr]    Compiling rayon v1.11.0
[INFO] [stderr]    Compiling pretty_assertions v1.4.1
[INFO] [stderr]    Compiling serde_yaml v0.9.34+deprecated
[INFO] [stderr]    Compiling plotters-svg v0.3.7
[INFO] [stderr]    Compiling tinytemplate v1.2.1
[INFO] [stderr]    Compiling plotters v0.3.7
[INFO] [stderr]    Compiling alloca v0.4.0
[INFO] [stderr]    Compiling regex-automata v0.4.14
[INFO] [stderr]    Compiling page_size v0.6.0
[INFO] [stderr]    Compiling nishikaze v0.3.2 (/opt/rustwide/workdir)
[INFO] [stderr]    Compiling criterion-plot v0.8.2
[INFO] [stderr]    Compiling regex v1.12.3
[INFO] [stderr]    Compiling half v2.7.1
[INFO] [stderr]    Compiling ciborium-ll v0.2.2
[INFO] [stderr]    Compiling ciborium v0.2.2
[INFO] [stderr]    Compiling criterion v0.8.2
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 25.15s
[INFO] running `Command { std: "docker" "inspect" "a4012df4721b8e0a0d9f5b7cd15eef56fa460e5fdda4aeaefa50e28df1afc105", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "a4012df4721b8e0a0d9f5b7cd15eef56fa460e5fdda4aeaefa50e28df1afc105", kill_on_drop: false }`
[INFO] [stdout] a4012df4721b8e0a0d9f5b7cd15eef56fa460e5fdda4aeaefa50e28df1afc105
[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] e6bbe465fa4f5fef72bf05ab931e0e9d97b736f332623a2067903a547819b980
[INFO] running `Command { std: "docker" "start" "-a" "e6bbe465fa4f5fef72bf05ab931e0e9d97b736f332623a2067903a547819b980", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.18s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/nishikaze-58fc344b29f48072)
[INFO] [stdout] 
[INFO] [stdout] running 170 tests
[INFO] [stdout] test config::load::tests::load_config_invalid_toml ... ok
[INFO] [stdout] test config::load::tests::load_config_success ... ok
[INFO] [stdout] test config::load::tests::load_config_rejects_legacy_args ... ok
[INFO] [stdout] test config::load::tests::load_error_sources_are_exposed ... ok
[INFO] [stdout] test config::load::tests::load_config_missing_file ... ok
[INFO] [stdout] test config::load::tests::load_error_display_includes_path ... ok
[INFO] [stdout] test config::schema::tests::arglist_deserialize_single_and_list ... ok
[INFO] [stdout] test config::schema::tests::arglist_deserialize_many_entry ... ok
[INFO] [stdout] test config::schema::tests::arglist_deserialize_single_string ... ok
[INFO] [stdout] test config::schema::tests::bom_cfg_defaults_from_toml ... ok
[INFO] [stdout] test config::schema::tests::bom_cfg_overlay_keeps_lhs_version_when_rhs_empty ... ok
[INFO] [stdout] test config::schema::tests::bom_cfg_overlay_prefers_rhs_when_set ... ok
[INFO] [stdout] test config::schema::tests::arglist_to_vec_flattens ... ok
[INFO] [stdout] test config::schema::tests::build_cfg_overlay_empty_root_keeps_lhs ... ok
[INFO] [stdout] test config::schema::tests::build_cfg_overlay_overrides_root ... ok
[INFO] [stdout] test config::schema::tests::file_config_overlay_merges_profiles ... ok
[INFO] [stdout] test config::schema::tests::file_config_overlay_replaces_profiles_when_missing_lhs ... ok
[INFO] [stdout] test config::schema::tests::phase_args_overlay_only_overrides_when_non_empty ... ok
[INFO] [stdout] test config::schema::tests::build_cfg_overlay_respects_root_and_flags ... ok
[INFO] [stdout] test config::schema::tests::phase_args_overlay_overrides_flash_and_run ... ok
[INFO] [stdout] test config::schema::tests::project_cfg_overlay_overrides_name ... ok
[INFO] [stdout] test config::schema::tests::zephyr_cfg_overlay_manifest_current_behavior ... ok
[INFO] [stdout] test config::schema::tests::project_cfg_overlay_prefers_rhs ... ok
[INFO] [stdout] test config::schema::tests::zephyr_cfg_overlay_overrides_url ... ok
[INFO] [stdout] test config::schema::tests::zephyr_cfg_overlay_prefers_rhs ... ok
[INFO] [stdout] test config::schema::tests::profile_cfg_overlay_overrides_board_and_runner ... ok
[INFO] [stdout] test config::schema::tests::zephyr_cfg_deserialize_workspace_fields ... ok
[INFO] [stdout] test config::schema::tests::zephyr_cfg_overlay_keeps_url_when_rhs_none ... ok
[INFO] [stdout] test config::schema::tests::zephyr_cfg_overlay_overrides_workspace ... ok
[INFO] [stdout] test config::schema::tests::build_cfg_defaults_root_when_missing ... ok
[INFO] [stdout] test core::cli::tests::parse_subcommands_with_args ... ok
[INFO] [stdout] test core::cli::tests::parse_conf_and_flash_with_passthrough ... ok
[INFO] [stdout] test core::cmd::tests::build_conf_cmd_non_sysbuild_uses_project_source ... ok
[INFO] [stdout] test core::cli::tests::parse_workspace_apply_and_alias ... ok
[INFO] [stdout] test core::cli::tests::parse_bom_with_sysbuild_args ... ok
[INFO] [stdout] test core::cli::tests::parse_menuconfig_with_sysbuild_args ... ok
[INFO] [stdout] test core::cli::tests::parse_simple_subcommands ... ok
[INFO] [stdout] test core::cli::tests::parse_workspace_update_and_alias ... ok
[INFO] [stdout] test core::cli::tests::parse_aliases_and_defaults ... ok
[INFO] [stdout] test config::load::tests::load_config_parses_project_fields ... ok
[INFO] [stdout] test core::cli::tests::parse_defaults ... ok
[INFO] [stdout] test core::cli::tests::parse_bom_alias_spdx ... ok
[INFO] [stdout] test config::schema::tests::profile_cfg_overlay_merges_args ... ok
[INFO] [stdout] test core::cli::tests::parse_menuconfig_alias_m ... ok
[INFO] [stdout] test core::cli::tests::parse_workspace_export_and_alias ... ok
[INFO] [stdout] kaze: Cleaning /tmp/nishikaze-cmd-test-clean-root-3/build
[INFO] [stdout] kaze: Listing sysbuild images
[INFO] [stdout] test core::cli::tests::parse_workspace_init_and_alias ... ok
[INFO] [stdout] kaze: Configuring project
[INFO] [stdout] kaze: Configure completed
[INFO] [stdout] kaze: Building project
[INFO] [stdout] test core::cli::tests::parse_flags_and_options ... ok
[INFO] [stdout] kaze: Build completed
[INFO] [stdout] kaze: Flashing project
[INFO] [stdout] kaze: Flash completed
[INFO] [stdout] test core::cmd::tests::clean_dir_safe_covers_error_and_dry_run_paths ... ok
[INFO] [stdout] test core::cmd::tests::cmd_bom_list_sysbuild_succeeds ... ok
[INFO] [stdout] test core::cmd::tests::cmd_flash_uses_runner_when_set ... ok
[INFO] [stdout] test core::cmd::tests::build_conf_cmd_sysbuild_uses_app_dir_and_sysbuild_source ... ok
[INFO] [stdout] kaze: Configuring project
[INFO] [stdout] kaze: Configure completed
[INFO] [stdout] kaze: Building project
[INFO] [stderr] Option --list is only supported for sysbuild projects
[INFO] [stdout] kaze: Build completed
[INFO] [stdout] kaze: Flashing project
[INFO] [stdout] kaze: Flash completed
[INFO] [stdout] kaze: Building project
[INFO] [stdout] kaze: Build completed
[INFO] [stdout] test core::cmd::tests::cmd_bom_list_errors_for_non_sysbuild ... ok
[INFO] [stderr] Option --list is only supported for sysbuild projects
[INFO] [stdout] test core::cmd::tests::cmd_flash_allows_no_runner ... ok
[INFO] [stdout] kaze: Configuring project
[INFO] [stdout] kaze: Configure completed
[INFO] [stderr] Option --image is only supported for sysbuild projects
[INFO] [stdout] kaze: Building project
[INFO] [stdout] kaze: Build completed
[INFO] [stdout] kaze: Flashing sysbuild image: app
[INFO] [stdout] kaze: Flashing project
[INFO] [stdout] kaze: Flash completed
[INFO] [stdout] test core::cmd::tests::cmd_flash_list_errors_for_non_sysbuild ... kaze: Building project
[INFO] [stdout] ok
[INFO] [stdout] test core::cmd::tests::cmd_flash_sysbuild_uses_image_dir ... ok
[INFO] [stdout] kaze: removed /tmp/nishikaze-cmd-test-clean-remove-6/build
[INFO] [stdout] test core::cmd::tests::cmd_bom_image_errors_for_non_sysbuild ... ok
[INFO] [stdout] test core::cmd::tests::clean_dir_safe_removes_dir ... ok
[INFO] [stderr] Option --list is only supported for sysbuild projects
[INFO] [stdout] test core::cmd::tests::cmd_run_list_errors_for_non_sysbuild ... ok
[INFO] [stdout] kaze: Running menuconfig for image: app
[INFO] [stdout] kaze: Running menuconfig...
[INFO] [stderr] Option --list is only supported for sysbuild projects
[INFO] [stdout] kaze: Configuring project
[INFO] [stdout] kaze: Configure completed
[INFO] [stdout] kaze: Running menuconfig for image: app
[INFO] [stdout] kaze: Running menuconfig...
[INFO] [stdout] kaze: Listing configured profiles
[INFO] [stdout] kaze: Listing configured profiles
[INFO] [stdout] kaze: Building project
[INFO] [stdout] kaze: Running project
[INFO] [stdout] kaze: Run completed
[INFO] [stdout] kaze: Build completed
[INFO] [stderr] Failed to resolve image
[INFO] [stdout] kaze: Listing sysbuild images
[INFO] [stdout] kaze: Build completed
[INFO] [stdout] kaze: Initializing kaze.toml
[INFO] [stderr] Option --image is only supported for sysbuild projects
[INFO] [stdout] kaze: created /tmp/nishikaze-cmd-test-cmd-init-16/kaze.toml
[INFO] [stdout] kaze: Initializing kaze.toml
[INFO] [stdout] test core::cmd::tests::cmd_menuconfig_sysbuild_runs_with_image ... ok
[INFO] [stdout] test core::cmd::tests::cmd_menuconfig_list_errors_for_non_sysbuild ... ok
[INFO] [stdout] kaze: Listing sysbuild images
[INFO] [stdout] kaze: Configuring project
[INFO] [stdout] kaze: Configure completed
[INFO] [stdout] kaze: Building project
[INFO] [stderr] Option --image is only supported for sysbuild projects
[INFO] [stdout] kaze: Build completed
[INFO] [stdout] test core::cmd::tests::cmd_menuconfig_sysbuild_defaults_to_first_image ... ok
[INFO] [stdout] test core::cmd::tests::cmd_profiles_handles_empty_and_non_empty ... ok
[INFO] [stdout] test core::cmd::tests::cmd_run_falls_back_to_ninja ... ok
[INFO] [stdout] test core::cmd::tests::cmd_flash_list_sysbuild_succeeds ... ok
[INFO] [stdout] test core::cmd::tests::cmd_flash_sysbuild_errors_when_no_images ... ok
[INFO] [stdout] test core::cmd::tests::cmd_init_creates_and_rejects_existing_config ... ok
[INFO] [stdout] test core::cmd::tests::cmd_run_image_errors_for_non_sysbuild ... ok
[INFO] [stdout] kaze: Listing sysbuild images
[INFO] [stdout] test core::cmd::tests::cmd_menuconfig_list_sysbuild_succeeds ... ok
[INFO] [stdout] test core::cmd::tests::cmd_flash_image_errors_for_non_sysbuild ... ok
[INFO] [stdout] test core::cmd::tests::cmd_runners_formats_listed_runners ... ok
[INFO] [stdout] test core::cmd::tests::cmd_runners_formats_empty_and_flash_runner ... ok
[INFO] [stdout] test core::cmd::tests::cmd_run_list_sysbuild_succeeds ... ok
[INFO] [stdout] kaze: Running sysbuild image: app
[INFO] [stdout] kaze: Running project
[INFO] [stdout] kaze: Run completed
[INFO] [stdout] kaze: kaze: Applying zephyr workspace
[INFO] [stdout] kaze: Generating SPDX `BoM` for sysbuild image: app
[INFO] [stderr] Failed to resolve image
[INFO] [stdout] kaze: BoM gerneration config changed, cleaning build directory
[INFO] [stdout] kaze: Cleaning /tmp/nishikaze-cmd-test-bom-sysbuild-12/build
[INFO] [stdout] kaze: Managing zephyr workspace
[INFO] [stdout] kaze: Cleaning /tmp/nishikaze-cmd-test-ws-branches-37/ws/.west
[INFO] [stdout] kaze: Managing zephyr workspace
[INFO] [stdout] kaze: Managing zephyr workspace
[INFO] [stdout] kaze: Applying zephyr workspace
[INFO] [stdout] Listing available runners
[INFO] [stdout] kaze: Running project
[INFO] [stdout] kaze: Run completed
[INFO] [stdout] kaze: Configuring sysbuild project
[INFO] [stdout] kaze: Running menuconfig for image: app
[INFO] [stdout] kaze: Running menuconfig...
[INFO] [stdout] kaze: Running project
[INFO] [stdout] kaze: Run completed
[INFO] [stdout] test core::cmd::tests::cmd_run_sysbuild_requires_image ... ok
[INFO] [stdout] test core::cmd::tests::cmd_ws_export_and_apply_succeed_with_dry_run ... ok
[INFO] [stdout] test core::cmd::tests::cmd_run_sysbuild_errors_when_no_images ... ok
[INFO] [stdout] test core::cmd::tests::cmd_workspace_branches_init_export_apply ... ok
[INFO] [stdout] test core::cmd::tests::cmd_run_prefers_zephyr_binary_when_present ... ok
[INFO] [stdout] test core::cmd::tests::cmd_runners_reads_yaml ... ok
[INFO] [stdout] kaze: Cleaning /tmp/nishikaze-cmd-test-ws-init-force-36/ws/.west
[INFO] [stdout] kaze: Configure completed
[INFO] [stdout] kaze: Building project
[INFO] [stdout] kaze: Build completed
[INFO] [stdout] kaze: SPDX BoM generated in: /tmp/nishikaze-cmd-test-bom-sysbuild-12/build/app/spdx
[INFO] [stdout] kaze: Listing available runners
[INFO] [stdout] kaze: Runner config not found in build dir. Try building the project first with `kaze build`.
[INFO] [stdout] kaze: Running sysbuild image: app
[INFO] [stdout] kaze: Managing zephyr workspace
[INFO] [stdout] kaze: Managing zephyr workspace
[INFO] [stdout] test core::cmd::tests::cmd_menuconfig_sysbuild_accepts_explicit_image ... ok
[INFO] [stdout] test core::cmd::tests::cmd_run_prefers_zephyr_binary_when_norebuild ... ok
[INFO] [stdout] test core::cmd::tests::cmd_ws_init_force_removes_west_dir ... ok
[INFO] [stdout] test core::cmd::tests::cmd_bom_sysbuild_runs_with_image ... ok
[INFO] [stdout] test core::cmd::tests::cmd_ws_init_errors_when_initialized_without_force ... ok
[INFO] [stdout] kaze: Managing zephyr workspace
[INFO] [stdout] test core::cmd::tests::cmd_runners_errors_without_yaml ... ok
[INFO] [stdout] test core::cmd::tests::cmd_workspace_errors_when_workspace_missing ... ok
[INFO] [stdout] test core::cmd::tests::cmd_workspace_update_succeeds_with_dry_run ... ok
[INFO] [stdout] test core::cmd::tests::cmd_workspace_errors_on_conflicting_manifest_config ... ok
[INFO] [stdout] test core::cmd::tests::compile_commands_link_replaces_existing_file ... ok
[INFO] [stdout] kaze: Running project
[INFO] [stdout] kaze: Run completed
[INFO] [stdout] test core::cmd::tests::compile_commands_link_creates_build_root_when_missing ... ok
[INFO] [stdout] test core::cmd::tests::compile_commands_link_skips_when_disabled ... ok
[INFO] [stdout] test core::cmd::tests::compile_commands_link_falls_back_to_first_profile ... ok
[INFO] [stdout] test core::cmd::tests::compile_commands_link_defaults_enabled_when_unset ... ok
[INFO] [stdout] test core::cmd::tests::compile_commands_link_skips_when_existing_symlink ... ok
[INFO] [stdout] test core::cmd::tests::compile_commands_link_falls_back_when_default_missing ... ok
[INFO] [stdout] test core::cmd::tests::compile_commands_link_skips_without_profiles ... ok
[INFO] [stdout] test core::cmd::tests::cmd_run_sysbuild_uses_image_dir ... ok
[INFO] [stdout] test core::cmd::tests::compile_commands_link_sysbuild_without_profiles ... ok
[INFO] [stdout] test core::cmd::tests::compile_commands_link_uses_default_profile ... ok
[INFO] [stdout] test core::cmd::tests::find_images_filters_non_image_dirs ... ok
[INFO] [stdout] test core::cmd::tests::compile_commands_link_uses_sysbuild_image ... ok
[INFO] [stdout] test core::cmd::tests::compile_commands_link_sysbuild_errors_without_matching_image ... ok
[INFO] [stdout] test core::cmd::tests::compile_commands_link_skips_when_existing_dir ... ok
[INFO] [stdout] test core::cmd::tests::ensure_bom_conf_runs_when_uninitialized ... ok
[INFO] [stdout] test core::cmd::tests::merge_args_appends_extra_after_config ... ok
[INFO] [stdout] test core::cmd::tests::push_filtered_args_skips_empty ... ok
[INFO] [stdout] test core::cmd::tests::resolve_default_profile_handles_default_and_fallback ... ok
[INFO] [stdout] test core::cmd::tests::resolve_sysbuild_image_accepts_index_and_name ... ok
[INFO] [stdout] test core::cmd::tests::resolve_sysbuild_image_errors_when_empty ... ok
[INFO] [stdout] test core::cmd::tests::resolve_sysbuild_image_rejects_invalid_selection ... ok
[INFO] [stdout] test core::cmd::tests::resolve_sysbuild_image_defaults_to_non_bootloader ... ok
[INFO] [stdout] test core::cmd::tests::resolve_sysbuild_image_defaults_to_mcuboot_when_only ... ok
[INFO] [stdout] test core::cmd::tests::merge_args_skips_empty_and_preserves_order ... ok
[INFO] [stdout] test core::cmd::tests::get_cache_var_skips_malformed_lines ... ok
[INFO] [stdout] test core::cmd::tests::resolve_sysbuild_image_rejects_zero_index ... ok
[INFO] [stdout] test core::cmd::tests::is_configured_and_get_cache_var_cover_cache_paths ... ok
[INFO] [stdout] test core::cmd::tests::kaze_error_from_conversions ... ok
[INFO] [stdout] test core::cmd::tests::ws_initialized_detects_west_dir ... ok
[INFO] [stdout] test core::config::tests::discover_zephyr_prefers_explicit_config ... ok
[INFO] [stdout] test core::config::tests::config_error_display_and_source ... ok
[INFO] [stderr] command failed for profile Some("dev"): Msg("refusing to delete outside project dir: /tmp/dev")
[INFO] [stdout] test core::cmd::tests::run_all_profiles_succeeds_with_dry_run ... ok
[INFO] [stderr] command failed for profile Some("prod"): Msg("refusing to delete outside project dir: /tmp/prod")
[INFO] [stdout] test core::cmd::tests::run_all_profiles_reports_any_failed ... ok
[INFO] [stdout] test core::config::tests::discover_zephyr_rel_workspace_uses_abs_canonical ... ok
[INFO] [stdout] test core::config::tests::discover_zephyr_none_when_absent ... ok
[INFO] [stdout] test core::config::tests::discover_zephyr_restores_existing_env_base ... ok
[INFO] [stdout] test core::config::tests::find_upwards_finds_marker_and_returns_none_when_absent ... ok
[INFO] [stdout] test core::config::tests::discover_skips_config_for_init_and_boards ... ok
[INFO] [stdout] test core::cmd::tests::run_uses_config_and_profiles ... ok
[INFO] [stdout] test core::config::tests::discover_zephyr_uses_env_base ... ok
[INFO] [stdout] test core::config::tests::resolve_error_display_formats ... ok
[INFO] [stdout] test core::config::tests::discover_zephyr_restores_preexisting_env_base ... ok
[INFO] [stdout] test core::config::tests::is_sysbuild_detects_conf_and_dir ... ok
[INFO] [stdout] test core::config::tests::resolve_single_allows_init_without_board ... ok
[INFO] [stdout] test core::config::tests::resolve_single_allows_boards_without_board ... ok
[INFO] [stdout] test core::config::tests::resolve_single_applies_profile_overrides ... ok
[INFO] [stdout] test core::config::tests::discover_zephyr_finds_workspace ... ok
[INFO] [stdout] test core::config::tests::resolve_all_handles_no_profiles ... ok
[INFO] [stdout] test core::config::tests::discover_errors_when_config_missing ... ok
[INFO] [stdout] test core::config::tests::resolve_single_cli_overrides_profile ... ok
[INFO] [stdout] test core::config::tests::resolve_all_resolves_each_profile ... ok
[INFO] [stdout] test core::cmd::tests::run_cmd_dispatches_across_commands ... ok
[INFO] [stdout] test core::cmd::tests::ensure_configured_reconfigures_on_board_mismatch ... ok
[INFO] [stdout] test core::config::tests::resolve_single_errors_without_board ... ok
[INFO] [stdout] test core::exec::tests::cmd_builder_sets_fields ... ok
[INFO] [stdout] test core::exec::tests::pretty_cmd_includes_cwd_and_quotes_whitespace ... ok
[INFO] [stdout] test core::exec::tests::reset_terminal_colors_best_effort ... ok
[INFO] [stdout] test core::exec::tests::dry_run_does_not_execute ... ok
[INFO] [stdout] test core::exec::tests::exec_error_display_formats_messages ... ok
[INFO] [stdout] test core::config::tests::select_profile_falls_back_when_default_missing ... ok
[INFO] [stdout] test core::config::tests::select_profile_unknown_returns_error ... ok
[INFO] [stdout] test core::config::tests::resolve_single_propagates_zephyr_workspace_config ... ok
[INFO] [stdout] test core::config::tests::select_profile_returns_none_when_no_profiles ... ok
[INFO] [stdout] test core::config::tests::select_profile_default_used_when_all_and_profiles_exist ... ok
[INFO] [stdout] test core::config::tests::resolve_single_propagates_bom_config ... ok
[INFO] [stdout] test core::exec::tests::run_verbose_three_dry_run_no_spawn ... ok
[INFO] [stdout] test core::exec::tests::run_executes_when_not_dry_run ... ok
[INFO] [stdout] test core::exec::tests::spinner_none_when_dry_run_or_not_verbose ... ok
[INFO] [stdout] 
kaze: Working
[INFO] [stdout] test core::exec::tests::write_raw_bytes_preserves_content ... ok
[INFO] [stdout] test core::log::tests::write_error_no_color_when_disabled ... ok
[INFO] [stdout] test core::log::tests::write_info_no_color_when_disabled ... ok
[INFO] [stdout] test core::log::tests::enabled_false_when_verbosity_low ... ok
[INFO] [stdout] test core::exec::tests::spinner_starts_and_stops ... ok
[INFO] [stdout] test core::log::tests::write_info_includes_color_when_ansi_enabled ... ok
[INFO] [stderr] oops
[INFO] [stdout] test core::log::tests::write_error_includes_color_when_ansi_enabled ... ok
[INFO] [stdout] 
kaze: Doing work .  kaze: hello
[INFO] [stdout] test core::log::tests::enabled_respects_testing_env_vars ... ok
[INFO] [stdout] test core::log::tests::info_and_error_cover_output_paths ... ok
[INFO] [stderr] sh -c "echo out; echo err 1>&2; exit 2"
[INFO] [stderr] err
[INFO] [stdout] out
[INFO] [stdout] test core::exec::tests::run_verbose_three_succeeds ... ok
[INFO] [stdout] test core::exec::tests::run_verbose_two_reports_failure_with_output ... ok
[INFO] [stdout] test core::exec::tests::run_verbose_three_fails ... ok
[INFO] [stdout] test core::config::tests::resolve_single_merges_phase_args ... ok
[INFO] [stdout] 
kaze: Doing work
[INFO] [stdout] test core::exec::tests::run_with_spinner_succeeds ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 170 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.51s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/kaze-d0263aaf96fb608e)
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stderr] error: Msg("boom")
[INFO] [stdout] test tests::test_main ... ok
[INFO] [stdout] test tests::test_main_error_path ... ok
[INFO] [stdout] 
[INFO] [stderr]      Running tests/integration_tests.rs (/opt/rustwide/target/debug/deps/integration_tests-83599ba644a61c58)
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 7 tests
[INFO] [stdout] test kaze_clean_fails_without_config ... ok
[INFO] [stdout] kaze: Running command 'init'
[INFO] [stdout] kaze: Initializing kaze.toml
[INFO] [stdout] kaze: created /tmp/nishikaze-integration-init-2/kaze.toml
[INFO] [stdout] test kaze_init_creates_config ... ok
[INFO] [stdout] test kaze_build_logs_command_and_profile_status ... ok
[INFO] [stdout] test kaze_quiet_suppresses_logs_and_cmd_output ... ok
[INFO] [stdout] test kaze_verbose_always_shows_command_output ... ok
[INFO] [stdout] test kaze_normal_shows_logs_only_on_success ... ok
[INFO] [stderr]    Doc-tests nishikaze
[INFO] [stdout] test kaze_normal_shows_command_output_on_error ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.42s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "e6bbe465fa4f5fef72bf05ab931e0e9d97b736f332623a2067903a547819b980", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "e6bbe465fa4f5fef72bf05ab931e0e9d97b736f332623a2067903a547819b980", kill_on_drop: false }`
[INFO] [stdout] e6bbe465fa4f5fef72bf05ab931e0e9d97b736f332623a2067903a547819b980