[INFO] fetching crate alioth 0.12.0...
[INFO] testing alioth-0.12.0 against 1.95.0 for beta-1.96-2
[INFO] extracting crate alioth 0.12.0 into /workspace/builds/worker-5-tc1/source
[INFO] started tweaking crates.io crate alioth 0.12.0
[INFO] finished tweaking crates.io crate alioth 0.12.0
[INFO] tweaked toml for crates.io crate alioth 0.12.0 written to /workspace/builds/worker-5-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate alioth 0.12.0 on toolchain 1.95.0
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.95.0" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate alioth 0.12.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.95.0" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded ctor v0.6.3
[INFO] [stderr]   Downloaded dtor v0.1.1
[INFO] [stderr]   Downloaded ctor-proc-macro v0.0.7
[INFO] [stderr]   Downloaded serde-aco v0.12.0
[INFO] [stderr]   Downloaded serde-aco-derive v0.12.0
[INFO] [stderr]   Downloaded cc v1.2.50
[INFO] [stderr]   Downloaded alioth-macros v0.12.0
[INFO] [stderr]   Downloaded dtor-proc-macro v0.0.6
[INFO] [stderr]   Downloaded bitfield-macros v0.19.4
[INFO] [stderr]   Downloaded bitfield v0.19.4
[INFO] [stderr]   Downloaded io-uring v0.7.11
[INFO] [stderr]   Downloaded flexi_logger v0.31.8
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/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" "+1.95.0" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] b3ab8a1e1dc280c3a5bead292febd2264952f0d320fb809ffeeb3ef663adc05b
[INFO] running `Command { std: "docker" "start" "-a" "b3ab8a1e1dc280c3a5bead292febd2264952f0d320fb809ffeeb3ef663adc05b", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "b3ab8a1e1dc280c3a5bead292febd2264952f0d320fb809ffeeb3ef663adc05b", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "b3ab8a1e1dc280c3a5bead292febd2264952f0d320fb809ffeeb3ef663adc05b", kill_on_drop: false }`
[INFO] [stdout] b3ab8a1e1dc280c3a5bead292febd2264952f0d320fb809ffeeb3ef663adc05b
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/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=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-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" "+1.95.0" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 052e067a0c9b683da45c34941a9408a85f88607cfc97f77b13fd54c5b605dae4
[INFO] running `Command { std: "docker" "start" "-a" "052e067a0c9b683da45c34941a9408a85f88607cfc97f77b13fd54c5b605dae4", kill_on_drop: false }`
[INFO] [stderr]    Compiling quote v1.0.44
[INFO] [stderr]    Compiling libc v0.2.182
[INFO] [stderr]    Compiling zerocopy v0.8.40
[INFO] [stderr]    Compiling io-uring v0.7.11
[INFO] [stderr]    Compiling iana-time-zone v0.1.64
[INFO] [stderr]    Compiling chrono v0.4.44
[INFO] [stderr]    Compiling syn v2.0.117
[INFO] [stderr]    Compiling parking_lot_core v0.9.12
[INFO] [stderr]    Compiling mio v1.1.1
[INFO] [stderr]    Compiling parking_lot v0.12.5
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling bitfield-macros v0.19.4
[INFO] [stderr]    Compiling zerocopy-derive v0.8.40
[INFO] [stderr]    Compiling serde-aco-derive v0.12.0
[INFO] [stderr]    Compiling snafu-derive v0.8.9
[INFO] [stderr]    Compiling alioth-macros v0.12.0
[INFO] [stderr]    Compiling bitfield v0.19.4
[INFO] [stderr]    Compiling snafu v0.8.9
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling serde-aco v0.12.0
[INFO] [stderr]    Compiling alioth v0.12.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 41.15s
[INFO] running `Command { std: "docker" "inspect" "052e067a0c9b683da45c34941a9408a85f88607cfc97f77b13fd54c5b605dae4", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "052e067a0c9b683da45c34941a9408a85f88607cfc97f77b13fd54c5b605dae4", kill_on_drop: false }`
[INFO] [stdout] 052e067a0c9b683da45c34941a9408a85f88607cfc97f77b13fd54c5b605dae4
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/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=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-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" "+1.95.0" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 3b0f94d584320764825e2ad0db533fbc105b326bf2fed3dff43d3dc1b9279c51
[INFO] running `Command { std: "docker" "start" "-a" "3b0f94d584320764825e2ad0db533fbc105b326bf2fed3dff43d3dc1b9279c51", kill_on_drop: false }`
[INFO] [stderr]    Compiling equivalent v1.0.2
[INFO] [stderr]    Compiling aho-corasick v1.1.4
[INFO] [stderr]    Compiling semver v1.0.27
[INFO] [stderr]    Compiling regex-syntax v0.8.8
[INFO] [stderr]    Compiling winnow v0.7.14
[INFO] [stderr]    Compiling syn v2.0.117
[INFO] [stderr]    Compiling toml_datetime v0.7.5+spec-1.1.0
[INFO] [stderr]    Compiling getrandom v0.3.4
[INFO] [stderr]    Compiling indexmap v2.12.1
[INFO] [stderr]    Compiling rustc_version v0.4.1
[INFO] [stderr]    Compiling rustix v1.1.3
[INFO] [stderr]    Compiling thiserror v2.0.17
[INFO] [stderr]    Compiling rstest_macros v0.26.1
[INFO] [stderr]    Compiling glob v0.3.3
[INFO] [stderr]    Compiling futures-core v0.3.31
[INFO] [stderr]    Compiling relative-path v1.9.3
[INFO] [stderr]    Compiling dtor-proc-macro v0.0.6
[INFO] [stderr]    Compiling slab v0.4.11
[INFO] [stderr]    Compiling dtor v0.1.1
[INFO] [stderr]    Compiling io-uring v0.7.11
[INFO] [stderr]    Compiling mio v1.1.1
[INFO] [stderr]    Compiling ctor-proc-macro v0.0.7
[INFO] [stderr]    Compiling once_cell v1.21.3
[INFO] [stderr]    Compiling ctor v0.6.3
[INFO] [stderr]    Compiling assert_matches v1.5.0
[INFO] [stderr]    Compiling regex-automata v0.4.13
[INFO] [stderr]    Compiling toml_parser v1.0.6+spec-1.1.0
[INFO] [stderr]    Compiling tempfile v3.25.0
[INFO] [stderr]    Compiling toml_edit v0.23.10+spec-1.0.0
[INFO] [stderr]    Compiling proc-macro-crate v3.4.0
[INFO] [stderr]    Compiling regex v1.12.2
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling snafu-derive v0.8.9
[INFO] [stderr]    Compiling futures-macro v0.3.31
[INFO] [stderr]    Compiling thiserror-impl v2.0.17
[INFO] [stderr]    Compiling zerocopy-derive v0.8.40
[INFO] [stderr]    Compiling bitfield-macros v0.19.4
[INFO] [stderr]    Compiling serde-aco-derive v0.12.0
[INFO] [stderr]    Compiling alioth-macros v0.12.0
[INFO] [stderr]    Compiling bitfield v0.19.4
[INFO] [stderr]    Compiling futures-util v0.3.31
[INFO] [stderr]    Compiling flexi_logger v0.31.8
[INFO] [stderr]    Compiling zerocopy v0.8.40
[INFO] [stderr]    Compiling snafu v0.8.9
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling serde-aco v0.12.0
[INFO] [stderr]    Compiling rstest v0.26.1
[INFO] [stderr]    Compiling alioth v0.12.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 1m 24s
[INFO] running `Command { std: "docker" "inspect" "3b0f94d584320764825e2ad0db533fbc105b326bf2fed3dff43d3dc1b9279c51", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "3b0f94d584320764825e2ad0db533fbc105b326bf2fed3dff43d3dc1b9279c51", kill_on_drop: false }`
[INFO] [stdout] 3b0f94d584320764825e2ad0db533fbc105b326bf2fed3dff43d3dc1b9279c51
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/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=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-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" "+1.95.0" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 69e150367d3785e047af52369afce6c7be6e58f083f9a7347ff7c59fa04aad0d
[INFO] running `Command { std: "docker" "start" "-a" "69e150367d3785e047af52369afce6c7be6e58f083f9a7347ff7c59fa04aad0d", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.14s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/alioth-9498acb008d0bdf3)
[INFO] [stdout] running 228 tests
[INFO] [stdout] test blk::qcow2::tests::test_cmpr_desc_offset_size::case_1 ... ok
[INFO] [stdout] test blk::qcow2::tests::test_l1entry_l2_offset::case_1 ... ok
[INFO] [stdout] test board::tests::test_cpu_topology_encode_decode::case_2 ... ok
[INFO] [stdout] test board::tests::test_cpu_topology_encode_decode::case_3 ... ok
[INFO] [stdout] test board::tests::test_cpu_topology_encode_decode::case_4 ... ok
[INFO] [stdout] test board::tests::test_cpu_topology_encode_decode::case_1 ... ok
[INFO] [stdout] test board::tests::test_cpu_topology_encode_decode::case_5 ... ok
[INFO] [stdout] test blk::qcow2::tests::test_std_desc_cluster_offset::case_1 ... ok
[INFO] [stdout] test board::tests::test_cpu_topology_encode_decode::case_7 ... ok
[INFO] [stdout] test board::tests::test_cpu_topology_encode_decode::case_8 ... ok
[INFO] [stdout] test board::x86_64::tests::test_encode_x2apic::case_2 ... ok
[INFO] [stdout] test board::x86_64::tests::test_encode_x2apic::case_1 ... ok
[INFO] [stdout] test board::x86_64::tests::test_encode_x2apic::case_3 ... ok
[INFO] [stdout] test board::tests::test_cpu_topology_encode_decode::case_9 ... ok
[INFO] [stdout] test board::x86_64::tests::test_encode_x2apic::case_5 ... ok
[INFO] [stdout] test board::x86_64::tests::test_encode_x2apic::case_4 ... ok
[INFO] [stdout] test board::x86_64::tests::test_encode_x2apic::case_7 ... ok
[INFO] [stdout] test board::x86_64::tests::test_encode_x2apic::case_6 ... ok
[INFO] [stdout] test board::x86_64::tests::test_encode_x2apic::case_8 ... ok
[INFO] [stdout] test board::x86_64::tests::test_encode_x2apic::case_9 ... ok
[INFO] [stdout] test device::cmos::tests::test_cmos ... ok
[INFO] [stdout] test device::cmos::tests::test_cmos_upgrade_in_progress ... ok
[INFO] [stdout] test device::fw_cfg::acpi::tests::test_size ... ok
[INFO] [stdout] test device::clock::tests::test_system_clock ... ok
[INFO] [stdout] test device::fw_cfg::tests::test_fw_cfg_content_access::case_02 ... ok
[INFO] [stdout] test device::fw_cfg::tests::test_fw_cfg_content_access::case_03 ... ok
[INFO] [stdout] test device::fw_cfg::tests::test_fw_cfg_content_access::case_04 ... ok
[INFO] [stdout] test device::fw_cfg::tests::test_fw_cfg_content_access::case_05 ... ok
[INFO] [stdout] test board::tests::test_cpu_topology_fixup ... ok
[INFO] [stdout] test device::fw_cfg::tests::test_fw_cfg_content_access::case_09 ... ok
[INFO] [stdout] test device::fw_cfg::tests::test_fw_cfg_content_access::case_07 ... ok
[INFO] [stdout] test device::fw_cfg::tests::test_fw_cfg_content_access::case_10 ... ok
[INFO] [stdout] test blk::qcow2::tests::test_cmpr_desc_offset_size::case_2 ... ok
[INFO] [stdout] test device::fw_cfg::tests::test_fw_cfg_content_access::case_06 ... ok
[INFO] [stdout] test device::fw_cfg::tests::test_fw_cfg_content_file_access ... ok
[INFO] [stdout] test device::fw_cfg::tests::test_fw_cfg_content_access::case_01 ... ok
[INFO] [stderr] ERROR [alioth::device::fw_cfg] fw_cfg: reading File { fd: 3, path: "/tmp/.tmpUp19hD/test_file (deleted)", read: true, write: false }: Error { kind: UnexpectedEof, message: "failed to fill whole buffer" }
[INFO] [stdout] test device::fw_cfg::tests::test_fw_cfg_content_access::case_08 ... ok
[INFO] [stdout] test device::fw_cfg::tests::test_fw_cfg_content_file_size ... ok
[INFO] [stdout] test device::fw_cfg::tests::test_fw_cfg_content_file_read ... ok
[INFO] [stdout] test device::fw_cfg::tests::test_fw_cfg_content_read::case_1 ... ok
[INFO] [stdout] test device::fw_cfg::tests::test_fw_cfg_content_read::case_2 ... ok
[INFO] [stdout] test device::fw_cfg::tests::test_fw_cfg_content_read::case_4 ... ok
[INFO] [stdout] test device::fw_cfg::tests::test_fw_cfg_content_read::case_5 ... ok
[INFO] [stdout] test device::fw_cfg::tests::test_fw_cfg_content_size::case_1 ... ok
[INFO] [stdout] test device::fw_cfg::tests::test_fw_cfg_content_read::case_6 ... ok
[INFO] [stdout] test device::fw_cfg::tests::test_fw_cfg_content_size::case_6 ... ok
[INFO] [stdout] test device::fw_cfg::tests::test_fw_cfg_content_size::case_2 ... ok
[INFO] [stdout] test device::fw_cfg::tests::test_fw_cfg_content_size::case_5 ... ok
[INFO] [stdout] test device::fw_cfg::tests::test_fw_cfg_content_size::case_3 ... ok
[INFO] [stdout] test device::ioapic::tests::test_ioapic_read_write ... ok
[INFO] [stdout] test device::ioapic::tests::test_ioapic_service_pin ... ok
[INFO] [stdout] test device::net::tests::test_mac_addr_visitor ... ok
[INFO] [stdout] test device::fw_cfg::tests::test_fw_cfg_content_size::case_4 ... ok
[INFO] [stdout] test firmware::acpi::bindings::tests::test_size ... ok
[INFO] [stdout] test firmware::acpi::reg::tests::test_pm_timer ... ok
[INFO] [stdout] test firmware::dt::dtb::tests::test_prop_val_write_as_blob::case_01 ... ok
[INFO] [stdout] test firmware::dt::dtb::tests::test_prop_val_write_as_blob::case_03 ... ok
[INFO] [stdout] test firmware::dt::dtb::tests::test_prop_val_write_as_blob::case_02 ... ok
[INFO] [stdout] test firmware::dt::dtb::tests::test_prop_val_write_as_blob::case_04 ... ok
[INFO] [stdout] test firmware::dt::dtb::tests::test_prop_val_write_as_blob::case_07 ... ok
[INFO] [stdout] test firmware::dt::dtb::tests::test_prop_val_write_as_blob::case_06 ... ok
[INFO] [stdout] test firmware::dt::dtb::tests::test_prop_val_write_as_blob::case_08 ... ok
[INFO] [stdout] test firmware::dt::dtb::tests::test_prop_val_write_as_blob::case_09 ... ok
[INFO] [stdout] test firmware::dt::dtb::tests::test_prop_val_write_as_blob::case_10 ... ok
[INFO] [stdout] test firmware::dt::dtb::tests::test_string_block ... ok
[INFO] [stdout] test firmware::dt::dtb::tests::test_prop_val_write_as_blob::case_05 ... ok
[INFO] [stdout] test firmware::dt::tests::test_val_size ... ok
[INFO] [stdout] test hv::kvm::vcpu::x86_64::tests::test_kvm_run ... ignored
[INFO] [stdout] test hv::kvm::vcpu::x86_64::tests::test_vcpu_regs ... ignored
[INFO] [stdout] test hv::kvm::vm::tests::test_mem_map ... ignored
[INFO] [stdout] test firmware::ovmf::x86_64::tdx::tests::test_create_hob ... ok
[INFO] [stdout] test hv::kvm::vm::x86_64::test::test_determine_vm_type::case_1 ... ok
[INFO] [stdout] test hv::kvm::vm::x86_64::test::test_determine_vm_type::case_2 ... ok
[INFO] [stdout] test hv::kvm::vm::x86_64::test::test_determine_vm_type::case_3 ... ok
[INFO] [stdout] test hv::kvm::vm::x86_64::test::test_translate_msi_addr::case_1 ... ok
[INFO] [stdout] test hv::kvm::vm::x86_64::test::test_determine_vm_type::case_4 ... ok
[INFO] [stdout] test hv::kvm::vm::x86_64::test::test_translate_msi_addr::case_3 ... ok
[INFO] [stdout] test hv::kvm::vm::x86_64::test::test_translate_msi_addr::case_2 ... ok
[INFO] [stdout] test hv::kvm::vm::x86_64::test::test_translate_msi_addr::case_5 ... ok
[INFO] [stdout] test hv::kvm::x86_64::tests::test_get_supported_cpuid ... ignored
[INFO] [stdout] test hv::kvm::x86_64::tests::test_convert_cpuid_entry::case_1 ... ok
[INFO] [stdout] test hv::kvm::x86_64::tests::test_convert_cpuid_entry::case_2 ... ok
[INFO] [stdout] test hv::kvm::vm::x86_64::test::test_translate_msi_addr::case_4 ... ok
[INFO] [stdout] test loader::elf::tests::test_size ... ok
[INFO] [stdout] test loader::xen::start_info::tests::test_size ... ok
[INFO] [stdout] test mem::addressable::tests::test_addressable ... ok
[INFO] [stdout] test mem::addressable::tests::test_new_slot ... ok
[INFO] [stdout] test mem::emulated::tests::test_mmio_bus_read::case_04 ... ok
[INFO] [stdout] test mem::emulated::tests::test_mmio_bus_read::case_01 ... ok
[INFO] [stdout] test mem::emulated::tests::test_mmio_bus_read::case_02 ... ok
[INFO] [stdout] test mem::emulated::tests::test_mmio_bus_read::case_05 ... ok
[INFO] [stdout] test mem::emulated::tests::test_mmio_bus_read::case_03 ... ok
[INFO] [stdout] test mem::emulated::tests::test_mmio_bus_read::case_06 ... ok
[INFO] [stdout] test mem::emulated::tests::test_mmio_bus_read::case_08 ... ok
[INFO] [stdout] test mem::emulated::tests::test_mmio_bus_read::case_10 ... ok
[INFO] [stdout] test mem::emulated::tests::test_mmio_bus_read::case_07 ... ok
[INFO] [stdout] test mem::emulated::tests::test_mmio_bus_read::case_11 ... ok
[INFO] [stdout] test mem::emulated::tests::test_mmio_bus_read::case_12 ... ok
[INFO] [stdout] test mem::emulated::tests::test_mmio_bus_read::case_09 ... ok
[INFO] [stdout] test mem::emulated::tests::test_mmio_bus_read::case_13 ... ok
[INFO] [stdout] test mem::emulated::tests::test_mmio_bus_read::case_14 ... ok
[INFO] [stdout] test mem::emulated::tests::test_mmio_bus_read::case_17 ... ok
[INFO] [stdout] test mem::emulated::tests::test_mmio_bus_write::case_01 ... ok
[INFO] [stdout] test mem::emulated::tests::test_mmio_bus_read::case_16 ... ok
[INFO] [stdout] test mem::emulated::tests::test_mmio_bus_write::case_02 ... ok
[INFO] [stdout] test mem::emulated::tests::test_mmio_bus_read::case_15 ... ok
[INFO] [stdout] test mem::emulated::tests::test_mmio_bus_write::case_03 ... ok
[INFO] [stdout] test mem::emulated::tests::test_mmio_bus_write::case_04 ... ok
[INFO] [stdout] test mem::emulated::tests::test_mmio_bus_write::case_05 ... ok
[INFO] [stdout] test mem::emulated::tests::test_mmio_bus_write::case_06 ... ok
[INFO] [stdout] test mem::emulated::tests::test_mmio_bus_write::case_08 ... ok
[INFO] [stdout] test mem::emulated::tests::test_mmio_bus_write::case_09 ... ok
[INFO] [stdout] test mem::emulated::tests::test_mmio_bus_write::case_12 ... ok
[INFO] [stdout] test mem::emulated::tests::test_mmio_bus_write::case_13 ... ok
[INFO] [stdout] test mem::emulated::tests::test_mmio_bus_write::case_07 ... ok
[INFO] [stdout] test mem::emulated::tests::test_mmio_bus_write::case_10 ... ok
[INFO] [stdout] test mem::mapped::tests::test_ram_bus_read ... ok
[INFO] [stdout] test pci::bus::tests::test_pci_io_bus ... ok
[INFO] [stderr] INFO [alioth::mem::mapped] munmap(0x71c49d40b000, 1000) = 0, done
[INFO] [stdout] test pci::bus::tests::test_pci_io_bus_disabled_address ... ok
[INFO] [stderr] INFO [alioth::mem::mapped] munmap(0x71c49d40a000, 1000) = 0, done
[INFO] [stdout] test pci::bus::tests::test_pci_io_bus_unaligned_address_access ... ok
[INFO] [stderr] INFO [alioth::pci::config] 00:00.0: updating bar 0: 0x0000000c -> 0xec00000c, mask=0xfffff000
[INFO] [stdout] test mem::emulated::tests::test_mmio_bus_write::case_11 ... ok
[INFO] [stderr] INFO [alioth::pci::config] 00:00.0: bar 0: write 0xec000000, update: 0x0000000c -> 0xec00000c
[INFO] [stdout] test pci::bus::tests::test_host_bridge ... ok
[INFO] [stderr] ERROR [alioth::pci::cap] MsiCapMmio: write 0xaabbccdd to invalid offset 0xc.
[INFO] [stdout] test pci::bus::tests::test_pci_bus ... ok
[INFO] [stdout] test pci::cap::tests::test_msi_cap_mmio_32_pvm ... ok
[INFO] [stdout] test pci::cap::tests::test_msi_cap_mmio_32 ... ok
[INFO] [stdout] test pci::cap::tests::test_msi_cap_mmio_64_pvm ... ok
[INFO] [stdout] test pci::cap::tests::test_msi_msg_ctrl_cap_size::case_1 ... ok
[INFO] [stdout] test pci::cap::tests::test_msi_msg_ctrl_cap_size::case_2 ... ok
[INFO] [stdout] test pci::cap::tests::test_msi_msg_ctrl_cap_size::case_3 ... ok
[INFO] [stdout] test pci::cap::tests::test_msix_msg_ctrl::case_2 ... ok
[INFO] [stderr] ERROR [alioth::pci::cap] unaligned access to msix table: size = 2, offset = 0x0
[INFO] [stderr] ERROR [alioth::pci::cap] unaligned access to msix table: size = 2, offset = 0x0
[INFO] [stderr] ERROR [alioth::pci::cap] unaligned access to msix table: size = 4, offset = 0xe
[INFO] [stderr] ERROR [alioth::pci::cap] unaligned access to msix table: size = 4, offset = 0x12
[INFO] [stderr] ERROR [alioth::pci::cap] MSI-X table size: 2, accessing index 2
[INFO] [stderr] ERROR [alioth::pci::cap] MSI-X table size: 2, accessing index 2
[INFO] [stdout] test pci::cap::tests::test_msi_msg_ctrl_cap_size::case_4 ... ok
[INFO] [stdout] test pci::cap::tests::test_null_cap::case_2 ... ok
[INFO] [stdout] test pci::cap::tests::test_msix_table_mmio ... ok
[INFO] [stdout] test pci::cap::tests::test_msix_cap_mmio ... ok
[INFO] [stdout] test pci::cap::tests::test_null_cap::case_5 ... ok
[INFO] [stdout] test pci::cap::tests::test_msix_msg_ctrl::case_1 ... ok
[INFO] [stdout] test pci::cap::tests::test_null_cap::case_1 ... ok
[INFO] [stdout] test pci::cap::tests::test_null_cap::case_3 ... ok
[INFO] [stdout] test pci::cap::tests::test_null_cap::case_7 ... ok
[INFO] [stdout] test pci::cap::tests::test_null_cap::case_8 ... ok
[INFO] [stdout] test pci::cap::tests::test_null_cap::case_4 ... ok
[INFO] [stdout] test pci::cap::tests::test_null_cap::case_9 ... ok
[INFO] [stdout] test pci::cap::tests::test_null_cap::case_6 ... ok
[INFO] [stdout] test pci::cap::tests::test_pci_cap_list ... ok
[INFO] [stdout] test pci::cap::tests::test_pci_cap_list_default ... ok
[INFO] [stdout] test pci::config::tests::test_emulated_config ... ok
[INFO] [stdout] test pci::config::tests::test_emulated_header_masks ... ok
[INFO] [stderr] INFO [alioth::pci::config] 00:01.0: updating bar 5: 0x0000e001 -> 0x0000c001, mask=0xfffffffc
[INFO] [stderr] INFO [alioth::pci::config] 00:01.0: bar 5: write 0x0000c000, update: 0x0000e001 -> 0x0000c001
[INFO] [stderr] INFO [alioth::pci::config] 00:01.0: updating bar 0: 0xe0000000 -> 0xfffff000, mask=0xfffff000
[INFO] [stderr] INFO [alioth::pci::config] 00:01.0: bar 0: write 0xffffffff, update: 0xe0000000 -> 0xfffff000
[INFO] [stdout] test pci::config::tests::test_emulated_header_change_command::case_2 ... ok
[INFO] [stdout] test pci::config::tests::test_emulated_header_write_bar::case_1 ... ok
[INFO] [stdout] test pci::config::tests::test_emulated_header_bar_callbacks ... ok
[INFO] [stdout] test pci::config::tests::test_emulated_header_change_command::case_4 ... ok
[INFO] [stdout] test pci::config::tests::test_emulated_header_change_command::case_1 ... ok
[INFO] [stdout] test pci::config::tests::test_emulated_header_change_command::case_3 ... ok
[INFO] [stdout] test pci::config::tests::test_emulated_header_write_bar::case_4 ... ok
[INFO] [stderr] INFO [alioth::pci::config] 00:01.0: updating bar 0: 0xe0000000 -> 0xd0000000, mask=0xfffff000
[INFO] [stderr] INFO [alioth::pci::config] 00:01.0: updating bar 3: 0x00000001 -> 0x00000002, mask=0xffffffff
[INFO] [stderr] INFO [alioth::pci::config] 00:01.0: updating bar 2: 0x00000004 -> 0x80000004, mask=0xc0000000
[INFO] [stderr] INFO [alioth::pci::config] 00:00.0: bar 5: set to 0x0000e000, update: 0x0000e001 -> 0x0000e001
[INFO] [stderr] INFO [alioth::pci::pvpanic] pvpanic: PANICKED
[INFO] [stderr] INFO [alioth::pci::config] 00:00.0: updating bar 5: 0x0000e001 -> 0x0000e101, mask=0xfffffffc
[INFO] [stdout] test pci::config::tests::test_emulated_header_write_bar::case_2 ... ok
[INFO] [stderr] INFO [alioth::pci::config] 00:00.0: bar 5: set to 0x0000e100, update: 0x0000e001 -> 0x0000e101
[INFO] [stdout] test pci::config::tests::test_emulated_header_write_bar::case_5 ... ok
[INFO] [stderr] INFO [alioth::pci::config] 00:00.0: bar 0: set to 0xe0000000, update: 0xe0000000 -> 0xe0000000
[INFO] [stdout] test pci::config::tests::test_emulated_header_write_bar::case_8 ... ok
[INFO] [stderr] INFO [alioth::pci::config] 00:00.0: bar 2: set to 0x00000000, update: 0x00000004 -> 0x00000004
[INFO] [stdout] test pci::config::tests::test_emulated_header_write_bar::case_7 ... ok
[INFO] [stderr] INFO [alioth::pci::config] 00:00.0: bar 3: set to 0x00000001, update: 0x00000001 -> 0x00000001
[INFO] [stdout] test pci::config::tests::test_emulated_header_write_bar::case_6 ... ok
[INFO] [stderr] INFO [alioth::pci::config] 00:00.0: updating bar 0: 0xe0000000 -> 0xe0010000, mask=0xfffff000
[INFO] [stdout] test pci::config::tests::test_emulated_header_write_status::case_1 ... ok
[INFO] [stderr] INFO [alioth::pci::config] 00:00.0: bar 0: set to 0xe0010000, update: 0xe0000000 -> 0xe0010000
[INFO] [stdout] test pci::config::tests::test_emulated_header_write_status::case_2 ... ok
[INFO] [stderr] INFO [alioth::pci::config] 00:00.0: updating bar 3: 0x00000001 -> 0x00000002, mask=0xffffffff
[INFO] [stdout] test pci::config::tests::test_emulated_header_write_status::case_3 ... ok
[INFO] [stderr] INFO [alioth::pci::config] 00:00.0: bar 2: set to 0x00000000, update: 0x00000004 -> 0x00000004
[INFO] [stderr] INFO [alioth::pci::config] 00:00.0: bar 3: set to 0x00000002, update: 0x00000001 -> 0x00000002
[INFO] [stdout] test pci::host_bridge::tests::test_host_bridge ... ok
[INFO] [stdout] test pci::pvpanic::tests::test_pvpanic_bar ... ok
[INFO] [stdout] test pci::config::tests::test_io_bar_layout_change ... ok
[INFO] [stdout] test pci::config::tests::test_mem_bar_layout_change ... ok
[INFO] [stdout] test pci::config::tests::test_emulated_header_write_status::case_4 ... ok
[INFO] [stdout] test pci::pvpanic::tests::test_pvpanic_read_config::case_01 ... ok
[INFO] [stdout] test pci::pvpanic::tests::test_pvpanic_read_config::case_02 ... ok
[INFO] [stdout] test pci::pvpanic::tests::test_pvpanic_read_config::case_04 ... ok
[INFO] [stdout] test pci::pvpanic::tests::test_pvpanic_read_config::case_06 ... ok
[INFO] [stdout] test pci::pvpanic::tests::test_pvpanic_read_config::case_03 ... ok
[INFO] [stdout] test pci::pvpanic::tests::test_pvpanic_read_config::case_05 ... ok
[INFO] [stdout] test pci::pvpanic::tests::test_pvpanic_read_config::case_09 ... ok
[INFO] [stdout] test pci::pvpanic::tests::test_pvpanic_read_config::case_08 ... ok
[INFO] [stdout] test pci::pvpanic::tests::test_pvpanic_read_config::case_07 ... ok
[INFO] [stderr] INFO [alioth::pci::config] 00:00.0: updating bar 0: 0x0000000c -> 0xee00000c, mask=0xfffff000
[INFO] [stdout] test pci::pvpanic::tests::test_pvpanic_read_config::case_11 ... ok
[INFO] [stderr] INFO [alioth::pci::config] 00:00.0: bar 0: write 0xee000000, update: 0x0000000c -> 0xee00000c
[INFO] [stdout] test pci::segment::tests::test_pci_segment_next_bdf_wrapping ... ok
[INFO] [stdout] test pci::pvpanic::tests::test_pvpanic_read_config::case_12 ... ok
[INFO] [stdout] test pci::segment::tests::test_pci_segment_mmio ... ok
[INFO] [stdout] test pci::pvpanic::tests::test_pvpanic_read_config::case_10 ... ok
[INFO] [stdout] test pci::segment::tests::test_pci_segment_reserve::case_1 ... ok
[INFO] [stdout] test pci::segment::tests::test_pci_segment_reserve::case_2 ... ok
[INFO] [stdout] test pci::segment::tests::test_pci_segment_reserve::case_3 ... ok
[INFO] [stdout] test sync::notifier::tests::test_notifier ... ok
[INFO] [stdout] test sys::linux::ioctl::tests::test_codes ... ok
[INFO] [stdout] test utils::endian::tests::test_big_endian ... ok
[INFO] [stdout] test pci::segment::tests::test_pci_segment_reserve::case_4 ... ok
[INFO] [stdout] test utils::endian::tests::test_little_endian ... ok
[INFO] [stderr] ERROR [alioth::virtio::dev::vsock] alioth::virtio::dev::vsock::VsockConfig: write 0x0000000000000000 to readonly offset 0x0.
[INFO] [stdout] test virtio::dev::entropy::tests::entry_config_test ... ok
[INFO] [stderr] INFO [alioth::mem::mapped] munmap(0x71c487800000, 200000) = 0, done
[INFO] [stdout] test virtio::dev::vsock::uds_vsock::tests::vsock_config_test ... ok
[INFO] [stderr] INFO [alioth::mem::mapped] munmap(0x71c487e00000, 200000) = 0, done
[INFO] [stdout] test utils::tests::test_align_up ... ok
[INFO] [stdout] test virtio::queue::packed::tests::disabled_queue ... ok
[INFO] [stdout] test virtio::dev::entropy::tests::entropy_test ... ok
[INFO] [stdout] test virtio::queue::packed::tests::enable_notification ... ok
[INFO] [stderr] INFO [alioth::mem::mapped] munmap(0x71c487800000, 200000) = 0, done
[INFO] [stdout] test virtio::queue::packed::tests::index_wrapping_add::case_1 ... ok
[INFO] [stderr] INFO [alioth::mem::mapped] munmap(0x71c487800000, 200000) = 0, done
[INFO] [stderr] INFO [alioth::mem::mapped] munmap(0x71c487400000, 200000) = 0, done
[INFO] [stdout] test virtio::queue::packed::tests::enabled_queue ... ok
[INFO] [stdout] test virtio::queue::packed::tests::index_wrapping_add::case_3 ... ok
[INFO] [stdout] test virtio::queue::packed::tests::index_wrapping_add::case_4 ... ok
[INFO] [stdout] test virtio::queue::packed::tests::index_wrapping_add::case_2 ... ok
[INFO] [stdout] test virtio::queue::packed::tests::index_wrapping_sub::case_1 ... ok
[INFO] [stdout] test virtio::queue::packed::tests::index_wrapping_sub::case_2 ... ok
[INFO] [stdout] test virtio::queue::packed::tests::index_wrapping_sub::case_3 ... ok
[INFO] [stderr] INFO [alioth::mem::mapped] munmap(0x71c487200000, 200000) = 0, done
[INFO] [stderr] ERROR [alioth::virtio::dev::vsock::uds_vsock] vsock: queue RX: no enough writable buffers for VsockOp::RST
[INFO] [stderr] ERROR [alioth::virtio::dev::vsock::uds_vsock] vsock: failed to respond to shutdown: Invalid virtq buffer
[INFO] [stderr] 0: Invalid virtq buffer, at src/virtio/dev/vsock/uds_vsock.rs:223:41
[INFO] [stderr] 
[INFO] [stderr] INFO [alioth::mem::mapped] munmap(0x71c487400000, 200000) = 0, done
[INFO] [stdout] test virtio::queue::packed::tests::is_interrupt_enabled::case_01 ... ok
[INFO] [stdout] test virtio::queue::packed::tests::is_interrupt_enabled::case_02 ... ok
[INFO] [stdout] test virtio::queue::packed::tests::index_wrapping_sub::case_4 ... ok
[INFO] [stderr] INFO [alioth::mem::mapped] munmap(0x71c487400000, 200000) = 0, done
[INFO] [stderr] INFO [alioth::mem::mapped] munmap(0x71c487400000, 200000) = 0, done
[INFO] [stderr] INFO [alioth::mem::mapped] munmap(0x71c487400000, 200000) = 0, done
[INFO] [stderr] INFO [alioth::mem::mapped] munmap(0x71c487600000, 200000) = 0, done
[INFO] [stdout] test virtio::queue::packed::tests::is_interrupt_enabled::case_03 ... ok
[INFO] [stderr] INFO [alioth::mem::mapped] munmap(0x71c487800000, 200000) = 0, done
[INFO] [stdout] test virtio::queue::packed::tests::is_interrupt_enabled::case_04 ... ok
[INFO] [stderr] INFO [alioth::mem::mapped] munmap(0x71c487800000, 200000) = 0, done
[INFO] [stdout] test virtio::queue::packed::tests::is_interrupt_enabled::case_05 ... ok
[INFO] [stderr] INFO [alioth::mem::mapped] munmap(0x71c487600000, 200000) = 0, done
[INFO] [stdout] test virtio::queue::packed::tests::is_interrupt_enabled::case_06 ... ok
[INFO] [stderr] INFO [alioth::mem::mapped] munmap(0x71c487600000, 200000) = 0, done
[INFO] [stdout] test virtio::dev::vsock::uds_vsock::tests::vsock_conn_test ... ok
[INFO] [stdout] test virtio::queue::packed::tests::is_interrupt_enabled::case_08 ... ok
[INFO] [stdout] test virtio::queue::packed::tests::is_interrupt_enabled::case_11 ... ok
[INFO] [stdout] test virtio::queue::packed::tests::is_interrupt_enabled::case_09 ... ok
[INFO] [stderr] INFO [alioth::mem::mapped] munmap(0x71c487400000, 200000) = 0, done
[INFO] [stdout] test virtio::queue::packed::tests::is_interrupt_enabled::case_13 ... ok
[INFO] [stdout] test virtio::queue::split::tests::enabled_queue ... ok
[INFO] [stderr] INFO [alioth::mem::mapped] munmap(0x71c487600000, 200000) = 0, done
[INFO] [stdout] test virtio::queue::packed::tests::is_interrupt_enabled::case_07 ... ok
[INFO] [stdout] test virtio::queue::packed::tests::is_interrupt_enabled::case_12 ... ok
[INFO] [stderr] INFO [alioth::mem::mapped] munmap(0x71c487800000, 200000) = 0, done
[INFO] [stderr] INFO [alioth::mem::mapped] munmap(0x71c487400000, 200000) = 0, done
[INFO] [stderr] INFO [alioth::mem::mapped] munmap(0x71c487800000, 200000) = 0, done
[INFO] [stdout] test virtio::queue::packed::tests::is_interrupt_enabled::case_10 ... ok
[INFO] [stderr] INFO [alioth::mem::mapped] munmap(0x71c487800000, 200000) = 0, done
[INFO] [stderr] INFO [alioth::mem::mapped] munmap(0x71c487600000, 200000) = 0, done
[INFO] [stdout] test virtio::queue::split::tests::disabled_queue ... ok
[INFO] [stdout] test virtio::queue::split::tests::event_idx_enabled ... ok
[INFO] [stdout] test virtio::queue::tests::test_copy_from_reader ... ok
[INFO] [stderr] INFO [alioth::mem::mapped] munmap(0x71c487800000, 200000) = 0, done
[INFO] [stdout] test virtio::queue::tests::test_copy_to_writer ... ok
[INFO] [stdout] test virtio::queue::tests::test_handle_deferred ... ok
[INFO] [stderr] INFO [alioth::mem::mapped] munmap(0x71c487800000, 200000) = 0, done
[INFO] [stdout] test board::tests::test_cpu_topology_encode_decode::case_6 ... ok
[INFO] [stderr] INFO [alioth::pci::config] 00:01.0: updating bar 5: 0x0000e001 -> 0x0000f001, mask=0xfffffffc
[INFO] [stdout] test device::fw_cfg::tests::test_fw_cfg_content_read::case_3 ... ok
[INFO] [stdout] test pci::config::tests::test_emulated_header_write_bar::case_3 ... ok
[INFO] [stdout] test virtio::queue::tests::test_written_bytes ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 224 passed; 0 failed; 4 ignored; 0 measured; 0 filtered out; finished in 0.05s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests alioth
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "69e150367d3785e047af52369afce6c7be6e58f083f9a7347ff7c59fa04aad0d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "69e150367d3785e047af52369afce6c7be6e58f083f9a7347ff7c59fa04aad0d", kill_on_drop: false }`
[INFO] [stdout] 69e150367d3785e047af52369afce6c7be6e58f083f9a7347ff7c59fa04aad0d
