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', 'xz-native', 'zlib-native', 'glib-2.0-native', 'python3-native', 'gettext-minimal-native', 'librepo-native', 'python3-iniparse-native', 'libdnf-native', 'cmake-native', 'libcomps-native', 'texinfo-dummy-native', 'libtool-native', 'expat-native', 'openssl-native', 'curl-native', 'file-native', 'libxml2-native', 'rpm-native', 'sqlite3-native', 'libtirpc-native', 'flex-native', 'dtc-native', 'alsa-lib-native', 'libepoxy-native', 'pixman-native', 'libsdl2-native', 'libslirp-native', 'virglrenderer-native', 'libffi-native', 'gettext-native', 'libpcre2-native', 'util-linux-native', 'gdbm-native', 'ncurses-native', 'util-linux-libuuid-native', 'readline-native', 'bzip2-native', 'libnsl2-native', 'gpgme-native', 'attr-native', 'python3-build-native', 'python3-installer-native', 'python3-setuptools-native', 'python3-wheel-native', 'python3-six-native', 'libsolv-native', 'libmodulemd-native', 'json-c-native', 'swig-native', 'gobject-introspection-native', 'libcheck-native', 'zstd-native', 'perl-native', 'libgcrypt-native', 'elfutils-native', 'popt-native', 'lua-native', 'm4-native', 'libx11-native', 'mesa-native', 'libxrender-native', 'libxext-native', 'libxrandr-native', 'libdrm-native', 'libcap-ng-native', 'libassuan-native', 'libgpg-error-native', 'python3-packaging-native', 'python3-flit-core-native', 'python3-pyproject-hooks-native', 'libyaml-native', 'make-native', 'libcap-native', 'libmicrohttpd-native', 'libarchive-native', 'gnu-config-native', 'util-macros-native', 'libxcb-native', 'xorgproto-native', 'xtrans-native', 'libxdamage-native', 'libxfixes-native', 'chrpath-native', 'wayland-protocols-native', 'xrandr-native', 'makedepend-native', 'wayland-native', 'python3-mako-native', 'llvm-native', 'libxxf86vm-native', 'libxshmfence-native', 'libpciaccess-native', 'libpthread-stubs-native', 'unzip-native', 'gnutls-native', 'libxau-native', 'libxdmcp-native', 'xcb-proto-native', 'python3-pygments-native', 'python3-markupsafe-native', 'libedit-native', 'libunistring-native', 'gmp-native', 'nettle-native', 'libidn2-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/.f6avzp1h DEBUG: Created listening socket for qemu serial console on: 127.0.0.1:36997 DEBUG: Created listening socket for qemu serial console on: 127.0.0.1:38579 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-lua-qemux86-64.ext4 tcpserial=36997:38579 bootparams=" printk.time=1" qemuparams="-pidfile /home/pokybuild/yocto-worker/qemux86-64-ptest-fast/build/build/pidfile_2981258 -S -qmp unix:./.zoxyd5qx,server,wait -qmp unix:./.f6avzp1h,server,nowait" DEBUG: runqemu started, pid is 2987459 DEBUG: waiting at most 300 seconds for qemu pid (03/25/23 07:51:16) DEBUG: QMP Initializing to /home/pokybuild/yocto-worker/qemux86-64-ptest-fast/build/build/tmp/.zoxyd5qx DEBUG: QMP Connecting to /home/pokybuild/yocto-worker/qemux86-64-ptest-fast/build/build/tmp/.zoxyd5qx QMP connected to QEMU at 03/25/23 07:51:17 and took 1.2127692699432373 seconds QMP released QEMU at 03/25/23 07:51:17 and took 0.3257935047149658 seconds from connect DEBUG: qemu started in 1.5318026542663574 seconds - qemu procces pid is 2990505 (03/25/23 07:51:17) 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:1a -netdev tap,id=net0,ifname=tap12,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-lua-qemux86-64.ext4.2987459,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:36997 -serial tcp:127.0.0.1:38579 -pidfile /home/pokybuild/yocto-worker/qemux86-64-ptest-fast/build/build/pidfile_2981258 -S -qmp unix:./.zoxyd5qx,server,wait -qmp unix:./.f6avzp1h,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.26::192.168.7.25: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.26 DEBUG: Server IP: 192.168.7.25 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-lua-qemux86-64.ext4] CONFFILE: [/home/pokybuild/yocto-worker/qemux86-64-ptest-fast/build/build/tmp/deploy/images/qemux86-64/core-image-ptest-lua-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 - Using preconfigured tap device tap12 runqemu - INFO - If this is not intended, touch /tmp/qemu-tap-locks/tap12.skip to make runqemu skip tap12. runqemu - INFO - Network configuration: ip=192.168.7.26::192.168.7.25:255.255.255.0::eth0:off:8.8.8.8 runqemu - INFO - Copying rootfs to /home/pokybuild/tmp/core-image-ptest-lua-qemux86-64.ext4.2987459 runqemu - INFO - Copy done in 0.5435581207275391 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:1a -netdev tap,id=net0,ifname=tap12,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-lua-qemux86-64.ext4.2987459,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:36997 -serial tcp:127.0.0.1:38579 -pidfile /home/pokybuild/yocto-worker/qemux86-64-ptest-fast/build/build/pidfile_2981258 -S -qmp unix:./.zoxyd5qx,server,wait -qmp unix:./.f6avzp1h,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.26::192.168.7.25: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: 396933.27 DEBUG: Waiting at most 1500 seconds for login banner (03/25/23 07:51:17) DEBUG: Connection from 127.0.0.1:33800 /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: Reached login banner in 8.586281776428223 seconds (03/25/23 07:51:26, 1679730686.3710034) DEBUG: Logged 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.26 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; uname -a DEBUG: Waiting for process output: time: 1679730689.606148, endtime: 1679730989.5974953 DEBUG: Partial data from SSH call: Linux qemux86-64 6.1.14-yocto-standard #1 SMP PREEMPT_DYNAMIC Mon Feb 27 14:44:30 UTC 2023 x86_64 GNU/Linux DEBUG: Waiting for process output: time: 1679730689.9982388, endtime: 1679730989.9982367 DEBUG: Data from SSH call: Linux qemux86-64 6.1.14-yocto-standard #1 SMP PREEMPT_DYNAMIC Mon Feb 27 14:44:30 UTC 2023 x86_64 GNU/Linux DEBUG: [Command returned '0' after 1.09 seconds] DEBUG: Command: uname -a Status: 0 Output: Linux qemux86-64 6.1.14-yocto-standard #1 SMP PREEMPT_DYNAMIC Mon Feb 27 14:44:30 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.26 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; cat /etc/controllerimage DEBUG: Waiting for process output: time: 1679730690.692352, endtime: 1679730990.6841419 DEBUG: Partial data from SSH call: cat: can't open '/etc/controllerimage': No such file or directory DEBUG: Waiting for process output: time: 1679730691.0561845, endtime: 1679730991.0561824 DEBUG: Data from SSH call: cat: can't open '/etc/controllerimage': No such file or directory DEBUG: [Command returned '1' after 0.57 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.26 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; which LSB_Test.sh DEBUG: Waiting for process output: time: 1679730691.2657108, endtime: 1679730991.2593293 DEBUG: Data from SSH call: DEBUG: [Command returned '1' after 0.42 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.26 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; dmesg > /tmp/dmesg_output.log DEBUG: Waiting for process output: time: 1679730691.6927376, endtime: 1679730991.684414 DEBUG: Data from SSH call: DEBUG: [Command returned '0' after 0.40 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.26 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; test -f /var/log/ DEBUG: Waiting for process output: time: 1679730692.0879529, endtime: 1679730992.0810852 DEBUG: Data from SSH call: DEBUG: [Command returned '1' after 0.36 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.26 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; test -d /var/log/ DEBUG: Waiting for process output: time: 1679730692.4510944, endtime: 1679730992.4443493 DEBUG: Data from SSH call: DEBUG: [Command returned '0' after 0.42 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.26 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; find /var/log//*.log -maxdepth 1 -type f DEBUG: Waiting for process output: time: 1679730692.8671849, endtime: 1679730992.8606458 DEBUG: Partial data from SSH call: find: /var/log//*.log: No such file or directory DEBUG: Waiting for process output: time: 1679730693.2555983, endtime: 1679730993.2555947 DEBUG: Data from SSH call: find: /var/log//*.log: No such file or directory DEBUG: [Command returned '1' after 0.60 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.26 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; test -f /var/log/dmesg DEBUG: Waiting for process output: time: 1679730693.6709998, endtime: 1679730993.659671 DEBUG: Data from SSH call: DEBUG: [Command returned '0' after 0.43 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.26 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; test -f /tmp/dmesg_output.log DEBUG: Waiting for process output: time: 1679730694.0957243, endtime: 1679730994.0892813 DEBUG: Data 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 root@192.168.7.26:/var/log/dmesg /home/pokybuild/yocto-worker/qemux86-64-ptest-fast/build/build/tmp/work/qemux86_64-poky-linux/core-image-ptest-lua/1.0-r0/target_logs DEBUG: Data from SSH call: DEBUG: [Command returned '0' after 0.30 seconds] DEBUG: [Running]$ scp -o ServerAliveCountMax=2 -o ServerAliveInterval=30 -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR root@192.168.7.26:/tmp/dmesg_output.log /home/pokybuild/yocto-worker/qemux86-64-ptest-fast/build/build/tmp/work/qemux86_64-poky-linux/core-image-ptest-lua/1.0-r0/target_logs DEBUG: Data from SSH call: DEBUG: [Command returned '0' after 0.63 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.26 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: 1679730695.4632535, endtime: 1679730995.4547703 DEBUG: Partial data from SSH call: Intel Xeon E3-12xx v2 (Ivy Bridge) DEBUG: Waiting for process output: time: 1679730695.7803566, endtime: 1679730995.7803543 DEBUG: Data from SSH call: Intel Xeon E3-12xx v2 (Ivy Bridge) DEBUG: [Command returned '0' after 0.53 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.26 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: 1679730695.9943016, endtime: 1679730995.9825141 DEBUG: Partial data from SSH call: 4 DEBUG: Waiting for process output: time: 1679730696.3471406, endtime: 1679730996.3471377 DEBUG: Data from SSH call: 4 DEBUG: [Command returned '0' after 0.57 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.26 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; cat /proc/cpuinfo | grep "processor" | wc -l DEBUG: Waiting for process output: time: 1679730696.5591683, endtime: 1679730996.5501554 DEBUG: Partial data from SSH call: 4 DEBUG: Waiting for process output: time: 1679730696.9378774, endtime: 1679730996.937875 DEBUG: Data from SSH call: 4 DEBUG: [Command returned '0' after 0.59 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.26 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; uname -m DEBUG: Waiting for process output: time: 1679730697.1466901, endtime: 1679730997.140165 DEBUG: Partial data from SSH call: x86_64 DEBUG: Waiting for process output: time: 1679730697.5208874, endtime: 1679730997.5208843 DEBUG: Data from SSH call: x86_64 DEBUG: [Command returned '0' after 0.58 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.26 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.18 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.26 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; ptest-runner -t 450 -d "/usr/lib" DEBUG: Data from SSH call: START: ptest-runner ERROR: setpgid() failed, Operation not permitted 2023-03-25T07:51 BEGIN: /usr/lib/lua/ptest ...........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................Lua warning: #This is an expected warning Lua warning: #This is another one .......--- test output --- Starting Tests random seeds: 1679730696, 94868817019560 current path: ****/usr/share/lua/5.4/?.lua;/usr/share/lua/5.4/?/init.lua;/usr/lib/lua/5.4/?.lua;/usr/lib/lua/5.4/?/init.lua;./?.lua;./?/init.lua**** ---- total memory: 44.6K, max memory: 44.6K ---- time: 4.8e-05 (+4.8e-05) ***** FILE 'main.lua'***** ***** FILE 'gc.lua'***** testing incremental garbage collection creating many objects functions with errors long strings steps steps (2) clearing tables weak tables + self-referenced threads OK ---- total memory: 1.4M, max memory: 1.4M ---- time: 0.279613 (+0.279565) ***** FILE 'db.lua'***** testing debug library and debug information + testing inspection of parameters/returned values + + testing traceback sizes testing debug functions on chunk without debug info OK ---- total memory: 152.8K, max memory: 1.4M ---- time: 0.384503 (+0.10489) ***** FILE 'calls.lua'***** testing functions and calls + + testing tail calls + + testing binary chunks OK testing strings and string library testing 'format %a %A' OK testing scanner + + + + + OK ---- total memory: 271.5K, max memory: 1.4M ---- time: 0.528099 (+0.143596) ***** FILE 'tpack.lua'***** testing pack/unpack platform: short 2, int 4, long 8, size_t 8, float 4, double 8, lua Integer 8, lua Number 8 little endian alignment: 8 testing invalid formats testing pack/unpack of floating-point numbers testing pack/unpack of strings testing alignment OK ---- total memory: 315.4K, max memory: 1.4M ---- time: 0.530041 (+0.001942) ***** FILE 'attrib.lua'***** testing require package config: /|;|?|!|-| testing 'require' message + testing assignments, logical operators, and constructors + OK ---- total memory: 318.1K, max memory: 1.4M ---- time: 0.534098 (+0.004057) ***** FILE 'gengc.lua'***** testing generational garbage collection + OK ---- total memory: 124.8K, max memory: 1.4M ---- time: 0.535194 (+0.001096) ***** FILE 'locals.lua'***** testing local variables and environments + testing to-be-closed variables testing errors in __close to-be-closed variables in coroutines OK ---- total memory: 178.4K, max memory: 1.4M ---- time: 0.775893 (+0.240699) ***** FILE 'constructs.lua'***** testing syntax + testing short-circuit optimizations (0) + OK ---- total memory: 967.6K, max memory: 1.4M ---- time: 0.860199 (+0.084306) ***** FILE 'code.lua'***** ---- total memory: 1.1M, max memory: 1.4M ---- time: 0.862048 (+0.001849) ***** FILE 'cstack.lua'***** testing stack overflow detection testing stack overflow in message handling final count: 250037 testing recursion inside pattern matching testing stack-overflow in recursive 'gsub' final count: 197 testing stack-overflow in recursive 'gsub' with metatables final count: 99 testing limits in coroutines inside deep calls final count: 196 nesting of resuming yielded coroutines final count: 195 nesting coroutines running after recoverable errors final count: 197 OK ---- total memory: 14.2M, max memory: 14.2M ---- time: 1.00283 (+0.140786) ***** FILE 'nextvar.lua'***** testing tables, next, and for + + + testing next x GC of deleted keys + testing floats in numeric for OK ---- total memory: 137.3K, max memory: 14.2M ---- time: 1.05334 (+0.050506) ***** FILE 'pm.lua'***** testing pattern matching + + + + OK ---- total memory: 168.1K, max memory: 14.2M ---- time: 1.05618 (+0.002843) ***** FILE 'utf8.lua'***** testing UTF-8 library ok ---- total memory: 154.7K, max memory: 14.2M ---- time: 1.05772 (+0.00154) ***** FILE 'api.lua'***** ---- total memory: 345.1K, max memory: 14.2M ---- time: 1.06143 (+0.003709) ***** FILE 'events.lua'***** testing metatables + + OK ---- total memory: 136.9K, max memory: 14.2M ---- time: 1.06448 (+0.003049) ***** FILE 'vararg.lua'***** testing vararg + + + OK ---- total memory: 171.6K, max memory: 14.2M ---- time: 1.06527 (+0.000786) ***** FILE 'closure.lua'***** testing closures + OK ---- total memory: 242.3K, max memory: 14.2M ---- time: 1.07012 (+0.004856) ***** FILE 'coroutine.lua'***** testing coroutines to-be-closed variables in coroutines testing 'coroutine.close' + testing yields inside metamethods + testing yields inside 'for' iterators OK ---- total memory: 218.9K, max memory: 14.2M ---- time: 1.07496 (+0.004835) ***** FILE 'goto.lua'***** OK ---- total memory: 140.2K, max memory: 14.2M ---- time: 1.07585 (+0.000891) ***** FILE 'errors.lua'***** testing errors + testing tokens in error messages + OK ---- total memory: 263.0K, max memory: 14.2M ---- time: 1.09632 (+0.020471) ***** FILE 'math.lua'***** testing numbers and math lib 64-bit integers, 53-bit (mantissa) floats testing order (floats cannot represent all integers) testing -0 and NaN testing 'math.random' random seeds: 1679730698, 94868817019560 float random range in 26500 calls: [0.000046, 0.999994] integer random range in 64000 calls: [minint + 43ppm, maxint - 0ppm] OK ---- total memory: 163.6K, max memory: 14.2M ---- time: 1.15037 (+0.05405) ***** FILE 'sort.lua'***** testing (parts of) table library testing unpack testing pack testing sort sorting 5000 random elements in 3.16 msec. re-sorting 5000 sorted elements in 2.72 msec. Invert-sorting other 5000 elements in 14.17 msec., with 65321 comparisons sorting 5000 equal elements in 6.57 msec. OK ---- total memory: 259.7K, max memory: 14.2M ---- time: 1.18993 (+0.039557) ***** FILE 'bitwise.lua'***** testing bitwise operations + testing bitwise library + + OK ---- total memory: 327.9K, max memory: 14.2M ---- time: 1.19217 (+0.002248) ***** FILE 'verybig.lua'***** testing RK ---- total memory: 125.4K, max memory: 14.2M ---- time: 1.19281 (+0.000631) ***** FILE 'files.lua'***** testing i/o + + + + + + testing date/time test done on 25/03/2023, at 07:51:38 Lua 5.4 (there should be two warnings now) 64-bit integers, 64-bit floats cleaning all!!!! ---- total memory: 58.5K, max memory: 14.2M ---- total time: 1.25s (wall time: 2s) final OK !!! >>> closing state <<< --- ptest result --- PASS: lua DURATION: 2 END: /usr/lib/lua/ptest 2023-03-25T07:51 STOP: ptest-runner TOTAL: 1 FAIL: 0 DEBUG: [Command returned '0' after 1.54 seconds] DEBUG: Command: ptest-runner -t 450 -d "/usr/lib" Status: 0 Output: START: ptest-runner ERROR: setpgid() failed, Operation not permitted 2023-03-25T07:51 BEGIN: /usr/lib/lua/ptest ...........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................Lua warning: #This is an expected warning Lua warning: #This is another one .......--- test output --- Starting Tests random seeds: 1679730696, 94868817019560 current path: ****/usr/share/lua/5.4/?.lua;/usr/share/lua/5.4/?/init.lua;/usr/lib/lua/5.4/?.lua;/usr/lib/lua/5.4/?/init.lua;./?.lua;./?/init.lua**** ---- total memory: 44.6K, max memory: 44.6K ---- time: 4.8e-05 (+4.8e-05) ***** FILE 'main.lua'***** ***** FILE 'gc.lua'***** testing incremental garbage collection creating many objects functions with errors long strings steps steps (2) clearing tables weak tables + self-referenced threads OK ---- total memory: 1.4M, max memory: 1.4M ---- time: 0.279613 (+0.279565) ***** FILE 'db.lua'***** testing debug library and debug information + testing inspection of parameters/returned values + + testing traceback sizes testing debug functions on chunk without debug info OK ---- total memory: 152.8K, max memory: 1.4M ---- time: 0.384503 (+0.10489) ***** FILE 'calls.lua'***** testing functions and calls + + testing tail calls + + testing binary chunks OK testing strings and string library testing 'format %a %A' OK testing scanner + + + + + OK ---- total memory: 271.5K, max memory: 1.4M ---- time: 0.528099 (+0.143596) ***** FILE 'tpack.lua'***** testing pack/unpack platform: short 2, int 4, long 8, size_t 8, float 4, double 8, lua Integer 8, lua Number 8 little endian alignment: 8 testing invalid formats testing pack/unpack of floating-point numbers testing pack/unpack of strings testing alignment OK ---- total memory: 315.4K, max memory: 1.4M ---- time: 0.530041 (+0.001942) ***** FILE 'attrib.lua'***** testing require package config: /|;|?|!|-| testing 'require' message + testing assignments, logical operators, and constructors + OK ---- total memory: 318.1K, max memory: 1.4M ---- time: 0.534098 (+0.004057) ***** FILE 'gengc.lua'***** testing generational garbage collection + OK ---- total memory: 124.8K, max memory: 1.4M ---- time: 0.535194 (+0.001096) ***** FILE 'locals.lua'***** testing local variables and environments + testing to-be-closed variables testing errors in __close to-be-closed variables in coroutines OK ---- total memory: 178.4K, max memory: 1.4M ---- time: 0.775893 (+0.240699) ***** FILE 'constructs.lua'***** testing syntax + testing short-circuit optimizations (0) + OK ---- total memory: 967.6K, max memory: 1.4M ---- time: 0.860199 (+0.084306) ***** FILE 'code.lua'***** ---- total memory: 1.1M, max memory: 1.4M ---- time: 0.862048 (+0.001849) ***** FILE 'cstack.lua'***** testing stack overflow detection testing stack overflow in message handling final count: 250037 testing recursion inside pattern matching testing stack-overflow in recursive 'gsub' final count: 197 testing stack-overflow in recursive 'gsub' with metatables final count: 99 testing limits in coroutines inside deep calls final count: 196 nesting of resuming yielded coroutines final count: 195 nesting coroutines running after recoverable errors final count: 197 OK ---- total memory: 14.2M, max memory: 14.2M ---- time: 1.00283 (+0.140786) ***** FILE 'nextvar.lua'***** testing tables, next, and for + + + testing next x GC of deleted keys + testing floats in numeric for OK ---- total memory: 137.3K, max memory: 14.2M ---- time: 1.05334 (+0.050506) ***** FILE 'pm.lua'***** testing pattern matching + + + + OK ---- total memory: 168.1K, max memory: 14.2M ---- time: 1.05618 (+0.002843) ***** FILE 'utf8.lua'***** testing UTF-8 library ok ---- total memory: 154.7K, max memory: 14.2M ---- time: 1.05772 (+0.00154) ***** FILE 'api.lua'***** ---- total memory: 345.1K, max memory: 14.2M ---- time: 1.06143 (+0.003709) ***** FILE 'events.lua'***** testing metatables + + OK ---- total memory: 136.9K, max memory: 14.2M ---- time: 1.06448 (+0.003049) ***** FILE 'vararg.lua'***** testing vararg + + + OK ---- total memory: 171.6K, max memory: 14.2M ---- time: 1.06527 (+0.000786) ***** FILE 'closure.lua'***** testing closures + OK ---- total memory: 242.3K, max memory: 14.2M ---- time: 1.07012 (+0.004856) ***** FILE 'coroutine.lua'***** testing coroutines to-be-closed variables in coroutines testing 'coroutine.close' + testing yields inside metamethods + testing yields inside 'for' iterators OK ---- total memory: 218.9K, max memory: 14.2M ---- time: 1.07496 (+0.004835) ***** FILE 'goto.lua'***** OK ---- total memory: 140.2K, max memory: 14.2M ---- time: 1.07585 (+0.000891) ***** FILE 'errors.lua'***** testing errors + testing tokens in error messages + OK ---- total memory: 263.0K, max memory: 14.2M ---- time: 1.09632 (+0.020471) ***** FILE 'math.lua'***** testing numbers and math lib 64-bit integers, 53-bit (mantissa) floats testing order (floats cannot represent all integers) testing -0 and NaN testing 'math.random' random seeds: 1679730698, 94868817019560 float random range in 26500 calls: [0.000046, 0.999994] integer random range in 64000 calls: [minint + 43ppm, maxint - 0ppm] OK ---- total memory: 163.6K, max memory: 14.2M ---- time: 1.15037 (+0.05405) ***** FILE 'sort.lua'***** testing (parts of) table library testing unpack testing pack testing sort sorting 5000 random elements in 3.16 msec. re-sorting 5000 sorted elements in 2.72 msec. Invert-sorting other 5000 elements in 14.17 msec., with 65321 comparisons sorting 5000 equal elements in 6.57 msec. OK ---- total memory: 259.7K, max memory: 14.2M ---- time: 1.18993 (+0.039557) ***** FILE 'bitwise.lua'***** testing bitwise operations + testing bitwise library + + OK ---- total memory: 327.9K, max memory: 14.2M ---- time: 1.19217 (+0.002248) ***** FILE 'verybig.lua'***** testing RK ---- total memory: 125.4K, max memory: 14.2M ---- time: 1.19281 (+0.000631) ***** FILE 'files.lua'***** testing i/o + + + + + + testing date/time test done on 25/03/2023, at 07:51:38 Lua 5.4 (there should be two warnings now) 64-bit integers, 64-bit floats cleaning all!!!! ---- total memory: 58.5K, max memory: 14.2M ---- total time: 1.25s (wall time: 2s) final OK !!! >>> closing state <<< --- ptest result --- PASS: lua DURATION: 2 END: /usr/lib/lua/ptest 2023-03-25T07:51 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.26 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; dmesg | grep "Killed process" DEBUG: Data from SSH call: DEBUG: [Command returned '1' after 0.23 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 11.398s 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: 396962.19 runqemu - INFO - Removing /home/pokybuild/tmp/core-image-ptest-lua-qemux86-64.ext4.2987459 tput: No value for $TERM and no -T specified RESULTS: RESULTS - parselogs.ParseLogsTest.test_parselogs: PASSED (6.04s) RESULTS - ping.PingTest.test_ping: PASSED (0.07s) RESULTS - ptest.PtestRunnerTest.test_ptestrunner_expectfail: PASSED (2.58s) RESULTS - ssh.SSHTest.test_ssh: PASSED (1.66s) RESULTS - ptest.PtestRunnerTest.test_ptestrunner_expectsuccess: SKIPPED (0.62s) SUMMARY: core-image-ptest-lua () - Ran 5 tests in 11.399s core-image-ptest-lua - OK - All required tests passed (successes=3, skipped=1, failures=0, errors=0) DEBUG: Python function do_testimage finished