DEBUG: Executing python function extend_recipe_sysroot
NOTE: Direct dependencies are ['/home/pokybuild/yocto-worker/qemuarm64-ptest-fast/build/meta/recipes-devtools/qemu/qemu-helper-native_1.0.bb:do_populate_sysroot', '/home/pokybuild/yocto-worker/qemuarm64-ptest-fast/build/meta/recipes-devtools/qemu/qemu-native_7.2.0.bb:do_populate_sysroot', '/home/pokybuild/yocto-worker/qemuarm64-ptest-fast/build/meta/recipes-devtools/qemu/qemu-system-native_7.2.0.bb:do_populate_sysroot', 'virtual:native:/home/pokybuild/yocto-worker/qemuarm64-ptest-fast/build/meta/recipes-devtools/createrepo-c/createrepo-c_0.20.1.bb:do_populate_sysroot', 'virtual:native:/home/pokybuild/yocto-worker/qemuarm64-ptest-fast/build/meta/recipes-devtools/dnf/dnf_4.14.0.bb:do_populate_sysroot', 'virtual:native:/home/pokybuild/yocto-worker/qemuarm64-ptest-fast/build/meta/recipes-devtools/pseudo/pseudo_git.bb:do_populate_sysroot', 'virtual:native:/home/pokybuild/yocto-worker/qemuarm64-ptest-fast/build/meta/recipes-devtools/unfs3/unfs3_git.bb:do_populate_sysroot', 'virtual:native:/home/pokybuild/yocto-worker/qemuarm64-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', 'zlib-native', 'libepoxy-native', 'xz-native', 'virglrenderer-native', 'glib-2.0-native', 'libslirp-native', 'pixman-native', 'dtc-native', 'alsa-lib-native', 'libsdl2-native', 'texinfo-dummy-native', 'gettext-minimal-native', 'libtool-native', 'flex-native', 'libtirpc-native', 'libcomps-native', 'python3-native', 'cmake-native', 'librepo-native', 'libdnf-native', 'python3-iniparse-native', 'expat-native', 'file-native', 'sqlite3-native', 'openssl-native', 'libxml2-native', 'curl-native', 'rpm-native', 'mesa-native', 'libx11-native', 'libdrm-native', 'libffi-native', 'libpcre2-native', 'util-linux-native', 'gettext-native', 'libxext-native', 'libxrender-native', 'libxrandr-native', 'm4-native', 'libcheck-native', 'libnsl2-native', 'ncurses-native', 'readline-native', 'gdbm-native', 'bzip2-native', 'util-linux-libuuid-native', 'zstd-native', 'attr-native', 'gpgme-native', 'json-c-native', 'swig-native', 'libmodulemd-native', 'libsolv-native', 'gobject-introspection-native', 'python3-setuptools-native', 'python3-installer-native', 'python3-wheel-native', 'python3-six-native', 'python3-build-native', 'perl-native', 'lua-native', 'elfutils-native', 'libgcrypt-native', 'popt-native', 'xrandr-native', 'makedepend-native', 'chrpath-native', 'libxfixes-native', 'llvm-native', 'libxxf86vm-native', 'wayland-native', 'libxshmfence-native', 'libxdamage-native', 'xorgproto-native', 'python3-mako-native', 'wayland-protocols-native', 'util-macros-native', 'libxcb-native', 'xtrans-native', 'libpciaccess-native', 'libpthread-stubs-native', 'libcap-ng-native', 'gnu-config-native', 'libassuan-native', 'libgpg-error-native', 'libyaml-native', 'python3-flit-core-native', 'python3-pyproject-hooks-native', 'python3-packaging-native', 'make-native', 'libarchive-native', 'libmicrohttpd-native', 'libcap-native', 'libedit-native', 'python3-markupsafe-native', 'python3-pygments-native', 'libxau-native', 'libxdmcp-native', 'xcb-proto-native', 'unzip-native', 'gnutls-native', 'libidn2-native', 'nettle-native', 'gmp-native', 'libunistring-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-fast/build/build/tmp/.59nlguly
DEBUG: Created listening socket for qemu serial console on: 127.0.0.1:56067
DEBUG: Created listening socket for qemu serial console on: 127.0.0.1:41677
DEBUG: launchcmd=runqemu snapshot kvm  qemuarm64 /home/pokybuild/yocto-worker/qemuarm64-ptest-fast/build/build/tmp/deploy/images/qemuarm64/core-image-ptest-babeltrace-qemuarm64.ext4 tcpserial=56067:41677 bootparams=" printk.time=1" qemuparams="-pidfile /home/pokybuild/yocto-worker/qemuarm64-ptest-fast/build/build/pidfile_2241490  -S -qmp unix:./.4_vf042c,server,wait -qmp unix:./.59nlguly,server,nowait"
DEBUG: runqemu started, pid is 2241540
DEBUG: waiting at most 300 seconds for qemu pid (04/20/23 01:22:59)
DEBUG: QMP Initializing to /home/pokybuild/yocto-worker/qemuarm64-ptest-fast/build/build/tmp/.4_vf042c
DEBUG: QMP Connecting to /home/pokybuild/yocto-worker/qemuarm64-ptest-fast/build/build/tmp/.4_vf042c
QMP connected to QEMU at 04/20/23 01:23:00 and took 1.16 seconds
QMP released QEMU at 04/20/23 01:23:01 and took 0.29 seconds from connect
DEBUG: qemu started in 1.44 seconds - qemu procces pid is 2241574 (04/20/23 01:23:01)
DEBUG: qemu cmdline used:
/home/pokybuild/yocto-worker/qemuarm64-ptest-fast/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:06 -netdev tap,id=net0,ifname=tap2,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-babeltrace-qemuarm64.ext4.2241542,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=56067,host=127.0.0.1 -device virtconsole,chardev=virtcon -serial tcp:127.0.0.1:41677 -pidfile /home/pokybuild/yocto-worker/qemuarm64-ptest-fast/build/build/pidfile_2241490 -S -qmp unix:./.4_vf042c,server,wait -qmp unix:./.59nlguly,server,nowait -serial mon:vc -display sdl,show-cursor=on -device virtio-gpu-pci -kernel /home/pokybuild/yocto-worker/qemuarm64-ptest-fast/build/build/tmp/deploy/images/qemuarm64/Image -append root=/dev/vda rw  mem=1024M ip=192.168.7.6::192.168.7.5:255.255.255.0::eth0:off:8.8.8.8 console=ttyAMA0 console=hvc0   printk.time=1 
DEBUG: Target IP: 192.168.7.6
DEBUG: Server IP: 192.168.7.5
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-fast/build/build/tmp/deploy/images/qemuarm64/Image]
MACHINE: [qemuarm64]
FSTYPE: [ext4]
ROOTFS: [/home/pokybuild/yocto-worker/qemuarm64-ptest-fast/build/build/tmp/deploy/images/qemuarm64/core-image-ptest-babeltrace-qemuarm64.ext4]
CONFFILE: [/home/pokybuild/yocto-worker/qemuarm64-ptest-fast/build/build/tmp/deploy/images/qemuarm64/core-image-ptest-babeltrace-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 - Using preconfigured tap device tap2
runqemu - INFO - If this is not intended, touch /tmp/qemu-tap-locks/tap2.skip to make runqemu skip tap2.
runqemu - INFO - Network configuration: ip=192.168.7.6::192.168.7.5:255.255.255.0::eth0:off:8.8.8.8
runqemu - INFO - Copying rootfs to /home/pokybuild/tmp/core-image-ptest-babeltrace-qemuarm64.ext4.2241542
runqemu - INFO - Copy done in 0.34680962562561035 seconds
runqemu - INFO - Running /home/pokybuild/yocto-worker/qemuarm64-ptest-fast/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:06 -netdev tap,id=net0,ifname=tap2,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-babeltrace-qemuarm64.ext4.2241542,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=56067,host=127.0.0.1 -device virtconsole,chardev=virtcon -serial tcp:127.0.0.1:41677  -pidfile /home/pokybuild/yocto-worker/qemuarm64-ptest-fast/build/build/pidfile_2241490  -S -qmp unix:./.4_vf042c,server,wait -qmp unix:./.59nlguly,server,nowait -serial mon:vc -display sdl,show-cursor=on -device virtio-gpu-pci -kernel /home/pokybuild/yocto-worker/qemuarm64-ptest-fast/build/build/tmp/deploy/images/qemuarm64/Image -append 'root=/dev/vda rw  mem=1024M ip=192.168.7.6::192.168.7.5:255.255.255.0::eth0:off:8.8.8.8 console=ttyAMA0 console=hvc0   printk.time=1'

runqemu - INFO - Host uptime: 767543.26


DEBUG: Waiting at most 1500 seconds for login banner (04/20/23 01:23:01)
DEBUG: Connection from 127.0.0.1:42532
DEBUG: Reached login banner in 2.99 seconds (04/20/23 01:23:04)
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.6 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; uname -a
DEBUG: Waiting for process output: time: 1681953787.227524, endtime: 1681954087.2181237
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: 1681953787.5752108, endtime: 1681954087.575208
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.6 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; cat /etc/controllerimage
DEBUG: Waiting for process output: time: 1681953787.7875752, endtime: 1681954087.777701
DEBUG: Partial data from SSH call:
cat: can't open '/etc/controllerimage': No such file or directory

DEBUG: Waiting for process output: time: 1681953788.1310782, endtime: 1681954088.131075
DEBUG: Data from SSH call:
cat: can't open '/etc/controllerimage': No such file or directory
DEBUG: [Command returned '1' after 0.56 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.6 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; which LSB_Test.sh
DEBUG: Waiting for process output: time: 1681953788.3443217, endtime: 1681954088.334386
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.6 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; dmesg > /tmp/dmesg_output.log
DEBUG: Waiting for process output: time: 1681953788.7112002, endtime: 1681954088.7005448
DEBUG: Data from SSH call:

DEBUG: [Command returned '0' after 0.38 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.6 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; test -f /var/log/
DEBUG: Waiting for process output: time: 1681953789.087902, endtime: 1681954089.0787532
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.6 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; test -d /var/log/
DEBUG: Waiting for process output: time: 1681953789.4468136, endtime: 1681954089.4375145
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.6 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; find /var/log//*.log -maxdepth 1 -type f
DEBUG: Waiting for process output: time: 1681953789.8102417, endtime: 1681954089.8006587
DEBUG: Partial data from SSH call:
/var/log//postinstall.log

DEBUG: Waiting for process output: time: 1681953790.1553652, endtime: 1681954090.1553626
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.6 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; test -f /var/log/dmesg
DEBUG: Waiting for process output: time: 1681953790.367739, endtime: 1681954090.3578458
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.6 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; test -f /tmp/dmesg_output.log
DEBUG: Waiting for process output: time: 1681953790.7291539, endtime: 1681954090.718365
DEBUG: Data from SSH call:

DEBUG: [Command returned '0' after 0.37 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.6:/var/log//postinstall.log /home/pokybuild/yocto-worker/qemuarm64-ptest-fast/build/build/tmp/work/qemuarm64-poky-linux/core-image-ptest-babeltrace/1.0-r0/target_logs
DEBUG: Data from SSH call:

DEBUG: [Command returned '0' after 0.17 seconds]
DEBUG: [Running]$ scp -o ServerAliveCountMax=2 -o ServerAliveInterval=30 -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR root@192.168.7.6:/var/log/dmesg /home/pokybuild/yocto-worker/qemuarm64-ptest-fast/build/build/tmp/work/qemuarm64-poky-linux/core-image-ptest-babeltrace/1.0-r0/target_logs
DEBUG: Data from SSH call:

DEBUG: [Command returned '0' after 0.17 seconds]
DEBUG: [Running]$ scp -o ServerAliveCountMax=2 -o ServerAliveInterval=30 -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR root@192.168.7.6:/tmp/dmesg_output.log /home/pokybuild/yocto-worker/qemuarm64-ptest-fast/build/build/tmp/work/qemuarm64-poky-linux/core-image-ptest-babeltrace/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.6 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: 1681953791.6600423, endtime: 1681954091.65079
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.6 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: 1681953792.0311913, endtime: 1681954092.0212252
DEBUG: Data from SSH call:

DEBUG: [Command returned '0' after 0.37 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.6 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; cat /proc/cpuinfo | grep "processor" | wc -l
DEBUG: Waiting for process output: time: 1681953792.3983603, endtime: 1681954092.3884385
DEBUG: Partial data from SSH call:
4

DEBUG: Waiting for process output: time: 1681953792.7487772, endtime: 1681954092.748773
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.6 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; uname -m
DEBUG: Waiting for process output: time: 1681953792.961406, endtime: 1681954092.9512029
DEBUG: Partial data from SSH call:
aarch64

DEBUG: Waiting for process output: time: 1681953793.3079076, endtime: 1681954093.3079042
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.6 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.17 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.6 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-20T01:23
BEGIN: /usr/lib/babeltrace/ptest
PASS: bin/test_trace_read 1 - Run babeltrace with trace env-warning
PASS: bin/test_trace_read 2 - Run babeltrace with trace lttng-modules-2.0-pre5
PASS: bin/test_trace_read 3 - Run babeltrace with trace sequence
PASS: bin/test_trace_read 4 - Run babeltrace with trace smalltrace
PASS: bin/test_trace_read 5 - Run babeltrace with trace succeed1
PASS: bin/test_trace_read 6 - Run babeltrace with trace succeed2
PASS: bin/test_trace_read 7 - Run babeltrace with trace succeed3
PASS: bin/test_trace_read 8 - Run babeltrace with trace succeed4
PASS: bin/test_trace_read 9 - Run babeltrace with trace warnings
PASS: bin/test_trace_read 10 - Run babeltrace with trace wk-heartbeat-u
PASS: bin/test_trace_read 11 - Run babeltrace with invalid trace fail1
PASS: bin/test_trace_read 12 - Run babeltrace with invalid trace fail2
PASS: bin/test_trace_read 13 - Run babeltrace with invalid trace integer-range
PASS: bin/test_trace_read 14 - Run babeltrace with invalid trace lttng-modules-2.0-pre1
PASS: bin/test_trace_read 15 - Run babeltrace with invalid trace packet-based-metadata
PASS: bin/test_trace_read 16 - Run babeltrace with invalid trace smalltrace
PASS: bin/test_packet_seq_num 1 - Trace parses
PASS: bin/test_packet_seq_num 2 - No events lost
PASS: bin/test_packet_seq_num 3 - Trace parses
PASS: bin/test_packet_seq_num 4 - No events lost
PASS: bin/test_packet_seq_num 5 - Trace parses
PASS: bin/test_packet_seq_num 6 - Lost events string matches 2
PASS: bin/test_packet_seq_num 7 - Trace parses
PASS: bin/test_packet_seq_num 8 - Lost events string matches 2
PASS: bin/test_packet_seq_num 9 - Trace parses
PASS: bin/test_packet_seq_num 10 - Lost events string matches 3,2,1
PASS: bin/intersection/test_intersection 1 - 8 events in the whole trace
PASS: bin/intersection/test_intersection 2 - 3 events in packets intersecting
PASS: bin/intersection/test_intersection 3 - 8 events in the whole trace
PASS: bin/intersection/test_intersection 4 - 3 events in packets intersecting
PASS: bin/intersection/test_intersection 5 - 6 events in the whole trace
PASS: bin/intersection/test_intersection 6 - 0 events in packets intersecting
PASS: bin/intersection/test_intersection 7 - 3 events in the whole trace
PASS: bin/intersection/test_intersection 8 - 3 events in packets intersecting
PASS: bin/intersection/test_intersection 9 - 0 events in the whole trace
PASS: bin/intersection/test_intersection 10 - 0 events in packets intersecting
PASS: lib/test_bitfield 1 - Writing and reading back 0x0, unsigned
PASS: lib/test_bitfield 2 - Writing and reading back 0x0, signed
PASS: lib/test_bitfield 3 - Writing and reading back 0x1, unsigned
PASS: lib/test_bitfield 4 - Writing and reading back 0xFFFFFFFF, unsigned
PASS: lib/test_bitfield 5 - Writing and reading back 0xFFFFFFFF, signed
PASS: lib/test_bitfield 6 - Writing and reading back 0x80000000, signed
PASS: lib/test_bitfield 7 - Writing and reading back 0x44185A9, unsigned
PASS: lib/test_bitfield 8 - Writing and reading back 0x44185A9, signed
PASS: lib/test_bitfield 9 - Writing and reading back 0x5C014CA4, unsigned
PASS: lib/test_bitfield 10 - Writing and reading back 0x5C014CA4, signed
PASS: lib/test_bitfield 11 - Writing and reading back 0x181568F6, unsigned
PASS: lib/test_bitfield 12 - Writing and reading back 0x181568F6, signed
PASS: lib/test_bitfield 13 - Writing and reading back 0x73A793CD, unsigned
PASS: lib/test_bitfield 14 - Writing and reading back 0x73A793CD, signed
PASS: lib/test_bitfield 15 - Writing and reading back 0x3DCCFBD, unsigned
PASS: lib/test_bitfield 16 - Writing and reading back 0x3DCCFBD, signed
PASS: lib/test_bitfield 17 - Writing and reading back 0x490F6970, unsigned
PASS: lib/test_bitfield 18 - Writing and reading back 0x490F6970, signed
PASS: lib/test_bitfield 19 - Writing and reading back 0x29EB77C5, unsigned
PASS: lib/test_bitfield 20 - Writing and reading back 0x29EB77C5, signed
PASS: lib/test_bitfield 21 - Writing and reading back 0x5217E716, unsigned
PASS: lib/test_bitfield 22 - Writing and reading back 0x5217E716, signed
PASS: lib/test_bitfield 23 - Writing and reading back 0x36274608, unsigned
PASS: lib/test_bitfield 24 - Writing and reading back 0x36274608, signed
PASS: lib/test_bitfield 25 - Writing and reading back 0x569414BA, unsigned
PASS: lib/test_bitfield 26 - Writing and reading back 0x569414BA, signed
PASS: lib/test_seek_empty_packet 1 - Event valid
PASS: lib/test_seek_empty_packet 2 - timestamp_begin == expected_begin
PASS: lib/test_seek_empty_packet 3 - Seek begin retval 0
PASS: lib/test_seek_empty_packet 4 - Event valid
PASS: lib/test_seek_empty_packet 5 - timestamp_begin == timestamp_seek_begin
PASS: lib/test_seek_empty_packet 6 - Event valid at beginning
PASS: lib/test_seek_empty_packet 7 - Seek time at last retval 0
PASS: lib/test_seek_empty_packet 8 - Event valid at last position
PASS: lib/test_seek_empty_packet 9 - timestamp_last == expected_last
PASS: lib/test_seek_empty_packet 10 - iter next should return an error
PASS: lib/test_seek_empty_packet 11 - Event after last should be invalid
PASS: lib/test_seek_empty_packet 12 - Event valid at beginning
PASS: lib/test_seek_empty_packet 13 - Seek last retval 0
PASS: lib/test_seek_empty_packet 14 - Event valid at last position
PASS: lib/test_seek_empty_packet 15 - timestamp_last == expected_last
PASS: lib/test_seek_empty_packet 16 - iter next should return an error
PASS: lib/test_seek_empty_packet 17 - Event after last should be invalid
PASS: lib/test_seek_empty_packet 18 - Event valid at beginning
PASS: lib/test_seek_empty_packet 19 - Seek last retval 0
PASS: lib/test_seek_empty_packet 20 - Event valid at last position
PASS: lib/test_seek_empty_packet 21 - timestamp == expected_last
PASS: lib/test_seek_empty_packet 22 - iter next should return an error
PASS: lib/test_seek_empty_packet 23 - Event after last should be invalid
PASS: lib/test_seek_empty_packet 24 - Seek begin retval 0
PASS: lib/test_seek_empty_packet 25 - Event valid at first position
PASS: lib/test_seek_empty_packet 26 - timestamp == expected_begin
PASS: lib/test_seek_empty_packet 27 - Seek last retval 0
PASS: lib/test_seek_empty_packet 28 - Event valid at last position
PASS: lib/test_seek_empty_packet 29 - timestamp == expected_last
PASS: lib/test_seek_big_trace 1 - Event valid
PASS: lib/test_seek_big_trace 2 - timestamp_begin == expected_begin
PASS: lib/test_seek_big_trace 3 - Seek begin retval 0
PASS: lib/test_seek_big_trace 4 - Event valid
PASS: lib/test_seek_big_trace 5 - timestamp_begin == timestamp_seek_begin
PASS: lib/test_seek_big_trace 6 - Event valid at beginning
PASS: lib/test_seek_big_trace 7 - Seek time at last retval 0
PASS: lib/test_seek_big_trace 8 - Event valid at last position
PASS: lib/test_seek_big_trace 9 - timestamp_last == expected_last
PASS: lib/test_seek_big_trace 10 - iter next should return an error
PASS: lib/test_seek_big_trace 11 - Event after last should be invalid
PASS: lib/test_seek_big_trace 12 - Event valid at beginning
PASS: lib/test_seek_big_trace 13 - Seek last retval 0
PASS: lib/test_seek_big_trace 14 - Event valid at last position
PASS: lib/test_seek_big_trace 15 - timestamp_last == expected_last
PASS: lib/test_seek_big_trace 16 - iter next should return an error
PASS: lib/test_seek_big_trace 17 - Event after last should be invalid
PASS: lib/test_seek_big_trace 18 - Event valid at beginning
PASS: lib/test_seek_big_trace 19 - Seek last retval 0
PASS: lib/test_seek_big_trace 20 - Event valid at last position
PASS: lib/test_seek_big_trace 21 - timestamp == expected_last
PASS: lib/test_seek_big_trace 22 - iter next should return an error
PASS: lib/test_seek_big_trace 23 - Event after last should be invalid
PASS: lib/test_seek_big_trace 24 - Seek begin retval 0
PASS: lib/test_seek_big_trace 25 - Event valid at first position
PASS: lib/test_seek_big_trace 26 - timestamp == expected_begin
PASS: lib/test_seek_big_trace 27 - Seek last retval 0
PASS: lib/test_seek_big_trace 28 - Event valid at last position
PASS: lib/test_seek_big_trace 29 - timestamp == expected_last
PASS: lib/test_ctf_writer_complete 1 - bt_ctf_create succeeds in creating trace with path
PASS: lib/test_ctf_writer_complete 2 - Add host (qemuarm64) environment field to writer instance
PASS: lib/test_ctf_writer_complete 3 - bt_ctf_writer_add_environment_field error with NULL writer
PASS: lib/test_ctf_writer_complete 4 - bt_ctf_writer_add_environment_field error with NULL field name
PASS: lib/test_ctf_writer_complete 5 - bt_ctf_writer_add_environment_field error with NULL field value
PASS: lib/test_ctf_writer_complete 6 - Add sysname (Linux) environment field to writer instance
PASS: lib/test_ctf_writer_complete 7 - Add nodename (qemuarm64) environment field to writer instance
PASS: lib/test_ctf_writer_complete 8 - Add release (6.1.20-yocto-standard) environment field to writer instance
PASS: lib/test_ctf_writer_complete 9 - Add version (\#1 SMP PREEMPT Sat Mar 18 02:48:04 UTC 2023) environment field to writer instance
PASS: lib/test_ctf_writer_complete 10 - Add machine (aarch64) environment field to writer istance
PASS: lib/test_ctf_writer_complete 11 - Illegal clock name rejected
PASS: lib/test_ctf_writer_complete 12 - NULL clock name rejected
PASS: lib/test_ctf_writer_complete 13 - Clock created sucessfully
PASS: lib/test_ctf_writer_complete 14 - Set clock frequency
PASS: lib/test_ctf_writer_complete 15 - Set clock offset (seconds)
PASS: lib/test_ctf_writer_complete 16 - Set clock offset
PASS: lib/test_ctf_writer_complete 17 - Set clock precision
PASS: lib/test_ctf_writer_complete 18 - Set clock absolute property
PASS: lib/test_ctf_writer_complete 19 - Add clock to writer instance
PASS: lib/test_ctf_writer_complete 20 - Verify a clock can't be added twice to a writer instance
PASS: lib/test_ctf_writer_complete 21 - Create stream class
PASS: lib/test_ctf_writer_complete 22 - Set a stream class' clock
PASS: lib/test_ctf_writer_complete 23 - Create an unsigned integer type
PASS: lib/test_ctf_writer_complete 24 - Set integer type's base as binary
PASS: lib/test_ctf_writer_complete 25 - Set integer type's base as decimal
PASS: lib/test_ctf_writer_complete 26 - Reject integer type's base set as unknown
PASS: lib/test_ctf_writer_complete 27 - Set integer type's base as octal
PASS: lib/test_ctf_writer_complete 28 - Set integer type's base as hexadecimal
PASS: lib/test_ctf_writer_complete 29 - Reject unknown integer base value
PASS: lib/test_ctf_writer_complete 30 - Set integer type signedness to signed
PASS: lib/test_ctf_writer_complete 31 - Set integer type signedness to unsigned
PASS: lib/test_ctf_writer_complete 32 - Create a sequence of int16_t type
PASS: lib/test_ctf_writer_complete 33 - Create a string type
PASS: lib/test_ctf_writer_complete 34 - Reject invalid "None" string encoding
PASS: lib/test_ctf_writer_complete 35 - Reject invalid string encoding
PASS: lib/test_ctf_writer_complete 36 - Set string encoding to ASCII
PASS: lib/test_ctf_writer_complete 37 - Create a structure type
PASS: lib/test_ctf_writer_complete 38 - Add a uint8_t type to a structure
PASS: lib/test_ctf_writer_complete 39 - Add a sequence type to a structure
PASS: lib/test_ctf_writer_complete 40 - Add a string type to a structure
PASS: lib/test_ctf_writer_complete 41 - Add a structure type to a structure
PASS: lib/test_ctf_writer_complete 42 - Instanciate a signed 16-bit integer
PASS: lib/test_ctf_writer_complete 43 - Instanciate an unsigned 12-bit integer
PASS: lib/test_ctf_writer_complete 44 - Check an integer type' base can't be modified after instanciation
PASS: lib/test_ctf_writer_complete 45 - Check an integer type's signedness can't be modified after instanciation
PASS: lib/test_ctf_writer_complete 46 - Check bt_ctf_field_signed_integer_set_value is not allowed on an unsigned integer
PASS: lib/test_ctf_writer_complete 47 - Check bt_ctf_field_unsigned_integer_set_value is not allowed on a signed integer
PASS: lib/test_ctf_writer_complete 48 - Check -32768 is allowed for a signed 16-bit integer
PASS: lib/test_ctf_writer_complete 49 - Check 32767 is allowed for a signed 16-bit integer
PASS: lib/test_ctf_writer_complete 50 - Check 32768 is not allowed for a signed 16-bit integer
PASS: lib/test_ctf_writer_complete 51 - Check -32769 is not allowed for a signed 16-bit integer
PASS: lib/test_ctf_writer_complete 52 - Check -42 is allowed for a signed 16-bit integer
PASS: lib/test_ctf_writer_complete 53 - Check 4095 is allowed for an unsigned 12-bit integer
PASS: lib/test_ctf_writer_complete 54 - Check 4096 is not allowed for a unsigned 12-bit integer
PASS: lib/test_ctf_writer_complete 55 - Check 0 is allowed for an unsigned 12-bit integer
PASS: lib/test_ctf_writer_complete 56 - Instanciate a string field
PASS: lib/test_ctf_writer_complete 57 - Set a string's value
PASS: lib/test_ctf_writer_complete 58 - Create an enumeration type with an unsigned 12-bit integer as container
PASS: lib/test_ctf_writer_complete 59 - Check enumeration types are validated before instantiation
PASS: lib/test_ctf_writer_complete 60 - Instanciate a stream class from writer
PASS: lib/test_ctf_writer_complete 61 - Changes to a stream class that was already instantiated fail
PASS: lib/test_ctf_writer_complete 62 - Accept enumeration mapping strings containing quotes
PASS: lib/test_ctf_writer_complete 63 - Accept enumeration mapping strings containing special characters
PASS: lib/test_ctf_writer_complete 64 - Accept enumeration mapping strings containing reserved keywords
PASS: lib/test_ctf_writer_complete 65 - Add enumeration field to event
PASS: lib/test_ctf_writer_complete 66 - Create an unsigned integer type
PASS: lib/test_ctf_writer_complete 67 - Instantiate an event containing a single integer field
PASS: lib/test_ctf_writer_complete 68 - Use bt_ctf_event_set_payload to set a manually allocated field
PASS: lib/test_ctf_writer_complete 69 - Set enumeration container value
PASS: lib/test_ctf_writer_complete 70 - Set clock time
PASS: lib/test_ctf_writer_complete 71 - Append simple event to trace stream
PASS: lib/test_ctf_writer_complete 72 - Flush trace stream with one event
PASS: lib/test_ctf_writer_complete 73 - Add a new event class to a stream class after writing an event
PASS: lib/test_ctf_writer_complete 74 - Append 100 000 events to a stream
PASS: lib/test_ctf_writer_complete 75 - Flush a stream that forces a packet resize
PASS: lib/test_ctf_writer_complete 76 - Reject a variant field based on an unknown tag value
PASS: lib/test_ctf_writer_complete 77 - Add a field to a variant
PASS: lib/test_ctf_writer_complete 78 - Reject creation of an event class with an illegal name
PASS: lib/test_ctf_writer_complete 79 - Create an event class
PASS: lib/test_ctf_writer_complete 80 - Reject addition of a field with an empty name to an event
PASS: lib/test_ctf_writer_complete 81 - Reject addition of a field with a NULL type to an event
PASS: lib/test_ctf_writer_complete 82 - Reject addition of a type with an illegal name to an event
PASS: lib/test_ctf_writer_complete 83 - Add field of type unsigned integer to an event
PASS: lib/test_ctf_writer_complete 84 - Add field of type signed integer to an event
PASS: lib/test_ctf_writer_complete 85 - Add composite structure to an event
PASS: lib/test_ctf_writer_complete 86 - Reject addition of NULL event class to a stream class
PASS: lib/test_ctf_writer_complete 87 - Add an event class to stream class
PASS: lib/test_ctf_writer_complete 88 - Instanciate a complex event
PASS: lib/test_ctf_writer_complete 89 - Use bt_ctf_event_get_payload to get a field instance
PASS: lib/test_ctf_writer_complete 90 - Set a sequence field's length
PASS: lib/test_ctf_writer_complete 91 - Append a complex event to a stream
PASS: lib/test_ctf_writer_complete 92 - Flush a stream containing a complex event
PASS: lib/test_ctf_writer_complete 93 - Get metadata string
PASS: lib/test_ctf_writer_complete 94 - Metadata string is valid
PASS: lib/test_ctf_writer_complete 95 - Babeltrace could read the resulting trace
============================================================================
Testsuite summary for babeltrace 1.5.11
============================================================================
# TOTAL: 231
# PASS:  231
# SKIP:  0
# XFAIL: 0
# FAIL:  0
# XPASS: 0
# ERROR: 0
============================================================================
DURATION: 8
END: /usr/lib/babeltrace/ptest
2023-04-20T01:23
STOP: ptest-runner
TOTAL: 1 FAIL: 0
DEBUG: [Command returned '0' after 7.92 seconds]
DEBUG: Command: ptest-runner -t 450 -d "/usr/lib"
Status: 0 Output:  START: ptest-runner
2023-04-20T01:23
BEGIN: /usr/lib/babeltrace/ptest
PASS: bin/test_trace_read 1 - Run babeltrace with trace env-warning
PASS: bin/test_trace_read 2 - Run babeltrace with trace lttng-modules-2.0-pre5
PASS: bin/test_trace_read 3 - Run babeltrace with trace sequence
PASS: bin/test_trace_read 4 - Run babeltrace with trace smalltrace
PASS: bin/test_trace_read 5 - Run babeltrace with trace succeed1
PASS: bin/test_trace_read 6 - Run babeltrace with trace succeed2
PASS: bin/test_trace_read 7 - Run babeltrace with trace succeed3
PASS: bin/test_trace_read 8 - Run babeltrace with trace succeed4
PASS: bin/test_trace_read 9 - Run babeltrace with trace warnings
PASS: bin/test_trace_read 10 - Run babeltrace with trace wk-heartbeat-u
PASS: bin/test_trace_read 11 - Run babeltrace with invalid trace fail1
PASS: bin/test_trace_read 12 - Run babeltrace with invalid trace fail2
PASS: bin/test_trace_read 13 - Run babeltrace with invalid trace integer-range
PASS: bin/test_trace_read 14 - Run babeltrace with invalid trace lttng-modules-2.0-pre1
PASS: bin/test_trace_read 15 - Run babeltrace with invalid trace packet-based-metadata
PASS: bin/test_trace_read 16 - Run babeltrace with invalid trace smalltrace
PASS: bin/test_packet_seq_num 1 - Trace parses
PASS: bin/test_packet_seq_num 2 - No events lost
PASS: bin/test_packet_seq_num 3 - Trace parses
PASS: bin/test_packet_seq_num 4 - No events lost
PASS: bin/test_packet_seq_num 5 - Trace parses
PASS: bin/test_packet_seq_num 6 - Lost events string matches 2
PASS: bin/test_packet_seq_num 7 - Trace parses
PASS: bin/test_packet_seq_num 8 - Lost events string matches 2
PASS: bin/test_packet_seq_num 9 - Trace parses
PASS: bin/test_packet_seq_num 10 - Lost events string matches 3,2,1
PASS: bin/intersection/test_intersection 1 - 8 events in the whole trace
PASS: bin/intersection/test_intersection 2 - 3 events in packets intersecting
PASS: bin/intersection/test_intersection 3 - 8 events in the whole trace
PASS: bin/intersection/test_intersection 4 - 3 events in packets intersecting
PASS: bin/intersection/test_intersection 5 - 6 events in the whole trace
PASS: bin/intersection/test_intersection 6 - 0 events in packets intersecting
PASS: bin/intersection/test_intersection 7 - 3 events in the whole trace
PASS: bin/intersection/test_intersection 8 - 3 events in packets intersecting
PASS: bin/intersection/test_intersection 9 - 0 events in the whole trace
PASS: bin/intersection/test_intersection 10 - 0 events in packets intersecting
PASS: lib/test_bitfield 1 - Writing and reading back 0x0, unsigned
PASS: lib/test_bitfield 2 - Writing and reading back 0x0, signed
PASS: lib/test_bitfield 3 - Writing and reading back 0x1, unsigned
PASS: lib/test_bitfield 4 - Writing and reading back 0xFFFFFFFF, unsigned
PASS: lib/test_bitfield 5 - Writing and reading back 0xFFFFFFFF, signed
PASS: lib/test_bitfield 6 - Writing and reading back 0x80000000, signed
PASS: lib/test_bitfield 7 - Writing and reading back 0x44185A9, unsigned
PASS: lib/test_bitfield 8 - Writing and reading back 0x44185A9, signed
PASS: lib/test_bitfield 9 - Writing and reading back 0x5C014CA4, unsigned
PASS: lib/test_bitfield 10 - Writing and reading back 0x5C014CA4, signed
PASS: lib/test_bitfield 11 - Writing and reading back 0x181568F6, unsigned
PASS: lib/test_bitfield 12 - Writing and reading back 0x181568F6, signed
PASS: lib/test_bitfield 13 - Writing and reading back 0x73A793CD, unsigned
PASS: lib/test_bitfield 14 - Writing and reading back 0x73A793CD, signed
PASS: lib/test_bitfield 15 - Writing and reading back 0x3DCCFBD, unsigned
PASS: lib/test_bitfield 16 - Writing and reading back 0x3DCCFBD, signed
PASS: lib/test_bitfield 17 - Writing and reading back 0x490F6970, unsigned
PASS: lib/test_bitfield 18 - Writing and reading back 0x490F6970, signed
PASS: lib/test_bitfield 19 - Writing and reading back 0x29EB77C5, unsigned
PASS: lib/test_bitfield 20 - Writing and reading back 0x29EB77C5, signed
PASS: lib/test_bitfield 21 - Writing and reading back 0x5217E716, unsigned
PASS: lib/test_bitfield 22 - Writing and reading back 0x5217E716, signed
PASS: lib/test_bitfield 23 - Writing and reading back 0x36274608, unsigned
PASS: lib/test_bitfield 24 - Writing and reading back 0x36274608, signed
PASS: lib/test_bitfield 25 - Writing and reading back 0x569414BA, unsigned
PASS: lib/test_bitfield 26 - Writing and reading back 0x569414BA, signed
PASS: lib/test_seek_empty_packet 1 - Event valid
PASS: lib/test_seek_empty_packet 2 - timestamp_begin == expected_begin
PASS: lib/test_seek_empty_packet 3 - Seek begin retval 0
PASS: lib/test_seek_empty_packet 4 - Event valid
PASS: lib/test_seek_empty_packet 5 - timestamp_begin == timestamp_seek_begin
PASS: lib/test_seek_empty_packet 6 - Event valid at beginning
PASS: lib/test_seek_empty_packet 7 - Seek time at last retval 0
PASS: lib/test_seek_empty_packet 8 - Event valid at last position
PASS: lib/test_seek_empty_packet 9 - timestamp_last == expected_last
PASS: lib/test_seek_empty_packet 10 - iter next should return an error
PASS: lib/test_seek_empty_packet 11 - Event after last should be invalid
PASS: lib/test_seek_empty_packet 12 - Event valid at beginning
PASS: lib/test_seek_empty_packet 13 - Seek last retval 0
PASS: lib/test_seek_empty_packet 14 - Event valid at last position
PASS: lib/test_seek_empty_packet 15 - timestamp_last == expected_last
PASS: lib/test_seek_empty_packet 16 - iter next should return an error
PASS: lib/test_seek_empty_packet 17 - Event after last should be invalid
PASS: lib/test_seek_empty_packet 18 - Event valid at beginning
PASS: lib/test_seek_empty_packet 19 - Seek last retval 0
PASS: lib/test_seek_empty_packet 20 - Event valid at last position
PASS: lib/test_seek_empty_packet 21 - timestamp == expected_last
PASS: lib/test_seek_empty_packet 22 - iter next should return an error
PASS: lib/test_seek_empty_packet 23 - Event after last should be invalid
PASS: lib/test_seek_empty_packet 24 - Seek begin retval 0
PASS: lib/test_seek_empty_packet 25 - Event valid at first position
PASS: lib/test_seek_empty_packet 26 - timestamp == expected_begin
PASS: lib/test_seek_empty_packet 27 - Seek last retval 0
PASS: lib/test_seek_empty_packet 28 - Event valid at last position
PASS: lib/test_seek_empty_packet 29 - timestamp == expected_last
PASS: lib/test_seek_big_trace 1 - Event valid
PASS: lib/test_seek_big_trace 2 - timestamp_begin == expected_begin
PASS: lib/test_seek_big_trace 3 - Seek begin retval 0
PASS: lib/test_seek_big_trace 4 - Event valid
PASS: lib/test_seek_big_trace 5 - timestamp_begin == timestamp_seek_begin
PASS: lib/test_seek_big_trace 6 - Event valid at beginning
PASS: lib/test_seek_big_trace 7 - Seek time at last retval 0
PASS: lib/test_seek_big_trace 8 - Event valid at last position
PASS: lib/test_seek_big_trace 9 - timestamp_last == expected_last
PASS: lib/test_seek_big_trace 10 - iter next should return an error
PASS: lib/test_seek_big_trace 11 - Event after last should be invalid
PASS: lib/test_seek_big_trace 12 - Event valid at beginning
PASS: lib/test_seek_big_trace 13 - Seek last retval 0
PASS: lib/test_seek_big_trace 14 - Event valid at last position
PASS: lib/test_seek_big_trace 15 - timestamp_last == expected_last
PASS: lib/test_seek_big_trace 16 - iter next should return an error
PASS: lib/test_seek_big_trace 17 - Event after last should be invalid
PASS: lib/test_seek_big_trace 18 - Event valid at beginning
PASS: lib/test_seek_big_trace 19 - Seek last retval 0
PASS: lib/test_seek_big_trace 20 - Event valid at last position
PASS: lib/test_seek_big_trace 21 - timestamp == expected_last
PASS: lib/test_seek_big_trace 22 - iter next should return an error
PASS: lib/test_seek_big_trace 23 - Event after last should be invalid
PASS: lib/test_seek_big_trace 24 - Seek begin retval 0
PASS: lib/test_seek_big_trace 25 - Event valid at first position
PASS: lib/test_seek_big_trace 26 - timestamp == expected_begin
PASS: lib/test_seek_big_trace 27 - Seek last retval 0
PASS: lib/test_seek_big_trace 28 - Event valid at last position
PASS: lib/test_seek_big_trace 29 - timestamp == expected_last
PASS: lib/test_ctf_writer_complete 1 - bt_ctf_create succeeds in creating trace with path
PASS: lib/test_ctf_writer_complete 2 - Add host (qemuarm64) environment field to writer instance
PASS: lib/test_ctf_writer_complete 3 - bt_ctf_writer_add_environment_field error with NULL writer
PASS: lib/test_ctf_writer_complete 4 - bt_ctf_writer_add_environment_field error with NULL field name
PASS: lib/test_ctf_writer_complete 5 - bt_ctf_writer_add_environment_field error with NULL field value
PASS: lib/test_ctf_writer_complete 6 - Add sysname (Linux) environment field to writer instance
PASS: lib/test_ctf_writer_complete 7 - Add nodename (qemuarm64) environment field to writer instance
PASS: lib/test_ctf_writer_complete 8 - Add release (6.1.20-yocto-standard) environment field to writer instance
PASS: lib/test_ctf_writer_complete 9 - Add version (\#1 SMP PREEMPT Sat Mar 18 02:48:04 UTC 2023) environment field to writer instance
PASS: lib/test_ctf_writer_complete 10 - Add machine (aarch64) environment field to writer istance
PASS: lib/test_ctf_writer_complete 11 - Illegal clock name rejected
PASS: lib/test_ctf_writer_complete 12 - NULL clock name rejected
PASS: lib/test_ctf_writer_complete 13 - Clock created sucessfully
PASS: lib/test_ctf_writer_complete 14 - Set clock frequency
PASS: lib/test_ctf_writer_complete 15 - Set clock offset (seconds)
PASS: lib/test_ctf_writer_complete 16 - Set clock offset
PASS: lib/test_ctf_writer_complete 17 - Set clock precision
PASS: lib/test_ctf_writer_complete 18 - Set clock absolute property
PASS: lib/test_ctf_writer_complete 19 - Add clock to writer instance
PASS: lib/test_ctf_writer_complete 20 - Verify a clock can't be added twice to a writer instance
PASS: lib/test_ctf_writer_complete 21 - Create stream class
PASS: lib/test_ctf_writer_complete 22 - Set a stream class' clock
PASS: lib/test_ctf_writer_complete 23 - Create an unsigned integer type
PASS: lib/test_ctf_writer_complete 24 - Set integer type's base as binary
PASS: lib/test_ctf_writer_complete 25 - Set integer type's base as decimal
PASS: lib/test_ctf_writer_complete 26 - Reject integer type's base set as unknown
PASS: lib/test_ctf_writer_complete 27 - Set integer type's base as octal
PASS: lib/test_ctf_writer_complete 28 - Set integer type's base as hexadecimal
PASS: lib/test_ctf_writer_complete 29 - Reject unknown integer base value
PASS: lib/test_ctf_writer_complete 30 - Set integer type signedness to signed
PASS: lib/test_ctf_writer_complete 31 - Set integer type signedness to unsigned
PASS: lib/test_ctf_writer_complete 32 - Create a sequence of int16_t type
PASS: lib/test_ctf_writer_complete 33 - Create a string type
PASS: lib/test_ctf_writer_complete 34 - Reject invalid "None" string encoding
PASS: lib/test_ctf_writer_complete 35 - Reject invalid string encoding
PASS: lib/test_ctf_writer_complete 36 - Set string encoding to ASCII
PASS: lib/test_ctf_writer_complete 37 - Create a structure type
PASS: lib/test_ctf_writer_complete 38 - Add a uint8_t type to a structure
PASS: lib/test_ctf_writer_complete 39 - Add a sequence type to a structure
PASS: lib/test_ctf_writer_complete 40 - Add a string type to a structure
PASS: lib/test_ctf_writer_complete 41 - Add a structure type to a structure
PASS: lib/test_ctf_writer_complete 42 - Instanciate a signed 16-bit integer
PASS: lib/test_ctf_writer_complete 43 - Instanciate an unsigned 12-bit integer
PASS: lib/test_ctf_writer_complete 44 - Check an integer type' base can't be modified after instanciation
PASS: lib/test_ctf_writer_complete 45 - Check an integer type's signedness can't be modified after instanciation
PASS: lib/test_ctf_writer_complete 46 - Check bt_ctf_field_signed_integer_set_value is not allowed on an unsigned integer
PASS: lib/test_ctf_writer_complete 47 - Check bt_ctf_field_unsigned_integer_set_value is not allowed on a signed integer
PASS: lib/test_ctf_writer_complete 48 - Check -32768 is allowed for a signed 16-bit integer
PASS: lib/test_ctf_writer_complete 49 - Check 32767 is allowed for a signed 16-bit integer
PASS: lib/test_ctf_writer_complete 50 - Check 32768 is not allowed for a signed 16-bit integer
PASS: lib/test_ctf_writer_complete 51 - Check -32769 is not allowed for a signed 16-bit integer
PASS: lib/test_ctf_writer_complete 52 - Check -42 is allowed for a signed 16-bit integer
PASS: lib/test_ctf_writer_complete 53 - Check 4095 is allowed for an unsigned 12-bit integer
PASS: lib/test_ctf_writer_complete 54 - Check 4096 is not allowed for a unsigned 12-bit integer
PASS: lib/test_ctf_writer_complete 55 - Check 0 is allowed for an unsigned 12-bit integer
PASS: lib/test_ctf_writer_complete 56 - Instanciate a string field
PASS: lib/test_ctf_writer_complete 57 - Set a string's value
PASS: lib/test_ctf_writer_complete 58 - Create an enumeration type with an unsigned 12-bit integer as container
PASS: lib/test_ctf_writer_complete 59 - Check enumeration types are validated before instantiation
PASS: lib/test_ctf_writer_complete 60 - Instanciate a stream class from writer
PASS: lib/test_ctf_writer_complete 61 - Changes to a stream class that was already instantiated fail
PASS: lib/test_ctf_writer_complete 62 - Accept enumeration mapping strings containing quotes
PASS: lib/test_ctf_writer_complete 63 - Accept enumeration mapping strings containing special characters
PASS: lib/test_ctf_writer_complete 64 - Accept enumeration mapping strings containing reserved keywords
PASS: lib/test_ctf_writer_complete 65 - Add enumeration field to event
PASS: lib/test_ctf_writer_complete 66 - Create an unsigned integer type
PASS: lib/test_ctf_writer_complete 67 - Instantiate an event containing a single integer field
PASS: lib/test_ctf_writer_complete 68 - Use bt_ctf_event_set_payload to set a manually allocated field
PASS: lib/test_ctf_writer_complete 69 - Set enumeration container value
PASS: lib/test_ctf_writer_complete 70 - Set clock time
PASS: lib/test_ctf_writer_complete 71 - Append simple event to trace stream
PASS: lib/test_ctf_writer_complete 72 - Flush trace stream with one event
PASS: lib/test_ctf_writer_complete 73 - Add a new event class to a stream class after writing an event
PASS: lib/test_ctf_writer_complete 74 - Append 100 000 events to a stream
PASS: lib/test_ctf_writer_complete 75 - Flush a stream that forces a packet resize
PASS: lib/test_ctf_writer_complete 76 - Reject a variant field based on an unknown tag value
PASS: lib/test_ctf_writer_complete 77 - Add a field to a variant
PASS: lib/test_ctf_writer_complete 78 - Reject creation of an event class with an illegal name
PASS: lib/test_ctf_writer_complete 79 - Create an event class
PASS: lib/test_ctf_writer_complete 80 - Reject addition of a field with an empty name to an event
PASS: lib/test_ctf_writer_complete 81 - Reject addition of a field with a NULL type to an event
PASS: lib/test_ctf_writer_complete 82 - Reject addition of a type with an illegal name to an event
PASS: lib/test_ctf_writer_complete 83 - Add field of type unsigned integer to an event
PASS: lib/test_ctf_writer_complete 84 - Add field of type signed integer to an event
PASS: lib/test_ctf_writer_complete 85 - Add composite structure to an event
PASS: lib/test_ctf_writer_complete 86 - Reject addition of NULL event class to a stream class
PASS: lib/test_ctf_writer_complete 87 - Add an event class to stream class
PASS: lib/test_ctf_writer_complete 88 - Instanciate a complex event
PASS: lib/test_ctf_writer_complete 89 - Use bt_ctf_event_get_payload to get a field instance
PASS: lib/test_ctf_writer_complete 90 - Set a sequence field's length
PASS: lib/test_ctf_writer_complete 91 - Append a complex event to a stream
PASS: lib/test_ctf_writer_complete 92 - Flush a stream containing a complex event
PASS: lib/test_ctf_writer_complete 93 - Get metadata string
PASS: lib/test_ctf_writer_complete 94 - Metadata string is valid
PASS: lib/test_ctf_writer_complete 95 - Babeltrace could read the resulting trace
============================================================================
Testsuite summary for babeltrace 1.5.11
============================================================================
# TOTAL: 231
# PASS:  231
# SKIP:  0
# XFAIL: 0
# FAIL:  0
# XPASS: 0
# ERROR: 0
============================================================================
DURATION: 8
END: /usr/lib/babeltrace/ptest
2023-04-20T01:23
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.6 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; dmesg | grep "Killed process"
DEBUG: Data from SSH call:

DEBUG: [Command returned '1' after 0.16 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 14.601s
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: 767569.91

runqemu - INFO - Removing /home/pokybuild/tmp/core-image-ptest-babeltrace-qemuarm64.ext4.2241542
tput: No value for $TERM and no -T specified

RESULTS:
RESULTS - parselogs.ParseLogsTest.test_parselogs: PASSED (4.81s)
RESULTS - ping.PingTest.test_ping: PASSED (0.04s)
RESULTS - ptest.PtestRunnerTest.test_ptestrunner_expectfail: PASSED (8.26s)
RESULTS - ssh.SSHTest.test_ssh: PASSED (1.12s)
RESULTS - ptest.PtestRunnerTest.test_ptestrunner_expectsuccess: SKIPPED (0.00s)
SUMMARY:
core-image-ptest-babeltrace () - Ran 5 tests in 14.602s
core-image-ptest-babeltrace - OK - All required tests passed (successes=3, skipped=1, failures=0, errors=0)
/home/pokybuild/yocto-worker/qemuarm64-ptest-fast/build/meta/lib/oeqa/utils/qemurunner.py:486: ResourceWarning: unclosed <socket.socket fd=25, family=AddressFamily.AF_INET, type=SocketKind.SOCK_STREAM, proto=0, laddr=('127.0.0.1', 41677)>
  self.server_socket = qemusock
ResourceWarning: Enable tracemalloc to get the object allocation traceback
DEBUG: Python function do_testimage finished