DEBUG: Executing python function extend_recipe_sysroot NOTE: Direct dependencies are ['/srv/pokybuild/yocto-worker/qemuarm64-ptest/build/meta/recipes-devtools/qemu/qemu-helper-native_1.0.bb:do_populate_sysroot', '/srv/pokybuild/yocto-worker/qemuarm64-ptest/build/meta/recipes-devtools/qemu/qemu-native_9.2.0.bb:do_populate_sysroot', '/srv/pokybuild/yocto-worker/qemuarm64-ptest/build/meta/recipes-devtools/qemu/qemu-system-native_9.2.0.bb:do_populate_sysroot', 'virtual:native:/srv/pokybuild/yocto-worker/qemuarm64-ptest/build/meta/recipes-devtools/createrepo-c/createrepo-c_1.2.0.bb:do_populate_sysroot', 'virtual:native:/srv/pokybuild/yocto-worker/qemuarm64-ptest/build/meta/recipes-devtools/dnf/dnf_4.22.0.bb:do_populate_sysroot', 'virtual:native:/srv/pokybuild/yocto-worker/qemuarm64-ptest/build/meta/recipes-devtools/pseudo/pseudo_git.bb:do_populate_sysroot', 'virtual:native:/srv/pokybuild/yocto-worker/qemuarm64-ptest/build/meta/recipes-devtools/unfs3/unfs3_git.bb:do_populate_sysroot'] NOTE: Installed into sysroot: [] NOTE: Skipping as already exists in sysroot: ['gettext-minimal-native', 'cmake-native', 'libtool-native', 'm4-native', 'qemu-helper-native', 'qemu-native', 'qemu-system-native', 'texinfo-dummy-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', 'chrpath-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', 'llvm-native', 'lua-native', 'make-native', 'perl-native', 'pseudo-native', 'python3-build-native', 'python3-calver-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-pip-native', 'python3-pluggy-native', 'python3-pygments-native', 'python3-pyparsing-native', 'python3-pyproject-hooks-native', 'python3-setuptools-scm-native', 'python3-setuptools-native', 'python3-trove-classifiers-native', 'python3-typing-extensions-native', 'python3-wheel-native', 'python3-native', 'rpm-native', 'swig-native', 'unfs3-native', 'bzip2-native', 'diffutils-native', 'libarchive-native', 'libidn2-native', 'libnsl2-native', 'libsolv-native', 'libtirpc-native', 'lzlib-native', 'unzip-native', 'xz-native', 'zstd-native', 'gobject-introspection-native', 'libdrm-native', 'libepoxy-native', 'libsdl2-native', 'mesa-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', 'libcheck-native', 'libffi-native', 'libgcrypt-native', 'libgpg-error-native', 'libmicrohttpd-native', 'libpcre2-native', 'libunistring-native', 'libyaml-native', 'nettle-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: Using kvm for runqemu QMP Available for connection at /srv/pokybuild/yocto-worker/qemuarm64-ptest/build/build/tmp/.i7__0c2a DEBUG: Created listening socket for qemu serial console on: 127.0.0.1:50711 DEBUG: Created listening socket for qemu serial console on: 127.0.0.1:36983 DEBUG: launchcmd=runqemu snapshot kvm qemuarm64 /srv/pokybuild/yocto-worker/qemuarm64-ptest/build/build/tmp/deploy/images/qemuarm64/core-image-ptest-libxml-namespacesupport-perl-qemuarm64.rootfs.ext4 tcpserial=50711:36983 bootparams=" printk.time=1" qemuparams="-pidfile /srv/pokybuild/yocto-worker/qemuarm64-ptest/build/build/pidfile_1454389 -S -qmp unix:./.2o3ebia4,server,wait -qmp unix:./.i7__0c2a,server,nowait" DEBUG: runqemu started, pid is 1454394 DEBUG: waiting at most 300 seconds for qemu pid (04/23/25 13:00:32) DEBUG: QMP Initializing to /srv/pokybuild/yocto-worker/qemuarm64-ptest/build/build/tmp/.2o3ebia4 /srv/pokybuild/yocto-worker/qemuarm64-ptest/build/build/tmp/work/qemuarm64-poky-linux/core-image-ptest-libxml-namespacesupport-perl/1.0/recipe-sysroot-native/usr/lib/qemu-python/qmp/legacy.py:89: DeprecationWarning: There is no current event loop self._aloop = asyncio.get_event_loop() DEBUG: QMP Connecting to /srv/pokybuild/yocto-worker/qemuarm64-ptest/build/build/tmp/.2o3ebia4 QMP connected to QEMU at 04/23/25 13:00:33 and took 0.64 seconds QMP released QEMU at 04/23/25 13:00:33 and took 0.12 seconds from connect DEBUG: qemu started in 0.75 seconds - qemu procces pid is 1454413 (04/23/25 13:00:33) DEBUG: qemu cmdline used: /srv/pokybuild/yocto-worker/qemuarm64-ptest/build/build/tmp/work/aarch64-linux/qemu-helper-native/1.0/recipe-sysroot-native/usr/bin/qemu-system-aarch64 -device virtio-net-pci,netdev=net0,mac=52:54:00:12:34:12 -netdev tap,id=net0,ifname=tap8,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-libxml-namespacesupport-perl-qemuarm64.rootfs.ext4.1454395,if=none,format=raw -device virtio-blk-pci,drive=disk0 -device qemu-xhci -device usb-tablet -device usb-kbd -machine virt -cpu host -machine gic-version=3 -smp 4 -enable-kvm -m 1024 -device virtio-serial-pci -chardev socket,id=virtcon,port=50711,host=127.0.0.1,nodelay=on -device virtconsole,chardev=virtcon -serial tcp:127.0.0.1:36983,nodelay=on -pidfile /srv/pokybuild/yocto-worker/qemuarm64-ptest/build/build/pidfile_1454389 -S -qmp unix:./.2o3ebia4,server,wait -qmp unix:./.i7__0c2a,server,nowait -serial null -serial mon:vc -display sdl,show-cursor=on -device virtio-gpu-pci -kernel /srv/pokybuild/yocto-worker/qemuarm64-ptest/build/build/tmp/deploy/images/qemuarm64/Image -append root=/dev/vda rw mem=1024M ip=192.168.7.18::192.168.7.17:255.255.255.0::eth0:off:8.8.8.8 net.ifnames=0 console=ttyAMA0 console=hvc0 swiotlb=0 printk.time=1 DEBUG: Target IP: 192.168.7.18 DEBUG: Server IP: 192.168.7.17 DEBUG: Starting logging thread DEBUG: Starting thread event loop DEBUG: Connection request received DEBUG: Setting connection established event DEBUG: Output from runqemu: runqemu - INFO - Continuing with the following parameters: KERNEL: [/srv/pokybuild/yocto-worker/qemuarm64-ptest/build/build/tmp/deploy/images/qemuarm64/Image] MACHINE: [qemuarm64] FSTYPE: [ext4] ROOTFS: [/srv/pokybuild/yocto-worker/qemuarm64-ptest/build/build/tmp/deploy/images/qemuarm64/core-image-ptest-libxml-namespacesupport-perl-qemuarm64.rootfs.ext4] CONFFILE: [/srv/pokybuild/yocto-worker/qemuarm64-ptest/build/build/tmp/deploy/images/qemuarm64/core-image-ptest-libxml-namespacesupport-perl-qemuarm64.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 - Acquiring lockfile /tmp/qemu-tap-locks/tap7.lock failed: [Errno 11] Resource temporarily unavailable runqemu - INFO - Using preconfigured tap device tap8 runqemu - INFO - If this is not intended, touch /tmp/qemu-tap-locks/tap8.skip to make runqemu skip tap8. runqemu - INFO - Network configuration: ip=192.168.7.18::192.168.7.17:255.255.255.0::eth0:off:8.8.8.8 net.ifnames=0 runqemu - INFO - Copying rootfs to /home/pokybuild/tmp/core-image-ptest-libxml-namespacesupport-perl-qemuarm64.rootfs.ext4.1454395 runqemu - INFO - Copy done in 0.1587364673614502 seconds runqemu - INFO - Running /srv/pokybuild/yocto-worker/qemuarm64-ptest/build/build/tmp/work/aarch64-linux/qemu-helper-native/1.0/recipe-sysroot-native/usr/bin/qemu-system-aarch64 -device virtio-net-pci,netdev=net0,mac=52:54:00:12:34:12 -netdev tap,id=net0,ifname=tap8,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-libxml-namespacesupport-perl-qemuarm64.rootfs.ext4.1454395,if=none,format=raw -device virtio-blk-pci,drive=disk0 -device qemu-xhci -device usb-tablet -device usb-kbd -machine virt -cpu host -machine gic-version=3 -smp 4 -enable-kvm -m 1024 -device virtio-serial-pci -chardev socket,id=virtcon,port=50711,host=127.0.0.1,nodelay=on -device virtconsole,chardev=virtcon -serial tcp:127.0.0.1:36983,nodelay=on -pidfile /srv/pokybuild/yocto-worker/qemuarm64-ptest/build/build/pidfile_1454389 -S -qmp unix:./.2o3ebia4,server,wait -qmp unix:./.i7__0c2a,server,nowait -serial null -serial mon:vc -display sdl,show-cursor=on -device virtio-gpu-pci -kernel /srv/pokybuild/yocto-worker/qemuarm64-ptest/build/build/tmp/deploy/images/qemuarm64/Image -append 'root=/dev/vda rw mem=1024M ip=192.168.7.18::192.168.7.17:255.255.255.0::eth0:off:8.8.8.8 net.ifnames=0 console=ttyAMA0 console=hvc0 swiotlb=0 printk.time=1' runqemu - INFO - Host uptime: 766000.37 DEBUG: Waiting at most 1500 seconds for login banner (04/23/25 13:00:33) DEBUG: Connection from 127.0.0.1:52768 DEBUG: Partial boot log: [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] [ 0.000000] Linux version 6.12.23-yocto-standard (oe-user@oe-host) (aarch64-poky-linux-gcc (GCC) 14.2.0, GNU ld (GNU Binutils) 2.44) #1 SMP PREEMPT Mon Apr 14 02:03:29 UTC 2025 [ 0.000000] random: crng init done [ 0.000000] Machine model: linux,dummy-virt [ 0.000000] Memory limited to 1024MB [ 0.000000] efi: UEFI not found. [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff] [ 0.000000] DMA32 empty [ 0.000000] Normal empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff] [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff] [ 0.000000] psci: probing for conduit method from DT. [ 0.000000] psci: PSCIv1.1 detected in firmware. [ DEBUG: Partial boot log: 0.000000] psci: Using standard PSCI v0.2 function IDs [ 0.000000] psci: Trusted OS migration not required [ 0.000000] psci: SMC Calling Convention v1.1 [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) [ 0.000000] percpu: Embedded 28 pages/cpu s76760 r8192 d29736 u114688 [ 0.000000] Detected PIPT I-cache on CPU0 [ 0.000000] CPU features: detected: GIC system register CPU interface [ 0.000000] CPU features: detected: Spectre-v4 [ 0.000000] CPU features: detected: Spectre-BHB [ 0.000000] CPU features: detected: ARM erratum 1418040 [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing [ 0.000000] alternatives: applying boot alternatives [ 0.000000] Kernel command line: root=/dev/vda rw mem=1024M ip=192.168.7.18::192.168.7.17:255.255.255.0::eth0:off:8.8.8.8 net.ifnames=0 console=ttyAMA0 console=hvc0 swiotlb=0 printk.time=1 [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 DEBUG: Partial boot log: 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] software IO TLB: area num 4. [ 0.000000] software IO TLB: SWIOTLB bounce buffer size roundup to 1MB [ 0.000000] software IO TLB: mapped [mem 0x000000007e880000-0x000000007e980000] (1MB) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] ftrace: allocating 42604 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=512 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. DEBUG: Partial boot log: [ 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 [ 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] GICv3: 256 SPIs implemented [ 0.000000] GICv3: 0 Extended SPIs implemented [ 0.000000] Root IRQ handler: gic_handle_irq [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI [ 0.000000] GICv3: GICD_CTRL.DS=1, SCR_EL3.FIQ=0 [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 [ 0.000000] ITS [mem 0x08080000-0x0809ffff] [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @419d0000 (indirect, esz 8, psz 64K, shr DEBUG: Partial boot log: 1) [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @419e0000 (flat, esz 8, psz 64K, shr 1) [ 0.000000] GICv3: using LPI property table @0x00000000419f0000 [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000041a00000 [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040 [ 0.000000] arch_timer: cp15 timer(s) running at 25.00MHz (virt). [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns [ 0.000181] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) [ 0.000217] Console: colour dummy device 80x25 [ 0.000224] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000) [ 0.000226] pid_max: DEBUG: Partial boot log: default: 32768 minimum: 301 [ 0.000248] LSM: initializing lsm=capability,landlock [ 0.000258] landlock: Up and running. [ 0.000281] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) [ 0.000284] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) [ 0.000507] cacheinfo: Unable to detect cache hierarchy for CPU 0 [ 0.012117] rcu: Hierarchical SRCU implementation. [ 0.012120] rcu: Max phase no-delay instances is 1000. [ 0.016224] EFI services will not be available. [ 0.020156] smp: Bringing up secondary CPUs ... [ 0.034378] Detected PIPT I-cache on CPU1 [ 0.034441] GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 [ 0.034496] GICv3: CPU1: using allocated LPI pending table @0x0000000041a10000 [ 0.034527] arch_timer: Enabling local workaround for ARM erratum 1418040 [ 0.034566] CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] [ 0.042403] Detected PIPT I-cache on CPU2 [ 0.042467] GICv3: CP DEBUG: Partial boot log: U2: found redistributor 2 region 0:0x00000000080e0000 [ 0.042523] GICv3: CPU2: using allocated LPI pending table @0x0000000041a20000 [ 0.042558] arch_timer: Enabling local workaround for ARM erratum 1418040 [ 0.042590] CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] [ 0.054459] Detected PIPT I-cache on CPU3 [ 0.054528] GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 [ 0.054594] GICv3: CPU3: using allocated LPI pending table @0x0000000041a30000 [ 0.054623] arch_timer: Enabling local workaround for ARM erratum 1418040 [ 0.054654] CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] [ 0.054844] smp: Brought up 1 node, 4 CPUs [ 0.054851] SMP: Total of 4 processors activated. [ 0.054852] CPU: All CPU(s) started at EL1 [ 0.054855] CPU features: detected: 32-bit EL0 Support [ 0.054857] CPU features: detected: Data cache clean to the PoU not required for I/D coherence [ 0.054859] CPU features: detected: Common not Private translat DEBUG: Partial boot log: ions [ 0.054861] CPU features: detected: CRC32 instructions [ 0.054863] CPU features: detected: Enhanced Virtualization Traps [ 0.054866] CPU features: detected: RCpc load-acquire (LDAPR) [ 0.054868] CPU features: detected: LSE atomic instructions [ 0.054870] CPU features: detected: Privileged Access Never [ 0.054872] CPU features: detected: RAS Extension Support [ 0.054875] CPU features: detected: Speculative Store Bypassing Safe (SSBS) [ 0.054958] alternatives: applying system-wide alternatives [ 0.056280] CPU features: detected: Hardware dirty bit management on CPU0-3 [ 0.056753] Memory: 995168K/1048576K available (14016K kernel code, 2104K rwdata, 3412K rodata, 1728K init, 711K bss, 47008K reserved, 0K cma-reserved) [ 0.056983] devtmpfs: initialized [ 0.057876] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns [ 0.057883] futex hash table entries: 1024 (order: 4, 65536 bytes, linear) [ 0.057912] 27232 pag DEBUG: Partial boot log: es in range for non-PLT usage [ 0.057912] 518752 pages in range for PLT usage [ 0.057959] DMI not present or invalid. [ 0.059171] NET: Registered PF_NETLINK/PF_ROUTE protocol family [ 0.059312] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations [ 0.059456] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.059462] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.059547] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. [ 0.059627] ASID allocator initialised with 65536 entries [ 0.059637] Serial: AMBA PL011 UART driver [ 0.060473] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 [ 0.060501] printk: legacy console [ttyAMA0] enabled [ 0.126844] 9040000.pl011: ttyAMA1 at MMIO 0x9040000 (irq = 14, base_baud = 0) is a PL011 rev1 [ 0.196156] raid6: neonx8 gen() 15791 MB/s [ 0.264201] raid6: neonx4 gen() 15829 MB/s [ 0.332249] raid6: neonx2 DEBUG: Partial boot log: gen() 13233 MB/s [ 0.400292] raid6: neonx1 gen() 10444 MB/s [ 0.468338] raid6: int64x8 gen() 6906 MB/s [ 0.536385] raid6: int64x4 gen() 7334 MB/s [ 0.609818] raid6: int64x2 gen() 6116 MB/s [ 0.677862] raid6: int64x1 gen() 5051 MB/s [ 0.678706] raid6: using algorithm neonx4 gen() 15829 MB/s [ 0.745907] raid6: .... xor() 12111 MB/s, rmw enabled [ 0.746921] raid6: using neon recovery algorithm [ 0.747860] iommu: Default domain type: Translated [ 0.748765] iommu: DMA domain TLB invalidation policy: strict mode [ 0.750157] SCSI subsystem initialized [ 0.750704] usbcore: registered new interface driver usbfs [ 0.751181] usbcore: registered new interface driver hub [ 0.751670] usbcore: registered new device driver usb [ 0.752114] pps_core: LinuxPPS API ver. 1 registered [ 0.752556] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.753325] PTP clock support registered [ 0.754316] vgaarb: loa DEBUG: Partial boot log: ded [ 0.754650] clocksource: Switched to clocksource arch_sys_counter [ 0.757339] NET: Registered PF_INET protocol family [ 0.757833] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) [ 0.758783] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) [ 0.759550] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) [ 0.760196] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) [ 0.760854] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) [ 0.761544] TCP: Hash tables configured (established 8192 bind 8192) [ 0.762090] UDP hash table entries: 512 (order: 2, 16384 bytes, linear) [ 0.762677] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear) [ 0.763300] NET: Registered PF_UNIX/PF_LOCAL protocol family [ 0.763973] RPC: Registered named UNIX socket transport module. [ 0.764469] RPC: Registered udp transport module. [ 0.764863] RPC: Registered tcp DEBUG: Partial boot log: transport module. [ 0.765250] RPC: Registered tcp-with-tls transport module. [ 0.765727] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.766262] PCI: CLS 0 bytes, default 64 [ 0.767048] workingset: timestamp_bits=46 max_order=18 bucket_order=0 [ 0.767795] NFS: Registering the id_resolver key type [ 0.768236] Key type id_resolver registered [ 0.768597] Key type id_legacy registered [ 0.769460] Key type cifs.idmap registered [ 0.780522] xor: measuring software checksum speed [ 0.781891] 8regs : 16177 MB/sec [ 0.782573] 32regs : 16553 MB/sec [ 0.783239] arm64_neon : 21913 MB/sec [ 0.783721] xor: using function: arm64_neon (21913 MB/sec) [ 0.784342] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) [ 0.785271] io scheduler mq-deadline registered [ 0.785658] io scheduler kyber registered [ 0.786254] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: [ 0.787548 DEBUG: Partial boot log: ] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 [ 0.789075] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 [ 0.797229] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 [ 0.798798] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits [ 0.800172] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] [ 0.801305] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 [ 0.801896] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.802343] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] [ 0.802898] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] [ 0.803472] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] [ 0.804094] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint [ 0.804986] pci 0000:00:01.0: [1af4:1000] type 00 class DEBUG: Partial boot log: 0x020000 conventional PCI endpoint [ 0.805707] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] [ 0.806153] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] [ 0.806704] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] [ 0.807286] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] [ 0.808052] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint [ 0.808795] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] [ 0.809238] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] [ 0.809775] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] [ 0.810659] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint [ 0.811379] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f] [ 0.811838] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] [ 0.812364] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] [ 0.813155] pci 0000:00:04.0: [1b36:000d] type 00 class 0x0c0330 PCIe Root Complex Integrated Endpoint DEBUG: Partial boot log: [ 0.813956] pci 0000:00:04.0: BAR 0 [mem 0x00000000-0x00003fff 64bit] [ 0.814577] pci 0000:00:04.0: enabling Extended Tags [ 0.815221] pci 0000:00:05.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint [ 0.816016] pci 0000:00:05.0: BAR 0 [io 0x0000-0x003f] [ 0.816466] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] [ 0.817012] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] [ 0.817832] pci 0000:00:06.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint [ 0.818556] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] [ 0.819137] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] [ 0.820052] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned [ 0.820662] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned [ 0.821353] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned [ 0.822042] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref DEBUG: Partial boot log: ]: assigned [ 0.822755] pci 0000:00:04.0: BAR 0 [mem 0x800000c000-0x800000ffff 64bit]: assigned [ 0.823390] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned [ 0.824380] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned [ 0.825091] pci 0000:00:01.0: BAR 1 [mem 0x10040000-0x10040fff]: assigned [ 0.825658] pci 0000:00:02.0: BAR 1 [mem 0x10041000-0x10041fff]: assigned [ 0.826217] pci 0000:00:03.0: BAR 1 [mem 0x10042000-0x10042fff]: assigned [ 0.826826] pci 0000:00:05.0: BAR 1 [mem 0x10043000-0x10043fff]: assigned [ 0.827379] pci 0000:00:06.0: BAR 1 [mem 0x10044000-0x10044fff]: assigned [ 0.828605] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned [ 0.829691] pci 0000:00:05.0: BAR 0 [io 0x1080-0x10bf]: assigned [ 0.830804] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned [ 0.831795] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned [ 0.832888] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] [ DEBUG: Partial boot log: 0.833759] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] [ 0.834900] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] [ 0.835972] pci 0000:00:04.0: enabling device (0000 -> 0002) [ 0.838072] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) [ 0.840880] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) [ 0.845846] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) [ 0.849550] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003) [ 0.852352] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002) [ 0.888763] [drm] pci: virtio-gpu-pci detected at 0000:00:06.0 [ 0.890028] printk: legacy console [hvc0] enabled [ 0.890140] [drm] features: -virgl +edid -resource_blob -host_visible [ 0.905085] [drm] features: -context_init [ 0.907416] [drm] number of scanouts: 1 [ 0.908189] [drm] number of cap sets: 0 [ 0.909376] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:06.0 on minor 0 [ 0.918172] Console: switching to colour fr DEBUG: Partial boot log: ame buffer device 160x50 [ 0.951935] virtio-pci 0000:00:06.0: [drm] fb0: virtio_gpudrmfb frame buffer device [ 0.963905] brd: module loaded [ 0.965987] virtio_blk virtio2: 4/0/0 default/read/poll queues [ 0.969793] virtio_blk virtio2: [vda] 717088 512-byte logical blocks (367 MB/350 MiB) [ 0.975208] xhci_hcd 0000:00:04.0: xHCI Host Controller [ 0.976481] xhci_hcd 0000:00:04.0: new USB bus registered, assigned bus number 1 [ 0.978196] xhci_hcd 0000:00:04.0: hcc params 0x00087001 hci version 0x100 quirks 0x0000000000000010 [ 0.980835] xhci_hcd 0000:00:04.0: xHCI Host Controller [ 0.982089] xhci_hcd 0000:00:04.0: new USB bus registered, assigned bus number 2 [ 0.983809] xhci_hcd 0000:00:04.0: Host supports USB 3.0 SuperSpeed [ 0.985384] hub 1-0:1.0: USB hub found [ 0.986209] hub 1-0:1.0: 4 ports detected [ 0.987357] usb usb2: We don't know the algorithms for LPM for this host, disabling LPM. [ 0.989263] hub 2-0:1.0: USB hub found [ 0.990115] hub 2-0:1.0 DEBUG: Partial boot log: : 4 ports detected [ 0.991422] rtc-pl031 9010000.pl031: registered as rtc0 [ 0.992639] rtc-pl031 9010000.pl031: setting system clock to 2025-04-23T13:00:34 UTC (1745413234) [ 0.994773] device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev [ 0.996935] usbcore: registered new interface driver usbhid [ 0.998122] usbhid: USB HID core driver [ 0.999479] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available [ 1.001339] u32 classifier [ 1.001942] input device check on [ 1.002780] Actions configured [ 1.003657] NET: Registered PF_INET6 protocol family [ 1.004945] Segment Routing with IPv6 [ 1.005714] In-situ OAM (IOAM) with IPv6 [ 1.006618] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver [ 1.008044] NET: Registered PF_PACKET protocol family [ 1.009204] Bridge firewalling registered [ 1.010068] l2tp_core: L2TP core driver, V2.0 [ 1.011461] Key type dns_resolver registered [ DEBUG: Partial boot log: 1.012473] NET: Registered PF_VSOCK protocol family [ 1.014876] registered taskstats version 1 [ 1.017606] Key type .fscrypt registered [ 1.020099] Key type fscrypt-provisioning registered [ 1.024425] Btrfs loaded, zoned=no, fsverity=no [ 1.031129] Key type encrypted registered [ 1.033778] printk: legacy console [netcon0] enabled [ 1.035749] netconsole: network logging started [ 1.054703] IP-Config: Complete: [ 1.056945] device=eth0, hwaddr=52:54:00:12:34:12, ipaddr=192.168.7.18, mask=255.255.255.0, gw=192.168.7.17 [ 1.061260] host=192.168.7.18, domain=, nis-domain=(none) [ 1.064010] bootserver=255.255.255.255, rootserver=255.255.255.255, rootpath= [ 1.064017] nameserver0=8.8.8.8 [ 1.068403] clk: Disabling unused clocks [ 1.070245] PM: genpd: Disabling unused power domains [ 1.071637] md: Waiting for all devices to be available before autodetect [ 1.073756] md: If you don't use raid, use raid=noautodetect [ 1.076625] md: DEBUG: Partial boot log: Autodetecting RAID arrays. [ 1.078557] md: autorun ... [ 1.079861] md: ... autorun DONE. [ 1.087881] EXT4-fs (vda): mounted filesystem dde4d0cf-a1cb-4991-80e1-a33bbb49c7d1 r/w with ordered data mode. Quota mode: disabled. [ 1.092147] VFS: Mounted root (ext4 filesystem) on device 253:0. [ 1.093409] devtmpfs: mounted [ 1.094493] Freeing unused kernel memory: 1728K [ 1.095599] Run /sbin/init as init process [ 1.157368] udevd[123]: starting version 3.2.14 [ 1.163162] udevd[124]: starting eudev-3.2.14 [ 1.242718] usb 1-1: new high-speed USB device number 2 using xhci_hcd [ 1.389053] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:04.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input0 [ 1.392580] hid-generic 0003:0627:0001.0001: input: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:04.0-1/input0 [ 1.522701] usb 1-2: new high-speed USB device number 3 using xhci_hcd [ 1.673456] input: QEMU QEMU USB Keyboard as /dev DEBUG: Partial boot log: ices/platform/4010000000.pcie/pci0000:00/0000:00:04.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input1 [ 1.734803] hid-generic 0003:0627:0001.0002: input: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:04.0-2/input0 [ 1.862727] EXT4-fs (vda): re-mounted dde4d0cf-a1cb-4991-80e1-a33bbb49c7d1 r/w. Quota mode: disabled. INIT: Entering runlevel: 5 Configuring network interfaces... ip: RTNETLINK answers: File exists Starting OpenBSD Secure Shell server: sshd generating ssh ECDSA host key... done. Starting syslogd/klogd: done Poky (Yocto Project Reference Distro) 5.2 qemuarm64 /dev/ttyAMA0 qemuarm64 login: DEBUG: Reached login banner in 2.39 seconds (04/23/25 13:00:35) DEBUG: Logged in as root in serial console NOTE: test_get_context (parselogs.ParseLogsTest.test_get_context) NOTE: ... ok NOTE: test_ping (ping.PingTest.test_ping) DEBUG: Setting up a 30 second(s) timeout DEBUG: Removed SIGALRM handler NOTE: ... ok NOTE: test_ssh (ssh.SSHTest.test_ssh) DEBUG: Checking if at least one of openssh-sshd, dropbear is installed DEBUG: [Running]$ ssh -l root -o ServerAliveCountMax=2 -o ServerAliveInterval=30 -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR 192.168.7.18 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; uname -a DEBUG: Data from SSH call: Linux qemuarm64 6.12.23-yocto-standard #1 SMP PREEMPT Mon Apr 14 02:03:29 UTC 2025 aarch64 GNU/Linux DEBUG: [Command returned '0' after 0.57 seconds] DEBUG: Command: uname -a Status: 0 Output: Linux qemuarm64 6.12.23-yocto-standard #1 SMP PREEMPT Mon Apr 14 02:03:29 UTC 2025 aarch64 GNU/Linux NOTE: ... ok NOTE: test_parselogs (parselogs.ParseLogsTest.test_parselogs) DEBUG: [Running]$ ssh -l root -o ServerAliveCountMax=2 -o ServerAliveInterval=30 -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR 192.168.7.18 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; dmesg > /tmp/dmesg_output.log DEBUG: No output from SSH call DEBUG: [Command returned '0' after 0.38 seconds] DEBUG: Command: dmesg > /tmp/dmesg_output.log Status: 0 Output: DEBUG: [Running]$ ssh -l root -o ServerAliveCountMax=2 -o ServerAliveInterval=30 -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR 192.168.7.18 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; test -f /var/log/ DEBUG: No output from SSH call DEBUG: [Command returned '1' after 0.38 seconds] DEBUG: Command: test -f /var/log/ Status: 1 Output: DEBUG: [Running]$ ssh -l root -o ServerAliveCountMax=2 -o ServerAliveInterval=30 -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR 192.168.7.18 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; test -d /var/log/ DEBUG: No output from SSH call DEBUG: [Command returned '0' after 0.38 seconds] DEBUG: Command: test -d /var/log/ Status: 0 Output: DEBUG: [Running]$ ssh -l root -o ServerAliveCountMax=2 -o ServerAliveInterval=30 -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR 192.168.7.18 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; find /var/log/ -name \*.log -maxdepth 1 -type f DEBUG: No output from SSH call DEBUG: [Command returned '0' after 0.37 seconds] DEBUG: Command: find /var/log/ -name \*.log -maxdepth 1 -type f Status: 0 Output: DEBUG: [Running]$ ssh -l root -o ServerAliveCountMax=2 -o ServerAliveInterval=30 -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR 192.168.7.18 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; test -f /var/log/dmesg DEBUG: No output from SSH call DEBUG: [Command returned '0' after 0.38 seconds] DEBUG: Command: test -f /var/log/dmesg Status: 0 Output: DEBUG: [Running]$ ssh -l root -o ServerAliveCountMax=2 -o ServerAliveInterval=30 -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR 192.168.7.18 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; test -f /tmp/dmesg_output.log DEBUG: No output from SSH call DEBUG: [Command returned '0' after 0.38 seconds] DEBUG: Command: test -f /tmp/dmesg_output.log Status: 0 Output: DEBUG: [Running]$ scp -o ServerAliveCountMax=2 -o ServerAliveInterval=30 -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR -r root@192.168.7.18:/var/log/dmesg /srv/pokybuild/yocto-worker/qemuarm64-ptest/build/build/tmp/work/qemuarm64-poky-linux/core-image-ptest-libxml-namespacesupport-perl/1.0/target_logs DEBUG: No output from SSH call DEBUG: [Command returned '0' after 0.18 seconds] DEBUG: [Running]$ scp -o ServerAliveCountMax=2 -o ServerAliveInterval=30 -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR -r root@192.168.7.18:/tmp/dmesg_output.log /srv/pokybuild/yocto-worker/qemuarm64-ptest/build/build/tmp/work/qemuarm64-poky-linux/core-image-ptest-libxml-namespacesupport-perl/1.0/target_logs DEBUG: No output from SSH call DEBUG: [Command returned '0' after 0.19 seconds] NOTE: ... ok NOTE: test_ptestrunner_expectfail (ptest.PtestRunnerTest.test_ptestrunner_expectfail) DEBUG: Checking if at least one of ptest-runner is installed DEBUG: Checking if ptest is in DISTRO_FEATURES or IMAGE_FEATURES DEBUG: [Running]$ ssh -l root -o ServerAliveCountMax=2 -o ServerAliveInterval=30 -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR 192.168.7.18 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; which ptest-runner DEBUG: Data from SSH call: /usr/bin/ptest-runner DEBUG: [Command returned '0' after 0.17 seconds] DEBUG: Command: which ptest-runner Status: 0 Output: /usr/bin/ptest-runner DEBUG: [Running]$ ssh -l root -o ServerAliveCountMax=2 -o ServerAliveInterval=30 -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR 192.168.7.18 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; ptest-runner -t 450 -d "/usr/lib" DEBUG: Data from SSH call: START: ptest-runner 2025-04-23T13:00 BEGIN: /usr/lib/libxml-namespacesupport-perl/ptest 1..49 ok 1 ok 2 - xml xmlns ok 3 ok 4 - http://www.w3.org/2000/xmlns/ ok 5 ok 6 - no declared_prefixes ok 7 - xml xmlns ok 8 ok 9 ok 10 ok 11 ok 12 - icl icl2 ok 13 - icl icl2 xml xmlns ok 14 - icl icl2 ok 15 ok 16 - http://www.icl.com ok 17 - http://www.icl.com el1 icl:el1 ok 18 - http://www.icl.com icl el1 ok 19 ok 20 - http://www.ibm.com undef el1 ok 21 - http://www.w3.org/XML/1998/namespace xml el1 ok 22 ok 23 - http://www.icl.com icl att1 ok 24 ok 25 - undef undef att1 ok 26 - http://www.w3.org/XML/1998/namespace xml att1 ok 27 ok 28 ok 29 ok 30 ok 31 - undef undef undef ok 32 - icl icl2 ok 33 - perl ok 34 - http://www.icl.com icl el1 ok 35 ok 36 - http://www.java.com undef el1 ok 37 - http://www.perl.com perl el1 ok 38 - http://www.icl.com icl att1 ok 39 ok 40 - undef undef att1 ok 41 - http://www.perl.com perl att1 ok 42 ok 43 - icl icl2 ok 44 ok 45 - xml xmlns ok 46 ok 47 - xml xmlns ok 48 ok 49 - get_declared_prefixes raises error without context on stack PASS: t/00base DURATION: 0 END: /usr/lib/libxml-namespacesupport-perl/ptest 2025-04-23T13:00 STOP: ptest-runner TOTAL: 1 FAIL: 0 DEBUG: [Command returned '0' after 0.27 seconds] DEBUG: Command: ptest-runner -t 450 -d "/usr/lib" Status: 0 Output: START: ptest-runner 2025-04-23T13:00 BEGIN: /usr/lib/libxml-namespacesupport-perl/ptest 1..49 ok 1 ok 2 - xml xmlns ok 3 ok 4 - http://www.w3.org/2000/xmlns/ ok 5 ok 6 - no declared_prefixes ok 7 - xml xmlns ok 8 ok 9 ok 10 ok 11 ok 12 - icl icl2 ok 13 - icl icl2 xml xmlns ok 14 - icl icl2 ok 15 ok 16 - http://www.icl.com ok 17 - http://www.icl.com el1 icl:el1 ok 18 - http://www.icl.com icl el1 ok 19 ok 20 - http://www.ibm.com undef el1 ok 21 - http://www.w3.org/XML/1998/namespace xml el1 ok 22 ok 23 - http://www.icl.com icl att1 ok 24 ok 25 - undef undef att1 ok 26 - http://www.w3.org/XML/1998/namespace xml att1 ok 27 ok 28 ok 29 ok 30 ok 31 - undef undef undef ok 32 - icl icl2 ok 33 - perl ok 34 - http://www.icl.com icl el1 ok 35 ok 36 - http://www.java.com undef el1 ok 37 - http://www.perl.com perl el1 ok 38 - http://www.icl.com icl att1 ok 39 ok 40 - undef undef att1 ok 41 - http://www.perl.com perl att1 ok 42 ok 43 - icl icl2 ok 44 ok 45 - xml xmlns ok 46 ok 47 - xml xmlns ok 48 ok 49 - get_declared_prefixes raises error without context on stack PASS: t/00base DURATION: 0 END: /usr/lib/libxml-namespacesupport-perl/ptest 2025-04-23T13:00 STOP: ptest-runner TOTAL: 1 FAIL: 0 DEBUG: [Running]$ ssh -l root -o ServerAliveCountMax=2 -o ServerAliveInterval=30 -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR 192.168.7.18 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; dmesg | grep "Killed process" DEBUG: No output from SSH call DEBUG: [Command returned '1' after 0.17 seconds] DEBUG: Command: dmesg | grep "Killed process" Status: 1 Output: NOTE: ... unexpected success NOTE: test_ptestrunner_expectsuccess (ptest.PtestRunnerTest.test_ptestrunner_expectsuccess) DEBUG: Checking if at least one of ptest-runner is installed DEBUG: Checking if ptest is in DISTRO_FEATURES or IMAGE_FEATURES NOTE: ... skipped 'Cannot run ptests without @expectedFailure as ptests are expected to fail' Cannot run ptests without @expectedFailure as ptests are expected to fail NOTE: ====================================================================== NOTE: UNEXPECTED SUCCESS: test_ptestrunner_expectfail (ptest.PtestRunnerTest.test_ptestrunner_expectfail) NOTE: ---------------------------------------------------------------------- NOTE: Ran 6 tests in 3.901s NOTE: OK NOTE: (skipped=1, unexpected successes=1) 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: 766015.29 runqemu - INFO - Removing /home/pokybuild/tmp/core-image-ptest-libxml-namespacesupport-perl-qemuarm64.rootfs.ext4.1454395 tput: No value for $TERM and no -T specified RESULTS: RESULTS - parselogs.ParseLogsTest.test_get_context: PASSED (0.00s) RESULTS - parselogs.ParseLogsTest.test_parselogs: PASSED (2.66s) RESULTS - ping.PingTest.test_ping: PASSED (0.03s) RESULTS - ptest.PtestRunnerTest.test_ptestrunner_expectfail: PASSED (0.62s) RESULTS - ssh.SSHTest.test_ssh: PASSED (0.58s) RESULTS - ptest.PtestRunnerTest.test_ptestrunner_expectsuccess: SKIPPED (0.00s) SUMMARY: core-image-ptest-libxml-namespacesupport-perl () - Ran 6 tests in 3.902s core-image-ptest-libxml-namespacesupport-perl - OK - All required tests passed (successes=4, skipped=1, failures=0, errors=0) DEBUG: Python function do_testimage finished