01KSJMSBQBP47H2R2E0NEK0GVB: test-gimlet

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

Buildomat Job: 01KSJMTDF5GDBXG69DJ7Z4ZVE6

Tags:

Output:

SEQ GLOBAL TIME DETAILS
12026-05-26T17:30:58.711Zjob dependencies complete; ready to run (waiting for 10 m 39 s)
22026-05-26T18:05:32.009Zjob assigned to worker 01KSJQ7B386XN7BHR5HAFXXBQE [factory edgar, gimlet/BRM42220010/1011] (queued for 34 m 33 s)
32026-05-26T18:05:32.948Zdownloading input: /input/phd-build/out/phd-run-with-args.sh
42026-05-26T18:05:32.986Zdownloaded input: /input/phd-build/out/phd-run-with-args.sh
52026-05-26T18:05:32.986Zdownloading input: /input/phd-build/out/phd-runner.sha256.txt
62026-05-26T18:05:33.026Zdownloaded input: /input/phd-build/out/phd-runner.sha256.txt
72026-05-26T18:05:33.064Zdownloading input: /input/phd-build/out/phd-runner.tar.gz
82026-05-26T18:05:40.580Zdownloaded input: /input/phd-build/out/phd-runner.tar.gz
92026-05-26T18:05:40.580Zdownloading input: /input/phd-build/out/propolis-server-debug.sha256.txt
102026-05-26T18:05:40.639Zdownloaded input: /input/phd-build/out/propolis-server-debug.sha256.txt
112026-05-26T18:05:40.640Zdownloading input: /input/phd-build/out/propolis-server-debug.tar.gz
122026-05-26T18:05:54.335Zdownloaded input: /input/phd-build/out/propolis-server-debug.tar.gz
132026-05-26T18:05:54.335Zdownloading input: /input/phd-build/out/propolis-tests-debug.tar.gz
142026-05-26T18:06:08.110Zdownloaded input: /input/phd-build/out/propolis-tests-debug.tar.gz
 
152026-05-26T18:06:08.110Zstarting task 0: "setup"
162026-05-26T18:06:08.147Z++ uname -s
172026-05-26T18:06:08.147Z+ kern=SunOS
182026-05-26T18:06:08.147Z+ build_user=build
192026-05-26T18:06:08.148Z+ build_uid=12345
202026-05-26T18:06:08.148Z+ work_dir=/work
212026-05-26T18:06:08.148Z+ input_dir=/input
222026-05-26T18:06:08.148Z+ [[ 0 == 12345 ]]
232026-05-26T18:06:08.148Z+ case "$kern" in
242026-05-26T18:06:08.148Z+ groupadd -g 12345 build
252026-05-26T18:06:08.148Z+ useradd -u 12345 -g build -d /home/build -s /bin/bash -c build -P 'Primary Administrator' build
262026-05-26T18:06:10.141Z+ zfs create -o mountpoint=/work rpool/work
272026-05-26T18:06:10.234Z++ awk '$2 == "/home" { print $3 }' /etc/mnttab
282026-05-26T18:06:10.269Z+ home_fs=
292026-05-26T18:06:10.269Z+ [[ '' == autofs ]]
302026-05-26T18:06:10.269Z+ mkdir -p /home/build
312026-05-26T18:06:10.269Z+ chown build:build /home/build /work
322026-05-26T18:06:12.243Z+ chmod 0700 /home/build /work
332026-05-26T18:06:12.287Zprocess exited: duration 4142 ms, exit code 0
 
342026-05-26T18:06:12.352Zstarting task 1: "authentication"
352026-05-26T18:06:12.435Zprocess exited: duration 80 ms, exit code 0
 
362026-05-26T18:06:12.516Zstarting task 2: "build"
372026-05-26T18:06:12.555Z+ banner prepare
382026-05-26T18:06:12.555Z
392026-05-26T18:06:12.555Z ##### ##### ###### ##### ## ##### ######
402026-05-26T18:06:12.555Z # # # # # # # # # # # #
412026-05-26T18:06:12.555Z # # # # ##### # # # # # # #####
422026-05-26T18:06:12.555Z ##### ##### # ##### ###### ##### #
432026-05-26T18:06:12.555Z # # # # # # # # # #
442026-05-26T18:06:12.555Z # # # ###### # # # # # ######
452026-05-26T18:06:12.555Z
462026-05-26T18:06:12.555Z+ TEST_TAR=propolis-tests-debug.tar.gz
472026-05-26T18:06:12.555Z+ cp /input/phd-build/out/propolis-tests-debug.tar.gz .
482026-05-26T18:06:12.696Z+ tar xvf propolis-tests-debug.tar.gz
492026-05-26T18:06:24.879ZDecompressing 'propolis-tests-debug.tar.gz' with '/usr/bin/gzcat'...
502026-05-26T18:06:24.879Zx target/debug/deps/propolis-1173301e1d5488a7, 721827664 bytes, 1409820 tape blocks
512026-05-26T18:06:24.926Z+ banner test-propolis
522026-05-26T18:06:24.926Z
532026-05-26T18:06:24.926Z ##### ###### #### ##### ##### ##### #### ##### ####
542026-05-26T18:06:24.927Z # # # # # # # # # # # # # #
552026-05-26T18:06:24.927Z # ##### #### # ##### # # # # # # # # # #
562026-05-26T18:06:24.927Z # # # # ##### ##### # # ##### # #
572026-05-26T18:06:24.927Z # # # # # # # # # # # # #
582026-05-26T18:06:24.927Z # ###### #### # # # # #### # ####
592026-05-26T18:06:24.927Z
602026-05-26T18:06:24.927Z+ TEST_DEV=prop_viona_test0
612026-05-26T18:06:24.927Z+ pfexec dladm create-etherstub prop_viona_test0
622026-05-26T18:06:24.927Z+ for testbin in ./target/debug/deps/propolis-*
632026-05-26T18:06:24.927Z+ VIONA_TEST_NIC=prop_viona_test0
642026-05-26T18:06:24.927Z+ pfexec ptime -m ./target/debug/deps/propolis-1173301e1d5488a7
652026-05-26T18:06:24.959Z
662026-05-26T18:06:24.959Zrunning 183 tests
672026-05-26T18:06:25.025Ztest accessors::test::print_names ... ok
682026-05-26T18:06:25.026Ztest accessors::test::orphan_sibling ... ok
692026-05-26T18:06:25.026Ztest accessors::test::simple_depth ... ok
702026-05-26T18:06:25.026Ztest accessors::test::simple_orphan ... ok
712026-05-26T18:06:25.026Ztest accessors::test::orphan_split ... ok
722026-05-26T18:06:25.026Ztest accessors::test::adopt_self - should panic ... ok
732026-05-26T18:06:25.026Ztest accessors::test::tree_root ... ok
742026-05-26T18:06:25.026Ztest accessors::test::adopt_nonroot - should panic ... ok
752026-05-26T18:06:25.026Ztest accessors::test::only_root_can_remove_resource - should panic ... ok
762026-05-26T18:06:25.026Ztest block::crucible::test::block_calc_ok ... ok
772026-05-26T18:06:25.026Ztest block::crucible::test::err_on_bad_offset ... ok
782026-05-26T18:06:25.026Ztest block::crucible::test::err_on_bad_size ... ok
792026-05-26T18:06:25.026Ztest block::crucible::test::ok_for_valid ... ok
802026-05-26T18:06:25.026Ztest chardev::pollers::test_copy_and_consume_1 ... ok
812026-05-26T18:06:25.026Ztest chardev::pollers::test_copy_and_consume_one_u8 ... ok
822026-05-26T18:06:25.026Ztest common::test::readop_short ... ok
832026-05-26T18:06:25.026Ztest common::test::readop_oversize - should panic ... ok
842026-05-26T18:06:25.026Ztest common::test::readop_base_size ... ok
852026-05-26T18:06:25.026Ztest common::test::writeop_base_size ... ok
862026-05-26T18:06:25.026Ztest common::test::writeop_oversize - should panic ... ok
872026-05-26T18:06:25.026Ztest common::test::writeop_short ... ok
882026-05-26T18:06:25.026Ztest chardev::pollers::test::write_empty_fills_zero_bytes ... ok
892026-05-26T18:06:25.026Ztest chardev::pollers::test::write_bytes ... ok
902026-05-26T18:06:25.026Ztest chardev::pollers::test::read_empty_returns_zero_bytes ... ok
912026-05-26T18:06:25.026Ztest chardev::pollers::test::read_bytes ... ok
922026-05-26T18:06:25.026Ztest chardev::pollers::test::write_byte ... ok
932026-05-26T18:06:25.026Ztest firmware::smbios::bits::test::memory_device ... ok
942026-05-26T18:06:25.026Ztest firmware::smbios::bits::test::baseboard_information ... ok
952026-05-26T18:06:25.026Ztest firmware::smbios::bits::test::bios_information ... ok
962026-05-26T18:06:25.026Ztest firmware::smbios::bits::test::entry_point ... ok
972026-05-26T18:06:25.026Ztest firmware::smbios::bits::test::physical_memory_array ... ok
982026-05-26T18:06:25.026Ztest chardev::pollers::test::write_bytes_beyond_internal_buffer_size ... ok
992026-05-26T18:06:25.026Ztest firmware::smbios::bits::test::system_enclosure ... ok
1002026-05-26T18:06:25.026Ztest enlightenment::hyperv::overlay::test::basic_add ... ok
1012026-05-26T18:06:25.026Ztest firmware::smbios::bits::test::system_boot_information ... ok
1022026-05-26T18:06:25.026Ztest enlightenment::hyperv::overlay::test::duplicate_kind_at_pfn ... ok
1032026-05-26T18:06:25.026Ztest firmware::smbios::bits::test::processor_information ... ok
1042026-05-26T18:06:25.026Ztest enlightenment::hyperv::overlay::test::multiple_overlays ... ok
1052026-05-26T18:06:25.027Ztest enlightenment::hyperv::overlay::test::basic_move ... ok
1062026-05-26T18:06:25.027Ztest enlightenment::hyperv::overlay::test::out_of_bounds_pfn ... ok
1072026-05-26T18:06:25.027Ztest chardev::sock::test::abort_wait_for_connect ... ok
1082026-05-26T18:06:25.027Ztest firmware::smbios::bits::test::struct_header ... ok
1092026-05-26T18:06:25.027Ztest firmware::smbios::bits::test::system_information ... ok
1102026-05-26T18:06:25.027Ztest enlightenment::hyperv::overlay::test::underlay_restored_after_drop ... ok
1112026-05-26T18:06:25.027Ztest chardev::pollers::test::read_byte ... ok
1122026-05-26T18:06:25.027Ztest enlightenment::hyperv::overlay::test::remove_nonexistent_overlay ... ok
1132026-05-26T18:06:25.027Ztest firmware::smbios::table::type16::test::error_correction_serde_roundtrip ... ok
1142026-05-26T18:06:25.027Ztest firmware::smbios::table::type16::test::array_use_serde_roundtrip ... ok
1152026-05-26T18:06:25.027Ztest firmware::smbios::table::type16::test::error_correction_deserialize ... ok
1162026-05-26T18:06:25.027Ztest firmware::smbios::table::type4::test::proc_type_serde_roundtrip ... ok
1172026-05-26T18:06:25.027Ztest firmware::smbios::table::type16::test::location_serde_roundtrip ... ok
1182026-05-26T18:06:25.027Ztest chardev::sock::test::bail_on_shutdown_sock ... ok
1192026-05-26T18:06:25.027Ztest firmware::smbios::table::type4::test::proc_type_deserialize ... ok
1202026-05-26T18:06:25.027Ztest firmware::smbios::table::type16::test::location_deserialize ... ok
1212026-05-26T18:06:25.027Ztest hw::nvme::bits::test::entry_sizing ... ok
1222026-05-26T18:06:25.027Ztest firmware::smbios::table::type4::test::proc_status_serde_roundtrip ... ok
1232026-05-26T18:06:25.027Ztest hw::chipset::i440fx::test::hb_pci_cfg_write ... ok
1242026-05-26T18:06:25.027Ztest hw::chipset::i440fx::test::lpc_pci_cfg_write ... ok
1252026-05-26T18:06:25.027Ztest hw::chipset::i440fx::test::hb_pci_cfg_read ... ok
1262026-05-26T18:06:25.027Ztest hw::chipset::i440fx::test::lpc_pci_cfg_read ... ok
1272026-05-26T18:06:25.027Ztest hw::nvme::cmds::test::test_prp_dual ... ok
1282026-05-26T18:06:25.027Ztest firmware::smbios::table::type1::test::wake_up_type_serde_roundtrip ... ok
1292026-05-26T18:06:25.027Ztest hw::nvme::cmds::test::test_dsmgmt_ranges_bad_address ... ok
1302026-05-26T18:06:25.027Ztest hw::chipset::i440fx::test::pm_pci_cfg_write ... ok
1312026-05-26T18:06:25.027Ztest hw::chipset::i440fx::test::pm_pci_cfg_read ... ok
1322026-05-26T18:06:25.027Ztest firmware::smbios::table::type1::test::wake_up_type_deserialize ... ok
1332026-05-26T18:06:25.027Ztest hw::nvme::cmds::test::test_dsmgmt_ranges ... ok
1342026-05-26T18:06:25.027Ztest hw::nvme::cmds::test::test_dsmgmt_ranges_dual ... ok
1352026-05-26T18:06:25.027Ztest hw::pci::bar::test::limits ... ok
1362026-05-26T18:06:25.027Ztest firmware::smbios::table::type4::test::proc_status_deserialize ... ok
1372026-05-26T18:06:25.027Ztest hw::nvme::cmds::test::test_dsmgmt_ranges_bad_dual ... ok
1382026-05-26T18:06:25.027Ztest hw::nvme::cmds::test::test_prp_list_offset_last ... ok
1392026-05-26T18:06:25.027Ztest hw::nvme::cmds::test::test_prp_list ... ok
1402026-05-26T18:06:25.027Ztest hw::pci::bar::test::write_place ... ok
1412026-05-26T18:06:25.027Ztest hw::pci::bar::test::read_type ... ok
1422026-05-26T18:06:25.027Ztest hw::pci::device::test::msix_cfg_too_big - should panic ... ok
1432026-05-26T18:06:25.028Ztest hw::pci::bus::test::empty ... ok
1442026-05-26T18:06:25.028Ztest hw::pci::bus::test::set_multifunc ... ok
1452026-05-26T18:06:25.028Ztest hw::pci::test::pcie_decoder_multiple_bdfs ... ok
1462026-05-26T18:06:25.028Ztest hw::nvme::cmds::test::test_prp_multiple ... ok
1472026-05-26T18:06:25.028Ztest hw::pci::test::pcie_decoder_min_buses ... ok
1482026-05-26T18:06:25.028Ztest hw::pci::bar::test::init ... ok
1492026-05-26T18:06:25.028Ztest hw::pci::topology::test::builder_conflicts ... ok
1502026-05-26T18:06:25.028Ztest hw::qemu::fwcfg::formats::test_e820::basic ... ok
1512026-05-26T18:06:25.028Ztest hw::pci::test::pcie_decoder ... ok
1522026-05-26T18:06:25.028Ztest hw::pci::test::pcie_decoder_access_spans_multiple_devs ... ok
1532026-05-26T18:06:25.028Ztest hw::pci::bridge::test::bridge_properties ... ok
1542026-05-26T18:06:25.028Ztest hw::pci::device::test::msix_cfg_zero - should panic ... ok
1552026-05-26T18:06:25.028Ztest hw::pci::topology::test::builder_bus_zero_reserved ... ok
1562026-05-26T18:06:25.028Ztest hw::nvme::cmds::test::test_prp_single ... ok
1572026-05-26T18:06:25.028Ztest hw::qemu::fwcfg::formats::test_e820::entry_size_is_correct ... ok
1582026-05-26T18:06:25.028Ztest firmware::smbios::table::type16::test::array_use_deserialize ... ok
1592026-05-26T18:06:25.028Ztest hw::pci::topology::test::build_without_bridges ... ok
1602026-05-26T18:06:25.028Ztest chardev::pollers::test::read_bytes_blocking ... ok
1612026-05-26T18:06:25.028Ztest hw::qemu::fwcfg::test::read_version ... ok
1622026-05-26T18:06:25.028Ztest hw::qemu::fwcfg::formats::test_bootorder::basic ... ok
1632026-05-26T18:06:25.028Ztest hw::qemu::fwcfg::test::dma_read_missing ... ok
1642026-05-26T18:06:25.028Ztest hw::qemu::fwcfg::test::struct_sizing ... ok
1652026-05-26T18:06:25.028Ztest hw::qemu::fwcfg::test::state_cleared_on_reset ... ok
1662026-05-26T18:06:25.028Ztest hw::qemu::fwcfg::test::pio_read_basic ... ok
1672026-05-26T18:06:25.064Ztest hw::uart::uart16550::test::safe_read_write_all ... ok
1682026-05-26T18:06:25.064Ztest hw::uart::uart16550::test::intr_dr_on_incoming ... ok
1692026-05-26T18:06:25.064Ztest hw::pci::topology::test::cfg_read ... ok
1702026-05-26T18:06:25.064Ztest hw::qemu::ramfb::test::config_reg_size ... ok
1712026-05-26T18:06:25.064Ztest hw::qemu::fwcfg::test::dma_read_basic ... ok
1722026-05-26T18:06:25.064Ztest hw::pci::topology::test::created_bridges ... ok
1732026-05-26T18:06:25.064Ztest hw::uart::uart16550::test::intr_thre_on_etbei_toggle ... ok
1742026-05-26T18:06:25.064Ztest hw::uart::uart16550::test::intr_thre_on_outgoing ... ok
1752026-05-26T18:06:25.064Ztest hw::pci::topology::test::build_with_bridges ... ok
1762026-05-26T18:06:25.064Ztest hw::uart::uart16550::test::reset_state ... ok
1772026-05-26T18:06:25.064Ztest hw::uart::uart16550::test::interrupt_codes ... ok
1782026-05-26T18:06:25.064Ztest hw::qemu::fwcfg::test::pio_read_missing ... ok
1792026-05-26T18:06:25.064Ztest hw::virtio::testutil::test::queue_writer_reset_cursors ... ok
1802026-05-26T18:06:25.064Ztest hw::virtio::testutil::test::multiple_chains ... ok
1812026-05-26T18:06:25.064Ztest hw::virtio::testutil::test::multi_queue_smoke ... ok
1822026-05-26T18:06:25.064Ztest util::aspace::test::bounds_accessors ... ok
1832026-05-26T18:06:25.064Ztest util::aspace::safe_end_bounds ... ok
1842026-05-26T18:06:25.064Ztest hw::pci::bridge::test::bridge_routing ... ok
1852026-05-26T18:06:25.064Ztest util::aspace::test::create_max ... ok
1862026-05-26T18:06:25.064Ztest util::aspace::test::create_normal ... ok
1872026-05-26T18:06:25.064Ztest hw::virtio::testutil::test::smoke_chained_descriptors ... ok
1882026-05-26T18:06:25.064Ztest util::aspace::test::create_one_elem ... ok
1892026-05-26T18:06:25.064Ztest hw::virtio::testutil::test::smoke_pop_avail_writable ... ok
1902026-05-26T18:06:25.064Ztest util::aspace::test::inverse_iterator_empty ... ok
1912026-05-26T18:06:25.064Ztest hw::virtio::testutil::test::smoke_pop_avail_readable ... ok
1922026-05-26T18:06:25.064Ztest util::aspace::test::inverse_iterator_full ... ok
1932026-05-26T18:06:25.064Ztest util::aspace::test::register_invalid ... ok
1942026-05-26T18:06:25.065Ztest hw::virtio::testutil::test::empty_avail_ring_returns_none ... ok
1952026-05-26T18:06:25.065Ztest util::aspace::test::register_plain ... ok
1962026-05-26T18:06:25.065ZMay 26 18:06:24.944May 26 18:06:24.943May 26 18:06:24.944 May 26 18:06:24.943 May 26 18:06:24.944May 26 18:06:24.944 INFOINFOINFO INFOvsock poller configured with INFOvsock poller configured with vsock poller configured withvsock poller configured with, , , , mappingsmappingsmappingsmappings::: {4000: VsockPortMapping { port: 4000, addr: 127.0.0.1:38700 }} {5000: VsockPortMapping { port: 5000, addr: 127.0.0.1:58979 }}{9300: VsockPortMapping { port: 9300, addr: 127.0.0.1:47073 }}: {3000: VsockPortMapping { port: 3000, addr: 127.0.0.1:61546 }} May 26 18:06:24.945vsock poller configured with INFO, mappingsvsock poller configured with:, , , , componentcomponent:: component vsock-test:vsock-test
1972026-05-26T18:06:25.065Z
1982026-05-26T18:06:25.065ZMay 26 18:06:24.945 vsock-testINFO
1992026-05-26T18:06:25.065Zvsock poller configured with, mappings :mappings{9000: VsockPortMapping { port: 9000, addr: 127.0.0.1:46161 }}: , component{8000: VsockPortMapping { port: 8000, addr: 127.0.0.1:49396 }}:, componentvsock-test:
2002026-05-26T18:06:25.065Z vsock-test
2012026-05-26T18:06:25.065Z{}, componentINFO: vsock-testvsock poller configured with
2022026-05-26T18:06:25.065Z, mappings: {9400: VsockPortMapping { port: 9400, addr: 127.0.0.1:54698 }}, , componentcomponent:: vsock-testvsock-test
2032026-05-26T18:06:25.065Z
2042026-05-26T18:06:25.065ZMay 26 18:06:24.945 INFO vsock poller configured with, mappings: {9500: VsockPortMapping { port: 9500, addr: 127.0.0.1:58048 }}, component: vsock-test
2052026-05-26T18:06:25.065ZMay 26 18:06:24.945 INFO vsock poller configured with, mappings: {3000: VsockPortMapping { port: 3000, addr: 127.0.0.1:33434 }}, component: vsock-test
2062026-05-26T18:06:25.065ZMay 26 18:06:24.946 INFO vsock poller configured with, mappings: {6000: VsockPortMapping { port: 6000, addr: 127.0.0.1:59675 }}, component: vsock-test
2072026-05-26T18:06:25.065Ztest util::aspace::test::inverse_iterator_sequential_registrations ... ok
2082026-05-26T18:06:25.065Ztest util::aspace::test::inverse_iterator_alloc_ends ... ok
2092026-05-26T18:06:25.065Ztest util::aspace::test::region_at_outside ... ok
2102026-05-26T18:06:25.065Ztest util::aspace::test::region_at_normal ... ok
2112026-05-26T18:06:25.065Ztest util::aspace::test::register_outside ... ok
2122026-05-26T18:06:25.065Ztest util::aspace::test::inverse_iterator_alloc_middle ... ok
2132026-05-26T18:06:25.065Ztest util::aspace::test::register_overlaps ... ok
2142026-05-26T18:06:25.065Ztest hw::virtio::viona::test::test_common_cfg_size_is_right ... ok
2152026-05-26T18:06:25.065Ztest vmm::mem::test::mapping_create_empty ... ok
2162026-05-26T18:06:25.065Ztest util::regmap::test::misaligned ... ok
2172026-05-26T18:06:25.065Ztest util::regmap::test::simple ... ok
2182026-05-26T18:06:25.065Ztest vmm::mem::test::mapping_denies_read_beyond_end ... ok
2192026-05-26T18:06:25.065Ztest vmm::mem::test::mapping_invalid_subregions ... ok
2202026-05-26T18:06:25.065Ztest vmm::mem::test::memory_protections_match_libc ... ok
2212026-05-26T18:06:25.065Ztest vmm::mem::test::mapping_shortens_read_bytes_beyond_end ... ok
2222026-05-26T18:06:25.065Ztest hw::virtio::testutil::test::smoke_mixed_chain ... ok
2232026-05-26T18:06:25.065Ztest vmm::time::test::test_calc_boot_hrtime ... ok
2242026-05-26T18:06:25.065Ztest vmm::mem::test::mapping_shortens_write_bytes_beyond_end ... ok
2252026-05-26T18:06:25.065Ztest vmm::mem::test::subregion_protection ... ok
2262026-05-26T18:06:25.065Ztest vmm::time::test::test_calc_guest_tsc ... ok
2272026-05-26T18:06:25.065Ztest vsock::buffer::test::test_capacity_and_len ... ok
2282026-05-26T18:06:25.065Ztest vmm::time::test::test_calc_guest_uptime ... ok
2292026-05-26T18:06:25.065Ztest vmm::time::test::test_calc_migrate_delta ... ok
2302026-05-26T18:06:25.065Ztest vmm::time::test::test_calc_tsc_delta ... ok
2312026-05-26T18:06:25.065Ztest vmm::time::test::test_invalid_hrtime ... ok
2322026-05-26T18:06:25.065Ztest vmm::mem::test::mapping_valid_subregions ... ok
2332026-05-26T18:06:25.066Ztest vsock::buffer::test::test_partial_write_to ... ok
2342026-05-26T18:06:25.066Ztest vsock::buffer::test::test_push_less_than_capacity ... ok
2352026-05-26T18:06:25.066Ztest vsock::buffer::test::test_push_more_than_capacity ... ok
2362026-05-26T18:06:25.066Ztest vsock::buffer::test::test_wrap_around ... ok
2372026-05-26T18:06:25.066Ztest vmm::mem::test::region_lookup ... ok
2382026-05-26T18:06:25.066Ztest vmm::time::test::test_calc_boot_hrtime_delta ... ok
2392026-05-26T18:06:25.066Ztest vsock::buffer::test::test_write_to ... ok
2402026-05-26T18:06:25.066Ztest vsock::poller::test::halt_from_paused ... ok
2412026-05-26T18:06:25.066Ztest vsock::poller::test::reset_clears_connections ... ok
2422026-05-26T18:06:25.066Ztest vsock::poller::test::request_receives_response ... ok
2432026-05-26T18:06:25.066ZMay 26 18:06:24.947 INFO vsock poller configured with, mappings: {7000: VsockPortMapping { port: 7000, addr: 127.0.0.1:48960 }}, component: vsock-test
2442026-05-26T18:06:25.066ZMay 26 18:06:24.948 INFO vsock poller configured with, mappings: {}, component: vsock-test
2452026-05-26T18:06:25.066Ztest vsock::poller::test::request_then_rw_delivers_data ... ok
2462026-05-26T18:06:25.066ZMay 26 18:06:24.948 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
2472026-05-26T18:06:25.066Ztest vsock::poller::test::end_to_end_guest_to_host_closes_half_open ... ok
2482026-05-26T18:06:25.066Ztest vsock::poller::test::rw_with_invalid_socket_type_receives_rst ... ok
2492026-05-26T18:06:25.066Ztest vsock::poller::test::rst_removes_established_connection ... ok
2502026-05-26T18:06:25.066Ztest vsock::poller::test::pause_resume_preserves_connections ... ok
2512026-05-26T18:06:25.066Ztest vsock::poller::test::end_to_end_guest_to_host ... ok
2522026-05-26T18:06:25.066Ztest vsock::poller::test::credit_update_sent_after_flushing_half_buffer ... ok
2532026-05-26T18:06:25.066Ztest hw::nvme::queue::test::create_cqs ... ok
2542026-05-26T18:06:25.066Ztest hw::nvme::queue::test::create_sqs ... ok
2552026-05-26T18:06:25.066Ztest hw::nvme::queue::test::cq_kicks ... ok
2562026-05-26T18:06:25.066Ztest hw::nvme::queue::test::push_failures ... ok
2572026-05-26T18:06:25.066Ztest hw::pci::device::test::msix_cfg_sizing ... ok
2582026-05-26T18:06:25.101Ztest vsock::poller::test::rx_blocked_resumes_when_descriptors_available ... ok
2592026-05-26T18:06:25.230Ztest hw::virtio::viona::test::run_viona_tests ... FAILED
2602026-05-26T18:06:25.876Ztest vsock::poller::test::end_to_end_large_data ... ok
2612026-05-26T18:06:26.722Ztest hw::nvme::queue::test::push_pop ... ok
2622026-05-26T18:06:26.972Ztest vsock::poller::test::host_socket_eof_sends_shutdown ... ok
2632026-05-26T18:06:28.794Ztest hw::pci::propolis_types_pcipath_is_always_valid_bdf ... ok
2642026-05-26T18:06:28.794Z
2652026-05-26T18:06:28.794Zfailures:
2662026-05-26T18:06:28.794Z
2672026-05-26T18:06:28.794Z---- hw::virtio::viona::test::run_viona_tests stdout ----
2682026-05-26T18:06:28.794Zwriting features: 0x030020
2692026-05-26T18:06:28.794Zn_qpairs: 1
2702026-05-26T18:06:28.794Zinitializing queue 0
2712026-05-26T18:06:28.794Zinitializing queue 1
2722026-05-26T18:06:28.794Zwriting features: 0x030020
2732026-05-26T18:06:28.794Zn_qpairs: 1
2742026-05-26T18:06:28.794Zinitializing queue 0
2752026-05-26T18:06:28.794Zinitializing queue 1
2762026-05-26T18:06:28.794Zwriting features: 0x030020
2772026-05-26T18:06:28.794Zn_qpairs: 1
2782026-05-26T18:06:28.794Zinitializing queue 0
2792026-05-26T18:06:28.794Zinitializing queue 1
2802026-05-26T18:06:28.794Zwriting features: 0x030020
2812026-05-26T18:06:28.794Zn_qpairs: 1
2822026-05-26T18:06:28.794Zinitializing queue 0
2832026-05-26T18:06:28.794Zinitializing queue 1
2842026-05-26T18:06:28.794Zwriting features: 0x030020
2852026-05-26T18:06:28.794Zn_qpairs: 1
2862026-05-26T18:06:28.794Zinitializing queue 0
2872026-05-26T18:06:28.794Zinitializing queue 1
2882026-05-26T18:06:28.795Zwriting features: 0x430020
2892026-05-26T18:06:28.795Zn_qpairs: 11
2902026-05-26T18:06:28.795Zinitializing queue 0
2912026-05-26T18:06:28.795Zinitializing queue 1
2922026-05-26T18:06:28.795Zinitializing queue 2
2932026-05-26T18:06:28.795Zinitializing queue 3
2942026-05-26T18:06:28.795Zinitializing queue 4
2952026-05-26T18:06:28.795Zinitializing queue 5
2962026-05-26T18:06:28.795Zinitializing queue 6
2972026-05-26T18:06:28.795Zinitializing queue 7
2982026-05-26T18:06:28.795Zinitializing queue 8
2992026-05-26T18:06:28.795Zinitializing queue 9
3002026-05-26T18:06:28.795Zinitializing queue 10
3012026-05-26T18:06:28.795Zinitializing queue 11
3022026-05-26T18:06:28.795Zinitializing queue 12
3032026-05-26T18:06:28.795Zinitializing queue 13
3042026-05-26T18:06:28.795Zinitializing queue 14
3052026-05-26T18:06:28.795Zinitializing queue 15
3062026-05-26T18:06:28.795Zinitializing queue 16
3072026-05-26T18:06:28.795Zinitializing queue 17
3082026-05-26T18:06:28.795Zinitializing queue 18
3092026-05-26T18:06:28.795Zinitializing queue 19
3102026-05-26T18:06:28.795Zinitializing queue 20
3112026-05-26T18:06:28.795Zinitializing queue 21
3122026-05-26T18:06:28.795Zwriting features: 0x430020
3132026-05-26T18:06:28.795Zn_qpairs: 11
3142026-05-26T18:06:28.795Zinitializing queue 0
3152026-05-26T18:06:28.795Zinitializing queue 1
3162026-05-26T18:06:28.795Zinitializing queue 2
3172026-05-26T18:06:28.795Zinitializing queue 3
3182026-05-26T18:06:28.795Zinitializing queue 4
3192026-05-26T18:06:28.795Zinitializing queue 5
3202026-05-26T18:06:28.795Zinitializing queue 6
3212026-05-26T18:06:28.795Zinitializing queue 7
3222026-05-26T18:06:28.795Zinitializing queue 8
3232026-05-26T18:06:28.795Zinitializing queue 9
3242026-05-26T18:06:28.795Zinitializing queue 10
3252026-05-26T18:06:28.795Zinitializing queue 11
3262026-05-26T18:06:28.796Zinitializing queue 12
3272026-05-26T18:06:28.796Zinitializing queue 13
3282026-05-26T18:06:28.796Zinitializing queue 14
3292026-05-26T18:06:28.796Zinitializing queue 15
3302026-05-26T18:06:28.796Zinitializing queue 16
3312026-05-26T18:06:28.796Zinitializing queue 17
3322026-05-26T18:06:28.796Zinitializing queue 18
3332026-05-26T18:06:28.796Zinitializing queue 19
3342026-05-26T18:06:28.796Zinitializing queue 20
3352026-05-26T18:06:28.796Zinitializing queue 21
3362026-05-26T18:06:28.796Zwriting features: 0x430020
3372026-05-26T18:06:28.796Zn_qpairs: 11
3382026-05-26T18:06:28.796Zinitializing queue 0
3392026-05-26T18:06:28.796Zinitializing queue 1
3402026-05-26T18:06:28.796Zinitializing queue 2
3412026-05-26T18:06:28.796Zinitializing queue 3
3422026-05-26T18:06:28.796Zinitializing queue 4
3432026-05-26T18:06:28.796Zinitializing queue 5
3442026-05-26T18:06:28.796Zinitializing queue 6
3452026-05-26T18:06:28.796Zinitializing queue 7
3462026-05-26T18:06:28.796Zinitializing queue 8
3472026-05-26T18:06:28.796Zinitializing queue 9
3482026-05-26T18:06:28.796Zinitializing queue 10
3492026-05-26T18:06:28.796Zinitializing queue 11
3502026-05-26T18:06:28.796Zinitializing queue 12
3512026-05-26T18:06:28.796Zinitializing queue 13
3522026-05-26T18:06:28.796Zinitializing queue 14
3532026-05-26T18:06:28.796Zinitializing queue 15
3542026-05-26T18:06:28.796Zinitializing queue 16
3552026-05-26T18:06:28.796Zinitializing queue 17
3562026-05-26T18:06:28.796Zinitializing queue 18
3572026-05-26T18:06:28.796Zinitializing queue 19
3582026-05-26T18:06:28.796Zinitializing queue 20
3592026-05-26T18:06:28.796Zinitializing queue 21
3602026-05-26T18:06:28.796Zwriting features: 0x430020
3612026-05-26T18:06:28.796Zn_qpairs: 11
3622026-05-26T18:06:28.796Zinitializing queue 0
3632026-05-26T18:06:28.838Zinitializing queue 1
3642026-05-26T18:06:28.838Zinitializing queue 2
3652026-05-26T18:06:28.838Zinitializing queue 3
3662026-05-26T18:06:28.838Zinitializing queue 4
3672026-05-26T18:06:28.838Zinitializing queue 5
3682026-05-26T18:06:28.838Zinitializing queue 6
3692026-05-26T18:06:28.838Zinitializing queue 7
3702026-05-26T18:06:28.838Zinitializing queue 8
3712026-05-26T18:06:28.838Zinitializing queue 9
3722026-05-26T18:06:28.838Zinitializing queue 10
3732026-05-26T18:06:28.838Zinitializing queue 11
3742026-05-26T18:06:28.838Zinitializing queue 12
3752026-05-26T18:06:28.838Zinitializing queue 13
3762026-05-26T18:06:28.838Zinitializing queue 14
3772026-05-26T18:06:28.838Zinitializing queue 15
3782026-05-26T18:06:28.838Zinitializing queue 16
3792026-05-26T18:06:28.838Zinitializing queue 17
3802026-05-26T18:06:28.838Zinitializing queue 18
3812026-05-26T18:06:28.839Zinitializing queue 19
3822026-05-26T18:06:28.839Zinitializing queue 20
3832026-05-26T18:06:28.839Zinitializing queue 21
3842026-05-26T18:06:28.839Zwriting features: 0x430020
3852026-05-26T18:06:28.839Zn_qpairs: 11
3862026-05-26T18:06:28.839Zinitializing queue 0
3872026-05-26T18:06:28.839Zinitializing queue 1
3882026-05-26T18:06:28.839Zinitializing queue 2
3892026-05-26T18:06:28.839Zinitializing queue 3
3902026-05-26T18:06:28.839Zinitializing queue 4
3912026-05-26T18:06:28.839Zinitializing queue 5
3922026-05-26T18:06:28.839Zinitializing queue 6
3932026-05-26T18:06:28.839Zinitializing queue 7
3942026-05-26T18:06:28.839Zinitializing queue 8
3952026-05-26T18:06:28.839Zinitializing queue 9
3962026-05-26T18:06:28.839Zinitializing queue 10
3972026-05-26T18:06:28.839Zinitializing queue 11
3982026-05-26T18:06:28.839Zinitializing queue 12
3992026-05-26T18:06:28.839Zinitializing queue 13
4002026-05-26T18:06:28.839Zinitializing queue 14
4012026-05-26T18:06:28.839Zinitializing queue 15
4022026-05-26T18:06:28.839Zinitializing queue 16
4032026-05-26T18:06:28.839Zinitializing queue 17
4042026-05-26T18:06:28.839Zinitializing queue 18
4052026-05-26T18:06:28.839Zinitializing queue 19
4062026-05-26T18:06:28.839Zinitializing queue 20
4072026-05-26T18:06:28.839Zinitializing queue 21
4082026-05-26T18:06:28.839Zwriting features: 0x430020
4092026-05-26T18:06:28.839Zn_qpairs: 11
4102026-05-26T18:06:28.839Zinitializing queue 0
4112026-05-26T18:06:28.839Zinitializing queue 1
4122026-05-26T18:06:28.839Zinitializing queue 2
4132026-05-26T18:06:28.839Zinitializing queue 3
4142026-05-26T18:06:28.839Zinitializing queue 4
4152026-05-26T18:06:28.839Zinitializing queue 5
4162026-05-26T18:06:28.839Zinitializing queue 6
4172026-05-26T18:06:28.840Zinitializing queue 7
4182026-05-26T18:06:28.840Zinitializing queue 8
4192026-05-26T18:06:28.840Zinitializing queue 9
4202026-05-26T18:06:28.840Zinitializing queue 10
4212026-05-26T18:06:28.840Zinitializing queue 11
4222026-05-26T18:06:28.840Zinitializing queue 12
4232026-05-26T18:06:28.840Zinitializing queue 13
4242026-05-26T18:06:28.840Zinitializing queue 14
4252026-05-26T18:06:28.840Zinitializing queue 15
4262026-05-26T18:06:28.840Zinitializing queue 16
4272026-05-26T18:06:28.840Zinitializing queue 17
4282026-05-26T18:06:28.840Zinitializing queue 18
4292026-05-26T18:06:28.840Zinitializing queue 19
4302026-05-26T18:06:28.840Zinitializing queue 20
4312026-05-26T18:06:28.840Zinitializing queue 21
4322026-05-26T18:06:28.840Zwriting features: 0x030020
4332026-05-26T18:06:28.840Zn_qpairs: 1
4342026-05-26T18:06:28.840Zinitializing queue 0
4352026-05-26T18:06:28.840Zinitializing queue 1
4362026-05-26T18:06:28.840Zwriting features: 0x430020
4372026-05-26T18:06:28.840Zn_qpairs: 11
4382026-05-26T18:06:28.840Zinitializing queue 0
4392026-05-26T18:06:28.840Zinitializing queue 1
4402026-05-26T18:06:28.840Zinitializing queue 2
4412026-05-26T18:06:28.840Z
4422026-05-26T18:06:28.840Zthread 'hw::virtio::viona::test::run_viona_tests' panicked at lib/propolis/src/hw/virtio/viona.rs:2456:17:
4432026-05-26T18:06:28.840Zassertion failed: self.status_ok()
4442026-05-26T18:06:28.840Z
4452026-05-26T18:06:28.840Z
4462026-05-26T18:06:28.840Zfailures:
4472026-05-26T18:06:28.840Z hw::virtio::viona::test::run_viona_tests
4482026-05-26T18:06:28.840Z
4492026-05-26T18:06:28.840Ztest result: FAILED. 182 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 3.84s
4502026-05-26T18:06:28.840Z
4512026-05-26T18:06:28.840Z
4522026-05-26T18:06:28.840Zreal 3.859864192
4532026-05-26T18:06:28.840Zuser 4.890507320
4542026-05-26T18:06:28.840Zsys 2.129772608
4552026-05-26T18:06:28.840Ztrap 0.045796489
4562026-05-26T18:06:28.840Ztflt 0.009918854
4572026-05-26T18:06:28.841Zdflt 0.010630552
4582026-05-26T18:06:28.841Zkflt 0.000053530
4592026-05-26T18:06:28.841Zlock 42.972065235
4602026-05-26T18:06:28.841Zslp 9.813200206
4612026-05-26T18:06:28.841Zlat 0.203841995
4622026-05-26T18:06:28.841Zstop 2.946466915
4632026-05-26T18:06:28.873Zprocess exited: duration 16272 ms, exit code 101
 
4642026-05-26T18:06:28.942Zfound 0 output files