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', 'rpm-native', 'openssl-native', 'curl-native', 'sqlite3-native', 'zlib-native', 'python3-native', 'glib-2.0-native', 'expat-native', 'xz-native', 'file-native', 'cmake-native', 'libxml2-native', 'python3-iniparse-native', 'libdnf-native', 'librepo-native', 'gettext-minimal-native', 'libcomps-native', 'libtirpc-native', 'libtool-native', 'flex-native', 'texinfo-dummy-native', 'virglrenderer-native', 'libsdl2-native', 'libepoxy-native', 'pixman-native', 'dtc-native', 'libslirp-native', 'alsa-lib-native', 'zstd-native', 'lua-native', 'libgcrypt-native', 'popt-native', 'elfutils-native', 'bzip2-native', 'perl-native', 'ncurses-native', 'util-linux-libuuid-native', 'gdbm-native', 'libffi-native', 'libnsl2-native', 'readline-native', 'gettext-native', 'libpcre2-native', 'util-linux-native', 'python3-installer-native', 'python3-wheel-native', 'python3-build-native', 'python3-setuptools-native', 'python3-six-native', 'libsolv-native', 'gobject-introspection-native', 'libmodulemd-native', 'json-c-native', 'swig-native', 'libcheck-native', 'gpgme-native', 'attr-native', 'm4-native', 'mesa-native', 'libdrm-native', 'libxrender-native', 'libxext-native', 'libx11-native', 'libxrandr-native', 'libcap-native', 'libgpg-error-native', 'libarchive-native', 'libmicrohttpd-native', 'make-native', 'libcap-ng-native', 'python3-flit-core-native', 'python3-packaging-native', 'python3-pyproject-hooks-native', 'libyaml-native', 'libassuan-native', 'gnu-config-native', 'llvm-native', 'wayland-native', 'makedepend-native', 'xorgproto-native', 'chrpath-native', 'libxfixes-native', 'xrandr-native', 'wayland-protocols-native', 'python3-mako-native', 'libxxf86vm-native', 'libxshmfence-native', 'libxdamage-native', 'libpthread-stubs-native', 'libpciaccess-native', 'util-macros-native', 'xtrans-native', 'libxcb-native', 'gnutls-native', 'unzip-native', 'libedit-native', 'python3-markupsafe-native', 'python3-pygments-native', 'xcb-proto-native', 'libxdmcp-native', 'libxau-native', 'libunistring-native', 'nettle-native', 'libidn2-native', 'gmp-native'] DEBUG: Python function extend_recipe_sysroot finished DEBUG: Executing python function do_testimage DEBUG: test suites: test_ping (ping.PingTest.test_ping) test_ssh (ssh.SSHTest.test_ssh) test_parselogs (parselogs.ParseLogsTest.test_parselogs) test_ptestrunner_expectfail (ptest.PtestRunnerTest.test_ptestrunner_expectfail) test_ptestrunner_expectsuccess (ptest.PtestRunnerTest.test_ptestrunner_expectsuccess) DEBUG: Using kvm for runqemu QMP Available for connection at /home/pokybuild/yocto-worker/qemux86-64-ptest-fast/build/build/tmp/.arxgozff DEBUG: Created listening socket for qemu serial console on: 127.0.0.1:50431 DEBUG: Created listening socket for qemu serial console on: 127.0.0.1:42965 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-findutils-qemux86-64.ext4 tcpserial=50431:42965 bootparams=" printk.time=1" qemuparams="-pidfile /home/pokybuild/yocto-worker/qemux86-64-ptest-fast/build/build/pidfile_2574702 -S -qmp unix:./.6wwisugq,server,wait -qmp unix:./.arxgozff,server,nowait" DEBUG: runqemu started, pid is 2574714 DEBUG: waiting at most 300 seconds for qemu pid (03/31/23 01:31:40) DEBUG: QMP Initializing to /home/pokybuild/yocto-worker/qemux86-64-ptest-fast/build/build/tmp/.6wwisugq DEBUG: QMP Connecting to /home/pokybuild/yocto-worker/qemux86-64-ptest-fast/build/build/tmp/.6wwisugq QMP connected to QEMU at 03/31/23 01:31:41 and took 0.5629220008850098 seconds QMP released QEMU at 03/31/23 01:31:41 and took 0.12624502182006836 seconds from connect DEBUG: qemu started in 0.6878283023834229 seconds - qemu procces pid is 2574733 (03/31/23 01:31:41) 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-findutils-qemux86-64.ext4.2574714,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:50431 -serial tcp:127.0.0.1:42965 -pidfile /home/pokybuild/yocto-worker/qemux86-64-ptest-fast/build/build/pidfile_2574702 -S -qmp unix:./.6wwisugq,server,wait -qmp unix:./.arxgozff,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-findutils-qemux86-64.ext4] CONFFILE: [/home/pokybuild/yocto-worker/qemux86-64-ptest-fast/build/build/tmp/deploy/images/qemux86-64/core-image-ptest-findutils-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-findutils-qemux86-64.ext4.2574714 runqemu - INFO - Copy done in 0.2876155376434326 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-findutils-qemux86-64.ext4.2574714,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:50431 -serial tcp:127.0.0.1:42965 -pidfile /home/pokybuild/yocto-worker/qemux86-64-ptest-fast/build/build/pidfile_2574702 -S -qmp unix:./.6wwisugq,server,wait -qmp unix:./.arxgozff,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: 435971.63 DEBUG: Waiting at most 1500 seconds for login banner (03/31/23 01:31:41) DEBUG: Connection from 127.0.0.1:60158 /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 2.434840202331543 seconds (03/31/23 01:31:43, 1680226303.7470696) DEBUG: Logged as root in serial console NOTE: test_ping (ping.PingTest.test_ping) DEBUG: Setting up a 30 second(s) timeout DEBUG: Removed SIGALRM handler NOTE: ... ok NOTE: test_ssh (ssh.SSHTest.test_ssh) DEBUG: Checking if at least one of 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: 1680226306.9704854, endtime: 1680226606.9482071 DEBUG: Partial data from SSH call: Linux qemux86-64 6.1.20-yocto-standard #1 SMP PREEMPT_DYNAMIC Sat Mar 18 02:48:04 UTC 2023 x86_64 GNU/Linux DEBUG: Waiting for process output: time: 1680226307.3886342, endtime: 1680226607.3886318 DEBUG: Data from SSH call: Linux qemux86-64 6.1.20-yocto-standard #1 SMP PREEMPT_DYNAMIC Sat Mar 18 02:48:04 UTC 2023 x86_64 GNU/Linux DEBUG: [Command returned '0' after 0.65 seconds] DEBUG: Command: uname -a Status: 0 Output: Linux qemux86-64 6.1.20-yocto-standard #1 SMP PREEMPT_DYNAMIC Sat Mar 18 02:48:04 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: 1680226307.6135957, endtime: 1680226607.5943666 DEBUG: Partial data from SSH call: cat: /etc/controllerimage: No such file or directory DEBUG: Waiting for process output: time: 1680226307.956675, endtime: 1680226607.9566724 DEBUG: Data from SSH call: cat: /etc/controllerimage: No such file or directory DEBUG: [Command returned '1' after 0.56 seconds] DEBUG: Command: cat /etc/controllerimage Status: 1 Output: cat: /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: 1680226308.1664886, endtime: 1680226608.158718 DEBUG: Data from SSH call: DEBUG: [Command returned '1' after 0.41 seconds] DEBUG: Command: which LSB_Test.sh Status: 1 Output: NOTE: test_parselogs (parselogs.ParseLogsTest.test_parselogs) DEBUG: [Running]$ ssh -l root -o ServerAliveCountMax=2 -o ServerAliveInterval=30 -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR 192.168.7.32 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; dmesg > /tmp/dmesg_output.log DEBUG: Waiting for process output: time: 1680226308.597482, endtime: 1680226608.5737555 DEBUG: Data from SSH call: DEBUG: [Command returned '0' after 0.46 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: 1680226309.0395896, endtime: 1680226609.0333378 DEBUG: Data from SSH call: DEBUG: [Command returned '1' after 0.41 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: 1680226309.4654968, endtime: 1680226609.4417481 DEBUG: Data from SSH call: DEBUG: [Command returned '0' after 0.46 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: 1680226309.9473233, endtime: 1680226609.9056866 DEBUG: Partial data from SSH call: find: '/var/log//*.log': No such file or directory DEBUG: Waiting for process output: time: 1680226310.3459017, endtime: 1680226610.3458989 DEBUG: Data from SSH call: find: '/var/log//*.log': No such file or directory DEBUG: [Command returned '1' after 0.64 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: 1680226310.5732005, endtime: 1680226610.5504699 DEBUG: Data from SSH call: DEBUG: [Command returned '0' after 0.38 seconds] DEBUG: Command: test -f /var/log/dmesg Status: 0 Output: DEBUG: [Running]$ ssh -l root -o ServerAliveCountMax=2 -o ServerAliveInterval=30 -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR 192.168.7.32 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; test -f /tmp/dmesg_output.log DEBUG: Waiting for process output: time: 1680226310.9586632, endtime: 1680226610.9293923 DEBUG: Data from SSH call: DEBUG: [Command returned '0' after 0.42 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-findutils/1.0-r0/target_logs DEBUG: Data from SSH call: DEBUG: [Command returned '0' after 0.27 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-findutils/1.0-r0/target_logs DEBUG: Data from SSH call: DEBUG: [Command returned '0' after 0.15 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: 1680226311.811679, endtime: 1680226611.8057182 DEBUG: Partial data from SSH call: Intel Xeon E3-12xx v2 (Ivy Bridge) DEBUG: Waiting for process output: time: 1680226312.14824, endtime: 1680226612.1482375 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: 1680226312.350306, endtime: 1680226612.3493788 DEBUG: Partial data from SSH call: 4 DEBUG: Waiting for process output: time: 1680226312.6817124, endtime: 1680226612.6817098 DEBUG: Data from SSH call: 4 DEBUG: [Command returned '0' after 0.53 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: 1680226312.8840854, endtime: 1680226612.8830142 DEBUG: Partial data from SSH call: 4 DEBUG: Waiting for process output: time: 1680226313.2121024, endtime: 1680226613.2121 DEBUG: Data from SSH call: 4 DEBUG: [Command returned '0' after 0.53 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: 1680226313.4137313, endtime: 1680226613.412997 DEBUG: Partial data from SSH call: x86_64 DEBUG: Waiting for process output: time: 1680226313.742545, endtime: 1680226613.7425432 DEBUG: Data from SSH call: x86_64 DEBUG: [Command returned '0' after 0.53 seconds] DEBUG: Command: uname -m Status: 0 Output: x86_64 NOTE: ... ok NOTE: test_ptestrunner_expectfail (ptest.PtestRunnerTest.test_ptestrunner_expectfail) DEBUG: Checking if at least one of ptest-runner is installed DEBUG: Checking if ptest is in DISTRO_FEATURES or IMAGE_FEATURES DEBUG: [Running]$ ssh -l root -o ServerAliveCountMax=2 -o ServerAliveInterval=30 -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR 192.168.7.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.12 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-03-31T01:31 BEGIN: /usr/lib/findutils/ptest find: Missing argument after the -D option. PASS: tests/find/debug-missing-arg.sh SKIP: tests/find/depth-unreadable-dir.sh PASS: tests/find/exec-plus-last-file.sh PASS: tests/find/execdir-fd-leak.sh find: missing argument to `-files0-from' find: extra operand 'OFFENDING' find: file operands cannot be combined with -files0-from find: option -files0-from reading from standard input cannot be combined with -ok, -okdir find: option -files0-from reading from standard input cannot be combined with -ok, -okdir find: option -files0-from: standard input must not refer to the same file when combined with -ok, -okdir: 'in' find: option -files0-from: standard input must not refer to the same file when combined with -ok, -okdir: 'inlink' find: option -files0-from: standard input must not refer to the same file when combined with -ok, -okdir: 'in' find: option -files0-from: standard input must not refer to the same file when combined with -ok, -okdir: '/dev/stdin' find: cannot open 'ENOENT' for reading: No such file or directory cat: /: Is a directory find: '/': read error: Is a directory find: invalid expression: expected expression before closing parentheses ')'. find: invalid expression: expected expression before closing parentheses ')'. find: '(standard input)':2: invalid zero-length file name find: 'file':2: invalid zero-length file name find: 'ENOENT': No such file or directory exp out PASS: tests/find/files0-from.sh inode-zero.sh: skipped test: no file with inode number 0 here SKIP: tests/find/inode-zero.sh many-dir-entries-vs-OOM.sh: skipped test: too few free inodes on '.': 91263; this test requires at least 240000 SKIP: tests/find/many-dir-entries-vs-OOM.sh PASS: tests/find/name-lbracket-literal.sh PASS: tests/find/printf_escape_c.sh PASS: tests/find/printf_escapechars.sh PASS: tests/find/printf_inode.sh find: unknown predicate `-noop' find: unknown predicate `---noop' PASS: tests/find/refuse-noop.sh find: -type D is not supported because Solaris doors are not supported on the platform find was compiled on. tests/find/type_list.sh: line 59: perl: command not found tests/find/type_list.sh: line 64: python: command not found nc: bad port 'sock' tests/find/type_list.sh: line 72: kill: (1470) - No such process tests/find/type_list.sh: line 74: netcat: command not found tests/find/type_list.sh: line 75: kill: (1472) - No such process tests/find/type_list.sh: line 78: socat: command not found tests/find/type_list.sh: line 79: kill: (1474) - No such process 1114 0 lrwxrwxrwx 1 root root 3 Mar 31 01:31 dir/blk-link -> blk 1112 0 lrwxrwxrwx 1 root root 3 Mar 31 01:31 dir/reg-link -> reg 1116 0 lrwxrwxrwx 1 root root 4 Mar 31 01:31 dir/fifo-link -> fifo 1111 0 prw-r--r-- 1 root root 0 Mar 31 01:31 dir/fifo 1115 0 lrwxrwxrwx 1 root root 3 Mar 31 01:31 dir/chr-link -> chr 1110 0 crw-r--r-- 1 root root 0, 0 Mar 31 01:31 dir/chr 1117 0 lrwxrwxrwx 1 root root 6 Mar 31 01:31 dir/dangling-link -> enoent 1113 0 lrwxrwxrwx 1 root root 3 Mar 31 01:31 dir/dir-link -> dir 1107 0 -rw-r--r-- 1 root root 0 Mar 31 01:31 dir/reg 1109 0 brw-r--r-- 1 root root 0, 0 Mar 31 01:31 dir/blk 1108 1 drwxr-xr-x 2 root root 1024 Mar 31 01:31 dir/dir find: Arguments to -type should contain at least one letter find: Arguments to -xtype should contain at least one letter find: Must separate multiple arguments to -type using: ',' find: Must separate multiple arguments to -xtype using: ',' find: Last file type in list argument to -type is missing, i.e., list is ending on: ',' find: Last file type in list argument to -xtype is missing, i.e., list is ending on: ',' find: Duplicate file type 'f' in the argument list to -type. find: Duplicate file type 'f' in the argument list to -xtype. PASS: tests/find/type_list.sh Name: t00 Access: 2023-03-31 01:31:58.350000000 +0000 Change: 2023-03-31 01:31:58.350000000 +0000 Name: t10 Access: 2023-04-10 01:31:58.000000000 +0000 Change: 2023-03-31 01:31:58.318000000 +0000 Name: t20 Access: 2023-04-20 01:31:58.000000000 +0000 Change: 2023-03-31 01:31:58.326000000 +0000 Name: t30 Access: 2023-04-30 01:31:58.000000000 +0000 Change: 2023-03-31 01:31:58.334000000 +0000 Name: t40 Access: 2023-05-10 01:31:58.000000000 +0000 Change: 2023-03-31 01:31:58.342000000 +0000 PASS: tests/find/used.sh PATH=/tmp:/usr/sbin:/sbin:/usr/bin:/bin ++ env find --version ++ sed -n '1s/.* //p;q' ++ echo find ++ sed 's/$//' ++ echo xargs ++ sed 's/$//' ++ echo locate ++ sed 's/$//' ++ sed 's/$//' ++ echo updatedb PASS: tests/misc/help-version.sh PASS: tests/xargs/conflicting_opts.sh PASS: tests/xargs/verbose-quote.sh DURATION: 6 END: /usr/lib/findutils/ptest 2023-03-31T01:31 STOP: ptest-runner TOTAL: 1 FAIL: 0 DEBUG: [Command returned '0' after 6.06 seconds] DEBUG: Command: ptest-runner -t 450 -d "/usr/lib" Status: 0 Output: START: ptest-runner 2023-03-31T01:31 BEGIN: /usr/lib/findutils/ptest find: Missing argument after the -D option. PASS: tests/find/debug-missing-arg.sh SKIP: tests/find/depth-unreadable-dir.sh PASS: tests/find/exec-plus-last-file.sh PASS: tests/find/execdir-fd-leak.sh find: missing argument to `-files0-from' find: extra operand 'OFFENDING' find: file operands cannot be combined with -files0-from find: option -files0-from reading from standard input cannot be combined with -ok, -okdir find: option -files0-from reading from standard input cannot be combined with -ok, -okdir find: option -files0-from: standard input must not refer to the same file when combined with -ok, -okdir: 'in' find: option -files0-from: standard input must not refer to the same file when combined with -ok, -okdir: 'inlink' find: option -files0-from: standard input must not refer to the same file when combined with -ok, -okdir: 'in' find: option -files0-from: standard input must not refer to the same file when combined with -ok, -okdir: '/dev/stdin' find: cannot open 'ENOENT' for reading: No such file or directory cat: /: Is a directory find: '/': read error: Is a directory find: invalid expression: expected expression before closing parentheses ')'. find: invalid expression: expected expression before closing parentheses ')'. find: '(standard input)':2: invalid zero-length file name find: 'file':2: invalid zero-length file name find: 'ENOENT': No such file or directory exp out PASS: tests/find/files0-from.sh inode-zero.sh: skipped test: no file with inode number 0 here SKIP: tests/find/inode-zero.sh many-dir-entries-vs-OOM.sh: skipped test: too few free inodes on '.': 91263; this test requires at least 240000 SKIP: tests/find/many-dir-entries-vs-OOM.sh PASS: tests/find/name-lbracket-literal.sh PASS: tests/find/printf_escape_c.sh PASS: tests/find/printf_escapechars.sh PASS: tests/find/printf_inode.sh find: unknown predicate `-noop' find: unknown predicate `---noop' PASS: tests/find/refuse-noop.sh find: -type D is not supported because Solaris doors are not supported on the platform find was compiled on. tests/find/type_list.sh: line 59: perl: command not found tests/find/type_list.sh: line 64: python: command not found nc: bad port 'sock' tests/find/type_list.sh: line 72: kill: (1470) - No such process tests/find/type_list.sh: line 74: netcat: command not found tests/find/type_list.sh: line 75: kill: (1472) - No such process tests/find/type_list.sh: line 78: socat: command not found tests/find/type_list.sh: line 79: kill: (1474) - No such process 1114 0 lrwxrwxrwx 1 root root 3 Mar 31 01:31 dir/blk-link -> blk 1112 0 lrwxrwxrwx 1 root root 3 Mar 31 01:31 dir/reg-link -> reg 1116 0 lrwxrwxrwx 1 root root 4 Mar 31 01:31 dir/fifo-link -> fifo 1111 0 prw-r--r-- 1 root root 0 Mar 31 01:31 dir/fifo 1115 0 lrwxrwxrwx 1 root root 3 Mar 31 01:31 dir/chr-link -> chr 1110 0 crw-r--r-- 1 root root 0, 0 Mar 31 01:31 dir/chr 1117 0 lrwxrwxrwx 1 root root 6 Mar 31 01:31 dir/dangling-link -> enoent 1113 0 lrwxrwxrwx 1 root root 3 Mar 31 01:31 dir/dir-link -> dir 1107 0 -rw-r--r-- 1 root root 0 Mar 31 01:31 dir/reg 1109 0 brw-r--r-- 1 root root 0, 0 Mar 31 01:31 dir/blk 1108 1 drwxr-xr-x 2 root root 1024 Mar 31 01:31 dir/dir find: Arguments to -type should contain at least one letter find: Arguments to -xtype should contain at least one letter find: Must separate multiple arguments to -type using: ',' find: Must separate multiple arguments to -xtype using: ',' find: Last file type in list argument to -type is missing, i.e., list is ending on: ',' find: Last file type in list argument to -xtype is missing, i.e., list is ending on: ',' find: Duplicate file type 'f' in the argument list to -type. find: Duplicate file type 'f' in the argument list to -xtype. PASS: tests/find/type_list.sh Name: t00 Access: 2023-03-31 01:31:58.350000000 +0000 Change: 2023-03-31 01:31:58.350000000 +0000 Name: t10 Access: 2023-04-10 01:31:58.000000000 +0000 Change: 2023-03-31 01:31:58.318000000 +0000 Name: t20 Access: 2023-04-20 01:31:58.000000000 +0000 Change: 2023-03-31 01:31:58.326000000 +0000 Name: t30 Access: 2023-04-30 01:31:58.000000000 +0000 Change: 2023-03-31 01:31:58.334000000 +0000 Name: t40 Access: 2023-05-10 01:31:58.000000000 +0000 Change: 2023-03-31 01:31:58.342000000 +0000 PASS: tests/find/used.sh PATH=/tmp:/usr/sbin:/sbin:/usr/bin:/bin ++ env find --version ++ sed -n '1s/.* //p;q' ++ echo find ++ sed 's/$//' ++ echo xargs ++ sed 's/$//' ++ echo locate ++ sed 's/$//' ++ sed 's/$//' ++ echo updatedb PASS: tests/misc/help-version.sh PASS: tests/xargs/conflicting_opts.sh PASS: tests/xargs/verbose-quote.sh DURATION: 6 END: /usr/lib/findutils/ptest 2023-03-31T01:31 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.23 seconds] DEBUG: Command: dmesg | grep "Killed process" Status: 1 Output: NOTE: ... unexpected success NOTE: test_ptestrunner_expectsuccess (ptest.PtestRunnerTest.test_ptestrunner_expectsuccess) DEBUG: Checking if at least one of ptest-runner is installed DEBUG: Checking if ptest is in DISTRO_FEATURES or IMAGE_FEATURES NOTE: ... skipped 'Cannot run ptests without @expectedFailure as ptests are expected to fail' Cannot run ptests without @expectedFailure as ptests are expected to fail NOTE: ====================================================================== NOTE: UNEXPECTED SUCCESS: test_ptestrunner_expectfail (ptest.PtestRunnerTest.test_ptestrunner_expectfail) NOTE: ---------------------------------------------------------------------- NOTE: Ran 5 tests in 13.495s 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: 435996.00 runqemu - INFO - Removing /home/pokybuild/tmp/core-image-ptest-findutils-qemux86-64.ext4.2574714 tput: No value for $TERM and no -T specified RESULTS: RESULTS - parselogs.ParseLogsTest.test_parselogs: PASSED (5.37s) RESULTS - ping.PingTest.test_ping: PASSED (0.08s) RESULTS - ptest.PtestRunnerTest.test_ptestrunner_expectfail: PASSED (6.42s) RESULTS - ssh.SSHTest.test_ssh: PASSED (1.21s) RESULTS - ptest.PtestRunnerTest.test_ptestrunner_expectsuccess: SKIPPED (0.00s) SUMMARY: core-image-ptest-findutils () - Ran 5 tests in 13.496s core-image-ptest-findutils - OK - All required tests passed (successes=3, skipped=1, failures=0, errors=0) DEBUG: Python function do_testimage finished