DEBUG: Executing python function extend_recipe_sysroot NOTE: Direct dependencies are ['/home/pokybuild/yocto-worker/no-x11/build/meta/recipes-devtools/qemu/qemu-helper-native_1.0.bb:do_populate_sysroot', '/home/pokybuild/yocto-worker/no-x11/build/meta/recipes-devtools/qemu/qemu-native_6.2.0.bb:do_populate_sysroot', '/home/pokybuild/yocto-worker/no-x11/build/meta/recipes-devtools/qemu/qemu-system-native_6.2.0.bb:do_populate_sysroot', 'virtual:native:/home/pokybuild/yocto-worker/no-x11/build/meta/recipes-devtools/createrepo-c/createrepo-c_0.19.0.bb:do_populate_sysroot', 'virtual:native:/home/pokybuild/yocto-worker/no-x11/build/meta/recipes-devtools/dnf/dnf_4.11.1.bb:do_populate_sysroot', 'virtual:native:/home/pokybuild/yocto-worker/no-x11/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', 'alsa-lib-native', 'xz-native', 'pixman-native', 'dtc-native', 'virglrenderer-native', 'libepoxy-native', 'glib-2.0-native', 'libsdl2-native', 'zlib-native', 'curl-native', 'python3-native', 'sqlite3-native', 'rpm-native', 'openssl-native', 'cmake-native', 'libxml2-native', 'file-native', 'expat-native', 'gettext-minimal-native', 'libdnf-native', 'libcomps-native', 'librepo-native', 'python3-iniparse-native', 'libtool-native', 'texinfo-dummy-native', 'flex-native', 'libdrm-native', 'mesa-native', 'libx11-native', 'util-linux-native', 'gettext-native', 'libpcre-native', 'libffi-native', 'libxext-native', 'libxrandr-native', 'libxrender-native', 'ncurses-native', 'readline-native', 'gdbm-native', 'libnsl2-native', 'bzip2-native', 'libtirpc-native', 'util-linux-libuuid-native', 'zstd-native', 'libgcrypt-native', 'lua-native', 'elfutils-native', 'popt-native', 'perl-native', 'libsolv-native', 'swig-native', 'json-c-native', 'libmodulemd-native', 'libcheck-native', 'gobject-introspection-native', 'gpgme-native', 'attr-native', 'python3-setuptools-native', 'python3-installer-native', 'python3-six-native', 'python3-wheel-native', 'm4-native', 'libpciaccess-native', 'libpthread-stubs-native', 'wayland-native', 'makedepend-native', 'python3-mako-native', 'chrpath-native', 'libxdamage-native', 'xorgproto-native', 'libxshmfence-native', 'libxxf86vm-native', 'wayland-protocols-native', 'libxfixes-native', 'xrandr-native', 'libxcb-native', 'xtrans-native', 'util-macros-native', 'libpcre2-native', 'libcap-ng-native', 'libcap-native', 'libgpg-error-native', 'libmicrohttpd-native', 'libarchive-native', 'make-native', 'libyaml-native', 'libassuan-native', 'unzip-native', 'python3-flit-core-native', 'gnu-config-native', 'libxdmcp-native', 'libxau-native', 'xcb-proto-native', 'gnutls-native', 'lzo-native', 'nettle-native', 'libunistring-native', 'libidn2-native', 'gmp-native'] DEBUG: Python function extend_recipe_sysroot finished DEBUG: Executing python function do_testimage NOTE: Executing '/home/pokybuild/yocto-worker/no-x11/build/build/tmp/work/qemux86_64-poky-linux/core-image-full-cmdline/1.0-r0/recipe-sysroot-native/usr/bin/createrepo_c --update -q /home/pokybuild/yocto-worker/no-x11/build/build/tmp/work/qemux86_64-poky-linux/core-image-full-cmdline/1.0-r0/oe-testimage-repo/noarch' ... NOTE: Executing '/home/pokybuild/yocto-worker/no-x11/build/build/tmp/work/qemux86_64-poky-linux/core-image-full-cmdline/1.0-r0/recipe-sysroot-native/usr/bin/createrepo_c --update -q /home/pokybuild/yocto-worker/no-x11/build/build/tmp/work/qemux86_64-poky-linux/core-image-full-cmdline/1.0-r0/oe-testimage-repo/core2_64' ... NOTE: Executing '/home/pokybuild/yocto-worker/no-x11/build/build/tmp/work/qemux86_64-poky-linux/core-image-full-cmdline/1.0-r0/recipe-sysroot-native/usr/bin/createrepo_c --update -q /home/pokybuild/yocto-worker/no-x11/build/build/tmp/work/qemux86_64-poky-linux/core-image-full-cmdline/1.0-r0/oe-testimage-repo/qemux86_64' ... /home/pokybuild/yocto-worker/no-x11/build/meta/lib/oeqa/runtime/cases/apt.py:40: DeprecationWarning: invalid escape sequence '\/' apt_get_source_server = 'http:\/\/%s:%s' % (self.tc.target.server_ip, self.repo_server.port) /home/pokybuild/yocto-worker/no-x11/build/meta/lib/oeqa/runtime/cases/apt.py:42: DeprecationWarning: invalid escape sequence '\[' self.target.run("cd %s; cp sources.list sources.list.bak; sed -i 's/\[trusted=yes\] http:\/\/bogus_ip:bogus_port/%s/g' sources.list" % (apt_get_sourceslist_dir, apt_get_source_server)) /home/pokybuild/yocto-worker/no-x11/build/meta/lib/oeqa/runtime/cases/buildcpio.py:31: DeprecationWarning: invalid escape sequence '\*' 'sed -i -e "/char \*program_name/d" src/global.c;') /home/pokybuild/yocto-worker/no-x11/build/meta/lib/oeqa/runtime/cases/systemd.py:155: DeprecationWarning: invalid escape sequence '\.' expr_items=['Startup finished', 'kernel', 'userspace','\.$'] DEBUG: test suites: test_apt_install_from_repo (apt.AptRepoTest.test_apt_install_from_repo) test_ping (ping.PingTest.test_ping) test_ssh (ssh.SSHTest.test_ssh) test_cpio (buildcpio.BuildCpioTest.test_cpio) test_galculator (buildgalculator.GalculatorTest.test_galculator) test_lzip (buildlzip.BuildLzipTest.test_lzip) test_connmand_help (connman.ConnmanTest.test_connmand_help) test_connmand_running (connman.ConnmanTest.test_connmand_running) test_date (date.DateTest.test_date) test_df (df.DfTest.test_df) test_dnf_help (dnf.DnfBasicTest.test_dnf_help) test_dnf_history (dnf.DnfBasicTest.test_dnf_history) test_dnf_info (dnf.DnfBasicTest.test_dnf_info) test_dnf_search (dnf.DnfBasicTest.test_dnf_search) test_dnf_version (dnf.DnfBasicTest.test_dnf_version) test_dnf_makecache (dnf.DnfRepoTest.test_dnf_makecache) test_dnf_exclude (dnf.DnfRepoTest.test_dnf_exclude) test_dnf_install (dnf.DnfRepoTest.test_dnf_install) test_dnf_install_dependency (dnf.DnfRepoTest.test_dnf_install_dependency) test_dnf_install_from_disk (dnf.DnfRepoTest.test_dnf_install_from_disk) test_dnf_install_from_http (dnf.DnfRepoTest.test_dnf_install_from_http) test_dnf_installroot (dnf.DnfRepoTest.test_dnf_installroot) test_dnf_installroot_usrmerge (dnf.DnfRepoTest.test_dnf_installroot_usrmerge) test_dnf_reinstall (dnf.DnfRepoTest.test_dnf_reinstall) test_dnf_repoinfo (dnf.DnfRepoTest.test_dnf_repoinfo) test_gcc_compile (gcc.GccCompileTest.test_gcc_compile) test_gpp2_compile (gcc.GccCompileTest.test_gpp2_compile) test_gpp_compile (gcc.GccCompileTest.test_gpp_compile) test_make (gcc.GccCompileTest.test_make) test_python (gi.GObjectIntrospectionTest.test_python) test_gohelloworld (go.GoHelloworldTest.test_gohelloworld) test_kernel_module (kernelmodule.KernelModuleTest.test_kernel_module) test_ldd (ldd.LddTest.test_ldd) test_logrotate_wtmp (logrotate.LogrotateTest.test_logrotate_wtmp) test_logrotate_newlog (logrotate.LogrotateTest.test_logrotate_newlog) test_syslog_running (oe_syslog.SyslogTest.test_syslog_running) test_syslog_logger (oe_syslog.SyslogTestConfig.test_syslog_logger) test_syslog_restart (oe_syslog.SyslogTestConfig.test_syslog_restart) test_syslog_startup_config (oe_syslog.SyslogTestConfig.test_syslog_startup_config) test_opkg_install_from_repo (opkg.OpkgRepoTest.test_opkg_install_from_repo) test_pam (pam.PamBasicTest.test_pam) test_parselogs (parselogs.ParseLogsTest.test_parselogs) test_perl_works (perl.PerlTest.test_perl_works) test_ptestrunner_expectfail (ptest.PtestRunnerTest.test_ptestrunner_expectfail) test_ptestrunner_expectsuccess (ptest.PtestRunnerTest.test_ptestrunner_expectsuccess) test_python3 (python.PythonTest.test_python3) test_rpm_help (rpm.RpmBasicTest.test_rpm_help) test_rpm_query (rpm.RpmBasicTest.test_rpm_query) test_rpm_query_nonroot (rpm.RpmBasicTest.test_rpm_query_nonroot) test_rpm_install (rpm.RpmInstallRemoveTest.test_rpm_install) test_rpm_remove (rpm.RpmInstallRemoveTest.test_rpm_remove) test_check_rpm_install_removal_log_file_size (rpm.RpmInstallRemoveTest.test_check_rpm_install_removal_log_file_size) test_rusthelloworld (rust.RustHelloworldTest.test_rusthelloworld) test_scp_file (scp.ScpTest.test_scp_file) test_stap (stap.StapTest.test_stap) test_systemd_basic (systemd.SystemdBasicTests.test_systemd_basic) test_systemd_failed (systemd.SystemdBasicTests.test_systemd_failed) test_systemd_list (systemd.SystemdBasicTests.test_systemd_list) test_systemd_boot_time (systemd.SystemdJournalTests.test_systemd_boot_time) test_systemd_journal (systemd.SystemdJournalTests.test_systemd_journal) test_systemd_status (systemd.SystemdServiceTests.test_systemd_status) test_systemd_disable_enable (systemd.SystemdServiceTests.test_systemd_disable_enable) test_systemd_disable_enable_ro (systemd.SystemdServiceTests.test_systemd_disable_enable_ro) test_systemd_stop_start (systemd.SystemdServiceTests.test_systemd_stop_start) test_wayland_info (weston.WestonTest.test_wayland_info) test_weston_can_initialize_new_wayland_compositor (weston.WestonTest.test_weston_can_initialize_new_wayland_compositor) test_weston_running (weston.WestonTest.test_weston_running) test_xorg_running (xorg.XorgTest.test_xorg_running) DEBUG: Using kvm for runqemu QMP Available for connection at /home/pokybuild/yocto-worker/no-x11/build/build/tmp/.mmut8k16 DEBUG: Created listening socket for qemu serial console on: 127.0.0.1:46635 DEBUG: Created listening socket for qemu serial console on: 127.0.0.1:39639 DEBUG: launchcmd=runqemu snapshot kvm qemux86-64 /home/pokybuild/yocto-worker/no-x11/build/build/tmp/deploy/images/qemux86-64/core-image-full-cmdline-qemux86-64.ext4 tcpserial=46635:39639 bootparams=" printk.time=1" qemuparams="-pidfile /home/pokybuild/yocto-worker/no-x11/build/build/pidfile_3702617 -S -qmp unix:./.d61vpshl,server,wait -qmp unix:./.mmut8k16,server,nowait" DEBUG: runqemu started, pid is 3705306 DEBUG: waiting at most 300 seconds for qemu pid (11/03/23 21:31:17) DEBUG: QMP Initializing to /home/pokybuild/yocto-worker/no-x11/build/build/tmp/.d61vpshl DEBUG: QMP Connecting to /home/pokybuild/yocto-worker/no-x11/build/build/tmp/.d61vpshl QMP connected to QEMU at 11/03/23 21:31:17 and took 0.5882294178009033 seconds QMP released QEMU at 11/03/23 21:31:18 and took 0.1330549716949463 seconds from connect DEBUG: qemu started in 0.7197418212890625 seconds - qemu procces pid is 3705555 (11/03/23 21:31:18) DEBUG: qemu cmdline used: /home/pokybuild/yocto-worker/no-x11/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:02 -netdev tap,id=net0,ifname=tap0,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-full-cmdline-qemux86-64.ext4.3705306,if=virtio,format=raw -usb -device usb-tablet -cpu IvyBridge -machine q35 -smp 4 -enable-kvm -m 256 -serial tcp:127.0.0.1:46635 -serial tcp:127.0.0.1:39639 -pidfile /home/pokybuild/yocto-worker/no-x11/build/build/pidfile_3702617 -S -qmp unix:./.d61vpshl,server,wait -qmp unix:./.mmut8k16,server,nowait -serial mon:vc -device virtio-vga -display sdl,show-cursor=on -kernel /home/pokybuild/yocto-worker/no-x11/build/build/tmp/deploy/images/qemux86-64/bzImage -append root=/dev/vda rw ip=192.168.7.2::192.168.7.1: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.2 DEBUG: Server IP: 192.168.7.1 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/no-x11/build/build/tmp/deploy/images/qemux86-64/bzImage] MACHINE: [qemux86-64] FSTYPE: [ext4] ROOTFS: [/home/pokybuild/yocto-worker/no-x11/build/build/tmp/deploy/images/qemux86-64/core-image-full-cmdline-qemux86-64.ext4] CONFFILE: [/home/pokybuild/yocto-worker/no-x11/build/build/tmp/deploy/images/qemux86-64/core-image-full-cmdline-qemux86-64.qemuboot.conf] runqemu - INFO - Using preconfigured tap device tap0 runqemu - INFO - If this is not intended, touch /tmp/qemu-tap-locks/tap0.skip to make runqemu skip tap0. runqemu - INFO - Network configuration: ip=192.168.7.2::192.168.7.1:255.255.255.0::eth0:off:8.8.8.8 runqemu - INFO - Copying rootfs to /home/pokybuild/tmp/core-image-full-cmdline-qemux86-64.ext4.3705306 runqemu - INFO - Copy done in 0.07171964645385742 seconds runqemu - INFO - Running /home/pokybuild/yocto-worker/no-x11/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:02 -netdev tap,id=net0,ifname=tap0,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-full-cmdline-qemux86-64.ext4.3705306,if=virtio,format=raw -usb -device usb-tablet -cpu IvyBridge -machine q35 -smp 4 -enable-kvm -m 256 -serial tcp:127.0.0.1:46635 -serial tcp:127.0.0.1:39639 -pidfile /home/pokybuild/yocto-worker/no-x11/build/build/pidfile_3702617 -S -qmp unix:./.d61vpshl,server,wait -qmp unix:./.mmut8k16,server,nowait -serial mon:vc -device virtio-vga -display sdl,show-cursor=on -kernel /home/pokybuild/yocto-worker/no-x11/build/build/tmp/deploy/images/qemux86-64/bzImage -append 'root=/dev/vda rw ip=192.168.7.2::192.168.7.1: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: 357233.51 DEBUG: Waiting at most 1500 seconds for login banner (11/03/23 21:31:18) DEBUG: Connection from 127.0.0.1:55130 /home/pokybuild/yocto-worker/no-x11/build/meta/lib/oeqa/utils/qemurunner.py:475: ResourceWarning: unclosed self.server_socket = qemusock ResourceWarning: Enable tracemalloc to get the object allocation traceback DEBUG: Reached login banner in 8.096923112869263 seconds (11/03/23 21:31:26, 1699047086.1193736) DEBUG: Logged as root in serial console Started HTTPService on 0.0.0.0:33547 NOTE: test_apt_install_from_repo (apt.AptRepoTest.test_apt_install_from_repo) DEBUG: Checking if at least one of apt is installed NOTE: ... skipped 'Test requires apt to be installed' Test requires apt to be installed Stopped HTTPService on 0.0.0.0:33547 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.2 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; uname -a DEBUG: Waiting for process output: time: 1699047089.2933438, endtime: 1699047389.2920623 DEBUG: Partial data from SSH call: Linux qemux86-64 5.15.124-yocto-standard #1 SMP PREEMPT Thu Aug 3 17:53:26 UTC 2023 x86_64 x86_64 x86_64 GNU/Linux DEBUG: Waiting for process output: time: 1699047089.631694, endtime: 1699047389.6316905 DEBUG: Data from SSH call: Linux qemux86-64 5.15.124-yocto-standard #1 SMP PREEMPT Thu Aug 3 17:53:26 UTC 2023 x86_64 x86_64 x86_64 GNU/Linux DEBUG: [Command returned '0' after 0.54 seconds] DEBUG: Command: uname -a Status: 0 Output: Linux qemux86-64 5.15.124-yocto-standard #1 SMP PREEMPT Thu Aug 3 17:53:26 UTC 2023 x86_64 x86_64 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.2 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; cat /etc/controllerimage DEBUG: Waiting for process output: time: 1699047089.8347764, endtime: 1699047389.8331516 DEBUG: Partial data from SSH call: cat: /etc/controllerimage: No such file or directory DEBUG: Waiting for process output: time: 1699047090.1819327, endtime: 1699047390.1819308 DEBUG: Data from SSH call: cat: /etc/controllerimage: No such file or directory DEBUG: [Command returned '1' after 0.55 seconds] DEBUG: Command: cat /etc/controllerimage Status: 1 Output: cat: /etc/controllerimage: No such file or directory NOTE: ... ok NOTE: test_cpio (buildcpio.BuildCpioTest.test_cpio) DEBUG: Checking if at least one of autoconf is installed NOTE: ... skipped 'Test requires autoconf to be installed' Test requires autoconf to be installed NOTE: test_galculator (buildgalculator.GalculatorTest.test_galculator) DEBUG: Checking if at least one of gtk+3 is installed NOTE: ... skipped 'Test requires gtk+3 to be installed' Test requires gtk+3 to be installed NOTE: test_lzip (buildlzip.BuildLzipTest.test_lzip) DEBUG: Checking if at least one of autoconf is installed NOTE: ... skipped 'Test requires autoconf to be installed' Test requires autoconf to be installed NOTE: test_connmand_help (connman.ConnmanTest.test_connmand_help) DEBUG: Checking if at least one of connman is installed NOTE: ... skipped 'Test requires connman to be installed' Test requires connman to be installed NOTE: test_connmand_running (connman.ConnmanTest.test_connmand_running) NOTE: ... skipped "Test case connman.ConnmanTest.test_connmand_running depends on connman.ConnmanTest.test_connmand_help but it didn't pass/run." Test case connman.ConnmanTest.test_connmand_running depends on connman.ConnmanTest.test_connmand_help but it didn't pass/run. NOTE: test_date (date.DateTest.test_date) DEBUG: Checking if at least one of busybox, coreutils 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.2 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; date +"%Y-%m-%d %T" DEBUG: Waiting for process output: time: 1699047090.3882666, endtime: 1699047390.3868644 DEBUG: Partial data from SSH call: 2023-11-03 21:31:29 DEBUG: Waiting for process output: time: 1699047090.7511377, endtime: 1699047390.7511353 DEBUG: Data from SSH call: 2023-11-03 21:31:29 DEBUG: [Command returned '0' after 0.57 seconds] DEBUG: Command: date +"%Y-%m-%d %T" Status: 0 Output: 2023-11-03 21:31:29 DEBUG: [Running]$ ssh -l root -o ServerAliveCountMax=2 -o ServerAliveInterval=30 -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR 192.168.7.2 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; date -s @1488800000 DEBUG: Waiting for process output: time: 1699047090.9539108, endtime: 1699047390.9523182 DEBUG: Partial data from SSH call: Mon Mar 6 11:33:20 UTC 2017 DEBUG: Waiting for process output: time: 1699047091.2848694, endtime: 1699047391.284866 DEBUG: Data from SSH call: Mon Mar 6 11:33:20 UTC 2017 DEBUG: [Command returned '0' after 0.53 seconds] DEBUG: Command: date -s @1488800000 Status: 0 Output: Mon Mar 6 11:33:20 UTC 2017 DEBUG: [Running]$ ssh -l root -o ServerAliveCountMax=2 -o ServerAliveInterval=30 -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR 192.168.7.2 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; date +"%s" DEBUG: Waiting for process output: time: 1699047091.4873943, endtime: 1699047391.4860353 DEBUG: Partial data from SSH call: 1488800000 DEBUG: Waiting for process output: time: 1699047091.8718896, endtime: 1699047391.8718858 DEBUG: Data from SSH call: 1488800000 DEBUG: [Command returned '0' after 0.59 seconds] DEBUG: Command: date +"%s" Status: 0 Output: 1488800000 DEBUG: [Running]$ ssh -l root -o ServerAliveCountMax=2 -o ServerAliveInterval=30 -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR 192.168.7.2 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; date -s "2023-11-03 21:31:29" DEBUG: Waiting for process output: time: 1699047092.0744953, endtime: 1699047392.0731714 DEBUG: Partial data from SSH call: Fri Nov 3 21:31:29 UTC 2023 DEBUG: Waiting for process output: time: 1699047092.4167936, endtime: 1699047392.4167914 DEBUG: Data from SSH call: Fri Nov 3 21:31:29 UTC 2023 DEBUG: [Command returned '0' after 0.54 seconds] DEBUG: Command: date -s "2023-11-03 21:31:29" Status: 0 Output: Fri Nov 3 21:31:29 UTC 2023 NOTE: ... ok NOTE: test_df (df.DfTest.test_df) DEBUG: Checking if 'IMAGE_FEATURES' value contains 'read-only-rootfs' to skip the test DEBUG: Checking if at least one of busybox, coreutils 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.2 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; df -P / | sed -n '2p' | awk '{print $4}' DEBUG: Waiting for process output: time: 1699047092.6203523, endtime: 1699047392.619132 DEBUG: Partial data from SSH call: 20513 DEBUG: Waiting for process output: time: 1699047092.9612262, endtime: 1699047392.961224 DEBUG: Data from SSH call: 20513 DEBUG: [Command returned '0' after 0.54 seconds] DEBUG: Command: df -P / | sed -n '2p' | awk '{print $4}' Status: 0 Output: 20513 NOTE: ... ok NOTE: test_dnf_help (dnf.DnfBasicTest.test_dnf_help) DEBUG: Checking if at least one of dnf is installed NOTE: ... skipped 'Test requires dnf to be installed' Test requires dnf to be installed NOTE: test_dnf_history (dnf.DnfBasicTest.test_dnf_history) NOTE: ... skipped "Test case dnf.DnfBasicTest.test_dnf_history depends on dnf.DnfBasicTest.test_dnf_help but it didn't pass/run." Test case dnf.DnfBasicTest.test_dnf_history depends on dnf.DnfBasicTest.test_dnf_help but it didn't pass/run. NOTE: test_dnf_info (dnf.DnfBasicTest.test_dnf_info) NOTE: ... skipped "Test case dnf.DnfBasicTest.test_dnf_info depends on dnf.DnfBasicTest.test_dnf_help but it didn't pass/run." Test case dnf.DnfBasicTest.test_dnf_info depends on dnf.DnfBasicTest.test_dnf_help but it didn't pass/run. NOTE: test_dnf_search (dnf.DnfBasicTest.test_dnf_search) NOTE: ... skipped "Test case dnf.DnfBasicTest.test_dnf_search depends on dnf.DnfBasicTest.test_dnf_help but it didn't pass/run." Test case dnf.DnfBasicTest.test_dnf_search depends on dnf.DnfBasicTest.test_dnf_help but it didn't pass/run. NOTE: test_dnf_version (dnf.DnfBasicTest.test_dnf_version) NOTE: ... skipped "Test case dnf.DnfBasicTest.test_dnf_version depends on dnf.DnfBasicTest.test_dnf_help but it didn't pass/run." Test case dnf.DnfBasicTest.test_dnf_version depends on dnf.DnfBasicTest.test_dnf_help but it didn't pass/run. Started HTTPService on 0.0.0.0:46469 NOTE: test_dnf_makecache (dnf.DnfRepoTest.test_dnf_makecache) NOTE: ... skipped "Test case dnf.DnfRepoTest.test_dnf_makecache depends on dnf.DnfBasicTest.test_dnf_help but it didn't pass/run." Test case dnf.DnfRepoTest.test_dnf_makecache depends on dnf.DnfBasicTest.test_dnf_help but it didn't pass/run. NOTE: test_dnf_exclude (dnf.DnfRepoTest.test_dnf_exclude) NOTE: ... skipped "Test case dnf.DnfRepoTest.test_dnf_exclude depends on dnf.DnfRepoTest.test_dnf_makecache but it didn't pass/run." Test case dnf.DnfRepoTest.test_dnf_exclude depends on dnf.DnfRepoTest.test_dnf_makecache but it didn't pass/run. NOTE: test_dnf_install (dnf.DnfRepoTest.test_dnf_install) NOTE: ... skipped "Test case dnf.DnfRepoTest.test_dnf_install depends on dnf.DnfRepoTest.test_dnf_makecache but it didn't pass/run." Test case dnf.DnfRepoTest.test_dnf_install depends on dnf.DnfRepoTest.test_dnf_makecache but it didn't pass/run. NOTE: test_dnf_install_dependency (dnf.DnfRepoTest.test_dnf_install_dependency) NOTE: ... skipped "Test case dnf.DnfRepoTest.test_dnf_install_dependency depends on dnf.DnfRepoTest.test_dnf_install but it didn't pass/run." Test case dnf.DnfRepoTest.test_dnf_install_dependency depends on dnf.DnfRepoTest.test_dnf_install but it didn't pass/run. NOTE: test_dnf_install_from_disk (dnf.DnfRepoTest.test_dnf_install_from_disk) NOTE: ... skipped "Test case dnf.DnfRepoTest.test_dnf_install_from_disk depends on dnf.DnfRepoTest.test_dnf_install_dependency but it didn't pass/run." Test case dnf.DnfRepoTest.test_dnf_install_from_disk depends on dnf.DnfRepoTest.test_dnf_install_dependency but it didn't pass/run. NOTE: test_dnf_install_from_http (dnf.DnfRepoTest.test_dnf_install_from_http) NOTE: ... skipped "Test case dnf.DnfRepoTest.test_dnf_install_from_http depends on dnf.DnfRepoTest.test_dnf_install_from_disk but it didn't pass/run." Test case dnf.DnfRepoTest.test_dnf_install_from_http depends on dnf.DnfRepoTest.test_dnf_install_from_disk but it didn't pass/run. NOTE: test_dnf_installroot (dnf.DnfRepoTest.test_dnf_installroot) DEBUG: Checking if at least one of busybox is installed DEBUG: Checking if 'DISTRO_FEATURES' value contains 'usrmerge' to skip the test NOTE: ... skipped "Test case dnf.DnfRepoTest.test_dnf_installroot depends on dnf.DnfRepoTest.test_dnf_makecache but it didn't pass/run." Test case dnf.DnfRepoTest.test_dnf_installroot depends on dnf.DnfRepoTest.test_dnf_makecache but it didn't pass/run. NOTE: test_dnf_installroot_usrmerge (dnf.DnfRepoTest.test_dnf_installroot_usrmerge) DEBUG: Checking if at least one of busybox is installed DEBUG: Checking if 'DISTRO_FEATURES' value contains 'usrmerge' to run the test NOTE: ... skipped 'Test run when enable usrmerge' Test run when enable usrmerge NOTE: test_dnf_reinstall (dnf.DnfRepoTest.test_dnf_reinstall) NOTE: ... skipped "Test case dnf.DnfRepoTest.test_dnf_reinstall depends on dnf.DnfRepoTest.test_dnf_install but it didn't pass/run." Test case dnf.DnfRepoTest.test_dnf_reinstall depends on dnf.DnfRepoTest.test_dnf_install but it didn't pass/run. NOTE: test_dnf_repoinfo (dnf.DnfRepoTest.test_dnf_repoinfo) NOTE: ... skipped "Test case dnf.DnfRepoTest.test_dnf_repoinfo depends on dnf.DnfRepoTest.test_dnf_makecache but it didn't pass/run." Test case dnf.DnfRepoTest.test_dnf_repoinfo depends on dnf.DnfRepoTest.test_dnf_makecache but it didn't pass/run. Stopped HTTPService on 0.0.0.0:46469 NOTE: test_gcc_compile (gcc.GccCompileTest.test_gcc_compile) DEBUG: Checking if at least one of gcc is installed NOTE: ... skipped 'Test requires gcc to be installed' Test requires gcc to be installed NOTE: test_gpp2_compile (gcc.GccCompileTest.test_gpp2_compile) DEBUG: Checking if at least one of g++ is installed NOTE: ... skipped 'Test requires g++ to be installed' Test requires g++ to be installed NOTE: test_gpp_compile (gcc.GccCompileTest.test_gpp_compile) DEBUG: Checking if at least one of g++ is installed NOTE: ... skipped 'Test requires g++ to be installed' Test requires g++ to be installed NOTE: test_make (gcc.GccCompileTest.test_make) DEBUG: Checking if at least one of make is installed DEBUG: Checking if at least one of gcc is installed NOTE: ... skipped 'Test requires gcc to be installed' Test requires gcc to be installed NOTE: test_python (gi.GObjectIntrospectionTest.test_python) DEBUG: Checking if at least one of python3-pygobject is installed NOTE: ... skipped 'Test requires python3-pygobject to be installed' Test requires python3-pygobject to be installed NOTE: test_gohelloworld (go.GoHelloworldTest.test_gohelloworld) DEBUG: Checking if at least one of go-helloworld is installed NOTE: ... skipped 'Test requires go-helloworld to be installed' Test requires go-helloworld to be installed NOTE: test_kernel_module (kernelmodule.KernelModuleTest.test_kernel_module) DEBUG: Checking if at least one of gcc is installed NOTE: ... skipped 'Test requires gcc to be installed' Test requires gcc to be installed NOTE: test_ldd (ldd.LddTest.test_ldd) DEBUG: Checking if at least one of ldd is installed NOTE: ... skipped 'Test requires ldd to be installed' Test requires ldd to be 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.2 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; cp /etc/logrotate.d/wtmp $HOME/wtmp.oeqabak DEBUG: Waiting for process output: time: 1699047093.1998935, endtime: 1699047393.1981966 DEBUG: Data from SSH call: DEBUG: [Command returned '0' after 0.34 seconds] DEBUG: Command: cp /etc/logrotate.d/wtmp $HOME/wtmp.oeqabak Status: 0 Output: NOTE: test_logrotate_wtmp (logrotate.LogrotateTest.test_logrotate_wtmp) DEBUG: Checking if at least one of logrotate 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.2 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; touch /var/log/wtmp DEBUG: Waiting for process output: time: 1699047093.5401495, endtime: 1699047393.5388463 DEBUG: Data from SSH call: DEBUG: [Command returned '0' after 0.35 seconds] DEBUG: Command: touch /var/log/wtmp 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.2 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; mkdir /var/log//logrotate_dir DEBUG: Waiting for process output: time: 1699047093.8926942, endtime: 1699047393.891446 DEBUG: Data from SSH call: DEBUG: [Command returned '0' after 0.34 seconds] DEBUG: Command: mkdir /var/log//logrotate_dir 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.2 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; echo "create olddir /var/log//logrotate_dir include /etc/logrotate.d/wtmp" > /tmp/logrotate-test.conf DEBUG: Waiting for process output: time: 1699047094.232429, endtime: 1699047394.2306511 DEBUG: Data from SSH call: DEBUG: [Command returned '0' after 0.34 seconds] DEBUG: Command: echo "create olddir /var/log//logrotate_dir include /etc/logrotate.d/wtmp" > /tmp/logrotate-test.conf 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.2 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; logrotate -vf /tmp/logrotate-test.conf DEBUG: Waiting for process output: time: 1699047094.5686584, endtime: 1699047394.5674145 DEBUG: Partial data from SSH call: reading config file /tmp/logrotate-test.conf olddir is now /var/log//logrotate_dir including /etc/logrotate.d/wtmp reading config file /etc/logrotate.d/wtmp Creating stub state file: /var/lib/logrotate.status Reading state from file: /var/lib/logrotate.status Allocating hash table for state file, size 64 entries Handling 1 logs rotating pattern: /var/log/wtmp forced from command line (1 rotations) olddir is /var/log//logrotate_dir, empty log files are rotated, only log files >= 1048576 bytes are rotated, old logs are removed considering log /var/log/wtmp Creating new state Now: 2023-11-03 21:31 Last rotated at 2023-11-03 21:00 log needs rotating rotating log /var/log/wtmp, log->rotateCount is 1 dateext suffix '-20231103' glob pattern '-[0-9][0-9][0-9][0-9][0-9][0-9][0-9][0-9]' renaming /var/log//logrotate_dir/wtmp.1 to /var/log//logrotate_dir/wtmp.2 (rotatecount 1, logstart 1, i 1), old log /var/log//logrotate_dir/wtmp.1 does not exist renaming /var/log//logrotate_dir/wtmp.0 to /var/log//logrotate_dir/wtmp.1 (rotatecount 1, logstart 1, i 0), old log /var/log//logrotate_dir/wtmp.0 does not exist log /var/log//logrotate_dir/wtmp.2 doesn't exist -- won't try to dispose of it renaming /var/log/wtmp to /var/log//logrotate_dir/wtmp.1 creating new /var/log/wtmp mode = 0664 uid = 0 gid = 43 DEBUG: Waiting for process output: time: 1699047094.9052942, endtime: 1699047394.905291 DEBUG: Data from SSH call: reading config file /tmp/logrotate-test.conf olddir is now /var/log//logrotate_dir including /etc/logrotate.d/wtmp reading config file /etc/logrotate.d/wtmp Creating stub state file: /var/lib/logrotate.status Reading state from file: /var/lib/logrotate.status Allocating hash table for state file, size 64 entries Handling 1 logs rotating pattern: /var/log/wtmp forced from command line (1 rotations) olddir is /var/log//logrotate_dir, empty log files are rotated, only log files >= 1048576 bytes are rotated, old logs are removed considering log /var/log/wtmp Creating new state Now: 2023-11-03 21:31 Last rotated at 2023-11-03 21:00 log needs rotating rotating log /var/log/wtmp, log->rotateCount is 1 dateext suffix '-20231103' glob pattern '-[0-9][0-9][0-9][0-9][0-9][0-9][0-9][0-9]' renaming /var/log//logrotate_dir/wtmp.1 to /var/log//logrotate_dir/wtmp.2 (rotatecount 1, logstart 1, i 1), old log /var/log//logrotate_dir/wtmp.1 does not exist renaming /var/log//logrotate_dir/wtmp.0 to /var/log//logrotate_dir/wtmp.1 (rotatecount 1, logstart 1, i 0), old log /var/log//logrotate_dir/wtmp.0 does not exist log /var/log//logrotate_dir/wtmp.2 doesn't exist -- won't try to dispose of it renaming /var/log/wtmp to /var/log//logrotate_dir/wtmp.1 creating new /var/log/wtmp mode = 0664 uid = 0 gid = 43 DEBUG: [Command returned '0' after 0.54 seconds] DEBUG: Command: logrotate -vf /tmp/logrotate-test.conf Status: 0 Output: reading config file /tmp/logrotate-test.conf olddir is now /var/log//logrotate_dir including /etc/logrotate.d/wtmp reading config file /etc/logrotate.d/wtmp Creating stub state file: /var/lib/logrotate.status Reading state from file: /var/lib/logrotate.status Allocating hash table for state file, size 64 entries Handling 1 logs rotating pattern: /var/log/wtmp forced from command line (1 rotations) olddir is /var/log//logrotate_dir, empty log files are rotated, only log files >= 1048576 bytes are rotated, old logs are removed considering log /var/log/wtmp Creating new state Now: 2023-11-03 21:31 Last rotated at 2023-11-03 21:00 log needs rotating rotating log /var/log/wtmp, log->rotateCount is 1 dateext suffix '-20231103' glob pattern '-[0-9][0-9][0-9][0-9][0-9][0-9][0-9][0-9]' renaming /var/log//logrotate_dir/wtmp.1 to /var/log//logrotate_dir/wtmp.2 (rotatecount 1, logstart 1, i 1), old log /var/log//logrotate_dir/wtmp.1 does not exist renaming /var/log//logrotate_dir/wtmp.0 to /var/log//logrotate_dir/wtmp.1 (rotatecount 1, logstart 1, i 0), old log /var/log//logrotate_dir/wtmp.0 does not exist log /var/log//logrotate_dir/wtmp.2 doesn't exist -- won't try to dispose of it renaming /var/log/wtmp to /var/log//logrotate_dir/wtmp.1 creating new /var/log/wtmp mode = 0664 uid = 0 gid = 43 DEBUG: [Running]$ ssh -l root -o ServerAliveCountMax=2 -o ServerAliveInterval=30 -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR 192.168.7.2 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; find /var/log//logrotate_dir -type f | grep wtmp.1 DEBUG: Waiting for process output: time: 1699047095.10831, endtime: 1699047395.1062782 DEBUG: Partial data from SSH call: /var/log//logrotate_dir/wtmp.1 DEBUG: Waiting for process output: time: 1699047095.4498866, endtime: 1699047395.4498837 DEBUG: Data from SSH call: /var/log//logrotate_dir/wtmp.1 DEBUG: [Command returned '0' after 0.54 seconds] DEBUG: Command: find /var/log//logrotate_dir -type f | grep wtmp.1 Status: 0 Output: /var/log//logrotate_dir/wtmp.1 NOTE: ... ok NOTE: test_logrotate_newlog (logrotate.LogrotateTest.test_logrotate_newlog) DEBUG: [Running]$ ssh -l root -o ServerAliveCountMax=2 -o ServerAliveInterval=30 -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR 192.168.7.2 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; echo "oeqa logrotate test file" > /var/log/logrotate_testfile DEBUG: Waiting for process output: time: 1699047095.652572, endtime: 1699047395.6513643 DEBUG: Data from SSH call: DEBUG: [Command returned '0' after 0.34 seconds] DEBUG: Command: echo "oeqa logrotate test file" > /var/log/logrotate_testfile 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.2 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; echo "/var/log/logrotate_testfile { missingok monthly rotate 1" > /etc/logrotate.d/logrotate_testfile DEBUG: Waiting for process output: time: 1699047095.9918199, endtime: 1699047395.9905362 DEBUG: Data from SSH call: DEBUG: [Command returned '0' after 0.34 seconds] DEBUG: Command: echo "/var/log/logrotate_testfile { missingok monthly rotate 1" > /etc/logrotate.d/logrotate_testfile 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.2 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; echo "create olddir /var/log//logrotate_dir include /etc/logrotate.d/logrotate_testfile" > /tmp/logrotate-test2.conf DEBUG: Waiting for process output: time: 1699047096.331199, endtime: 1699047396.329679 DEBUG: Data from SSH call: DEBUG: [Command returned '0' after 0.35 seconds] DEBUG: Command: echo "create olddir /var/log//logrotate_dir include /etc/logrotate.d/logrotate_testfile" > /tmp/logrotate-test2.conf 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.2 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; find /var/log//logrotate_dir -type f | grep logrotate_testfile.1 DEBUG: Waiting for process output: time: 1699047096.6787236, endtime: 1699047396.67748 DEBUG: Data from SSH call: DEBUG: [Command returned '1' after 0.35 seconds] DEBUG: Command: find /var/log//logrotate_dir -type f | grep logrotate_testfile.1 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.2 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; logrotate -vf /tmp/logrotate-test2.conf DEBUG: Waiting for process output: time: 1699047097.0307753, endtime: 1699047397.029482 DEBUG: Partial data from SSH call: reading config file /tmp/logrotate-test2.conf olddir is now /var/log//logrotate_dir including /etc/logrotate.d/logrotate_testfile reading config file /etc/logrotate.d/logrotate_testfile Reading state from file: /var/lib/logrotate.status Allocating hash table for state file, size 64 entries Creating new state Handling 1 logs rotating pattern: /var/log/logrotate_testfile forced from command line (1 rotations) olddir is /var/log//logrotate_dir, empty log files are rotated, old logs are removed considering log /var/log/logrotate_testfile Creating new state Now: 2023-11-03 21:31 Last rotated at 2023-11-03 21:00 log needs rotating rotating log /var/log/logrotate_testfile, log->rotateCount is 1 dateext suffix '-20231103' glob pattern '-[0-9][0-9][0-9][0-9][0-9][0-9][0-9][0-9]' renaming /var/log//logrotate_dir/logrotate_testfile.1 to /var/log//logrotate_dir/logrotate_testfile.2 (rotatecount 1, logstart 1, i 1), old log /var/log//logrotate_dir/logrotate_testfile.1 does not exist renaming /var/log//logrotate_dir/logrotate_testfile.0 to /var/log//logrotate_dir/logrotate_testfile.1 (rotatecount 1, logstart 1, i 0), old log /var/log//logrotate_dir/logrotate_testfile.0 does not exist log /var/log//logrotate_dir/logrotate_testfile.2 doesn't exist -- won't try to dispose of it renaming /var/log/logrotate_testfile to /var/log//logrotate_dir/logrotate_testfile.1 creating new /var/log/logrotate_testfile mode = 0644 uid = 0 gid = 0 DEBUG: Waiting for process output: time: 1699047097.3624437, endtime: 1699047397.3624423 DEBUG: Data from SSH call: reading config file /tmp/logrotate-test2.conf olddir is now /var/log//logrotate_dir including /etc/logrotate.d/logrotate_testfile reading config file /etc/logrotate.d/logrotate_testfile Reading state from file: /var/lib/logrotate.status Allocating hash table for state file, size 64 entries Creating new state Handling 1 logs rotating pattern: /var/log/logrotate_testfile forced from command line (1 rotations) olddir is /var/log//logrotate_dir, empty log files are rotated, old logs are removed considering log /var/log/logrotate_testfile Creating new state Now: 2023-11-03 21:31 Last rotated at 2023-11-03 21:00 log needs rotating rotating log /var/log/logrotate_testfile, log->rotateCount is 1 dateext suffix '-20231103' glob pattern '-[0-9][0-9][0-9][0-9][0-9][0-9][0-9][0-9]' renaming /var/log//logrotate_dir/logrotate_testfile.1 to /var/log//logrotate_dir/logrotate_testfile.2 (rotatecount 1, logstart 1, i 1), old log /var/log//logrotate_dir/logrotate_testfile.1 does not exist renaming /var/log//logrotate_dir/logrotate_testfile.0 to /var/log//logrotate_dir/logrotate_testfile.1 (rotatecount 1, logstart 1, i 0), old log /var/log//logrotate_dir/logrotate_testfile.0 does not exist log /var/log//logrotate_dir/logrotate_testfile.2 doesn't exist -- won't try to dispose of it renaming /var/log/logrotate_testfile to /var/log//logrotate_dir/logrotate_testfile.1 creating new /var/log/logrotate_testfile mode = 0644 uid = 0 gid = 0 DEBUG: [Command returned '0' after 0.53 seconds] DEBUG: Command: logrotate -vf /tmp/logrotate-test2.conf Status: 0 Output: reading config file /tmp/logrotate-test2.conf olddir is now /var/log//logrotate_dir including /etc/logrotate.d/logrotate_testfile reading config file /etc/logrotate.d/logrotate_testfile Reading state from file: /var/lib/logrotate.status Allocating hash table for state file, size 64 entries Creating new state Handling 1 logs rotating pattern: /var/log/logrotate_testfile forced from command line (1 rotations) olddir is /var/log//logrotate_dir, empty log files are rotated, old logs are removed considering log /var/log/logrotate_testfile Creating new state Now: 2023-11-03 21:31 Last rotated at 2023-11-03 21:00 log needs rotating rotating log /var/log/logrotate_testfile, log->rotateCount is 1 dateext suffix '-20231103' glob pattern '-[0-9][0-9][0-9][0-9][0-9][0-9][0-9][0-9]' renaming /var/log//logrotate_dir/logrotate_testfile.1 to /var/log//logrotate_dir/logrotate_testfile.2 (rotatecount 1, logstart 1, i 1), old log /var/log//logrotate_dir/logrotate_testfile.1 does not exist renaming /var/log//logrotate_dir/logrotate_testfile.0 to /var/log//logrotate_dir/logrotate_testfile.1 (rotatecount 1, logstart 1, i 0), old log /var/log//logrotate_dir/logrotate_testfile.0 does not exist log /var/log//logrotate_dir/logrotate_testfile.2 doesn't exist -- won't try to dispose of it renaming /var/log/logrotate_testfile to /var/log//logrotate_dir/logrotate_testfile.1 creating new /var/log/logrotate_testfile mode = 0644 uid = 0 gid = 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.2 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; find /var/log//logrotate_dir -type f | grep logrotate_testfile.1 DEBUG: Waiting for process output: time: 1699047097.5656273, endtime: 1699047397.5637321 DEBUG: Partial data from SSH call: /var/log//logrotate_dir/logrotate_testfile.1 DEBUG: Waiting for process output: time: 1699047097.9142637, endtime: 1699047397.914261 DEBUG: Data from SSH call: /var/log//logrotate_dir/logrotate_testfile.1 DEBUG: [Command returned '0' after 0.55 seconds] DEBUG: Command: find /var/log//logrotate_dir -type f | grep logrotate_testfile.1 Status: 0 Output: /var/log//logrotate_dir/logrotate_testfile.1 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.2 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; mv -f $HOME/wtmp.oeqabak /etc/logrotate.d/wtmp && rm -rf /var/log//logrotate_dir DEBUG: Waiting for process output: time: 1699047098.1166253, endtime: 1699047398.1153736 DEBUG: Data from SSH call: DEBUG: [Command returned '0' after 0.35 seconds] DEBUG: Command: mv -f $HOME/wtmp.oeqabak /etc/logrotate.d/wtmp && rm -rf /var/log//logrotate_dir 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.2 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; rm -rf /var/log/logrotate_testfile && rm -rf /etc/logrotate.d/logrotate_testfile DEBUG: Waiting for process output: time: 1699047098.4653258, endtime: 1699047398.464134 DEBUG: Data from SSH call: DEBUG: [Command returned '0' after 0.34 seconds] DEBUG: Command: rm -rf /var/log/logrotate_testfile && rm -rf /etc/logrotate.d/logrotate_testfile Status: 0 Output: NOTE: test_syslog_running (oe_syslog.SyslogTest.test_syslog_running) DEBUG: Checking if at least one of syslog-ng, busybox-syslog, rsyslog, sysklogd 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.2 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; ps -ef DEBUG: Waiting for process output: time: 1699047098.8028448, endtime: 1699047398.80154 DEBUG: Partial data from SSH call: UID PID PPID C STIME TTY TIME CMD root 1 0 4 21:31 ? 00:00:00 init [5] root 2 0 0 21:31 ? 00:00:00 [kthreadd] root 3 2 0 21:31 ? 00:00:00 [rcu_gp] root 4 2 0 21:31 ? 00:00:00 [rcu_par_gp] root 5 2 0 21:31 ? 00:00:00 [slub_flushwq] root 6 2 0 21:31 ? 00:00:00 [netns] root 7 2 0 21:31 ? 00:00:00 [kworker/0:0-events] root 8 2 0 21:31 ? 00:00:00 [kworker/0:0H-events_highpri] root 9 2 0 21:31 ? 00:00:00 [kworker/u8:0-events_unbound] root 10 2 0 21:31 ? 00:00:00 [mm_percpu_wq] root 11 2 0 21:31 ? 00:00:00 [kworker/u8:1-events_unbound] root 12 2 0 21:31 ? 00:00:00 [rcu_tasks_kthre] root 13 2 0 21:31 ? 00:00:00 [rcu_tasks_rude_] root 14 2 0 21:31 ? 00:00:00 [rcu_tasks_trace] root 15 2 0 21:31 ? 00:00:00 [ksoftirqd/0] root 16 2 0 21:31 ? 00:00:00 [rcu_preempt] root 17 2 0 21:31 ? 00:00:00 [migration/0] root 18 2 0 21:31 ? 00:00:00 [cpuhp/0] root 19 2 0 21:31 ? 00:00:00 [cpuhp/1] root 20 2 0 21:31 ? 00:00:00 [migration/1] root 21 2 0 21:31 ? 00:00:00 [ksoftirqd/1] root 22 2 0 21:31 ? 00:00:00 [kworker/1:0-rcu_gp] root 23 2 0 21:31 ? 00:00:00 [kworker/1:0H-kblockd] root 24 2 0 21:31 ? 00:00:00 [cpuhp/2] root 25 2 0 21:31 ? 00:00:00 [migration/2] root 26 2 0 21:31 ? 00:00:00 [ksoftirqd/2] root 27 2 0 21:31 ? 00:00:00 [kworker/2:0-events_freezable_power_] root 28 2 0 21:31 ? 00:00:00 [kworker/2:0H-kblockd] root 29 2 0 21:31 ? 00:00:00 [cpuhp/3] root 30 2 0 21:31 ? 00:00:00 [migration/3] root 31 2 0 21:31 ? 00:00:00 [ksoftirqd/3] root 32 2 0 21:31 ? 00:00:00 [kworker/3:0-events] root 33 2 0 21:31 ? 00:00:00 [kworker/3:0H-kblockd] root 34 2 0 21:31 ? 00:00:00 [kdevtmpfs] root 35 2 0 21:31 ? 00:00:00 [inet_frag_wq] root 36 2 0 21:31 ? 00:00:00 [kworker/0:1-events] root 37 2 0 21:31 ? 00:00:00 [kworker/1:1-events] root 38 2 0 21:31 ? 00:00:00 [oom_reaper] root 39 2 0 21:31 ? 00:00:00 [writeback] root 40 2 0 21:31 ? 00:00:00 [kcompactd0] root 46 2 0 21:31 ? 00:00:00 [kworker/3:1-events] root 78 2 0 21:31 ? 00:00:00 [kworker/2:1-mm_percpu_wq] root 79 2 0 21:31 ? 00:00:00 [kblockd] root 80 2 0 21:31 ? 00:00:00 [blkcg_punt_bio] root 81 2 0 21:31 ? 00:00:00 [ata_sff] root 82 2 0 21:31 ? 00:00:00 [md] root 83 2 0 21:31 ? 00:00:00 [kworker/0:1H-kblockd] root 84 2 0 21:31 ? 00:00:00 [rpciod] root 85 2 0 21:31 ? 00:00:00 [kworker/u9:0-xprtiod] root 86 2 0 21:31 ? 00:00:00 [xprtiod] root 87 2 0 21:31 ? 00:00:00 [kworker/u8:2-events_unbound] root 88 2 0 21:31 ? 00:00:00 [kswapd0] root 89 2 0 21:31 ? 00:00:00 [nfsiod] root 90 2 0 21:31 ? 00:00:00 [cifsiod] root 91 2 0 21:31 ? 00:00:00 [smb3decryptd] root 92 2 0 21:31 ? 00:00:00 [cifsfileinfoput] root 93 2 0 21:31 ? 00:00:00 [cifsoplockd] root 94 2 0 21:31 ? 00:00:00 [deferredclose] root 96 2 0 21:31 ? 00:00:00 [acpi_thermal_pm] root 97 2 0 21:31 ? 00:00:00 [hwrng] root 98 2 0 21:31 ? 00:00:00 [kworker/0:2-events] root 99 2 0 21:31 ? 00:00:00 [nvme-wq] root 100 2 0 21:31 ? 00:00:00 [nvme-reset-wq] root 101 2 0 21:31 ? 00:00:00 [nvme-delete-wq] root 102 2 0 21:31 ? 00:00:00 [scsi_eh_0] root 103 2 0 21:31 ? 00:00:00 [scsi_tmf_0] root 104 2 0 21:31 ? 00:00:00 [scsi_eh_1] root 105 2 0 21:31 ? 00:00:00 [scsi_tmf_1] root 106 2 0 21:31 ? 00:00:00 [scsi_eh_2] root 107 2 0 21:31 ? 00:00:00 [scsi_tmf_2] root 108 2 0 21:31 ? 00:00:00 [scsi_eh_3] root 109 2 0 21:31 ? 00:00:00 [scsi_tmf_3] root 110 2 0 21:31 ? 00:00:00 [scsi_eh_4] root 111 2 0 21:31 ? 00:00:00 [scsi_tmf_4] root 112 2 0 21:31 ? 00:00:00 [scsi_eh_5] root 113 2 0 21:31 ? 00:00:00 [scsi_tmf_5] root 114 2 0 21:31 ? 00:00:00 [kworker/u8:3-events_unbound] root 115 2 0 21:31 ? 00:00:00 [kworker/u8:4-events_unbound] root 116 2 0 21:31 ? 00:00:00 [kworker/u8:5-events_unbound] root 117 2 0 21:31 ? 00:00:00 [kworker/u8:6-events_unbound] root 118 2 0 21:31 ? 00:00:00 [kworker/u8:7-events_unbound] root 119 2 0 21:31 ? 00:00:00 [kworker/2:2-mld] root 120 2 0 21:31 ? 00:00:00 [raid5wq] root 121 2 0 21:31 ? 00:00:00 [dm_bufio_cache] root 122 2 0 21:31 ? 00:00:00 [mld] root 123 2 0 21:31 ? 00:00:00 [ipv6_addrconf] root 128 2 0 21:31 ? 00:00:00 [kworker/3:1H-kblockd] root 129 2 0 21:31 ? 00:00:00 [kworker/2:3] root 130 2 0 21:31 ? 00:00:00 [jbd2/vda-8] root 131 2 0 21:31 ? 00:00:00 [ext4-rsv-conver] root 162 2 0 21:31 ? 00:00:00 [kworker/1:1H-kblockd] root 168 1 0 21:31 ? 00:00:00 /sbin/udevd -d root 308 2 0 21:31 ? 00:00:00 [kworker/2:1H-kblockd] root 366 1 0 21:31 ? 00:00:00 sshd: /usr/sbin/sshd [listener] 0 of 10-100 startups rpc 371 1 0 21:31 ? 00:00:00 /usr/sbin/rpcbind rpcuser 378 1 0 21:31 ? 00:00:00 /usr/sbin/rpc.statd root 384 1 0 21:31 ? 00:00:00 /usr/sbin/atd -f root 397 2 0 21:31 ? 00:00:00 [kworker/u9:1] root 400 2 0 21:31 ? 00:00:00 [lockd] root 402 2 0 21:31 ? 00:00:00 [nfsd] root 403 2 0 21:31 ? 00:00:00 [nfsd] root 404 2 0 21:31 ? 00:00:00 [nfsd] root 405 2 0 21:31 ? 00:00:00 [nfsd] root 406 2 0 21:31 ? 00:00:00 [nfsd] root 407 2 0 21:31 ? 00:00:00 [nfsd] root 408 2 0 21:31 ? 00:00:00 [nfsd] root 409 2 0 21:31 ? 00:00:00 [nfsd] root 411 1 0 21:31 ? 00:00:00 /usr/sbin/rpc.mountd root 416 1 0 21:31 ? 00:00:00 /usr/sbin/syslogd -ss root 420 1 0 21:31 ? 00:00:00 /usr/sbin/crond root 430 1 0 21:31 ? 00:00:00 /bin/sh /bin/start_getty 115200 ttyS0 vt102 root 431 1 0 21:31 ? 00:00:00 /bin/sh /bin/start_getty 115200 ttyS1 vt102 root 432 1 0 21:31 tty1 00:00:00 /sbin/getty 38400 tty1 root 434 430 0 21:31 ttyS0 00:00:00 /sbin/getty -L 115200 ttyS0 vt102 root 436 431 0 21:31 ttyS1 00:00:00 -sh root 516 366 0 21:31 ? 00:00:00 sshd: root@notty root 518 516 0 21:31 ? 00:00:00 ps -ef DEBUG: Waiting for process output: time: 1699047099.1464317, endtime: 1699047399.1464288 DEBUG: Data from SSH call: UID PID PPID C STIME TTY TIME CMD root 1 0 4 21:31 ? 00:00:00 init [5] root 2 0 0 21:31 ? 00:00:00 [kthreadd] root 3 2 0 21:31 ? 00:00:00 [rcu_gp] root 4 2 0 21:31 ? 00:00:00 [rcu_par_gp] root 5 2 0 21:31 ? 00:00:00 [slub_flushwq] root 6 2 0 21:31 ? 00:00:00 [netns] root 7 2 0 21:31 ? 00:00:00 [kworker/0:0-events] root 8 2 0 21:31 ? 00:00:00 [kworker/0:0H-events_highpri] root 9 2 0 21:31 ? 00:00:00 [kworker/u8:0-events_unbound] root 10 2 0 21:31 ? 00:00:00 [mm_percpu_wq] root 11 2 0 21:31 ? 00:00:00 [kworker/u8:1-events_unbound] root 12 2 0 21:31 ? 00:00:00 [rcu_tasks_kthre] root 13 2 0 21:31 ? 00:00:00 [rcu_tasks_rude_] root 14 2 0 21:31 ? 00:00:00 [rcu_tasks_trace] root 15 2 0 21:31 ? 00:00:00 [ksoftirqd/0] root 16 2 0 21:31 ? 00:00:00 [rcu_preempt] root 17 2 0 21:31 ? 00:00:00 [migration/0] root 18 2 0 21:31 ? 00:00:00 [cpuhp/0] root 19 2 0 21:31 ? 00:00:00 [cpuhp/1] root 20 2 0 21:31 ? 00:00:00 [migration/1] root 21 2 0 21:31 ? 00:00:00 [ksoftirqd/1] root 22 2 0 21:31 ? 00:00:00 [kworker/1:0-rcu_gp] root 23 2 0 21:31 ? 00:00:00 [kworker/1:0H-kblockd] root 24 2 0 21:31 ? 00:00:00 [cpuhp/2] root 25 2 0 21:31 ? 00:00:00 [migration/2] root 26 2 0 21:31 ? 00:00:00 [ksoftirqd/2] root 27 2 0 21:31 ? 00:00:00 [kworker/2:0-events_freezable_power_] root 28 2 0 21:31 ? 00:00:00 [kworker/2:0H-kblockd] root 29 2 0 21:31 ? 00:00:00 [cpuhp/3] root 30 2 0 21:31 ? 00:00:00 [migration/3] root 31 2 0 21:31 ? 00:00:00 [ksoftirqd/3] root 32 2 0 21:31 ? 00:00:00 [kworker/3:0-events] root 33 2 0 21:31 ? 00:00:00 [kworker/3:0H-kblockd] root 34 2 0 21:31 ? 00:00:00 [kdevtmpfs] root 35 2 0 21:31 ? 00:00:00 [inet_frag_wq] root 36 2 0 21:31 ? 00:00:00 [kworker/0:1-events] root 37 2 0 21:31 ? 00:00:00 [kworker/1:1-events] root 38 2 0 21:31 ? 00:00:00 [oom_reaper] root 39 2 0 21:31 ? 00:00:00 [writeback] root 40 2 0 21:31 ? 00:00:00 [kcompactd0] root 46 2 0 21:31 ? 00:00:00 [kworker/3:1-events] root 78 2 0 21:31 ? 00:00:00 [kworker/2:1-mm_percpu_wq] root 79 2 0 21:31 ? 00:00:00 [kblockd] root 80 2 0 21:31 ? 00:00:00 [blkcg_punt_bio] root 81 2 0 21:31 ? 00:00:00 [ata_sff] root 82 2 0 21:31 ? 00:00:00 [md] root 83 2 0 21:31 ? 00:00:00 [kworker/0:1H-kblockd] root 84 2 0 21:31 ? 00:00:00 [rpciod] root 85 2 0 21:31 ? 00:00:00 [kworker/u9:0-xprtiod] root 86 2 0 21:31 ? 00:00:00 [xprtiod] root 87 2 0 21:31 ? 00:00:00 [kworker/u8:2-events_unbound] root 88 2 0 21:31 ? 00:00:00 [kswapd0] root 89 2 0 21:31 ? 00:00:00 [nfsiod] root 90 2 0 21:31 ? 00:00:00 [cifsiod] root 91 2 0 21:31 ? 00:00:00 [smb3decryptd] root 92 2 0 21:31 ? 00:00:00 [cifsfileinfoput] root 93 2 0 21:31 ? 00:00:00 [cifsoplockd] root 94 2 0 21:31 ? 00:00:00 [deferredclose] root 96 2 0 21:31 ? 00:00:00 [acpi_thermal_pm] root 97 2 0 21:31 ? 00:00:00 [hwrng] root 98 2 0 21:31 ? 00:00:00 [kworker/0:2-events] root 99 2 0 21:31 ? 00:00:00 [nvme-wq] root 100 2 0 21:31 ? 00:00:00 [nvme-reset-wq] root 101 2 0 21:31 ? 00:00:00 [nvme-delete-wq] root 102 2 0 21:31 ? 00:00:00 [scsi_eh_0] root 103 2 0 21:31 ? 00:00:00 [scsi_tmf_0] root 104 2 0 21:31 ? 00:00:00 [scsi_eh_1] root 105 2 0 21:31 ? 00:00:00 [scsi_tmf_1] root 106 2 0 21:31 ? 00:00:00 [scsi_eh_2] root 107 2 0 21:31 ? 00:00:00 [scsi_tmf_2] root 108 2 0 21:31 ? 00:00:00 [scsi_eh_3] root 109 2 0 21:31 ? 00:00:00 [scsi_tmf_3] root 110 2 0 21:31 ? 00:00:00 [scsi_eh_4] root 111 2 0 21:31 ? 00:00:00 [scsi_tmf_4] root 112 2 0 21:31 ? 00:00:00 [scsi_eh_5] root 113 2 0 21:31 ? 00:00:00 [scsi_tmf_5] root 114 2 0 21:31 ? 00:00:00 [kworker/u8:3-events_unbound] root 115 2 0 21:31 ? 00:00:00 [kworker/u8:4-events_unbound] root 116 2 0 21:31 ? 00:00:00 [kworker/u8:5-events_unbound] root 117 2 0 21:31 ? 00:00:00 [kworker/u8:6-events_unbound] root 118 2 0 21:31 ? 00:00:00 [kworker/u8:7-events_unbound] root 119 2 0 21:31 ? 00:00:00 [kworker/2:2-mld] root 120 2 0 21:31 ? 00:00:00 [raid5wq] root 121 2 0 21:31 ? 00:00:00 [dm_bufio_cache] root 122 2 0 21:31 ? 00:00:00 [mld] root 123 2 0 21:31 ? 00:00:00 [ipv6_addrconf] root 128 2 0 21:31 ? 00:00:00 [kworker/3:1H-kblockd] root 129 2 0 21:31 ? 00:00:00 [kworker/2:3] root 130 2 0 21:31 ? 00:00:00 [jbd2/vda-8] root 131 2 0 21:31 ? 00:00:00 [ext4-rsv-conver] root 162 2 0 21:31 ? 00:00:00 [kworker/1:1H-kblockd] root 168 1 0 21:31 ? 00:00:00 /sbin/udevd -d root 308 2 0 21:31 ? 00:00:00 [kworker/2:1H-kblockd] root 366 1 0 21:31 ? 00:00:00 sshd: /usr/sbin/sshd [listener] 0 of 10-100 startups rpc 371 1 0 21:31 ? 00:00:00 /usr/sbin/rpcbind rpcuser 378 1 0 21:31 ? 00:00:00 /usr/sbin/rpc.statd root 384 1 0 21:31 ? 00:00:00 /usr/sbin/atd -f root 397 2 0 21:31 ? 00:00:00 [kworker/u9:1] root 400 2 0 21:31 ? 00:00:00 [lockd] root 402 2 0 21:31 ? 00:00:00 [nfsd] root 403 2 0 21:31 ? 00:00:00 [nfsd] root 404 2 0 21:31 ? 00:00:00 [nfsd] root 405 2 0 21:31 ? 00:00:00 [nfsd] root 406 2 0 21:31 ? 00:00:00 [nfsd] root 407 2 0 21:31 ? 00:00:00 [nfsd] root 408 2 0 21:31 ? 00:00:00 [nfsd] root 409 2 0 21:31 ? 00:00:00 [nfsd] root 411 1 0 21:31 ? 00:00:00 /usr/sbin/rpc.mountd root 416 1 0 21:31 ? 00:00:00 /usr/sbin/syslogd -ss root 420 1 0 21:31 ? 00:00:00 /usr/sbin/crond root 430 1 0 21:31 ? 00:00:00 /bin/sh /bin/start_getty 115200 ttyS0 vt102 root 431 1 0 21:31 ? 00:00:00 /bin/sh /bin/start_getty 115200 ttyS1 vt102 root 432 1 0 21:31 tty1 00:00:00 /sbin/getty 38400 tty1 root 434 430 0 21:31 ttyS0 00:00:00 /sbin/getty -L 115200 ttyS0 vt102 root 436 431 0 21:31 ttyS1 00:00:00 -sh root 516 366 0 21:31 ? 00:00:00 sshd: root@notty root 518 516 0 21:31 ? 00:00:00 ps -ef DEBUG: [Command returned '0' after 0.55 seconds] DEBUG: Command: ps -ef Status: 0 Output: UID PID PPID C STIME TTY TIME CMD root 1 0 4 21:31 ? 00:00:00 init [5] root 2 0 0 21:31 ? 00:00:00 [kthreadd] root 3 2 0 21:31 ? 00:00:00 [rcu_gp] root 4 2 0 21:31 ? 00:00:00 [rcu_par_gp] root 5 2 0 21:31 ? 00:00:00 [slub_flushwq] root 6 2 0 21:31 ? 00:00:00 [netns] root 7 2 0 21:31 ? 00:00:00 [kworker/0:0-events] root 8 2 0 21:31 ? 00:00:00 [kworker/0:0H-events_highpri] root 9 2 0 21:31 ? 00:00:00 [kworker/u8:0-events_unbound] root 10 2 0 21:31 ? 00:00:00 [mm_percpu_wq] root 11 2 0 21:31 ? 00:00:00 [kworker/u8:1-events_unbound] root 12 2 0 21:31 ? 00:00:00 [rcu_tasks_kthre] root 13 2 0 21:31 ? 00:00:00 [rcu_tasks_rude_] root 14 2 0 21:31 ? 00:00:00 [rcu_tasks_trace] root 15 2 0 21:31 ? 00:00:00 [ksoftirqd/0] root 16 2 0 21:31 ? 00:00:00 [rcu_preempt] root 17 2 0 21:31 ? 00:00:00 [migration/0] root 18 2 0 21:31 ? 00:00:00 [cpuhp/0] root 19 2 0 21:31 ? 00:00:00 [cpuhp/1] root 20 2 0 21:31 ? 00:00:00 [migration/1] root 21 2 0 21:31 ? 00:00:00 [ksoftirqd/1] root 22 2 0 21:31 ? 00:00:00 [kworker/1:0-rcu_gp] root 23 2 0 21:31 ? 00:00:00 [kworker/1:0H-kblockd] root 24 2 0 21:31 ? 00:00:00 [cpuhp/2] root 25 2 0 21:31 ? 00:00:00 [migration/2] root 26 2 0 21:31 ? 00:00:00 [ksoftirqd/2] root 27 2 0 21:31 ? 00:00:00 [kworker/2:0-events_freezable_power_] root 28 2 0 21:31 ? 00:00:00 [kworker/2:0H-kblockd] root 29 2 0 21:31 ? 00:00:00 [cpuhp/3] root 30 2 0 21:31 ? 00:00:00 [migration/3] root 31 2 0 21:31 ? 00:00:00 [ksoftirqd/3] root 32 2 0 21:31 ? 00:00:00 [kworker/3:0-events] root 33 2 0 21:31 ? 00:00:00 [kworker/3:0H-kblockd] root 34 2 0 21:31 ? 00:00:00 [kdevtmpfs] root 35 2 0 21:31 ? 00:00:00 [inet_frag_wq] root 36 2 0 21:31 ? 00:00:00 [kworker/0:1-events] root 37 2 0 21:31 ? 00:00:00 [kworker/1:1-events] root 38 2 0 21:31 ? 00:00:00 [oom_reaper] root 39 2 0 21:31 ? 00:00:00 [writeback] root 40 2 0 21:31 ? 00:00:00 [kcompactd0] root 46 2 0 21:31 ? 00:00:00 [kworker/3:1-events] root 78 2 0 21:31 ? 00:00:00 [kworker/2:1-mm_percpu_wq] root 79 2 0 21:31 ? 00:00:00 [kblockd] root 80 2 0 21:31 ? 00:00:00 [blkcg_punt_bio] root 81 2 0 21:31 ? 00:00:00 [ata_sff] root 82 2 0 21:31 ? 00:00:00 [md] root 83 2 0 21:31 ? 00:00:00 [kworker/0:1H-kblockd] root 84 2 0 21:31 ? 00:00:00 [rpciod] root 85 2 0 21:31 ? 00:00:00 [kworker/u9:0-xprtiod] root 86 2 0 21:31 ? 00:00:00 [xprtiod] root 87 2 0 21:31 ? 00:00:00 [kworker/u8:2-events_unbound] root 88 2 0 21:31 ? 00:00:00 [kswapd0] root 89 2 0 21:31 ? 00:00:00 [nfsiod] root 90 2 0 21:31 ? 00:00:00 [cifsiod] root 91 2 0 21:31 ? 00:00:00 [smb3decryptd] root 92 2 0 21:31 ? 00:00:00 [cifsfileinfoput] root 93 2 0 21:31 ? 00:00:00 [cifsoplockd] root 94 2 0 21:31 ? 00:00:00 [deferredclose] root 96 2 0 21:31 ? 00:00:00 [acpi_thermal_pm] root 97 2 0 21:31 ? 00:00:00 [hwrng] root 98 2 0 21:31 ? 00:00:00 [kworker/0:2-events] root 99 2 0 21:31 ? 00:00:00 [nvme-wq] root 100 2 0 21:31 ? 00:00:00 [nvme-reset-wq] root 101 2 0 21:31 ? 00:00:00 [nvme-delete-wq] root 102 2 0 21:31 ? 00:00:00 [scsi_eh_0] root 103 2 0 21:31 ? 00:00:00 [scsi_tmf_0] root 104 2 0 21:31 ? 00:00:00 [scsi_eh_1] root 105 2 0 21:31 ? 00:00:00 [scsi_tmf_1] root 106 2 0 21:31 ? 00:00:00 [scsi_eh_2] root 107 2 0 21:31 ? 00:00:00 [scsi_tmf_2] root 108 2 0 21:31 ? 00:00:00 [scsi_eh_3] root 109 2 0 21:31 ? 00:00:00 [scsi_tmf_3] root 110 2 0 21:31 ? 00:00:00 [scsi_eh_4] root 111 2 0 21:31 ? 00:00:00 [scsi_tmf_4] root 112 2 0 21:31 ? 00:00:00 [scsi_eh_5] root 113 2 0 21:31 ? 00:00:00 [scsi_tmf_5] root 114 2 0 21:31 ? 00:00:00 [kworker/u8:3-events_unbound] root 115 2 0 21:31 ? 00:00:00 [kworker/u8:4-events_unbound] root 116 2 0 21:31 ? 00:00:00 [kworker/u8:5-events_unbound] root 117 2 0 21:31 ? 00:00:00 [kworker/u8:6-events_unbound] root 118 2 0 21:31 ? 00:00:00 [kworker/u8:7-events_unbound] root 119 2 0 21:31 ? 00:00:00 [kworker/2:2-mld] root 120 2 0 21:31 ? 00:00:00 [raid5wq] root 121 2 0 21:31 ? 00:00:00 [dm_bufio_cache] root 122 2 0 21:31 ? 00:00:00 [mld] root 123 2 0 21:31 ? 00:00:00 [ipv6_addrconf] root 128 2 0 21:31 ? 00:00:00 [kworker/3:1H-kblockd] root 129 2 0 21:31 ? 00:00:00 [kworker/2:3] root 130 2 0 21:31 ? 00:00:00 [jbd2/vda-8] root 131 2 0 21:31 ? 00:00:00 [ext4-rsv-conver] root 162 2 0 21:31 ? 00:00:00 [kworker/1:1H-kblockd] root 168 1 0 21:31 ? 00:00:00 /sbin/udevd -d root 308 2 0 21:31 ? 00:00:00 [kworker/2:1H-kblockd] root 366 1 0 21:31 ? 00:00:00 sshd: /usr/sbin/sshd [listener] 0 of 10-100 startups rpc 371 1 0 21:31 ? 00:00:00 /usr/sbin/rpcbind rpcuser 378 1 0 21:31 ? 00:00:00 /usr/sbin/rpc.statd root 384 1 0 21:31 ? 00:00:00 /usr/sbin/atd -f root 397 2 0 21:31 ? 00:00:00 [kworker/u9:1] root 400 2 0 21:31 ? 00:00:00 [lockd] root 402 2 0 21:31 ? 00:00:00 [nfsd] root 403 2 0 21:31 ? 00:00:00 [nfsd] root 404 2 0 21:31 ? 00:00:00 [nfsd] root 405 2 0 21:31 ? 00:00:00 [nfsd] root 406 2 0 21:31 ? 00:00:00 [nfsd] root 407 2 0 21:31 ? 00:00:00 [nfsd] root 408 2 0 21:31 ? 00:00:00 [nfsd] root 409 2 0 21:31 ? 00:00:00 [nfsd] root 411 1 0 21:31 ? 00:00:00 /usr/sbin/rpc.mountd root 416 1 0 21:31 ? 00:00:00 /usr/sbin/syslogd -ss root 420 1 0 21:31 ? 00:00:00 /usr/sbin/crond root 430 1 0 21:31 ? 00:00:00 /bin/sh /bin/start_getty 115200 ttyS0 vt102 root 431 1 0 21:31 ? 00:00:00 /bin/sh /bin/start_getty 115200 ttyS1 vt102 root 432 1 0 21:31 tty1 00:00:00 /sbin/getty 38400 tty1 root 434 430 0 21:31 ttyS0 00:00:00 /sbin/getty -L 115200 ttyS0 vt102 root 436 431 0 21:31 ttyS1 00:00:00 -sh root 516 366 0 21:31 ? 00:00:00 sshd: root@notty root 518 516 0 21:31 ? 00:00:00 ps -ef NOTE: ... ok NOTE: test_syslog_logger (oe_syslog.SyslogTestConfig.test_syslog_logger) DEBUG: [Running]$ ssh -l root -o ServerAliveCountMax=2 -o ServerAliveInterval=30 -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR 192.168.7.2 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; logger foobar DEBUG: Waiting for process output: time: 1699047099.3490453, endtime: 1699047399.3478885 DEBUG: Data from SSH call: DEBUG: [Command returned '0' after 0.33 seconds] DEBUG: Command: logger foobar 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.2 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; grep foobar /var/log/messages DEBUG: Waiting for process output: time: 1699047100.6813042, endtime: 1699047400.6800816 DEBUG: Partial data from SSH call: Nov 3 21:31:36 qemux86-64 root: foobar DEBUG: Waiting for process output: time: 1699047101.0188866, endtime: 1699047401.018885 DEBUG: Data from SSH call: Nov 3 21:31:36 qemux86-64 root: foobar DEBUG: [Command returned '0' after 0.54 seconds] DEBUG: Command: grep foobar /var/log/messages Status: 0 Output: Nov 3 21:31:36 qemux86-64 root: foobar NOTE: ... ok NOTE: test_syslog_restart (oe_syslog.SyslogTestConfig.test_syslog_restart) DEBUG: [Running]$ ssh -l root -o ServerAliveCountMax=2 -o ServerAliveInterval=30 -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR 192.168.7.2 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; pidof systemd-journald DEBUG: Waiting for process output: time: 1699047101.2214792, endtime: 1699047401.2202482 DEBUG: Data from SSH call: DEBUG: [Command returned '1' after 0.36 seconds] DEBUG: Command: pidof systemd-journald Status: 1 Output: DEBUG: systemd-journald is not running DEBUG: [Running]$ ssh -l root -o ServerAliveCountMax=2 -o ServerAliveInterval=30 -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR 192.168.7.2 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; pidof rsyslogd DEBUG: Waiting for process output: time: 1699047101.5799804, endtime: 1699047401.5789022 DEBUG: Data from SSH call: DEBUG: [Command returned '1' after 0.34 seconds] DEBUG: Command: pidof rsyslogd Status: 1 Output: DEBUG: rsyslogd is not running DEBUG: [Running]$ ssh -l root -o ServerAliveCountMax=2 -o ServerAliveInterval=30 -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR 192.168.7.2 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; pidof syslogd DEBUG: Waiting for process output: time: 1699047101.922678, endtime: 1699047401.921347 DEBUG: Partial data from SSH call: 416 DEBUG: Waiting for process output: time: 1699047102.2569523, endtime: 1699047402.2569504 DEBUG: Data from SSH call: 416 DEBUG: [Command returned '0' after 0.54 seconds] DEBUG: Command: pidof syslogd Status: 0 Output: 416 DEBUG: [Running]$ ssh -l root -o ServerAliveCountMax=2 -o ServerAliveInterval=30 -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR 192.168.7.2 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; pidof klogd DEBUG: Waiting for process output: time: 1699047102.4590526, endtime: 1699047402.4577549 DEBUG: Data from SSH call: DEBUG: [Command returned '1' after 0.34 seconds] DEBUG: Command: pidof klogd Status: 1 Output: DEBUG: klogd is not running No syslog found to restart, ignoring NOTE: ... ok NOTE: test_syslog_startup_config (oe_syslog.SyslogTestConfig.test_syslog_startup_config) DEBUG: Checking if 'VIRTUAL-RUNTIME_init_manager' value is 'systemd' to skip test DEBUG: Checking if at least one of busybox-syslog is installed NOTE: ... skipped 'Test requires busybox-syslog to be installed' Test requires busybox-syslog to be installed NOTE: test_opkg_install_from_repo (opkg.OpkgRepoTest.test_opkg_install_from_repo) DEBUG: Checking if at least one of opkg is installed NOTE: ... skipped 'Test requires opkg to be installed' Test requires opkg to be installed NOTE: test_pam (pam.PamBasicTest.test_pam) DEBUG: Checking if at least one of shadow-base is installed DEBUG: Checking if at least one of shadow is installed DEBUG: Checking if pam is in DISTRO_FEATURES or IMAGE_FEATURES NOTE: ... skipped 'Test requires pam to be in DISTRO_FEATURES' Test requires pam to be in DISTRO_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.2 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; which LSB_Test.sh DEBUG: Waiting for process output: time: 1699047102.8038876, endtime: 1699047402.8026643 DEBUG: Data from SSH call: DEBUG: [Command returned '1' after 0.34 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.2 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; dmesg > /tmp/dmesg_output.log DEBUG: Waiting for process output: time: 1699047103.1453927, endtime: 1699047403.1438165 DEBUG: Data from SSH call: DEBUG: [Command returned '0' after 0.34 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.2 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; test -f /var/log/ DEBUG: Waiting for process output: time: 1699047103.4860039, endtime: 1699047403.4848754 DEBUG: Data from SSH call: DEBUG: [Command returned '1' after 0.33 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.2 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; test -d /var/log/ DEBUG: Waiting for process output: time: 1699047103.817045, endtime: 1699047403.8158603 DEBUG: Data from SSH call: DEBUG: [Command returned '0' after 0.33 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.2 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; find /var/log//*.log -maxdepth 1 -type f DEBUG: Waiting for process output: time: 1699047104.1482327, endtime: 1699047404.1468337 DEBUG: Partial data from SSH call: /var/log//auth.log /var/log//kern.log /var/log//mail.log DEBUG: Waiting for process output: time: 1699047104.4903064, endtime: 1699047404.490304 DEBUG: Data from SSH call: /var/log//auth.log /var/log//kern.log /var/log//mail.log DEBUG: [Command returned '0' after 0.54 seconds] DEBUG: Command: find /var/log//*.log -maxdepth 1 -type f Status: 0 Output: /var/log//auth.log /var/log//kern.log /var/log//mail.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.2 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; test -f /var/log/dmesg DEBUG: Waiting for process output: time: 1699047104.6941977, endtime: 1699047404.6922033 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.2 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; test -f /tmp/dmesg_output.log DEBUG: Waiting for process output: time: 1699047105.0638154, endtime: 1699047405.062467 DEBUG: Data from SSH call: DEBUG: [Command returned '0' after 0.35 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.2:/var/log//auth.log /home/pokybuild/yocto-worker/no-x11/build/build/tmp/work/qemux86_64-poky-linux/core-image-full-cmdline/1.0-r0/target_logs DEBUG: Data from SSH call: DEBUG: [Command returned '0' after 0.15 seconds] DEBUG: [Running]$ scp -o ServerAliveCountMax=2 -o ServerAliveInterval=30 -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR root@192.168.7.2:/var/log//kern.log /home/pokybuild/yocto-worker/no-x11/build/build/tmp/work/qemux86_64-poky-linux/core-image-full-cmdline/1.0-r0/target_logs DEBUG: Data from SSH call: DEBUG: [Command returned '0' after 0.15 seconds] DEBUG: [Running]$ scp -o ServerAliveCountMax=2 -o ServerAliveInterval=30 -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR root@192.168.7.2:/var/log//mail.log /home/pokybuild/yocto-worker/no-x11/build/build/tmp/work/qemux86_64-poky-linux/core-image-full-cmdline/1.0-r0/target_logs DEBUG: Data from SSH call: DEBUG: [Command returned '0' after 0.16 seconds] DEBUG: [Running]$ scp -o ServerAliveCountMax=2 -o ServerAliveInterval=30 -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR root@192.168.7.2:/var/log/dmesg /home/pokybuild/yocto-worker/no-x11/build/build/tmp/work/qemux86_64-poky-linux/core-image-full-cmdline/1.0-r0/target_logs DEBUG: Data from SSH call: DEBUG: [Command returned '0' after 0.14 seconds] DEBUG: [Running]$ scp -o ServerAliveCountMax=2 -o ServerAliveInterval=30 -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR root@192.168.7.2:/tmp/dmesg_output.log /home/pokybuild/yocto-worker/no-x11/build/build/tmp/work/qemux86_64-poky-linux/core-image-full-cmdline/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.2 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: 1699047106.2164934, endtime: 1699047406.2150416 DEBUG: Partial data from SSH call: Intel Xeon E3-12xx v2 (Ivy Bridge) DEBUG: Waiting for process output: time: 1699047106.5571637, endtime: 1699047406.557143 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.2 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: 1699047106.760088, endtime: 1699047406.758641 DEBUG: Partial data from SSH call: 4 DEBUG: Waiting for process output: time: 1699047107.0957124, endtime: 1699047407.0957098 DEBUG: Data from SSH call: 4 DEBUG: [Command returned '0' after 0.54 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.2 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; cat /proc/cpuinfo | grep "processor" | wc -l DEBUG: Waiting for process output: time: 1699047107.2982855, endtime: 1699047407.2970357 DEBUG: Partial data from SSH call: 4 DEBUG: Waiting for process output: time: 1699047107.6415708, endtime: 1699047407.6415682 DEBUG: Data from SSH call: 4 DEBUG: [Command returned '0' after 0.55 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.2 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; uname -m DEBUG: Waiting for process output: time: 1699047107.843739, endtime: 1699047407.8424425 DEBUG: Partial data from SSH call: x86_64 DEBUG: Waiting for process output: time: 1699047108.1797364, endtime: 1699047408.1797345 DEBUG: Data from SSH call: x86_64 DEBUG: [Command returned '0' after 0.54 seconds] DEBUG: Command: uname -m Status: 0 Output: x86_64 NOTE: ... ok NOTE: test_perl_works (perl.PerlTest.test_perl_works) DEBUG: Checking if at least one of perl 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.2 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; perl -e '$_="Uryyb, jbeyq"; tr/a-zA-Z/n-za-mN-ZA-M/;print' DEBUG: Waiting for process output: time: 1699047108.3831003, endtime: 1699047408.3818188 DEBUG: Partial data from SSH call: Hello, world DEBUG: Waiting for process output: time: 1699047108.724392, endtime: 1699047408.724389 DEBUG: Data from SSH call: Hello, world DEBUG: [Command returned '0' after 0.54 seconds] DEBUG: Command: perl -e '$_="Uryyb, jbeyq"; tr/a-zA-Z/n-za-mN-ZA-M/;print' Status: 0 Output: Hello, world NOTE: ... ok NOTE: test_ptestrunner_expectfail (ptest.PtestRunnerTest.test_ptestrunner_expectfail) DEBUG: Checking if at least one of ptest-runner is installed NOTE: ... skipped 'Test requires ptest-runner to be installed' Test requires ptest-runner to be installed NOTE: test_ptestrunner_expectsuccess (ptest.PtestRunnerTest.test_ptestrunner_expectsuccess) DEBUG: Checking if at least one of ptest-runner is installed NOTE: ... skipped 'Test requires ptest-runner to be installed' Test requires ptest-runner to be installed NOTE: test_python3 (python.PythonTest.test_python3) DEBUG: Checking if at least one of python3-core is installed NOTE: ... skipped 'Test requires python3-core to be installed' Test requires python3-core to be installed NOTE: test_rpm_help (rpm.RpmBasicTest.test_rpm_help) DEBUG: Checking if at least one of rpm is installed NOTE: ... skipped 'Test requires rpm to be installed' Test requires rpm to be installed NOTE: test_rpm_query (rpm.RpmBasicTest.test_rpm_query) NOTE: ... skipped "Test case rpm.RpmBasicTest.test_rpm_query depends on rpm.RpmBasicTest.test_rpm_help but it didn't pass/run." Test case rpm.RpmBasicTest.test_rpm_query depends on rpm.RpmBasicTest.test_rpm_help but it didn't pass/run. NOTE: test_rpm_query_nonroot (rpm.RpmBasicTest.test_rpm_query_nonroot) NOTE: ... skipped "Test case rpm.RpmBasicTest.test_rpm_query_nonroot depends on rpm.RpmBasicTest.test_rpm_query but it didn't pass/run." Test case rpm.RpmBasicTest.test_rpm_query_nonroot depends on rpm.RpmBasicTest.test_rpm_query but it didn't pass/run. NOTE: test_rpm_install (rpm.RpmInstallRemoveTest.test_rpm_install) NOTE: ... skipped "Test case rpm.RpmInstallRemoveTest.test_rpm_install depends on rpm.RpmBasicTest.test_rpm_query but it didn't pass/run." Test case rpm.RpmInstallRemoveTest.test_rpm_install depends on rpm.RpmBasicTest.test_rpm_query but it didn't pass/run. NOTE: test_rpm_remove (rpm.RpmInstallRemoveTest.test_rpm_remove) NOTE: ... skipped "Test case rpm.RpmInstallRemoveTest.test_rpm_remove depends on rpm.RpmInstallRemoveTest.test_rpm_install but it didn't pass/run." Test case rpm.RpmInstallRemoveTest.test_rpm_remove depends on rpm.RpmInstallRemoveTest.test_rpm_install but it didn't pass/run. NOTE: test_check_rpm_install_removal_log_file_size (rpm.RpmInstallRemoveTest.test_check_rpm_install_removal_log_file_size) NOTE: ... skipped "Test case rpm.RpmInstallRemoveTest.test_check_rpm_install_removal_log_file_size depends on rpm.RpmInstallRemoveTest.test_rpm_remove but it didn't pass/run." Test case rpm.RpmInstallRemoveTest.test_check_rpm_install_removal_log_file_size depends on rpm.RpmInstallRemoveTest.test_rpm_remove but it didn't pass/run. NOTE: test_rusthelloworld (rust.RustHelloworldTest.test_rusthelloworld) DEBUG: Checking if at least one of rust-hello-world is installed NOTE: ... skipped 'Test requires rust-hello-world to be installed' Test requires rust-hello-world to be installed NOTE: test_scp_file (scp.ScpTest.test_scp_file) DEBUG: Checking if at least one of openssh-scp is installed DEBUG: [Running]$ scp -o ServerAliveCountMax=2 -o ServerAliveInterval=30 -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR /tmp/tmpggult5oe root@192.168.7.2:/tmp/test_scp_file DEBUG: Data from SSH call: DEBUG: [Command returned '0' after 0.28 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.2 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; ls -la /tmp/test_scp_file DEBUG: Waiting for process output: time: 1699047109.224671, endtime: 1699047409.2229822 DEBUG: Partial data from SSH call: -rw------- 1 root root 4194304 Nov 3 21:31 /tmp/test_scp_file DEBUG: Waiting for process output: time: 1699047109.5660968, endtime: 1699047409.5660944 DEBUG: Data from SSH call: -rw------- 1 root root 4194304 Nov 3 21:31 /tmp/test_scp_file DEBUG: [Command returned '0' after 0.54 seconds] DEBUG: Command: ls -la /tmp/test_scp_file Status: 0 Output: -rw------- 1 root root 4194304 Nov 3 21:31 /tmp/test_scp_file DEBUG: [Running]$ ssh -l root -o ServerAliveCountMax=2 -o ServerAliveInterval=30 -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR 192.168.7.2 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; rm /tmp/test_scp_file DEBUG: Waiting for process output: time: 1699047109.768422, endtime: 1699047409.7669034 DEBUG: Data from SSH call: DEBUG: [Command returned '0' after 0.35 seconds] DEBUG: Command: rm /tmp/test_scp_file Status: 0 Output: NOTE: ... ok NOTE: test_stap (stap.StapTest.test_stap) DEBUG: Checking if at least one of kernel-devsrc is installed NOTE: ... skipped 'Test requires kernel-devsrc to be installed' Test requires kernel-devsrc to be installed NOTE: test_systemd_basic (systemd.SystemdBasicTests.test_systemd_basic) DEBUG: Checking if 'VIRTUAL-RUNTIME_init_manager' value is not 'systemd' to skip test NOTE: ... skipped 'systemd is not the init manager for this image' systemd is not the init manager for this image NOTE: test_systemd_failed (systemd.SystemdBasicTests.test_systemd_failed) NOTE: ... skipped "Test case systemd.SystemdBasicTests.test_systemd_failed depends on systemd.SystemdBasicTests.test_systemd_basic but it didn't pass/run." Test case systemd.SystemdBasicTests.test_systemd_failed depends on systemd.SystemdBasicTests.test_systemd_basic but it didn't pass/run. NOTE: test_systemd_list (systemd.SystemdBasicTests.test_systemd_list) NOTE: ... skipped "Test case systemd.SystemdBasicTests.test_systemd_list depends on systemd.SystemdBasicTests.test_systemd_basic but it didn't pass/run." Test case systemd.SystemdBasicTests.test_systemd_list depends on systemd.SystemdBasicTests.test_systemd_basic but it didn't pass/run. NOTE: test_systemd_boot_time (systemd.SystemdJournalTests.test_systemd_boot_time) NOTE: ... skipped "Test case systemd.SystemdJournalTests.test_systemd_boot_time depends on systemd.SystemdBasicTests.test_systemd_basic but it didn't pass/run." Test case systemd.SystemdJournalTests.test_systemd_boot_time depends on systemd.SystemdBasicTests.test_systemd_basic but it didn't pass/run. NOTE: test_systemd_journal (systemd.SystemdJournalTests.test_systemd_journal) NOTE: ... skipped "Test case systemd.SystemdJournalTests.test_systemd_journal depends on systemd.SystemdBasicTests.test_systemd_basic but it didn't pass/run." Test case systemd.SystemdJournalTests.test_systemd_journal depends on systemd.SystemdBasicTests.test_systemd_basic but it didn't pass/run. NOTE: test_systemd_status (systemd.SystemdServiceTests.test_systemd_status) NOTE: ... skipped "Test case systemd.SystemdServiceTests.test_systemd_status depends on systemd.SystemdBasicTests.test_systemd_basic but it didn't pass/run." Test case systemd.SystemdServiceTests.test_systemd_status depends on systemd.SystemdBasicTests.test_systemd_basic but it didn't pass/run. NOTE: test_systemd_disable_enable (systemd.SystemdServiceTests.test_systemd_disable_enable) DEBUG: Checking if read-only-rootfs is not in DISTRO_FEATURES or IMAGE_FEATURES NOTE: ... skipped "Test case systemd.SystemdServiceTests.test_systemd_disable_enable depends on systemd.SystemdServiceTests.test_systemd_status but it didn't pass/run." Test case systemd.SystemdServiceTests.test_systemd_disable_enable depends on systemd.SystemdServiceTests.test_systemd_status but it didn't pass/run. NOTE: test_systemd_disable_enable_ro (systemd.SystemdServiceTests.test_systemd_disable_enable_ro) DEBUG: Checking if read-only-rootfs is in DISTRO_FEATURES or IMAGE_FEATURES NOTE: ... skipped 'Test is only meant to run with read-only-rootfs in IMAGE_FEATURES' Test is only meant to run with read-only-rootfs in IMAGE_FEATURES NOTE: test_systemd_stop_start (systemd.SystemdServiceTests.test_systemd_stop_start) NOTE: ... skipped "Test case systemd.SystemdServiceTests.test_systemd_stop_start depends on systemd.SystemdServiceTests.test_systemd_status but it didn't pass/run." Test case systemd.SystemdServiceTests.test_systemd_stop_start depends on systemd.SystemdServiceTests.test_systemd_status but it didn't pass/run. NOTE: test_wayland_info (weston.WestonTest.test_wayland_info) DEBUG: Checking if at least one of wayland-utils is installed NOTE: ... skipped 'Test requires wayland-utils to be installed' Test requires wayland-utils to be installed NOTE: test_weston_can_initialize_new_wayland_compositor (weston.WestonTest.test_weston_can_initialize_new_wayland_compositor) DEBUG: Checking if at least one of weston is installed NOTE: ... skipped 'Test requires weston to be installed' Test requires weston to be installed NOTE: test_weston_running (weston.WestonTest.test_weston_running) DEBUG: Checking if at least one of weston is installed NOTE: ... skipped 'Test requires weston to be installed' Test requires weston to be 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.2 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; rm /tmp/weston-2.log DEBUG: Waiting for process output: time: 1699047110.1202898, endtime: 1699047410.1190736 DEBUG: Partial data from SSH call: rm: cannot remove '/tmp/weston-2.log': No such file or directory DEBUG: Waiting for process output: time: 1699047110.4557235, endtime: 1699047410.4557216 DEBUG: Data from SSH call: rm: cannot remove '/tmp/weston-2.log': No such file or directory DEBUG: [Command returned '1' after 0.54 seconds] DEBUG: Command: rm /tmp/weston-2.log Status: 1 Output: rm: cannot remove '/tmp/weston-2.log': No such file or directory NOTE: test_xorg_running (xorg.XorgTest.test_xorg_running) DEBUG: Checking if at least one of xserver-nodm-init is installed NOTE: ... skipped 'Test requires xserver-nodm-init to be installed' Test requires xserver-nodm-init to be installed NOTE: ---------------------------------------------------------------------- NOTE: Ran 68 tests in 21.416s NOTE: OK NOTE: (skipped=56) 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: 357271.70 runqemu - INFO - Removing /home/pokybuild/tmp/core-image-full-cmdline-qemux86-64.ext4.3705306 tput: No value for $TERM and no -T specified RESULTS: RESULTS - date.DateTest.test_date: PASSED (2.23s) RESULTS - df.DfTest.test_df: PASSED (0.54s) RESULTS - logrotate.LogrotateTest.test_logrotate_newlog: PASSED (2.46s) RESULTS - logrotate.LogrotateTest.test_logrotate_wtmp: PASSED (2.11s) RESULTS - oe_syslog.SyslogTest.test_syslog_running: PASSED (0.55s) RESULTS - oe_syslog.SyslogTestConfig.test_syslog_logger: PASSED (1.87s) RESULTS - oe_syslog.SyslogTestConfig.test_syslog_restart: PASSED (1.58s) RESULTS - parselogs.ParseLogsTest.test_parselogs: PASSED (5.24s) RESULTS - perl.PerlTest.test_perl_works: PASSED (0.54s) RESULTS - ping.PingTest.test_ping: PASSED (0.02s) RESULTS - scp.ScpTest.test_scp_file: PASSED (1.17s) RESULTS - ssh.SSHTest.test_ssh: PASSED (1.09s) RESULTS - apt.AptRepoTest.test_apt_install_from_repo: SKIPPED (0.00s) RESULTS - buildcpio.BuildCpioTest.test_cpio: SKIPPED (0.00s) RESULTS - buildgalculator.GalculatorTest.test_galculator: SKIPPED (0.00s) RESULTS - buildlzip.BuildLzipTest.test_lzip: SKIPPED (0.00s) RESULTS - connman.ConnmanTest.test_connmand_help: SKIPPED (0.00s) RESULTS - connman.ConnmanTest.test_connmand_running: SKIPPED (0.00s) RESULTS - dnf.DnfBasicTest.test_dnf_help: SKIPPED (0.00s) RESULTS - dnf.DnfBasicTest.test_dnf_history: SKIPPED (0.00s) RESULTS - dnf.DnfBasicTest.test_dnf_info: SKIPPED (0.00s) RESULTS - dnf.DnfBasicTest.test_dnf_search: SKIPPED (0.00s) RESULTS - dnf.DnfBasicTest.test_dnf_version: SKIPPED (0.00s) RESULTS - dnf.DnfRepoTest.test_dnf_exclude: SKIPPED (0.00s) RESULTS - dnf.DnfRepoTest.test_dnf_install: SKIPPED (0.00s) RESULTS - dnf.DnfRepoTest.test_dnf_install_dependency: SKIPPED (0.00s) RESULTS - dnf.DnfRepoTest.test_dnf_install_from_disk: SKIPPED (0.00s) RESULTS - dnf.DnfRepoTest.test_dnf_install_from_http: SKIPPED (0.00s) RESULTS - dnf.DnfRepoTest.test_dnf_installroot: SKIPPED (0.00s) RESULTS - dnf.DnfRepoTest.test_dnf_installroot_usrmerge: SKIPPED (0.00s) RESULTS - dnf.DnfRepoTest.test_dnf_makecache: SKIPPED (0.00s) RESULTS - dnf.DnfRepoTest.test_dnf_reinstall: SKIPPED (0.00s) RESULTS - dnf.DnfRepoTest.test_dnf_repoinfo: SKIPPED (0.00s) RESULTS - gcc.GccCompileTest.test_gcc_compile: SKIPPED (0.00s) RESULTS - gcc.GccCompileTest.test_gpp2_compile: SKIPPED (0.00s) RESULTS - gcc.GccCompileTest.test_gpp_compile: SKIPPED (0.00s) RESULTS - gcc.GccCompileTest.test_make: SKIPPED (0.00s) RESULTS - gi.GObjectIntrospectionTest.test_python: SKIPPED (0.00s) RESULTS - go.GoHelloworldTest.test_gohelloworld: SKIPPED (0.00s) RESULTS - kernelmodule.KernelModuleTest.test_kernel_module: SKIPPED (0.00s) RESULTS - ldd.LddTest.test_ldd: SKIPPED (0.00s) RESULTS - oe_syslog.SyslogTestConfig.test_syslog_startup_config: SKIPPED (0.00s) RESULTS - opkg.OpkgRepoTest.test_opkg_install_from_repo: SKIPPED (0.00s) RESULTS - pam.PamBasicTest.test_pam: SKIPPED (0.00s) RESULTS - ptest.PtestRunnerTest.test_ptestrunner_expectfail: SKIPPED (0.00s) RESULTS - ptest.PtestRunnerTest.test_ptestrunner_expectsuccess: SKIPPED (0.00s) RESULTS - python.PythonTest.test_python3: SKIPPED (0.00s) RESULTS - rpm.RpmBasicTest.test_rpm_help: SKIPPED (0.00s) RESULTS - rpm.RpmBasicTest.test_rpm_query: SKIPPED (0.00s) RESULTS - rpm.RpmBasicTest.test_rpm_query_nonroot: SKIPPED (0.00s) RESULTS - rpm.RpmInstallRemoveTest.test_check_rpm_install_removal_log_file_size: SKIPPED (0.00s) RESULTS - rpm.RpmInstallRemoveTest.test_rpm_install: SKIPPED (0.00s) RESULTS - rpm.RpmInstallRemoveTest.test_rpm_remove: SKIPPED (0.00s) RESULTS - rust.RustHelloworldTest.test_rusthelloworld: SKIPPED (0.00s) RESULTS - stap.StapTest.test_stap: SKIPPED (0.00s) RESULTS - systemd.SystemdBasicTests.test_systemd_basic: SKIPPED (0.00s) RESULTS - systemd.SystemdBasicTests.test_systemd_failed: SKIPPED (0.00s) RESULTS - systemd.SystemdBasicTests.test_systemd_list: SKIPPED (0.00s) RESULTS - systemd.SystemdJournalTests.test_systemd_boot_time: SKIPPED (0.00s) RESULTS - systemd.SystemdJournalTests.test_systemd_journal: SKIPPED (0.00s) RESULTS - systemd.SystemdServiceTests.test_systemd_disable_enable: SKIPPED (0.00s) RESULTS - systemd.SystemdServiceTests.test_systemd_disable_enable_ro: SKIPPED (0.00s) RESULTS - systemd.SystemdServiceTests.test_systemd_status: SKIPPED (0.00s) RESULTS - systemd.SystemdServiceTests.test_systemd_stop_start: SKIPPED (0.00s) RESULTS - weston.WestonTest.test_wayland_info: SKIPPED (0.00s) RESULTS - weston.WestonTest.test_weston_can_initialize_new_wayland_compositor: SKIPPED (0.00s) RESULTS - weston.WestonTest.test_weston_running: SKIPPED (0.00s) RESULTS - xorg.XorgTest.test_xorg_running: SKIPPED (0.00s) SUMMARY: core-image-full-cmdline () - Ran 68 tests in 21.417s core-image-full-cmdline - OK - All required tests passed (successes=12, skipped=56, failures=0, errors=0) DEBUG: Python function do_testimage finished