[INFO] fetching crate flashkraft-core 1.0.7...
[INFO] testing flashkraft-core-1.0.7 against try#33835004928d3bf65db4d4712e1330766263b0bd for pr-155739
[INFO] extracting crate flashkraft-core 1.0.7 into /workspace/builds/worker-3-tc2/source
[INFO] started tweaking crates.io crate flashkraft-core 1.0.7
[INFO] removed 0 missing examples
[INFO] finished tweaking crates.io crate flashkraft-core 1.0.7
[INFO] tweaked toml for crates.io crate flashkraft-core 1.0.7 written to /workspace/builds/worker-3-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate flashkraft-core 1.0.7 on toolchain 33835004928d3bf65db4d4712e1330766263b0bd
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+33835004928d3bf65db4d4712e1330766263b0bd" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate flashkraft-core 1.0.7 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" "+33835004928d3bf65db4d4712e1330766263b0bd" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-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" "+33835004928d3bf65db4d4712e1330766263b0bd" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] b51c820d322725be5057083905c51efeb6923b97573e2e64ffdb1927085a4ad2
[INFO] running `Command { std: "docker" "start" "-a" "b51c820d322725be5057083905c51efeb6923b97573e2e64ffdb1927085a4ad2", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "b51c820d322725be5057083905c51efeb6923b97573e2e64ffdb1927085a4ad2", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "b51c820d322725be5057083905c51efeb6923b97573e2e64ffdb1927085a4ad2", kill_on_drop: false }`
[INFO] [stdout] b51c820d322725be5057083905c51efeb6923b97573e2e64ffdb1927085a4ad2
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-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" "+33835004928d3bf65db4d4712e1330766263b0bd" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 3a4493e5e8f1a2b9763661a6f48c7bd63fbf4595a8cf93e946c90b4faf8ea8a1
[INFO] running `Command { std: "docker" "start" "-a" "3a4493e5e8f1a2b9763661a6f48c7bd63fbf4595a8cf93e946c90b4faf8ea8a1", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.185
[INFO] [stderr]    Compiling typenum v1.20.0
[INFO] [stderr]    Compiling bitflags v2.11.1
[INFO] [stderr]    Compiling syn v2.0.117
[INFO] [stderr]    Compiling cfg_aliases v0.2.1
[INFO] [stderr]    Compiling option-ext v0.2.0
[INFO] [stderr]    Compiling redb v4.1.0
[INFO] [stderr]    Compiling nix v0.31.2
[INFO] [stderr]    Compiling notify-types v2.1.0
[INFO] [stderr]    Compiling generic-array v0.14.7
[INFO] [stderr]    Compiling block-buffer v0.10.4
[INFO] [stderr]    Compiling crypto-common v0.1.7
[INFO] [stderr]    Compiling digest v0.10.7
[INFO] [stderr]    Compiling sha2 v0.10.9
[INFO] [stderr]    Compiling errno v0.3.14
[INFO] [stderr]    Compiling parking_lot_core v0.9.12
[INFO] [stderr]    Compiling inotify-sys v0.1.5
[INFO] [stderr]    Compiling mio v1.2.0
[INFO] [stderr]    Compiling socket2 v0.6.3
[INFO] [stderr]    Compiling dirs-sys v0.5.0
[INFO] [stderr]    Compiling signal-hook-registry v1.4.8
[INFO] [stderr]    Compiling inotify v0.11.1
[INFO] [stderr]    Compiling parking_lot v0.12.5
[INFO] [stderr]    Compiling dirs v6.0.0
[INFO] [stderr]    Compiling sysinfo v0.38.4
[INFO] [stderr]    Compiling notify v8.2.0
[INFO] [stderr]    Compiling futures-macro v0.3.32
[INFO] [stderr]    Compiling tokio-macros v2.7.0
[INFO] [stderr]    Compiling tokio v1.52.1
[INFO] [stderr]    Compiling futures-util v0.3.32
[INFO] [stderr]    Compiling futures-executor v0.3.32
[INFO] [stderr]    Compiling futures v0.3.32
[INFO] [stderr]    Compiling flashkraft-core v1.0.7 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 36.36s
[INFO] running `Command { std: "docker" "inspect" "3a4493e5e8f1a2b9763661a6f48c7bd63fbf4595a8cf93e946c90b4faf8ea8a1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "3a4493e5e8f1a2b9763661a6f48c7bd63fbf4595a8cf93e946c90b4faf8ea8a1", kill_on_drop: false }`
[INFO] [stdout] 3a4493e5e8f1a2b9763661a6f48c7bd63fbf4595a8cf93e946c90b4faf8ea8a1
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-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" "+33835004928d3bf65db4d4712e1330766263b0bd" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] bf92e1382c34daf4ec6c1feb18e26dc3dee5a098ce06939489d11a1467cfd71a
[INFO] running `Command { std: "docker" "start" "-a" "bf92e1382c34daf4ec6c1feb18e26dc3dee5a098ce06939489d11a1467cfd71a", kill_on_drop: false }`
[INFO] [stderr]    Compiling inotify v0.11.1
[INFO] [stderr]    Compiling notify-types v2.1.0
[INFO] [stderr]    Compiling nix v0.31.2
[INFO] [stderr]    Compiling getrandom v0.4.2
[INFO] [stderr]    Compiling once_cell v1.21.4
[INFO] [stderr]    Compiling tempfile v3.27.0
[INFO] [stderr]    Compiling notify v8.2.0
[INFO] [stderr]    Compiling flashkraft-core v1.0.7 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 9.19s
[INFO] running `Command { std: "docker" "inspect" "bf92e1382c34daf4ec6c1feb18e26dc3dee5a098ce06939489d11a1467cfd71a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "bf92e1382c34daf4ec6c1feb18e26dc3dee5a098ce06939489d11a1467cfd71a", kill_on_drop: false }`
[INFO] [stdout] bf92e1382c34daf4ec6c1feb18e26dc3dee5a098ce06939489d11a1467cfd71a
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-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" "+33835004928d3bf65db4d4712e1330766263b0bd" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] fcd60f2d389e004b27acbd217c31479b95c8d0136cb07cdd9bfbdfd1d16378be
[INFO] running `Command { std: "docker" "start" "-a" "fcd60f2d389e004b27acbd217c31479b95c8d0136cb07cdd9bfbdfd1d16378be", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.14s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/flashkraft_core-6bda0d6ab6997972)
[INFO] [stdout] 
[INFO] [stdout] running 174 tests
[INFO] [stdout] test commands::drive_detection::tests::test_allow_sata_usb_sda ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_allow_sata_usb_sdb ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_build_display_name_product_only ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_build_display_name_vid_pid_fallback ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_allow_sata_usb_sdc ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_build_display_name_with_label ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_extract_integer_from_real_tag ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_extract_integer_from_integer_tag ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_extract_string_tag ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_find_mount_point_different_device_not_matched ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_find_mount_point_exact_device ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_find_mount_point_p_partition_suffix ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_find_mount_point_multiple_partitions_returns_first_match ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_find_sp_device_by_vid_pid_serial ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_find_sp_device_no_match ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_find_sp_device_none_vid_returns_none ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_find_mount_point_partition ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_find_mount_point_not_found ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_find_sp_device_by_vid_pid_only ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_find_sp_device_wrong_serial_falls_back_to_vid_pid ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_find_usb_sysfs_dir_stops_at_sys_root ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_json_str_value_present ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_parse_disk_info_plist_whole_removable ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_parse_disk_info_plist_zero_size_returns_none ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_parse_hex_u16_bare ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_parse_hex_u16_invalid ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_parse_hex_u16_overflow ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_parse_hex_u16_with_prefix ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_parse_plist_string_array_missing_key ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_find_usb_sysfs_dir_no_id_vendor_in_tmp ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_parse_sp_hex_id_bare ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_json_str_value_missing_key ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_json_str_value_non_string_value ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_find_usb_sysfs_dir_finds_id_vendor_in_parent ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_parse_sp_hex_id_with_prefix ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_load_drives_sync_returns_vec ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_parse_disk_info_plist_not_whole_disk ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_parse_system_profiler_multiple_devices ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_parse_system_profiler_no_usb_data ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_parse_plist_string_array_whole_disks ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_parse_sp_hex_id_invalid ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_find_usb_sysfs_dir_finds_id_vendor_in_dir ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_parse_system_profiler_empty_items ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_parse_vid_pid_from_pnp_ffff_values ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_parse_vid_pid_from_pnp_lowercase ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_parse_vid_pid_from_pnp_standard ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_parse_vid_pid_from_pnp_empty ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_parse_vid_pid_from_pnp_no_vid ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_parse_vid_pid_from_pnp_non_usb_path ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_parse_vid_pid_from_pnp_no_pid ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_parse_system_profiler_single_device ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_parse_wmic_csv_basic ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_parse_wmic_csv_missing_pnp_column_still_parses ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_parse_wmic_csv_skips_non_physical ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_read_proc_mounts_does_not_panic ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_read_proc_mounts_parses_dev_entries ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_parse_wmic_csv_header_only ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_parse_wmic_csv_empty ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_read_sysfs_u64_valid ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_parse_wmic_csv_multiple_disks ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_skip_dm_devices ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_skip_loop_devices_loop0 ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_read_sysfs_u64_invalid ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_skip_empty_name ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_read_usb_info_from_sysfs_all_fields ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_skip_loop_devices_loop1 ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_read_usb_info_from_sysfs_empty_string_fields ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_read_usb_info_from_sysfs_missing_optional_fields ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_skip_nvme_devices ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_parse_wmic_csv_zero_size_included ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_skip_ram_devices ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_sp_speed_string_all_variants ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_sysfs_speed_trims_whitespace ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_sysfs_speed_all_variants ... ok
[INFO] [stdout] test commands::hotplug::tests::test_event_traits ... ok
[INFO] [stdout] test commands::hotplug::tests::test_translate_access_ignored ... ok
[INFO] [stdout] test commands::hotplug::tests::test_translate_create_any_arrives ... ok
[INFO] [stdout] test commands::hotplug::tests::test_hotplug_error_from_notify ... ok
[INFO] [stdout] test commands::hotplug::tests::test_translate_any_ignored ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_skip_optical_drives ... ok
[INFO] [stdout] test commands::hotplug::tests::test_hotplug_error_display ... ok
[INFO] [stdout] test commands::hotplug::tests::test_translate_other_arrives ... ok
[INFO] [stdout] test commands::hotplug::tests::test_translate_modify_metadata_ignored ... ok
[INFO] [stdout] test commands::hotplug::tests::test_translate_rename_arrives ... ok
[INFO] [stdout] test commands::hotplug::tests::test_translate_remove_any_left ... ok
[INFO] [stdout] test commands::hotplug::tests::test_translate_modify_data_ignored ... ok
[INFO] [stdout] test commands::hotplug::tests::test_translate_create_folder_arrives ... ok
[INFO] [stdout] test domain::constraints::tests::test_is_drive_large_enough ... ok
[INFO] [stdout] test domain::constraints::tests::test_is_drive_size_large ... ok
[INFO] [stdout] test commands::hotplug::tests::test_translate_create_file_arrives ... ok
[INFO] [stdout] test commands::hotplug::tests::test_translate_remove_file_left ... ok
[INFO] [stdout] test domain::constraints::tests::test_is_drive_too_small ... ok
[INFO] [stdout] test domain::constraints::tests::test_is_drive_valid ... ok
[INFO] [stdout] test domain::constraints::tests::test_mark_invalid_drives ... ok
[INFO] [stdout] test domain::constraints::tests::test_read_only_drive_error ... ok
[INFO] [stdout] test domain::drive_info::tests::test_usb_info_display_label_both ... ok
[INFO] [stdout] test domain::constraints::tests::test_system_drive_warning ... ok
[INFO] [stdout] test commands::hotplug::tests::test_watch_usb_events_does_not_panic ... ok
[INFO] [stdout] test domain::drive_info::tests::test_usb_info_display_label_fallback_to_ids ... ok
[INFO] [stdout] test domain::drive_info::tests::test_drive_equality_by_device_path ... ok
[INFO] [stdout] test domain::drive_info::tests::test_usb_info_display_label_product_only ... ok
[INFO] [stdout] test domain::drive_info::tests::test_drive_equality_ignores_usb_info ... ok
[INFO] [stdout] test domain::drive_info::tests::test_new_defaults ... ok
[INFO] [stdout] test domain::drive_info::tests::test_with_constraints_defaults_usb_info_none ... ok
[INFO] [stdout] test domain::image_info::tests::test_image_info_nonexistent_file ... ok
[INFO] [stdout] test flash_helper::tests::check_device_not_busy_eacces_is_ignored ... ok
[INFO] [stdout] test flash_helper::tests::check_device_not_busy_ebusy_returns_error ... ok
[INFO] [stdout] test flash_helper::tests::check_device_not_busy_success_returns_ok ... ok
[INFO] [stdout] test flash_helper::tests::flash_stage_progress_floor_other_stages_are_zero ... ok
[INFO] [stdout] test flash_helper::tests::check_device_not_busy_regular_file_never_ebusy ... ok
[INFO] [stdout] test flash_helper::tests::check_device_not_busy_eperm_is_ignored ... ok
[INFO] [stdout] test flash_helper::tests::flash_stage_progress_floor_rereading ... ok
[INFO] [stdout] test flash_helper::tests::flash_stage_progress_floor_verifying ... ok
[INFO] [stdout] test flash_helper::tests::flash_stage_progress_floor_syncing ... ok
[INFO] [stdout] test domain::image_info::tests::test_image_info_from_path ... ok
[INFO] [stdout] test commands::hotplug::tests::test_variant_exhaustiveness ... ok
[INFO] [stdout] test domain::drive_info::tests::test_with_usb_info_builder ... ok
[INFO] [stdout] test flash_helper::tests::test_find_mounted_partitions_nonexistent_device_returns_empty ... ok
[INFO] [stdout] test flash_helper::tests::test_find_mounted_partitions_empty_name_no_panic ... ok
[INFO] [stdout] test flash_helper::tests::test_find_mounted_partitions_linux_no_panic ... ok
[INFO] [stdout] test flash_helper::tests::test_flash_event_clone ... ok
[INFO] [stdout] test flash_helper::tests::test_find_mounted_partitions_linux_reads_proc_mounts ... ok
[INFO] [stdout] test flash_helper::tests::test_find_mounted_partitions_parses_proc_mounts_format ... ok
[INFO] [stdout] test commands::drive_detection::tests::test_read_sysfs_u64_missing_file ... ok
[INFO] [stdout] test flash_helper::tests::test_do_unmount_not_mounted_does_not_panic ... ok
[INFO] [stdout] test flash_helper::tests::test_flash_stage_display ... ok
[INFO] [stdout] test flash_helper::tests::test_flash_stage_eq ... ok
[INFO] [stdout] test flash_helper::tests::test_is_partition_of_no_false_prefix_match ... ok
[INFO] [stdout] test flash_helper::tests::test_is_partition_of_mmcblk ... ok
[INFO] [stdout] test flash_helper::tests::test_is_partition_of_windows_style_paths ... ok
[INFO] [stdout] test flash_helper::tests::test_is_partition_of_nvme ... ok
[INFO] [stdout] test flash_helper::tests::test_is_partition_of_sda ... ok
[INFO] [stdout] test flash_helper::tests::test_is_privileged_returns_bool ... ok
[INFO] [stdout] test flash_helper::tests::test_open_device_for_writing_nonexistent_mentions_path ... ok
[INFO] [stdout] test flash_helper::tests::test_pipeline_rejects_empty_image ... ok
[INFO] [stdout] test flash_helper::tests::test_pipeline_rejects_missing_device ... ok
[INFO] [stdout] test flash_helper::tests::test_pipeline_rejects_missing_image ... ok
[INFO] [stdout] test flash_helper::tests::test_reexec_as_root_does_not_panic_when_already_escalated ... ok
[INFO] [stdout] test flash_helper::tests::test_reject_partition_node_accepts_whole_disk ... ok
[INFO] [stdout] test flash_helper::tests::test_reject_partition_node_nvme ... ok
[INFO] [stdout] test flash_helper::tests::test_reject_partition_node_sda1 ... ok
[INFO] [stdout] test flash_helper::tests::test_set_real_uid_stores_value ... ok
[INFO] [stdout] test flash_helper::tests::test_sha256_empty_read_is_hash_of_empty ... ok
[INFO] [stdout] test flash_helper::tests::test_sha256_full_file ... ok
[INFO] [stdout] test flash_helper::tests::test_sha256_nonexistent_returns_error ... ok
[INFO] [stdout] test flash_helper::tests::test_sha256_partial ... ok
[INFO] [stdout] test flash_helper::tests::test_reread_partition_table_emits_log ... ok
[INFO] [stdout] test flash_helper::tests::test_sync_device_emits_log ... ok
[INFO] [stdout] test flash_helper::tests::test_unmount_device_no_partitions_emits_log ... ok
[INFO] [stdout] test flash_helper::tests::test_verify_matching_files ... ok
[INFO] [stdout] test flash_helper::tests::test_pipeline_stage_ordering ... FAILED
[INFO] [stdout] test flash_helper::tests::test_verify_only_checks_image_size_bytes ... ok
[INFO] [stdout] test flash_helper::tests::test_verify_mismatch_returns_error ... ok
[INFO] [stdout] test flash_helper::tests::test_write_image_missing_image_returns_error ... ok
[INFO] [stdout] test flash_helper::tests::verify_overall_device_phase_end ... ok
[INFO] [stdout] test flash_helper::tests::verify_overall_device_phase_midpoint ... ok
[INFO] [stdout] test flash_helper::tests::verify_overall_device_phase_start ... ok
[INFO] [stdout] test flash_helper::tests::verify_overall_image_phase_end ... ok
[INFO] [stdout] test flash_helper::tests::verify_overall_image_phase_midpoint ... ok
[INFO] [stdout] test flash_helper::tests::verify_overall_image_phase_start ... ok
[INFO] [stdout] test flash_helper::tests::verify_overall_unknown_phase_treated_as_device ... ok
[INFO] [stdout] test utils::logger::tests::fmt_bytes_bytes_range ... ok
[INFO] [stdout] test utils::logger::tests::fmt_bytes_gib_boundary ... ok
[INFO] [stdout] test utils::logger::tests::fmt_bytes_kib_boundary ... ok
[INFO] [stdout] test utils::logger::tests::fmt_bytes_mib_boundary ... ok
[INFO] [stdout] test utils::logger::tests::fmt_bytes_zero ... ok
[INFO] [stdout] test utils::logger::tests::test_debug_macros_compile ... ok
[INFO] [stdout] test flash_helper::tests::test_write_image_cancelled_mid_write ... ok
[INFO] [stdout] test flash_helper::tests::test_write_image_to_temp_file ... ok
[INFO] [stdout] test flash_helper::tests::pipeline_unmounting_precedes_busy_check_in_stage_stream ... ok
[INFO] [stdout] test flash_helper::tests::test_pipeline_emits_verifying_stage ... ok
[INFO] [stdout] test flash_helper::tests::test_pipeline_emits_rereading_stage ... ok
[INFO] [stdout] test flash_helper::tests::test_pipeline_emits_syncing_stage ... ok
[INFO] [stdout] test flash_helper::tests::test_pipeline_end_to_end_temp_files ... ok
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- flash_helper::tests::test_pipeline_stage_ordering stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'flash_helper::tests::test_pipeline_stage_ordering' (162) panicked at src/flash_helper.rs:2446:9:
[INFO] [stdout] Syncing must precede Rereading
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5d97e8815c2a - std[ec419fa8110287d5]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x5d97e8815c2a - std[ec419fa8110287d5]::backtrace_rs::backtrace::trace_unsynchronized::<std[ec419fa8110287d5]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x5d97e8815c2a - std[ec419fa8110287d5]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x5d97e8815c2a - <<std[ec419fa8110287d5]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[35159d6ffb30e017]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x5d97e882c8aa - <core[35159d6ffb30e017]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x5d97e882c8aa - core[35159d6ffb30e017]::fmt::write
[INFO] [stdout]    6:     0x5d97e881a7d2 - std[ec419fa8110287d5]::io::default_write_fmt::<alloc[a8a7aba520129d98]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/io/mod.rs:621:11
[INFO] [stdout]    7:     0x5d97e881a7d2 - <alloc[a8a7aba520129d98]::vec::Vec<u8> as std[ec419fa8110287d5]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/io/mod.rs:1976:13
[INFO] [stdout]    8:     0x5d97e87f1c6f - <std[ec419fa8110287d5]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x5d97e87f1c6f - std[ec419fa8110287d5]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x5d97e880ca59 - std[ec419fa8110287d5]::panicking::default_hook
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x5d97e873089c - <alloc[a8a7aba520129d98]::boxed::Box<dyn for<'a, 'b> core[35159d6ffb30e017]::ops::function::Fn<(&'a std[ec419fa8110287d5]::panic::PanicHookInfo<'b>,), Output = ()> + core[35159d6ffb30e017]::marker::Sync + core[35159d6ffb30e017]::marker::Send> as core[35159d6ffb30e017]::ops::function::Fn<(&std[ec419fa8110287d5]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/alloc/src/boxed.rs:2285:9
[INFO] [stdout]   12:     0x5d97e873089c - test[a8007afcd3effadc]::test_main_inner::<test[a8007afcd3effadc]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x5d97e880cc12 - <alloc[a8a7aba520129d98]::boxed::Box<dyn for<'a, 'b> core[35159d6ffb30e017]::ops::function::Fn<(&'a std[ec419fa8110287d5]::panic::PanicHookInfo<'b>,), Output = ()> + core[35159d6ffb30e017]::marker::Sync + core[35159d6ffb30e017]::marker::Send> as core[35159d6ffb30e017]::ops::function::Fn<(&std[ec419fa8110287d5]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/alloc/src/boxed.rs:2285:9
[INFO] [stdout]   14:     0x5d97e880cc12 - std[ec419fa8110287d5]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x5d97e87f1d5a - std[ec419fa8110287d5]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panicking.rs:691:13
[INFO] [stdout]   16:     0x5d97e87e92d9 - std[ec419fa8110287d5]::sys::backtrace::__rust_end_short_backtrace::<std[ec419fa8110287d5]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x5d97e87f2bfd - __rustc[cfb3622dd870db93]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x5d97e882d03c - core[35159d6ffb30e017]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x5d97e86a5493 - flashkraft_core[82a5da9eb7cdf3ad]::flash_helper::tests::test_pipeline_stage_ordering
[INFO] [stdout]                                at /opt/rustwide/workdir/src/flash_helper.rs:2446:9
[INFO] [stdout]   20:     0x5d97e86a0e97 - flashkraft_core[82a5da9eb7cdf3ad]::flash_helper::tests::test_pipeline_stage_ordering::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/flash_helper.rs:2403:38
[INFO] [stdout]   21:     0x5d97e86eb456 - <flashkraft_core[82a5da9eb7cdf3ad]::flash_helper::tests::test_pipeline_stage_ordering::{closure#0} as core[35159d6ffb30e017]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   22:     0x5d97e8723b7b - <fn() -> core[35159d6ffb30e017]::result::Result<(), alloc[a8a7aba520129d98]::string::String> as core[35159d6ffb30e017]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   23:     0x5d97e8723b7b - test[a8007afcd3effadc]::__rust_begin_short_backtrace::<core[35159d6ffb30e017]::result::Result<(), alloc[a8a7aba520129d98]::string::String>, fn() -> core[35159d6ffb30e017]::result::Result<(), alloc[a8a7aba520129d98]::string::String>>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/test/src/lib.rs:724:18
[INFO] [stdout]   24:     0x5d97e873136b - test[a8007afcd3effadc]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/test/src/lib.rs:747:74
[INFO] [stdout]   25:     0x5d97e873136b - <core[35159d6ffb30e017]::panic::unwind_safe::AssertUnwindSafe<test[a8007afcd3effadc]::run_test_in_process::{closure#0}> as core[35159d6ffb30e017]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   26:     0x5d97e873136b - std[ec419fa8110287d5]::panicking::catch_unwind::do_call::<core[35159d6ffb30e017]::panic::unwind_safe::AssertUnwindSafe<test[a8007afcd3effadc]::run_test_in_process::{closure#0}>, core[35159d6ffb30e017]::result::Result<(), alloc[a8a7aba520129d98]::string::String>>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panicking.rs:581:40
[INFO] [stdout]   27:     0x5d97e873136b - std[ec419fa8110287d5]::panicking::catch_unwind::<core[35159d6ffb30e017]::result::Result<(), alloc[a8a7aba520129d98]::string::String>, core[35159d6ffb30e017]::panic::unwind_safe::AssertUnwindSafe<test[a8007afcd3effadc]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panicking.rs:544:19
[INFO] [stdout]   28:     0x5d97e873136b - std[ec419fa8110287d5]::panic::catch_unwind::<core[35159d6ffb30e017]::panic::unwind_safe::AssertUnwindSafe<test[a8007afcd3effadc]::run_test_in_process::{closure#0}>, core[35159d6ffb30e017]::result::Result<(), alloc[a8a7aba520129d98]::string::String>>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panic.rs:359:14
[INFO] [stdout]   29:     0x5d97e873136b - test[a8007afcd3effadc]::run_test_in_process
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/test/src/lib.rs:747:27
[INFO] [stdout]   30:     0x5d97e873136b - test[a8007afcd3effadc]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/test/src/lib.rs:668:43
[INFO] [stdout]   31:     0x5d97e872bd44 - test[a8007afcd3effadc]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/test/src/lib.rs:698:41
[INFO] [stdout]   32:     0x5d97e872bd44 - std[ec419fa8110287d5]::sys::backtrace::__rust_begin_short_backtrace::<test[a8007afcd3effadc]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   33:     0x5d97e8733f72 - std[ec419fa8110287d5]::thread::lifecycle::spawn_unchecked::<test[a8007afcd3effadc]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   34:     0x5d97e8733f72 - <core[35159d6ffb30e017]::panic::unwind_safe::AssertUnwindSafe<std[ec419fa8110287d5]::thread::lifecycle::spawn_unchecked<test[a8007afcd3effadc]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[35159d6ffb30e017]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   35:     0x5d97e8733f72 - std[ec419fa8110287d5]::panicking::catch_unwind::do_call::<core[35159d6ffb30e017]::panic::unwind_safe::AssertUnwindSafe<std[ec419fa8110287d5]::thread::lifecycle::spawn_unchecked<test[a8007afcd3effadc]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panicking.rs:581:40
[INFO] [stdout]   36:     0x5d97e8733f72 - std[ec419fa8110287d5]::panicking::catch_unwind::<(), core[35159d6ffb30e017]::panic::unwind_safe::AssertUnwindSafe<std[ec419fa8110287d5]::thread::lifecycle::spawn_unchecked<test[a8007afcd3effadc]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panicking.rs:544:19
[INFO] [stdout]   37:     0x5d97e8733f72 - std[ec419fa8110287d5]::panic::catch_unwind::<core[35159d6ffb30e017]::panic::unwind_safe::AssertUnwindSafe<std[ec419fa8110287d5]::thread::lifecycle::spawn_unchecked<test[a8007afcd3effadc]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panic.rs:359:14
[INFO] [stdout]   38:     0x5d97e8733f72 - std[ec419fa8110287d5]::thread::lifecycle::spawn_unchecked::<test[a8007afcd3effadc]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   39:     0x5d97e8733f72 - <std[ec419fa8110287d5]::thread::lifecycle::spawn_unchecked<test[a8007afcd3effadc]::run_test::{closure#1}, ()>::{closure#1} as core[35159d6ffb30e017]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   40:     0x5d97e8814cef - <alloc[a8a7aba520129d98]::boxed::Box<dyn core[35159d6ffb30e017]::ops::function::FnOnce<(), Output = ()> + core[35159d6ffb30e017]::marker::Send> as core[35159d6ffb30e017]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/alloc/src/boxed.rs:2271:9
[INFO] [stdout]   41:     0x5d97e8814cef - <std[ec419fa8110287d5]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   42:     0x7a463d245aa4 - <unknown>
[INFO] [stdout]   43:     0x7a463d2d2a64 - clone
[INFO] [stdout]   44:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     flash_helper::tests::test_pipeline_stage_ordering
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 173 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 2.48s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--lib`
[INFO] running `Command { std: "docker" "inspect" "fcd60f2d389e004b27acbd217c31479b95c8d0136cb07cdd9bfbdfd1d16378be", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "fcd60f2d389e004b27acbd217c31479b95c8d0136cb07cdd9bfbdfd1d16378be", kill_on_drop: false }`
[INFO] [stdout] fcd60f2d389e004b27acbd217c31479b95c8d0136cb07cdd9bfbdfd1d16378be
