DEBUG: Executing python function extend_recipe_sysroot NOTE: Direct dependencies are ['/home/pokybuild/yocto-worker/qemuarm64-ptest/build/meta/recipes-devtools/qemu/qemu-helper-native_1.0.bb:do_populate_sysroot', '/home/pokybuild/yocto-worker/qemuarm64-ptest/build/meta/recipes-devtools/qemu/qemu-native_7.2.0.bb:do_populate_sysroot', '/home/pokybuild/yocto-worker/qemuarm64-ptest/build/meta/recipes-devtools/qemu/qemu-system-native_7.2.0.bb:do_populate_sysroot', 'virtual:native:/home/pokybuild/yocto-worker/qemuarm64-ptest/build/meta/recipes-devtools/createrepo-c/createrepo-c_0.20.1.bb:do_populate_sysroot', 'virtual:native:/home/pokybuild/yocto-worker/qemuarm64-ptest/build/meta/recipes-devtools/dnf/dnf_4.14.0.bb:do_populate_sysroot', 'virtual:native:/home/pokybuild/yocto-worker/qemuarm64-ptest/build/meta/recipes-devtools/pseudo/pseudo_git.bb:do_populate_sysroot', 'virtual:native:/home/pokybuild/yocto-worker/qemuarm64-ptest/build/meta/recipes-devtools/unfs3/unfs3_git.bb:do_populate_sysroot', 'virtual:native:/home/pokybuild/yocto-worker/qemuarm64-ptest/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', 'gettext-minimal-native', 'cmake-native', 'librepo-native', 'python3-iniparse-native', 'libcomps-native', 'python3-native', 'libdnf-native', 'texinfo-dummy-native', 'libtool-native', 'flex-native', 'libtirpc-native', 'libepoxy-native', 'glib-2.0-native', 'xz-native', 'pixman-native', 'zlib-native', 'alsa-lib-native', 'libsdl2-native', 'dtc-native', 'libslirp-native', 'virglrenderer-native', 'sqlite3-native', 'libxml2-native', 'openssl-native', 'expat-native', 'curl-native', 'file-native', 'rpm-native', 'bzip2-native', 'zstd-native', 'ncurses-native', 'gpgme-native', 'attr-native', 'python3-build-native', 'python3-installer-native', 'python3-six-native', 'python3-setuptools-native', 'python3-wheel-native', 'libcheck-native', 'libffi-native', 'gdbm-native', 'libnsl2-native', 'readline-native', 'util-linux-libuuid-native', 'gobject-introspection-native', 'json-c-native', 'swig-native', 'util-linux-native', 'libmodulemd-native', 'libsolv-native', 'm4-native', 'libx11-native', 'mesa-native', 'libpcre2-native', 'gettext-native', 'libxrandr-native', 'libxext-native', 'libxrender-native', 'libdrm-native', 'perl-native', 'popt-native', 'libgcrypt-native', 'elfutils-native', 'lua-native', 'libgpg-error-native', 'libassuan-native', 'python3-pyproject-hooks-native', 'python3-flit-core-native', 'python3-packaging-native', 'libcap-ng-native', 'libyaml-native', 'gnu-config-native', 'xorgproto-native', 'libxcb-native', 'xtrans-native', 'util-macros-native', 'libxdamage-native', 'wayland-protocols-native', 'makedepend-native', 'xrandr-native', 'libxxf86vm-native', 'libxfixes-native', 'chrpath-native', 'python3-mako-native', 'wayland-native', 'llvm-native', 'libxshmfence-native', 'libpthread-stubs-native', 'libpciaccess-native', 'make-native', 'libcap-native', 'libmicrohttpd-native', 'libarchive-native', 'unzip-native', 'xcb-proto-native', 'libxau-native', 'libxdmcp-native', 'python3-markupsafe-native', 'python3-pygments-native', 'libedit-native', 'gnutls-native', 'nettle-native', 'gmp-native', 'libunistring-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/qemuarm64-ptest/build/build/tmp/.eh4bnnz2 DEBUG: Created listening socket for qemu serial console on: 127.0.0.1:52341 DEBUG: Created listening socket for qemu serial console on: 127.0.0.1:54339 DEBUG: launchcmd=runqemu snapshot kvm qemuarm64 /home/pokybuild/yocto-worker/qemuarm64-ptest/build/build/tmp/deploy/images/qemuarm64/core-image-ptest-python3-cryptography-qemuarm64.ext4 tcpserial=52341:54339 bootparams=" printk.time=1" qemuparams="-pidfile /home/pokybuild/yocto-worker/qemuarm64-ptest/build/build/pidfile_961496 -S -qmp unix:./.zbl5e6tz,server,wait -qmp unix:./.eh4bnnz2,server,nowait" DEBUG: runqemu started, pid is 961545 DEBUG: waiting at most 300 seconds for qemu pid (04/14/23 00:02:24) DEBUG: QMP Initializing to /home/pokybuild/yocto-worker/qemuarm64-ptest/build/build/tmp/.zbl5e6tz DEBUG: QMP Connecting to /home/pokybuild/yocto-worker/qemuarm64-ptest/build/build/tmp/.zbl5e6tz QMP connected to QEMU at 04/14/23 00:02:26 and took 1.64 seconds QMP released QEMU at 04/14/23 00:02:26 and took 0.28 seconds from connect DEBUG: qemu started in 1.92 seconds - qemu procces pid is 961587 (04/14/23 00:02:26) DEBUG: qemu cmdline used: /home/pokybuild/yocto-worker/qemuarm64-ptest/build/build/tmp/work/aarch64-linux/qemu-helper-native/1.0-r1/recipe-sysroot-native/usr/bin/qemu-system-aarch64 -device virtio-net-pci,netdev=net0,mac=52:54:00:12:34:0c -netdev tap,id=net0,ifname=tap5,script=no,downscript=no -object rng-random,filename=/dev/urandom,id=rng0 -device virtio-rng-pci,rng=rng0 -drive id=disk0,file=/home/pokybuild/tmp/core-image-ptest-python3-cryptography-qemuarm64.ext4.961546,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 4096 -device virtio-serial-pci -chardev socket,id=virtcon,port=52341,host=127.0.0.1 -device virtconsole,chardev=virtcon -serial tcp:127.0.0.1:54339 -pidfile /home/pokybuild/yocto-worker/qemuarm64-ptest/build/build/pidfile_961496 -S -qmp unix:./.zbl5e6tz,server,wait -qmp unix:./.eh4bnnz2,server,nowait -serial mon:vc -display sdl,show-cursor=on -device virtio-gpu-pci -kernel /home/pokybuild/yocto-worker/qemuarm64-ptest/build/build/tmp/deploy/images/qemuarm64/Image -append root=/dev/vda rw mem=4096M ip=192.168.7.12::192.168.7.11:255.255.255.0::eth0:off:8.8.8.8 console=ttyAMA0 console=hvc0 printk.time=1 DEBUG: Target IP: 192.168.7.12 DEBUG: Server IP: 192.168.7.11 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: [/home/pokybuild/yocto-worker/qemuarm64-ptest/build/build/tmp/deploy/images/qemuarm64/Image] MACHINE: [qemuarm64] FSTYPE: [ext4] ROOTFS: [/home/pokybuild/yocto-worker/qemuarm64-ptest/build/build/tmp/deploy/images/qemuarm64/core-image-ptest-python3-cryptography-qemuarm64.ext4] CONFFILE: [/home/pokybuild/yocto-worker/qemuarm64-ptest/build/build/tmp/deploy/images/qemuarm64/core-image-ptest-python3-cryptography-qemuarm64.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 - Using preconfigured tap device tap5 runqemu - INFO - If this is not intended, touch /tmp/qemu-tap-locks/tap5.skip to make runqemu skip tap5. runqemu - INFO - Network configuration: ip=192.168.7.12::192.168.7.11:255.255.255.0::eth0:off:8.8.8.8 runqemu - INFO - Copying rootfs to /home/pokybuild/tmp/core-image-ptest-python3-cryptography-qemuarm64.ext4.961546 runqemu - INFO - Copy done in 0.4519011974334717 seconds runqemu - INFO - Running /home/pokybuild/yocto-worker/qemuarm64-ptest/build/build/tmp/work/aarch64-linux/qemu-helper-native/1.0-r1/recipe-sysroot-native/usr/bin/qemu-system-aarch64 -device virtio-net-pci,netdev=net0,mac=52:54:00:12:34:0c -netdev tap,id=net0,ifname=tap5,script=no,downscript=no -object rng-random,filename=/dev/urandom,id=rng0 -device virtio-rng-pci,rng=rng0 -drive id=disk0,file=/home/pokybuild/tmp/core-image-ptest-python3-cryptography-qemuarm64.ext4.961546,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 4096 -device virtio-serial-pci -chardev socket,id=virtcon,port=52341,host=127.0.0.1 -device virtconsole,chardev=virtcon -serial tcp:127.0.0.1:54339 -pidfile /home/pokybuild/yocto-worker/qemuarm64-ptest/build/build/pidfile_961496 -S -qmp unix:./.zbl5e6tz,server,wait -qmp unix:./.eh4bnnz2,server,nowait -serial mon:vc -display sdl,show-cursor=on -device virtio-gpu-pci -kernel /home/pokybuild/yocto-worker/qemuarm64-ptest/build/build/tmp/deploy/images/qemuarm64/Image -append 'root=/dev/vda rw mem=4096M ip=192.168.7.12::192.168.7.11:255.255.255.0::eth0:off:8.8.8.8 console=ttyAMA0 console=hvc0 printk.time=1' runqemu - INFO - Host uptime: 244308.49 DEBUG: Waiting at most 1500 seconds for login banner (04/14/23 00:02:26) DEBUG: Connection from 127.0.0.1:48986 DEBUG: Reached login banner in 4.59 seconds (04/14/23 00:02:31) 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 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.12 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; uname -a DEBUG: Waiting for process output: time: 1681430554.417889, endtime: 1681430854.4086454 DEBUG: Partial data from SSH call: Linux qemuarm64 6.1.20-yocto-standard #1 SMP PREEMPT Sat Mar 18 02:48:04 UTC 2023 aarch64 GNU/Linux DEBUG: Waiting for process output: time: 1681430554.768181, endtime: 1681430854.768178 DEBUG: Data from SSH call: Linux qemuarm64 6.1.20-yocto-standard #1 SMP PREEMPT Sat Mar 18 02:48:04 UTC 2023 aarch64 GNU/Linux DEBUG: [Command returned '0' after 0.56 seconds] DEBUG: Command: uname -a Status: 0 Output: Linux qemuarm64 6.1.20-yocto-standard #1 SMP PREEMPT Sat Mar 18 02:48:04 UTC 2023 aarch64 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.12 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; cat /etc/controllerimage DEBUG: Waiting for process output: time: 1681430554.9809487, endtime: 1681430854.970481 DEBUG: Partial data from SSH call: cat: can't open '/etc/controllerimage': No such file or directory DEBUG: Waiting for process output: time: 1681430555.322382, endtime: 1681430855.3223794 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.12 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; which LSB_Test.sh DEBUG: Waiting for process output: time: 1681430555.5360425, endtime: 1681430855.5257168 DEBUG: Data from SSH call: DEBUG: [Command returned '1' after 0.36 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.12 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; dmesg > /tmp/dmesg_output.log DEBUG: Waiting for process output: time: 1681430555.8962212, endtime: 1681430855.8864193 DEBUG: Data from SSH call: DEBUG: [Command returned '0' after 0.36 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.12 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; test -f /var/log/ DEBUG: Waiting for process output: time: 1681430556.2556365, endtime: 1681430856.2457 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.12 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; test -d /var/log/ DEBUG: Waiting for process output: time: 1681430556.6142411, endtime: 1681430856.6040058 DEBUG: Data from SSH call: DEBUG: [Command returned '0' after 0.36 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.12 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; find /var/log//*.log -maxdepth 1 -type f DEBUG: Waiting for process output: time: 1681430556.9730754, endtime: 1681430856.9635558 DEBUG: Partial data from SSH call: /var/log//postinstall.log DEBUG: Waiting for process output: time: 1681430557.3193476, endtime: 1681430857.3193448 DEBUG: Data from SSH call: /var/log//postinstall.log DEBUG: [Command returned '0' after 0.56 seconds] DEBUG: Command: find /var/log//*.log -maxdepth 1 -type f Status: 0 Output: /var/log//postinstall.log DEBUG: [Running]$ ssh -l root -o ServerAliveCountMax=2 -o ServerAliveInterval=30 -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR 192.168.7.12 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; test -f /var/log/dmesg DEBUG: Waiting for process output: time: 1681430557.531569, endtime: 1681430857.5215847 DEBUG: Data from SSH call: DEBUG: [Command returned '0' after 0.36 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.12 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; test -f /tmp/dmesg_output.log DEBUG: Waiting for process output: time: 1681430557.8893337, endtime: 1681430857.8795636 DEBUG: Data from SSH call: DEBUG: [Command returned '0' after 0.36 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.12:/var/log//postinstall.log /home/pokybuild/yocto-worker/qemuarm64-ptest/build/build/tmp/work/qemuarm64-poky-linux/core-image-ptest-python3-cryptography/1.0-r0/target_logs DEBUG: Data 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 root@192.168.7.12:/var/log/dmesg /home/pokybuild/yocto-worker/qemuarm64-ptest/build/build/tmp/work/qemuarm64-poky-linux/core-image-ptest-python3-cryptography/1.0-r0/target_logs DEBUG: Data from SSH call: DEBUG: [Command returned '0' after 0.16 seconds] DEBUG: [Running]$ scp -o ServerAliveCountMax=2 -o ServerAliveInterval=30 -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR root@192.168.7.12:/tmp/dmesg_output.log /home/pokybuild/yocto-worker/qemuarm64-ptest/build/build/tmp/work/qemuarm64-poky-linux/core-image-ptest-python3-cryptography/1.0-r0/target_logs DEBUG: Data from SSH call: DEBUG: [Command returned '0' after 0.17 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.12 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: 1681430558.8095207, endtime: 1681430858.799078 DEBUG: Data from SSH call: DEBUG: [Command returned '0' after 0.35 seconds] DEBUG: Command: cat /proc/cpuinfo | grep "model name" | head -n1 | awk 'BEGIN{FS=":"}{print $2}' 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.12 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: 1681430559.1645873, endtime: 1681430859.1545782 DEBUG: Data from SSH call: DEBUG: [Command returned '0' after 0.36 seconds] DEBUG: Command: cat /proc/cpuinfo | grep "cpu cores" | head -n1 | awk {'print $4'} 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.12 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; cat /proc/cpuinfo | grep "processor" | wc -l DEBUG: Waiting for process output: time: 1681430559.5282032, endtime: 1681430859.5183496 DEBUG: Partial data from SSH call: 4 DEBUG: Waiting for process output: time: 1681430559.875447, endtime: 1681430859.875444 DEBUG: Data from SSH call: 4 DEBUG: [Command returned '0' after 0.56 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.12 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; uname -m DEBUG: Waiting for process output: time: 1681430560.0877495, endtime: 1681430860.0776594 DEBUG: Partial data from SSH call: aarch64 DEBUG: Waiting for process output: time: 1681430560.4344978, endtime: 1681430860.434494 DEBUG: Data from SSH call: aarch64 DEBUG: [Command returned '0' after 0.56 seconds] DEBUG: Command: uname -m Status: 0 Output: aarch64 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.12 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.16 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.12 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-14T00:02 BEGIN: /usr/lib/python3-cryptography/ptest Free memory: 3.840 GB Traceback (most recent call last): File "/usr/bin/pytest", line 8, in sys.exit(console_main()) ^^^^^^^^^^^^^^ File "/usr/lib/python3.11/site-packages/_pytest/config/__init__.py", line 190, in console_main code = main() ^^^^^^ File "/usr/lib/python3.11/site-packages/_pytest/config/__init__.py", line 148, in main config = _prepareconfig(args, plugins) ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/usr/lib/python3.11/site-packages/_pytest/config/__init__.py", line 329, in _prepareconfig config = pluginmanager.hook.pytest_cmdline_parse( ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/usr/lib/python3.11/site-packages/pluggy/_hooks.py", line 265, in __call__ return self._hookexec(self.name, self.get_hookimpls(), kwargs, firstresult) ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/usr/lib/python3.11/site-packages/pluggy/_manager.py", line 80, in _hookexec return self._inner_hookexec(hook_name, methods, kwargs, firstresult) ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/usr/lib/python3.11/site-packages/pluggy/_callers.py", line 55, in _multicall gen.send(outcome) File "/usr/lib/python3.11/site-packages/_pytest/helpconfig.py", line 103, in pytest_cmdline_parse config: Config = outcome.get_result() ^^^^^^^^^^^^^^^^^^^^ File "/usr/lib/python3.11/site-packages/pluggy/_result.py", line 60, in get_result raise ex[1].with_traceback(ex[2]) File "/usr/lib/python3.11/site-packages/pluggy/_callers.py", line 39, in _multicall res = hook_impl.function(*args) ^^^^^^^^^^^^^^^^^^^^^^^^^ File "/usr/lib/python3.11/site-packages/_pytest/config/__init__.py", line 1060, in pytest_cmdline_parse self.parse(args) File "/usr/lib/python3.11/site-packages/_pytest/config/__init__.py", line 1348, in parse self._preparse(args, addopts=addopts) File "/usr/lib/python3.11/site-packages/_pytest/config/__init__.py", line 1216, in _preparse self._initini(args) File "/usr/lib/python3.11/site-packages/_pytest/config/__init__.py", line 1135, in _initini rootpath, inipath, inicfg = determine_setup( ^^^^^^^^^^^^^^^^ File "/usr/lib/python3.11/site-packages/_pytest/config/findpaths.py", line 191, in determine_setup rootdir, inipath, inicfg = locate_config([ancestor]) ^^^^^^^^^^^^^^^^^^^^^^^^^ File "/usr/lib/python3.11/site-packages/_pytest/config/findpaths.py", line 113, in locate_config ini_config = load_config_dict_from_file(p) ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/usr/lib/python3.11/site-packages/_pytest/config/findpaths.py", line 69, in load_config_dict_from_file import tomllib ModuleNotFoundError: No module named 'tomllib' ERROR: Exit status is 1 DURATION: 1 END: /usr/lib/python3-cryptography/ptest 2023-04-14T00:02 STOP: ptest-runner TOTAL: 1 FAIL: 1 DEBUG: [Command returned '1' after 1.02 seconds] DEBUG: Command: ptest-runner -t 450 -d "/usr/lib" Status: 1 Output: START: ptest-runner 2023-04-14T00:02 BEGIN: /usr/lib/python3-cryptography/ptest Free memory: 3.840 GB Traceback (most recent call last): File "/usr/bin/pytest", line 8, in sys.exit(console_main()) ^^^^^^^^^^^^^^ File "/usr/lib/python3.11/site-packages/_pytest/config/__init__.py", line 190, in console_main code = main() ^^^^^^ File "/usr/lib/python3.11/site-packages/_pytest/config/__init__.py", line 148, in main config = _prepareconfig(args, plugins) ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/usr/lib/python3.11/site-packages/_pytest/config/__init__.py", line 329, in _prepareconfig config = pluginmanager.hook.pytest_cmdline_parse( ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/usr/lib/python3.11/site-packages/pluggy/_hooks.py", line 265, in __call__ return self._hookexec(self.name, self.get_hookimpls(), kwargs, firstresult) ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/usr/lib/python3.11/site-packages/pluggy/_manager.py", line 80, in _hookexec return self._inner_hookexec(hook_name, methods, kwargs, firstresult) ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/usr/lib/python3.11/site-packages/pluggy/_callers.py", line 55, in _multicall gen.send(outcome) File "/usr/lib/python3.11/site-packages/_pytest/helpconfig.py", line 103, in pytest_cmdline_parse config: Config = outcome.get_result() ^^^^^^^^^^^^^^^^^^^^ File "/usr/lib/python3.11/site-packages/pluggy/_result.py", line 60, in get_result raise ex[1].with_traceback(ex[2]) File "/usr/lib/python3.11/site-packages/pluggy/_callers.py", line 39, in _multicall res = hook_impl.function(*args) ^^^^^^^^^^^^^^^^^^^^^^^^^ File "/usr/lib/python3.11/site-packages/_pytest/config/__init__.py", line 1060, in pytest_cmdline_parse self.parse(args) File "/usr/lib/python3.11/site-packages/_pytest/config/__init__.py", line 1348, in parse self._preparse(args, addopts=addopts) File "/usr/lib/python3.11/site-packages/_pytest/config/__init__.py", line 1216, in _preparse self._initini(args) File "/usr/lib/python3.11/site-packages/_pytest/config/__init__.py", line 1135, in _initini rootpath, inipath, inicfg = determine_setup( ^^^^^^^^^^^^^^^^ File "/usr/lib/python3.11/site-packages/_pytest/config/findpaths.py", line 191, in determine_setup rootdir, inipath, inicfg = locate_config([ancestor]) ^^^^^^^^^^^^^^^^^^^^^^^^^ File "/usr/lib/python3.11/site-packages/_pytest/config/findpaths.py", line 113, in locate_config ini_config = load_config_dict_from_file(p) ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/usr/lib/python3.11/site-packages/_pytest/config/findpaths.py", line 69, in load_config_dict_from_file import tomllib ModuleNotFoundError: No module named 'tomllib' ERROR: Exit status is 1 DURATION: 1 END: /usr/lib/python3-cryptography/ptest 2023-04-14T00:02 STOP: ptest-runner TOTAL: 1 FAIL: 1 DEBUG: [Running]$ ssh -l root -o ServerAliveCountMax=2 -o ServerAliveInterval=30 -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR 192.168.7.12 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; dmesg | grep "Killed process" DEBUG: Data from SSH call: DEBUG: [Command returned '1' after 0.17 seconds] DEBUG: Command: dmesg | grep "Killed process" Status: 1 Output: WARNING: There were failing ptests. NOTE: ... expected failure Traceback (most recent call last): File "/home/pokybuild/yocto-worker/qemuarm64-ptest/build/meta/lib/oeqa/core/decorator/__init__.py", line 35, in wrapped_f return func(*args, **kwargs) File "/home/pokybuild/yocto-worker/qemuarm64-ptest/build/meta/lib/oeqa/core/decorator/__init__.py", line 35, in wrapped_f return func(*args, **kwargs) File "/home/pokybuild/yocto-worker/qemuarm64-ptest/build/meta/lib/oeqa/core/decorator/__init__.py", line 35, in wrapped_f return func(*args, **kwargs) File "/home/pokybuild/yocto-worker/qemuarm64-ptest/build/meta/lib/oeqa/runtime/cases/ptest.py", line 27, in test_ptestrunner_expectfail self.do_ptestrunner() File "/home/pokybuild/yocto-worker/qemuarm64-ptest/build/meta/lib/oeqa/runtime/cases/ptest.py", line 114, in do_ptestrunner self.fail(failmsg) AssertionError: Failed ptests: {'python3-cryptography': 'START: ptest-runner\n' '2023-04-14T00:02\n' 'Free memory: 3.840 GB\n' 'Traceback (most recent call last):\n' ' File "/usr/bin/pytest", line 8, in \n' ' sys.exit(console_main())\n' ' ^^^^^^^^^^^^^^\n' ' File ' '"/usr/lib/python3.11/site-packages/_pytest/config/__init__.py", ' 'line 190, in console_main\n' ' code = main()\n' ' ^^^^^^\n' ' File ' '"/usr/lib/python3.11/site-packages/_pytest/config/__init__.py", ' 'line 148, in main\n' ' config = _prepareconfig(args, plugins)\n' ' ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^\n' ' File ' '"/usr/lib/python3.11/site-packages/_pytest/config/__init__.py", ' 'line 329, in _prepareconfig\n' ' config = ' 'pluginmanager.hook.pytest_cmdline_parse(\n' ' ' '^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^\n' ' File ' '"/usr/lib/python3.11/site-packages/pluggy/_hooks.py", ' 'line 265, in __call__\n' ' return self._hookexec(self.name, ' 'self.get_hookimpls(), kwargs, firstresult)\n' ' ' '^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^\n' ' File ' '"/usr/lib/python3.11/site-packages/pluggy/_manager.py", ' 'line 80, in _hookexec\n' ' return self._inner_hookexec(hook_name, methods, ' 'kwargs, firstresult)\n' ' ' '^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^\n' ' File ' '"/usr/lib/python3.11/site-packages/pluggy/_callers.py", ' 'line 55, in _multicall\n' ' gen.send(outcome)\n' ' File ' '"/usr/lib/python3.11/site-packages/_pytest/helpconfig.py", ' 'line 103, in pytest_cmdline_parse\n' ' config: Config = outcome.get_result()\n' ' ^^^^^^^^^^^^^^^^^^^^\n' ' File ' '"/usr/lib/python3.11/site-packages/pluggy/_result.py", ' 'line 60, in get_result\n' ' raise ex[1].with_traceback(ex[2])\n' ' File ' '"/usr/lib/python3.11/site-packages/pluggy/_callers.py", ' 'line 39, in _multicall\n' ' res = hook_impl.function(*args)\n' ' ^^^^^^^^^^^^^^^^^^^^^^^^^\n' ' File ' '"/usr/lib/python3.11/site-packages/_pytest/config/__init__.py", ' 'line 1060, in pytest_cmdline_parse\n' ' self.parse(args)\n' ' File ' '"/usr/lib/python3.11/site-packages/_pytest/config/__init__.py", ' 'line 1348, in parse\n' ' self._preparse(args, addopts=addopts)\n' ' File ' '"/usr/lib/python3.11/site-packages/_pytest/config/__init__.py", ' 'line 1216, in _preparse\n' ' self._initini(args)\n' ' File ' '"/usr/lib/python3.11/site-packages/_pytest/config/__init__.py", ' 'line 1135, in _initini\n' ' rootpath, inipath, inicfg = determine_setup(\n' ' ^^^^^^^^^^^^^^^^\n' ' File ' '"/usr/lib/python3.11/site-packages/_pytest/config/findpaths.py", ' 'line 191, in determine_setup\n' ' rootdir, inipath, inicfg = ' 'locate_config([ancestor])\n' ' ' '^^^^^^^^^^^^^^^^^^^^^^^^^\n' ' File ' '"/usr/lib/python3.11/site-packages/_pytest/config/findpaths.py", ' 'line 113, in locate_config\n' ' ini_config = load_config_dict_from_file(p)\n' ' ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^\n' ' File ' '"/usr/lib/python3.11/site-packages/_pytest/config/findpaths.py", ' 'line 69, in load_config_dict_from_file\n' ' import tomllib\n' "ModuleNotFoundError: No module named 'tomllib'\n" '\n' 'ERROR: Exit status is 1\n' 'DURATION: 1\n'} 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 7.641s NOTE: OK NOTE: (skipped=1, expected failures=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: 244330.14 runqemu - INFO - Removing /home/pokybuild/tmp/core-image-ptest-python3-cryptography-qemuarm64.ext4.961546 tput: No value for $TERM and no -T specified RESULTS: RESULTS - parselogs.ParseLogsTest.test_parselogs: PASSED (4.75s) RESULTS - ping.PingTest.test_ping: PASSED (0.05s) RESULTS - ssh.SSHTest.test_ssh: PASSED (1.12s) RESULTS - ptest.PtestRunnerTest.test_ptestrunner_expectsuccess: SKIPPED (0.00s) RESULTS - ptest.PtestRunnerTest.test_ptestrunner_expectfail: EXPECTEDFAIL (1.36s) SUMMARY: core-image-ptest-python3-cryptography () - Ran 5 tests in 7.643s core-image-ptest-python3-cryptography - OK - All required tests passed (successes=3, skipped=1, failures=0, errors=0) /home/pokybuild/yocto-worker/qemuarm64-ptest/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