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', 'libxml2-native', 'expat-native', 'python3-native', 'cmake-native', 'rpm-native', 'sqlite3-native', 'openssl-native', 'glib-2.0-native', 'curl-native', 'xz-native', 'file-native', 'zlib-native', 'libtool-native', 'texinfo-dummy-native', 'gettext-minimal-native', 'libcomps-native', 'python3-iniparse-native', 'libdnf-native', 'librepo-native', 'pixman-native', 'virglrenderer-native', 'libsdl2-native', 'libslirp-native', 'alsa-lib-native', 'libepoxy-native', 'dtc-native', 'flex-native', 'libtirpc-native', 'ncurses-native', 'libnsl2-native', 'bzip2-native', 'readline-native', 'libffi-native', 'gdbm-native', 'util-linux-libuuid-native', 'zstd-native', 'popt-native', 'elfutils-native', 'libgcrypt-native', 'lua-native', 'perl-native', 'util-linux-native', 'libpcre2-native', 'gettext-native', 'libcheck-native', 'python3-wheel-native', 'python3-setuptools-native', 'python3-build-native', 'python3-six-native', 'python3-installer-native', 'json-c-native', 'gobject-introspection-native', 'libsolv-native', 'swig-native', 'libmodulemd-native', 'attr-native', 'gpgme-native', 'libdrm-native', 'mesa-native', 'libxrender-native', 'libxrandr-native', 'libxext-native', 'libx11-native', 'm4-native', 'libarchive-native', 'libmicrohttpd-native', 'libcap-native', 'libgpg-error-native', 'make-native', 'libcap-ng-native', 'python3-flit-core-native', 'python3-packaging-native', 'python3-pyproject-hooks-native', 'libyaml-native', 'libassuan-native', 'libpthread-stubs-native', 'libpciaccess-native', 'xorgproto-native', 'wayland-protocols-native', 'python3-mako-native', 'libxshmfence-native', 'makedepend-native', 'libxfixes-native', 'libxxf86vm-native', 'xrandr-native', 'llvm-native', 'libxdamage-native', 'wayland-native', 'chrpath-native', 'util-macros-native', 'xtrans-native', 'libxcb-native', 'gnu-config-native', 'gnutls-native', 'unzip-native', 'python3-pygments-native', 'python3-markupsafe-native', 'libedit-native', 'libxau-native', 'libxdmcp-native', 'xcb-proto-native', 'nettle-native', 'libunistring-native', 'gmp-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/.b12etk43 DEBUG: Created listening socket for qemu serial console on: 127.0.0.1:52989 DEBUG: Created listening socket for qemu serial console on: 127.0.0.1:39825 DEBUG: launchcmd=runqemu snapshot kvm qemuarm64 /home/pokybuild/yocto-worker/qemuarm64-ptest/build/build/tmp/deploy/images/qemuarm64/core-image-ptest-libgcrypt-qemuarm64.ext4 tcpserial=52989:39825 bootparams=" printk.time=1" qemuparams="-pidfile /home/pokybuild/yocto-worker/qemuarm64-ptest/build/build/pidfile_2114253 -S -qmp unix:./.ftzoujut,server,wait -qmp unix:./.b12etk43,server,nowait" DEBUG: runqemu started, pid is 2114352 DEBUG: waiting at most 300 seconds for qemu pid (03/17/23 08:43:51) DEBUG: QMP Initializing to /home/pokybuild/yocto-worker/qemuarm64-ptest/build/build/tmp/.ftzoujut DEBUG: QMP Connecting to /home/pokybuild/yocto-worker/qemuarm64-ptest/build/build/tmp/.ftzoujut QMP connected to QEMU at 03/17/23 08:43:52 and took 1.3529105186462402 seconds QMP released QEMU at 03/17/23 08:43:53 and took 0.36019301414489746 seconds from connect DEBUG: qemu started in 1.708446979522705 seconds - qemu procces pid is 2114377 (03/17/23 08:43:53) 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: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 id=disk0,file=/home/pokybuild/tmp/core-image-ptest-libgcrypt-qemuarm64.ext4.2114355,if=none,format=raw -device virtio-blk-pci,drive=disk0 -device qemu-xhci -device usb-tablet -device usb-kbd -machine virt -cpu host -machine gic-version=3 -smp 4 -enable-kvm -m 1024 -device virtio-serial-pci -chardev socket,id=virtcon,port=52989,host=127.0.0.1 -device virtconsole,chardev=virtcon -serial tcp:127.0.0.1:39825 -pidfile /home/pokybuild/yocto-worker/qemuarm64-ptest/build/build/pidfile_2114253 -S -qmp unix:./.ftzoujut,server,wait -qmp unix:./.b12etk43,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=1024M ip=192.168.7.32::192.168.7.31:255.255.255.0::eth0:off:8.8.8.8 console=ttyAMA0 console=hvc0 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 - 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-libgcrypt-qemuarm64.ext4] CONFFILE: [/home/pokybuild/yocto-worker/qemuarm64-ptest/build/build/tmp/deploy/images/qemuarm64/core-image-ptest-libgcrypt-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 - 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-libgcrypt-qemuarm64.ext4.2114355 runqemu - INFO - Copy done in 0.5191559791564941 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: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 id=disk0,file=/home/pokybuild/tmp/core-image-ptest-libgcrypt-qemuarm64.ext4.2114355,if=none,format=raw -device virtio-blk-pci,drive=disk0 -device qemu-xhci -device usb-tablet -device usb-kbd -machine virt -cpu host -machine gic-version=3 -smp 4 -enable-kvm -m 1024 -device virtio-serial-pci -chardev socket,id=virtcon,port=52989,host=127.0.0.1 -device virtconsole,chardev=virtcon -serial tcp:127.0.0.1:39825 -pidfile /home/pokybuild/yocto-worker/qemuarm64-ptest/build/build/pidfile_2114253 -S -qmp unix:./.ftzoujut,server,wait -qmp unix:./.b12etk43,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=1024M ip=192.168.7.32::192.168.7.31:255.255.255.0::eth0:off:8.8.8.8 console=ttyAMA0 console=hvc0 printk.time=1' runqemu - INFO - Host uptime: 316476.36 DEBUG: Waiting at most 1500 seconds for login banner (03/17/23 08:43:53) DEBUG: Connection from 127.0.0.1:58554 DEBUG: Reached login banner in 18.056267738342285 seconds (03/17/23 08:44:11, 1679042651.2631254) 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 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.32 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; uname -a DEBUG: Waiting for process output: time: 1679042654.461952, endtime: 1679042954.4514594 DEBUG: Partial data from SSH call: Linux qemuarm64 6.1.14-yocto-standard #1 SMP PREEMPT Mon Feb 27 14:44:30 UTC 2023 aarch64 GNU/Linux DEBUG: Waiting for process output: time: 1679042654.83879, endtime: 1679042954.8387866 DEBUG: Data from SSH call: Linux qemuarm64 6.1.14-yocto-standard #1 SMP PREEMPT Mon Feb 27 14:44:30 UTC 2023 aarch64 GNU/Linux DEBUG: [Command returned '0' after 0.59 seconds] DEBUG: Command: uname -a Status: 0 Output: Linux qemuarm64 6.1.14-yocto-standard #1 SMP PREEMPT Mon Feb 27 14:44:30 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.32 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; cat /etc/controllerimage DEBUG: Waiting for process output: time: 1679042655.05264, endtime: 1679042955.041373 DEBUG: Partial data from SSH call: cat: can't open '/etc/controllerimage': No such file or directory DEBUG: Waiting for process output: time: 1679042655.430757, endtime: 1679042955.4307535 DEBUG: Data from SSH call: cat: can't open '/etc/controllerimage': No such file or directory DEBUG: [Command returned '1' after 0.59 seconds] DEBUG: Command: cat /etc/controllerimage Status: 1 Output: cat: can't open '/etc/controllerimage': No such file or directory NOTE: ... ok DEBUG: [Running]$ ssh -l root -o ServerAliveCountMax=2 -o ServerAliveInterval=30 -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR 192.168.7.32 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; which LSB_Test.sh DEBUG: Waiting for process output: time: 1679042655.644599, endtime: 1679042955.634209 DEBUG: Data from SSH call: DEBUG: [Command returned '1' after 0.37 seconds] DEBUG: Command: which LSB_Test.sh Status: 1 Output: NOTE: test_parselogs (parselogs.ParseLogsTest) DEBUG: [Running]$ ssh -l root -o ServerAliveCountMax=2 -o ServerAliveInterval=30 -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR 192.168.7.32 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; dmesg > /tmp/dmesg_output.log DEBUG: Waiting for process output: time: 1679042656.0191245, endtime: 1679042956.0090802 DEBUG: Data from SSH call: DEBUG: [Command returned '0' after 0.37 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: 1679042656.3920236, endtime: 1679042956.3814745 DEBUG: Data from SSH call: DEBUG: [Command returned '1' after 0.37 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: 1679042656.7618492, endtime: 1679042956.750525 DEBUG: Data from SSH call: DEBUG: [Command returned '0' after 0.37 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: 1679042657.1407278, endtime: 1679042957.1221564 DEBUG: Partial data from SSH call: /var/log//postinstall.log DEBUG: Waiting for process output: time: 1679042657.5020795, endtime: 1679042957.5020761 DEBUG: Data from SSH call: /var/log//postinstall.log DEBUG: [Command returned '0' after 0.58 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.32 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; test -f /var/log/dmesg DEBUG: Waiting for process output: time: 1679042657.714444, endtime: 1679042957.704512 DEBUG: Data from SSH call: DEBUG: [Command returned '0' after 0.37 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: 1679042658.0885797, endtime: 1679042958.077755 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.32:/var/log//postinstall.log /home/pokybuild/yocto-worker/qemuarm64-ptest/build/build/tmp/work/qemuarm64-poky-linux/core-image-ptest-libgcrypt/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.32:/var/log/dmesg /home/pokybuild/yocto-worker/qemuarm64-ptest/build/build/tmp/work/qemuarm64-poky-linux/core-image-ptest-libgcrypt/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.32:/tmp/dmesg_output.log /home/pokybuild/yocto-worker/qemuarm64-ptest/build/build/tmp/work/qemuarm64-poky-linux/core-image-ptest-libgcrypt/1.0-r0/target_logs DEBUG: Data from SSH call: DEBUG: [Command returned '0' after 0.18 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: 1679042659.0513542, endtime: 1679042959.0410328 DEBUG: Data from SSH call: DEBUG: [Command returned '0' after 0.37 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.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: 1679042659.419831, endtime: 1679042959.4094048 DEBUG: Data from SSH call: DEBUG: [Command returned '0' after 0.38 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.32 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; cat /proc/cpuinfo | grep "processor" | wc -l DEBUG: Waiting for process output: time: 1679042659.8036397, endtime: 1679042959.7929702 DEBUG: Partial data from SSH call: 4 DEBUG: Waiting for process output: time: 1679042660.1740704, endtime: 1679042960.1740673 DEBUG: Data from SSH call: 4 DEBUG: [Command returned '0' after 0.58 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: 1679042660.386819, endtime: 1679042960.376366 DEBUG: Partial data from SSH call: aarch64 DEBUG: Waiting for process output: time: 1679042660.7623982, endtime: 1679042960.762395 DEBUG: Data from SSH call: aarch64 DEBUG: [Command returned '0' after 0.59 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.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.19 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-17T08:44 BEGIN: /usr/lib/libgcrypt/ptest version:1.10.1-unknown:10a01:1.46-unknown:12e00: cc:120200:gcc:12.2.0: ciphers:arcfour:blowfish:cast5:des:aes:twofish:serpent:rfc2268:seed:camellia:idea:salsa20:gost28147:chacha20:sm4: pubkeys:dsa:elgamal:rsa:ecc: digests:crc:gostr3411-94::md4:md5:rmd160:sha1:sha256:sha512:sha3:tiger:whirlpool:stribog:blake2:sm3: rnd-mod:getentropy: cpu-arch:: mpi-asm:generic/mpih-add1.c:generic/mpih-sub1.c:generic/mpih-mul1.c:generic/mpih-mul2.c:generic/mpih-mul3.c:generic/mpih-lshift.c:generic/mpih-rshift.c: hwflist: fips-mode:n::: rng-type:standard:1:3030000:2: compliance::: PASS: version PASS: t-secmem PASS: mpitests PASS: t-sexp PASS: t-convert PASS: t-mpi-bit PASS: t-mpi-point PASS: curves PASS: t-lock PASS: prime PASS: basic PASS: basic-disable-all-hwf PASS: keygen PASS: pubkey PASS: hmac PASS: hashtest PASS: t-kdf PASS: keygrip PASS: fips186-dsa PASS: aeswrap PASS: pkcs1v2 PASS: random PASS: dsa-rfc6979 t-ed25519: 256 of 1026 tests done t-ed25519: 512 of 1026 tests done t-ed25519: 768 of 1026 tests done t-ed25519: 1024 of 1026 tests done t-ed25519: 1026 tests done PASS: t-ed25519 t-cv25519: 18 tests done PASS: t-cv25519 t-x448: 9 tests done PASS: t-x448 t-ed448: 11 tests done PASS: t-ed448 Note: benchmark running in quick regression test mode. MD5 0ms 0ms 30ms 0ms 0ms SHA1 10ms 0ms 30ms 0ms 10ms RIPEMD160 0ms 0ms 30ms 10ms 0ms TIGER192 10ms 0ms 20ms 10ms 0ms SHA256 10ms 0ms 40ms 0ms 10ms SHA384 10ms 0ms 30ms 0ms 10ms SHA512 0ms 10ms 30ms 0ms 10ms SHA224 0ms 10ms 30ms 10ms 10ms MD4 0ms 0ms 30ms 0ms 0ms CRC32 0ms 0ms 20ms 0ms 0ms CRC32RFC1510 0ms 0ms 20ms 0ms 0ms CRC24RFC2440 0ms 10ms 10ms 0ms 0ms WHIRLPOOL 20ms 10ms 40ms 10ms 20ms TIGER 0ms 0ms 30ms 0ms 0ms TIGER2 0ms 10ms 20ms 10ms 0ms GOSTR3411_94 30ms 30ms 50ms 40ms 30ms STRIBOG256 10ms 20ms 40ms 20ms 10ms STRIBOG512 20ms 20ms 40ms 20ms 10ms GOSTR3411_CP 30ms 30ms 60ms 30ms 30ms SHA3-224 0ms 0ms 40ms 10ms 0ms SHA3-256 10ms 0ms 40ms 0ms 10ms SHA3-384 0ms 10ms 40ms 0ms 10ms SHA3-512 10ms 10ms 30ms 20ms 0ms SHAKE128 0ms 10ms 30ms 10ms SHAKE256 0ms 10ms 30ms 10ms BLAKE2B_512 0ms 0ms 30ms 0ms 0ms BLAKE2B_384 10ms 0ms 20ms 10ms 0ms BLAKE2B_256 0ms 0ms 30ms 0ms 0ms BLAKE2B_160 0ms 10ms 20ms 10ms 0ms BLAKE2S_256 0ms 0ms 30ms 10ms 0ms BLAKE2S_224 0ms 10ms 20ms 10ms 0ms BLAKE2S_160 0ms 10ms 20ms 10ms 0ms BLAKE2S_128 0ms 10ms 20ms 10ms 0ms SM3 10ms 10ms 30ms 0ms 10ms SHA512_256 0ms 10ms 30ms 0ms 10ms SHA512_224 0ms 10ms 30ms 0ms 10ms GOST28147_IMIT 0ms 10ms 20ms HMAC_SHA256 0ms 10ms 10ms HMAC_SHA224 10ms 0ms 10ms HMAC_SHA512 10ms 0ms 10ms HMAC_SHA384 0ms 10ms 10ms HMAC_SHA1 0ms 0ms 10ms HMAC_MD5 0ms 0ms 10ms HMAC_MD4 0ms 0ms 10ms HMAC_RIPEMD160 0ms 10ms 0ms HMAC_TIGER 0ms 0ms 0ms HMAC_WHIRLPOOL 20ms 10ms 20ms HMAC_GOSTR3411_94 30ms 30ms 30ms HMAC_STRIBOG256 20ms 10ms 20ms HMAC_STRIBOG512 20ms 10ms 20ms HMAC_SHA3_224 10ms 0ms 10ms HMAC_SHA3_256 0ms 10ms 10ms HMAC_SHA3_384 0ms 10ms 10ms HMAC_SHA3_512 10ms 10ms 10ms HMAC_GOSTR3411_CP 30ms 30ms 40ms HMAC_BLAKE2B_512 0ms 0ms 10ms HMAC_BLAKE2B_384 0ms 0ms 0ms HMAC_BLAKE2B_256 10ms 0ms 0ms HMAC_BLAKE2B_160 10ms 0ms 0ms HMAC_BLAKE2S_256 10ms 0ms 10ms HMAC_BLAKE2S_224 0ms 10ms 0ms HMAC_BLAKE2S_160 0ms 10ms 0ms HMAC_BLAKE2S_128 10ms 0ms 10ms HMAC_SM3 0ms 10ms 10ms HMAC_SHA512_256 0ms 10ms 10ms HMAC_SHA512_224 0ms 10ms 0ms CMAC_AES 10ms 10ms 10ms CMAC_3DES 40ms 40ms 40ms CMAC_CAMELLIA 10ms 10ms 20ms CMAC_CAST5 10ms 10ms 20ms CMAC_BLOWFISH 10ms 10ms 10ms CMAC_TWOFISH 10ms 10ms 10ms CMAC_SERPENT 10ms 20ms 20ms CMAC_SEED 10ms 20ms 20ms CMAC_RFC2268 20ms 30ms 30ms CMAC_IDEA 20ms 10ms 20ms CMAC_GOST28147 30ms 20ms 30ms CMAC_SM4 10ms 20ms 20ms GMAC_AES 0ms 10ms 10ms GMAC_CAMELLIA 0ms 10ms 10ms GMAC_TWOFISH 0ms 0ms 10ms GMAC_SERPENT 10ms 0ms 10ms GMAC_SEED 10ms 0ms 10ms POLY1305 0ms 0ms 10ms POLY1305_AES 0ms 0ms 0ms POLY1305_CAMELLIA 10ms 0ms 0ms POLY1305_TWOFISH 0ms 0ms 10ms POLY1305_SERPENT 0ms 0ms 0ms POLY1305_SEED 0ms 0ms 10ms ECB/Stream CBC/Poly1305 CFB OFB CTR XTS CCM GCM OCB EAX --------------- --------------- --------------- --------------- --------------- --------------- --------------- --------------- --------------- --------------- IDEA 10ms 20ms 20ms 10ms 20ms 20ms 10ms 20ms 10ms 20ms - - - - - - - - 30ms 40ms 3DES 30ms 40ms 40ms 40ms 40ms 40ms 40ms 40ms 40ms 40ms - - - - - - - - 80ms 80ms CAST5 10ms 10ms 10ms 10ms 10ms 10ms 10ms 10ms 10ms 10ms - - - - - - - - 10ms 20ms BLOWFISH 10ms 10ms 10ms 10ms 10ms 0ms 10ms 10ms 10ms 0ms - - - - - - - - 20ms 10ms AES 10ms 10ms 10ms 10ms 10ms 10ms 10ms 0ms 10ms 10ms 10ms 10ms 10ms 20ms 10ms 20ms 10ms 0ms 20ms 20ms AES192 10ms 10ms 10ms 10ms 10ms 10ms 10ms 10ms 10ms 10ms 10ms 10ms 20ms 20ms 20ms 10ms 10ms 10ms 20ms 20ms AES256 10ms 20ms 10ms 10ms 10ms 10ms 10ms 20ms 10ms 10ms 10ms 10ms 20ms 30ms 10ms 20ms 10ms 10ms 30ms 20ms TWOFISH 10ms 0ms 10ms 10ms 10ms 10ms 0ms 10ms 10ms 10ms 10ms 10ms 10ms 20ms 10ms 20ms 10ms 0ms 20ms 20ms ARCFOUR 0ms 0ms DES 20ms 10ms 20ms 20ms 10ms 20ms 20ms 10ms 20ms 10ms - - - - - - - - 40ms 30ms TWOFISH128 10ms 0ms 10ms 10ms 0ms 10ms 10ms 10ms 10ms 10ms 0ms 10ms 20ms 20ms 10ms 10ms 10ms 0ms 20ms 20ms SERPENT128 10ms 10ms 20ms 10ms 20ms 20ms 10ms 20ms 10ms 20ms 10ms 20ms 30ms 30ms 20ms 20ms 20ms 20ms 30ms 30ms SERPENT192 10ms 10ms 10ms 10ms 20ms 10ms 20ms 10ms 20ms 10ms 20ms 20ms 30ms 30ms 20ms 20ms 20ms 10ms 30ms 40ms SERPENT256 10ms 20ms 10ms 20ms 10ms 20ms 10ms 20ms 20ms 10ms 20ms 10ms 40ms 30ms 20ms 20ms 10ms 20ms 30ms 40ms RFC2268_40 20ms 20ms 30ms 10ms 30ms 30ms 20ms 30ms 20ms 30ms - - - - - - - - 50ms 50ms RFC2268_128 30ms 20ms 30ms 20ms 20ms 30ms 20ms 30ms 20ms 30ms - - - - - - - - 50ms 60ms SEED 10ms 10ms 20ms 10ms 20ms 10ms 20ms 10ms 20ms 10ms 20ms 10ms 30ms 30ms 20ms 20ms 10ms 20ms 20ms 30ms CAMELLIA128 10ms 10ms 10ms 10ms 10ms 10ms 10ms 10ms 0ms 10ms 10ms 10ms 20ms 10ms 20ms 10ms 10ms 10ms 20ms 20ms CAMELLIA192 10ms 10ms 10ms 10ms 10ms 10ms 10ms 10ms 20ms 10ms 10ms 10ms 20ms 30ms 10ms 20ms 10ms 10ms 20ms 30ms CAMELLIA256 10ms 10ms 10ms 10ms 10ms 10ms 10ms 20ms 10ms 10ms 10ms 10ms 20ms 30ms 10ms 20ms 10ms 10ms 20ms 20ms SALSA20 10ms 0ms SALSA20R12 0ms 10ms GOST28147 20ms 20ms 20ms 20ms 20ms 30ms 20ms 20ms 20ms 20ms - - - - - - - - 50ms 40ms CHACHA20 0ms 10ms 0ms 0ms GOST28147_MESH 30ms 20ms 20ms 20ms 20ms 30ms 20ms 20ms 30ms 20ms - - - - - - - - 40ms 50ms SM4 10ms 20ms 20ms 10ms 10ms 10ms 20ms 10ms 10ms 20ms 10ms 20ms 30ms 30ms 10ms 20ms 10ms 10ms 20ms 30ms Algorithm generate 10*priv 10*public ------------------------------------------------ RSA 1024 bit 40ms 20ms 0ms RSA 2048 bit 250ms 110ms 0ms RSA 3072 bit 1860ms 260ms 0ms RSA 4096 bit 5050ms 510ms 0ms ELG 1024 bit - 130ms 60ms ELG 2048 bit - 670ms 310ms ELG 3072 bit - 1850ms 840ms DSA 1024/160 - 0ms 10ms DSA 2048/224 - 20ms 20ms DSA 3072/256 - 40ms 60ms ECDSA 192 bit 10ms 20ms 30ms ECDSA 224 bit 10ms 40ms 40ms ECDSA 256 bit 10ms 40ms 30ms ECDSA 384 bit 30ms 80ms 80ms ECDSA 521 bit 50ms 160ms 190ms EdDSA Ed25519 0ms 10ms 10ms EdDSA Ed448 0ms 40ms 50ms GOST 256 bit 20ms 30ms 40ms GOST 512 bit 50ms 150ms 180ms powm 10ms 20ms 40ms random 0ms 10ms PASS: benchmark SKIP: hashtest-256g 28 tests run, 28 succeeded, 0 failed, 1 skipped. testdrv: All tests completed in 0ms. Errors: 0 DURATION: 111 END: /usr/lib/libgcrypt/ptest 2023-03-17T08:46 STOP: ptest-runner TOTAL: 1 FAIL: 0 DEBUG: [Command returned '0' after 111.28 seconds] DEBUG: Command: ptest-runner -t 450 -d "/usr/lib" Status: 0 Output: START: ptest-runner 2023-03-17T08:44 BEGIN: /usr/lib/libgcrypt/ptest version:1.10.1-unknown:10a01:1.46-unknown:12e00: cc:120200:gcc:12.2.0: ciphers:arcfour:blowfish:cast5:des:aes:twofish:serpent:rfc2268:seed:camellia:idea:salsa20:gost28147:chacha20:sm4: pubkeys:dsa:elgamal:rsa:ecc: digests:crc:gostr3411-94::md4:md5:rmd160:sha1:sha256:sha512:sha3:tiger:whirlpool:stribog:blake2:sm3: rnd-mod:getentropy: cpu-arch:: mpi-asm:generic/mpih-add1.c:generic/mpih-sub1.c:generic/mpih-mul1.c:generic/mpih-mul2.c:generic/mpih-mul3.c:generic/mpih-lshift.c:generic/mpih-rshift.c: hwflist: fips-mode:n::: rng-type:standard:1:3030000:2: compliance::: PASS: version PASS: t-secmem PASS: mpitests PASS: t-sexp PASS: t-convert PASS: t-mpi-bit PASS: t-mpi-point PASS: curves PASS: t-lock PASS: prime PASS: basic PASS: basic-disable-all-hwf PASS: keygen PASS: pubkey PASS: hmac PASS: hashtest PASS: t-kdf PASS: keygrip PASS: fips186-dsa PASS: aeswrap PASS: pkcs1v2 PASS: random PASS: dsa-rfc6979 t-ed25519: 256 of 1026 tests done t-ed25519: 512 of 1026 tests done t-ed25519: 768 of 1026 tests done t-ed25519: 1024 of 1026 tests done t-ed25519: 1026 tests done PASS: t-ed25519 t-cv25519: 18 tests done PASS: t-cv25519 t-x448: 9 tests done PASS: t-x448 t-ed448: 11 tests done PASS: t-ed448 Note: benchmark running in quick regression test mode. MD5 0ms 0ms 30ms 0ms 0ms SHA1 10ms 0ms 30ms 0ms 10ms RIPEMD160 0ms 0ms 30ms 10ms 0ms TIGER192 10ms 0ms 20ms 10ms 0ms SHA256 10ms 0ms 40ms 0ms 10ms SHA384 10ms 0ms 30ms 0ms 10ms SHA512 0ms 10ms 30ms 0ms 10ms SHA224 0ms 10ms 30ms 10ms 10ms MD4 0ms 0ms 30ms 0ms 0ms CRC32 0ms 0ms 20ms 0ms 0ms CRC32RFC1510 0ms 0ms 20ms 0ms 0ms CRC24RFC2440 0ms 10ms 10ms 0ms 0ms WHIRLPOOL 20ms 10ms 40ms 10ms 20ms TIGER 0ms 0ms 30ms 0ms 0ms TIGER2 0ms 10ms 20ms 10ms 0ms GOSTR3411_94 30ms 30ms 50ms 40ms 30ms STRIBOG256 10ms 20ms 40ms 20ms 10ms STRIBOG512 20ms 20ms 40ms 20ms 10ms GOSTR3411_CP 30ms 30ms 60ms 30ms 30ms SHA3-224 0ms 0ms 40ms 10ms 0ms SHA3-256 10ms 0ms 40ms 0ms 10ms SHA3-384 0ms 10ms 40ms 0ms 10ms SHA3-512 10ms 10ms 30ms 20ms 0ms SHAKE128 0ms 10ms 30ms 10ms SHAKE256 0ms 10ms 30ms 10ms BLAKE2B_512 0ms 0ms 30ms 0ms 0ms BLAKE2B_384 10ms 0ms 20ms 10ms 0ms BLAKE2B_256 0ms 0ms 30ms 0ms 0ms BLAKE2B_160 0ms 10ms 20ms 10ms 0ms BLAKE2S_256 0ms 0ms 30ms 10ms 0ms BLAKE2S_224 0ms 10ms 20ms 10ms 0ms BLAKE2S_160 0ms 10ms 20ms 10ms 0ms BLAKE2S_128 0ms 10ms 20ms 10ms 0ms SM3 10ms 10ms 30ms 0ms 10ms SHA512_256 0ms 10ms 30ms 0ms 10ms SHA512_224 0ms 10ms 30ms 0ms 10ms GOST28147_IMIT 0ms 10ms 20ms HMAC_SHA256 0ms 10ms 10ms HMAC_SHA224 10ms 0ms 10ms HMAC_SHA512 10ms 0ms 10ms HMAC_SHA384 0ms 10ms 10ms HMAC_SHA1 0ms 0ms 10ms HMAC_MD5 0ms 0ms 10ms HMAC_MD4 0ms 0ms 10ms HMAC_RIPEMD160 0ms 10ms 0ms HMAC_TIGER 0ms 0ms 0ms HMAC_WHIRLPOOL 20ms 10ms 20ms HMAC_GOSTR3411_94 30ms 30ms 30ms HMAC_STRIBOG256 20ms 10ms 20ms HMAC_STRIBOG512 20ms 10ms 20ms HMAC_SHA3_224 10ms 0ms 10ms HMAC_SHA3_256 0ms 10ms 10ms HMAC_SHA3_384 0ms 10ms 10ms HMAC_SHA3_512 10ms 10ms 10ms HMAC_GOSTR3411_CP 30ms 30ms 40ms HMAC_BLAKE2B_512 0ms 0ms 10ms HMAC_BLAKE2B_384 0ms 0ms 0ms HMAC_BLAKE2B_256 10ms 0ms 0ms HMAC_BLAKE2B_160 10ms 0ms 0ms HMAC_BLAKE2S_256 10ms 0ms 10ms HMAC_BLAKE2S_224 0ms 10ms 0ms HMAC_BLAKE2S_160 0ms 10ms 0ms HMAC_BLAKE2S_128 10ms 0ms 10ms HMAC_SM3 0ms 10ms 10ms HMAC_SHA512_256 0ms 10ms 10ms HMAC_SHA512_224 0ms 10ms 0ms CMAC_AES 10ms 10ms 10ms CMAC_3DES 40ms 40ms 40ms CMAC_CAMELLIA 10ms 10ms 20ms CMAC_CAST5 10ms 10ms 20ms CMAC_BLOWFISH 10ms 10ms 10ms CMAC_TWOFISH 10ms 10ms 10ms CMAC_SERPENT 10ms 20ms 20ms CMAC_SEED 10ms 20ms 20ms CMAC_RFC2268 20ms 30ms 30ms CMAC_IDEA 20ms 10ms 20ms CMAC_GOST28147 30ms 20ms 30ms CMAC_SM4 10ms 20ms 20ms GMAC_AES 0ms 10ms 10ms GMAC_CAMELLIA 0ms 10ms 10ms GMAC_TWOFISH 0ms 0ms 10ms GMAC_SERPENT 10ms 0ms 10ms GMAC_SEED 10ms 0ms 10ms POLY1305 0ms 0ms 10ms POLY1305_AES 0ms 0ms 0ms POLY1305_CAMELLIA 10ms 0ms 0ms POLY1305_TWOFISH 0ms 0ms 10ms POLY1305_SERPENT 0ms 0ms 0ms POLY1305_SEED 0ms 0ms 10ms ECB/Stream CBC/Poly1305 CFB OFB CTR XTS CCM GCM OCB EAX --------------- --------------- --------------- --------------- --------------- --------------- --------------- --------------- --------------- --------------- IDEA 10ms 20ms 20ms 10ms 20ms 20ms 10ms 20ms 10ms 20ms - - - - - - - - 30ms 40ms 3DES 30ms 40ms 40ms 40ms 40ms 40ms 40ms 40ms 40ms 40ms - - - - - - - - 80ms 80ms CAST5 10ms 10ms 10ms 10ms 10ms 10ms 10ms 10ms 10ms 10ms - - - - - - - - 10ms 20ms BLOWFISH 10ms 10ms 10ms 10ms 10ms 0ms 10ms 10ms 10ms 0ms - - - - - - - - 20ms 10ms AES 10ms 10ms 10ms 10ms 10ms 10ms 10ms 0ms 10ms 10ms 10ms 10ms 10ms 20ms 10ms 20ms 10ms 0ms 20ms 20ms AES192 10ms 10ms 10ms 10ms 10ms 10ms 10ms 10ms 10ms 10ms 10ms 10ms 20ms 20ms 20ms 10ms 10ms 10ms 20ms 20ms AES256 10ms 20ms 10ms 10ms 10ms 10ms 10ms 20ms 10ms 10ms 10ms 10ms 20ms 30ms 10ms 20ms 10ms 10ms 30ms 20ms TWOFISH 10ms 0ms 10ms 10ms 10ms 10ms 0ms 10ms 10ms 10ms 10ms 10ms 10ms 20ms 10ms 20ms 10ms 0ms 20ms 20ms ARCFOUR 0ms 0ms DES 20ms 10ms 20ms 20ms 10ms 20ms 20ms 10ms 20ms 10ms - - - - - - - - 40ms 30ms TWOFISH128 10ms 0ms 10ms 10ms 0ms 10ms 10ms 10ms 10ms 10ms 0ms 10ms 20ms 20ms 10ms 10ms 10ms 0ms 20ms 20ms SERPENT128 10ms 10ms 20ms 10ms 20ms 20ms 10ms 20ms 10ms 20ms 10ms 20ms 30ms 30ms 20ms 20ms 20ms 20ms 30ms 30ms SERPENT192 10ms 10ms 10ms 10ms 20ms 10ms 20ms 10ms 20ms 10ms 20ms 20ms 30ms 30ms 20ms 20ms 20ms 10ms 30ms 40ms SERPENT256 10ms 20ms 10ms 20ms 10ms 20ms 10ms 20ms 20ms 10ms 20ms 10ms 40ms 30ms 20ms 20ms 10ms 20ms 30ms 40ms RFC2268_40 20ms 20ms 30ms 10ms 30ms 30ms 20ms 30ms 20ms 30ms - - - - - - - - 50ms 50ms RFC2268_128 30ms 20ms 30ms 20ms 20ms 30ms 20ms 30ms 20ms 30ms - - - - - - - - 50ms 60ms SEED 10ms 10ms 20ms 10ms 20ms 10ms 20ms 10ms 20ms 10ms 20ms 10ms 30ms 30ms 20ms 20ms 10ms 20ms 20ms 30ms CAMELLIA128 10ms 10ms 10ms 10ms 10ms 10ms 10ms 10ms 0ms 10ms 10ms 10ms 20ms 10ms 20ms 10ms 10ms 10ms 20ms 20ms CAMELLIA192 10ms 10ms 10ms 10ms 10ms 10ms 10ms 10ms 20ms 10ms 10ms 10ms 20ms 30ms 10ms 20ms 10ms 10ms 20ms 30ms CAMELLIA256 10ms 10ms 10ms 10ms 10ms 10ms 10ms 20ms 10ms 10ms 10ms 10ms 20ms 30ms 10ms 20ms 10ms 10ms 20ms 20ms SALSA20 10ms 0ms SALSA20R12 0ms 10ms GOST28147 20ms 20ms 20ms 20ms 20ms 30ms 20ms 20ms 20ms 20ms - - - - - - - - 50ms 40ms CHACHA20 0ms 10ms 0ms 0ms GOST28147_MESH 30ms 20ms 20ms 20ms 20ms 30ms 20ms 20ms 30ms 20ms - - - - - - - - 40ms 50ms SM4 10ms 20ms 20ms 10ms 10ms 10ms 20ms 10ms 10ms 20ms 10ms 20ms 30ms 30ms 10ms 20ms 10ms 10ms 20ms 30ms Algorithm generate 10*priv 10*public ------------------------------------------------ RSA 1024 bit 40ms 20ms 0ms RSA 2048 bit 250ms 110ms 0ms RSA 3072 bit 1860ms 260ms 0ms RSA 4096 bit 5050ms 510ms 0ms ELG 1024 bit - 130ms 60ms ELG 2048 bit - 670ms 310ms ELG 3072 bit - 1850ms 840ms DSA 1024/160 - 0ms 10ms DSA 2048/224 - 20ms 20ms DSA 3072/256 - 40ms 60ms ECDSA 192 bit 10ms 20ms 30ms ECDSA 224 bit 10ms 40ms 40ms ECDSA 256 bit 10ms 40ms 30ms ECDSA 384 bit 30ms 80ms 80ms ECDSA 521 bit 50ms 160ms 190ms EdDSA Ed25519 0ms 10ms 10ms EdDSA Ed448 0ms 40ms 50ms GOST 256 bit 20ms 30ms 40ms GOST 512 bit 50ms 150ms 180ms powm 10ms 20ms 40ms random 0ms 10ms PASS: benchmark SKIP: hashtest-256g 28 tests run, 28 succeeded, 0 failed, 1 skipped. testdrv: All tests completed in 0ms. Errors: 0 DURATION: 111 END: /usr/lib/libgcrypt/ptest 2023-03-17T08:46 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.18 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 118.240s 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: 316621.78 runqemu - INFO - Removing /home/pokybuild/tmp/core-image-ptest-libgcrypt-qemuarm64.ext4.2114355 tput: No value for $TERM and no -T specified RESULTS: RESULTS - parselogs.ParseLogsTest.test_parselogs: PASSED (4.96s) RESULTS - ping.PingTest.test_ping: PASSED (0.06s) RESULTS - ptest.PtestRunnerTest.test_ptestrunner_expectfail: PASSED (111.66s) RESULTS - ssh.SSHTest.test_ssh: PASSED (1.18s) RESULTS - ptest.PtestRunnerTest.test_ptestrunner_expectsuccess: SKIPPED (0.00s) SUMMARY: core-image-ptest-libgcrypt () - Ran 5 tests in 118.241s core-image-ptest-libgcrypt - 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