DEBUG: Executing python function extend_recipe_sysroot
NOTE: Direct dependencies are ['/srv/pokybuild/yocto-worker/qemuriscv64-ptest/build/layers/openembedded-core/meta/recipes-devtools/qemu/qemu-helper-native_1.0.bb:do_populate_sysroot', '/srv/pokybuild/yocto-worker/qemuriscv64-ptest/build/layers/openembedded-core/meta/recipes-devtools/qemu/qemu-native_11.0.3.bb:do_populate_sysroot', '/srv/pokybuild/yocto-worker/qemuriscv64-ptest/build/layers/openembedded-core/meta/recipes-devtools/qemu/qemu-system-native_11.0.3.bb:do_populate_sysroot', 'virtual:native:/srv/pokybuild/yocto-worker/qemuriscv64-ptest/build/layers/openembedded-core/meta/recipes-devtools/createrepo-c/createrepo-c_1.2.4.bb:do_populate_sysroot', 'virtual:native:/srv/pokybuild/yocto-worker/qemuriscv64-ptest/build/layers/openembedded-core/meta/recipes-devtools/dnf/dnf_4.24.0.bb:do_populate_sysroot', 'virtual:native:/srv/pokybuild/yocto-worker/qemuriscv64-ptest/build/layers/openembedded-core/meta/recipes-devtools/pseudo/pseudo_git.bb:do_populate_sysroot', 'virtual:native:/srv/pokybuild/yocto-worker/qemuriscv64-ptest/build/layers/openembedded-core/meta/recipes-devtools/unfs3/unfs3_0.11.0.bb:do_populate_sysroot']
NOTE: Installed into sysroot: []
NOTE: Skipping as already exists in sysroot: ['gettext-minimal-native', 'llvm-tblgen-native', 'cmake-native', 'libtool-native', 'm4-native', 'qemu-helper-native', 'qemu-native', 'qemu-system-native', 'texinfo-dummy-native', 'mesa-tools-native', 'openssl-native', 'libslirp-native', 'expat-native', 'gettext-native', 'glib-2.0-native', 'libxml2-native', 'ncurses-native', 'readline-native', 'util-linux-libuuid-native', 'util-linux-native', 'zlib-native', 'binutils-native', 'chrpath-native', 'clang-native', 'libclc-native', 'llvm-native', 'createrepo-c-native', 'dnf-native', 'elfutils-native', 'file-native', 'flex-native', 'gnu-config-native', 'json-c-native', 'libcomps-native', 'libdnf-native', 'libedit-native', 'libmodulemd-native', 'librepo-native', 'lua-native', 'make-native', 'perl-native', 'pseudo-native', 'python3-build-native', 'python3-calver-native', 'python3-cython-native', 'python3-editables-native', 'python3-flit-core-native', 'python3-hatchling-native', 'python3-installer-native', 'python3-mako-native', 'python3-markupsafe-native', 'python3-packaging-native', 'python3-pathspec-native', 'python3-pluggy-native', 'python3-pygments-native', 'python3-pyproject-hooks-native', 'python3-pyyaml-native', 'python3-setuptools-scm-native', 'python3-setuptools-native', 'python3-trove-classifiers-native', 'python3-typing-extensions-native', 'python3-vcs-versioning-native', 'python3-wheel-native', 'python3-native', 'python3-qemu-qmp-native', 'rpm-native', 'swig-native', 'unfs3-native', 'bzip2-native', 'diffutils-native', 'libarchive-native', 'libidn2-native', 'libsolv-native', 'libtirpc-native', 'lzlib-native', 'unzip-native', 'xz-native', 'zstd-native', 'gobject-introspection-native', 'libdrm-native', 'libepoxy-native', 'libsdl2-native', 'mesa-native', 'spirv-headers-native', 'spirv-llvm-translator-native', 'spirv-tools-native', 'virglrenderer-native', 'wayland-protocols-native', 'wayland-native', 'xrandr-native', 'libpciaccess-native', 'libpthread-stubs-native', 'libx11-native', 'libxau-native', 'libxcb-native', 'libxdamage-native', 'libxdmcp-native', 'libxext-native', 'libxfixes-native', 'libxrandr-native', 'libxrender-native', 'libxshmfence-native', 'libxxf86vm-native', 'pixman-native', 'xtrans-native', 'xcb-proto-native', 'xorgproto-native', 'makedepend-native', 'util-macros-native', 'dtc-native', 'alsa-lib-native', 'libpng-native', 'acl-native', 'attr-native', 'curl-native', 'gdbm-native', 'gmp-native', 'gnutls-native', 'libtasn1-native', 'gpgme-native', 'libassuan-native', 'libcap-ng-native', 'libcap-native', 'libffi-native', 'libgcrypt-native', 'libgpg-error-native', 'libmicrohttpd-native', 'libpcre2-native', 'libunistring-native', 'libyaml-native', 'nettle-native', 'p11-kit-native', 'popt-native', 'sqlite3-native']
DEBUG: Python function extend_recipe_sysroot finished
DEBUG: Executing python function do_testimage
DEBUG: test suites:
test_get_context (parselogs.ParseLogsTest.test_get_context)
test_ping (ping.PingTest.test_ping)
test_ssh (ssh.SSHTest.test_ssh)
test_parselogs (parselogs.ParseLogsTest.test_parselogs)
test_ptestrunner_expectfail (ptest.PtestRunnerTest.test_ptestrunner_expectfail)
test_ptestrunner_expectsuccess (ptest.PtestRunnerTest.test_ptestrunner_expectsuccess)
DEBUG: Not using kvm for runqemu
QMP Available for connection at TOPDIR/tmp/.hawre5_5
DEBUG: Created listening socket for qemu serial console on: 127.0.0.1:59503
DEBUG: Created listening socket for qemu serial console on: 127.0.0.1:56405
DEBUG: launchcmd=runqemu snapshot qemuriscv64 TOPDIR/tmp/deploy/images/qemuriscv64/core-image-ptest-python3-pefile-qemuriscv64.rootfs.ext4.zst tcpserial=59503:56405 bootparams=" printk.time=1" qemuparams="-pidfile TOPDIR/pidfile_2061456 -S -qmp unix:./.30c8goii,server,wait -qmp unix:./.hawre5_5,server,nowait"
DEBUG: runqemu started, pid is 2062408
DEBUG: waiting at most 300 seconds for qemu pid (07/30/26 08:51:10)
DEBUG: QMP Initializing to TOPDIR/tmp/.30c8goii
DEBUG: QMP Connecting to TOPDIR/tmp/.30c8goii
QMP connected to QEMU at 07/30/26 08:51:17 and took 6.69 seconds
QMP released QEMU at 07/30/26 08:51:17 and took 0.09 seconds from connect
DEBUG: qemu started in 6.77 seconds - qemu procces pid is 2063045 (07/30/26 08:51:17)
DEBUG: qemu cmdline used:
TOPDIR/tmp/work/x86_64-linux/qemu-helper-native/1.0/recipe-sysroot-native/usr/bin/qemu-system-riscv64 -device virtio-net-device,netdev=net0,mac=52:54:00:12:34:10 -netdev tap,id=net0,ifname=tap7,script=no,downscript=no -object rng-random,filename=/dev/urandom,id=rng0 -device virtio-rng-pci,rng=rng0 -drive id=disk0,file=/home/pokybuild/tmp/core-image-ptest-python3-pefile-qemuriscv64.rootfs-20260730081147.ext4.2062408,if=none,format=raw -device virtio-blk-device,drive=disk0 -device qemu-xhci -device usb-tablet -device usb-kbd -machine virt -cpu rva23s64,pmp=true -smp 4 -m 1024 -device virtio-serial-device -chardev socket,id=virtcon,port=59503,host=127.0.0.1,nodelay=on -device virtconsole,chardev=virtcon -serial tcp:127.0.0.1:56405,nodelay=on -pidfile TOPDIR/pidfile_2061456 -S -qmp unix:./.30c8goii,server,wait -qmp unix:./.hawre5_5,server,nowait -serial null -serial mon:vc -vnc unix:/tmp/qemu-tap-locks/tap7.vnc-socket -device bochs-display -bios TOPDIR/tmp/deploy/images/qemuriscv64/fw_jump.elf -kernel TOPDIR/tmp/deploy/images/qemuriscv64/Image -append root=/dev/vda rw mem=1024M ip=192.168.7.16::192.168.7.15:255.255.255.0::eth0:off:8.8.8.8 net.ifnames=0 console=ttyS0 console=hvc0 earlycon=sbi swiotlb=0 printk.time=1
DEBUG: Target IP: 192.168.7.16
DEBUG: Server IP: 192.168.7.15
DEBUG: Starting logging thread
DEBUG: Starting thread event loop
DEBUG: Connection request received
DEBUG: Setting connection established event
DEBUG: Output from runqemu:
runqemu - INFO - Decompressing TOPDIR/tmp/deploy/images/qemuriscv64/core-image-ptest-python3-pefile-qemuriscv64.rootfs.ext4.zst to TOPDIR/tmp/deploy/images/qemuriscv64/core-image-ptest-python3-pefile-qemuriscv64.rootfs-20260730081147.ext4
TOPDIR/tmp/deploy/images/qemuriscv64/core-image-ptest-python3-pefile-qemuriscv64.rootfs-20260730081147.ext4.zst: 467533824 bytes
runqemu - INFO - Continuing with the following parameters:
KERNEL: [TOPDIR/tmp/deploy/images/qemuriscv64/Image]
BIOS: [TOPDIR/tmp/deploy/images/qemuriscv64/fw_jump.elf]
MACHINE: [qemuriscv64]
FSTYPE: [ext4]
ROOTFS: [TOPDIR/tmp/deploy/images/qemuriscv64/core-image-ptest-python3-pefile-qemuriscv64.rootfs-20260730081147.ext4]
SNAPSHOT: [Enabled. Changes on rootfs won't be kept after QEMU shutdown.]
CONFFILE: [TOPDIR/tmp/deploy/images/qemuriscv64/core-image-ptest-python3-pefile-qemuriscv64.rootfs.qemuboot.conf]
runqemu - INFO - Acquiring lockfile /tmp/qemu-tap-locks/tap0.lock failed: [Errno 11] Resource temporarily unavailable
runqemu - INFO - Acquiring lockfile /tmp/qemu-tap-locks/tap1.lock failed: [Errno 11] Resource temporarily unavailable
runqemu - INFO - Acquiring lockfile /tmp/qemu-tap-locks/tap2.lock failed: [Errno 11] Resource temporarily unavailable
runqemu - INFO - Acquiring lockfile /tmp/qemu-tap-locks/tap3.lock failed: [Errno 11] Resource temporarily unavailable
runqemu - INFO - Acquiring lockfile /tmp/qemu-tap-locks/tap4.lock failed: [Errno 11] Resource temporarily unavailable
runqemu - INFO - Acquiring lockfile /tmp/qemu-tap-locks/tap5.lock failed: [Errno 11] Resource temporarily unavailable
runqemu - INFO - Acquiring lockfile /tmp/qemu-tap-locks/tap6.lock failed: [Errno 11] Resource temporarily unavailable
runqemu - INFO - Using preconfigured tap device tap7
runqemu - INFO - If this is not intended, touch /tmp/qemu-tap-locks/tap7.skip to make runqemu skip tap7.
runqemu - INFO - Network configuration: ip=192.168.7.16::192.168.7.15:255.255.255.0::eth0:off:8.8.8.8 net.ifnames=0
runqemu - INFO - Copying rootfs to /home/pokybuild/tmp/core-image-ptest-python3-pefile-qemuriscv64.rootfs-20260730081147.ext4.2062408
runqemu - INFO - Copy done in 0.2795138359069824 seconds
runqemu - INFO - Using VNC server at unix:/tmp/qemu-tap-locks/tap7.vnc-socket for graphical output
runqemu - INFO - Running TOPDIR/tmp/work/x86_64-linux/qemu-helper-native/1.0/recipe-sysroot-native/usr/bin/qemu-system-riscv64 -device virtio-net-device,netdev=net0,mac=52:54:00:12:34:10 -netdev tap,id=net0,ifname=tap7,script=no,downscript=no -object rng-random,filename=/dev/urandom,id=rng0 -device virtio-rng-pci,rng=rng0 -drive id=disk0,file=/home/pokybuild/tmp/core-image-ptest-python3-pefile-qemuriscv64.rootfs-20260730081147.ext4.2062408,if=none,format=raw -device virtio-blk-device,drive=disk0 -device qemu-xhci -device usb-tablet -device usb-kbd -machine virt -cpu rva23s64,pmp=true -smp 4 -m 1024 -device virtio-serial-device -chardev socket,id=virtcon,port=59503,host=127.0.0.1,nodelay=on -device virtconsole,chardev=virtcon -serial tcp:127.0.0.1:56405,nodelay=on -pidfile TOPDIR/pidfile_2061456 -S -qmp unix:./.30c8goii,server,wait -qmp unix:./.hawre5_5,server,nowait -serial null -serial mon:vc -vnc unix:/tmp/qemu-tap-locks/tap7.vnc-socket -device bochs-display -bios TOPDIR/tmp/deploy/images/qemuriscv64/fw_jump.elf -kernel TOPDIR/tmp/deploy/images/qemuriscv64/Image -append 'root=/dev/vda rw mem=1024M ip=192.168.7.16::192.168.7.15:255.255.255.0::eth0:off:8.8.8.8 net.ifnames=0 console=ttyS0 console=hvc0 earlycon=sbi swiotlb=0 printk.time=1'
runqemu - INFO - Host uptime: 220032.33
DEBUG: Waiting at most 1500 seconds for login banner (07/30/26 08:51:17)
DEBUG: Connection from 127.0.0.1:45658
DEBUG: Partial boot log:
OpenSBI v1.9
____ _____ ____ _____
/ __ \ / ____| _ \_ _|
| | | |_ __ ___ _ __ | (___ | |_) || |
| | | | '_ \ / _ \ '_ \ \___ \| _ < | |
| |__| | |_) | __/ | | |____) | |_) || |_
\____/| .__/ \___|_| |_|_____/|____/_____|
| |
|_|
Platform Name : riscv-virtio,qemu
Platform Features : medeleg
Platform HART Count : 4
Platform HART Protection : pmp
Platform IPI Device : aclint-mswi
Platform Timer Device : aclint-mtimer @ 10000000Hz
Platform Console Device : uart8250
Platform HSM Device : ---
Platform PMU Device : ---
Platform Reboot Device : syscon-reboot
Platform Shutdown Device : syscon-poweroff
Platform Suspend Device : ---
Platform CPPC Device : ---
Firmware Base : 0x80000000
Firmware Size : 357 KB
Firmware RW Offset : 0x40000
Firmware RW Size : 101 KB
Firmware Heap Offset
DEBUG: Partial boot log:
: 0x4d000
Firmware Heap Size : 49 KB (total), 0 KB (reserved), 14 KB (used), 33 KB (free)
Firmware Scratch Size : 4096 B (total), 1536 B (used), 2560 B (free)
Runtime SBI Version : 3.0
Standard SBI Extensions : time,rfnc,ipi,base,hsm,srst,pmu,dbcn,fwft,legacy,dbtr,sse
Experimental SBI Extensions : none
Domain0 Name : root
Domain0 Boot HART : 1
Domain0 HARTs : 0x0*,0x1*,0x2*,0x3*
Domain0 Region00 : 0x0000000080040000-0x000000008005ffff M: (F,R,W) S/U: ()
Domain0 Region01 : 0x0000000080000000-0x000000008003ffff M: (F,R,X) S/U: ()
Domain0 Region02 : 0x0000000000100000-0x0000000000100fff M: (I,R,W) S/U: (R,W)
Domain0 Region03 : 0x0000000010000000-0x0000000010000fff M: (I,R,W) S/U: (R,W)
Domain0 Region04 : 0x0000000002000000-0x000000000200ffff M: (I,R,W) S/U: ()
Domain0 Region05 : 0x000000000c400000-0x000000000c5fffff M: (I,R,W) S/U: (R,W)
Domain0 Region06
DEBUG: Partial boot log:
: 0x000000000c000000-0x000000000c3fffff M: (I,R,W) S/U: (R,W)
Domain0 Region07 : 0x0000000000000000-0xffffffffffffffff M: () S/U: (R,W,X)
Domain0 Next Address : 0x0000000080200000
Domain0 Next Arg1 : 0x0000000082200000
Domain0 Next Mode : S-mode
Domain0 SysReset : yes
Domain0 SysSuspend : yes
Boot HART ID : 1
Boot HART Domain : root
Boot HART Priv Version : v1.12
Boot HART Base ISA : rv64imafdcbvh
Boot HART ISA Extensions : smstateen,sscofpmf,sstc,zicntr,zihpm,zicboz,zicbom,svpbmt,sdtrig,svade,smnpm,ssstateen,v,f,d
Boot HART PMP Count : 16
Boot HART PMP Granularity : 2 bits
Boot HART PMP Address Bits : 54
Boot HART MHPM Info : 16 (0x0007fff8)
Boot HART Debug Triggers : 2 triggers
Boot HART MIDELEG : 0x0000000000003666
Boot HART MEDELEG : 0x0000000000f4b509
DEBUG: Partial boot log:
[ 0.000000] Booting Linux on hartid 1
[ 0.000000] Linux version 6.18.39-yocto-standard (oe-user@oe-host) (riscv64-poky-linux-gcc (GCC) 16.1.0, GNU ld (GNU Binutils) 2.47.20260726) #1 SMP PREEMPT Wed Jul 22 16:47:36 UTC 2026
[ 0.000000] random: crng init done
[ 0.000000] Machine model: riscv-virtio,qemu
[ 0.000000] SBI specification v3.0 detected
[ 0.000000] SBI implementation ID=0x1 Version=0x10009
[ 0.000000] SBI TIME extension detected
[ 0.000000] SBI IPI extension detected
[ 0.000000] SBI RFENCE extension detected
[ 0.000000] SBI SRST extension detected
[ 0.000000] SBI DBCN extension detected
[ 0.000000] SBI FWFT extension detected
[ 0.000000] Memory limited to 1024MB
[ 0.000000] efi: UEFI not found.
[ 0.000000] OF: reserved mem: 0x0000000080000000..0x000000008003ffff (256 KiB) nomap non-reusable mmode_resv1@80000000
[ 0.000000] OF: reserved mem: 0x0000000080040000..0x000000008005ffff (128 KiB) nomap non-reusable mmode_resv0@80040000
[ 0.00
DEBUG: Partial boot log:
0000] Zone ranges:
[ 0.000000] DMA32 [mem 0x0000000080000000-0x00000000bfffffff]
[ 0.000000] Normal empty
[ 0.000000] Movable zone start for each node
[ 0.000000] Early memory node ranges
[ 0.000000] node 0: [mem 0x0000000080000000-0x000000008005ffff]
[ 0.000000] node 0: [mem 0x0000000080060000-0x00000000bfffffff]
[ 0.000000] Initmem setup node 0 [mem 0x0000000080000000-0x00000000bfffffff]
[ 0.000000] SBI HSM extension detected
[ 0.000000] riscv: base ISA extensions acdfhim
[ 0.000000] riscv: ELF capabilities acdfim
[ 0.000000] Queued spinlock using Ziccrse: enabled
[ 0.000000] percpu: Embedded 28 pages/cpu s75416 r8192 d31080 u114688
[ 0.000000] Kernel command line: root=/dev/vda rw mem=1024M ip=192.168.7.16::192.168.7.15:255.255.255.0::eth0:off:8.8.8.8 net.ifnames=0 console=ttyS0 console=hvc0 earlycon=sbi swiotlb=0 printk.time=1
[ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes
[ 0.000000] Dentry cac
DEBUG: Partial boot log:
he hash table entries: 131072 (order: 8, 1048576 bytes, linear)
[ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)
[ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144
[ 0.000000] mem auto-init: stack:all(zero), heap alloc:off, heap free:off
[ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[ 0.000000] ftrace: allocating 42549 entries in 167 pages
[ 0.000000] ftrace: allocated 167 pages with 5 groups
[ 0.000000] rcu: Preemptible hierarchical RCU implementation.
[ 0.000000] rcu: RCU event tracing is enabled.
[ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8 to nr_cpu_ids=4.
[ 0.000000] Trampoline variant of Tasks RCU enabled.
[ 0.000000] Rude variant of Tasks RCU enabled.
[ 0.000000] Tracing variant of Tasks RCU enabled.
[ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.
[ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4
DEBUG: Partial boot log:
[ 0.000000] RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4.
[ 0.000000] RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4.
[ 0.000000] RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4.
[ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0
[ 0.000000] riscv-intc: 64 local interrupts mapped
[ 0.000000] riscv: providing IPIs using SBI IPI extension
[ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.
[ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[ 0.000000] clocksource: riscv_clocksource: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns
[ 0.000104] sched_clock: 64 bits at 10MHz, resolution 100ns, wraps every 4398046511100ns
[ 0.000269] riscv-timer: Timer interrupt in S-mode is available via sstc extension
[ 0.006888] kfence: initialized
DEBUG: Partial boot log:
- using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)
[ 0.015522] Console: colour dummy device 80x25
[ 0.017929] Calibrating delay loop (skipped), value calculated using timer frequency.. 20.00 BogoMIPS (lpj=40000)
[ 0.018138] pid_max: default: 32768 minimum: 301
[ 0.021377] LSM: initializing lsm=capability,landlock
[ 0.022116] landlock: Up and running.
[ 0.025555] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)
[ 0.025627] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)
[ 0.081582] riscv: ELF compat mode supported
[ 0.081975] ASID allocator using 16 bits (65536 entries)
[ 0.088045] rcu: Hierarchical SRCU implementation.
[ 0.088117] rcu: Max phase no-delay instances is 1000.
[ 0.092902] EFI services will not be available.
[ 0.094717] smp: Bringing up secondary CPUs ...
[ 1.269777] CPU2: failed to come online
[ 1.294713] smp: Brought up 1 node, 3 CPUs
[ 1.309381] Memory: 993
DEBUG: Partial boot log:
548K/1048576K available (12992K kernel code, 5545K rwdata, 4096K rodata, 2658K init, 455K bss, 49836K reserved, 0K cma-reserved)
[ 1.343140] devtmpfs: initialized
[ 1.456650] posixtimers hash table entries: 2048 (order: 3, 32768 bytes, linear)
[ 1.457192] futex hash table entries: 1024 (65536 bytes on 1 NUMA nodes, total 64 KiB, linear).
[ 1.462642] DMI not present or invalid.
[ 1.472110] NET: Registered PF_NETLINK/PF_ROUTE protocol family
[ 1.482896] SBI misaligned access exception delegation ok
[ 1.515400] cpu1: Ratio of byte access time to unaligned word access is 7.30, unaligned accesses are fast
[ 1.515590] cpu3: Ratio of byte access time to unaligned word access is 5.50, unaligned accesses are fast
[ 1.545586] cpu0: Ratio of byte access time to unaligned word access is 7.52, unaligned accesses are fast
[ 1.726713] raid6: int64x8 gen() 1157 MB/s
[ 1.799320] raid6: int64x4 gen() 1312 MB/s
[ 1.874041] raid6: int64x2 gen() 1375 MB/s
[ 1.954296] raid6
DEBUG: Partial boot log:
: int64x1 gen() 928 MB/s
[ 1.954388] raid6: using algorithm int64x2 gen() 1375 MB/s
[ 2.038600] raid6: .... xor() 856 MB/s, rmw enabled
[ 2.038709] raid6: using intx1 recovery algorithm
[ 2.041066] iommu: Default domain type: Translated
[ 2.041125] iommu: DMA domain TLB invalidation policy: strict mode
[ 2.062107] SCSI subsystem initialized
[ 2.066041] usbcore: registered new interface driver usbfs
[ 2.068812] usbcore: registered new interface driver hub
[ 2.070408] usbcore: registered new device driver usb
[ 2.071120] pps_core: LinuxPPS API ver. 1 registered
[ 2.071149] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[ 2.071261] PTP clock support registered
[ 2.114224] vgaarb: loaded
[ 2.131468] clocksource: Switched to clocksource riscv_clocksource
[ 2.842571] NET: Registered PF_INET protocol family
[ 2.844500] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)
[ 2.855747] tcp_li
DEBUG: Partial boot log:
sten_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)
[ 2.855890] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
[ 2.856009] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)
[ 2.856419] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)
[ 2.890669] TCP: Hash tables configured (established 8192 bind 8192)
[ 2.892668] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)
[ 2.893064] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)
[ 2.899288] NET: Registered PF_UNIX/PF_LOCAL protocol family
[ 2.906298] RPC: Registered named UNIX socket transport module.
[ 2.906396] RPC: Registered udp transport module.
[ 2.906948] RPC: Registered tcp transport module.
[ 2.906977] RPC: Registered tcp-with-tls transport module.
[ 2.906994] RPC: Registered tcp NFSv4.1 backchannel transport module.
[ 2.907246] PCI: CLS 0 bytes, default 64
[ 2.919456] workingset: timestam
DEBUG: Partial boot log:
p_bits=46 max_order=18 bucket_order=0
[ 2.927605] NFS: Registering the id_resolver key type
[ 2.928194] Key type id_resolver registered
[ 2.928254] Key type id_legacy registered
[ 2.952787] Key type cifs.idmap registered
[ 3.106735] xor: measuring software checksum speed
[ 3.108916] 8regs : 1622 MB/sec
[ 3.111079] 8regs_prefetch : 1559 MB/sec
[ 3.112815] 32regs : 1933 MB/sec
[ 3.116820] 32regs_prefetch : 826 MB/sec
[ 3.116948] xor: using function: 32regs (1933 MB/sec)
[ 3.117605] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250)
[ 3.117857] io scheduler mq-deadline registered
[ 3.117955] io scheduler kyber registered
[ 3.128920] riscv-plic: plic@c000000: mapped 95 interrupts with 4 handlers for 8 contexts.
[ 3.135066] pci-host-generic 30000000.pci: host bridge /soc/pci@30000000 ranges:
[ 3.136115] pci-host-generic 30000000.pci: IO 0x0003000000..0x000300ffff -> 0x0000000000
[ 3.1368
DEBUG: Partial boot log:
05] pci-host-generic 30000000.pci: MEM 0x0040000000..0x007fffffff -> 0x0040000000
[ 3.136909] pci-host-generic 30000000.pci: MEM 0x0400000000..0x07ffffffff -> 0x0400000000
[ 3.137706] pci-host-generic 30000000.pci: Memory resource size exceeds max for 32 bits
[ 3.141117] pci-host-generic 30000000.pci: ECAM at [mem 0x30000000-0x3fffffff] for [bus 00-ff]
[ 3.143585] pci-host-generic 30000000.pci: PCI host bridge to bus 0000:00
[ 3.143883] pci_bus 0000:00: root bus resource [bus 00-ff]
[ 3.143979] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]
[ 3.144034] pci_bus 0000:00: root bus resource [mem 0x40000000-0x7fffffff]
[ 3.144049] pci_bus 0000:00: root bus resource [mem 0x400000000-0x7ffffffff]
[ 3.145812] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint
[ 3.153014] pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint
[ 3.153365] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]
[ 3.153433] pci 0000:
DEBUG: Partial boot log:
00:01.0: BAR 1 [mem 0x00000000-0x00000fff]
[ 3.153487] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]
[ 3.154583] pci 0000:00:02.0: [1b36:000d] type 00 class 0x0c0330 PCIe Root Complex Integrated Endpoint
[ 3.154684] pci 0000:00:02.0: BAR 0 [mem 0x00000000-0x00003fff 64bit]
[ 3.155021] pci 0000:00:02.0: enabling Extended Tags
[ 3.155952] pci 0000:00:03.0: [1234:1111] type 00 class 0x038000 PCIe Root Complex Integrated Endpoint
[ 3.156029] pci 0000:00:03.0: BAR 0 [mem 0x00000000-0x00ffffff pref]
[ 3.156052] pci 0000:00:03.0: BAR 2 [mem 0x00000000-0x00000fff]
[ 3.156082] pci 0000:00:03.0: ROM [mem 0x00000000-0x00007fff pref]
[ 3.156111] pci 0000:00:03.0: enabling Extended Tags
[ 3.159162] pci 0000:00:03.0: BAR 0 [mem 0x40000000-0x40ffffff pref]: assigned
[ 3.159452] pci 0000:00:03.0: ROM [mem 0x41000000-0x41007fff pref]: assigned
[ 3.159576] pci 0000:00:01.0: BAR 4 [mem 0x400000000-0x400003fff 64bit pref]: assigned
[ 3.159694] pci 0000:00:02.0:
DEBUG: Partial boot log:
BAR 0 [mem 0x400004000-0x400007fff 64bit]: assigned
[ 3.159751] pci 0000:00:01.0: BAR 1 [mem 0x41008000-0x41008fff]: assigned
[ 3.159771] pci 0000:00:03.0: BAR 2 [mem 0x41009000-0x41009fff]: assigned
[ 3.159800] pci 0000:00:01.0: BAR 0 [io 0x0020-0x003f]: assigned
[ 3.160164] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]
[ 3.160203] pci_bus 0000:00: resource 5 [mem 0x40000000-0x7fffffff]
[ 3.160216] pci_bus 0000:00: resource 6 [mem 0x400000000-0x7ffffffff]
[ 3.161531] pci 0000:00:02.0: enabling device (0000 -> 0002)
[ 3.171134] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)
[ 3.177583] Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled
[ 3.248323] printk: legacy console [ttyS0] disabled
[ 3.265669] 10000000.serial: ttyS0 at MMIO 0x10000000 (irq = 13, base_baud = 230400) is a 16550A
[ 3.269077] printk: legacy console [ttyS0] enabled
[ 3.584363] bochs-drm 0000:00:03.0: enabling device (0000 -> 0002)
[ 3.586197] [drm] Found bochs VGA, ID
DEBUG: Partial boot log:
0xb0c5.
[ 3.587343] [drm] Framebuffer size 16384 kB @ 0x40000000, mmio @ 0x41009000.
[ 3.599212] [drm] Initialized bochs-drm 1.0.0 for 0000:00:03.0 on minor 0
[ 3.711907] printk: legacy console [hvc0] enabled
[ 3.892876] Console: switching to colour frame buffer device 160x50
[ 3.931674] bochs-drm 0000:00:03.0: [drm] fb0: bochs-drmdrmfb frame buffer device
[ 4.040142] brd: module loaded
[ 4.045382] virtio_blk virtio1: 1/0/0 default/read/poll queues
[ 4.059220] virtio_blk virtio1: [vda] 913152 512-byte logical blocks (468 MB/446 MiB)
[ 4.107043] xhci_hcd 0000:00:02.0: xHCI Host Controller
[ 4.109529] xhci_hcd 0000:00:02.0: new USB bus registered, assigned bus number 1
[ 4.161390] xhci_hcd 0000:00:02.0: hcc params 0x00087001 hci version 0x100 quirks 0x0000000000000010
[ 4.285734] xhci_hcd 0000:00:02.0: xHCI Host Controller
[ 4.287811] xhci_hcd 0000:00:02.0: new USB bus registered, assigned bus number 2
[ 4.289573] xhci_hcd 0000:00:02.0: Host supports USB 3
DEBUG: Partial boot log:
.0 SuperSpeed
[ 4.357109] hub 1-0:1.0: USB hub found
[ 4.359378] hub 1-0:1.0: 4 ports detected
[ 4.366338] usb usb2: We don't know the algorithms for LPM for this host, disabling LPM.
[ 4.379054] hub 2-0:1.0: USB hub found
[ 4.380122] hub 2-0:1.0: 4 ports detected
[ 4.401688] goldfish_rtc 101000.rtc: registered as rtc0
[ 4.409431] goldfish_rtc 101000.rtc: setting system clock to 2026-07-30T08:51:23 UTC (1785401483)
[ 4.426325] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev
[ 4.441453] usbcore: registered new interface driver usbhid
[ 4.443288] usbhid: USB HID core driver
[ 4.445320] riscv-pmu-sbi: SBI PMU extension is available
[ 4.448005] riscv-pmu-sbi: 16 firmware and 18 hardware counters
[ 4.623837] usb 1-1: new high-speed USB device number 2 using xhci_hcd
DEBUG: Partial boot log:
[ 4.857619] input: QEMU QEMU USB Tablet as /devices/platform/soc/30000000.pci/pci0000:00/0000:00:02.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input0
[ 4.867740] hid-generic 0003:0627:0001.0001: input: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:02.0-1/input0
DEBUG: Partial boot log:
[ 4.995729] usb 1-2: new high-speed USB device number 3 using xhci_hcd
DEBUG: Partial boot log:
[ 5.176105] input: QEMU QEMU USB Keyboard as /devices/platform/soc/30000000.pci/pci0000:00/0000:00:02.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input1
[ 5.244355] hid-generic 0003:0627:0001.0002: input: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:02.0-2/input0
WARNING: Target didn't reach login banner in 1500 seconds (07/30/26 09:16:21)
WARNING: Last 25 lines of login console (17808):
[ 4.059220] virtio_blk virtio1: [vda] 913152 512-byte logical blocks (468 MB/446 MiB)
[ 4.107043] xhci_hcd 0000:00:02.0: xHCI Host Controller
[ 4.109529] xhci_hcd 0000:00:02.0: new USB bus registered, assigned bus number 1
[ 4.161390] xhci_hcd 0000:00:02.0: hcc params 0x00087001 hci version 0x100 quirks 0x0000000000000010
[ 4.285734] xhci_hcd 0000:00:02.0: xHCI Host Controller
[ 4.287811] xhci_hcd 0000:00:02.0: new USB bus registered, assigned bus number 2
[ 4.289573] xhci_hcd 0000:00:02.0: Host supports USB 3.0 SuperSpeed
[ 4.357109] hub 1-0:1.0: USB hub found
[ 4.359378] hub 1-0:1.0: 4 ports detected
[ 4.366338] usb usb2: We don't know the algorithms for LPM for this host, disabling LPM.
[ 4.379054] hub 2-0:1.0: USB hub found
[ 4.380122] hub 2-0:1.0: 4 ports detected
[ 4.401688] goldfish_rtc 101000.rtc: registered as rtc0
[ 4.409431] goldfish_rtc 101000.rtc: setting system clock to 2026-07-30T08:51:23 UTC (1785401483)
[ 4.426325] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev
[ 4.441453] usbcore: registered new interface driver usbhid
[ 4.443288] usbhid: USB HID core driver
[ 4.445320] riscv-pmu-sbi: SBI PMU extension is available
[ 4.448005] riscv-pmu-sbi: 16 firmware and 18 hardware counters
[ 4.623837] usb 1-1: new high-speed USB device number 2 using xhci_hcd
[ 4.857619] input: QEMU QEMU USB Tablet as /devices/platform/soc/30000000.pci/pci0000:00/0000:00:02.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input0
[ 4.867740] hid-generic 0003:0627:0001.0001: input: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:02.0-1/input0
[ 4.995729] usb 1-2: new high-speed USB device number 3 using xhci_hcd
[ 5.176105] input: QEMU QEMU USB Keyboard as /devices/platform/soc/30000000.pci/pci0000:00/0000:00:02.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input1
[ 5.244355] hid-generic 0003:0627:0001.0002: input: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:02.0-2/input0
WARNING: Last 25 lines of all logging (20123):
[ 4.289573] xhci_hcd 0000:00:02.0: Host supports USB 3[ 4.623837] usb 1-1: new high-speed USB device number 2 using xhci_hcd
.0 SuperSpeed
[ 4.357109] hub 1-0:1.0: USB hub found
[ 4.359378] hub 1-0:1.0: 4 ports detected
[ 4.366338] usb usb2: We don't know the algorithms for LPM for this host, disabling LPM.
[ 4.379054] hub 2-0:1.0: USB hub found
[ 4.380122] hub 2-0:1.0: 4 ports detected
[ 4.401688] goldfish_rtc 101000.rtc: registered as rtc0
[ 4.409431] goldfish_rtc 101000.rtc: setting system clock to 2026-07-30T08:51:23 UTC (1785401483)
[ 4.426325] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev
[ 4.441453] usbcore: registered new interface driver usbhid
[ 4.443288] usbhid: USB HID core driver
[ 4.445320] riscv-pmu-sbi: SBI PMU extension is available
[ 4.448005] riscv-pmu-sbi: 16 firmware and 18 hardware counters
[ 4.623837] usb 1-1: new high-speed USB device number 2 using xhci_hcd
[ 4.857619] input: QEMU QEMU USB Tablet as /devices/platform/soc/30000000.pci/pci0000:00/0000:00:02.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input0
[ 4.867740] hid-generic 0003:0627:0001.0001: input: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:02.0-1/input0
[ 4.857619] input: QEMU QEMU USB Tablet as /devices/platform/soc/30000000.pci/pci0000:00/0000:00:02.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input0
[ 4.867740] hid-generic 0003:0627:0001.0001: input: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:02.0-1/input0
[ 4.995729] usb 1-2: new high-speed USB device number 3 using xhci_hcd
[ 4.995729] usb 1-2: new high-speed USB device number 3 using xhci_hcd
[ 5.176105] input: QEMU QEMU USB Keyboard as /devices/platform/soc/30000000.pci/pci0000:00/0000:00:02.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input1
[ 5.244355] hid-generic 0003:0627:0001.0002: input: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:02.0-2/input0
[ 5.176105] input: QEMU QEMU USB Keyboard as /devices/platform/soc/30000000.pci/pci0000:00/0000:00:02.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input1
[ 5.244355] hid-generic 0003:0627:0001.0002: input: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:02.0-2/input0
WARNING: Check full boot log: TOPDIR/tmp/work/qemuriscv64-poky-linux/core-image-ptest-python3-pefile/1.0/testimage/qemu_boot_log.20260730083010
DEBUG: Stopping logging thread
DEBUG: Stop event received
DEBUG: Tearing down logging thread
DEBUG: Sending SIGTERM to runqemu
Output from runqemu:
runqemu - INFO - Received signal: 15
runqemu - INFO - Cleaning up
runqemu - INFO - Host uptime: 221546.84
runqemu - INFO - Removing TOPDIR/tmp/deploy/images/qemuriscv64/core-image-ptest-python3-pefile-qemuriscv64.rootfs-20260730081147.ext4
runqemu - INFO - Removing /home/pokybuild/tmp/core-image-ptest-python3-pefile-qemuriscv64.rootfs-20260730081147.ext4.2062408
tput: No value for $TERM and no -T specified
WARNING: Extra log data read:
/srv/pokybuild/yocto-worker/qemuriscv64-ptest/build/layers/openembedded-core/meta/lib/oeqa/utils/qemurunner.py:186: ResourceWarning: unclosed <socket.socket fd=36, family=2, type=1, proto=0, laddr=('127.0.0.1', 56405), raddr=('127.0.0.1', 45658)>
return self.launch(launch_cmd, qemuparams=qemuparams, get_ip=get_ip, extra_bootparams=extra_bootparams, env=env)
ResourceWarning: Enable tracemalloc to get the object allocation traceback
DEBUG: Sending SIGTERM to runqemu
Output from runqemu:
runqemu - INFO - Received signal: 15
runqemu - INFO - Cleaning up
runqemu - INFO - Host uptime: 221546.84
runqemu - INFO - Removing TOPDIR/tmp/deploy/images/qemuriscv64/core-image-ptest-python3-pefile-qemuriscv64.rootfs-20260730081147.ext4
runqemu - INFO - Removing /home/pokybuild/tmp/core-image-ptest-python3-pefile-qemuriscv64.rootfs-20260730081147.ext4.2062408
tput: No value for $TERM and no -T specified
DEBUG: Sending SIGTERM to runqemu
Output from runqemu:
runqemu - INFO - Received signal: 15
runqemu - INFO - Cleaning up
runqemu - INFO - Host uptime: 221546.84
runqemu - INFO - Removing TOPDIR/tmp/deploy/images/qemuriscv64/core-image-ptest-python3-pefile-qemuriscv64.rootfs-20260730081147.ext4
runqemu - INFO - Removing /home/pokybuild/tmp/core-image-ptest-python3-pefile-qemuriscv64.rootfs-20260730081147.ext4.2062408
tput: No value for $TERM and no -T specified
ERROR: Error executing a python function in exec_func_python() autogenerated:
The stack trace of python calls that resulted in this exception/failure was:
File: 'exec_func_python() autogenerated', lineno: 2, function: <module>
0001:
*** 0002:do_testimage(d)
0003:
File: '/srv/pokybuild/yocto-worker/qemuriscv64-ptest/build/layers/openembedded-core/meta/classes-recipe/testimage.bbclass', lineno: 127, function: do_testimage
0123: dump-guest-memory {"paging":false,"protocol":"file:%s.img"}
0124:}
0125:
0126:python do_testimage() {
*** 0127: testimage_main(d)
0128:}
0129:
0130:addtask testimage
0131:do_testimage[nostamp] = "1"
File: '/srv/pokybuild/yocto-worker/qemuriscv64-ptest/build/layers/openembedded-core/meta/classes-recipe/testimage.bbclass', lineno: 382, function: testimage_main
0378: orig_sigterm_handler = signal.signal(signal.SIGTERM, sigterm_exception)
0379: try:
0380: # We need to check if runqemu ends unexpectedly
0381: # or if the worker send us a SIGTERM
*** 0382: tc.target.start(params=d.getVar("TEST_QEMUPARAMS"), runqemuparams=d.getVar("TEST_RUNQEMUPARAMS"))
0383: import threading
0384: try:
0385: threading.Timer(int(d.getVar("TEST_OVERALL_TIMEOUT")), handle_test_timeout, (int(d.getVar("TEST_OVERALL_TIMEOUT")),)).start()
0386: except ValueError:
File: '/srv/pokybuild/yocto-worker/qemuriscv64-ptest/build/layers/openembedded-core/meta/lib/oeqa/core/target/qemu.py', lineno: 91, function: start
0087: except (subprocess.CalledProcessError, subprocess.TimeoutExpired, FileNotFoundError) as err:
0088: msg += "Error running command: %s\n%s\n" % (blcmd, err)
0089: msg += "\n\n===== end: snippet =====\n"
0090:
*** 0091: raise RuntimeError("FAILED to start qemu - check the task log and the boot log %s" % (msg))
0092:
0093: def stop(self):
0094: self.runner.stop()
Exception: RuntimeError: FAILED to start qemu - check the task log and the boot log
===== start: snippet =====
===== end: snippet =====
===== start: snippet =====
file: TOPDIR/tmp/work/qemuriscv64-poky-linux/core-image-ptest-python3-pefile/1.0/testimage/qemu_boot_log.20260730083010
[ 4.287811] xhci_hcd 0000:00:02.0: new USB bus registered, assigned bus number 2
[ 4.289573] xhci_hcd 0000:00:02.0: Host supports USB 3.0 SuperSpeed
[ 4.357109] hub 1-0:1.0: USB hub found
[ 4.359378] hub 1-0:1.0: 4 ports detected
[ 4.366338] usb usb2: We don't know the algorithms for LPM for this host, disabling LPM.
[ 4.379054] hub 2-0:1.0: USB hub found
[ 4.380122] hub 2-0:1.0: 4 ports detected
[ 4.401688] goldfish_rtc 101000.rtc: registered as rtc0
[ 4.409431] goldfish_rtc 101000.rtc: setting system clock to 2026-07-30T08:51:23 UTC (1785401483)
[ 4.426325] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev
[ 4.441453] usbcore: registered new interface driver usbhid
[ 4.443288] usbhid: USB HID core driver
[ 4.445320] riscv-pmu-sbi: SBI PMU extension is available
[ 4.448005] riscv-pmu-sbi: 16 firmware and 18 hardware counters
[ 4.623837] usb 1-1: new high-speed USB device number 2 using xhci_hcd
[ 4.857619] input: QEMU QEMU USB Tablet as /devices/platform/soc/30000000.pci/pci0000:00/0000:00:02.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input0
[ 4.867740] hid-generic 0003:0627:0001.0001: input: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:02.0-1/input0
[ 4.995729] usb 1-2: new high-speed USB device number 3 using xhci_hcd
[ 5.176105] input: QEMU QEMU USB Keyboard as /devices/platform/soc/30000000.pci/pci0000:00/0000:00:02.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input1
[ 5.244355] hid-generic 0003:0627:0001.0002: input: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:02.0-2/input0
===== end: snippet =====
DEBUG: Python function do_testimage finished
