01M35N33KPDMHF2JWGSFE4SGSE: test-gimlet

BasicConfig {
    output_rules: [],
    rust_toolchain: Some(
        Bool(
            false,
        ),
    ),
    target: Some(
        "lab-2.0-gimlet",
    ),
    access_repos: [],
    publish: [],
    skip_clone: true,
}

Buildomat Job: 01M35N3J86HV7G6A8Q1ZTHW218

Tags:

Output:

SEQ GLOBAL TIME DETAILS
12026-09-22T23:03:49.708Zjob dependencies complete; ready to run (waiting for 11 m 39 s)
22026-09-22T23:37:31.251Zjob assigned to worker 01M35QGJGMBFNQPKPN8GCWPG2H [factory edgar, gimlet/BRM42220010/1576] (queued for 33 m 41 s)
32026-09-22T23:37:31.807Zdownloading input: /input/phd-build/out/phd-run-with-args.sh
42026-09-22T23:37:31.832Zdownloaded input: /input/phd-build/out/phd-run-with-args.sh
52026-09-22T23:37:31.832Zdownloading input: /input/phd-build/out/phd-runner.sha256.txt
62026-09-22T23:37:31.874Zdownloaded input: /input/phd-build/out/phd-runner.sha256.txt
72026-09-22T23:37:31.874Zdownloading input: /input/phd-build/out/phd-runner.tar.gz
82026-09-22T23:37:40.899Zdownloaded input: /input/phd-build/out/phd-runner.tar.gz
92026-09-22T23:37:40.899Zdownloading input: /input/phd-build/out/propolis-server-debug.sha256.txt
102026-09-22T23:37:40.954Zdownloaded input: /input/phd-build/out/propolis-server-debug.sha256.txt
112026-09-22T23:37:40.954Zdownloading input: /input/phd-build/out/propolis-server-debug.tar.gz
122026-09-22T23:37:53.791Zdownloaded input: /input/phd-build/out/propolis-server-debug.tar.gz
132026-09-22T23:37:53.791Zdownloading input: /input/phd-build/out/propolis-tests-debug.tar.gz
142026-09-22T23:38:15.699Zdownloaded input: /input/phd-build/out/propolis-tests-debug.tar.gz
 
152026-09-22T23:38:15.699Zstarting task 0: "setup"
162026-09-22T23:38:15.724Z++ uname -s
172026-09-22T23:38:15.724Z+ kern=SunOS
182026-09-22T23:38:15.725Z+ build_user=build
192026-09-22T23:38:15.725Z+ build_uid=12345
202026-09-22T23:38:15.725Z+ work_dir=/work
212026-09-22T23:38:15.725Z+ input_dir=/input
222026-09-22T23:38:15.725Z+ [[ 0 == 12345 ]]
232026-09-22T23:38:15.725Z+ case "$kern" in
242026-09-22T23:38:15.725Z+ groupadd -g 12345 build
252026-09-22T23:38:15.750Z+ useradd -u 12345 -g build -d /home/build -s /bin/bash -c build -P 'Primary Administrator' build
262026-09-22T23:38:17.735Z+ zfs create -o mountpoint=/work rpool/work
272026-09-22T23:38:17.777Z++ awk '$2 == "/home" { print $3 }' /etc/mnttab
282026-09-22T23:38:17.802Z+ home_fs=
292026-09-22T23:38:17.802Z+ [[ '' == autofs ]]
302026-09-22T23:38:17.802Z+ mkdir -p /home/build
312026-09-22T23:38:17.802Z+ chown build:build /home/build /work
322026-09-22T23:38:19.790Z+ chmod 0700 /home/build /work
332026-09-22T23:38:19.814Zprocess exited: duration 4096 ms, exit code 0
 
342026-09-22T23:38:19.863Zstarting task 1: "authentication"
352026-09-22T23:38:19.933Zprocess exited: duration 70 ms, exit code 0
 
362026-09-22T23:38:19.982Zstarting task 2: "build"
372026-09-22T23:38:20.006Z+ banner prepare
382026-09-22T23:38:20.006Z
392026-09-22T23:38:20.006Z ##### ##### ###### ##### ## ##### ######
402026-09-22T23:38:20.007Z # # # # # # # # # # # #
412026-09-22T23:38:20.007Z # # # # ##### # # # # # # #####
422026-09-22T23:38:20.007Z ##### ##### # ##### ###### ##### #
432026-09-22T23:38:20.007Z # # # # # # # # # #
442026-09-22T23:38:20.007Z # # # ###### # # # # # ######
452026-09-22T23:38:20.007Z
462026-09-22T23:38:20.007Z+ TEST_TAR=propolis-tests-debug.tar.gz
472026-09-22T23:38:20.007Z+ cp /input/phd-build/out/propolis-tests-debug.tar.gz .
482026-09-22T23:38:20.190Z+ tar xvf propolis-tests-debug.tar.gz
492026-09-22T23:38:34.221ZDecompressing 'propolis-tests-debug.tar.gz' with '/usr/bin/gzcat'...
502026-09-22T23:38:34.221Zx target/debug/deps/propolis-16accc46ec7fe886, 918881248 bytes, 1794690 tape blocks
512026-09-22T23:38:34.246Z+ banner test-propolis
522026-09-22T23:38:34.246Z
532026-09-22T23:38:34.246Z ##### ###### #### ##### ##### ##### #### ##### ####
542026-09-22T23:38:34.246Z # # # # # # # # # # # # # #
552026-09-22T23:38:34.246Z # ##### #### # ##### # # # # # # # # # #
562026-09-22T23:38:34.246Z # # # # ##### ##### # # ##### # #
572026-09-22T23:38:34.246Z # # # # # # # # # # # # #
582026-09-22T23:38:34.246Z # ###### #### # # # # #### # ####
592026-09-22T23:38:34.246Z
602026-09-22T23:38:34.246Z+ TEST_DEV=prop_viona_test0
612026-09-22T23:38:34.246Z+ pfexec dladm create-etherstub prop_viona_test0
622026-09-22T23:38:34.246Z+ for testbin in ./target/debug/deps/propolis-*
632026-09-22T23:38:34.246Z+ VIONA_TEST_NIC=prop_viona_test0
642026-09-22T23:38:34.246Z+ pfexec ptime -m ./target/debug/deps/propolis-16accc46ec7fe886
652026-09-22T23:38:34.270Z
662026-09-22T23:38:34.270Zrunning 195 tests
672026-09-22T23:38:34.316Ztest block::crucible::test::block_calc_ok ... ok
682026-09-22T23:38:34.316Ztest accessors::test::adopt_nonroot - should panic ... ok
692026-09-22T23:38:34.316Ztest accessors::test::adopt_self - should panic ... ok
702026-09-22T23:38:34.316Ztest accessors::test::only_root_can_remove_resource - should panic ... ok
712026-09-22T23:38:34.316Ztest accessors::test::simple_orphan ... ok
722026-09-22T23:38:34.316Ztest block::crucible::test::err_on_bad_size ... ok
732026-09-22T23:38:34.316Ztest accessors::test::orphan_sibling ... ok
742026-09-22T23:38:34.316Ztest block::crucible::test::ok_for_valid ... ok
752026-09-22T23:38:34.316Ztest accessors::test::simple_depth ... ok
762026-09-22T23:38:34.316Ztest accessors::test::orphan_split ... ok
772026-09-22T23:38:34.316Ztest accessors::test::tree_root ... ok
782026-09-22T23:38:34.316Ztest accessors::test::print_names ... ok
792026-09-22T23:38:34.316Ztest block::crucible::test::err_on_bad_offset ... ok
802026-09-22T23:38:34.316Ztest chardev::pollers::test_copy_and_consume_one_u8 ... ok
812026-09-22T23:38:34.316Ztest chardev::pollers::test_copy_and_consume_1 ... ok
822026-09-22T23:38:34.316Ztest common::test::readop_base_size ... ok
832026-09-22T23:38:34.316Ztest common::test::readop_oversize - should panic ... ok
842026-09-22T23:38:34.316Ztest common::device_metadata_test::device_metadata_test ... ok
852026-09-22T23:38:34.316Ztest common::test::readop_short ... ok
862026-09-22T23:38:34.316Ztest common::test::writeop_base_size ... ok
872026-09-22T23:38:34.316Ztest common::test::writeop_short ... ok
882026-09-22T23:38:34.316Ztest common::test::writeop_oversize - should panic ... ok
892026-09-22T23:38:34.316Ztest chardev::pollers::test::read_empty_returns_zero_bytes ... ok
902026-09-22T23:38:34.316Ztest chardev::pollers::test::write_empty_fills_zero_bytes ... ok
912026-09-22T23:38:34.316Ztest chardev::pollers::test::write_bytes ... ok
922026-09-22T23:38:34.316Ztest chardev::pollers::test::read_byte ... ok
932026-09-22T23:38:34.316Ztest chardev::pollers::test::write_bytes_beyond_internal_buffer_size ... ok
942026-09-22T23:38:34.316Ztest chardev::pollers::test::read_bytes ... ok
952026-09-22T23:38:34.316Ztest chardev::pollers::test::write_byte ... ok
962026-09-22T23:38:34.316Ztest enlightenment::hyperv::overlay::test::basic_add ... ok
972026-09-22T23:38:34.316Ztest enlightenment::hyperv::overlay::test::out_of_bounds_pfn ... ok
982026-09-22T23:38:34.316Ztest enlightenment::hyperv::overlay::test::basic_move ... ok
992026-09-22T23:38:34.317Ztest enlightenment::hyperv::overlay::test::multiple_overlays ... ok
1002026-09-22T23:38:34.317Ztest firmware::acpi::dsdt::test::field_references ... ok
1012026-09-22T23:38:34.317Ztest enlightenment::hyperv::overlay::test::duplicate_kind_at_pfn ... ok
1022026-09-22T23:38:34.317Ztest firmware::acpi::dsdt::test::dsdt_generator_aml ... ok
1032026-09-22T23:38:34.317Ztest firmware::acpi::fadt::test::field_references ... ok
1042026-09-22T23:38:34.317Ztest firmware::acpi::rsdp::test::field_references ... ok
1052026-09-22T23:38:34.317Ztest firmware::acpi::dsdt::test::panic_if_generator_not_called - should panic ... ok
1062026-09-22T23:38:34.317Ztest firmware::smbios::bits::test::baseboard_information ... ok
1072026-09-22T23:38:34.317Ztest firmware::acpi::ssdt_edk2::test::field_references ... ok
1082026-09-22T23:38:34.317Ztest firmware::smbios::bits::test::bios_information ... ok
1092026-09-22T23:38:34.317Ztest enlightenment::hyperv::overlay::test::remove_nonexistent_overlay ... ok
1102026-09-22T23:38:34.317Ztest firmware::smbios::bits::test::entry_point ... ok
1112026-09-22T23:38:34.317Ztest firmware::acpi::dsdt::test::dsdt_valid_aml ... ok
1122026-09-22T23:38:34.317Ztest firmware::smbios::bits::test::memory_device ... ok
1132026-09-22T23:38:34.317Ztest chardev::sock::test::abort_wait_for_connect ... ok
1142026-09-22T23:38:34.317Ztest firmware::smbios::bits::test::physical_memory_array ... ok
1152026-09-22T23:38:34.317Ztest enlightenment::hyperv::overlay::test::underlay_restored_after_drop ... ok
1162026-09-22T23:38:34.317Ztest firmware::smbios::bits::test::processor_information ... ok
1172026-09-22T23:38:34.317Ztest firmware::smbios::bits::test::struct_header ... ok
1182026-09-22T23:38:34.317Ztest firmware::smbios::bits::test::system_boot_information ... ok
1192026-09-22T23:38:34.317Ztest firmware::smbios::bits::test::system_information ... ok
1202026-09-22T23:38:34.317Ztest firmware::smbios::bits::test::system_enclosure ... ok
1212026-09-22T23:38:34.317Ztest chardev::sock::test::bail_on_shutdown_sock ... ok
1222026-09-22T23:38:34.317Ztest firmware::smbios::table::type4::test::proc_type_serde_roundtrip ... ok
1232026-09-22T23:38:34.317Ztest firmware::smbios::table::type16::test::array_use_serde_roundtrip ... ok
1242026-09-22T23:38:34.317Ztest firmware::smbios::table::type4::test::proc_status_serde_roundtrip ... ok
1252026-09-22T23:38:34.342Ztest firmware::smbios::table::type16::test::error_correction_serde_roundtrip ... ok
1262026-09-22T23:38:34.342Ztest firmware::smbios::table::type4::test::proc_type_deserialize ... ok
1272026-09-22T23:38:34.342Ztest firmware::smbios::table::type16::test::error_correction_deserialize ... ok
1282026-09-22T23:38:34.342Ztest firmware::smbios::table::type16::test::array_use_deserialize ... ok
1292026-09-22T23:38:34.342Ztest firmware::smbios::table::type4::test::proc_status_deserialize ... ok
1302026-09-22T23:38:34.342Ztest firmware::smbios::table::type16::test::location_deserialize ... ok
1312026-09-22T23:38:34.342Ztest firmware::smbios::table::type1::test::wake_up_type_serde_roundtrip ... ok
1322026-09-22T23:38:34.342Ztest hw::nvme::bits::test::entry_sizing ... ok
1332026-09-22T23:38:34.342Ztest firmware::smbios::table::type1::test::wake_up_type_deserialize ... ok
1342026-09-22T23:38:34.342Ztest hw::nvme::cmds::test::test_dsmgmt_ranges ... ok
1352026-09-22T23:38:34.342Ztest firmware::smbios::table::type16::test::location_serde_roundtrip ... ok
1362026-09-22T23:38:34.342Ztest hw::chipset::i440fx::test::lpc_pci_cfg_write ... ok
1372026-09-22T23:38:34.342Ztest hw::chipset::i440fx::test::hb_pci_cfg_read ... ok
1382026-09-22T23:38:34.342Ztest hw::chipset::i440fx::test::lpc_pci_cfg_read ... ok
1392026-09-22T23:38:34.342Ztest hw::chipset::i440fx::test::hb_pci_cfg_write ... ok
1402026-09-22T23:38:34.343Ztest hw::nvme::cmds::test::test_dsmgmt_ranges_bad_dual ... ok
1412026-09-22T23:38:34.343Ztest hw::nvme::cmds::test::test_dsmgmt_ranges_bad_address ... ok
1422026-09-22T23:38:34.343Ztest hw::chipset::i440fx::test::pm_pci_cfg_read ... ok
1432026-09-22T23:38:34.343Ztest hw::chipset::i440fx::test::pm_pci_cfg_write ... ok
1442026-09-22T23:38:34.343Ztest hw::pci::bar::test::limits ... ok
1452026-09-22T23:38:34.343Ztest hw::pci::bar::test::init ... ok
1462026-09-22T23:38:34.343Ztest hw::nvme::cmds::test::test_dsmgmt_ranges_dual ... ok
1472026-09-22T23:38:34.343Ztest hw::nvme::cmds::test::test_prp_single ... ok
1482026-09-22T23:38:34.343Ztest hw::nvme::cmds::test::test_prp_multiple ... ok
1492026-09-22T23:38:34.343Ztest hw::pci::bus::test::empty ... ok
1502026-09-22T23:38:34.343Ztest hw::nvme::cmds::test::test_prp_dual ... ok
1512026-09-22T23:38:34.343Ztest hw::pci::bus::test::set_multifunc ... ok
1522026-09-22T23:38:34.343Ztest hw::pci::bar::test::write_place ... ok
1532026-09-22T23:38:34.343Ztest hw::pci::bar::test::read_type ... ok
1542026-09-22T23:38:34.343Ztest hw::pci::test::pcie_decoder_access_spans_multiple_devs ... ok
1552026-09-22T23:38:34.343Ztest hw::pci::test::pcie_decoder_min_buses ... ok
1562026-09-22T23:38:34.343Ztest chardev::pollers::test::read_bytes_blocking ... ok
1572026-09-22T23:38:34.343Ztest hw::pci::device::test::msix_cfg_too_big - should panic ... ok
1582026-09-22T23:38:34.343Ztest hw::pci::device::test::msix_cfg_zero - should panic ... ok
1592026-09-22T23:38:34.343Ztest hw::pci::test::pcie_decoder_multiple_bdfs ... ok
1602026-09-22T23:38:34.343Ztest hw::pci::test::pcie_decoder ... ok
1612026-09-22T23:38:34.343Ztest hw::pci::bridge::test::bridge_properties ... ok
1622026-09-22T23:38:34.343Ztest hw::pci::topology::test::builder_bus_zero_reserved ... ok
1632026-09-22T23:38:34.343Ztest hw::pci::topology::test::builder_conflicts ... ok
1642026-09-22T23:38:34.343Ztest hw::nvme::cmds::test::test_prp_list ... ok
1652026-09-22T23:38:34.343Ztest hw::nvme::cmds::test::test_prp_list_offset_last ... ok
1662026-09-22T23:38:34.343Ztest hw::pci::bridge::test::bridge_routing ... ok
1672026-09-22T23:38:34.343Ztest hw::pci::topology::test::cfg_read ... ok
1682026-09-22T23:38:34.343Ztest hw::pci::topology::test::build_without_bridges ... ok
1692026-09-22T23:38:34.343Ztest hw::qemu::fwcfg::test::pio_read_basic ... ok
1702026-09-22T23:38:34.343Ztest hw::qemu::fwcfg::formats::test_bootorder::basic ... ok
1712026-09-22T23:38:34.343Ztest hw::qemu::fwcfg::formats::test_pci_window::basic ... ok
1722026-09-22T23:38:34.343Ztest hw::qemu::fwcfg::formats::test_table_loader::basic ... ok
1732026-09-22T23:38:34.343Ztest hw::pci::topology::test::build_with_bridges ... ok
1742026-09-22T23:38:34.343Ztest hw::qemu::fwcfg::test::read_version ... ok
1752026-09-22T23:38:34.343Ztest hw::qemu::fwcfg::formats::test_e820::basic ... ok
1762026-09-22T23:38:34.343Ztest hw::pci::topology::test::created_bridges ... ok
1772026-09-22T23:38:34.343Ztest hw::qemu::fwcfg::formats::test_e820::entry_size_is_correct ... ok
1782026-09-22T23:38:34.343Ztest hw::uart::uart16550::test::safe_read_write_all ... ok
1792026-09-22T23:38:34.344Ztest hw::uart::uart16550::test::interrupt_codes ... ok
1802026-09-22T23:38:34.344Ztest hw::qemu::fwcfg::test::dma_read_basic ... ok
1812026-09-22T23:38:34.344Ztest hw::qemu::ramfb::test::config_reg_size ... ok
1822026-09-22T23:38:34.344Ztest hw::qemu::fwcfg::test::pio_read_missing ... ok
1832026-09-22T23:38:34.344Ztest hw::uart::uart16550::test::intr_dr_on_incoming ... ok
1842026-09-22T23:38:34.344Ztest hw::uart::uart16550::test::intr_thre_on_etbei_toggle ... ok
1852026-09-22T23:38:34.344Ztest hw::uart::uart16550::test::reset_state ... ok
1862026-09-22T23:38:34.344Ztest hw::qemu::fwcfg::test::struct_sizing ... ok
1872026-09-22T23:38:34.344Ztest hw::uart::uart16550::test::intr_thre_on_outgoing ... ok
1882026-09-22T23:38:34.344Ztest hw::virtio::testutil::test::empty_avail_ring_returns_none ... ok
1892026-09-22T23:38:34.344Ztest hw::qemu::fwcfg::test::state_cleared_on_reset ... ok
1902026-09-22T23:38:34.344Ztest test::print_version ... ok
1912026-09-22T23:38:34.344Ztest util::aspace::test::inverse_iterator_alloc_ends ... ok
1922026-09-22T23:38:34.344Ztest hw::virtio::testutil::test::smoke_chained_descriptors ... ok
1932026-09-22T23:38:34.344Ztest hw::virtio::testutil::test::multi_queue_smoke ... ok
1942026-09-22T23:38:34.344Ztest hw::virtio::viona::test::test_common_cfg_size_is_right ... ok
1952026-09-22T23:38:34.344Ztest util::aspace::test::create_normal ... ok
1962026-09-22T23:38:34.344Ztest hw::virtio::testutil::test::smoke_mixed_chain ... ok
1972026-09-22T23:38:34.344Ztest util::aspace::test::inverse_iterator_alloc_middle ... ok
1982026-09-22T23:38:34.344Ztest util::aspace::test::create_one_elem ... ok
1992026-09-22T23:38:34.344Ztest util::aspace::test::inverse_iterator_full ... ok
2002026-09-22T23:38:34.344Ztest hw::virtio::testutil::test::smoke_pop_avail_readable ... ok
2012026-09-22T23:38:34.344Ztest util::aspace::test::inverse_iterator_empty ... ok
2022026-09-22T23:38:34.344Ztest util::aspace::test::region_at_normal ... ok
2032026-09-22T23:38:34.344Ztest hw::qemu::fwcfg::test::dma_read_missing ... ok
2042026-09-22T23:38:34.344Ztest util::aspace::test::region_at_outside ... ok
2052026-09-22T23:38:34.344Ztest util::aspace::test::inverse_iterator_sequential_registrations ... ok
2062026-09-22T23:38:34.344Ztest util::aspace::test::register_invalid ... ok
2072026-09-22T23:38:34.344Ztest util::aspace::test::register_outside ... ok
2082026-09-22T23:38:34.344Ztest util::aspace::test::create_max ... ok
2092026-09-22T23:38:34.344Ztest util::aspace::test::bounds_accessors ... ok
2102026-09-22T23:38:34.344Ztest util::aspace::test::register_overlaps ... ok
2112026-09-22T23:38:34.344Ztest util::aspace::test::register_plain ... ok
2122026-09-22T23:38:34.344Ztest vmm::mem::test::mapping_create_empty ... ok
2132026-09-22T23:38:34.344Ztest util::regmap::test::misaligned ... ok
2142026-09-22T23:38:34.344Ztest util::regmap::test::simple ... ok
2152026-09-22T23:38:34.344Ztest vmm::mem::test::memory_protections_match_libc ... ok
2162026-09-22T23:38:34.344Ztest vmm::time::test::test_calc_boot_hrtime ... ok
2172026-09-22T23:38:34.344Ztest vmm::mem::test::mapping_shortens_write_bytes_beyond_end ... ok
2182026-09-22T23:38:34.344Ztest vmm::time::test::test_calc_boot_hrtime_delta ... ok
2192026-09-22T23:38:34.345Ztest hw::virtio::testutil::test::queue_writer_reset_cursors ... ok
2202026-09-22T23:38:34.345Ztest vmm::time::test::test_calc_guest_tsc ... ok
2212026-09-22T23:38:34.345Ztest vmm::time::test::test_calc_guest_uptime ... ok
2222026-09-22T23:38:34.345Ztest vmm::mem::test::mapping_invalid_subregions ... ok
2232026-09-22T23:38:34.345Ztest hw::virtio::testutil::test::multiple_chains ... ok
2242026-09-22T23:38:34.345Ztest vmm::mem::test::mapping_valid_subregions ... ok
2252026-09-22T23:38:34.370Ztest vmm::time::test::test_calc_migrate_delta ... ok
2262026-09-22T23:38:34.370Ztest vmm::time::test::test_calc_tsc_delta ... ok
2272026-09-22T23:38:34.370Ztest vsock::buffer::test::test_push_less_than_capacity ... ok
2282026-09-22T23:38:34.370Ztest vmm::mem::test::subregion_protection ... ok
2292026-09-22T23:38:34.370Ztest vmm::time::test::test_invalid_hrtime ... ok
2302026-09-22T23:38:34.370Ztest vsock::buffer::test::test_capacity_and_len ... ok
2312026-09-22T23:38:34.370Ztest hw::virtio::testutil::test::smoke_pop_avail_writable ... ok
2322026-09-22T23:38:34.370Ztest vsock::buffer::test::test_partial_write_to ... ok
2332026-09-22T23:38:34.370Ztest vmm::mem::test::mapping_shortens_read_bytes_beyond_end ... ok
2342026-09-22T23:38:34.370Ztest vsock::buffer::test::test_push_more_than_capacity ... ok
2352026-09-22T23:38:34.370Ztest vsock::buffer::test::test_write_to ... ok
2362026-09-22T23:38:34.370Ztest vsock::buffer::test::test_wrap_around ... ok
2372026-09-22T23:38:34.370Ztest vmm::mem::test::mapping_denies_read_beyond_end ... ok
2382026-09-22T23:38:34.370Ztest util::aspace::safe_end_bounds ... ok
2392026-09-22T23:38:34.370Ztest vmm::mem::test::region_lookup ... ok
2402026-09-22T23:38:34.370ZSep 22 23:38:34.286 INFO vsock poller configured with, mappings: {}, component: vsock-test
2412026-09-22T23:38:34.370ZSep 22 23:38:34.286 INFO vsock poller configured with, mappings: {7000: VsockPortMapping { port: 7000, addr: 127.0.0.1:59528 }}, component: vsock-test
2422026-09-22T23:38:34.370ZSep 22 23:38:34.287 INFO vsock poller configured with, Sep 22 23:38:34.288mappings :INFOSep 22 23:38:34.288 {9300: VsockPortMapping { port: 9300, addr: 127.0.0.1:56336 }}vsock poller configured with, component, :mappings :vsock-test
2432026-09-22T23:38:34.370Z{6000: VsockPortMapping { port: 6000, addr: 127.0.0.1:41089 }}, component: vsock-test
2442026-09-22T23:38:34.370ZINFO vsock poller configured with, mappings: {8000: VsockPortMapping { port: 8000, addr: 127.0.0.1:40293 }}, component: vsock-test
2452026-09-22T23:38:34.370ZSep 22 23:38:34.289 INFO vsock poller configured with, mappings: {5000: VsockPortMapping { port: 5000, addr: 127.0.0.1:52193 }}, componentSep 22 23:38:34.289: INFOvsock-test
2462026-09-22T23:38:34.370Zvsock poller configured with, mappings: {9000: VsockPortMapping { port: 9000, addr: 127.0.0.1:54303 }}, component: vsock-test
2472026-09-22T23:38:34.370ZSep 22 23:38:34.289 INFO vsock poller configured with, mappings: {9500: VsockPortMapping { port: 9500, addr: 127.0.0.1:41003 }}, component: vsock-test
2482026-09-22T23:38:34.370ZSep 22 23:38:34.289Sep 22 23:38:34.289 INFOINFO vsock poller configured withvsock poller configured with, , mappingsmappings:: {9400: VsockPortMapping { port: 9400, addr: 127.0.0.1:45370 }}{3000: VsockPortMapping { port: 3000, addr: 127.0.0.1:35458 }}, , componentcomponent:: vsock-testvsock-test
2492026-09-22T23:38:34.370Z
2502026-09-22T23:38:34.370ZSep 22 23:38:34.286 INFO vsock poller configured with, mappings: {4000: VsockPortMapping { port: 4000, addr: 127.0.0.1:43502 }}, component: vsock-test
2512026-09-22T23:38:34.370ZSep 22 23:38:34.290 INFO vsock poller configured with, mappings: {3000: VsockPortMapping { port: 3000, addr: 127.0.0.1:43222 }}, component: vsock-test
2522026-09-22T23:38:34.370Ztest vsock::poller::test::halt_from_paused ... ok
2532026-09-22T23:38:34.370Ztest vsock::poller::test::end_to_end_guest_to_host ... ok
2542026-09-22T23:38:34.370ZSep 22 23:38:34.291 INFO vsock poller configured with, mappings: {}, component: vsock-test
2552026-09-22T23:38:34.371ZSep 22 23:38:34.292 WARN received invalid vsock packet type, packet: VsockPacket { header: VsockPacketHeader { src_cid: U64(50), dst_cid: U64(2), src_port: U32(5555), dst_port: U32(8080), len: U32(0), socket_type: U16(478), op: U16(5), flags: U32(0), buf_alloc: U32(65536), fwd_cnt: U32(0) }, data_len: 0 }, component: vsock-test
2562026-09-22T23:38:34.371Ztest vsock::poller::test::reset_clears_connections ... ok
2572026-09-22T23:38:34.371Ztest vsock::poller::test::rst_removes_established_connection ... ok
2582026-09-22T23:38:34.371Ztest vsock::poller::test::request_then_rw_delivers_data ... ok
2592026-09-22T23:38:34.371Ztest vsock::poller::test::request_receives_response ... ok
2602026-09-22T23:38:34.371Ztest vsock::poller::test::end_to_end_guest_to_host_closes_half_open ... ok
2612026-09-22T23:38:34.371Ztest vsock::poller::test::rw_with_invalid_socket_type_receives_rst ... ok
2622026-09-22T23:38:34.371Ztest vsock::poller::test::pause_resume_preserves_connections ... ok
2632026-09-22T23:38:34.371Ztest vsock::poller::test::credit_update_sent_after_flushing_half_buffer ... ok
2642026-09-22T23:38:34.371Ztest hw::nvme::queue::test::create_sqs ... ok
2652026-09-22T23:38:34.371Ztest hw::nvme::queue::test::push_failures ... ok
2662026-09-22T23:38:34.371Ztest hw::nvme::queue::test::cq_kicks ... ok
2672026-09-22T23:38:34.371Ztest hw::nvme::queue::test::create_cqs ... ok
2682026-09-22T23:38:34.371Ztest hw::pci::device::test::msix_cfg_sizing ... ok
2692026-09-22T23:38:34.429Ztest vsock::poller::test::rx_blocked_resumes_when_descriptors_available ... ok
2702026-09-22T23:38:35.010Ztest hw::virtio::viona::test::run_viona_tests ... ok
2712026-09-22T23:38:35.187Ztest hw::nvme::queue::test::push_pop ... ok
2722026-09-22T23:38:35.282Ztest vsock::poller::test::end_to_end_large_data ... ok
2732026-09-22T23:38:36.470Ztest hw::nvme::test::fuzzy ... FAILED
2742026-09-22T23:38:38.304Ztest vsock::poller::test::host_socket_eof_sends_shutdown ... ok
2752026-09-22T23:38:38.351Ztest hw::pci::propolis_types_pcipath_is_always_valid_bdf ... ok
2762026-09-22T23:38:38.351Z
2772026-09-22T23:38:38.351Zfailures:
2782026-09-22T23:38:38.351Z
2792026-09-22T23:38:38.351Z---- hw::nvme::test::fuzzy stdout ----
2802026-09-22T23:38:38.351Zfuzzing nvme from seed 0xb4f66d9acb932c52
2812026-09-22T23:38:38.351Zoperation: TestAction { op: Init, result: Ok }
2822026-09-22T23:38:38.351Zdoing init!
2832026-09-22T23:38:38.351Zcontroller enabled
2842026-09-22T23:38:38.351Z[1, 0, 0, 0]
2852026-09-22T23:38:38.351Zoperation: TestAction { op: DeleteSQ(10), result: Err }
2862026-09-22T23:38:38.351Zsq (10) lives another day
2872026-09-22T23:38:38.351Zoperation: TestAction { op: DeleteCQ(11), result: Err }
2882026-09-22T23:38:38.351Zoperation: TestAction { op: Migrate, result: Ok }
2892026-09-22T23:38:38.351Zoperation: TestAction { op: CreateCQ(8), result: Ok }
2902026-09-22T23:38:38.351Zoperation: TestAction { op: CreateSQ(9), result: Err }
2912026-09-22T23:38:38.351Zcreating sq 9
2922026-09-22T23:38:38.351Zoperation: TestAction { op: DeleteSQ(7), result: Err }
2932026-09-22T23:38:38.351Zsq (7) lives another day
2942026-09-22T23:38:38.351Zoperation: TestAction { op: DeleteSQ(9), result: Err }
2952026-09-22T23:38:38.351Zsq (9) lives another day
2962026-09-22T23:38:38.351Zoperation: TestAction { op: Migrate, result: Ok }
2972026-09-22T23:38:38.351Zoperation: TestAction { op: DeleteCQ(3), result: Err }
2982026-09-22T23:38:38.351Zoperation: TestAction { op: CreateSQ(1), result: Err }
2992026-09-22T23:38:38.351Zcreating sq 1
3002026-09-22T23:38:38.352Zoperation: TestAction { op: CreateCQ(6), result: Ok }
3012026-09-22T23:38:38.352Zoperation: TestAction { op: DeleteCQ(13), result: Err }
3022026-09-22T23:38:38.352Zoperation: TestAction { op: CreateCQ(8), result: Err }
3032026-09-22T23:38:38.352Zoperation: TestAction { op: Migrate, result: Ok }
3042026-09-22T23:38:38.352Zoperation: TestAction { op: DeleteSQ(15), result: Err }
3052026-09-22T23:38:38.352Zsq (15) lives another day
3062026-09-22T23:38:38.352Zoperation: TestAction { op: Migrate, result: Ok }
3072026-09-22T23:38:38.352Zoperation: TestAction { op: DeleteCQ(6), result: Ok }
3082026-09-22T23:38:38.352Zoperation: TestAction { op: CreateCQ(2), result: Ok }
3092026-09-22T23:38:38.352Zoperation: TestAction { op: CreateSQ(6), result: Err }
3102026-09-22T23:38:38.352Zcreating sq 6
3112026-09-22T23:38:38.352Zoperation: TestAction { op: DeleteSQ(11), result: Err }
3122026-09-22T23:38:38.352Zsq (11) lives another day
3132026-09-22T23:38:38.352Zoperation: TestAction { op: CreateSQ(11), result: Err }
3142026-09-22T23:38:38.352Zcreating sq 11
3152026-09-22T23:38:38.352Zoperation: TestAction { op: CreateSQ(15), result: Err }
3162026-09-22T23:38:38.352Zcreating sq 15
3172026-09-22T23:38:38.352Zoperation: TestAction { op: DeleteSQ(13), result: Err }
3182026-09-22T23:38:38.352Zsq (13) lives another day
3192026-09-22T23:38:38.352Zoperation: TestAction { op: DeleteCQ(14), result: Err }
3202026-09-22T23:38:38.352Zoperation: TestAction { op: Migrate, result: Ok }
3212026-09-22T23:38:38.352Zoperation: TestAction { op: DeleteSQ(14), result: Err }
3222026-09-22T23:38:38.352Zsq (14) lives another day
3232026-09-22T23:38:38.352Zoperation: TestAction { op: CreateSQ(1), result: Err }
3242026-09-22T23:38:38.352Zcreating sq 1
3252026-09-22T23:38:38.352Zoperation: TestAction { op: DeleteSQ(13), result: Err }
3262026-09-22T23:38:38.352Zsq (13) lives another day
3272026-09-22T23:38:38.352Zoperation: TestAction { op: DeleteSQ(3), result: Err }
3282026-09-22T23:38:38.352Zsq (3) lives another day
3292026-09-22T23:38:38.352Zoperation: TestAction { op: CreateSQ(3), result: Err }
3302026-09-22T23:38:38.352Zcreating sq 3
3312026-09-22T23:38:38.352Zoperation: TestAction { op: DeleteCQ(8), result: Ok }
3322026-09-22T23:38:38.352Zoperation: TestAction { op: DeleteCQ(7), result: Err }
3332026-09-22T23:38:38.352Zoperation: TestAction { op: CreateCQ(3), result: Ok }
3342026-09-22T23:38:38.352Zoperation: TestAction { op: CreateSQ(3), result: Ok }
3352026-09-22T23:38:38.352Zcreating sq 3
3362026-09-22T23:38:38.352Zoperation: TestAction { op: Migrate, result: Ok }
3372026-09-22T23:38:38.352Zoperation: TestAction { op: DeleteSQ(1), result: Err }
3382026-09-22T23:38:38.352Zsq (1) lives another day
3392026-09-22T23:38:38.353Zoperation: TestAction { op: DeleteCQ(14), result: Err }
3402026-09-22T23:38:38.353Zoperation: TestAction { op: CreateCQ(6), result: Ok }
3412026-09-22T23:38:38.353Zoperation: TestAction { op: Migrate, result: Ok }
3422026-09-22T23:38:38.353Zoperation: TestAction { op: CreateCQ(9), result: Ok }
3432026-09-22T23:38:38.353Zoperation: TestAction { op: CreateCQ(4), result: Ok }
3442026-09-22T23:38:38.353Zoperation: TestAction { op: DeleteSQ(11), result: Err }
3452026-09-22T23:38:38.353Zsq (11) lives another day
3462026-09-22T23:38:38.353Zoperation: TestAction { op: DeleteCQ(4), result: Ok }
3472026-09-22T23:38:38.353Zoperation: TestAction { op: CreateCQ(15), result: Ok }
3482026-09-22T23:38:38.353Zoperation: TestAction { op: CreateSQ(16), result: Err }
3492026-09-22T23:38:38.353Zcreating sq 16
3502026-09-22T23:38:38.353Zoperation: TestAction { op: DeleteCQ(2), result: Ok }
3512026-09-22T23:38:38.353Zoperation: TestAction { op: CreateCQ(8), result: Ok }
3522026-09-22T23:38:38.353Zoperation: TestAction { op: Migrate, result: Ok }
3532026-09-22T23:38:38.353Zoperation: TestAction { op: CreateSQ(12), result: Err }
3542026-09-22T23:38:38.353Zcreating sq 12
3552026-09-22T23:38:38.353Zoperation: TestAction { op: CreateSQ(4), result: Err }
3562026-09-22T23:38:38.353Zcreating sq 4
3572026-09-22T23:38:38.353Zoperation: TestAction { op: CreateSQ(4), result: Err }
3582026-09-22T23:38:38.353Zcreating sq 4
3592026-09-22T23:38:38.353Zoperation: TestAction { op: DeleteSQ(10), result: Err }
3602026-09-22T23:38:38.353Zsq (10) lives another day
3612026-09-22T23:38:38.353Zoperation: TestAction { op: Migrate, result: Ok }
3622026-09-22T23:38:38.353Zoperation: TestAction { op: CreateSQ(5), result: Err }
3632026-09-22T23:38:38.353Zcreating sq 5
3642026-09-22T23:38:38.353Zoperation: TestAction { op: DeleteSQ(2), result: Err }
3652026-09-22T23:38:38.353Zsq (2) lives another day
3662026-09-22T23:38:38.353Zoperation: TestAction { op: Migrate, result: Ok }
3672026-09-22T23:38:38.353Zoperation: TestAction { op: DeleteSQ(1), result: Err }
3682026-09-22T23:38:38.353Zsq (1) lives another day
3692026-09-22T23:38:38.353Zoperation: TestAction { op: Migrate, result: Ok }
3702026-09-22T23:38:38.353Zoperation: TestAction { op: CreateCQ(16), result: Ok }
3712026-09-22T23:38:38.353Zoperation: TestAction { op: CreateSQ(10), result: Err }
3722026-09-22T23:38:38.353Zcreating sq 10
3732026-09-22T23:38:38.353Zoperation: TestAction { op: Migrate, result: Ok }
3742026-09-22T23:38:38.353Zoperation: TestAction { op: CreateSQ(15), result: Ok }
3752026-09-22T23:38:38.379Zcreating sq 15
3762026-09-22T23:38:38.379Zoperation: TestAction { op: Migrate, result: Ok }
3772026-09-22T23:38:38.379Zoperation: TestAction { op: SubmitRead { queue: 3, lba: 9659, memptr: 1437696, size: 4096, fresh_cid: true }, result: Ok }
3782026-09-22T23:38:38.379Zoperation: TestAction { op: Migrate, result: Ok }
3792026-09-22T23:38:38.379Zoperation: TestAction { op: SubmitWrite { queue: 15, lba: 127, memptr: 1552384, size: 4096, fresh_cid: true }, result: Ok }
3802026-09-22T23:38:38.379Zoperation: TestAction { op: Migrate, result: Ok }
3812026-09-22T23:38:38.379Zoperation: TestAction { op: CreateCQ(4), result: Ok }
3822026-09-22T23:38:38.379Zoperation: TestAction { op: CreateSQ(13), result: Err }
3832026-09-22T23:38:38.379Zcreating sq 13
3842026-09-22T23:38:38.379Zoperation: TestAction { op: CreateSQ(8), result: Ok }
3852026-09-22T23:38:38.379Zcreating sq 8
3862026-09-22T23:38:38.379Zoperation: TestAction { op: CreateCQ(11), result: Ok }
3872026-09-22T23:38:38.379Zoperation: TestAction { op: DeleteSQ(16), result: Err }
3882026-09-22T23:38:38.379Zsq (16) lives another day
3892026-09-22T23:38:38.379Zoperation: TestAction { op: WaitIO { queue: 3, cid: 65534 }, result: Ok }
3902026-09-22T23:38:38.379Zreading cqe from cq slot 0, addr 1c7000
3912026-09-22T23:38:38.379Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 1, sqid: 3, cid: 65534, status_phase: 1 }
3922026-09-22T23:38:38.379ZDONE with I/O 65534 on queue 3
3932026-09-22T23:38:38.379Zoperation: TestAction { op: DeleteCQ(14), result: Err }
3942026-09-22T23:38:38.379Zoperation: TestAction { op: DeleteSQ(4), result: Err }
3952026-09-22T23:38:38.379Zsq (4) lives another day
3962026-09-22T23:38:38.379Zoperation: TestAction { op: CreateCQ(9), result: Err }
3972026-09-22T23:38:38.379Zoperation: TestAction { op: DeleteSQ(5), result: Err }
3982026-09-22T23:38:38.379Zsq (5) lives another day
3992026-09-22T23:38:38.379Zoperation: TestAction { op: CreateSQ(2), result: Err }
4002026-09-22T23:38:38.379Zcreating sq 2
4012026-09-22T23:38:38.379Zoperation: TestAction { op: DeleteCQ(2), result: Err }
4022026-09-22T23:38:38.379Zoperation: TestAction { op: DeleteCQ(7), result: Err }
4032026-09-22T23:38:38.379Zoperation: TestAction { op: CreateCQ(6), result: Err }
4042026-09-22T23:38:38.379Zoperation: TestAction { op: DeleteCQ(2), result: Err }
4052026-09-22T23:38:38.379Zoperation: TestAction { op: DeleteSQ(13), result: Err }
4062026-09-22T23:38:38.379Zsq (13) lives another day
4072026-09-22T23:38:38.379Zoperation: TestAction { op: CreateSQ(4), result: Ok }
4082026-09-22T23:38:38.379Zcreating sq 4
4092026-09-22T23:38:38.379Zoperation: TestAction { op: CreateCQ(2), result: Ok }
4102026-09-22T23:38:38.379Zoperation: TestAction { op: DeleteCQ(5), result: Err }
4112026-09-22T23:38:38.379Zoperation: TestAction { op: DeleteCQ(13), result: Err }
4122026-09-22T23:38:38.380Zoperation: TestAction { op: CreateCQ(10), result: Ok }
4132026-09-22T23:38:38.380Zoperation: TestAction { op: CreateCQ(11), result: Err }
4142026-09-22T23:38:38.380Zoperation: TestAction { op: DeleteCQ(12), result: Err }
4152026-09-22T23:38:38.380Zoperation: TestAction { op: SubmitRead { queue: 8, lba: 11534, memptr: 1224704, size: 4096, fresh_cid: true }, result: Ok }
4162026-09-22T23:38:38.380Zoperation: TestAction { op: DeleteCQ(7), result: Err }
4172026-09-22T23:38:38.380Zoperation: TestAction { op: CreateCQ(9), result: Err }
4182026-09-22T23:38:38.380Zoperation: TestAction { op: CreateCQ(10), result: Err }
4192026-09-22T23:38:38.380Zoperation: TestAction { op: Migrate, result: Ok }
4202026-09-22T23:38:38.380Zoperation: TestAction { op: CreateSQ(10), result: Ok }
4212026-09-22T23:38:38.380Zcreating sq 10
4222026-09-22T23:38:38.380Zoperation: TestAction { op: SubmitWrite { queue: 3, lba: 11286, memptr: 1433600, size: 4096, fresh_cid: true }, result: Ok }
4232026-09-22T23:38:38.380Zoperation: TestAction { op: Migrate, result: Ok }
4242026-09-22T23:38:38.380Zoperation: TestAction { op: Migrate, result: Ok }
4252026-09-22T23:38:38.380Zoperation: TestAction { op: DeleteCQ(5), result: Err }
4262026-09-22T23:38:38.380Zoperation: TestAction { op: CreateSQ(6), result: Ok }
4272026-09-22T23:38:38.380Zcreating sq 6
4282026-09-22T23:38:38.380Zoperation: TestAction { op: Migrate, result: Ok }
4292026-09-22T23:38:38.380Zoperation: TestAction { op: SubmitWrite { queue: 10, lba: 7132, memptr: 1400832, size: 4096, fresh_cid: true }, result: Ok }
4302026-09-22T23:38:38.380Zoperation: TestAction { op: DeleteCQ(16), result: Ok }
4312026-09-22T23:38:38.380Zoperation: TestAction { op: Migrate, result: Ok }
4322026-09-22T23:38:38.380Zoperation: TestAction { op: SubmitRead { queue: 4, lba: 10284, memptr: 1556480, size: 4096, fresh_cid: true }, result: Ok }
4332026-09-22T23:38:38.380Zoperation: TestAction { op: DeleteSQ(16), result: Err }
4342026-09-22T23:38:38.380Zsq (16) lives another day
4352026-09-22T23:38:38.380Zoperation: TestAction { op: Migrate, result: Ok }
4362026-09-22T23:38:38.380Zoperation: TestAction { op: DeleteSQ(1), result: Err }
4372026-09-22T23:38:38.380Zsq (1) lives another day
4382026-09-22T23:38:38.380Zoperation: TestAction { op: Migrate, result: Ok }
4392026-09-22T23:38:38.380Zoperation: TestAction { op: Doorbell(8), result: Ok }
4402026-09-22T23:38:38.380Zoperation: TestAction { op: SubmitRead { queue: 8, lba: 2233, memptr: 1466368, size: 4096, fresh_cid: true }, result: Ok }
4412026-09-22T23:38:38.380Zoperation: TestAction { op: Migrate, result: Ok }
4422026-09-22T23:38:38.380Zoperation: TestAction { op: CreateSQ(2), result: Ok }
4432026-09-22T23:38:38.380Zcreating sq 2
4442026-09-22T23:38:38.380Zoperation: TestAction { op: CreateSQ(1), result: Err }
4452026-09-22T23:38:38.380Zcreating sq 1
4462026-09-22T23:38:38.380Zoperation: TestAction { op: Migrate, result: Ok }
4472026-09-22T23:38:38.380Zoperation: TestAction { op: SubmitRead { queue: 15, lba: 4714, memptr: 1495040, size: 4096, fresh_cid: true }, result: Ok }
4482026-09-22T23:38:38.380Zoperation: TestAction { op: WaitIO { queue: 4, cid: 65534 }, result: Ok }
4492026-09-22T23:38:38.380Zreading cqe from cq slot 0, addr 1c9000
4502026-09-22T23:38:38.381Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 1, sqid: 4, cid: 65534, status_phase: 1 }
4512026-09-22T23:38:38.381ZDONE with I/O 65534 on queue 4
4522026-09-22T23:38:38.381Zoperation: TestAction { op: SubmitRead { queue: 3, lba: 4783, memptr: 1208320, size: 4096, fresh_cid: true }, result: Ok }
4532026-09-22T23:38:38.381Zoperation: TestAction { op: DeleteCQ(16), result: Err }
4542026-09-22T23:38:38.381Zoperation: TestAction { op: WaitIO { queue: 8, cid: 65533 }, result: Ok }
4552026-09-22T23:38:38.381Zreading cqe from cq slot 0, addr 1d1000
4562026-09-22T23:38:38.381Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 1, sqid: 8, cid: 65534, status_phase: 1 }
4572026-09-22T23:38:38.381Zreading cqe from cq slot 1, addr 1d1010
4582026-09-22T23:38:38.381Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
4592026-09-22T23:38:38.381Zreading cqe from cq slot 1, addr 1d1010
4602026-09-22T23:38:38.381Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
4612026-09-22T23:38:38.381Zreading cqe from cq slot 1, addr 1d1010
4622026-09-22T23:38:38.381Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
4632026-09-22T23:38:38.381Zreading cqe from cq slot 1, addr 1d1010
4642026-09-22T23:38:38.381Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
4652026-09-22T23:38:38.381Zreading cqe from cq slot 1, addr 1d1010
4662026-09-22T23:38:38.381Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
4672026-09-22T23:38:38.381Zreading cqe from cq slot 1, addr 1d1010
4682026-09-22T23:38:38.381Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
4692026-09-22T23:38:38.381Zreading cqe from cq slot 1, addr 1d1010
4702026-09-22T23:38:38.381Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
4712026-09-22T23:38:38.381Zreading cqe from cq slot 1, addr 1d1010
4722026-09-22T23:38:38.381Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
4732026-09-22T23:38:38.381Zreading cqe from cq slot 1, addr 1d1010
4742026-09-22T23:38:38.381Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
4752026-09-22T23:38:38.406Zreading cqe from cq slot 1, addr 1d1010
4762026-09-22T23:38:38.406Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
4772026-09-22T23:38:38.406Zreading cqe from cq slot 1, addr 1d1010
4782026-09-22T23:38:38.406Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
4792026-09-22T23:38:38.406Zreading cqe from cq slot 1, addr 1d1010
4802026-09-22T23:38:38.406Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
4812026-09-22T23:38:38.406Zreading cqe from cq slot 1, addr 1d1010
4822026-09-22T23:38:38.406Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
4832026-09-22T23:38:38.406Zreading cqe from cq slot 1, addr 1d1010
4842026-09-22T23:38:38.406Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
4852026-09-22T23:38:38.406Zreading cqe from cq slot 1, addr 1d1010
4862026-09-22T23:38:38.406Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
4872026-09-22T23:38:38.406Zreading cqe from cq slot 1, addr 1d1010
4882026-09-22T23:38:38.406Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
4892026-09-22T23:38:38.406Zreading cqe from cq slot 1, addr 1d1010
4902026-09-22T23:38:38.407Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
4912026-09-22T23:38:38.407Zreading cqe from cq slot 1, addr 1d1010
4922026-09-22T23:38:38.407Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
4932026-09-22T23:38:38.407Zreading cqe from cq slot 1, addr 1d1010
4942026-09-22T23:38:38.407Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
4952026-09-22T23:38:38.407Zreading cqe from cq slot 1, addr 1d1010
4962026-09-22T23:38:38.407Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
4972026-09-22T23:38:38.407Zreading cqe from cq slot 1, addr 1d1010
4982026-09-22T23:38:38.407Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
4992026-09-22T23:38:38.407Zreading cqe from cq slot 1, addr 1d1010
5002026-09-22T23:38:38.407Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
5012026-09-22T23:38:38.407Zreading cqe from cq slot 1, addr 1d1010
5022026-09-22T23:38:38.407Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
5032026-09-22T23:38:38.407Zreading cqe from cq slot 1, addr 1d1010
5042026-09-22T23:38:38.407Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
5052026-09-22T23:38:38.407Zreading cqe from cq slot 1, addr 1d1010
5062026-09-22T23:38:38.407Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
5072026-09-22T23:38:38.407Zreading cqe from cq slot 1, addr 1d1010
5082026-09-22T23:38:38.407Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
5092026-09-22T23:38:38.407Zreading cqe from cq slot 1, addr 1d1010
5102026-09-22T23:38:38.407Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
5112026-09-22T23:38:38.407Zreading cqe from cq slot 1, addr 1d1010
5122026-09-22T23:38:38.407Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
5132026-09-22T23:38:38.407Zreading cqe from cq slot 1, addr 1d1010
5142026-09-22T23:38:38.407Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
5152026-09-22T23:38:38.407Zreading cqe from cq slot 1, addr 1d1010
5162026-09-22T23:38:38.407Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
5172026-09-22T23:38:38.407Zreading cqe from cq slot 1, addr 1d1010
5182026-09-22T23:38:38.407Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
5192026-09-22T23:38:38.407Zreading cqe from cq slot 1, addr 1d1010
5202026-09-22T23:38:38.407Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
5212026-09-22T23:38:38.407Zreading cqe from cq slot 1, addr 1d1010
5222026-09-22T23:38:38.407Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
5232026-09-22T23:38:38.407Zreading cqe from cq slot 1, addr 1d1010
5242026-09-22T23:38:38.407Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
5252026-09-22T23:38:38.407Zreading cqe from cq slot 1, addr 1d1010
5262026-09-22T23:38:38.407Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
5272026-09-22T23:38:38.407Zreading cqe from cq slot 1, addr 1d1010
5282026-09-22T23:38:38.407Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
5292026-09-22T23:38:38.407Zreading cqe from cq slot 1, addr 1d1010
5302026-09-22T23:38:38.408Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
5312026-09-22T23:38:38.408Zreading cqe from cq slot 1, addr 1d1010
5322026-09-22T23:38:38.408Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
5332026-09-22T23:38:38.408Zreading cqe from cq slot 1, addr 1d1010
5342026-09-22T23:38:38.408Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
5352026-09-22T23:38:38.408Zreading cqe from cq slot 1, addr 1d1010
5362026-09-22T23:38:38.408Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
5372026-09-22T23:38:38.408Zreading cqe from cq slot 1, addr 1d1010
5382026-09-22T23:38:38.408Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
5392026-09-22T23:38:38.408Zreading cqe from cq slot 1, addr 1d1010
5402026-09-22T23:38:38.408Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
5412026-09-22T23:38:38.408Zreading cqe from cq slot 1, addr 1d1010
5422026-09-22T23:38:38.408Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
5432026-09-22T23:38:38.408Zreading cqe from cq slot 1, addr 1d1010
5442026-09-22T23:38:38.408Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
5452026-09-22T23:38:38.408Zreading cqe from cq slot 1, addr 1d1010
5462026-09-22T23:38:38.408Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
5472026-09-22T23:38:38.408Zreading cqe from cq slot 1, addr 1d1010
5482026-09-22T23:38:38.408Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
5492026-09-22T23:38:38.408Zreading cqe from cq slot 1, addr 1d1010
5502026-09-22T23:38:38.408Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
5512026-09-22T23:38:38.408Zreading cqe from cq slot 1, addr 1d1010
5522026-09-22T23:38:38.408Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
5532026-09-22T23:38:38.408Zreading cqe from cq slot 1, addr 1d1010
5542026-09-22T23:38:38.408Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
5552026-09-22T23:38:38.408Zreading cqe from cq slot 1, addr 1d1010
5562026-09-22T23:38:38.408Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
5572026-09-22T23:38:38.408Zreading cqe from cq slot 1, addr 1d1010
5582026-09-22T23:38:38.408Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
5592026-09-22T23:38:38.408Zreading cqe from cq slot 1, addr 1d1010
5602026-09-22T23:38:38.408Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
5612026-09-22T23:38:38.408Zreading cqe from cq slot 1, addr 1d1010
5622026-09-22T23:38:38.408Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
5632026-09-22T23:38:38.408Zreading cqe from cq slot 1, addr 1d1010
5642026-09-22T23:38:38.408Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
5652026-09-22T23:38:38.408Zreading cqe from cq slot 1, addr 1d1010
5662026-09-22T23:38:38.408Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
5672026-09-22T23:38:38.408Zreading cqe from cq slot 1, addr 1d1010
5682026-09-22T23:38:38.408Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
5692026-09-22T23:38:38.409Zreading cqe from cq slot 1, addr 1d1010
5702026-09-22T23:38:38.409Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
5712026-09-22T23:38:38.409Zreading cqe from cq slot 1, addr 1d1010
5722026-09-22T23:38:38.409Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
5732026-09-22T23:38:38.409Zreading cqe from cq slot 1, addr 1d1010
5742026-09-22T23:38:38.409Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
5752026-09-22T23:38:38.434Zreading cqe from cq slot 1, addr 1d1010
5762026-09-22T23:38:38.434Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
5772026-09-22T23:38:38.434Zreading cqe from cq slot 1, addr 1d1010
5782026-09-22T23:38:38.434Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
5792026-09-22T23:38:38.434Zreading cqe from cq slot 1, addr 1d1010
5802026-09-22T23:38:38.434Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
5812026-09-22T23:38:38.434Zreading cqe from cq slot 1, addr 1d1010
5822026-09-22T23:38:38.434Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
5832026-09-22T23:38:38.434Zreading cqe from cq slot 1, addr 1d1010
5842026-09-22T23:38:38.434Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
5852026-09-22T23:38:38.434Zreading cqe from cq slot 1, addr 1d1010
5862026-09-22T23:38:38.434Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
5872026-09-22T23:38:38.434Zreading cqe from cq slot 1, addr 1d1010
5882026-09-22T23:38:38.434Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
5892026-09-22T23:38:38.434Zreading cqe from cq slot 1, addr 1d1010
5902026-09-22T23:38:38.434Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
5912026-09-22T23:38:38.434Zreading cqe from cq slot 1, addr 1d1010
5922026-09-22T23:38:38.434Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
5932026-09-22T23:38:38.434Zreading cqe from cq slot 1, addr 1d1010
5942026-09-22T23:38:38.434Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
5952026-09-22T23:38:38.434Zreading cqe from cq slot 1, addr 1d1010
5962026-09-22T23:38:38.434Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
5972026-09-22T23:38:38.434Zreading cqe from cq slot 1, addr 1d1010
5982026-09-22T23:38:38.434Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
5992026-09-22T23:38:38.435Zreading cqe from cq slot 1, addr 1d1010
6002026-09-22T23:38:38.435Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
6012026-09-22T23:38:38.435Zreading cqe from cq slot 1, addr 1d1010
6022026-09-22T23:38:38.435Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
6032026-09-22T23:38:38.435Zreading cqe from cq slot 1, addr 1d1010
6042026-09-22T23:38:38.435Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
6052026-09-22T23:38:38.435Zreading cqe from cq slot 1, addr 1d1010
6062026-09-22T23:38:38.435Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
6072026-09-22T23:38:38.435Zreading cqe from cq slot 1, addr 1d1010
6082026-09-22T23:38:38.435Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
6092026-09-22T23:38:38.435Zreading cqe from cq slot 1, addr 1d1010
6102026-09-22T23:38:38.435Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
6112026-09-22T23:38:38.435Zreading cqe from cq slot 1, addr 1d1010
6122026-09-22T23:38:38.435Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
6132026-09-22T23:38:38.435Zreading cqe from cq slot 1, addr 1d1010
6142026-09-22T23:38:38.435Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
6152026-09-22T23:38:38.435Zreading cqe from cq slot 1, addr 1d1010
6162026-09-22T23:38:38.435Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
6172026-09-22T23:38:38.435Zreading cqe from cq slot 1, addr 1d1010
6182026-09-22T23:38:38.435Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
6192026-09-22T23:38:38.435Zreading cqe from cq slot 1, addr 1d1010
6202026-09-22T23:38:38.435Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
6212026-09-22T23:38:38.435Zreading cqe from cq slot 1, addr 1d1010
6222026-09-22T23:38:38.435Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
6232026-09-22T23:38:38.435Zreading cqe from cq slot 1, addr 1d1010
6242026-09-22T23:38:38.435Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
6252026-09-22T23:38:38.435Zreading cqe from cq slot 1, addr 1d1010
6262026-09-22T23:38:38.435Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
6272026-09-22T23:38:38.435Zreading cqe from cq slot 1, addr 1d1010
6282026-09-22T23:38:38.435Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
6292026-09-22T23:38:38.435Zreading cqe from cq slot 1, addr 1d1010
6302026-09-22T23:38:38.435Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
6312026-09-22T23:38:38.435Zreading cqe from cq slot 1, addr 1d1010
6322026-09-22T23:38:38.435Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
6332026-09-22T23:38:38.435Zreading cqe from cq slot 1, addr 1d1010
6342026-09-22T23:38:38.435Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
6352026-09-22T23:38:38.435Zreading cqe from cq slot 1, addr 1d1010
6362026-09-22T23:38:38.435Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
6372026-09-22T23:38:38.435Zreading cqe from cq slot 1, addr 1d1010
6382026-09-22T23:38:38.436Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
6392026-09-22T23:38:38.436Zreading cqe from cq slot 1, addr 1d1010
6402026-09-22T23:38:38.436Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
6412026-09-22T23:38:38.436Zreading cqe from cq slot 1, addr 1d1010
6422026-09-22T23:38:38.436Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
6432026-09-22T23:38:38.436Zreading cqe from cq slot 1, addr 1d1010
6442026-09-22T23:38:38.436Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
6452026-09-22T23:38:38.436Zreading cqe from cq slot 1, addr 1d1010
6462026-09-22T23:38:38.436Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
6472026-09-22T23:38:38.436Zreading cqe from cq slot 1, addr 1d1010
6482026-09-22T23:38:38.436Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
6492026-09-22T23:38:38.436Zreading cqe from cq slot 1, addr 1d1010
6502026-09-22T23:38:38.436Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
6512026-09-22T23:38:38.436Zreading cqe from cq slot 1, addr 1d1010
6522026-09-22T23:38:38.436Zgot cqe: CompletionQueueEntry { dw0: 0, rsvd: 0, sqhd: 0, sqid: 0, cid: 0, status_phase: 0 }
6532026-09-22T23:38:38.436Z
6542026-09-22T23:38:38.436Zthread 'hw::nvme::test::fuzzy' (97) panicked at lib/propolis/src/hw/nvme/test.rs:944:29:
6552026-09-22T23:38:38.436Zi/o never happened
6562026-09-22T23:38:38.436Z
6572026-09-22T23:38:38.436Z
6582026-09-22T23:38:38.436Zfailures:
6592026-09-22T23:38:38.436Z hw::nvme::test::fuzzy
6602026-09-22T23:38:38.436Z
6612026-09-22T23:38:38.436Ztest result: FAILED. 194 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 4.04s
6622026-09-22T23:38:38.436Z
6632026-09-22T23:38:38.436Z
6642026-09-22T23:38:38.436Zreal 4.064855838
6652026-09-22T23:38:38.436Zuser 6.085072850
6662026-09-22T23:38:38.436Zsys 5.559887993
6672026-09-22T23:38:38.436Ztrap 0.126076981
6682026-09-22T23:38:38.436Ztflt 0.009476234
6692026-09-22T23:38:38.436Zdflt 0.017345473
6702026-09-22T23:38:38.436Zkflt 0.000013565
6712026-09-22T23:38:38.436Zlock 2:15.875114221
6722026-09-22T23:38:38.436Zslp 13.688650126
6732026-09-22T23:38:38.436Zlat 0.355600855
6742026-09-22T23:38:38.436Zstop 8.233092243
6752026-09-22T23:38:38.461Zprocess exited: duration 18352 ms, exit code 101
 
6762026-09-22T23:38:38.511Zfound 0 output files