DEBUG: Executing python function extend_recipe_sysroot NOTE: Direct dependencies are ['/home/pokybuild/yocto-worker/qemux86-64-ptest-fast/build/meta/recipes-devtools/qemu/qemu-helper-native_1.0.bb:do_populate_sysroot', '/home/pokybuild/yocto-worker/qemux86-64-ptest-fast/build/meta/recipes-devtools/qemu/qemu-native_7.2.0.bb:do_populate_sysroot', '/home/pokybuild/yocto-worker/qemux86-64-ptest-fast/build/meta/recipes-devtools/qemu/qemu-system-native_7.2.0.bb:do_populate_sysroot', 'virtual:native:/home/pokybuild/yocto-worker/qemux86-64-ptest-fast/build/meta/recipes-devtools/createrepo-c/createrepo-c_0.20.1.bb:do_populate_sysroot', 'virtual:native:/home/pokybuild/yocto-worker/qemux86-64-ptest-fast/build/meta/recipes-devtools/dnf/dnf_4.14.0.bb:do_populate_sysroot', 'virtual:native:/home/pokybuild/yocto-worker/qemux86-64-ptest-fast/build/meta/recipes-devtools/pseudo/pseudo_git.bb:do_populate_sysroot', 'virtual:native:/home/pokybuild/yocto-worker/qemux86-64-ptest-fast/build/meta/recipes-devtools/unfs3/unfs3_git.bb:do_populate_sysroot', 'virtual:native:/home/pokybuild/yocto-worker/qemux86-64-ptest-fast/build/meta/recipes-extended/cpio/cpio_2.13.bb:do_populate_sysroot'] NOTE: Installed into sysroot: ['cpio-native'] NOTE: Skipping as already exists in sysroot: ['qemu-helper-native', 'qemu-native', 'qemu-system-native', 'createrepo-c-native', 'dnf-native', 'pseudo-native', 'unfs3-native', 'python3-native', 'libcomps-native', 'python3-iniparse-native', 'libdnf-native', 'cmake-native', 'gettext-minimal-native', 'librepo-native', 'zlib-native', 'file-native', 'expat-native', 'xz-native', 'sqlite3-native', 'curl-native', 'openssl-native', 'libxml2-native', 'glib-2.0-native', 'rpm-native', 'libtool-native', 'flex-native', 'libtirpc-native', 'texinfo-dummy-native', 'dtc-native', 'virglrenderer-native', 'alsa-lib-native', 'pixman-native', 'libepoxy-native', 'libsdl2-native', 'libslirp-native', 'libffi-native', 'readline-native', 'gdbm-native', 'ncurses-native', 'bzip2-native', 'util-linux-libuuid-native', 'libnsl2-native', 'libcheck-native', 'python3-installer-native', 'python3-six-native', 'python3-build-native', 'python3-setuptools-native', 'python3-wheel-native', 'swig-native', 'json-c-native', 'gobject-introspection-native', 'libmodulemd-native', 'libsolv-native', 'util-linux-native', 'zstd-native', 'attr-native', 'gpgme-native', 'perl-native', 'gettext-native', 'libpcre2-native', 'lua-native', 'libgcrypt-native', 'elfutils-native', 'popt-native', 'm4-native', 'mesa-native', 'libdrm-native', 'libx11-native', 'libxrender-native', 'libxext-native', 'libxrandr-native', 'python3-flit-core-native', 'python3-pyproject-hooks-native', 'python3-packaging-native', 'libyaml-native', 'libcap-ng-native', 'libgpg-error-native', 'libassuan-native', 'make-native', 'libcap-native', 'libmicrohttpd-native', 'libarchive-native', 'gnu-config-native', 'libxfixes-native', 'python3-mako-native', 'xrandr-native', 'wayland-native', 'libxxf86vm-native', 'xorgproto-native', 'libxshmfence-native', 'llvm-native', 'makedepend-native', 'wayland-protocols-native', 'chrpath-native', 'libxdamage-native', 'libpciaccess-native', 'libpthread-stubs-native', 'xtrans-native', 'util-macros-native', 'libxcb-native', 'unzip-native', 'gnutls-native', 'python3-markupsafe-native', 'python3-pygments-native', 'libedit-native', 'libxdmcp-native', 'xcb-proto-native', 'libxau-native', 'gmp-native', 'libidn2-native', 'libunistring-native', 'nettle-native'] DEBUG: Python function extend_recipe_sysroot finished DEBUG: Executing python function do_testimage DEBUG: test suites: test_ping (ping.PingTest) test_ssh (ssh.SSHTest) test_parselogs (parselogs.ParseLogsTest) test_ptestrunner_expectfail (ptest.PtestRunnerTest) test_ptestrunner_expectsuccess (ptest.PtestRunnerTest) DEBUG: Using kvm for runqemu QMP Available for connection at /home/pokybuild/yocto-worker/qemux86-64-ptest-fast/build/build/tmp/.47ggqr8e DEBUG: Created listening socket for qemu serial console on: 127.0.0.1:46051 DEBUG: Created listening socket for qemu serial console on: 127.0.0.1:47701 DEBUG: launchcmd=runqemu snapshot kvm qemux86-64 /home/pokybuild/yocto-worker/qemux86-64-ptest-fast/build/build/tmp/deploy/images/qemux86-64/core-image-ptest-libxml2-qemux86-64.ext4 tcpserial=46051:47701 bootparams=" printk.time=1" qemuparams="-pidfile /home/pokybuild/yocto-worker/qemux86-64-ptest-fast/build/build/pidfile_589195 -S -qmp unix:./.0z76ejn_,server,wait -qmp unix:./.47ggqr8e,server,nowait" DEBUG: runqemu started, pid is 589298 DEBUG: waiting at most 300 seconds for qemu pid (04/28/23 02:57:38) DEBUG: QMP Initializing to /home/pokybuild/yocto-worker/qemux86-64-ptest-fast/build/build/tmp/.0z76ejn_ DEBUG: QMP Connecting to /home/pokybuild/yocto-worker/qemux86-64-ptest-fast/build/build/tmp/.0z76ejn_ QMP connected to QEMU at 04/28/23 02:57:39 and took 1.11 seconds QMP released QEMU at 04/28/23 02:57:39 and took 0.16 seconds from connect DEBUG: qemu started in 1.27 seconds - qemu procces pid is 589640 (04/28/23 02:57:39) DEBUG: qemu cmdline used: /home/pokybuild/yocto-worker/qemux86-64-ptest-fast/build/build/tmp/work/x86_64-linux/qemu-helper-native/1.0-r1/recipe-sysroot-native/usr/bin/qemu-system-x86_64 -device virtio-net-pci,netdev=net0,mac=52:54:00:12:34:20 -netdev tap,id=net0,ifname=tap15,script=no,downscript=no -object rng-random,filename=/dev/urandom,id=rng0 -device virtio-rng-pci,rng=rng0 -drive file=/home/pokybuild/tmp/core-image-ptest-libxml2-qemux86-64.ext4.589300,if=virtio,format=raw -usb -device usb-tablet -usb -device usb-kbd -cpu IvyBridge -machine q35,i8042=off -smp 4 -enable-kvm -m 1024 -serial tcp:127.0.0.1:46051 -serial tcp:127.0.0.1:47701 -pidfile /home/pokybuild/yocto-worker/qemux86-64-ptest-fast/build/build/pidfile_589195 -S -qmp unix:./.0z76ejn_,server,wait -qmp unix:./.47ggqr8e,server,nowait -serial mon:vc -device virtio-vga -display sdl,show-cursor=on -kernel /home/pokybuild/yocto-worker/qemux86-64-ptest-fast/build/build/tmp/deploy/images/qemux86-64/bzImage -append root=/dev/vda rw ip=192.168.7.32::192.168.7.31:255.255.255.0::eth0:off:8.8.8.8 console=ttyS0 console=ttyS1 oprofile.timer=1 tsc=reliable no_timer_check rcupdate.rcu_expedited=1 printk.time=1 DEBUG: Target IP: 192.168.7.32 DEBUG: Server IP: 192.168.7.31 DEBUG: Starting logging thread DEBUG: Starting thread event loop DEBUG: Connection request received DEBUG: Setting connection established event DEBUG: Output from runqemu: runqemu - INFO - Using /home/pokybuild/bin/runqemu-renice to renice runqemu - INFO - Continuing with the following parameters: KERNEL: [/home/pokybuild/yocto-worker/qemux86-64-ptest-fast/build/build/tmp/deploy/images/qemux86-64/bzImage] MACHINE: [qemux86-64] FSTYPE: [ext4] ROOTFS: [/home/pokybuild/yocto-worker/qemux86-64-ptest-fast/build/build/tmp/deploy/images/qemux86-64/core-image-ptest-libxml2-qemux86-64.ext4] CONFFILE: [/home/pokybuild/yocto-worker/qemux86-64-ptest-fast/build/build/tmp/deploy/images/qemux86-64/core-image-ptest-libxml2-qemux86-64.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 - Acquiring lockfile /tmp/qemu-tap-locks/tap8.lock failed: [Errno 11] Resource temporarily unavailable runqemu - INFO - Acquiring lockfile /tmp/qemu-tap-locks/tap9.lock failed: [Errno 11] Resource temporarily unavailable runqemu - INFO - Acquiring lockfile /tmp/qemu-tap-locks/tap10.lock failed: [Errno 11] Resource temporarily unavailable runqemu - INFO - Acquiring lockfile /tmp/qemu-tap-locks/tap11.lock failed: [Errno 11] Resource temporarily unavailable runqemu - INFO - Acquiring lockfile /tmp/qemu-tap-locks/tap12.lock failed: [Errno 11] Resource temporarily unavailable runqemu - INFO - Acquiring lockfile /tmp/qemu-tap-locks/tap13.lock failed: [Errno 11] Resource temporarily unavailable runqemu - INFO - Acquiring lockfile /tmp/qemu-tap-locks/tap14.lock failed: [Errno 11] Resource temporarily unavailable runqemu - INFO - Using preconfigured tap device tap15 runqemu - INFO - If this is not intended, touch /tmp/qemu-tap-locks/tap15.skip to make runqemu skip tap15. runqemu - INFO - Network configuration: ip=192.168.7.32::192.168.7.31:255.255.255.0::eth0:off:8.8.8.8 runqemu - INFO - Copying rootfs to /home/pokybuild/tmp/core-image-ptest-libxml2-qemux86-64.ext4.589300 runqemu - INFO - Copy done in 0.5380144119262695 seconds runqemu - INFO - Running /home/pokybuild/yocto-worker/qemux86-64-ptest-fast/build/build/tmp/work/x86_64-linux/qemu-helper-native/1.0-r1/recipe-sysroot-native/usr/bin/qemu-system-x86_64 -device virtio-net-pci,netdev=net0,mac=52:54:00:12:34:20 -netdev tap,id=net0,ifname=tap15,script=no,downscript=no -object rng-random,filename=/dev/urandom,id=rng0 -device virtio-rng-pci,rng=rng0 -drive file=/home/pokybuild/tmp/core-image-ptest-libxml2-qemux86-64.ext4.589300,if=virtio,format=raw -usb -device usb-tablet -usb -device usb-kbd -cpu IvyBridge -machine q35,i8042=off -smp 4 -enable-kvm -m 1024 -serial tcp:127.0.0.1:46051 -serial tcp:127.0.0.1:47701 -pidfile /home/pokybuild/yocto-worker/qemux86-64-ptest-fast/build/build/pidfile_589195 -S -qmp unix:./.0z76ejn_,server,wait -qmp unix:./.47ggqr8e,server,nowait -serial mon:vc -device virtio-vga -display sdl,show-cursor=on -kernel /home/pokybuild/yocto-worker/qemux86-64-ptest-fast/build/build/tmp/deploy/images/qemux86-64/bzImage -append 'root=/dev/vda rw ip=192.168.7.32::192.168.7.31:255.255.255.0::eth0:off:8.8.8.8 console=ttyS0 console=ttyS1 oprofile.timer=1 tsc=reliable no_timer_check rcupdate.rcu_expedited=1 printk.time=1' runqemu - INFO - Host uptime: 283067.81 DEBUG: Waiting at most 1500 seconds for login banner (04/28/23 02:57:39) DEBUG: Connection from 127.0.0.1:57538 DEBUG: Reached login banner in 4.15 seconds (04/28/23 02:57:43) DEBUG: Logged in as root in serial console NOTE: test_ping (ping.PingTest) DEBUG: Setting up a 30 second(s) timeout DEBUG: Removed SIGALRM handler NOTE: ... ok NOTE: test_ssh (ssh.SSHTest) DEBUG: Checking if at least one of dropbear, openssh-sshd 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.32 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; uname -a DEBUG: Waiting for process output: time: 1682650666.8138957, endtime: 1682650966.8098655 DEBUG: Partial data from SSH call: Linux qemux86-64 6.1.25-yocto-standard #1 SMP PREEMPT_DYNAMIC Fri Apr 21 14:37:42 UTC 2023 x86_64 GNU/Linux DEBUG: Waiting for process output: time: 1682650667.1584847, endtime: 1682650967.158482 DEBUG: Data from SSH call: Linux qemux86-64 6.1.25-yocto-standard #1 SMP PREEMPT_DYNAMIC Fri Apr 21 14:37:42 UTC 2023 x86_64 GNU/Linux DEBUG: [Command returned '0' after 0.55 seconds] DEBUG: Command: uname -a Status: 0 Output: Linux qemux86-64 6.1.25-yocto-standard #1 SMP PREEMPT_DYNAMIC Fri Apr 21 14:37:42 UTC 2023 x86_64 GNU/Linux DEBUG: [Running]$ ssh -l root -o ServerAliveCountMax=2 -o ServerAliveInterval=30 -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR 192.168.7.32 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; cat /etc/controllerimage DEBUG: Waiting for process output: time: 1682650667.3632567, endtime: 1682650967.3598344 DEBUG: Partial data from SSH call: cat: can't open '/etc/controllerimage': No such file or directory DEBUG: Waiting for process output: time: 1682650667.7044823, endtime: 1682650967.70448 DEBUG: Data from SSH call: cat: can't open '/etc/controllerimage': No such file or directory DEBUG: [Command returned '1' after 0.55 seconds] DEBUG: Command: cat /etc/controllerimage Status: 1 Output: cat: can't open '/etc/controllerimage': No such file or directory NOTE: ... ok DEBUG: [Running]$ ssh -l root -o ServerAliveCountMax=2 -o ServerAliveInterval=30 -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR 192.168.7.32 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; which LSB_Test.sh DEBUG: Waiting for process output: time: 1682650667.912873, endtime: 1682650967.9071023 DEBUG: Data from SSH call: DEBUG: [Command returned '1' after 0.34 seconds] DEBUG: Command: which LSB_Test.sh Status: 1 Output: NOTE: test_parselogs (parselogs.ParseLogsTest) DEBUG: [Running]$ ssh -l root -o ServerAliveCountMax=2 -o ServerAliveInterval=30 -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR 192.168.7.32 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; dmesg > /tmp/dmesg_output.log DEBUG: Waiting for process output: time: 1682650668.2555797, endtime: 1682650968.246055 DEBUG: Data from SSH call: DEBUG: [Command returned '0' after 0.35 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.32 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; test -f /var/log/ DEBUG: Waiting for process output: time: 1682650668.6056635, endtime: 1682650968.60023 DEBUG: Data from SSH call: DEBUG: [Command returned '1' after 0.35 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.32 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; test -d /var/log/ DEBUG: Waiting for process output: time: 1682650668.950355, endtime: 1682650968.9465814 DEBUG: Data from SSH call: DEBUG: [Command returned '0' after 0.34 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.32 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; find /var/log//*.log -maxdepth 1 -type f DEBUG: Waiting for process output: time: 1682650669.2933998, endtime: 1682650969.2892015 DEBUG: Partial data from SSH call: find: /var/log//*.log: No such file or directory DEBUG: Waiting for process output: time: 1682650669.6256406, endtime: 1682650969.625639 DEBUG: Data from SSH call: find: /var/log//*.log: No such file or directory DEBUG: [Command returned '1' after 0.54 seconds] DEBUG: Command: find /var/log//*.log -maxdepth 1 -type f Status: 1 Output: find: /var/log//*.log: No such file or directory DEBUG: [Running]$ ssh -l root -o ServerAliveCountMax=2 -o ServerAliveInterval=30 -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR 192.168.7.32 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; test -f /var/log/dmesg DEBUG: Waiting for process output: time: 1682650669.8316731, endtime: 1682650969.827443 DEBUG: Data from SSH call: DEBUG: [Command returned '0' after 0.34 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.32 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; test -f /tmp/dmesg_output.log DEBUG: Waiting for process output: time: 1682650670.1766868, endtime: 1682650970.1726668 DEBUG: Data from SSH call: DEBUG: [Command returned '0' after 0.34 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 root@192.168.7.32:/var/log/dmesg /home/pokybuild/yocto-worker/qemux86-64-ptest-fast/build/build/tmp/work/qemux86_64-poky-linux/core-image-ptest-libxml2/1.0-r0/target_logs DEBUG: Data from SSH call: DEBUG: [Command returned '0' after 0.15 seconds] DEBUG: [Running]$ scp -o ServerAliveCountMax=2 -o ServerAliveInterval=30 -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR root@192.168.7.32:/tmp/dmesg_output.log /home/pokybuild/yocto-worker/qemux86-64-ptest-fast/build/build/tmp/work/qemux86_64-poky-linux/core-image-ptest-libxml2/1.0-r0/target_logs DEBUG: Data from SSH call: DEBUG: [Command returned '0' after 0.14 seconds] DEBUG: [Running]$ ssh -l root -o ServerAliveCountMax=2 -o ServerAliveInterval=30 -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR 192.168.7.32 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; cat /proc/cpuinfo | grep "model name" | head -n1 | awk 'BEGIN{FS=":"}{print $2}' DEBUG: Waiting for process output: time: 1682650670.842552, endtime: 1682650970.8386862 DEBUG: Partial data from SSH call: Intel Xeon E3-12xx v2 (Ivy Bridge) DEBUG: Waiting for process output: time: 1682650671.1754577, endtime: 1682650971.175455 DEBUG: Data from SSH call: Intel Xeon E3-12xx v2 (Ivy Bridge) DEBUG: [Command returned '0' after 0.54 seconds] DEBUG: Command: cat /proc/cpuinfo | grep "model name" | head -n1 | awk 'BEGIN{FS=":"}{print $2}' Status: 0 Output: Intel Xeon E3-12xx v2 (Ivy Bridge) DEBUG: [Running]$ ssh -l root -o ServerAliveCountMax=2 -o ServerAliveInterval=30 -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR 192.168.7.32 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; cat /proc/cpuinfo | grep "cpu cores" | head -n1 | awk {'print $4'} DEBUG: Waiting for process output: time: 1682650671.381387, endtime: 1682650971.3774812 DEBUG: Partial data from SSH call: 4 DEBUG: Waiting for process output: time: 1682650671.733256, endtime: 1682650971.7332544 DEBUG: Data from SSH call: 4 DEBUG: [Command returned '0' after 0.56 seconds] DEBUG: Command: cat /proc/cpuinfo | grep "cpu cores" | head -n1 | awk {'print $4'} Status: 0 Output: 4 DEBUG: [Running]$ ssh -l root -o ServerAliveCountMax=2 -o ServerAliveInterval=30 -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR 192.168.7.32 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; cat /proc/cpuinfo | grep "processor" | wc -l DEBUG: Waiting for process output: time: 1682650671.939813, endtime: 1682650971.9351516 DEBUG: Partial data from SSH call: 4 DEBUG: Waiting for process output: time: 1682650672.2810588, endtime: 1682650972.2810562 DEBUG: Data from SSH call: 4 DEBUG: [Command returned '0' after 0.55 seconds] DEBUG: Command: cat /proc/cpuinfo | grep "processor" | wc -l Status: 0 Output: 4 DEBUG: [Running]$ ssh -l root -o ServerAliveCountMax=2 -o ServerAliveInterval=30 -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR 192.168.7.32 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; uname -m DEBUG: Waiting for process output: time: 1682650672.4871244, endtime: 1682650972.4831128 DEBUG: Partial data from SSH call: x86_64 DEBUG: Waiting for process output: time: 1682650672.8348622, endtime: 1682650972.8348596 DEBUG: Data from SSH call: x86_64 DEBUG: [Command returned '0' after 0.55 seconds] DEBUG: Command: uname -m Status: 0 Output: x86_64 NOTE: ... ok NOTE: test_ptestrunner_expectfail (ptest.PtestRunnerTest) 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.32 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.13 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.32 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; ptest-runner -t 450 -d "/usr/lib" DEBUG: Data from SSH call: START: ptest-runner 2023-04-28T02:57 BEGIN: /usr/lib/libxml2/ptest Running runtest ## XML regression tests ## XML regression tests on memory ## XML entity subst regression tests ## XML Namespaces regression tests ## Error cases regression tests ## Error cases regression tests from file descriptor ## Error cases regression tests with entity substitution ## Error cases regression tests (old 1.0) ## Error cases stream regression tests ## Reader regression tests ## Reader entities substitution regression tests ## Reader on memory regression tests ## Walker regression tests ## SAX1 callbacks regression tests ## SAX2 callbacks regression tests ## SAX2 callbacks regression tests with entity substitution ## XML push regression tests ## HTML regression tests ## HTML regression tests from file descriptor ## Push HTML regression tests ## HTML SAX regression tests ## Valid documents regression tests ## Validity checking regression tests ## Streaming validity checking regression tests ## Streaming validity error checking regression tests ## General documents valid regression tests ## XInclude regression tests ## XInclude xmlReader regression tests ## XInclude regression tests stripping include nodes ## XInclude xmlReader regression tests stripping include nodes ## URI parsing tests ## URI base composition tests ## Path URI conversion tests ## Schemas regression tests ## Relax-NG regression tests ## Relax-NG streaming regression tests ## Pattern regression tests ## C14N with comments regression tests ## C14N without comments regression tests ## C14N exclusive without comments regression tests ## C14N 1.1 without comments regression tests ## Catalog and Threads regression tests ## SVG parsing regression tests ## Regexp regression tests Total 3052 tests, no errors PASS: runtest Running runsuite Failed to parse xstc/Tests/Metadata/NISTXMLSchemaDatatypes.testSet Failed to parse xstc/Tests/Metadata/SunXMLSchema1-0-20020116.testSet Failed to parse xstc/Tests/Metadata/MSXMLSchema1-0-20020116.testSet ## XML Schemas datatypes test suite from James Clark Ran 1035 tests, 10 errors, 0 leaks 10 errors were expected ## Relax NG test suite from James Clark Ran 253 tests, no errors ## Relax NG test suite for libxml2 Ran 183 tests, no errors Ran 0 tests (0 schemata), no errors Ran 0 tests (0 schemata), no errors Ran 0 tests (0 schemata), no errors Total 1471 tests, no errors PASS: runsuite Running testrecurse ......... ## Parsing recursive test cases ## Parsing non-recursive test cases ## Parsing non-recursive huge case Total 9 tests, no errors PASS: testrecurse Running testchar testing char range: 1 2 3 4 done testing 1 byte char in document: 1 2 3 4 done testing 2 byte char in document: 1 2 3 4 done PASS: testchar Running testdict dictionary tests succeeded 20000 strings PASS: testdict Running testThreads PASS: testThreads Running runxmlconf Test suite: XML 1.0 (2nd edition) W3C Conformance Test Suite, 6 October 2000 Test cases: James Clark XML 1.0 Tests Test cases: Fuji Xerox Japanese Text Tests XML 1.0 Tests Test cases: Sun Microsystems XML Tests Test cases: 159 tests Test cases: OASIS/NIST XML 1.0 Tests Test cases: IBM XML 1.0 Tests Test cases: IBM XML 1.1 Tests Test cases: Richard Tobin's XML 1.0 2nd edition errata test suite 21 Jul 2003 Test cases: 34 tests Test cases: Richard Tobin's XML 1.1 test suite 13 Feb 2003 Test cases: 57 tests Test cases: Richard Tobin's XML Namespaces 1.0 test suite 14 Feb 2003 Test cases: 48 tests Test cases: Richard Tobin's XML Namespaces 1.1 test suite 14 Feb 2003 Test cases: 8 tests Test cases: Richard Tobin's XML 1.0 3rd edition errata test suite 1 June 2006 Test cases: 13 tests Test cases: University of Edinburgh tests for XML 1.0 5th edition Test cases: 393 tests Test cases: Richard Tobin's XML Namespaces 1.0/1.1 2nd edition test suite 1 June 2006 Test cases: 3 tests Test cases: Bjoern Hoehrmann via HST 2013-09-18 Test cases: 9 tests Ran 2273 tests, 15 errors, 0 leaks Total 2273 tests, 15 errors, 0 leaks See runxmlconf.log for detailed output 15 errors were expected PASS: runxmlconf Running testapi Testing HTMLparser : 32 of 38 functions ... Testing HTMLtree : 18 of 18 functions ... Testing SAX2 : 36 of 36 functions ... Testing c14n : 3 of 4 functions ... Testing catalog : 27 of 36 functions ... Testing chvalid : 9 of 9 functions ... Testing debugXML : 25 of 28 functions ... Testing dict : 10 of 13 functions ... Testing encoding : 16 of 19 functions ... Testing entities : 11 of 17 functions ... Testing hash : 17 of 25 functions ... Testing list : 19 of 26 functions ... Testing nanohttp : 13 of 17 functions ... Testing parser : 58 of 70 functions ... Testing parserInternals : 33 of 90 functions ... Testing pattern : 10 of 15 functions ... Testing relaxng : 14 of 24 functions ... Testing schemasInternals : 0 of 2 functions ... Testing schematron : 1 of 10 functions ... Testing tree : 142 of 164 functions ... Testing uri : 10 of 15 functions ... Testing valid : 50 of 70 functions ... Testing xinclude : 8 of 10 functions ... Testing xmlIO : 41 of 51 functions ... Testing xmlautomata : 3 of 19 functions ... Testing xmlerror : 7 of 15 functions ... Testing xmlmodule : 2 of 4 functions ... Testing xmlreader : 76 of 86 functions ... Testing xmlregexp : 16 of 30 functions ... Testing xmlsave : 4 of 10 functions ... Testing xmlschemas : 16 of 27 functions ... Testing xmlschemastypes : 26 of 34 functions ... Testing xmlstring : 26 of 30 functions ... Testing xmlunicode : 166 of 166 functions ... Testing xmlwriter : 52 of 80 functions ... Testing xpath : 32 of 40 functions ... Testing xpathInternals : 106 of 117 functions ... Testing xpointer : 13 of 21 functions ... Total: 1096 functions, 279085 tests, 0 errors PASS: testapi OK PASS: attribs.py OK PASS: build.py OK PASS: compareNodes.py OK PASS: ctxterror.py OK PASS: cutnpaste.py OK PASS: dtdvalid.py OK PASS: error.py OK PASS: inbuf.py OK PASS: indexes.py OK PASS: input_callback.py OK PASS: nsdel.py OK PASS: outbuf.py OK PASS: push.py OK PASS: pushSAXhtml.py OK PASS: pushSAX.py OK PASS: reader2.py OK PASS: reader3.py OK PASS: reader4.py OK PASS: reader5.py OK PASS: reader6.py OK PASS: reader7.py OK PASS: reader8.py OK PASS: readererr.py OK PASS: readernext.py OK PASS: reader.py OK PASS: regexp.py OK PASS: relaxng.py OK PASS: resolver.py OK PASS: schema.py OK PASS: serialize.py OK PASS: sync.py OK PASS: thread2.py OK PASS: tstLastError.py OK PASS: tstmem.py OK PASS: tst.py OK PASS: tstURI.py OK PASS: tstxpath.py OK PASS: validate.py OK PASS: validDTD.py OK PASS: validRNG.py OK PASS: validSchemas.py OK PASS: walker.py OK PASS: xpathext.py OK PASS: xpathleak.py OK PASS: xpathns.py OK PASS: xpath.py OK PASS: xpathret.py DURATION: 14 END: /usr/lib/libxml2/ptest 2023-04-28T02:58 STOP: ptest-runner TOTAL: 1 FAIL: 0 DEBUG: [Command returned '0' after 13.45 seconds] DEBUG: Command: ptest-runner -t 450 -d "/usr/lib" Status: 0 Output: START: ptest-runner 2023-04-28T02:57 BEGIN: /usr/lib/libxml2/ptest Running runtest ## XML regression tests ## XML regression tests on memory ## XML entity subst regression tests ## XML Namespaces regression tests ## Error cases regression tests ## Error cases regression tests from file descriptor ## Error cases regression tests with entity substitution ## Error cases regression tests (old 1.0) ## Error cases stream regression tests ## Reader regression tests ## Reader entities substitution regression tests ## Reader on memory regression tests ## Walker regression tests ## SAX1 callbacks regression tests ## SAX2 callbacks regression tests ## SAX2 callbacks regression tests with entity substitution ## XML push regression tests ## HTML regression tests ## HTML regression tests from file descriptor ## Push HTML regression tests ## HTML SAX regression tests ## Valid documents regression tests ## Validity checking regression tests ## Streaming validity checking regression tests ## Streaming validity error checking regression tests ## General documents valid regression tests ## XInclude regression tests ## XInclude xmlReader regression tests ## XInclude regression tests stripping include nodes ## XInclude xmlReader regression tests stripping include nodes ## URI parsing tests ## URI base composition tests ## Path URI conversion tests ## Schemas regression tests ## Relax-NG regression tests ## Relax-NG streaming regression tests ## Pattern regression tests ## C14N with comments regression tests ## C14N without comments regression tests ## C14N exclusive without comments regression tests ## C14N 1.1 without comments regression tests ## Catalog and Threads regression tests ## SVG parsing regression tests ## Regexp regression tests Total 3052 tests, no errors PASS: runtest Running runsuite Failed to parse xstc/Tests/Metadata/NISTXMLSchemaDatatypes.testSet Failed to parse xstc/Tests/Metadata/SunXMLSchema1-0-20020116.testSet Failed to parse xstc/Tests/Metadata/MSXMLSchema1-0-20020116.testSet ## XML Schemas datatypes test suite from James Clark Ran 1035 tests, 10 errors, 0 leaks 10 errors were expected ## Relax NG test suite from James Clark Ran 253 tests, no errors ## Relax NG test suite for libxml2 Ran 183 tests, no errors Ran 0 tests (0 schemata), no errors Ran 0 tests (0 schemata), no errors Ran 0 tests (0 schemata), no errors Total 1471 tests, no errors PASS: runsuite Running testrecurse ......... ## Parsing recursive test cases ## Parsing non-recursive test cases ## Parsing non-recursive huge case Total 9 tests, no errors PASS: testrecurse Running testchar testing char range: 1 2 3 4 done testing 1 byte char in document: 1 2 3 4 done testing 2 byte char in document: 1 2 3 4 done PASS: testchar Running testdict dictionary tests succeeded 20000 strings PASS: testdict Running testThreads PASS: testThreads Running runxmlconf Test suite: XML 1.0 (2nd edition) W3C Conformance Test Suite, 6 October 2000 Test cases: James Clark XML 1.0 Tests Test cases: Fuji Xerox Japanese Text Tests XML 1.0 Tests Test cases: Sun Microsystems XML Tests Test cases: 159 tests Test cases: OASIS/NIST XML 1.0 Tests Test cases: IBM XML 1.0 Tests Test cases: IBM XML 1.1 Tests Test cases: Richard Tobin's XML 1.0 2nd edition errata test suite 21 Jul 2003 Test cases: 34 tests Test cases: Richard Tobin's XML 1.1 test suite 13 Feb 2003 Test cases: 57 tests Test cases: Richard Tobin's XML Namespaces 1.0 test suite 14 Feb 2003 Test cases: 48 tests Test cases: Richard Tobin's XML Namespaces 1.1 test suite 14 Feb 2003 Test cases: 8 tests Test cases: Richard Tobin's XML 1.0 3rd edition errata test suite 1 June 2006 Test cases: 13 tests Test cases: University of Edinburgh tests for XML 1.0 5th edition Test cases: 393 tests Test cases: Richard Tobin's XML Namespaces 1.0/1.1 2nd edition test suite 1 June 2006 Test cases: 3 tests Test cases: Bjoern Hoehrmann via HST 2013-09-18 Test cases: 9 tests Ran 2273 tests, 15 errors, 0 leaks Total 2273 tests, 15 errors, 0 leaks See runxmlconf.log for detailed output 15 errors were expected PASS: runxmlconf Running testapi Testing HTMLparser : 32 of 38 functions ... Testing HTMLtree : 18 of 18 functions ... Testing SAX2 : 36 of 36 functions ... Testing c14n : 3 of 4 functions ... Testing catalog : 27 of 36 functions ... Testing chvalid : 9 of 9 functions ... Testing debugXML : 25 of 28 functions ... Testing dict : 10 of 13 functions ... Testing encoding : 16 of 19 functions ... Testing entities : 11 of 17 functions ... Testing hash : 17 of 25 functions ... Testing list : 19 of 26 functions ... Testing nanohttp : 13 of 17 functions ... Testing parser : 58 of 70 functions ... Testing parserInternals : 33 of 90 functions ... Testing pattern : 10 of 15 functions ... Testing relaxng : 14 of 24 functions ... Testing schemasInternals : 0 of 2 functions ... Testing schematron : 1 of 10 functions ... Testing tree : 142 of 164 functions ... Testing uri : 10 of 15 functions ... Testing valid : 50 of 70 functions ... Testing xinclude : 8 of 10 functions ... Testing xmlIO : 41 of 51 functions ... Testing xmlautomata : 3 of 19 functions ... Testing xmlerror : 7 of 15 functions ... Testing xmlmodule : 2 of 4 functions ... Testing xmlreader : 76 of 86 functions ... Testing xmlregexp : 16 of 30 functions ... Testing xmlsave : 4 of 10 functions ... Testing xmlschemas : 16 of 27 functions ... Testing xmlschemastypes : 26 of 34 functions ... Testing xmlstring : 26 of 30 functions ... Testing xmlunicode : 166 of 166 functions ... Testing xmlwriter : 52 of 80 functions ... Testing xpath : 32 of 40 functions ... Testing xpathInternals : 106 of 117 functions ... Testing xpointer : 13 of 21 functions ... Total: 1096 functions, 279085 tests, 0 errors PASS: testapi OK PASS: attribs.py OK PASS: build.py OK PASS: compareNodes.py OK PASS: ctxterror.py OK PASS: cutnpaste.py OK PASS: dtdvalid.py OK PASS: error.py OK PASS: inbuf.py OK PASS: indexes.py OK PASS: input_callback.py OK PASS: nsdel.py OK PASS: outbuf.py OK PASS: push.py OK PASS: pushSAXhtml.py OK PASS: pushSAX.py OK PASS: reader2.py OK PASS: reader3.py OK PASS: reader4.py OK PASS: reader5.py OK PASS: reader6.py OK PASS: reader7.py OK PASS: reader8.py OK PASS: readererr.py OK PASS: readernext.py OK PASS: reader.py OK PASS: regexp.py OK PASS: relaxng.py OK PASS: resolver.py OK PASS: schema.py OK PASS: serialize.py OK PASS: sync.py OK PASS: thread2.py OK PASS: tstLastError.py OK PASS: tstmem.py OK PASS: tst.py OK PASS: tstURI.py OK PASS: tstxpath.py OK PASS: validate.py OK PASS: validDTD.py OK PASS: validRNG.py OK PASS: validSchemas.py OK PASS: walker.py OK PASS: xpathext.py OK PASS: xpathleak.py OK PASS: xpathns.py OK PASS: xpath.py OK PASS: xpathret.py DURATION: 14 END: /usr/lib/libxml2/ptest 2023-04-28T02:58 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.32 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; dmesg | grep "Killed process" DEBUG: Data from SSH call: DEBUG: [Command returned '1' after 0.14 seconds] DEBUG: Command: dmesg | grep "Killed process" Status: 1 Output: NOTE: ... unexpected success NOTE: test_ptestrunner_expectsuccess (ptest.PtestRunnerTest) 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: Ran 5 tests in 20.017s 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: 283100.72 runqemu - INFO - Removing /home/pokybuild/tmp/core-image-ptest-libxml2-qemux86-64.ext4.589300 tput: No value for $TERM and no -T specified RESULTS: RESULTS - parselogs.ParseLogsTest.test_parselogs: PASSED (4.79s) RESULTS - ping.PingTest.test_ping: PASSED (0.04s) RESULTS - ptest.PtestRunnerTest.test_ptestrunner_expectfail: PASSED (13.75s) RESULTS - ssh.SSHTest.test_ssh: PASSED (1.10s) RESULTS - ptest.PtestRunnerTest.test_ptestrunner_expectsuccess: SKIPPED (0.00s) SUMMARY: core-image-ptest-libxml2 () - Ran 5 tests in 20.017s core-image-ptest-libxml2 - OK - All required tests passed (successes=3, skipped=1, failures=0, errors=0) /home/pokybuild/yocto-worker/qemux86-64-ptest-fast/build/meta/lib/oeqa/utils/qemurunner.py:486: ResourceWarning: unclosed self.server_socket = qemusock ResourceWarning: Enable tracemalloc to get the object allocation traceback DEBUG: Python function do_testimage finished