[INFO] fetching crate alioth 0.12.0... [INFO] testing alioth-0.12.0 against 1.95.0 for beta-1.96-1 [INFO] extracting crate alioth 0.12.0 into /workspace/builds/worker-6-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-6-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 dtor v0.1.1 [INFO] [stderr] Downloaded snafu v0.8.9 [INFO] [stderr] Downloaded alioth-macros v0.12.0 [INFO] [stderr] Downloaded bitfield v0.19.4 [INFO] [stderr] Downloaded cc v1.2.50 [INFO] [stderr] Downloaded io-uring v0.7.11 [INFO] [stderr] Downloaded zerocopy-derive v0.8.40 [INFO] [stderr] Downloaded serde-aco v0.12.0 [INFO] [stderr] Downloaded snafu-derive v0.8.9 [INFO] [stderr] Downloaded bitfield-macros v0.19.4 [INFO] [stderr] Downloaded ctor-proc-macro v0.0.7 [INFO] [stderr] Downloaded dtor-proc-macro v0.0.6 [INFO] [stderr] Downloaded flexi_logger v0.31.8 [INFO] [stderr] Downloaded zerocopy v0.8.40 [INFO] [stderr] Downloaded ctor v0.6.3 [INFO] [stderr] Downloaded serde-aco-derive v0.12.0 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-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] 7f372aa66917e133862cedcc3503293e7cc6209bfd5a259121c3034dc14e7af4 [INFO] running `Command { std: "docker" "start" "-a" "7f372aa66917e133862cedcc3503293e7cc6209bfd5a259121c3034dc14e7af4", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "7f372aa66917e133862cedcc3503293e7cc6209bfd5a259121c3034dc14e7af4", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "7f372aa66917e133862cedcc3503293e7cc6209bfd5a259121c3034dc14e7af4", kill_on_drop: false }` [INFO] [stdout] 7f372aa66917e133862cedcc3503293e7cc6209bfd5a259121c3034dc14e7af4 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-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] 8efa436537437f0e5b5f229e92fcad096716bd332380b516522e12e4c5768e4a [INFO] running `Command { std: "docker" "start" "-a" "8efa436537437f0e5b5f229e92fcad096716bd332380b516522e12e4c5768e4a", kill_on_drop: false }` [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 proc-macro2 v1.0.106 [INFO] [stderr] Compiling mio v1.1.1 [INFO] [stderr] Compiling parking_lot v0.12.5 [INFO] [stderr] Compiling chrono v0.4.44 [INFO] [stderr] Compiling quote v1.0.44 [INFO] [stderr] Compiling syn v2.0.117 [INFO] [stderr] Compiling serde_derive v1.0.228 [INFO] [stderr] Compiling snafu-derive v0.8.9 [INFO] [stderr] Compiling zerocopy-derive v0.8.40 [INFO] [stderr] Compiling serde-aco-derive v0.12.0 [INFO] [stderr] Compiling bitfield-macros v0.19.4 [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 31.08s [INFO] running `Command { std: "docker" "inspect" "8efa436537437f0e5b5f229e92fcad096716bd332380b516522e12e4c5768e4a", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "8efa436537437f0e5b5f229e92fcad096716bd332380b516522e12e4c5768e4a", kill_on_drop: false }` [INFO] [stdout] 8efa436537437f0e5b5f229e92fcad096716bd332380b516522e12e4c5768e4a [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-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] 918b2619f1febc458c47635ee080c6678a4234a1608af4cac964185d564457f5 [INFO] running `Command { std: "docker" "start" "-a" "918b2619f1febc458c47635ee080c6678a4234a1608af4cac964185d564457f5", kill_on_drop: false }` [INFO] [stderr] Compiling memchr v2.7.6 [INFO] [stderr] Compiling regex-syntax v0.8.8 [INFO] [stderr] Compiling semver v1.0.27 [INFO] [stderr] Compiling indexmap v2.12.1 [INFO] [stderr] Compiling toml_parser v1.0.6+spec-1.1.0 [INFO] [stderr] Compiling getrandom v0.3.4 [INFO] [stderr] Compiling thiserror v2.0.17 [INFO] [stderr] Compiling syn v2.0.117 [INFO] [stderr] Compiling dtor-proc-macro v0.0.6 [INFO] [stderr] Compiling relative-path v1.9.3 [INFO] [stderr] Compiling glob v0.3.3 [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 nu-ansi-term v0.50.3 [INFO] [stderr] Compiling futures-timer v3.0.3 [INFO] [stderr] Compiling rustc_version v0.4.1 [INFO] [stderr] Compiling dtor v0.1.1 [INFO] [stderr] Compiling ctor v0.6.3 [INFO] [stderr] Compiling tempfile v3.25.0 [INFO] [stderr] Compiling aho-corasick v1.1.4 [INFO] [stderr] Compiling rstest_macros v0.26.1 [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-automata v0.4.13 [INFO] [stderr] Compiling serde_derive v1.0.228 [INFO] [stderr] Compiling zerocopy-derive v0.8.40 [INFO] [stderr] Compiling snafu-derive v0.8.9 [INFO] [stderr] Compiling bitfield-macros v0.19.4 [INFO] [stderr] Compiling futures-macro v0.3.31 [INFO] [stderr] Compiling thiserror-impl v2.0.17 [INFO] [stderr] Compiling serde-aco-derive v0.12.0 [INFO] [stderr] Compiling alioth-macros v0.12.0 [INFO] [stderr] Compiling regex v1.12.2 [INFO] [stderr] Compiling futures-util v0.3.31 [INFO] [stderr] Compiling bitfield v0.19.4 [INFO] [stderr] Compiling snafu v0.8.9 [INFO] [stderr] Compiling flexi_logger v0.31.8 [INFO] [stderr] Compiling zerocopy v0.8.40 [INFO] [stderr] Compiling serde v1.0.228 [INFO] [stderr] Compiling rstest v0.26.1 [INFO] [stderr] Compiling serde-aco v0.12.0 [INFO] [stderr] Compiling alioth v0.12.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 41.21s [INFO] running `Command { std: "docker" "inspect" "918b2619f1febc458c47635ee080c6678a4234a1608af4cac964185d564457f5", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "918b2619f1febc458c47635ee080c6678a4234a1608af4cac964185d564457f5", kill_on_drop: false }` [INFO] [stdout] 918b2619f1febc458c47635ee080c6678a4234a1608af4cac964185d564457f5 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-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] 6a024575f582da1b4147a9490a2eafc3559f9b21af04eb184bbbe3a231885ef5 [INFO] running `Command { std: "docker" "start" "-a" "6a024575f582da1b4147a9490a2eafc3559f9b21af04eb184bbbe3a231885ef5", kill_on_drop: false }` [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.15s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/alioth-9498acb008d0bdf3) [INFO] [stdout] [INFO] [stdout] running 228 tests [INFO] [stdout] test blk::qcow2::tests::test_cmpr_desc_offset_size::case_2 ... ok [INFO] [stdout] test blk::qcow2::tests::test_l1entry_l2_offset::case_1 ... 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_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_5 ... ok [INFO] [stdout] test board::tests::test_cpu_topology_encode_decode::case_6 ... 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::tests::test_cpu_topology_encode_decode::case_9 ... ok [INFO] [stdout] test board::x86_64::tests::test_encode_x2apic::case_1 ... ok [INFO] [stdout] test board::tests::test_cpu_topology_encode_decode::case_1 ... ok [INFO] [stdout] test blk::qcow2::tests::test_cmpr_desc_offset_size::case_1 ... ok [INFO] [stdout] test board::tests::test_cpu_topology_encode_decode::case_4 ... 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_5 ... ok [INFO] [stdout] test board::x86_64::tests::test_encode_x2apic::case_3 ... 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_8 ... ok [INFO] [stdout] test board::x86_64::tests::test_encode_x2apic::case_9 ... ok [INFO] [stdout] test board::x86_64::tests::test_encode_x2apic::case_6 ... ok [INFO] [stdout] test device::cmos::tests::test_cmos_upgrade_in_progress ... ok [INFO] [stdout] test device::cmos::tests::test_cmos ... ok [INFO] [stdout] test device::fw_cfg::acpi::tests::test_size ... ok [INFO] [stdout] test device::fw_cfg::tests::test_fw_cfg_content_access::case_01 ... 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_04 ... ok [INFO] [stdout] test board::tests::test_cpu_topology_fixup ... ok [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_access::case_06 ... 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_10 ... ok [INFO] [stdout] test device::fw_cfg::tests::test_fw_cfg_content_access::case_05 ... 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_read::case_1 ... ok [INFO] [stdout] test device::fw_cfg::tests::test_fw_cfg_content_read::case_2 ... ok [INFO] [stdout] test board::x86_64::tests::test_encode_x2apic::case_2 ... 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_read::case_3 ... 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_read::case_6 ... 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_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_6 ... 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 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_02 ... ok [INFO] [stdout] test device::net::tests::test_mac_addr_visitor ... ok [INFO] [stdout] test firmware::dt::dtb::tests::test_prop_val_write_as_blob::case_03 ... ok [INFO] [stdout] test device::fw_cfg::tests::test_fw_cfg_content_size::case_4 ... ok [INFO] [stdout] test firmware::dt::dtb::tests::test_prop_val_write_as_blob::case_04 ... ok [INFO] [stdout] test firmware::acpi::bindings::tests::test_size ... 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_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_05 ... ok [INFO] [stdout] test firmware::dt::dtb::tests::test_string_block ... 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 hv::kvm::vm::x86_64::test::test_determine_vm_type::case_1 ... ok [INFO] [stdout] test firmware::dt::tests::test_val_size ... ok [INFO] [stdout] test hv::kvm::vm::x86_64::test::test_determine_vm_type::case_2 ... ok [INFO] [stdout] test firmware::ovmf::x86_64::tdx::tests::test_create_hob ... ok [INFO] [stdout] test firmware::dt::dtb::tests::test_prop_val_write_as_blob::case_06 ... ok [INFO] [stdout] test hv::kvm::vm::x86_64::test::test_determine_vm_type::case_3 ... ok [INFO] [stdout] test firmware::dt::dtb::tests::test_prop_val_write_as_blob::case_10 ... ok [INFO] [stdout] test hv::kvm::vm::x86_64::test::test_determine_vm_type::case_4 ... ok [INFO] [stdout] test hv::kvm::x86_64::tests::test_convert_cpuid_entry::case_1 ... ok [INFO] [stdout] test hv::kvm::vm::x86_64::test::test_translate_msi_addr::case_1 ... 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_2 ... 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_3 ... 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 hv::kvm::vm::x86_64::test::test_translate_msi_addr::case_5 ... 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_03 ... 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_05 ... 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_06 ... 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_10 ... 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_11 ... ok [INFO] [stdout] test mem::emulated::tests::test_mmio_bus_read::case_15 ... ok [INFO] [stdout] test mem::emulated::tests::test_mmio_bus_read::case_17 ... ok [INFO] [stdout] test mem::emulated::tests::test_mmio_bus_read::case_16 ... ok [INFO] [stdout] test mem::emulated::tests::test_mmio_bus_read::case_14 ... ok [INFO] [stdout] test mem::emulated::tests::test_mmio_bus_write::case_01 ... 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_06 ... 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_07 ... ok [INFO] [stdout] test mem::emulated::tests::test_mmio_bus_read::case_08 ... ok [INFO] [stdout] test mem::emulated::tests::test_mmio_bus_write::case_10 ... ok [INFO] [stderr] INFO [alioth::pci::config] 00:00.0: updating bar 0: 0x0000000c -> 0xec00000c, mask=0xfffff000 [INFO] [stderr] INFO [alioth::pci::config] 00:00.0: bar 0: write 0xec000000, update: 0x0000000c -> 0xec00000c [INFO] [stderr] INFO [alioth::mem::mapped] munmap(0x738158573000, 1000) = 0, done [INFO] [stderr] INFO [alioth::mem::mapped] munmap(0x738158572000, 1000) = 0, done [INFO] [stderr] ERROR [alioth::pci::cap] MsiCapMmio: write 0xaabbccdd to invalid offset 0xc. [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 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 pci::bus::tests::test_host_bridge ... ok [INFO] [stdout] test mem::emulated::tests::test_mmio_bus_read::case_12 ... ok [INFO] [stdout] test pci::bus::tests::test_pci_bus ... ok [INFO] [stdout] test mem::emulated::tests::test_mmio_bus_write::case_08 ... ok [INFO] [stdout] test mem::emulated::tests::test_mmio_bus_read::case_02 ... 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_11 ... ok [INFO] [stdout] test pci::bus::tests::test_pci_io_bus ... ok [INFO] [stdout] test mem::emulated::tests::test_mmio_bus_write::case_02 ... ok [INFO] [stdout] test pci::bus::tests::test_pci_io_bus_disabled_address ... ok [INFO] [stdout] test pci::bus::tests::test_pci_io_bus_unaligned_address_access ... ok [INFO] [stdout] test mem::mapped::tests::test_ram_bus_read ... ok [INFO] [stdout] test pci::cap::tests::test_msi_cap_mmio_32 ... 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_cap_mmio ... ok [INFO] [stdout] test pci::cap::tests::test_msix_msg_ctrl::case_1 ... ok [INFO] [stdout] test pci::cap::tests::test_msix_msg_ctrl::case_2 ... ok [INFO] [stdout] test pci::cap::tests::test_null_cap::case_1 ... ok [INFO] [stdout] test pci::cap::tests::test_msi_cap_mmio_32_pvm ... 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_null_cap::case_3 ... ok [INFO] [stdout] test pci::cap::tests::test_null_cap::case_6 ... ok [INFO] [stdout] test pci::cap::tests::test_null_cap::case_7 ... ok [INFO] [stdout] test pci::cap::tests::test_null_cap::case_5 ... ok [INFO] [stdout] test pci::cap::tests::test_msi_msg_ctrl_cap_size::case_4 ... ok [INFO] [stdout] test pci::cap::tests::test_pci_cap_list ... ok [INFO] [stderr] ERROR [alioth::device::fw_cfg] fw_cfg: reading File { fd: 3, path: "/tmp/.tmpKnMwO0/test_file (deleted)", read: true, write: false }: Error { kind: UnexpectedEof, message: "failed to fill whole buffer" } [INFO] [stdout] test pci::cap::tests::test_msi_cap_mmio_64_pvm ... ok [INFO] [stdout] test pci::cap::tests::test_pci_cap_list_default ... ok [INFO] [stdout] test pci::cap::tests::test_null_cap::case_8 ... ok [INFO] [stdout] test pci::config::tests::test_emulated_header_bar_callbacks ... ok [INFO] [stderr] INFO [alioth::pci::config] 00:01.0: updating bar 5: 0x0000e001 -> 0x0000c001, mask=0xfffffffc [INFO] [stdout] test pci::config::tests::test_emulated_header_change_command::case_1 ... ok [INFO] [stderr] INFO [alioth::pci::config] 00:01.0: updating bar 0: 0xe0000000 -> 0xd0000000, mask=0xfffff000 [INFO] [stdout] test pci::config::tests::test_emulated_header_change_command::case_2 ... ok [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 5: 0x0000e001 -> 0x0000f001, mask=0xfffffffc [INFO] [stdout] test pci::config::tests::test_emulated_header_change_command::case_3 ... ok [INFO] [stderr] INFO [alioth::pci::config] 00:01.0: updating bar 0: 0xe0000000 -> 0xfffff000, mask=0xfffff000 [INFO] [stdout] test pci::config::tests::test_emulated_header_change_command::case_4 ... ok [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_masks ... ok [INFO] [stderr] INFO [alioth::pci::config] 00:01.0: updating bar 3: 0x00000001 -> 0x00000002, mask=0xffffffff [INFO] [stdout] test pci::config::tests::test_emulated_config ... ok [INFO] [stderr] INFO [alioth::pci::config] 00:01.0: updating bar 2: 0x00000004 -> 0x80000004, mask=0xc0000000 [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_file_read ... ok [INFO] [stdout] test device::fw_cfg::tests::test_fw_cfg_content_file_size ... ok [INFO] [stderr] INFO [alioth::pci::config] 00:00.0: bar 5: set to 0x0000e000, update: 0x0000e001 -> 0x0000e001 [INFO] [stderr] INFO [alioth::pci::config] 00:00.0: updating bar 5: 0x0000e001 -> 0x0000e101, mask=0xfffffffc [INFO] [stderr] INFO [alioth::pci::config] 00:00.0: bar 5: set to 0x0000e100, update: 0x0000e001 -> 0x0000e101 [INFO] [stdout] test pci::cap::tests::test_null_cap::case_9 ... 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_5 ... 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_1 ... ok [INFO] [stderr] INFO [alioth::pci::config] 00:00.0: bar 3: set to 0x00000001, update: 0x00000001 -> 0x00000001 [INFO] [stderr] INFO [alioth::pci::config] 00:00.0: updating bar 0: 0xe0000000 -> 0xe0010000, mask=0xfffff000 [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_bar::case_2 ... ok [INFO] [stdout] test pci::config::tests::test_emulated_header_write_bar::case_3 ... ok [INFO] [stdout] test pci::config::tests::test_emulated_header_write_bar::case_4 ... ok [INFO] [stdout] test pci::config::tests::test_emulated_header_write_bar::case_8 ... ok [INFO] [stdout] test pci::cap::tests::test_null_cap::case_4 ... ok [INFO] [stdout] test pci::config::tests::test_emulated_header_write_bar::case_7 ... ok [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 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] [stdout] test pci::config::tests::test_emulated_header_write_status::case_4 ... ok [INFO] [stderr] INFO [alioth::pci::config] 00:00.0: bar 3: set to 0x00000002, update: 0x00000001 -> 0x00000002 [INFO] [stdout] test pci::config::tests::test_emulated_header_write_status::case_2 ... ok [INFO] [stderr] INFO [alioth::pci::pvpanic] pvpanic: PANICKED [INFO] [stdout] test pci::config::tests::test_io_bar_layout_change ... ok [INFO] [stderr] INFO [alioth::pci::config] 00:00.0: updating bar 0: 0x0000000c -> 0xee00000c, mask=0xfffff000 [INFO] [stdout] test pci::host_bridge::tests::test_host_bridge ... ok [INFO] [stderr] INFO [alioth::pci::config] 00:00.0: bar 0: write 0xee000000, update: 0x0000000c -> 0xee00000c [INFO] [stdout] test pci::pvpanic::tests::test_pvpanic_read_config::case_01 ... ok [INFO] [stderr] INFO [alioth::mem::mapped] munmap(0x738155c00000, 200000) = 0, done [INFO] [stdout] test pci::pvpanic::tests::test_pvpanic_read_config::case_02 ... ok [INFO] [stderr] INFO [alioth::mem::mapped] munmap(0x738155c00000, 200000) = 0, done [INFO] [stdout] test pci::pvpanic::tests::test_pvpanic_read_config::case_03 ... 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_07 ... 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_08 ... ok [INFO] [stdout] test pci::config::tests::test_emulated_header_write_status::case_1 ... ok [INFO] [stdout] test pci::pvpanic::tests::test_pvpanic_read_config::case_12 ... ok [INFO] [stdout] test pci::pvpanic::tests::test_pvpanic_read_config::case_09 ... ok [INFO] [stdout] test pci::segment::tests::test_pci_segment_next_bdf_wrapping ... 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::config::tests::test_mem_bar_layout_change ... ok [INFO] [stdout] test sys::linux::ioctl::tests::test_codes ... ok [INFO] [stdout] test pci::pvpanic::tests::test_pvpanic_bar ... ok [INFO] [stdout] test utils::endian::tests::test_big_endian ... ok [INFO] [stdout] test pci::pvpanic::tests::test_pvpanic_read_config::case_11 ... ok [INFO] [stdout] test utils::endian::tests::test_little_endian ... ok [INFO] [stderr] ERROR [alioth::virtio::dev::vsock::uds_vsock] vsock: queue RX: no enough writable buffers for VsockOp::RST [INFO] [stdout] test pci::segment::tests::test_pci_segment_mmio ... ok [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] [stdout] test pci::segment::tests::test_pci_segment_reserve::case_4 ... ok [INFO] [stderr] ERROR [alioth::virtio::dev::vsock] alioth::virtio::dev::vsock::VsockConfig: write 0x0000000000000000 to readonly offset 0x0. [INFO] [stdout] test utils::tests::test_align_up ... ok [INFO] [stderr] INFO [alioth::mem::mapped] munmap(0x738155600000, 200000) = 0, done [INFO] [stdout] test virtio::queue::packed::tests::disabled_queue ... ok [INFO] [stdout] test virtio::queue::packed::tests::index_wrapping_add::case_1 ... ok [INFO] [stderr] INFO [alioth::mem::mapped] munmap(0x738155200000, 200000) = 0, done [INFO] [stdout] test virtio::queue::packed::tests::enable_notification ... ok [INFO] [stderr] INFO [alioth::mem::mapped] munmap(0x738156000000, 200000) = 0, done [INFO] [stdout] test virtio::queue::packed::tests::index_wrapping_add::case_3 ... ok [INFO] [stderr] INFO [alioth::mem::mapped] munmap(0x738155200000, 200000) = 0, done [INFO] [stdout] test pci::segment::tests::test_pci_segment_reserve::case_3 ... ok [INFO] [stderr] INFO [alioth::mem::mapped] munmap(0x738155200000, 200000) = 0, done [INFO] [stdout] test virtio::queue::packed::tests::index_wrapping_add::case_4 ... ok [INFO] [stderr] INFO [alioth::mem::mapped] munmap(0x738155600000, 200000) = 0, done [INFO] [stdout] test virtio::queue::packed::tests::index_wrapping_add::case_2 ... ok [INFO] [stderr] INFO [alioth::mem::mapped] munmap(0x738154e00000, 200000) = 0, done [INFO] [stdout] test virtio::queue::packed::tests::index_wrapping_sub::case_1 ... ok [INFO] [stdout] test sync::notifier::tests::test_notifier ... ok [INFO] [stdout] test virtio::dev::vsock::uds_vsock::tests::vsock_config_test ... ok [INFO] [stdout] test virtio::queue::packed::tests::enabled_queue ... ok [INFO] [stdout] test virtio::queue::packed::tests::index_wrapping_sub::case_3 ... ok [INFO] [stdout] test virtio::dev::entropy::tests::entry_config_test ... 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_4 ... ok [INFO] [stdout] test virtio::queue::packed::tests::is_interrupt_enabled::case_03 ... ok [INFO] [stdout] test virtio::dev::vsock::uds_vsock::tests::vsock_conn_test ... ok [INFO] [stdout] test virtio::queue::packed::tests::is_interrupt_enabled::case_09 ... ok [INFO] [stdout] test virtio::queue::packed::tests::is_interrupt_enabled::case_05 ... ok [INFO] [stderr] INFO [alioth::mem::mapped] munmap(0x738154e00000, 200000) = 0, done [INFO] [stderr] INFO [alioth::mem::mapped] munmap(0x738155e00000, 200000) = 0, done [INFO] [stderr] INFO [alioth::mem::mapped] munmap(0x738155400000, 200000) = 0, done [INFO] [stdout] test virtio::queue::packed::tests::is_interrupt_enabled::case_01 ... ok [INFO] [stderr] INFO [alioth::mem::mapped] munmap(0x738156000000, 200000) = 0, done [INFO] [stderr] INFO [alioth::mem::mapped] munmap(0x738155400000, 200000) = 0, done [INFO] [stderr] INFO [alioth::mem::mapped] munmap(0x738155000000, 200000) = 0, done [INFO] [stderr] INFO [alioth::mem::mapped] munmap(0x738155200000, 200000) = 0, done [INFO] [stderr] INFO [alioth::mem::mapped] munmap(0x738154e00000, 200000) = 0, done [INFO] [stderr] INFO [alioth::mem::mapped] munmap(0x738154c00000, 200000) = 0, done [INFO] [stderr] INFO [alioth::mem::mapped] munmap(0x738154a00000, 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] [stdout] test virtio::dev::entropy::tests::entropy_test ... ok [INFO] [stdout] test virtio::queue::packed::tests::is_interrupt_enabled::case_02 ... ok [INFO] [stdout] test virtio::queue::packed::tests::is_interrupt_enabled::case_04 ... ok [INFO] [stdout] test virtio::queue::packed::tests::is_interrupt_enabled::case_12 ... 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_06 ... ok [INFO] [stdout] test virtio::queue::tests::test_copy_to_writer ... ok [INFO] [stderr] INFO [alioth::mem::mapped] munmap(0x738156000000, 200000) = 0, done [INFO] [stderr] INFO [alioth::mem::mapped] munmap(0x738155600000, 200000) = 0, done [INFO] [stderr] INFO [alioth::mem::mapped] munmap(0x738155400000, 200000) = 0, done [INFO] [stderr] INFO [alioth::mem::mapped] munmap(0x738155000000, 200000) = 0, done [INFO] [stdout] test virtio::queue::tests::test_written_bytes ... ok [INFO] [stdout] test virtio::queue::packed::tests::is_interrupt_enabled::case_07 ... ok [INFO] [stdout] test virtio::queue::split::tests::event_idx_enabled ... 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_10 ... ok [INFO] [stdout] test virtio::queue::split::tests::disabled_queue ... ok [INFO] [stdout] test virtio::queue::tests::test_handle_deferred ... ok [INFO] [stderr] INFO [alioth::mem::mapped] munmap(0x738156000000, 200000) = 0, done [INFO] [stdout] test virtio::queue::tests::test_copy_from_reader ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 224 passed; 0 failed; 4 ignored; 0 measured; 0 filtered out; finished in 0.03s [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" "6a024575f582da1b4147a9490a2eafc3559f9b21af04eb184bbbe3a231885ef5", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "6a024575f582da1b4147a9490a2eafc3559f9b21af04eb184bbbe3a231885ef5", kill_on_drop: false }` [INFO] [stdout] 6a024575f582da1b4147a9490a2eafc3559f9b21af04eb184bbbe3a231885ef5