[INFO] fetching crate agv 0.3.0...
[INFO] testing agv-0.3.0 against 1.98.0-beta.1 for beta-1.98-1
[INFO] extracting crate agv 0.3.0 into /workspace/builds/worker-1-tc2/source
[INFO] removed /workspace/builds/worker-1-tc2/source/.cargo/config.toml
[INFO] started tweaking crates.io crate agv 0.3.0
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate agv 0.3.0
[INFO] tweaked toml for crates.io crate agv 0.3.0 written to /workspace/builds/worker-1-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate agv 0.3.0 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 crates.io crate agv 0.3.0 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-1-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-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] f6508f1f1691bd76fbf56374dc1c0e2f05406234719e2a970cead3691f1e7c35
[INFO] running `Command { std: "docker" "start" "f6508f1f1691bd76fbf56374dc1c0e2f05406234719e2a970cead3691f1e7c35", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "f6508f1f1691bd76fbf56374dc1c0e2f05406234719e2a970cead3691f1e7c35", 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" "f6508f1f1691bd76fbf56374dc1c0e2f05406234719e2a970cead3691f1e7c35" "/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" "f6508f1f1691bd76fbf56374dc1c0e2f05406234719e2a970cead3691f1e7c35", 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" "f6508f1f1691bd76fbf56374dc1c0e2f05406234719e2a970cead3691f1e7c35" "/opt/rustwide/cargo-home/bin/cargo" "+1.98.0-beta.1" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling proc-macro2 v1.0.106
[INFO] [stderr]    Compiling quote v1.0.44
[INFO] [stderr]    Compiling unicode-ident v1.0.24
[INFO] [stderr]    Compiling libc v0.2.182
[INFO] [stderr]    Compiling stable_deref_trait v1.2.1
[INFO] [stderr]    Compiling bytes v1.11.1
[INFO] [stderr]    Compiling once_cell v1.21.3
[INFO] [stderr]    Compiling futures-core v0.3.32
[INFO] [stderr]    Compiling serde_core v1.0.228
[INFO] [stderr]    Compiling cfg-if v1.0.4
[INFO] [stderr]    Compiling itoa v1.0.17
[INFO] [stderr]    Compiling find-msvc-tools v0.1.9
[INFO] [stderr]    Compiling smallvec v1.15.1
[INFO] [stderr]    Compiling writeable v0.6.2
[INFO] [stderr]    Compiling litemap v0.8.1
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling typenum v1.19.0
[INFO] [stderr]    Compiling zeroize v1.8.2
[INFO] [stderr]    Compiling icu_normalizer_data v2.1.1
[INFO] [stderr]    Compiling icu_properties_data v2.1.2
[INFO] [stderr]    Compiling tracing-core v0.1.36
[INFO] [stderr]    Compiling httparse v1.10.1
[INFO] [stderr]    Compiling rustls-pki-types v1.14.0
[INFO] [stderr]    Compiling percent-encoding v2.3.2
[INFO] [stderr]    Compiling cc v1.2.56
[INFO] [stderr]    Compiling untrusted v0.9.0
[INFO] [stderr]    Compiling futures-task v0.3.32
[INFO] [stderr]    Compiling slab v0.4.12
[INFO] [stderr]    Compiling rustls v0.23.36
[INFO] [stderr]    Compiling futures-channel v0.3.32
[INFO] [stderr]    Compiling subtle v2.6.1
[INFO] [stderr]    Compiling anstyle-parse v0.2.7
[INFO] [stderr]    Compiling form_urlencoded v1.2.2
[INFO] [stderr]    Compiling sync_wrapper v1.0.2
[INFO] [stderr]    Compiling ipnet v2.11.0
[INFO] [stderr]    Compiling anstyle v1.0.13
[INFO] [stderr]    Compiling hashbrown v0.16.1
[INFO] [stderr]    Compiling regex-syntax v0.8.9
[INFO] [stderr]    Compiling colorchoice v1.0.4
[INFO] [stderr]    Compiling log v0.4.29
[INFO] [stderr]    Compiling is_terminal_polyfill v1.70.2
[INFO] [stderr]    Compiling anstyle-query v1.1.5
[INFO] [stderr]    Compiling equivalent v1.0.2
[INFO] [stderr]    Compiling zmij v1.0.21
[INFO] [stderr]    Compiling bitflags v2.11.0
[INFO] [stderr]    Compiling portable-atomic v1.13.1
[INFO] [stderr]    Compiling webpki-roots v1.0.7
[INFO] [stderr]    Compiling anstream v0.6.21
[INFO] [stderr]    Compiling futures-sink v0.3.32
[INFO] [stderr]    Compiling iri-string v0.7.10
[INFO] [stderr]    Compiling serde_json v1.0.149
[INFO] [stderr]    Compiling generic-array v0.14.7
[INFO] [stderr]    Compiling rustix v1.1.3
[INFO] [stderr]    Compiling thiserror v2.0.18
[INFO] [stderr]    Compiling ryu v1.0.23
[INFO] [stderr]    Compiling anyhow v1.0.101
[INFO] [stderr]    Compiling http v1.4.0
[INFO] [stderr]    Compiling memchr v2.8.0
[INFO] [stderr]    Compiling clap_lex v1.0.0
[INFO] [stderr]    Compiling winnow v0.7.14
[INFO] [stderr]    Compiling toml_write v0.1.2
[INFO] [stderr]    Compiling unicode-width v0.2.2
[INFO] [stderr]    Compiling indexmap v2.13.0
[INFO] [stderr]    Compiling lazy_static v1.5.0
[INFO] [stderr]    Compiling sharded-slab v0.1.7
[INFO] [stderr]    Compiling clap_builder v4.5.60
[INFO] [stderr]    Compiling block-buffer v0.10.4
[INFO] [stderr]    Compiling crypto-common v0.1.7
[INFO] [stderr]    Compiling syn v2.0.116
[INFO] [stderr]    Compiling digest v0.10.7
[INFO] [stderr]    Compiling errno v0.3.14
[INFO] [stderr]    Compiling socket2 v0.6.2
[INFO] [stderr]    Compiling mio v1.1.1
[INFO] [stderr]    Compiling getrandom v0.2.17
[INFO] [stderr]    Compiling signal-hook-registry v1.4.8
[INFO] [stderr]    Compiling console v0.15.11
[INFO] [stderr]    Compiling tracing-log v0.2.0
[INFO] [stderr]    Compiling thread_local v1.1.9
[INFO] [stderr]    Compiling http-body v1.0.1
[INFO] [stderr]    Compiling ring v0.17.14
[INFO] [stderr]    Compiling http-body-util v0.1.3
[INFO] [stderr]    Compiling cpufeatures v0.2.17
[INFO] [stderr]    Compiling number_prefix v0.4.0
[INFO] [stderr]    Compiling linux-raw-sys v0.11.0
[INFO] [stderr]    Compiling regex-automata v0.4.14
[INFO] [stderr]    Compiling agv v0.3.0 (/opt/rustwide/workdir)
[INFO] [stderr]    Compiling nu-ansi-term v0.50.3
[INFO] [stderr]    Compiling indicatif v0.17.11
[INFO] [stderr]    Compiling sha2 v0.10.9
[INFO] [stderr]    Compiling sysinfo v0.32.1
[INFO] [stderr]    Compiling matchers v0.2.0
[INFO] [stderr]    Compiling synstructure v0.13.2
[INFO] [stderr]    Compiling zerofrom-derive v0.1.6
[INFO] [stderr]    Compiling yoke-derive v0.8.1
[INFO] [stderr]    Compiling zerovec-derive v0.11.2
[INFO] [stderr]    Compiling displaydoc v0.2.5
[INFO] [stderr]    Compiling tokio-macros v2.6.0
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling futures-macro v0.3.32
[INFO] [stderr]    Compiling tracing-attributes v0.1.31
[INFO] [stderr]    Compiling clap_derive v4.5.55
[INFO] [stderr]    Compiling thiserror-impl v2.0.18
[INFO] [stderr]    Compiling async-trait v0.1.89
[INFO] [stderr]    Compiling futures-util v0.3.32
[INFO] [stderr]    Compiling tokio v1.49.0
[INFO] [stderr]    Compiling rustls-webpki v0.103.9
[INFO] [stderr]    Compiling zerofrom v0.1.6
[INFO] [stderr]    Compiling yoke v0.8.1
[INFO] [stderr]    Compiling tracing v0.1.44
[INFO] [stderr]    Compiling zerotrie v0.2.3
[INFO] [stderr]    Compiling tracing-subscriber v0.3.22
[INFO] [stderr]    Compiling zerovec v0.11.5
[INFO] [stderr]    Compiling clap v4.5.60
[INFO] [stderr]    Compiling tinystr v0.8.2
[INFO] [stderr]    Compiling potential_utf v0.1.4
[INFO] [stderr]    Compiling icu_collections v2.1.1
[INFO] [stderr]    Compiling icu_locale_core v2.1.1
[INFO] [stderr]    Compiling icu_provider v2.1.1
[INFO] [stderr]    Compiling icu_normalizer v2.1.1
[INFO] [stderr]    Compiling icu_properties v2.1.2
[INFO] [stderr]    Compiling serde_spanned v0.6.9
[INFO] [stderr]    Compiling toml_datetime v0.6.11
[INFO] [stderr]    Compiling serde_urlencoded v0.7.1
[INFO] [stderr]    Compiling toml_edit v0.22.27
[INFO] [stderr]    Compiling idna_adapter v1.2.1
[INFO] [stderr]    Compiling idna v1.1.0
[INFO] [stderr]    Compiling url v2.5.8
[INFO] [stderr]    Compiling toml v0.8.23
[INFO] [stderr]    Compiling hyper v1.8.1
[INFO] [stderr]    Compiling tower v0.5.3
[INFO] [stderr]    Compiling tokio-rustls v0.26.4
[INFO] [stderr]    Compiling tokio-util v0.7.18
[INFO] [stderr]    Compiling tower-http v0.6.8
[INFO] [stderr]    Compiling hyper-util v0.1.20
[INFO] [stderr]    Compiling hyper-rustls v0.27.7
[INFO] [stderr]    Compiling reqwest v0.12.28
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 1m 09s
[INFO] running `Command { std: "docker" "inspect" "f6508f1f1691bd76fbf56374dc1c0e2f05406234719e2a970cead3691f1e7c35", 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" "f6508f1f1691bd76fbf56374dc1c0e2f05406234719e2a970cead3691f1e7c35" "/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 aho-corasick v1.1.4
[INFO] [stderr]    Compiling regex-syntax v0.8.9
[INFO] [stderr]    Compiling autocfg v1.5.0
[INFO] [stderr]    Compiling parking_lot_core v0.9.12
[INFO] [stderr]    Compiling predicates-core v1.0.10
[INFO] [stderr]    Compiling getrandom v0.4.1
[INFO] [stderr]    Compiling lock_api v0.4.14
[INFO] [stderr]    Compiling agv v0.3.0 (/opt/rustwide/workdir)
[INFO] [stderr]    Compiling difflib v0.4.0
[INFO] [stderr]    Compiling assert_cmd v2.1.2
[INFO] [stderr]    Compiling normalize-line-endings v0.3.0
[INFO] [stderr]    Compiling termtree v0.5.1
[INFO] [stderr]    Compiling sdd v3.0.10
[INFO] [stderr]    Compiling futures-executor v0.3.32
[INFO] [stderr]    Compiling serial_test_derive v3.4.0
[INFO] [stderr]    Compiling wait-timeout v0.2.1
[INFO] [stderr]    Compiling fastrand v2.3.0
[INFO] [stderr]    Compiling predicates-tree v1.0.13
[INFO] [stderr]    Compiling scc v2.4.0
[INFO] [stderr]    Compiling num-traits v0.2.19
[INFO] [stderr]    Compiling tempfile v3.25.0
[INFO] [stderr]    Compiling parking_lot v0.12.5
[INFO] [stderr]    Compiling float-cmp v0.10.0
[INFO] [stderr]    Compiling serial_test v3.4.0
[INFO] [stderr]    Compiling regex-automata v0.4.14
[INFO] [stderr]    Compiling matchers v0.2.0
[INFO] [stderr]    Compiling regex v1.12.3
[INFO] [stderr]    Compiling bstr v1.12.1
[INFO] [stderr]    Compiling tracing-subscriber v0.3.22
[INFO] [stderr]    Compiling predicates v3.1.4
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 31.15s
[INFO] running `Command { std: "docker" "inspect" "f6508f1f1691bd76fbf56374dc1c0e2f05406234719e2a970cead3691f1e7c35", 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" "f6508f1f1691bd76fbf56374dc1c0e2f05406234719e2a970cead3691f1e7c35" "/opt/rustwide/cargo-home/bin/cargo" "+1.98.0-beta.1" "test" "--frozen", kill_on_drop: false }`
[INFO] [stderr]    Compiling agv v0.3.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 23.76s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/agv-c3a52ce68f351de3)
[INFO] [stdout] 
[INFO] [stdout] running 345 tests
[INFO] [stdout] test config::tests::auto_forward_name_validation_rejects_uppercase_and_hyphens ... ok
[INFO] [stdout] test cli::tests::ssh_clap_preserves_dash_dash_after_value ... ok
[INFO] [stdout] test cli::tests::ssh_clap_bare_interactive ... ok
[INFO] [stdout] test config::tests::auto_forward_rejects_unknown_toml_fields ... ok
[INFO] [stdout] test cli::tests::ssh_clap_accepts_lone_hyphen_value ... ok
[INFO] [stdout] test cli::tests::ssh_clap_swallows_leading_dash_dash ... ok
[INFO] [stdout] test config::tests::auto_forwards_parse_from_mixin ... ok
[INFO] [stdout] test config::tests::build_from_cli_invalid_file_format ... ok
[INFO] [stdout] test config::tests::auto_forwards_duplicate_keys_across_layers_error ... ok
[INFO] [stdout] test config::tests::build_from_cli_image_flag ... ok
[INFO] [stdout] test config::tests::build_from_cli_provisions ... ok
[INFO] [stdout] test config::tests::build_from_cli_allows_qemu_with_idle_suspend ... ok
[INFO] [stdout] test config::tests::family_steps_inherit_source_tag_from_mixin_name ... ok
[INFO] [stdout] test config::tests::build_from_cli_cli_overrides_config ... ok
[INFO] [stdout] test config::tests::build_from_cli_with_config_file ... ok
[INFO] [stdout] test config::tests::auto_forwards_resolve_through_inheritance_and_includes ... ok
[INFO] [stdout] test config::tests::default_backend_is_qemu_off_macos_apple_silicon ... ok
[INFO] [stdout] test config::tests::distro_agnostic_mixin_runs_on_any_family ... ok
[INFO] [stdout] test config::tests::empty_run_array_is_an_error ... ok
[INFO] [stdout] test config::tests::build_from_cli_parses_files ... ok
[INFO] [stdout] test config::tests::build_from_cli_minimal ... ok
[INFO] [stdout] test config::tests::child_image_inherits_os_family_from_parent ... ok
[INFO] [stdout] test config::tests::merge_accumulates_forwards ... ok
[INFO] [stdout] test config::tests::build_from_cli_allows_avf_without_idle_suspend ... ok
[INFO] [stdout] test config::tests::file_entry_optional_field_parses_and_defaults_to_false ... ok
[INFO] [stdout] test config::tests::parse_labels_empty_value_explicit_form ... ok
[INFO] [stdout] test config::tests::merge_lists_accumulate ... ok
[INFO] [stdout] test config::tests::parse_labels_first_equals_is_the_separator ... ok
[INFO] [stdout] test config::tests::merge_scalars_child_wins ... ok
[INFO] [stdout] test config::tests::parse_labels_rejects_empty_key ... ok
[INFO] [stdout] test config::tests::parse_labels_basic_kv_pairs ... ok
[INFO] [stdout] test config::tests::resolve_missing_image_errors ... ok
[INFO] [stdout] test config::tests::mixed_string_and_array_blocks_concatenate_in_order ... ok
[INFO] [stdout] test config::tests::resolve_collects_and_normalizes_forwards ... ok
[INFO] [stdout] test config::tests::resolve_root_defaults ... ok
[INFO] [stdout] test config::tests::resolve_rejects_duplicate_forward ... ok
[INFO] [stdout] test config::tests::resolve_carries_top_level_notes_into_config_notes ... ok
[INFO] [stdout] test config::tests::mixin_with_families_section_parses ... ok
[INFO] [stdout] test config::tests::fedora_base_plus_devtools_picks_dnf_steps ... ok
[INFO] [stdout] test config::tests::resolve_rejects_invalid_forward ... ok
[INFO] [stdout] test config::tests::parse_labels_bare_key_maps_to_empty_value ... ok
[INFO] [stdout] test config::tests::parse_labels_rejects_duplicate_key_in_one_invocation ... ok
[INFO] [stdout] test config::tests::resolve_merges_forwards_through_inheritance ... ok
[INFO] [stdout] test config::tests::root_image_without_os_family_is_an_error ... ok
[INFO] [stdout] test config::tests::root_image_with_os_family_resolves ... ok
[INFO] [stdout] test config::tests::mixin_with_supports_parses ... ok
[INFO] [stdout] test config::tests::resolve_two_layers ... ok
[INFO] [stdout] test config::tests::run_array_combined_with_script_is_an_error ... ok
[INFO] [stdout] test config::tests::resolve_root_image ... ok
[INFO] [stdout] test config::tests::resolved_config_loads_with_default_os_family_for_legacy ... ok
[INFO] [stdout] test config::tests::resolved_config_without_backend_field_loads_as_qemu ... ok
[INFO] [stdout] test dirs::tests::all_dirs_are_under_data_dir ... ok
[INFO] [stdout] test config::tests::supports_must_include_every_family_with_steps ... ok
[INFO] [stdout] test dirs::tests::instances_dir_ends_with_instances ... ok
[INFO] [stdout] test dirs::tests::templates_dir_ends_with_templates ... ok
[INFO] [stdout] test dirs::tests::data_dir_ends_with_agv ... ok
[INFO] [stdout] test config::tests::unsupported_family_errors_with_clear_message ... ok
[INFO] [stdout] test config::tests::resolve_with_include ... ok
[INFO] [stdout] test config::tests::run_as_array_works_for_setup_too ... ok
[INFO] [stdout] test config::tests::resolved_config_also_accepts_array_form ... ok
[INFO] [stdout] test config::tests::run_as_array_expands_to_multiple_steps ... ok
[INFO] [stdout] test doctor::tests::doctor_report_checks_serialize_as_array ... ok
[INFO] [stdout] test doctor::tests::check_json_schema_pin ... ok
[INFO] [stdout] test dirs::tests::image_cache_dir_ends_with_expected_path ... ok
[INFO] [stdout] test dirs::tests::images_dir_ends_with_images ... ok
[INFO] [stdout] test config::tests::run_as_string_parses_as_single_step ... ok
[INFO] [stdout] test dirs::tests::instance_dir_appends_name ... ok
[INFO] [stdout] test doctor::tests::doctor_report_json_schema_pin ... ok
[INFO] [stdout] test doctor::tests::non_macos_check_list_omits_avf_runner ... ok
[INFO] [stdout] test dirs::tests::specs_dir_ends_with_specs ... ok
[INFO] [stdout] test error::tests::exit_code_walks_the_anyhow_chain ... ok
[INFO] [stdout] test error::tests::exit_code_already_exists_variants ... ok
[INFO] [stdout] test error::tests::unknown_error_falls_through_to_generic ... ok
[INFO] [stdout] test doctor::tests::runner_protocol_check_json_shapes ... ok
[INFO] [stdout] test error::tests::exit_code_not_found_variants ... ok
[INFO] [stdout] test error::tests::exit_code_wrong_state_variants ... ok
[INFO] [stdout] test error::tests::exit_code_host_capacity ... ok
[INFO] [stdout] test forward::tests::forward_json_origin_serializes_lowercase ... ok
[INFO] [stdout] test config::tests::unsupported_family_via_supports_errors_with_clear_message ... ok
[INFO] [stdout] test forward::tests::forward_json_schema_pin ... ok
[INFO] [stdout] test forward::tests::display_roundtrip_host_guest ... ok
[INFO] [stdout] test forward::tests::display_roundtrip_single_port ... ok
[INFO] [stdout] test forward::tests::parse_specs_collects_all ... ok
[INFO] [stdout] test forward::tests::parses_host_guest ... ok
[INFO] [stdout] test forward::tests::rejects_empty ... ok
[INFO] [stdout] test forward::tests::parses_single_port ... ok
[INFO] [stdout] test forward::tests::rejects_non_numeric ... ok
[INFO] [stdout] test config::tests::save_and_reload_roundtrips ... ok
[INFO] [stdout] test forward::tests::parse_specs_reports_first_error ... ok
[INFO] [stdout] test forward::tests::rejects_missing_guest_with_colon ... ok
[INFO] [stdout] test forward::tests::kill_supervisor_tolerates_dead_pid ... ok
[INFO] [stdout] test forward::tests::rejects_out_of_range ... ok
[INFO] [stdout] test forward::tests::rejects_proto_suffix_with_helpful_message ... ok
[INFO] [stdout] test forward::tests::rejects_zero_port ... ok
[INFO] [stdout] test forward::tests::trims_whitespace ... ok
[INFO] [stdout] test forward::tests::active_forwards_roundtrip ... ok
[INFO] [stdout] test forward::tests::active_forwards_empty_when_missing ... ok
[INFO] [stdout] test forward::tests::clear_active_is_idempotent ... ok
[INFO] [stdout] test forward::tests::active_forwards_empty_write_removes_file ... ok
[INFO] [stdout] test forward::tests::validate_unique_rejects_duplicate_host_port ... ok
[INFO] [stdout] test gui::tests::launcher_tool_is_defined_for_this_platform ... ok
[INFO] [stdout] test idle_watcher::tests::host_wake_not_detected_at_slight_overshoot ... ok
[INFO] [stdout] test idle_watcher::tests::idle_when_no_session_and_low_load ... ok
[INFO] [stdout] test forward::tests::validate_unique_accepts_distinct_host_ports ... ok
[INFO] [stdout] test idle_watcher::tests::parse_loadavg_extracts_five_min ... ok
[INFO] [stdout] test idle_watcher::tests::active_when_load_at_threshold ... ok
[INFO] [stdout] test idle_watcher::tests::active_when_session_present ... ok
[INFO] [stdout] test idle_watcher::tests::host_wake_detected_for_large_gap ... ok
[INFO] [stdout] test idle_watcher::tests::host_wake_not_detected_at_normal_interval ... ok
[INFO] [stdout] test idle_watcher::tests::host_wake_not_detected_on_backward_jump ... ok
[INFO] [stdout] test image::tests::cache_entry_json_schema_pin ... ok
[INFO] [stdout] test idle_watcher::tests::idle_with_zero_threshold_is_unreachable ... ok
[INFO] [stdout] test idle_watcher::tests::parse_loadavg_rejects_garbage ... ok
[INFO] [stdout] test idle_watcher::tests::active_when_load_above_threshold ... ok
[INFO] [stdout] test image::tests::filename_from_url_no_extension_falls_back_to_hash ... ok
[INFO] [stdout] test image::tests::filename_from_url_normal ... ok
[INFO] [stdout] test image::tests::filename_from_url_trailing_slash ... ok
[INFO] [stdout] test image::tests::filename_from_url_ubuntu_default ... ok
[INFO] [stdout] test image::tests::normalize_size_case_insensitive ... ok
[INFO] [stdout] test image::tests::normalize_size_invalid_unit_fails ... ok
[INFO] [stdout] test image::tests::normalize_size_no_unit_fails ... ok
[INFO] [stdout] test forward::tests::kill_all_and_clear_kills_listed_pids ... ok
[INFO] [stdout] test image::tests::normalize_size_short_form_unchanged ... ok
[INFO] [stdout] test image::tests::normalize_size_strips_b_suffix ... ok
[INFO] [stdout] test image::tests::parse_checksum_bad_prefix ... ok
[INFO] [stdout] test image::tests::parse_checksum_non_hex_chars ... ok
[INFO] [stdout] test image::tests::parse_checksum_none ... ok
[INFO] [stdout] test image::tests::parse_checksum_sha256_short_hex ... ok
[INFO] [stdout] test image::tests::parse_checksum_sha256_valid ... ok
[INFO] [stdout] test image::tests::parse_checksum_sha512_short_hex ... ok
[INFO] [stdout] test image::tests::parse_checksum_sha512_valid ... ok
[INFO] [stdout] test image::tests::parse_disk_size_case_insensitive ... ok
[INFO] [stdout] test image::tests::parse_disk_size_no_unit_fails ... ok
[INFO] [stdout] test config::tests::build_from_cli_rejects_avf_with_idle_suspend ... ok
[INFO] [stdout] test image::tests::parse_disk_size_units ... ok
[INFO] [stdout] test image::tests::parse_disk_size_unknown_unit_fails ... ok
[INFO] [stdout] test image::tests::parse_disk_size_with_b_suffix ... ok
[INFO] [stdout] test images::tests::image_json_schema_pin ... ok
[INFO] [stdout] test images::tests::image_json_user_source_serializes_path ... ok
[INFO] [stdout] test images::tests::alias_ubuntu_resolves_to_ubuntu_24_04 ... ok
[INFO] [stdout] test images::tests::alias_fedora_resolves_to_fedora_43 ... ok
[INFO] [stdout] test images::tests::alias_debian_resolves_to_debian_12 ... ok
[INFO] [stdout] test image::tests::sha256_file_known_digest ... ok
[INFO] [stdout] test image::tests::sha512_file_known_digest ... ok
[INFO] [stdout] test images::tests::lookup_builtin_debian_12 ... ok
[INFO] [stdout] test images::tests::lookup_builtin_docker ... ok
[INFO] [stdout] test images::tests::lookup_builtin_codex ... ok
[INFO] [stdout] test images::tests::lookup_builtin_gemini ... ok
[INFO] [stdout] test images::tests::lookup_builtin_devtools ... ok
[INFO] [stdout] test images::tests::lookup_builtin_fedora_43 ... ok
[INFO] [stdout] test images::tests::lookup_builtin_gh ... ok
[INFO] [stdout] test images::tests::lookup_builtin_claude ... ok
[INFO] [stdout] test images::tests::list_all_does_not_duplicate_aliases ... ok
[INFO] [stdout] test images::tests::lookup_builtin_nodejs ... ok
[INFO] [stdout] test images::tests::lookup_builtin_openclaw ... ok
[INFO] [stdout] test images::tests::lookup_builtin_oh_my_zsh ... ok
[INFO] [stdout] test images::tests::lookup_builtin_ubuntu ... ok
[INFO] [stdout] test images::tests::lookup_builtin_zsh ... ok
[INFO] [stdout] test images::tests::lookup_builtin_rust ... ok
[INFO] [stdout] test images::tests::lookup_builtin_uv ... ok
[INFO] [stdout] test images::tests::lookup_nonexistent ... ok
[INFO] [stdout] test init::tests::default_content_points_to_examples ... ok
[INFO] [stdout] test init::tests::default_content_is_valid_toml ... ok
[INFO] [stdout] test init::tests::default_content_uses_correct_template_syntax ... ok
[INFO] [stdout] test init::tests::all_templates_parse_as_valid_toml ... ok
[INFO] [stdout] test interactive::tests::a_runs_all ... ok
[INFO] [stdout] test interactive::tests::edit_empty_keeps_original ... ok
[INFO] [stdout] test interactive::tests::edit_replaces_command ... ok
[INFO] [stdout] test interactive::tests::empty_default_is_yes ... ok
[INFO] [stdout] test init::tests::run_default_creates_file ... ok
[INFO] [stdout] test init::tests::run_unknown_template_errors ... ok
[INFO] [stdout] test init::tests::run_fails_if_exists_without_force ... ok
[INFO] [stdout] test init::tests::run_writes_to_custom_output_path ... ok
[INFO] [stdout] test interactive::tests::eof_quits ... ok
[INFO] [stdout] test init::tests::run_template_claude ... ok
[INFO] [stdout] test init::tests::run_force_overwrites ... ok
[INFO] [stdout] test interactive::tests::invalid_then_valid ... ok
[INFO] [stdout] test interactive::tests::n_skips ... ok
[INFO] [stdout] test interactive::tests::q_quits ... ok
[INFO] [stdout] test interactive::tests::uppercase_yes_works ... ok
[INFO] [stdout] test interactive::tests::yes_runs_command_unchanged ... ok
[INFO] [stdout] test interactive::tests::user_quit_error_message ... ok
[INFO] [stdout] test manual_steps::tests::render_emits_one_bullet_per_step ... ok
[INFO] [stdout] test locks::tests::acquire_creates_parent_dir_if_missing ... ok
[INFO] [stdout] test manual_steps::tests::render_lists_config_steps_above_mixin_steps ... ok
[INFO] [stdout] test manual_steps::tests::render_lists_mixin_steps_with_attribution ... ok
[INFO] [stdout] test manual_steps::tests::render_returns_none_when_no_steps ... ok
[INFO] [stdout] test resources::tests::check_capacity_at_exact_threshold_passes ... ok
[INFO] [stdout] test resources::tests::check_capacity_force_bypasses_threshold ... ok
[INFO] [stdout] test resources::tests::check_capacity_passes_when_under_threshold ... ok
[INFO] [stdout] test resources::tests::check_capacity_refuses_when_over_threshold ... ok
[INFO] [stdout] test resources::tests::check_capacity_skips_when_host_total_unknown ... ok
[INFO] [stdout] test specs::tests::lookup_builtin_large ... ok
[INFO] [stdout] test specs::tests::lookup_builtin_small ... ok
[INFO] [stdout] test specs::tests::lookup_builtin_medium ... ok
[INFO] [stdout] test specs::tests::lookup_nonexistent_returns_none ... ok
[INFO] [stdout] test specs::tests::spec_json_schema_pin ... ok
[INFO] [stdout] test ssh::tests::base_ssh_args_contains_expected_flags ... ok
[INFO] [stdout] test specs::tests::lookup_builtin_xlarge ... ok
[INFO] [stdout] test specs::tests::list_all_includes_all_builtins ... ok
[INFO] [stdout] test ssh::tests::expand_vm_path_absolute_local_unchanged ... ok
[INFO] [stdout] test ssh::tests::expand_vm_path_custom_user_and_host ... ok
[INFO] [stdout] test ssh::tests::expand_vm_path_local_unchanged ... ok
[INFO] [stdout] test ssh::tests::expand_vm_path_with_absolute_remote ... ok
[INFO] [stdout] test ssh::tests::expand_vm_path_with_colon_prefix ... ok
[INFO] [stdout] test ssh_config::tests::host_entry_contains_all_fields_qemu ... ok
[INFO] [stdout] test resources::tests::host_probe_returns_nonzero_values ... ok
[INFO] [stdout] test ssh_config::tests::host_entry_uses_guest_ip_for_avf ... ok
[INFO] [stdout] test ssh_config::tests::host_entry_quotes_identity_file ... ok
[INFO] [stdout] test ssh_config::tests::managed_config_path_is_under_data_dir ... ok
[INFO] [stdout] test ssh_config::tests::include_block_quotes_path ... ok
[INFO] [stdout] test ssh::tests::ssh_port_missing_file_errors ... ok
[INFO] [stdout] test ssh_config::tests::remove_host_block_empty ... ok
[INFO] [stdout] test images::tests::list_all_includes_builtins ... ok
[INFO] [stdout] test ssh_config::tests::remove_host_block_no_match ... ok
[INFO] [stdout] test ssh_config::tests::remove_host_block_only_entry ... ok
[INFO] [stdout] test ssh_config::tests::remove_host_block_removes_target ... ok
[INFO] [stdout] test template::tests::expand_config_expands_all_fields ... ok
[INFO] [stdout] test ssh::tests::ssh_port_reads_and_parses ... ok
[INFO] [stdout] test template::tests::expand_empty_default ... ok
[INFO] [stdout] test template::tests::expand_default_not_used_when_set ... ok
[INFO] [stdout] test template::tests::expand_missing_required_fails ... ok
[INFO] [stdout] test template::tests::expand_no_placeholders ... ok
[INFO] [stdout] test template::tests::expand_multiple_variables ... ok
[INFO] [stdout] test template::tests::expand_simple_variable ... ok
[INFO] [stdout] test template::tests::expand_unclosed_brace_fails ... ok
[INFO] [stdout] test template::tests::expand_whitespace_in_var_name ... ok
[INFO] [stdout] test tests::detects_dash_dash_after_global_flags ... ok
[INFO] [stdout] test tests::format_size_mib ... ok
[INFO] [stdout] test tests::format_size_tib ... ok
[INFO] [stdout] test template::tests::expand_with_default ... ok
[INFO] [stdout] test template::tests::load_dotenv_basic ... ok
[INFO] [stdout] test template::tests::load_variables_errors_when_explicit_env_file_missing ... ok
[INFO] [stdout] test tests::detects_dash_dash_after_vm_name ... ok
[INFO] [stdout] test tests::format_size_gib ... ok
[INFO] [stdout] test tests::format_size_kib ... ok
[INFO] [stdout] test tests::no_dash_dash_for_bare_interactive ... ok
[INFO] [stdout] test tests::no_dash_dash_when_value_precedes_it ... ok
[INFO] [stdout] test tests::format_size_bytes ... ok
[INFO] [stdout] test tests::split_ssh_args_empty ... ok
[INFO] [stdout] test tests::split_ssh_args_separator_at_end ... ok
[INFO] [stdout] test tests::split_ssh_args_only_opts ... ok
[INFO] [stdout] test tests::split_ssh_args_separator_in_middle ... ok
[INFO] [stdout] test tests::ignores_dash_dash_in_other_subcommands ... ok
[INFO] [stdout] test tests::split_ssh_args_separator_at_start_in_args ... ok
[INFO] [stdout] test vm::backend::tests::for_config_dispatches_qemu_on_every_platform ... ok
[INFO] [stdout] test tests::vm_name_named_ssh_still_works ... ok
[INFO] [stdout] test vm::cloud_init::tests::meta_data_contains_instance_id_and_hostname ... ok
[INFO] [stdout] test vm::cloud_init::tests::migration_instance_id_differs_from_hostname ... ok
[INFO] [stdout] test forward::tests::kill_supervisor_terminates_alive_pid ... ok
[INFO] [stdout] test vm::cloud_init::tests::user_data_contains_expected_sections ... ok
[INFO] [stdout] test template::tests::load_variables_picks_up_explicit_env_file ... ok
[INFO] [stdout] test vm::forwarding::tests::sweep_dead_handles_missing_state_file ... ok
[INFO] [stdout] test vm::instance::tests::provision_state_complete_is_complete ... ok
[INFO] [stdout] test vm::instance::tests::path_getters_return_expected_filenames ... ok
[INFO] [stdout] test vm::instance::tests::is_provisioned_falls_back_to_legacy_marker ... ok
[INFO] [stdout] test vm::forwarding::tests::sweep_dead_no_changes_when_all_alive ... ok
[INFO] [stdout] test vm::instance::tests::provision_state_fresh_is_ssh_wait ... ok
[INFO] [stdout] test vm::instance::tests::provision_state_legacy_marker_returns_complete ... ok
[INFO] [stdout] test vm::instance::tests::mark_provisioned_writes_both_files ... ok
[INFO] [stdout] test vm::instance::tests::provision_state_missing_returns_fresh ... ok
[INFO] [stdout] test vm::instance::tests::is_provisioned_uses_state_file ... ok
[INFO] [stdout] test vm::instance::tests::status_display ... ok
[INFO] [stdout] test vm::instance::tests::status_from_str_trims_whitespace ... ok
[INFO] [stdout] test vm::instance::tests::status_from_str_unknown_fails ... ok
[INFO] [stdout] test vm::instance::tests::read_status_missing_file_errors ... ok
[INFO] [stdout] test vm::instance::tests::provision_state_roundtrips_to_disk ... ok
[INFO] [stdout] test vm::instance::tests::status_from_str_all_variants ... ok
[INFO] [stdout] test vm::provision::tests::parent_dir_of_absolute_path ... ok
[INFO] [stdout] test vm::provision::tests::parent_dir_of_home_file ... ok
[INFO] [stdout] test vm::instance::tests::reconcile_status_stopped_passthrough ... ok
[INFO] [stdout] test vm::forwarding::tests::sweep_dead_removes_stale_entries_and_persists ... ok
[INFO] [stdout] test vm::provision::tests::parent_dir_of_nested ... ok
[INFO] [stdout] test vm::instance::tests::provisioned_marker ... ok
[INFO] [stdout] test vm::provision::tests::parent_dir_of_root_file ... ok
[INFO] [stdout] test vm::instance::tests::reconcile_status_alive_pid_stays_running ... ok
[INFO] [stdout] test vm::provision::tests::parent_dir_of_no_slash ... ok
[INFO] [stdout] test vm::provision::tests::shell_escape_only_a_single_quote ... ok
[INFO] [stdout] test vm::provision::tests::shell_escape_multiple_single_quotes ... ok
[INFO] [stdout] test vm::instance::tests::reconcile_status_broken_passthrough ... ok
[INFO] [stdout] test vm::provision::tests::shell_escape_single_quote_uses_end_escape_reopen_idiom ... ok
[INFO] [stdout] test vm::provision::tests::shell_escape_plain_ascii ... ok
[INFO] [stdout] test vm::instance::tests::write_and_read_status_roundtrip ... ok
[INFO] [stdout] test vm::provision::tests::shell_escape_quote_at_end ... ok
[INFO] [stdout] test vm::provision::tests::step_label_exactly_40_chars_not_truncated ... ok
[INFO] [stdout] test vm::provision::tests::shell_escape_quote_at_start ... ok
[INFO] [stdout] test vm::provision::tests::step_label_none_everywhere_is_unknown ... ok
[INFO] [stdout] test vm::provision::tests::step_label_script_wins_over_run ... ok
[INFO] [stdout] test vm::provision::tests::shell_escape_with_spaces ... ok
[INFO] [stdout] test vm::provision::tests::step_label_does_not_panic_on_utf8_boundary ... ok
[INFO] [stdout] test vm::provision::tests::step_label_long_run_is_truncated_with_ellipsis ... ok
[INFO] [stdout] test vm::provision::tests::shell_escape_empty_string ... ok
[INFO] [stdout] test vm::provision::tests::step_label_multiline_run_takes_first_line_only ... ok
[INFO] [stdout] test vm::provision::tests::step_label_source_wins_over_everything ... ok
[INFO] [stdout] test vm::provision::tests::with_set_e_prepends_directive ... ok
[INFO] [stdout] test vm::provision::tests::step_label_trims_leading_and_trailing_whitespace ... ok
[INFO] [stdout] test vm::provision::tests::step_label_short_run_returned_as_is ... ok
[INFO] [stdout] test vm::instance::tests::reconcile_status_stale_pid_transitions_to_stopped ... ok
[INFO] [stdout] test vm::qemu::tests::parse_machine_help_falls_back_to_highest_versioned ... ok
[INFO] [stdout] test vm::qemu::tests::parse_machine_help_returns_none_when_alias_absent ... ok
[INFO] [stdout] test vm::qemu::tests::qmp_error_response_is_recognized ... ok
[INFO] [stdout] test vm::qemu::tests::allocate_free_port_returns_nonzero ... ok
[INFO] [stdout] test vm::qemu::tests::build_qemu_args_contains_required_flags ... ok
[INFO] [stdout] test vm::qemu::tests::parse_machine_help_resolves_q35_alias ... ok
[INFO] [stdout] test vm::qemu::tests::parse_machine_help_resolves_virt_alias ... ok
[INFO] [stdout] test vm::qemu::tests::allocate_free_port_returns_unique_ports ... ok
[INFO] [stdout] test vm::qemu::tests::platform_args_returns_expected_binary ... ok
[INFO] [stdout] test vm::qemu::tests::qmp_event_is_not_a_command_response ... ok
[INFO] [stdout] test vm::qemu::tests::qmp_greeting_has_expected_shape ... ok
[INFO] [stdout] test vm::qemu::tests::qmp_success_response_is_recognized ... ok
[INFO] [stdout] test vm::qemu::tests::cleanup_runtime_files_tolerates_missing ... ok
[INFO] [stdout] test vm::qemu::tests::read_pid_missing_errors ... ok
[INFO] [stdout] test vm::system_info::tests::includes_shutdown_hint ... ok
[INFO] [stdout] test vm::system_info::tests::lists_mixins_with_and_without_notes ... ok
[INFO] [stdout] test vm::system_info::tests::multi_line_notes_render_as_sub_bullets ... ok
[INFO] [stdout] test vm::system_info::tests::config_notes_render_in_their_own_section_above_mixins ... ok
[INFO] [stdout] test vm::system_info::tests::no_config_notes_section_when_empty ... ok
[INFO] [stdout] test vm::system_info::tests::renders_bare_minimum ... ok
[INFO] [stdout] test vm::system_info::tests::renders_under_30_lines_for_typical_config ... ok
[INFO] [stdout] test vm::template::tests::template_info_dependents_serialize_as_array_when_empty ... ok
[INFO] [stdout] test vm::template::tests::template_info_json_schema_pin ... ok
[INFO] [stdout] test vm::tests::destroy_report_json_schema_pin ... ok
[INFO] [stdout] test vm::tests::idle_suspend_status_json_schema_pin ... ok
[INFO] [stdout] test vm::tests::config_set_rejects_nan_idle_load_threshold ... ok
[INFO] [stdout] test vm::tests::ensure_machine_type_skips_qemu_shellout_for_avf ... ok
[INFO] [stdout] test vm::tests::config_set_rejects_negative_idle_load_threshold ... ok
[INFO] [stdout] test vm::cloud_init::tests::find_iso_tool_returns_known_tool ... ok
[INFO] [stdout] test vm::tests::config_set_rejects_zero_idle_load_threshold ... ok
[INFO] [stdout] test vm::tests::config_set_requires_at_least_one_flag ... ok
[INFO] [stdout] test vm::tests::idle_suspend_status_serializes_null_pid ... ok
[INFO] [stdout] test vm::tests::skip_watcher_on_avf_even_when_idle_minutes_set ... ok
[INFO] [stdout] test vm::tests::skip_watcher_when_idle_minutes_zero ... ok
[INFO] [stdout] test vm::tests::spawn_watcher_when_qemu_and_idle_minutes_set ... ok
[INFO] [stdout] test vm::tests::vm_state_report_collections_serialize_as_arrays ... ok
[INFO] [stdout] test vm::qemu::tests::read_pid_valid ... ok
[INFO] [stdout] test vm::tests::vm_state_report_omits_no_keys_for_stopped_vm ... ok
[INFO] [stdout] test vm::tests::vm_state_report_empty_label_value_serializes_as_empty_string ... ok
[INFO] [stdout] test vm::tests::vm_state_report_json_schema_pin ... ok
[INFO] [stdout] test vm::tests::vm_state_report_empty_labels_serialize_as_object ... ok
[INFO] [stdout] test vm::provision::tests::shell_escape_roundtrips_through_sh_for_malicious_inputs ... ok
[INFO] [stdout] test vm::provision::tests::with_set_e_makes_mid_script_failure_abort ... ok
[INFO] [stdout] test ssh::tests::generate_keypair_creates_key_files ... ok
[INFO] [stdout] test locks::tests::second_acquire_waits_until_first_drops ... ok
[INFO] [stdout] test vm::qemu::tests::cleanup_runtime_files_removes_existing ... ok
[INFO] [stdout] test vm::instance::tests::reconcile_status_running_no_pid_file_transitions_to_stopped ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 345 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.19s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/agv-0a95f6a4254d9967)
[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] [stderr]      Running tests/avf_backend_test.rs (/opt/rustwide/target/debug/deps/avf_backend_test-2e156ab683796192)
[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] [stderr]      Running tests/avf_e2e_test.rs (/opt/rustwide/target/debug/deps/avf_e2e_test-cf21cd93338e5dd9)
[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] [stderr]      Running tests/avf_migrate_test.rs (/opt/rustwide/target/debug/deps/avf_migrate_test-0920271ad43b8e46)
[INFO] [stderr]      Running tests/avf_runner_test.rs (/opt/rustwide/target/debug/deps/avf_runner_test-df9ebf9c64e50c2b)
[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] [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] [stderr]      Running tests/cli_test.rs (/opt/rustwide/target/debug/deps/cli_test-c230a2d113bd9176)
[INFO] [stdout] 
[INFO] [stdout] running 81 tests
[INFO] [stdout] test config_show_help_succeeds ... ok
[INFO] [stdout] test config_help_succeeds ... ok
[INFO] [stdout] test config_set_help_succeeds ... ok
[INFO] [stdout] test backend_cleanup_help_succeeds ... ok
[INFO] [stdout] test cp_no_vm_path_fails ... ok
[INFO] [stdout] test cache_ls_json_against_empty_data_dir_returns_empty_array ... ok
[INFO] [stdout] test cache_help_succeeds ... ok
[INFO] [stdout] test config_set_without_name_fails ... ok
[INFO] [stdout] test cache_clean_keeps_raw_alongside_referenced_qcow2 ... ok
[INFO] [stdout] test cp_without_args_fails ... ok
[INFO] [stdout] test config_set_help_lists_idle_flags ... ok
[INFO] [stdout] test cp_missing_dest_fails ... ok
[INFO] [stdout] test create_from_and_image_conflict ... ok
[INFO] [stdout] test create_from_and_config_conflict ... ok
[INFO] [stdout] test cp_both_vm_paths_fails ... ok
[INFO] [stdout] test create_help_mentions_interactive ... ok
[INFO] [stdout] test config_show_without_name_fails ... ok
[INFO] [stdout] test create_rejects_unknown_backend_value ... ok
[INFO] [stdout] test cache_ls_succeeds ... ok
[INFO] [stdout] test create_backend_flag_is_registered ... ok
[INFO] [stdout] test create_help_succeeds ... ok
[INFO] [stdout] test destroy_with_label_against_no_matches_succeeds ... ok
[INFO] [stdout] test create_without_name_fails ... ok
[INFO] [stdout] test cp_help_succeeds ... ok
[INFO] [stdout] test destroy_without_name_or_label_errors ... ok
[INFO] [stdout] test forward_without_ports_fails ... ok
[INFO] [stdout] test destroy_without_name_fails ... ok
[INFO] [stdout] test forward_help_succeeds ... ok
[INFO] [stdout] test doctor_succeeds ... ok
[INFO] [stdout] test help_flag_succeeds ... ok
[INFO] [stdout] test doctor_json_emits_object_with_expected_keys ... ok
[INFO] [stdout] test help_lists_all_subcommands ... ok
[INFO] [stdout] test forward_invalid_port_fails ... ok
[INFO] [stdout] test init_help_succeeds ... ok
[INFO] [stdout] test init_unknown_template_fails ... ok
[INFO] [stdout] test init_template_claude_writes_agv_toml ... ok
[INFO] [stdout] test init_without_output_fails ... ok
[INFO] [stdout] test exit_code_2_for_clap_usage_errors ... ok
[INFO] [stdout] test init_writes_agv_toml ... ok
[INFO] [stdout] test init_fails_if_agv_toml_exists ... ok
[INFO] [stdout] test inspect_without_name_fails ... ok
[INFO] [stdout] test init_force_overwrites ... ok
[INFO] [stdout] test ls_succeeds_with_no_vms ... ok
[INFO] [stdout] test images_json_emits_array_with_expected_keys ... ok
[INFO] [stdout] test no_subcommand_fails ... ok
[INFO] [stdout] test json_flag_is_accepted_on_ls ... ok
[INFO] [stdout] test quiet_flag_is_accepted ... ok
[INFO] [stdout] test ls_json_emits_an_array_and_no_human_chrome ... ok
[INFO] [stdout] test specs_succeeds_and_lists_builtins ... ok
[INFO] [stdout] test rename_missing_args_fails ... ok
[INFO] [stdout] test ssh_flag_port_forward_accepted ... ok
[INFO] [stdout] test ssh_command_after_separator_accepted ... ok
[INFO] [stdout] test ssh_opts_and_command_accepted ... ok
[INFO] [stdout] test ssh_flag_agent_forwarding_accepted ... ok
[INFO] [stdout] test resume_without_name_fails ... ok
[INFO] [stdout] test resources_json_has_expected_top_level_keys ... ok
[INFO] [stdout] test start_without_name_fails ... ok
[INFO] [stdout] test ssh_without_name_fails ... ok
[INFO] [stdout] test ls_with_label_filter_against_empty_data_dir_returns_empty_json ... ok
[INFO] [stdout] test start_help_mentions_retry_and_interactive ... ok
[INFO] [stdout] test images_succeeds_and_lists_builtins ... ok
[INFO] [stdout] test stop_without_name_fails ... ok
[INFO] [stdout] test suspend_without_name_fails ... ok
[INFO] [stdout] test template_help_succeeds ... ok
[INFO] [stdout] test template_create_without_args_fails ... ok
[INFO] [stdout] test suspend_help_succeeds ... ok
[INFO] [stdout] test template_rm_without_name_fails ... ok
[INFO] [stdout] test template_ls_succeeds_with_no_templates ... ok
[INFO] [stdout] test template_ls_json_against_empty_data_dir_returns_empty_array ... ok
[INFO] [stdout] test version_flag_succeeds ... ok
[INFO] [stdout] test verbose_flag_is_accepted ... ok
[INFO] [stdout] test ssh_help_succeeds ... ok
[INFO] [stdout] test rename_help_succeeds ... ok
[INFO] [stdout] test resume_help_succeeds ... ok
[INFO] [stdout] test parallel_resources_invocations_all_succeed ... ok
[INFO] [stdout] test specs_json_emits_array_with_expected_keys ... ok
[INFO] [stdout] test start_retry_and_interactive_combine ... ok
[INFO] [stdout] test exit_code_11_for_not_found_commands ... ok
[INFO] [stdout] test unknown_subcommand_fails ... ok
[INFO] [stdout] test json_flag_is_registered_on_every_lifecycle_verb ... ok
[INFO] [stdout] test json_flag_is_registered_on_list_like_commands ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 81 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.34s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/create_test.rs (/opt/rustwide/target/debug/deps/create_test-017ab0833ccdbcb5)
[INFO] [stdout] 
[INFO] [stdout] running 13 tests
[INFO] [stdout] test auto_forwards_end_to_end ... ignored, downloads a real cloud image and boots a VM — slow
[INFO] [stdout] test auto_suspend_active_session_keeps_vm_running ... ignored, downloads a real cloud image and boots a VM — slow
[INFO] [stdout] test auto_suspend_idle_vm_suspends ... ignored, downloads a real cloud image and boots a VM — slow
[INFO] [stdout] test create_with_start_and_provision ... ignored, downloads a real cloud image and boots a VM — slow
[INFO] [stdout] test fedora_base_boots_and_provisions ... ignored, downloads a real cloud image and boots a VM — slow
[INFO] [stdout] test provision_failure_then_retry_resumes ... ignored, downloads a real cloud image and boots a VM — slow
[INFO] [stdout] test suspend_and_resume_preserves_state ... ignored, downloads a real cloud image and boots a VM — slow
[INFO] [stdout] test create_marks_broken_on_failure ... ok
[INFO] [stdout] test create_without_start ... ok
[INFO] [stdout] test create_backend_flag_persists_to_saved_config ... ok
[INFO] [stdout] test create_duplicate_name_fails ... ok
[INFO] [stdout] test backend_cleanup_removes_residual_qcow2_after_flip ... FAILED
[INFO] [stdout] test destroy_kills_live_process_for_broken_vm ... ok
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- backend_cleanup_removes_residual_qcow2_after_flip stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'backend_cleanup_removes_residual_qcow2_after_flip' (6239) panicked at tests/create_test.rs:503:5:
[INFO] [stdout] cleanup --dry-run failed: Error: invalid backend in /tmp/.tmpVf2ptx/instances/_test-cleanup-flip/config.toml: backend 'avf' is macOS-only — Apple Virtualization is not available on this platform
[INFO] [stdout] 
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x63ccbc35d211 - 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:     0x63ccbc35d211 - 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:     0x63ccbc35d211 - std[73adb7dc35730857]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x63ccbc35d211 - <<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:     0x63ccbc3737ea - <core[6883ba1bc0fe4ed1]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x63ccbc3737ea - core[6883ba1bc0fe4ed1]::fmt::write
[INFO] [stdout]    6:     0x63ccbc361f8c - 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:     0x63ccbc361f8c - <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:     0x63ccbc338446 - <std[73adb7dc35730857]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x63ccbc338446 - std[73adb7dc35730857]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x63ccbc353e29 - std[73adb7dc35730857]::panicking::default_hook
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x63ccbc216fd0 - <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:     0x63ccbc216fd0 - 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:     0x63ccbc354152 - <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:     0x63ccbc354152 - std[73adb7dc35730857]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:823:13
[INFO] [stdout]   15:     0x63ccbc3384f2 - std[73adb7dc35730857]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:688:13
[INFO] [stdout]   16:     0x63ccbc32fa69 - 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:     0x63ccbc3393cd - __rustc[a7b7b02e776dd976]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:679:5
[INFO] [stdout]   18:     0x63ccbc37401c - core[6883ba1bc0fe4ed1]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x63ccbbd7cba4 - create_test[1a66a884fc69fe17]::backend_cleanup_removes_residual_qcow2_after_flip::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/create_test.rs:503:5
[INFO] [stdout]   20:     0x63ccbbd9a1c2 - <core[6883ba1bc0fe4ed1]::pin::Pin<&mut dyn core[6883ba1bc0fe4ed1]::future::future::Future<Output = ()>> as core[6883ba1bc0fe4ed1]::future::future::Future>::poll
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/future/future.rs:133:9
[INFO] [stdout]   21:     0x63ccbbd9a23d - <core[6883ba1bc0fe4ed1]::pin::Pin<&mut core[6883ba1bc0fe4ed1]::pin::Pin<&mut dyn core[6883ba1bc0fe4ed1]::future::future::Future<Output = ()>>> as core[6883ba1bc0fe4ed1]::future::future::Future>::poll
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/future/future.rs:133:9
[INFO] [stdout]   22:     0x63ccbbd92cad - <tokio[efc768c0b6924cdb]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[6883ba1bc0fe4ed1]::pin::Pin<&mut core[6883ba1bc0fe4ed1]::pin::Pin<&mut dyn core[6883ba1bc0fe4ed1]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/scheduler/current_thread/mod.rs:753:70
[INFO] [stdout]   23:     0x63ccbbd92bab - tokio[efc768c0b6924cdb]::task::coop::with_budget::<core[6883ba1bc0fe4ed1]::task::poll::Poll<()>, <tokio[efc768c0b6924cdb]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[6883ba1bc0fe4ed1]::pin::Pin<&mut core[6883ba1bc0fe4ed1]::pin::Pin<&mut dyn core[6883ba1bc0fe4ed1]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/task/coop/mod.rs:167:5
[INFO] [stdout]   24:     0x63ccbbd92bab - tokio[efc768c0b6924cdb]::task::coop::budget::<core[6883ba1bc0fe4ed1]::task::poll::Poll<()>, <tokio[efc768c0b6924cdb]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[6883ba1bc0fe4ed1]::pin::Pin<&mut core[6883ba1bc0fe4ed1]::pin::Pin<&mut dyn core[6883ba1bc0fe4ed1]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/task/coop/mod.rs:133:5
[INFO] [stdout]   25:     0x63ccbbd92bab - <tokio[efc768c0b6924cdb]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[6883ba1bc0fe4ed1]::pin::Pin<&mut core[6883ba1bc0fe4ed1]::pin::Pin<&mut dyn core[6883ba1bc0fe4ed1]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/scheduler/current_thread/mod.rs:753:25
[INFO] [stdout]   26:     0x63ccbbd8df80 - <tokio[efc768c0b6924cdb]::runtime::scheduler::current_thread::Context>::enter::<core[6883ba1bc0fe4ed1]::task::poll::Poll<()>, <tokio[efc768c0b6924cdb]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[6883ba1bc0fe4ed1]::pin::Pin<&mut core[6883ba1bc0fe4ed1]::pin::Pin<&mut dyn core[6883ba1bc0fe4ed1]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/scheduler/current_thread/mod.rs:442:19
[INFO] [stdout]   27:     0x63ccbbd9121d - <tokio[efc768c0b6924cdb]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[6883ba1bc0fe4ed1]::pin::Pin<&mut core[6883ba1bc0fe4ed1]::pin::Pin<&mut dyn core[6883ba1bc0fe4ed1]::future::future::Future<Output = ()>>>>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/scheduler/current_thread/mod.rs:752:44
[INFO] [stdout]   28:     0x63ccbbd90514 - <tokio[efc768c0b6924cdb]::runtime::scheduler::current_thread::CoreGuard>::enter::<<tokio[efc768c0b6924cdb]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[6883ba1bc0fe4ed1]::pin::Pin<&mut core[6883ba1bc0fe4ed1]::pin::Pin<&mut dyn core[6883ba1bc0fe4ed1]::future::future::Future<Output = ()>>>>::{closure#0}, core[6883ba1bc0fe4ed1]::option::Option<()>>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/scheduler/current_thread/mod.rs:840:68
[INFO] [stdout]   29:     0x63ccbbd9bedb - <tokio[efc768c0b6924cdb]::runtime::context::scoped::Scoped<tokio[efc768c0b6924cdb]::runtime::scheduler::Context>>::set::<<tokio[efc768c0b6924cdb]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[efc768c0b6924cdb]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[6883ba1bc0fe4ed1]::pin::Pin<&mut core[6883ba1bc0fe4ed1]::pin::Pin<&mut dyn core[6883ba1bc0fe4ed1]::future::future::Future<Output = ()>>>>::{closure#0}, core[6883ba1bc0fe4ed1]::option::Option<()>>::{closure#0}, (alloc[55a36b64bcbf2c0d]::boxed::Box<tokio[efc768c0b6924cdb]::runtime::scheduler::current_thread::Core>, core[6883ba1bc0fe4ed1]::option::Option<()>)>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/context/scoped.rs:40:9
[INFO] [stdout]   30:     0x63ccbbd9fa89 - tokio[efc768c0b6924cdb]::runtime::context::set_scheduler::<(alloc[55a36b64bcbf2c0d]::boxed::Box<tokio[efc768c0b6924cdb]::runtime::scheduler::current_thread::Core>, core[6883ba1bc0fe4ed1]::option::Option<()>), <tokio[efc768c0b6924cdb]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[efc768c0b6924cdb]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[6883ba1bc0fe4ed1]::pin::Pin<&mut core[6883ba1bc0fe4ed1]::pin::Pin<&mut dyn core[6883ba1bc0fe4ed1]::future::future::Future<Output = ()>>>>::{closure#0}, core[6883ba1bc0fe4ed1]::option::Option<()>>::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/context.rs:176:38
[INFO] [stdout]   31:     0x63ccbbdab8d2 - <std[73adb7dc35730857]::thread::local::LocalKey<tokio[efc768c0b6924cdb]::runtime::context::Context>>::try_with::<tokio[efc768c0b6924cdb]::runtime::context::set_scheduler<(alloc[55a36b64bcbf2c0d]::boxed::Box<tokio[efc768c0b6924cdb]::runtime::scheduler::current_thread::Core>, core[6883ba1bc0fe4ed1]::option::Option<()>), <tokio[efc768c0b6924cdb]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[efc768c0b6924cdb]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[6883ba1bc0fe4ed1]::pin::Pin<&mut core[6883ba1bc0fe4ed1]::pin::Pin<&mut dyn core[6883ba1bc0fe4ed1]::future::future::Future<Output = ()>>>>::{closure#0}, core[6883ba1bc0fe4ed1]::option::Option<()>>::{closure#0}>::{closure#0}, (alloc[55a36b64bcbf2c0d]::boxed::Box<tokio[efc768c0b6924cdb]::runtime::scheduler::current_thread::Core>, core[6883ba1bc0fe4ed1]::option::Option<()>)>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/thread/local.rs:463:12
[INFO] [stdout]   32:     0x63ccbbdab45e - <std[73adb7dc35730857]::thread::local::LocalKey<tokio[efc768c0b6924cdb]::runtime::context::Context>>::with::<tokio[efc768c0b6924cdb]::runtime::context::set_scheduler<(alloc[55a36b64bcbf2c0d]::boxed::Box<tokio[efc768c0b6924cdb]::runtime::scheduler::current_thread::Core>, core[6883ba1bc0fe4ed1]::option::Option<()>), <tokio[efc768c0b6924cdb]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[efc768c0b6924cdb]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[6883ba1bc0fe4ed1]::pin::Pin<&mut core[6883ba1bc0fe4ed1]::pin::Pin<&mut dyn core[6883ba1bc0fe4ed1]::future::future::Future<Output = ()>>>>::{closure#0}, core[6883ba1bc0fe4ed1]::option::Option<()>>::{closure#0}>::{closure#0}, (alloc[55a36b64bcbf2c0d]::boxed::Box<tokio[efc768c0b6924cdb]::runtime::scheduler::current_thread::Core>, core[6883ba1bc0fe4ed1]::option::Option<()>)>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/thread/local.rs:427:20
[INFO] [stdout]   33:     0x63ccbbd9df6d - tokio[efc768c0b6924cdb]::runtime::context::set_scheduler::<(alloc[55a36b64bcbf2c0d]::boxed::Box<tokio[efc768c0b6924cdb]::runtime::scheduler::current_thread::Core>, core[6883ba1bc0fe4ed1]::option::Option<()>), <tokio[efc768c0b6924cdb]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[efc768c0b6924cdb]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[6883ba1bc0fe4ed1]::pin::Pin<&mut core[6883ba1bc0fe4ed1]::pin::Pin<&mut dyn core[6883ba1bc0fe4ed1]::future::future::Future<Output = ()>>>>::{closure#0}, core[6883ba1bc0fe4ed1]::option::Option<()>>::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/context.rs:176:17
[INFO] [stdout]   34:     0x63ccbbd8f020 - <tokio[efc768c0b6924cdb]::runtime::scheduler::current_thread::CoreGuard>::enter::<<tokio[efc768c0b6924cdb]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[6883ba1bc0fe4ed1]::pin::Pin<&mut core[6883ba1bc0fe4ed1]::pin::Pin<&mut dyn core[6883ba1bc0fe4ed1]::future::future::Future<Output = ()>>>>::{closure#0}, core[6883ba1bc0fe4ed1]::option::Option<()>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/scheduler/current_thread/mod.rs:840:27
[INFO] [stdout]   35:     0x63ccbbd8f316 - <tokio[efc768c0b6924cdb]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[6883ba1bc0fe4ed1]::pin::Pin<&mut core[6883ba1bc0fe4ed1]::pin::Pin<&mut dyn core[6883ba1bc0fe4ed1]::future::future::Future<Output = ()>>>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/scheduler/current_thread/mod.rs:740:24
[INFO] [stdout]   36:     0x63ccbbd90120 - <tokio[efc768c0b6924cdb]::runtime::scheduler::current_thread::CurrentThread>::block_on::<core[6883ba1bc0fe4ed1]::pin::Pin<&mut dyn core[6883ba1bc0fe4ed1]::future::future::Future<Output = ()>>>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/scheduler/current_thread/mod.rs:200:33
[INFO] [stdout]   37:     0x63ccbbd9eff3 - tokio[efc768c0b6924cdb]::runtime::context::runtime::enter_runtime::<<tokio[efc768c0b6924cdb]::runtime::scheduler::current_thread::CurrentThread>::block_on<core[6883ba1bc0fe4ed1]::pin::Pin<&mut dyn core[6883ba1bc0fe4ed1]::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/context/runtime.rs:65:16
[INFO] [stdout]   38:     0x63ccbbd8dab1 - <tokio[efc768c0b6924cdb]::runtime::scheduler::current_thread::CurrentThread>::block_on::<core[6883ba1bc0fe4ed1]::pin::Pin<&mut dyn core[6883ba1bc0fe4ed1]::future::future::Future<Output = ()>>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/scheduler/current_thread/mod.rs:188:9
[INFO] [stdout]   39:     0x63ccbbdaafb4 - <tokio[efc768c0b6924cdb]::runtime::runtime::Runtime>::block_on_inner::<core[6883ba1bc0fe4ed1]::pin::Pin<&mut dyn core[6883ba1bc0fe4ed1]::future::future::Future<Output = ()>>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/runtime.rs:368:52
[INFO] [stdout]   40:     0x63ccbbdab17f - <tokio[efc768c0b6924cdb]::runtime::runtime::Runtime>::block_on::<core[6883ba1bc0fe4ed1]::pin::Pin<&mut dyn core[6883ba1bc0fe4ed1]::future::future::Future<Output = ()>>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/runtime.rs:342:18
[INFO] [stdout]   41:     0x63ccbbd7fd03 - create_test[1a66a884fc69fe17]::backend_cleanup_removes_residual_qcow2_after_flip
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/create_test.rs:556:47
[INFO] [stdout]   42:     0x63ccbbd71797 - create_test[1a66a884fc69fe17]::backend_cleanup_removes_residual_qcow2_after_flip::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/create_test.rs:444:61
[INFO] [stdout]   43:     0x63ccbbd87466 - <create_test[1a66a884fc69fe17]::backend_cleanup_removes_residual_qcow2_after_flip::{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]   44:     0x63ccbc20a30b - <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]   45:     0x63ccbc20a30b - 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]   46:     0x63ccbc217955 - test[980ffaebd391d06d]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:747:74
[INFO] [stdout]   47:     0x63ccbc217955 - <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]   48:     0x63ccbc217955 - 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]   49:     0x63ccbc217955 - 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]   50:     0x63ccbc217955 - 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]   51:     0x63ccbc217955 - test[980ffaebd391d06d]::run_test_in_process
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:747:27
[INFO] [stdout]   52:     0x63ccbc217955 - test[980ffaebd391d06d]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:668:43
[INFO] [stdout]   53:     0x63ccbc212404 - test[980ffaebd391d06d]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:698:41
[INFO] [stdout]   54:     0x63ccbc212404 - 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]   55:     0x63ccbc21aaa2 - 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]   56:     0x63ccbc21aaa2 - <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]   57:     0x63ccbc21aaa2 - 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]   58:     0x63ccbc21aaa2 - 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]   59:     0x63ccbc21aaa2 - 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]   60:     0x63ccbc21aaa2 - 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]   61:     0x63ccbc21aaa2 - <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]   62:     0x63ccbc35c17f - <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]   63:     0x63ccbc35c17f - <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]   64:     0x72e7483ddaa4 - <unknown>
[INFO] [stdout]   65:     0x72e74846aa64 - clone
[INFO] [stdout]   66:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     backend_cleanup_removes_residual_qcow2_after_flip
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 5 passed; 1 failed; 7 ignored; 0 measured; 0 filtered out; finished in 0.52s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--test create_test`
[INFO] running `Command { std: "docker" "inspect" "f6508f1f1691bd76fbf56374dc1c0e2f05406234719e2a970cead3691f1e7c35", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "f6508f1f1691bd76fbf56374dc1c0e2f05406234719e2a970cead3691f1e7c35", kill_on_drop: false }`
[INFO] [stdout] f6508f1f1691bd76fbf56374dc1c0e2f05406234719e2a970cead3691f1e7c35
