DEBUG: Executing python function extend_recipe_sysroot NOTE: Direct dependencies are ['/home/pokybuild/yocto-worker/pkgman-non-rpm/build/meta/recipes-devtools/qemu/qemu-helper-native_1.0.bb:do_populate_sysroot', '/home/pokybuild/yocto-worker/pkgman-non-rpm/build/meta/recipes-devtools/qemu/qemu-native_7.2.0.bb:do_populate_sysroot', '/home/pokybuild/yocto-worker/pkgman-non-rpm/build/meta/recipes-devtools/qemu/qemu-system-native_7.2.0.bb:do_populate_sysroot', 'virtual:native:/home/pokybuild/yocto-worker/pkgman-non-rpm/build/meta/recipes-devtools/opkg-utils/opkg-utils_0.5.0.bb:do_populate_sysroot', 'virtual:native:/home/pokybuild/yocto-worker/pkgman-non-rpm/build/meta/recipes-devtools/opkg/opkg_0.6.1.bb:do_populate_sysroot', 'virtual:native:/home/pokybuild/yocto-worker/pkgman-non-rpm/build/meta/recipes-devtools/pseudo/pseudo_git.bb:do_populate_sysroot', 'virtual:native:/home/pokybuild/yocto-worker/pkgman-non-rpm/build/meta/recipes-devtools/unfs3/unfs3_git.bb:do_populate_sysroot'] NOTE: Installed into sysroot: [] NOTE: Skipping as already exists in sysroot: ['qemu-helper-native', 'qemu-native', 'qemu-system-native', 'opkg-utils-native', 'opkg-native', 'pseudo-native', 'unfs3-native', 'libtool-native', 'libsolv-native', 'libarchive-native', 'libtirpc-native', 'flex-native', 'xz-native', 'zlib-native', 'glib-2.0-native', 'perl-native', 'libslirp-native', 'alsa-lib-native', 'libsdl2-native', 'virglrenderer-native', 'libepoxy-native', 'pixman-native', 'dtc-native', 'texinfo-dummy-native', 'expat-native', 'cmake-native', 'bzip2-native', 'zstd-native', 'gettext-minimal-native', 'm4-native', 'util-linux-native', 'python3-native', 'gettext-native', 'libffi-native', 'libpcre2-native', 'gdbm-native', 'make-native', 'libxext-native', 'libxrandr-native', 'libxrender-native', 'mesa-native', 'libx11-native', 'libdrm-native', 'ncurses-native', 'openssl-native', 'gnu-config-native', 'libcap-ng-native', 'util-linux-libuuid-native', 'sqlite3-native', 'readline-native', 'libnsl2-native', 'util-macros-native', 'xorgproto-native', 'wayland-protocols-native', 'chrpath-native', 'libxml2-native', 'xrandr-native', 'elfutils-native', 'makedepend-native', 'llvm-native', 'wayland-native', 'libxshmfence-native', 'python3-mako-native', 'libxxf86vm-native', 'libxdamage-native', 'libxfixes-native', 'xtrans-native', 'libxcb-native', 'libpciaccess-native', 'libpthread-stubs-native', 'libmicrohttpd-native', 'curl-native', 'libedit-native', 'python3-build-native', 'python3-installer-native', 'python3-pygments-native', 'python3-markupsafe-native', 'python3-wheel-native', 'python3-setuptools-native', 'libxdmcp-native', 'xcb-proto-native', 'libxau-native', 'gnutls-native', 'libgcrypt-native', 'python3-pyproject-hooks-native', 'python3-packaging-native', 'python3-flit-core-native', 'gmp-native', 'libunistring-native', 'libidn2-native', 'nettle-native', 'libgpg-error-native', 'libcap-native', 'unzip-native'] DEBUG: Python function extend_recipe_sysroot finished DEBUG: Executing python function do_testimage DEBUG: test suites: test_apt_install_from_repo (apt.AptRepoTest) test_ping (ping.PingTest) test_ssh (ssh.SSHTest) test_cpio (buildcpio.BuildCpioTest) test_galculator (buildgalculator.GalculatorTest) test_lzip (buildlzip.BuildLzipTest) test_connmand_help (connman.ConnmanTest) test_connmand_running (connman.ConnmanTest) test_date (date.DateTest) test_df (df.DfTest) test_dnf_help (dnf.DnfBasicTest) test_dnf_history (dnf.DnfBasicTest) test_dnf_info (dnf.DnfBasicTest) test_dnf_search (dnf.DnfBasicTest) test_dnf_version (dnf.DnfBasicTest) test_dnf_makecache (dnf.DnfRepoTest) test_dnf_exclude (dnf.DnfRepoTest) test_dnf_install (dnf.DnfRepoTest) test_dnf_install_dependency (dnf.DnfRepoTest) test_dnf_install_from_disk (dnf.DnfRepoTest) test_dnf_install_from_http (dnf.DnfRepoTest) test_dnf_installroot (dnf.DnfRepoTest) test_dnf_installroot_usrmerge (dnf.DnfRepoTest) test_dnf_reinstall (dnf.DnfRepoTest) test_dnf_repoinfo (dnf.DnfRepoTest) test_gcc_compile (gcc.GccCompileTest) test_gpp2_compile (gcc.GccCompileTest) test_gpp_compile (gcc.GccCompileTest) test_make (gcc.GccCompileTest) test_python (gi.GObjectIntrospectionTest) test_gohelloworld (go.GoHelloworldTest) test_kernel_module (kernelmodule.KernelModuleTest) test_ldd (ldd.LddTest) test_logrotate_wtmp (logrotate.LogrotateTest) test_logrotate_newlog (logrotate.LogrotateTest) test_syslog_running (oe_syslog.SyslogTest) test_syslog_logger (oe_syslog.SyslogTestConfig) test_syslog_restart (oe_syslog.SyslogTestConfig) test_syslog_startup_config (oe_syslog.SyslogTestConfig) test_opkg_install_from_repo (opkg.OpkgRepoTest) test_pam (pam.PamBasicTest) test_parselogs (parselogs.ParseLogsTest) test_perl_works (perl.PerlTest) test_ptestrunner_expectfail (ptest.PtestRunnerTest) test_ptestrunner_expectsuccess (ptest.PtestRunnerTest) test_python3 (python.PythonTest) test_rpm_help (rpm.RpmBasicTest) test_rpm_query (rpm.RpmBasicTest) test_rpm_query_nonroot (rpm.RpmBasicTest) test_rpm_install (rpm.RpmInstallRemoveTest) test_rpm_remove (rpm.RpmInstallRemoveTest) test_check_rpm_install_removal_log_file_size (rpm.RpmInstallRemoveTest) test_cargo_compile (rust.RustCompileTest) test_rust_compile (rust.RustCompileTest) test_rusthelloworld (rust.RustHelloworldTest) test_scp_file (scp.ScpTest) test_stap (stap.StapTest) test_systemd_basic (systemd.SystemdBasicTests) test_systemd_failed (systemd.SystemdBasicTests) test_systemd_list (systemd.SystemdBasicTests) test_systemd_boot_time (systemd.SystemdJournalTests) test_systemd_journal (systemd.SystemdJournalTests) test_systemd_status (systemd.SystemdServiceTests) test_systemd_disable_enable (systemd.SystemdServiceTests) test_systemd_disable_enable_ro (systemd.SystemdServiceTests) test_systemd_stop_start (systemd.SystemdServiceTests) test_wayland_info (weston.WestonTest) test_weston_can_initialize_new_wayland_compositor (weston.WestonTest) test_weston_running (weston.WestonTest) test_weston_supports_xwayland (weston.WestonTest) test_xorg_running (xorg.XorgTest) DEBUG: Using kvm for runqemu QMP Available for connection at /home/pokybuild/yocto-worker/pkgman-non-rpm/build/build/tmp/.xpog71sn DEBUG: Created listening socket for qemu serial console on: 127.0.0.1:44235 DEBUG: Created listening socket for qemu serial console on: 127.0.0.1:50589 DEBUG: launchcmd=runqemu snapshot kvm qemux86 /home/pokybuild/yocto-worker/pkgman-non-rpm/build/build/tmp/deploy/images/qemux86/core-image-sato-qemux86.ext4 tcpserial=44235:50589 bootparams=" printk.time=1" qemuparams="-pidfile /home/pokybuild/yocto-worker/pkgman-non-rpm/build/build/pidfile_2268056 -S -qmp unix:./.tivf8nyo,server,wait -qmp unix:./.xpog71sn,server,nowait" DEBUG: runqemu started, pid is 2268067 DEBUG: waiting at most 300 seconds for qemu pid (04/22/23 10:40:12) DEBUG: QMP Initializing to /home/pokybuild/yocto-worker/pkgman-non-rpm/build/build/tmp/.tivf8nyo DEBUG: QMP Connecting to /home/pokybuild/yocto-worker/pkgman-non-rpm/build/build/tmp/.tivf8nyo QMP connected to QEMU at 04/22/23 10:40:13 and took 0.60 seconds QMP released QEMU at 04/22/23 10:40:13 and took 0.14 seconds from connect DEBUG: qemu started in 0.74 seconds - qemu procces pid is 2268094 (04/22/23 10:40:13) DEBUG: qemu cmdline used: /home/pokybuild/yocto-worker/pkgman-non-rpm/build/build/tmp/work/x86_64-linux/qemu-helper-native/1.0-r1/recipe-sysroot-native/usr/bin/qemu-system-i386 -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-sato-qemux86.ext4.2268067,if=virtio,format=raw -usb -device usb-tablet -usb -device usb-kbd -cpu IvyBridge -machine q35,i8042=off -smp 4 -enable-kvm -m 512 -serial tcp:127.0.0.1:44235 -serial tcp:127.0.0.1:50589 -pidfile /home/pokybuild/yocto-worker/pkgman-non-rpm/build/build/pidfile_2268056 -S -qmp unix:./.tivf8nyo,server,wait -qmp unix:./.xpog71sn,server,nowait -serial mon:vc -device virtio-vga -display sdl,show-cursor=on -kernel /home/pokybuild/yocto-worker/pkgman-non-rpm/build/build/tmp/deploy/images/qemux86/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/pkgman-non-rpm/build/build/tmp/deploy/images/qemux86/bzImage] MACHINE: [qemux86] FSTYPE: [ext4] ROOTFS: [/home/pokybuild/yocto-worker/pkgman-non-rpm/build/build/tmp/deploy/images/qemux86/core-image-sato-qemux86.ext4] CONFFILE: [/home/pokybuild/yocto-worker/pkgman-non-rpm/build/build/tmp/deploy/images/qemux86/core-image-sato-qemux86.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-sato-qemux86.ext4.2268067 runqemu - INFO - Copy done in 0.238267183303833 seconds runqemu - INFO - Running /home/pokybuild/yocto-worker/pkgman-non-rpm/build/build/tmp/work/x86_64-linux/qemu-helper-native/1.0-r1/recipe-sysroot-native/usr/bin/qemu-system-i386 -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-sato-qemux86.ext4.2268067,if=virtio,format=raw -usb -device usb-tablet -usb -device usb-kbd -cpu IvyBridge -machine q35,i8042=off -smp 4 -enable-kvm -m 512 -serial tcp:127.0.0.1:44235 -serial tcp:127.0.0.1:50589 -pidfile /home/pokybuild/yocto-worker/pkgman-non-rpm/build/build/pidfile_2268056 -S -qmp unix:./.tivf8nyo,server,wait -qmp unix:./.xpog71sn,server,nowait -serial mon:vc -device virtio-vga -display sdl,show-cursor=on -kernel /home/pokybuild/yocto-worker/pkgman-non-rpm/build/build/tmp/deploy/images/qemux86/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: 998868.45 DEBUG: Waiting at most 1500 seconds for login banner (04/22/23 10:40:13) DEBUG: Connection from 127.0.0.1:55596 /home/pokybuild/yocto-worker/pkgman-non-rpm/build/meta/lib/oeqa/utils/qemurunner.py:486: ResourceWarning: unclosed self.server_socket = qemusock ResourceWarning: Enable tracemalloc to get the object allocation traceback DEBUG: Reached login banner in 4.47 seconds (04/22/23 10:40:17) DEBUG: Logged in as root in serial console NOTE: Not starting HTTPService for directory tmp/deploy/deb/ which doesn't exist NOTE: test_apt_install_from_repo (apt.AptRepoTest) DEBUG: Checking if at least one of apt is installed NOTE: ... skipped 'Test requires apt to be installed' Test requires apt to be installed NOTE: Stopped HTTPService on 0.0.0.0:0 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 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: 1682160021.0072963, endtime: 1682160320.9959326 DEBUG: Partial data from SSH call: Linux qemux86 6.1.25-yocto-standard #1 SMP PREEMPT_DYNAMIC Fri Apr 21 14:37:42 UTC 2023 i686 GNU/Linux DEBUG: Waiting for process output: time: 1682160021.3413746, endtime: 1682160321.341372 DEBUG: Data from SSH call: Linux qemux86 6.1.25-yocto-standard #1 SMP PREEMPT_DYNAMIC Fri Apr 21 14:37:42 UTC 2023 i686 GNU/Linux DEBUG: [Command returned '0' after 0.55 seconds] DEBUG: Command: uname -a Status: 0 Output: Linux qemux86 6.1.25-yocto-standard #1 SMP PREEMPT_DYNAMIC Fri Apr 21 14:37:42 UTC 2023 i686 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: 1682160021.5455303, endtime: 1682160321.5434382 DEBUG: Partial data from SSH call: cat: can't open '/etc/controllerimage': No such file or directory DEBUG: Waiting for process output: time: 1682160021.869833, endtime: 1682160321.86983 DEBUG: Data from SSH call: cat: can't open '/etc/controllerimage': No such file or directory DEBUG: [Command returned '1' after 0.53 seconds] DEBUG: Command: cat /etc/controllerimage Status: 1 Output: cat: can't open '/etc/controllerimage': No such file or directory NOTE: ... ok NOTE: test_cpio (buildcpio.BuildCpioTest) 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) 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) 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) DEBUG: Checking if at least one of connman 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; /usr/sbin/connmand --help DEBUG: Waiting for process output: time: 1682160022.0796704, endtime: 1682160322.0776875 DEBUG: Partial data from SSH call: Usage: connmand [OPTION?] Help Options: -h, --help Show help options Application Options: -c, --config=FILE Load the specified configuration file instead of /etc/connman/main.conf -d, --debug=DEBUG Specify debug options to enable -i, --device=DEV,... Specify networking devices or interfaces -I, --nodevice=DEV,... Specify networking interfaces to ignore -p, --plugin=NAME,... Specify plugins to load -P, --noplugin=NAME,... Specify plugins not to load -W, --wifi=NAME Specify driver for WiFi/Supplicant -n, --nodaemon Don't fork daemon to background -r, --nodnsproxy Don't support DNS resolving --nobacktrace Don't print out backtrace information -v, --version Show version information and exit DEBUG: Waiting for process output: time: 1682160022.4225473, endtime: 1682160322.422546 DEBUG: Data from SSH call: Usage: connmand [OPTION?] Help Options: -h, --help Show help options Application Options: -c, --config=FILE Load the specified configuration file instead of /etc/connman/main.conf -d, --debug=DEBUG Specify debug options to enable -i, --device=DEV,... Specify networking devices or interfaces -I, --nodevice=DEV,... Specify networking interfaces to ignore -p, --plugin=NAME,... Specify plugins to load -P, --noplugin=NAME,... Specify plugins not to load -W, --wifi=NAME Specify driver for WiFi/Supplicant -n, --nodaemon Don't fork daemon to background -r, --nodnsproxy Don't support DNS resolving --nobacktrace Don't print out backtrace information -v, --version Show version information and exit DEBUG: [Command returned '0' after 0.55 seconds] DEBUG: Command: /usr/sbin/connmand --help Status: 0 Output: Usage: connmand [OPTION?] Help Options: -h, --help Show help options Application Options: -c, --config=FILE Load the specified configuration file instead of /etc/connman/main.conf -d, --debug=DEBUG Specify debug options to enable -i, --device=DEV,... Specify networking devices or interfaces -I, --nodevice=DEV,... Specify networking interfaces to ignore -p, --plugin=NAME,... Specify plugins to load -P, --noplugin=NAME,... Specify plugins not to load -W, --wifi=NAME Specify driver for WiFi/Supplicant -n, --nodaemon Don't fork daemon to background -r, --nodnsproxy Don't support DNS resolving --nobacktrace Don't print out backtrace information -v, --version Show version information and exit NOTE: ... ok NOTE: test_connmand_running (connman.ConnmanTest) 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 | grep [c]onnmand DEBUG: Waiting for process output: time: 1682160022.6272082, endtime: 1682160322.6254694 DEBUG: Partial data from SSH call: 536 root 12008 S /usr/sbin/connmand DEBUG: Waiting for process output: time: 1682160022.952709, endtime: 1682160322.9527059 DEBUG: Data from SSH call: 536 root 12008 S /usr/sbin/connmand DEBUG: [Command returned '0' after 0.53 seconds] DEBUG: Command: ps | grep [c]onnmand Status: 0 Output: 536 root 12008 S /usr/sbin/connmand NOTE: ... ok NOTE: test_date (date.DateTest) 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: 1682160023.1579258, endtime: 1682160323.1557178 DEBUG: Partial data from SSH call: 2023-04-22 10:40:22 DEBUG: Waiting for process output: time: 1682160023.4984782, endtime: 1682160323.4984748 DEBUG: Data from SSH call: 2023-04-22 10:40:22 DEBUG: [Command returned '0' after 0.54 seconds] DEBUG: Command: date +"%Y-%m-%d %T" Status: 0 Output: 2023-04-22 10:40:22 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: 1682160023.7027245, endtime: 1682160323.7006705 DEBUG: Partial data from SSH call: Mon Mar 6 11:33:20 UTC 2017 DEBUG: Waiting for process output: time: 1682160024.0268493, endtime: 1682160324.0268466 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: 1682160024.2306042, endtime: 1682160324.2287636 DEBUG: Partial data from SSH call: 1488800000 DEBUG: Waiting for process output: time: 1682160024.554295, endtime: 1682160324.554292 DEBUG: Data from SSH call: 1488800000 DEBUG: [Command returned '0' after 0.53 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-04-22 10:40:22" DEBUG: Waiting for process output: time: 1682160024.7573166, endtime: 1682160324.75583 DEBUG: Partial data from SSH call: Sat Apr 22 10:40:22 UTC 2023 DEBUG: Waiting for process output: time: 1682160025.0920208, endtime: 1682160325.0919888 DEBUG: Data from SSH call: Sat Apr 22 10:40:22 UTC 2023 DEBUG: [Command returned '0' after 0.54 seconds] DEBUG: Command: date -s "2023-04-22 10:40:22" Status: 0 Output: Sat Apr 22 10:40:22 UTC 2023 NOTE: ... ok NOTE: test_df (df.DfTest) 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: 1682160025.2971184, endtime: 1682160325.295324 DEBUG: Partial data from SSH call: 67687 DEBUG: Waiting for process output: time: 1682160025.6168938, endtime: 1682160325.6168904 DEBUG: Data from SSH call: 67687 DEBUG: [Command returned '0' after 0.52 seconds] DEBUG: Command: df -P / | sed -n '2p' | awk '{print $4}' Status: 0 Output: 67687 NOTE: ... ok NOTE: test_dnf_help (dnf.DnfBasicTest) 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) 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) 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) 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) 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. NOTE: Not starting HTTPService for directory /home/pokybuild/yocto-worker/pkgman-non-rpm/build/build/tmp/work/qemux86-poky-linux/core-image-sato/1.0-r0/oe-testimage-repo which doesn't exist NOTE: test_dnf_makecache (dnf.DnfRepoTest) 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) 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) 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) 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) 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) 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) 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) 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) 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) 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. NOTE: Stopped HTTPService on 0.0.0.0:0 NOTE: test_gcc_compile (gcc.GccCompileTest) 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) 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) 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) DEBUG: Checking if at least one of make is installed NOTE: ... skipped 'Test requires make to be installed' Test requires make to be installed NOTE: test_python (gi.GObjectIntrospectionTest) 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) 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) 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) 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: 1682160025.8405354, endtime: 1682160325.8386393 DEBUG: Partial data from SSH call: cp: can't stat '/etc/logrotate.d/wtmp': No such file or directory DEBUG: Waiting for process output: time: 1682160026.1671975, endtime: 1682160326.1671946 DEBUG: Data from SSH call: cp: can't stat '/etc/logrotate.d/wtmp': No such file or directory DEBUG: [Command returned '1' after 0.53 seconds] DEBUG: Command: cp /etc/logrotate.d/wtmp $HOME/wtmp.oeqabak Status: 1 Output: cp: can't stat '/etc/logrotate.d/wtmp': No such file or directory NOTE: test_logrotate_wtmp (logrotate.LogrotateTest) DEBUG: Checking if at least one of logrotate is installed NOTE: ... skipped 'Test requires logrotate to be installed' Test requires logrotate to be installed NOTE: test_logrotate_newlog (logrotate.LogrotateTest) NOTE: ... skipped "Test case logrotate.LogrotateTest.test_logrotate_newlog depends on logrotate.LogrotateTest.test_logrotate_wtmp but it didn't pass/run." Test case logrotate.LogrotateTest.test_logrotate_newlog depends on logrotate.LogrotateTest.test_logrotate_wtmp but it didn't pass/run. 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: 1682160026.3732119, endtime: 1682160326.3711364 DEBUG: Partial data from SSH call: mv: can't rename '/home/root/wtmp.oeqabak': No such file or directory DEBUG: Waiting for process output: time: 1682160026.6960707, endtime: 1682160326.6960676 DEBUG: Data from SSH call: mv: can't rename '/home/root/wtmp.oeqabak': No such file or directory DEBUG: [Command returned '1' after 0.53 seconds] DEBUG: Command: mv -f $HOME/wtmp.oeqabak /etc/logrotate.d/wtmp && rm -rf /var/log//logrotate_dir Status: 1 Output: mv: can't rename '/home/root/wtmp.oeqabak': No such file or directory DEBUG: [Running]$ ssh -l root -o ServerAliveCountMax=2 -o ServerAliveInterval=30 -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR 192.168.7.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: 1682160026.900035, endtime: 1682160326.897963 DEBUG: Data from SSH call: DEBUG: [Command returned '0' after 0.33 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) DEBUG: Checking if at least one of busybox-syslog, rsyslog, sysklogd, syslog-ng 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 DEBUG: Waiting for process output: time: 1682160027.22817, endtime: 1682160327.2262955 DEBUG: Partial data from SSH call: PID USER VSZ STAT COMMAND 1 root 2764 S init [5] 2 root 0 SW [kthreadd] 3 root 0 IW< [rcu_gp] 4 root 0 IW< [rcu_par_gp] 5 root 0 IW< [slub_flushwq] 6 root 0 IW< [netns] 7 root 0 IW [kworker/0:0-rcu] 8 root 0 IW< [kworker/0:0H-ev] 9 root 0 IW [kworker/u8:0-ev] 10 root 0 IW< [mm_percpu_wq] 11 root 0 IW [kworker/u8:1-ev] 12 root 0 IW [rcu_tasks_kthre] 13 root 0 IW [rcu_tasks_rude_] 14 root 0 IW [rcu_tasks_trace] 15 root 0 SW [ksoftirqd/0] 16 root 0 IW [rcu_preempt] 17 root 0 SW [migration/0] 18 root 0 SW [cpuhp/0] 19 root 0 SW [cpuhp/1] 20 root 0 SW [migration/1] 21 root 0 SW [ksoftirqd/1] 22 root 0 IW [kworker/1:0-eve] 23 root 0 IW< [kworker/1:0H-ev] 24 root 0 SW [cpuhp/2] 25 root 0 SW [migration/2] 26 root 0 SW [ksoftirqd/2] 27 root 0 IW [kworker/2:0-pm] 28 root 0 IW< [kworker/2:0H-ev] 29 root 0 SW [cpuhp/3] 30 root 0 SW [migration/3] 31 root 0 SW [ksoftirqd/3] 32 root 0 IW [kworker/3:0-mm_] 33 root 0 IW< [kworker/3:0H-ev] 34 root 0 SW [kdevtmpfs] 35 root 0 IW< [inet_frag_wq] 36 root 0 IW [kworker/0:1-eve] 37 root 0 IW [kworker/1:1-mm_] 38 root 0 SW [oom_reaper] 39 root 0 IW< [writeback] 40 root 0 IW [kworker/u8:2-ev] 41 root 0 SW [kcompactd0] 42 root 0 IW< [kblockd] 43 root 0 IW< [blkcg_punt_bio] 44 root 0 IW< [ata_sff] 45 root 0 IW< [md] 46 root 0 IW< [kworker/0:1H-kb] 47 root 0 IW< [rpciod] 48 root 0 IW< [xprtiod] 49 root 0 SW [kswapd0] 50 root 0 IW< [nfsiod] 51 root 0 IW< [cifsiod] 52 root 0 IW< [smb3decryptd] 53 root 0 IW< [cifsfileinfoput] 54 root 0 IW< [cifsoplockd] 55 root 0 IW< [deferredclose] 57 root 0 IW< [acpi_thermal_pm] 58 root 0 SW [hwrng] 59 root 0 IW< [nvme-wq] 60 root 0 IW< [nvme-reset-wq] 61 root 0 IW< [nvme-delete-wq] 62 root 0 SW [scsi_eh_0] 63 root 0 IW< [scsi_tmf_0] 64 root 0 SW [scsi_eh_1] 65 root 0 IW< [scsi_tmf_1] 66 root 0 SW [scsi_eh_2] 67 root 0 IW< [scsi_tmf_2] 68 root 0 SW [scsi_eh_3] 69 root 0 IW< [scsi_tmf_3] 70 root 0 SW [scsi_eh_4] 71 root 0 IW< [scsi_tmf_4] 72 root 0 SW [scsi_eh_5] 73 root 0 IW< [scsi_tmf_5] 74 root 0 IW [kworker/u8:3-ev] 75 root 0 IW [kworker/u8:4-ev] 76 root 0 IW [kworker/u8:5-ev] 77 root 0 IW [kworker/u8:6-ev] 78 root 0 IW [kworker/u8:7-ev] 79 root 0 IW< [raid5wq] 80 root 0 IW< [dm_bufio_cache] 81 root 0 IW [kworker/2:1-mm_] 82 root 0 IW< [mld] 83 root 0 IW< [kworker/1:1H-kb] 84 root 0 IW< [ipv6_addrconf] 85 root 0 IW< [kworker/u9:0] 126 root 0 IW [kworker/1:2-eve] 127 root 0 IW [kworker/3:1] 128 root 0 SW [jbd2/vda-8] 129 root 0 IW< [ext4-rsv-conver] 172 root 8856 S /sbin/udevd -d 175 root 0 IW< [kworker/3:1H-kb] 177 root 0 IW< [kworker/2:1H-kb] 370 root 0 IW [kworker/2:2] 532 messageb 4108 S /usr/bin/dbus-daemon --system 536 root 12008 S /usr/sbin/connmand 541 root 4356 S xinit /etc/X11/Xsession -- /usr/bin/Xorg :0 -br -pn 546 root 282m S< /usr/bin/Xorg :0 -br -pn 548 root 12436 S /usr/sbin/wpa_supplicant -u 550 root 3468 S /usr/sbin/dropbear -r /etc/dropbear/dropbear_rsa_hos 555 rpc 2976 S /usr/sbin/rpcbind 563 root 6800 S /usr/libexec/bluetooth/bluetoothd 568 root 4152 S /sbin/syslogd -n -O /var/log/messages 571 root 4156 S /sbin/klogd -n 577 root 0 IW [kworker/0:2-eve] 580 avahi 4264 S avahi-daemon: running [qemux86.local] 581 avahi 4156 S avahi-daemon: chroot helper 588 root 8384 S /usr/sbin/ofonod 596 root 4156 S {start_getty} /bin/sh /bin/start_getty 115200 ttyS0 597 root 4156 S {start_getty} /bin/sh /bin/start_getty 115200 ttyS1 598 root 4156 S /sbin/getty 38400 tty1 600 root 4156 S /sbin/getty -L 115200 ttyS0 vt102 602 root 4200 S -sh 607 root 9076 S matchbox-window-manager -theme Sato -use_cursor no 611 root 5056 S dbus-launch --sh-syntax --exit-with-session 612 root 4116 S /usr/bin/dbus-daemon --syslog --fork --print-pid 5 - 631 root 37604 S /usr/libexec/at-spi-bus-launcher --launch-immediatel 635 root 63140 S connman-applet 646 root 4004 S /usr/bin/dbus-daemon --config-file=/usr/share/defaul 659 root 9400 S /usr/libexec/gconfd-2 669 root 56608 S matchbox-desktop 670 root 63964 S matchbox-panel --start-applets showdesktop,windowsel 671 root 22960 S /usr/bin/settings-daemon 674 root 85976 S< /usr/bin/pulseaudio --start --log-target=syslog 678 root 20668 S /usr/sbin/console-kit-daemon --no-daemon 752 root 29700 S /usr/libexec/at-spi2-registryd --use-gnome-session 803 root 3508 S /usr/sbin/dropbear -r /etc/dropbear/dropbear_rsa_hos 804 root 4200 R ps DEBUG: Waiting for process output: time: 1682160027.5528076, endtime: 1682160327.5528047 DEBUG: Data from SSH call: PID USER VSZ STAT COMMAND 1 root 2764 S init [5] 2 root 0 SW [kthreadd] 3 root 0 IW< [rcu_gp] 4 root 0 IW< [rcu_par_gp] 5 root 0 IW< [slub_flushwq] 6 root 0 IW< [netns] 7 root 0 IW [kworker/0:0-rcu] 8 root 0 IW< [kworker/0:0H-ev] 9 root 0 IW [kworker/u8:0-ev] 10 root 0 IW< [mm_percpu_wq] 11 root 0 IW [kworker/u8:1-ev] 12 root 0 IW [rcu_tasks_kthre] 13 root 0 IW [rcu_tasks_rude_] 14 root 0 IW [rcu_tasks_trace] 15 root 0 SW [ksoftirqd/0] 16 root 0 IW [rcu_preempt] 17 root 0 SW [migration/0] 18 root 0 SW [cpuhp/0] 19 root 0 SW [cpuhp/1] 20 root 0 SW [migration/1] 21 root 0 SW [ksoftirqd/1] 22 root 0 IW [kworker/1:0-eve] 23 root 0 IW< [kworker/1:0H-ev] 24 root 0 SW [cpuhp/2] 25 root 0 SW [migration/2] 26 root 0 SW [ksoftirqd/2] 27 root 0 IW [kworker/2:0-pm] 28 root 0 IW< [kworker/2:0H-ev] 29 root 0 SW [cpuhp/3] 30 root 0 SW [migration/3] 31 root 0 SW [ksoftirqd/3] 32 root 0 IW [kworker/3:0-mm_] 33 root 0 IW< [kworker/3:0H-ev] 34 root 0 SW [kdevtmpfs] 35 root 0 IW< [inet_frag_wq] 36 root 0 IW [kworker/0:1-eve] 37 root 0 IW [kworker/1:1-mm_] 38 root 0 SW [oom_reaper] 39 root 0 IW< [writeback] 40 root 0 IW [kworker/u8:2-ev] 41 root 0 SW [kcompactd0] 42 root 0 IW< [kblockd] 43 root 0 IW< [blkcg_punt_bio] 44 root 0 IW< [ata_sff] 45 root 0 IW< [md] 46 root 0 IW< [kworker/0:1H-kb] 47 root 0 IW< [rpciod] 48 root 0 IW< [xprtiod] 49 root 0 SW [kswapd0] 50 root 0 IW< [nfsiod] 51 root 0 IW< [cifsiod] 52 root 0 IW< [smb3decryptd] 53 root 0 IW< [cifsfileinfoput] 54 root 0 IW< [cifsoplockd] 55 root 0 IW< [deferredclose] 57 root 0 IW< [acpi_thermal_pm] 58 root 0 SW [hwrng] 59 root 0 IW< [nvme-wq] 60 root 0 IW< [nvme-reset-wq] 61 root 0 IW< [nvme-delete-wq] 62 root 0 SW [scsi_eh_0] 63 root 0 IW< [scsi_tmf_0] 64 root 0 SW [scsi_eh_1] 65 root 0 IW< [scsi_tmf_1] 66 root 0 SW [scsi_eh_2] 67 root 0 IW< [scsi_tmf_2] 68 root 0 SW [scsi_eh_3] 69 root 0 IW< [scsi_tmf_3] 70 root 0 SW [scsi_eh_4] 71 root 0 IW< [scsi_tmf_4] 72 root 0 SW [scsi_eh_5] 73 root 0 IW< [scsi_tmf_5] 74 root 0 IW [kworker/u8:3-ev] 75 root 0 IW [kworker/u8:4-ev] 76 root 0 IW [kworker/u8:5-ev] 77 root 0 IW [kworker/u8:6-ev] 78 root 0 IW [kworker/u8:7-ev] 79 root 0 IW< [raid5wq] 80 root 0 IW< [dm_bufio_cache] 81 root 0 IW [kworker/2:1-mm_] 82 root 0 IW< [mld] 83 root 0 IW< [kworker/1:1H-kb] 84 root 0 IW< [ipv6_addrconf] 85 root 0 IW< [kworker/u9:0] 126 root 0 IW [kworker/1:2-eve] 127 root 0 IW [kworker/3:1] 128 root 0 SW [jbd2/vda-8] 129 root 0 IW< [ext4-rsv-conver] 172 root 8856 S /sbin/udevd -d 175 root 0 IW< [kworker/3:1H-kb] 177 root 0 IW< [kworker/2:1H-kb] 370 root 0 IW [kworker/2:2] 532 messageb 4108 S /usr/bin/dbus-daemon --system 536 root 12008 S /usr/sbin/connmand 541 root 4356 S xinit /etc/X11/Xsession -- /usr/bin/Xorg :0 -br -pn 546 root 282m S< /usr/bin/Xorg :0 -br -pn 548 root 12436 S /usr/sbin/wpa_supplicant -u 550 root 3468 S /usr/sbin/dropbear -r /etc/dropbear/dropbear_rsa_hos 555 rpc 2976 S /usr/sbin/rpcbind 563 root 6800 S /usr/libexec/bluetooth/bluetoothd 568 root 4152 S /sbin/syslogd -n -O /var/log/messages 571 root 4156 S /sbin/klogd -n 577 root 0 IW [kworker/0:2-eve] 580 avahi 4264 S avahi-daemon: running [qemux86.local] 581 avahi 4156 S avahi-daemon: chroot helper 588 root 8384 S /usr/sbin/ofonod 596 root 4156 S {start_getty} /bin/sh /bin/start_getty 115200 ttyS0 597 root 4156 S {start_getty} /bin/sh /bin/start_getty 115200 ttyS1 598 root 4156 S /sbin/getty 38400 tty1 600 root 4156 S /sbin/getty -L 115200 ttyS0 vt102 602 root 4200 S -sh 607 root 9076 S matchbox-window-manager -theme Sato -use_cursor no 611 root 5056 S dbus-launch --sh-syntax --exit-with-session 612 root 4116 S /usr/bin/dbus-daemon --syslog --fork --print-pid 5 - 631 root 37604 S /usr/libexec/at-spi-bus-launcher --launch-immediatel 635 root 63140 S connman-applet 646 root 4004 S /usr/bin/dbus-daemon --config-file=/usr/share/defaul 659 root 9400 S /usr/libexec/gconfd-2 669 root 56608 S matchbox-desktop 670 root 63964 S matchbox-panel --start-applets showdesktop,windowsel 671 root 22960 S /usr/bin/settings-daemon 674 root 85976 S< /usr/bin/pulseaudio --start --log-target=syslog 678 root 20668 S /usr/sbin/console-kit-daemon --no-daemon 752 root 29700 S /usr/libexec/at-spi2-registryd --use-gnome-session 803 root 3508 S /usr/sbin/dropbear -r /etc/dropbear/dropbear_rsa_hos 804 root 4200 R ps DEBUG: [Command returned '0' after 0.53 seconds] DEBUG: Command: ps Status: 0 Output: PID USER VSZ STAT COMMAND 1 root 2764 S init [5] 2 root 0 SW [kthreadd] 3 root 0 IW< [rcu_gp] 4 root 0 IW< [rcu_par_gp] 5 root 0 IW< [slub_flushwq] 6 root 0 IW< [netns] 7 root 0 IW [kworker/0:0-rcu] 8 root 0 IW< [kworker/0:0H-ev] 9 root 0 IW [kworker/u8:0-ev] 10 root 0 IW< [mm_percpu_wq] 11 root 0 IW [kworker/u8:1-ev] 12 root 0 IW [rcu_tasks_kthre] 13 root 0 IW [rcu_tasks_rude_] 14 root 0 IW [rcu_tasks_trace] 15 root 0 SW [ksoftirqd/0] 16 root 0 IW [rcu_preempt] 17 root 0 SW [migration/0] 18 root 0 SW [cpuhp/0] 19 root 0 SW [cpuhp/1] 20 root 0 SW [migration/1] 21 root 0 SW [ksoftirqd/1] 22 root 0 IW [kworker/1:0-eve] 23 root 0 IW< [kworker/1:0H-ev] 24 root 0 SW [cpuhp/2] 25 root 0 SW [migration/2] 26 root 0 SW [ksoftirqd/2] 27 root 0 IW [kworker/2:0-pm] 28 root 0 IW< [kworker/2:0H-ev] 29 root 0 SW [cpuhp/3] 30 root 0 SW [migration/3] 31 root 0 SW [ksoftirqd/3] 32 root 0 IW [kworker/3:0-mm_] 33 root 0 IW< [kworker/3:0H-ev] 34 root 0 SW [kdevtmpfs] 35 root 0 IW< [inet_frag_wq] 36 root 0 IW [kworker/0:1-eve] 37 root 0 IW [kworker/1:1-mm_] 38 root 0 SW [oom_reaper] 39 root 0 IW< [writeback] 40 root 0 IW [kworker/u8:2-ev] 41 root 0 SW [kcompactd0] 42 root 0 IW< [kblockd] 43 root 0 IW< [blkcg_punt_bio] 44 root 0 IW< [ata_sff] 45 root 0 IW< [md] 46 root 0 IW< [kworker/0:1H-kb] 47 root 0 IW< [rpciod] 48 root 0 IW< [xprtiod] 49 root 0 SW [kswapd0] 50 root 0 IW< [nfsiod] 51 root 0 IW< [cifsiod] 52 root 0 IW< [smb3decryptd] 53 root 0 IW< [cifsfileinfoput] 54 root 0 IW< [cifsoplockd] 55 root 0 IW< [deferredclose] 57 root 0 IW< [acpi_thermal_pm] 58 root 0 SW [hwrng] 59 root 0 IW< [nvme-wq] 60 root 0 IW< [nvme-reset-wq] 61 root 0 IW< [nvme-delete-wq] 62 root 0 SW [scsi_eh_0] 63 root 0 IW< [scsi_tmf_0] 64 root 0 SW [scsi_eh_1] 65 root 0 IW< [scsi_tmf_1] 66 root 0 SW [scsi_eh_2] 67 root 0 IW< [scsi_tmf_2] 68 root 0 SW [scsi_eh_3] 69 root 0 IW< [scsi_tmf_3] 70 root 0 SW [scsi_eh_4] 71 root 0 IW< [scsi_tmf_4] 72 root 0 SW [scsi_eh_5] 73 root 0 IW< [scsi_tmf_5] 74 root 0 IW [kworker/u8:3-ev] 75 root 0 IW [kworker/u8:4-ev] 76 root 0 IW [kworker/u8:5-ev] 77 root 0 IW [kworker/u8:6-ev] 78 root 0 IW [kworker/u8:7-ev] 79 root 0 IW< [raid5wq] 80 root 0 IW< [dm_bufio_cache] 81 root 0 IW [kworker/2:1-mm_] 82 root 0 IW< [mld] 83 root 0 IW< [kworker/1:1H-kb] 84 root 0 IW< [ipv6_addrconf] 85 root 0 IW< [kworker/u9:0] 126 root 0 IW [kworker/1:2-eve] 127 root 0 IW [kworker/3:1] 128 root 0 SW [jbd2/vda-8] 129 root 0 IW< [ext4-rsv-conver] 172 root 8856 S /sbin/udevd -d 175 root 0 IW< [kworker/3:1H-kb] 177 root 0 IW< [kworker/2:1H-kb] 370 root 0 IW [kworker/2:2] 532 messageb 4108 S /usr/bin/dbus-daemon --system 536 root 12008 S /usr/sbin/connmand 541 root 4356 S xinit /etc/X11/Xsession -- /usr/bin/Xorg :0 -br -pn 546 root 282m S< /usr/bin/Xorg :0 -br -pn 548 root 12436 S /usr/sbin/wpa_supplicant -u 550 root 3468 S /usr/sbin/dropbear -r /etc/dropbear/dropbear_rsa_hos 555 rpc 2976 S /usr/sbin/rpcbind 563 root 6800 S /usr/libexec/bluetooth/bluetoothd 568 root 4152 S /sbin/syslogd -n -O /var/log/messages 571 root 4156 S /sbin/klogd -n 577 root 0 IW [kworker/0:2-eve] 580 avahi 4264 S avahi-daemon: running [qemux86.local] 581 avahi 4156 S avahi-daemon: chroot helper 588 root 8384 S /usr/sbin/ofonod 596 root 4156 S {start_getty} /bin/sh /bin/start_getty 115200 ttyS0 597 root 4156 S {start_getty} /bin/sh /bin/start_getty 115200 ttyS1 598 root 4156 S /sbin/getty 38400 tty1 600 root 4156 S /sbin/getty -L 115200 ttyS0 vt102 602 root 4200 S -sh 607 root 9076 S matchbox-window-manager -theme Sato -use_cursor no 611 root 5056 S dbus-launch --sh-syntax --exit-with-session 612 root 4116 S /usr/bin/dbus-daemon --syslog --fork --print-pid 5 - 631 root 37604 S /usr/libexec/at-spi-bus-launcher --launch-immediatel 635 root 63140 S connman-applet 646 root 4004 S /usr/bin/dbus-daemon --config-file=/usr/share/defaul 659 root 9400 S /usr/libexec/gconfd-2 669 root 56608 S matchbox-desktop 670 root 63964 S matchbox-panel --start-applets showdesktop,windowsel 671 root 22960 S /usr/bin/settings-daemon 674 root 85976 S< /usr/bin/pulseaudio --start --log-target=syslog 678 root 20668 S /usr/sbin/console-kit-daemon --no-daemon 752 root 29700 S /usr/libexec/at-spi2-registryd --use-gnome-session 803 root 3508 S /usr/sbin/dropbear -r /etc/dropbear/dropbear_rsa_hos 804 root 4200 R ps NOTE: ... ok NOTE: test_syslog_logger (oe_syslog.SyslogTestConfig) 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: 1682160027.7576137, endtime: 1682160327.755944 DEBUG: Data from SSH call: DEBUG: [Command returned '0' after 0.32 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: 1682160029.0840557, endtime: 1682160329.0818665 DEBUG: Partial data from SSH call: Apr 22 10:40:24 qemux86 user.notice root: foobar DEBUG: Waiting for process output: time: 1682160029.4079554, endtime: 1682160329.4079525 DEBUG: Data from SSH call: Apr 22 10:40:24 qemux86 user.notice root: foobar DEBUG: [Command returned '0' after 0.53 seconds] DEBUG: Command: grep foobar /var/log/messages Status: 0 Output: Apr 22 10:40:24 qemux86 user.notice root: foobar NOTE: ... ok NOTE: test_syslog_restart (oe_syslog.SyslogTestConfig) 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: 1682160029.613162, endtime: 1682160329.6109757 DEBUG: Data from SSH call: DEBUG: [Command returned '1' after 0.33 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: 1682160029.944843, endtime: 1682160329.9432113 DEBUG: Data from SSH call: DEBUG: [Command returned '1' after 0.32 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: 1682160030.2707353, endtime: 1682160330.2688763 DEBUG: Partial data from SSH call: 568 DEBUG: Waiting for process output: time: 1682160030.5914593, endtime: 1682160330.5914557 DEBUG: Data from SSH call: 568 DEBUG: [Command returned '0' after 0.52 seconds] DEBUG: Command: pidof syslogd Status: 0 Output: 568 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: 1682160030.793947, endtime: 1682160330.7926643 DEBUG: Partial data from SSH call: 571 DEBUG: Waiting for process output: time: 1682160031.1387765, endtime: 1682160331.1387734 DEBUG: Data from SSH call: 571 DEBUG: [Command returned '0' after 0.55 seconds] DEBUG: Command: pidof klogd Status: 0 Output: 571 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; /etc/init.d/syslog restart DEBUG: Waiting for process output: time: 1682160031.3424838, endtime: 1682160331.3406255 DEBUG: Partial data from SSH call: Stopping syslogd/klogd: stopped syslogd (pid 568) stopped klogd (pid 571) done Starting syslogd/klogd: done DEBUG: Waiting for process output: time: 1682160031.6833448, endtime: 1682160331.6833417 DEBUG: Data from SSH call: Stopping syslogd/klogd: stopped syslogd (pid 568) stopped klogd (pid 571) done Starting syslogd/klogd: done DEBUG: [Command returned '0' after 0.54 seconds] DEBUG: Command: /etc/init.d/syslog restart Status: 0 Output: Stopping syslogd/klogd: stopped syslogd (pid 568) stopped klogd (pid 571) done Starting syslogd/klogd: done 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; kill -0 568 DEBUG: Waiting for process output: time: 1682160031.8869066, endtime: 1682160331.8853364 DEBUG: Partial data from SSH call: sh: can't kill pid 568: No such process DEBUG: Waiting for process output: time: 1682160032.2076735, endtime: 1682160332.2076707 DEBUG: Data from SSH call: sh: can't kill pid 568: No such process DEBUG: [Command returned '1' after 0.52 seconds] DEBUG: Command: kill -0 568 Status: 1 Output: sh: can't kill pid 568: No such process 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; kill -0 571 DEBUG: Waiting for process output: time: 1682160032.4115405, endtime: 1682160332.4094641 DEBUG: Partial data from SSH call: sh: can't kill pid 571: No such process DEBUG: Waiting for process output: time: 1682160032.7316132, endtime: 1682160332.7316103 DEBUG: Data from SSH call: sh: can't kill pid 571: No such process DEBUG: [Command returned '1' after 0.52 seconds] DEBUG: Command: kill -0 571 Status: 1 Output: sh: can't kill pid 571: No such process 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: 1682160032.9365118, endtime: 1682160332.934241 DEBUG: Partial data from SSH call: 827 DEBUG: Waiting for process output: time: 1682160033.2639594, endtime: 1682160333.263957 DEBUG: Data from SSH call: 827 DEBUG: [Command returned '0' after 0.53 seconds] DEBUG: Command: pidof syslogd Status: 0 Output: 827 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: 1682160033.4679506, endtime: 1682160333.4657278 DEBUG: Partial data from SSH call: 830 DEBUG: Waiting for process output: time: 1682160033.7936385, endtime: 1682160333.7936366 DEBUG: Data from SSH call: 830 DEBUG: [Command returned '0' after 0.53 seconds] DEBUG: Command: pidof klogd Status: 0 Output: 830 NOTE: ... ok NOTE: test_syslog_startup_config (oe_syslog.SyslogTestConfig) DEBUG: Checking if 'VIRTUAL-RUNTIME_init_manager' value is 'systemd' to skip test DEBUG: Checking if at least one of busybox-syslog 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; echo "LOGFILE=/var/log/test" >> /etc/syslog-startup.conf DEBUG: Waiting for process output: time: 1682160033.998705, endtime: 1682160333.9968305 DEBUG: Data from SSH call: DEBUG: [Command returned '0' after 0.33 seconds] DEBUG: Command: echo "LOGFILE=/var/log/test" >> /etc/syslog-startup.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; pidof systemd-journald DEBUG: Waiting for process output: time: 1682160034.3275332, endtime: 1682160334.3254778 DEBUG: Data from SSH call: DEBUG: [Command returned '1' after 0.33 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: 1682160034.654609, endtime: 1682160334.6525142 DEBUG: Data from SSH call: DEBUG: [Command returned '1' after 0.33 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: 1682160034.9894001, endtime: 1682160334.987634 DEBUG: Partial data from SSH call: 827 DEBUG: Waiting for process output: time: 1682160035.3315053, endtime: 1682160335.3315024 DEBUG: Data from SSH call: 827 DEBUG: [Command returned '0' after 0.55 seconds] DEBUG: Command: pidof syslogd Status: 0 Output: 827 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: 1682160035.5353951, endtime: 1682160335.533445 DEBUG: Partial data from SSH call: 830 DEBUG: Waiting for process output: time: 1682160035.8681197, endtime: 1682160335.868117 DEBUG: Data from SSH call: 830 DEBUG: [Command returned '0' after 0.54 seconds] DEBUG: Command: pidof klogd Status: 0 Output: 830 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; /etc/init.d/syslog restart DEBUG: Waiting for process output: time: 1682160036.0721195, endtime: 1682160336.0700622 DEBUG: Partial data from SSH call: Stopping syslogd/klogd: stopped syslogd (pid 827) stopped klogd (pid 830) done DEBUG: Waiting for process output: time: 1682160036.4164627, endtime: 1682160336.4164596 DEBUG: Partial data from SSH call: Starting syslogd/klogd: done DEBUG: Waiting for process output: time: 1682160037.4267836, endtime: 1682160337.4267805 DEBUG: Data from SSH call: Stopping syslogd/klogd: stopped syslogd (pid 827) stopped klogd (pid 830) done Starting syslogd/klogd: done DEBUG: [Command returned '0' after 1.56 seconds] DEBUG: Command: /etc/init.d/syslog restart Status: 0 Output: Stopping syslogd/klogd: stopped syslogd (pid 827) stopped klogd (pid 830) done Starting syslogd/klogd: done 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; kill -0 827 DEBUG: Waiting for process output: time: 1682160037.630917, endtime: 1682160337.6287348 DEBUG: Partial data from SSH call: sh: can't kill pid 827: No such process DEBUG: Waiting for process output: time: 1682160037.9531054, endtime: 1682160337.9531024 DEBUG: Data from SSH call: sh: can't kill pid 827: No such process DEBUG: [Command returned '1' after 0.53 seconds] DEBUG: Command: kill -0 827 Status: 1 Output: sh: can't kill pid 827: No such process 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; kill -0 830 DEBUG: Waiting for process output: time: 1682160038.1568067, endtime: 1682160338.1549618 DEBUG: Partial data from SSH call: sh: can't kill pid 830: No such process DEBUG: Waiting for process output: time: 1682160038.4819424, endtime: 1682160338.4819393 DEBUG: Data from SSH call: sh: can't kill pid 830: No such process DEBUG: [Command returned '1' after 0.53 seconds] DEBUG: Command: kill -0 830 Status: 1 Output: sh: can't kill pid 830: No such process 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: 1682160038.6857708, endtime: 1682160338.6839578 DEBUG: Partial data from SSH call: 859 DEBUG: Waiting for process output: time: 1682160039.0151806, endtime: 1682160339.0151775 DEBUG: Data from SSH call: 859 DEBUG: [Command returned '0' after 0.53 seconds] DEBUG: Command: pidof syslogd Status: 0 Output: 859 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: 1682160039.219081, endtime: 1682160339.2171433 DEBUG: Partial data from SSH call: 862 DEBUG: Waiting for process output: time: 1682160039.5453546, endtime: 1682160339.5453517 DEBUG: Data from SSH call: 862 DEBUG: [Command returned '0' after 0.53 seconds] DEBUG: Command: pidof klogd Status: 0 Output: 862 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: 1682160039.748333, endtime: 1682160339.7468715 DEBUG: Data from SSH call: DEBUG: [Command returned '0' after 0.32 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; cat /var/log/test DEBUG: Waiting for process output: time: 1682160040.0735946, endtime: 1682160340.0714169 DEBUG: Partial data from SSH call: Apr 22 10:40:34 qemux86 syslog.info syslogd started: BusyBox v1.36.0 Apr 22 10:40:34 qemux86 kern.notice kernel: klogd started: BusyBox v1.36.0 () Apr 22 10:40:34 qemux86 authpriv.info dropbear[849]: Exit (root) from <192.168.7.1:58904>: Disconnect received Apr 22 10:40:34 qemux86 authpriv.info dropbear[863]: Child connection from 192.168.7.1:58910 Apr 22 10:40:34 qemux86 authpriv.notice dropbear[863]: Auth succeeded with blank password for 'root' from 192.168.7.1:58910 Apr 22 10:40:34 qemux86 authpriv.info dropbear[863]: Exit (root) from <192.168.7.1:58910>: Disconnect received Apr 22 10:40:35 qemux86 authpriv.info dropbear[865]: Child connection from 192.168.7.1:58912 Apr 22 10:40:35 qemux86 authpriv.notice dropbear[865]: Auth succeeded with blank password for 'root' from 192.168.7.1:58912 Apr 22 10:40:35 qemux86 authpriv.info dropbear[865]: Exit (root) from <192.168.7.1:58912>: Disconnect received Apr 22 10:40:35 qemux86 authpriv.info dropbear[867]: Child connection from 192.168.7.1:47500 Apr 22 10:40:35 qemux86 authpriv.notice dropbear[867]: Auth succeeded with blank password for 'root' from 192.168.7.1:47500 Apr 22 10:40:35 qemux86 authpriv.info dropbear[867]: Exit (root) from <192.168.7.1:47500>: Disconnect received Apr 22 10:40:36 qemux86 authpriv.info dropbear[869]: Child connection from 192.168.7.1:47510 Apr 22 10:40:36 qemux86 authpriv.notice dropbear[869]: Auth succeeded with blank password for 'root' from 192.168.7.1:47510 Apr 22 10:40:36 qemux86 authpriv.info dropbear[869]: Exit (root) from <192.168.7.1:47510>: Disconnect received Apr 22 10:40:36 qemux86 authpriv.info dropbear[871]: Child connection from 192.168.7.1:47526 Apr 22 10:40:36 qemux86 authpriv.notice dropbear[871]: Auth succeeded with blank password for 'root' from 192.168.7.1:47526 Apr 22 10:40:36 qemux86 user.notice root: foobar Apr 22 10:40:36 qemux86 authpriv.info dropbear[871]: Exit (root) from <192.168.7.1:47526>: Disconnect received Apr 22 10:40:37 qemux86 authpriv.info dropbear[873]: Child connection from 192.168.7.1:47540 Apr 22 10:40:37 qemux86 authpriv.notice dropbear[873]: Auth succeeded with blank password for 'root' from 192.168.7.1:47540 DEBUG: Waiting for process output: time: 1682160040.394623, endtime: 1682160340.3946197 DEBUG: Data from SSH call: Apr 22 10:40:34 qemux86 syslog.info syslogd started: BusyBox v1.36.0 Apr 22 10:40:34 qemux86 kern.notice kernel: klogd started: BusyBox v1.36.0 () Apr 22 10:40:34 qemux86 authpriv.info dropbear[849]: Exit (root) from <192.168.7.1:58904>: Disconnect received Apr 22 10:40:34 qemux86 authpriv.info dropbear[863]: Child connection from 192.168.7.1:58910 Apr 22 10:40:34 qemux86 authpriv.notice dropbear[863]: Auth succeeded with blank password for 'root' from 192.168.7.1:58910 Apr 22 10:40:34 qemux86 authpriv.info dropbear[863]: Exit (root) from <192.168.7.1:58910>: Disconnect received Apr 22 10:40:35 qemux86 authpriv.info dropbear[865]: Child connection from 192.168.7.1:58912 Apr 22 10:40:35 qemux86 authpriv.notice dropbear[865]: Auth succeeded with blank password for 'root' from 192.168.7.1:58912 Apr 22 10:40:35 qemux86 authpriv.info dropbear[865]: Exit (root) from <192.168.7.1:58912>: Disconnect received Apr 22 10:40:35 qemux86 authpriv.info dropbear[867]: Child connection from 192.168.7.1:47500 Apr 22 10:40:35 qemux86 authpriv.notice dropbear[867]: Auth succeeded with blank password for 'root' from 192.168.7.1:47500 Apr 22 10:40:35 qemux86 authpriv.info dropbear[867]: Exit (root) from <192.168.7.1:47500>: Disconnect received Apr 22 10:40:36 qemux86 authpriv.info dropbear[869]: Child connection from 192.168.7.1:47510 Apr 22 10:40:36 qemux86 authpriv.notice dropbear[869]: Auth succeeded with blank password for 'root' from 192.168.7.1:47510 Apr 22 10:40:36 qemux86 authpriv.info dropbear[869]: Exit (root) from <192.168.7.1:47510>: Disconnect received Apr 22 10:40:36 qemux86 authpriv.info dropbear[871]: Child connection from 192.168.7.1:47526 Apr 22 10:40:36 qemux86 authpriv.notice dropbear[871]: Auth succeeded with blank password for 'root' from 192.168.7.1:47526 Apr 22 10:40:36 qemux86 user.notice root: foobar Apr 22 10:40:36 qemux86 authpriv.info dropbear[871]: Exit (root) from <192.168.7.1:47526>: Disconnect received Apr 22 10:40:37 qemux86 authpriv.info dropbear[873]: Child connection from 192.168.7.1:47540 Apr 22 10:40:37 qemux86 authpriv.notice dropbear[873]: Auth succeeded with blank password for 'root' from 192.168.7.1:47540 DEBUG: [Command returned '0' after 0.52 seconds] DEBUG: Command: cat /var/log/test Status: 0 Output: Apr 22 10:40:34 qemux86 syslog.info syslogd started: BusyBox v1.36.0 Apr 22 10:40:34 qemux86 kern.notice kernel: klogd started: BusyBox v1.36.0 () Apr 22 10:40:34 qemux86 authpriv.info dropbear[849]: Exit (root) from <192.168.7.1:58904>: Disconnect received Apr 22 10:40:34 qemux86 authpriv.info dropbear[863]: Child connection from 192.168.7.1:58910 Apr 22 10:40:34 qemux86 authpriv.notice dropbear[863]: Auth succeeded with blank password for 'root' from 192.168.7.1:58910 Apr 22 10:40:34 qemux86 authpriv.info dropbear[863]: Exit (root) from <192.168.7.1:58910>: Disconnect received Apr 22 10:40:35 qemux86 authpriv.info dropbear[865]: Child connection from 192.168.7.1:58912 Apr 22 10:40:35 qemux86 authpriv.notice dropbear[865]: Auth succeeded with blank password for 'root' from 192.168.7.1:58912 Apr 22 10:40:35 qemux86 authpriv.info dropbear[865]: Exit (root) from <192.168.7.1:58912>: Disconnect received Apr 22 10:40:35 qemux86 authpriv.info dropbear[867]: Child connection from 192.168.7.1:47500 Apr 22 10:40:35 qemux86 authpriv.notice dropbear[867]: Auth succeeded with blank password for 'root' from 192.168.7.1:47500 Apr 22 10:40:35 qemux86 authpriv.info dropbear[867]: Exit (root) from <192.168.7.1:47500>: Disconnect received Apr 22 10:40:36 qemux86 authpriv.info dropbear[869]: Child connection from 192.168.7.1:47510 Apr 22 10:40:36 qemux86 authpriv.notice dropbear[869]: Auth succeeded with blank password for 'root' from 192.168.7.1:47510 Apr 22 10:40:36 qemux86 authpriv.info dropbear[869]: Exit (root) from <192.168.7.1:47510>: Disconnect received Apr 22 10:40:36 qemux86 authpriv.info dropbear[871]: Child connection from 192.168.7.1:47526 Apr 22 10:40:36 qemux86 authpriv.notice dropbear[871]: Auth succeeded with blank password for 'root' from 192.168.7.1:47526 Apr 22 10:40:36 qemux86 user.notice root: foobar Apr 22 10:40:36 qemux86 authpriv.info dropbear[871]: Exit (root) from <192.168.7.1:47526>: Disconnect received Apr 22 10:40:37 qemux86 authpriv.info dropbear[873]: Child connection from 192.168.7.1:47540 Apr 22 10:40:37 qemux86 authpriv.notice dropbear[873]: Auth succeeded with blank password for 'root' from 192.168.7.1:47540 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; sed -i 's#LOGFILE=/var/log/test##' /etc/syslog-startup.conf DEBUG: Waiting for process output: time: 1682160040.5986445, endtime: 1682160340.5965996 DEBUG: Data from SSH call: DEBUG: [Command returned '0' after 0.33 seconds] DEBUG: Command: sed -i 's#LOGFILE=/var/log/test##' /etc/syslog-startup.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; pidof systemd-journald DEBUG: Waiting for process output: time: 1682160040.925318, endtime: 1682160340.9232392 DEBUG: Data from SSH call: DEBUG: [Command returned '1' after 0.33 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: 1682160041.257446, endtime: 1682160341.2557616 DEBUG: Data from SSH call: DEBUG: [Command returned '1' after 0.33 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: 1682160041.5892074, endtime: 1682160341.5874949 DEBUG: Partial data from SSH call: 859 DEBUG: Waiting for process output: time: 1682160041.9163275, endtime: 1682160341.9163246 DEBUG: Data from SSH call: 859 DEBUG: [Command returned '0' after 0.53 seconds] DEBUG: Command: pidof syslogd Status: 0 Output: 859 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: 1682160042.120289, endtime: 1682160342.1182861 DEBUG: Partial data from SSH call: 862 DEBUG: Waiting for process output: time: 1682160042.4476125, endtime: 1682160342.4476097 DEBUG: Data from SSH call: 862 DEBUG: [Command returned '0' after 0.53 seconds] DEBUG: Command: pidof klogd Status: 0 Output: 862 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; /etc/init.d/syslog restart DEBUG: Waiting for process output: time: 1682160042.6511936, endtime: 1682160342.6495686 DEBUG: Partial data from SSH call: Stopping syslogd/klogd: stopped syslogd (pid 859) stopped klogd (pid 862) done Starting syslogd/klogd: done DEBUG: Waiting for process output: time: 1682160042.9933326, endtime: 1682160342.99333 DEBUG: Data from SSH call: Stopping syslogd/klogd: stopped syslogd (pid 859) stopped klogd (pid 862) done Starting syslogd/klogd: done DEBUG: [Command returned '0' after 0.55 seconds] DEBUG: Command: /etc/init.d/syslog restart Status: 0 Output: Stopping syslogd/klogd: stopped syslogd (pid 859) stopped klogd (pid 862) done Starting syslogd/klogd: done 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; kill -0 859 DEBUG: Waiting for process output: time: 1682160043.1969519, endtime: 1682160343.1951506 DEBUG: Partial data from SSH call: sh: can't kill pid 859: No such process DEBUG: Waiting for process output: time: 1682160043.5136979, endtime: 1682160343.5136943 DEBUG: Data from SSH call: sh: can't kill pid 859: No such process DEBUG: [Command returned '1' after 0.52 seconds] DEBUG: Command: kill -0 859 Status: 1 Output: sh: can't kill pid 859: No such process 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; kill -0 862 DEBUG: Waiting for process output: time: 1682160043.7169127, endtime: 1682160343.7153654 DEBUG: Partial data from SSH call: sh: can't kill pid 862: No such process DEBUG: Waiting for process output: time: 1682160044.0523124, endtime: 1682160344.052309 DEBUG: Data from SSH call: sh: can't kill pid 862: No such process DEBUG: [Command returned '1' after 0.54 seconds] DEBUG: Command: kill -0 862 Status: 1 Output: sh: can't kill pid 862: No such process 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: 1682160044.2558613, endtime: 1682160344.2542915 DEBUG: Partial data from SSH call: 895 DEBUG: Waiting for process output: time: 1682160044.5578198, endtime: 1682160344.5578177 DEBUG: Data from SSH call: 895 DEBUG: [Command returned '0' after 0.50 seconds] DEBUG: Command: pidof syslogd Status: 0 Output: 895 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: 1682160044.7613711, endtime: 1682160344.7596605 DEBUG: Partial data from SSH call: 898 DEBUG: Waiting for process output: time: 1682160045.0888872, endtime: 1682160345.0888846 DEBUG: Data from SSH call: 898 DEBUG: [Command returned '0' after 0.53 seconds] DEBUG: Command: pidof klogd Status: 0 Output: 898 NOTE: ... ok NOTE: test_opkg_install_from_repo (opkg.OpkgRepoTest) DEBUG: Checking if at least one of opkg is installed DEBUG: Checking if read-only-rootfs is not in DISTRO_FEATURES or IMAGE_FEATURES DEBUG: Checking if 'IMAGE_PKGTYPE' value is not 'ipk' to skip test DEBUG: Checking if package-management is in DISTRO_FEATURES or IMAGE_FEATURES NOTE: Started HTTPService for tmp/deploy/ipk/all on 0.0.0.0:42431 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; cd /etc/opkg/; echo src/gz all http://192.168.7.1:42431/ >> opkg.conf DEBUG: Waiting for process output: time: 1682160045.3229678, endtime: 1682160345.3203971 DEBUG: Data from SSH call: DEBUG: [Command returned '0' after 0.33 seconds] DEBUG: Command: cd /etc/opkg/; echo src/gz all http://192.168.7.1:42431/ >> opkg.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; opkg update DEBUG: Waiting for process output: time: 1682160045.6482038, endtime: 1682161545.646073 NOTE: "GET //Packages.gz HTTP/1.1" 200 - DEBUG: Partial data from SSH call: Downloading http://192.168.7.1:42431//Packages.gz. Updated source 'all'. DEBUG: Waiting for process output: time: 1682160046.0630867, endtime: 1682161546.0630841 DEBUG: Data from SSH call: Downloading http://192.168.7.1:42431//Packages.gz. Updated source 'all'. DEBUG: [Command returned '0' after 0.62 seconds] DEBUG: Command: opkg update Status: 0 Output: Downloading http://192.168.7.1:42431//Packages.gz. Updated source 'all'. 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; opkg remove run-postinsts-dev DEBUG: Waiting for process output: time: 1682160046.267215, endtime: 1682161546.2651272 DEBUG: Partial data from SSH call: No packages installed or removed. DEBUG: Waiting for process output: time: 1682160046.988151, endtime: 1682161546.9881482 DEBUG: Data from SSH call: No packages installed or removed. DEBUG: [Command returned '0' after 0.92 seconds] DEBUG: Command: opkg remove run-postinsts-dev Status: 0 Output: No packages installed or removed. 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; opkg install run-postinsts-dev DEBUG: Waiting for process output: time: 1682160047.1917858, endtime: 1682161547.1899426 NOTE: "GET //update-rc.d-dev_0.8-r0_all.ipk HTTP/1.1" 200 - NOTE: "GET //run-postinsts_1.0-r10_all.ipk HTTP/1.1" 200 - NOTE: "GET //run-postinsts-dev_1.0-r10_all.ipk HTTP/1.1" 200 - DEBUG: Partial data from SSH call: Adding system startup for /etc/init.d/run-postinsts. Installing update-rc.d-dev (0.8) on root Downloading http://192.168.7.1:42431//update-rc.d-dev_0.8-r0_all.ipk. Installing run-postinsts (1.0) on root Downloading http://192.168.7.1:42431//run-postinsts_1.0-r10_all.ipk. Installing run-postinsts-dev (1.0) on root Downloading http://192.168.7.1:42431//run-postinsts-dev_1.0-r10_all.ipk. Configuring update-rc.d-dev. Configuring run-postinsts. Configuring run-postinsts-dev. DEBUG: Waiting for process output: time: 1682160047.7121744, endtime: 1682161547.7121716 DEBUG: Data from SSH call: Adding system startup for /etc/init.d/run-postinsts. Installing update-rc.d-dev (0.8) on root Downloading http://192.168.7.1:42431//update-rc.d-dev_0.8-r0_all.ipk. Installing run-postinsts (1.0) on root Downloading http://192.168.7.1:42431//run-postinsts_1.0-r10_all.ipk. Installing run-postinsts-dev (1.0) on root Downloading http://192.168.7.1:42431//run-postinsts-dev_1.0-r10_all.ipk. Configuring update-rc.d-dev. Configuring run-postinsts. Configuring run-postinsts-dev. DEBUG: [Command returned '0' after 0.72 seconds] DEBUG: Command: opkg install run-postinsts-dev Status: 0 Output: Adding system startup for /etc/init.d/run-postinsts. Installing update-rc.d-dev (0.8) on root Downloading http://192.168.7.1:42431//update-rc.d-dev_0.8-r0_all.ipk. Installing run-postinsts (1.0) on root Downloading http://192.168.7.1:42431//run-postinsts_1.0-r10_all.ipk. Installing run-postinsts-dev (1.0) on root Downloading http://192.168.7.1:42431//run-postinsts-dev_1.0-r10_all.ipk. Configuring update-rc.d-dev. Configuring run-postinsts. Configuring run-postinsts-dev. 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; cd /etc/opkg/; sed -i "/^src/d" opkg.conf DEBUG: Waiting for process output: time: 1682160047.9158523, endtime: 1682160347.9140966 DEBUG: Data from SSH call: DEBUG: [Command returned '0' after 0.33 seconds] DEBUG: Command: cd /etc/opkg/; sed -i "/^src/d" opkg.conf Status: 0 Output: NOTE: Stopped HTTPService on 0.0.0.0:42431 NOTE: ... ok NOTE: test_pam (pam.PamBasicTest) 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: 1682160048.2496083, endtime: 1682160348.24783 DEBUG: Data from SSH call: DEBUG: [Command returned '1' after 0.32 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.2 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; dmesg > /tmp/dmesg_output.log DEBUG: Waiting for process output: time: 1682160048.5746574, endtime: 1682160348.5728464 DEBUG: Data from SSH call: DEBUG: [Command returned '0' after 0.32 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: 1682160048.8985505, endtime: 1682160348.8965383 DEBUG: Data from SSH call: DEBUG: [Command returned '1' after 0.32 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: 1682160049.2206173, endtime: 1682160349.218673 DEBUG: Data from SSH call: DEBUG: [Command returned '0' after 0.32 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: 1682160049.544515, endtime: 1682160349.5424645 DEBUG: Partial data from SSH call: /var/log//Xorg.0.log DEBUG: Waiting for process output: time: 1682160049.8658094, endtime: 1682160349.8658063 DEBUG: Data from SSH call: /var/log//Xorg.0.log DEBUG: [Command returned '0' after 0.52 seconds] DEBUG: Command: find /var/log//*.log -maxdepth 1 -type f Status: 0 Output: /var/log//Xorg.0.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: 1682160050.0697048, endtime: 1682160350.0676599 DEBUG: Data from SSH call: DEBUG: [Command returned '0' after 0.32 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: 1682160050.392189, endtime: 1682160350.390097 DEBUG: Data from SSH call: DEBUG: [Command returned '0' after 0.33 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//Xorg.0.log /home/pokybuild/yocto-worker/pkgman-non-rpm/build/build/tmp/work/qemux86-poky-linux/core-image-sato/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:/var/log/dmesg /home/pokybuild/yocto-worker/pkgman-non-rpm/build/build/tmp/work/qemux86-poky-linux/core-image-sato/1.0-r0/target_logs DEBUG: Data from SSH call: DEBUG: [Command returned '0' after 0.13 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/pkgman-non-rpm/build/build/tmp/work/qemux86-poky-linux/core-image-sato/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: 1682160051.1779008, endtime: 1682160351.1756253 DEBUG: Partial data from SSH call: Intel Xeon E3-12xx v2 (Ivy Bridge) DEBUG: Waiting for process output: time: 1682160051.4988272, endtime: 1682160351.4988241 DEBUG: Data from SSH call: Intel Xeon E3-12xx v2 (Ivy Bridge) DEBUG: [Command returned '0' after 0.52 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: 1682160051.702644, endtime: 1682160351.7007213 DEBUG: Partial data from SSH call: 4 DEBUG: Waiting for process output: time: 1682160052.025294, endtime: 1682160352.0252912 DEBUG: Data from SSH call: 4 DEBUG: [Command returned '0' after 0.53 seconds] DEBUG: Command: cat /proc/cpuinfo | grep "cpu cores" | head -n1 | awk {'print $4'} Status: 0 Output: 4 DEBUG: [Running]$ ssh -l root -o ServerAliveCountMax=2 -o ServerAliveInterval=30 -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR 192.168.7.2 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; cat /proc/cpuinfo | grep "processor" | wc -l DEBUG: Waiting for process output: time: 1682160052.2289038, endtime: 1682160352.2272654 DEBUG: Partial data from SSH call: 4 DEBUG: Waiting for process output: time: 1682160052.550482, endtime: 1682160352.5504792 DEBUG: Data from SSH call: 4 DEBUG: [Command returned '0' after 0.52 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: 1682160052.7542205, endtime: 1682160352.7524326 DEBUG: Partial data from SSH call: i686 DEBUG: Waiting for process output: time: 1682160053.0755956, endtime: 1682160353.0755925 DEBUG: Data from SSH call: i686 DEBUG: [Command returned '0' after 0.52 seconds] DEBUG: Command: uname -m Status: 0 Output: i686 NOTE: ... ok NOTE: test_perl_works (perl.PerlTest) DEBUG: Checking if at least one of perl is installed NOTE: ... skipped 'Test requires perl to be installed' Test requires perl to be installed NOTE: test_ptestrunner_expectfail (ptest.PtestRunnerTest) 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) 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) DEBUG: Checking if at least one of python3-core 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; python3 -c "import codecs; print(codecs.encode('Uryyb, jbeyq', 'rot13'))" DEBUG: Waiting for process output: time: 1682160053.2834072, endtime: 1682160353.2813416 DEBUG: Partial data from SSH call: Hello, world DEBUG: Waiting for process output: time: 1682160053.6778414, endtime: 1682160353.6778386 DEBUG: Data from SSH call: Hello, world DEBUG: [Command returned '0' after 0.60 seconds] DEBUG: Command: python3 -c "import codecs; print(codecs.encode('Uryyb, jbeyq', 'rot13'))" Status: 0 Output: Hello, world NOTE: ... ok NOTE: test_rpm_help (rpm.RpmBasicTest) 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) 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) 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) 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) 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) 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_cargo_compile (rust.RustCompileTest) DEBUG: Checking if at least one of cargo is installed NOTE: ... skipped 'Test requires cargo to be installed' Test requires cargo to be installed NOTE: test_rust_compile (rust.RustCompileTest) DEBUG: Checking if at least one of rust is installed NOTE: ... skipped 'Test requires rust to be installed' Test requires rust to be installed NOTE: test_rusthelloworld (rust.RustHelloworldTest) 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) DEBUG: Checking if at least one of openssh-scp is installed NOTE: ... skipped 'Test requires openssh-scp to be installed' Test requires openssh-scp to be installed NOTE: test_stap (stap.StapTest) 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) 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) 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) 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) 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) 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) 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) 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) 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) 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) 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) 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) 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_supports_xwayland (weston.WestonTest) 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: 1682160053.9094415, endtime: 1682160353.9076405 DEBUG: Partial data from SSH call: rm: can't remove '/tmp/weston-2.log': No such file or directory DEBUG: Waiting for process output: time: 1682160054.2371676, endtime: 1682160354.2371647 DEBUG: Data from SSH call: rm: can't remove '/tmp/weston-2.log': No such file or directory DEBUG: [Command returned '1' after 0.53 seconds] DEBUG: Command: rm /tmp/weston-2.log Status: 1 Output: rm: can't remove '/tmp/weston-2.log': No such file or directory NOTE: test_xorg_running (xorg.XorgTest) DEBUG: Checking if at least one of xserver-nodm-init is installed DEBUG: Checking if x11-base 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.2 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; ps | grep -v xinit | grep [X]org DEBUG: Waiting for process output: time: 1682160054.442215, endtime: 1682160354.4402199 DEBUG: Partial data from SSH call: 546 root 282m S< /usr/bin/Xorg :0 -br -pn DEBUG: Waiting for process output: time: 1682160054.7744067, endtime: 1682160354.7744038 DEBUG: Data from SSH call: 546 root 282m S< /usr/bin/Xorg :0 -br -pn DEBUG: [Command returned '0' after 0.54 seconds] DEBUG: Command: ps | grep -v xinit | grep [X]org Status: 0 Output: 546 root 282m S< /usr/bin/Xorg :0 -br -pn 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 DEBUG: Waiting for process output: time: 1682160054.9781291, endtime: 1682160354.9762926 DEBUG: Partial data from SSH call: PID USER VSZ STAT COMMAND 1 root 2764 S init [5] 2 root 0 SW [kthreadd] 3 root 0 IW< [rcu_gp] 4 root 0 IW< [rcu_par_gp] 5 root 0 IW< [slub_flushwq] 6 root 0 IW< [netns] 7 root 0 IW [kworker/0:0-rcu] 8 root 0 IW< [kworker/0:0H-ev] 9 root 0 IW [kworker/u8:0-ev] 10 root 0 IW< [mm_percpu_wq] 11 root 0 IW [kworker/u8:1-ev] 12 root 0 IW [rcu_tasks_kthre] 13 root 0 IW [rcu_tasks_rude_] 14 root 0 IW [rcu_tasks_trace] 15 root 0 SW [ksoftirqd/0] 16 root 0 IW [rcu_preempt] 17 root 0 SW [migration/0] 18 root 0 SW [cpuhp/0] 19 root 0 SW [cpuhp/1] 20 root 0 SW [migration/1] 21 root 0 SW [ksoftirqd/1] 22 root 0 IW [kworker/1:0-eve] 23 root 0 IW< [kworker/1:0H-ev] 24 root 0 SW [cpuhp/2] 25 root 0 SW [migration/2] 26 root 0 SW [ksoftirqd/2] 27 root 0 IW [kworker/2:0-pm] 28 root 0 IW< [kworker/2:0H-ev] 29 root 0 SW [cpuhp/3] 30 root 0 SW [migration/3] 31 root 0 SW [ksoftirqd/3] 32 root 0 IW [kworker/3:0-mm_] 33 root 0 IW< [kworker/3:0H-ev] 34 root 0 SW [kdevtmpfs] 35 root 0 IW< [inet_frag_wq] 36 root 0 IW [kworker/0:1-eve] 37 root 0 IW [kworker/1:1-mm_] 38 root 0 SW [oom_reaper] 39 root 0 IW< [writeback] 40 root 0 IW [kworker/u8:2-ev] 41 root 0 SW [kcompactd0] 42 root 0 IW< [kblockd] 43 root 0 IW< [blkcg_punt_bio] 44 root 0 IW< [ata_sff] 45 root 0 IW< [md] 46 root 0 IW< [kworker/0:1H-kb] 47 root 0 IW< [rpciod] 48 root 0 IW< [xprtiod] 49 root 0 SW [kswapd0] 50 root 0 IW< [nfsiod] 51 root 0 IW< [cifsiod] 52 root 0 IW< [smb3decryptd] 53 root 0 IW< [cifsfileinfoput] 54 root 0 IW< [cifsoplockd] 55 root 0 IW< [deferredclose] 57 root 0 IW< [acpi_thermal_pm] 58 root 0 SW [hwrng] 59 root 0 IW< [nvme-wq] 60 root 0 IW< [nvme-reset-wq] 61 root 0 IW< [nvme-delete-wq] 62 root 0 SW [scsi_eh_0] 63 root 0 IW< [scsi_tmf_0] 64 root 0 SW [scsi_eh_1] 65 root 0 IW< [scsi_tmf_1] 66 root 0 SW [scsi_eh_2] 67 root 0 IW< [scsi_tmf_2] 68 root 0 SW [scsi_eh_3] 69 root 0 IW< [scsi_tmf_3] 70 root 0 SW [scsi_eh_4] 71 root 0 IW< [scsi_tmf_4] 72 root 0 SW [scsi_eh_5] 73 root 0 IW< [scsi_tmf_5] 74 root 0 IW [kworker/u8:3-ev] 75 root 0 IW [kworker/u8:4-ev] 76 root 0 IW [kworker/u8:5-ev] 77 root 0 IW [kworker/u8:6-ev] 78 root 0 IW [kworker/u8:7-ev] 79 root 0 IW< [raid5wq] 80 root 0 IW< [dm_bufio_cache] 81 root 0 IW [kworker/2:1-mm_] 82 root 0 IW< [mld] 83 root 0 IW< [kworker/1:1H-kb] 84 root 0 IW< [ipv6_addrconf] 85 root 0 IW< [kworker/u9:0] 126 root 0 IW [kworker/1:2-eve] 127 root 0 IW [kworker/3:1] 128 root 0 SW [jbd2/vda-8] 129 root 0 IW< [ext4-rsv-conver] 172 root 8856 S /sbin/udevd -d 175 root 0 IW< [kworker/3:1H-kb] 177 root 0 IW< [kworker/2:1H-kb] 370 root 0 IW [kworker/2:2] 532 messageb 4108 S /usr/bin/dbus-daemon --system 536 root 12008 S /usr/sbin/connmand 541 root 4356 S xinit /etc/X11/Xsession -- /usr/bin/Xorg :0 -br -pn 546 root 282m S< /usr/bin/Xorg :0 -br -pn 548 root 12436 S /usr/sbin/wpa_supplicant -u 550 root 3468 S /usr/sbin/dropbear -r /etc/dropbear/dropbear_rsa_hos 555 rpc 2976 S /usr/sbin/rpcbind 563 root 6800 S /usr/libexec/bluetooth/bluetoothd 577 root 0 IW [kworker/0:2-mm_] 580 avahi 4264 S avahi-daemon: running [qemux86.local] 581 avahi 4156 S avahi-daemon: chroot helper 588 root 8384 S /usr/sbin/ofonod 596 root 4156 S {start_getty} /bin/sh /bin/start_getty 115200 ttyS0 597 root 4156 S {start_getty} /bin/sh /bin/start_getty 115200 ttyS1 598 root 4156 S /sbin/getty 38400 tty1 600 root 4156 S /sbin/getty -L 115200 ttyS0 vt102 602 root 4200 S -sh 607 root 9076 S matchbox-window-manager -theme Sato -use_cursor no 611 root 5056 S dbus-launch --sh-syntax --exit-with-session 612 root 4116 S /usr/bin/dbus-daemon --syslog --fork --print-pid 5 - 631 root 37604 S /usr/libexec/at-spi-bus-launcher --launch-immediatel 635 root 63140 S connman-applet 646 root 4004 S /usr/bin/dbus-daemon --config-file=/usr/share/defaul 659 root 9400 S /usr/libexec/gconfd-2 669 root 56608 S matchbox-desktop 670 root 63964 S matchbox-panel --start-applets showdesktop,windowsel 671 root 22960 S /usr/bin/settings-daemon 678 root 20668 S /usr/sbin/console-kit-daemon --no-daemon 752 root 29700 S /usr/libexec/at-spi2-registryd --use-gnome-session 895 root 4152 S /sbin/syslogd -n -O /var/log/messages 898 root 4156 S /sbin/klogd -n 985 root 3508 S /usr/sbin/dropbear -r /etc/dropbear/dropbear_rsa_hos 986 root 4200 R ps DEBUG: Waiting for process output: time: 1682160055.322621, endtime: 1682160355.322618 DEBUG: Data from SSH call: PID USER VSZ STAT COMMAND 1 root 2764 S init [5] 2 root 0 SW [kthreadd] 3 root 0 IW< [rcu_gp] 4 root 0 IW< [rcu_par_gp] 5 root 0 IW< [slub_flushwq] 6 root 0 IW< [netns] 7 root 0 IW [kworker/0:0-rcu] 8 root 0 IW< [kworker/0:0H-ev] 9 root 0 IW [kworker/u8:0-ev] 10 root 0 IW< [mm_percpu_wq] 11 root 0 IW [kworker/u8:1-ev] 12 root 0 IW [rcu_tasks_kthre] 13 root 0 IW [rcu_tasks_rude_] 14 root 0 IW [rcu_tasks_trace] 15 root 0 SW [ksoftirqd/0] 16 root 0 IW [rcu_preempt] 17 root 0 SW [migration/0] 18 root 0 SW [cpuhp/0] 19 root 0 SW [cpuhp/1] 20 root 0 SW [migration/1] 21 root 0 SW [ksoftirqd/1] 22 root 0 IW [kworker/1:0-eve] 23 root 0 IW< [kworker/1:0H-ev] 24 root 0 SW [cpuhp/2] 25 root 0 SW [migration/2] 26 root 0 SW [ksoftirqd/2] 27 root 0 IW [kworker/2:0-pm] 28 root 0 IW< [kworker/2:0H-ev] 29 root 0 SW [cpuhp/3] 30 root 0 SW [migration/3] 31 root 0 SW [ksoftirqd/3] 32 root 0 IW [kworker/3:0-mm_] 33 root 0 IW< [kworker/3:0H-ev] 34 root 0 SW [kdevtmpfs] 35 root 0 IW< [inet_frag_wq] 36 root 0 IW [kworker/0:1-eve] 37 root 0 IW [kworker/1:1-mm_] 38 root 0 SW [oom_reaper] 39 root 0 IW< [writeback] 40 root 0 IW [kworker/u8:2-ev] 41 root 0 SW [kcompactd0] 42 root 0 IW< [kblockd] 43 root 0 IW< [blkcg_punt_bio] 44 root 0 IW< [ata_sff] 45 root 0 IW< [md] 46 root 0 IW< [kworker/0:1H-kb] 47 root 0 IW< [rpciod] 48 root 0 IW< [xprtiod] 49 root 0 SW [kswapd0] 50 root 0 IW< [nfsiod] 51 root 0 IW< [cifsiod] 52 root 0 IW< [smb3decryptd] 53 root 0 IW< [cifsfileinfoput] 54 root 0 IW< [cifsoplockd] 55 root 0 IW< [deferredclose] 57 root 0 IW< [acpi_thermal_pm] 58 root 0 SW [hwrng] 59 root 0 IW< [nvme-wq] 60 root 0 IW< [nvme-reset-wq] 61 root 0 IW< [nvme-delete-wq] 62 root 0 SW [scsi_eh_0] 63 root 0 IW< [scsi_tmf_0] 64 root 0 SW [scsi_eh_1] 65 root 0 IW< [scsi_tmf_1] 66 root 0 SW [scsi_eh_2] 67 root 0 IW< [scsi_tmf_2] 68 root 0 SW [scsi_eh_3] 69 root 0 IW< [scsi_tmf_3] 70 root 0 SW [scsi_eh_4] 71 root 0 IW< [scsi_tmf_4] 72 root 0 SW [scsi_eh_5] 73 root 0 IW< [scsi_tmf_5] 74 root 0 IW [kworker/u8:3-ev] 75 root 0 IW [kworker/u8:4-ev] 76 root 0 IW [kworker/u8:5-ev] 77 root 0 IW [kworker/u8:6-ev] 78 root 0 IW [kworker/u8:7-ev] 79 root 0 IW< [raid5wq] 80 root 0 IW< [dm_bufio_cache] 81 root 0 IW [kworker/2:1-mm_] 82 root 0 IW< [mld] 83 root 0 IW< [kworker/1:1H-kb] 84 root 0 IW< [ipv6_addrconf] 85 root 0 IW< [kworker/u9:0] 126 root 0 IW [kworker/1:2-eve] 127 root 0 IW [kworker/3:1] 128 root 0 SW [jbd2/vda-8] 129 root 0 IW< [ext4-rsv-conver] 172 root 8856 S /sbin/udevd -d 175 root 0 IW< [kworker/3:1H-kb] 177 root 0 IW< [kworker/2:1H-kb] 370 root 0 IW [kworker/2:2] 532 messageb 4108 S /usr/bin/dbus-daemon --system 536 root 12008 S /usr/sbin/connmand 541 root 4356 S xinit /etc/X11/Xsession -- /usr/bin/Xorg :0 -br -pn 546 root 282m S< /usr/bin/Xorg :0 -br -pn 548 root 12436 S /usr/sbin/wpa_supplicant -u 550 root 3468 S /usr/sbin/dropbear -r /etc/dropbear/dropbear_rsa_hos 555 rpc 2976 S /usr/sbin/rpcbind 563 root 6800 S /usr/libexec/bluetooth/bluetoothd 577 root 0 IW [kworker/0:2-mm_] 580 avahi 4264 S avahi-daemon: running [qemux86.local] 581 avahi 4156 S avahi-daemon: chroot helper 588 root 8384 S /usr/sbin/ofonod 596 root 4156 S {start_getty} /bin/sh /bin/start_getty 115200 ttyS0 597 root 4156 S {start_getty} /bin/sh /bin/start_getty 115200 ttyS1 598 root 4156 S /sbin/getty 38400 tty1 600 root 4156 S /sbin/getty -L 115200 ttyS0 vt102 602 root 4200 S -sh 607 root 9076 S matchbox-window-manager -theme Sato -use_cursor no 611 root 5056 S dbus-launch --sh-syntax --exit-with-session 612 root 4116 S /usr/bin/dbus-daemon --syslog --fork --print-pid 5 - 631 root 37604 S /usr/libexec/at-spi-bus-launcher --launch-immediatel 635 root 63140 S connman-applet 646 root 4004 S /usr/bin/dbus-daemon --config-file=/usr/share/defaul 659 root 9400 S /usr/libexec/gconfd-2 669 root 56608 S matchbox-desktop 670 root 63964 S matchbox-panel --start-applets showdesktop,windowsel 671 root 22960 S /usr/bin/settings-daemon 678 root 20668 S /usr/sbin/console-kit-daemon --no-daemon 752 root 29700 S /usr/libexec/at-spi2-registryd --use-gnome-session 895 root 4152 S /sbin/syslogd -n -O /var/log/messages 898 root 4156 S /sbin/klogd -n 985 root 3508 S /usr/sbin/dropbear -r /etc/dropbear/dropbear_rsa_hos 986 root 4200 R ps DEBUG: [Command returned '0' after 0.55 seconds] DEBUG: Command: ps Status: 0 Output: PID USER VSZ STAT COMMAND 1 root 2764 S init [5] 2 root 0 SW [kthreadd] 3 root 0 IW< [rcu_gp] 4 root 0 IW< [rcu_par_gp] 5 root 0 IW< [slub_flushwq] 6 root 0 IW< [netns] 7 root 0 IW [kworker/0:0-rcu] 8 root 0 IW< [kworker/0:0H-ev] 9 root 0 IW [kworker/u8:0-ev] 10 root 0 IW< [mm_percpu_wq] 11 root 0 IW [kworker/u8:1-ev] 12 root 0 IW [rcu_tasks_kthre] 13 root 0 IW [rcu_tasks_rude_] 14 root 0 IW [rcu_tasks_trace] 15 root 0 SW [ksoftirqd/0] 16 root 0 IW [rcu_preempt] 17 root 0 SW [migration/0] 18 root 0 SW [cpuhp/0] 19 root 0 SW [cpuhp/1] 20 root 0 SW [migration/1] 21 root 0 SW [ksoftirqd/1] 22 root 0 IW [kworker/1:0-eve] 23 root 0 IW< [kworker/1:0H-ev] 24 root 0 SW [cpuhp/2] 25 root 0 SW [migration/2] 26 root 0 SW [ksoftirqd/2] 27 root 0 IW [kworker/2:0-pm] 28 root 0 IW< [kworker/2:0H-ev] 29 root 0 SW [cpuhp/3] 30 root 0 SW [migration/3] 31 root 0 SW [ksoftirqd/3] 32 root 0 IW [kworker/3:0-mm_] 33 root 0 IW< [kworker/3:0H-ev] 34 root 0 SW [kdevtmpfs] 35 root 0 IW< [inet_frag_wq] 36 root 0 IW [kworker/0:1-eve] 37 root 0 IW [kworker/1:1-mm_] 38 root 0 SW [oom_reaper] 39 root 0 IW< [writeback] 40 root 0 IW [kworker/u8:2-ev] 41 root 0 SW [kcompactd0] 42 root 0 IW< [kblockd] 43 root 0 IW< [blkcg_punt_bio] 44 root 0 IW< [ata_sff] 45 root 0 IW< [md] 46 root 0 IW< [kworker/0:1H-kb] 47 root 0 IW< [rpciod] 48 root 0 IW< [xprtiod] 49 root 0 SW [kswapd0] 50 root 0 IW< [nfsiod] 51 root 0 IW< [cifsiod] 52 root 0 IW< [smb3decryptd] 53 root 0 IW< [cifsfileinfoput] 54 root 0 IW< [cifsoplockd] 55 root 0 IW< [deferredclose] 57 root 0 IW< [acpi_thermal_pm] 58 root 0 SW [hwrng] 59 root 0 IW< [nvme-wq] 60 root 0 IW< [nvme-reset-wq] 61 root 0 IW< [nvme-delete-wq] 62 root 0 SW [scsi_eh_0] 63 root 0 IW< [scsi_tmf_0] 64 root 0 SW [scsi_eh_1] 65 root 0 IW< [scsi_tmf_1] 66 root 0 SW [scsi_eh_2] 67 root 0 IW< [scsi_tmf_2] 68 root 0 SW [scsi_eh_3] 69 root 0 IW< [scsi_tmf_3] 70 root 0 SW [scsi_eh_4] 71 root 0 IW< [scsi_tmf_4] 72 root 0 SW [scsi_eh_5] 73 root 0 IW< [scsi_tmf_5] 74 root 0 IW [kworker/u8:3-ev] 75 root 0 IW [kworker/u8:4-ev] 76 root 0 IW [kworker/u8:5-ev] 77 root 0 IW [kworker/u8:6-ev] 78 root 0 IW [kworker/u8:7-ev] 79 root 0 IW< [raid5wq] 80 root 0 IW< [dm_bufio_cache] 81 root 0 IW [kworker/2:1-mm_] 82 root 0 IW< [mld] 83 root 0 IW< [kworker/1:1H-kb] 84 root 0 IW< [ipv6_addrconf] 85 root 0 IW< [kworker/u9:0] 126 root 0 IW [kworker/1:2-eve] 127 root 0 IW [kworker/3:1] 128 root 0 SW [jbd2/vda-8] 129 root 0 IW< [ext4-rsv-conver] 172 root 8856 S /sbin/udevd -d 175 root 0 IW< [kworker/3:1H-kb] 177 root 0 IW< [kworker/2:1H-kb] 370 root 0 IW [kworker/2:2] 532 messageb 4108 S /usr/bin/dbus-daemon --system 536 root 12008 S /usr/sbin/connmand 541 root 4356 S xinit /etc/X11/Xsession -- /usr/bin/Xorg :0 -br -pn 546 root 282m S< /usr/bin/Xorg :0 -br -pn 548 root 12436 S /usr/sbin/wpa_supplicant -u 550 root 3468 S /usr/sbin/dropbear -r /etc/dropbear/dropbear_rsa_hos 555 rpc 2976 S /usr/sbin/rpcbind 563 root 6800 S /usr/libexec/bluetooth/bluetoothd 577 root 0 IW [kworker/0:2-mm_] 580 avahi 4264 S avahi-daemon: running [qemux86.local] 581 avahi 4156 S avahi-daemon: chroot helper 588 root 8384 S /usr/sbin/ofonod 596 root 4156 S {start_getty} /bin/sh /bin/start_getty 115200 ttyS0 597 root 4156 S {start_getty} /bin/sh /bin/start_getty 115200 ttyS1 598 root 4156 S /sbin/getty 38400 tty1 600 root 4156 S /sbin/getty -L 115200 ttyS0 vt102 602 root 4200 S -sh 607 root 9076 S matchbox-window-manager -theme Sato -use_cursor no 611 root 5056 S dbus-launch --sh-syntax --exit-with-session 612 root 4116 S /usr/bin/dbus-daemon --syslog --fork --print-pid 5 - 631 root 37604 S /usr/libexec/at-spi-bus-launcher --launch-immediatel 635 root 63140 S connman-applet 646 root 4004 S /usr/bin/dbus-daemon --config-file=/usr/share/defaul 659 root 9400 S /usr/libexec/gconfd-2 669 root 56608 S matchbox-desktop 670 root 63964 S matchbox-panel --start-applets showdesktop,windowsel 671 root 22960 S /usr/bin/settings-daemon 678 root 20668 S /usr/sbin/console-kit-daemon --no-daemon 752 root 29700 S /usr/libexec/at-spi2-registryd --use-gnome-session 895 root 4152 S /sbin/syslogd -n -O /var/log/messages 898 root 4156 S /sbin/klogd -n 985 root 3508 S /usr/sbin/dropbear -r /etc/dropbear/dropbear_rsa_hos 986 root 4200 R ps NOTE: ... ok NOTE: ---------------------------------------------------------------------- NOTE: Ran 71 tests in 34.560s NOTE: OK NOTE: (skipped=57) 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: 998916.01 runqemu - INFO - Removing /home/pokybuild/tmp/core-image-sato-qemux86.ext4.2268067 tput: No value for $TERM and no -T specified RESULTS: RESULTS - connman.ConnmanTest.test_connmand_help: PASSED (0.55s) RESULTS - connman.ConnmanTest.test_connmand_running: PASSED (0.53s) RESULTS - date.DateTest.test_date: PASSED (2.14s) RESULTS - df.DfTest.test_df: PASSED (0.52s) RESULTS - oe_syslog.SyslogTest.test_syslog_running: PASSED (0.53s) RESULTS - oe_syslog.SyslogTestConfig.test_syslog_logger: PASSED (1.85s) RESULTS - oe_syslog.SyslogTestConfig.test_syslog_restart: PASSED (4.39s) RESULTS - oe_syslog.SyslogTestConfig.test_syslog_startup_config: PASSED (11.30s) RESULTS - opkg.OpkgRepoTest.test_opkg_install_from_repo: PASSED (2.95s) RESULTS - parselogs.ParseLogsTest.test_parselogs: PASSED (4.71s) RESULTS - ping.PingTest.test_ping: PASSED (0.03s) RESULTS - python.PythonTest.test_python3: PASSED (0.60s) RESULTS - ssh.SSHTest.test_ssh: PASSED (1.08s) RESULTS - xorg.XorgTest.test_xorg_running: 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 - 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 - logrotate.LogrotateTest.test_logrotate_newlog: SKIPPED (0.00s) RESULTS - logrotate.LogrotateTest.test_logrotate_wtmp: SKIPPED (0.00s) RESULTS - pam.PamBasicTest.test_pam: SKIPPED (0.00s) RESULTS - perl.PerlTest.test_perl_works: SKIPPED (0.00s) RESULTS - ptest.PtestRunnerTest.test_ptestrunner_expectfail: SKIPPED (0.00s) RESULTS - ptest.PtestRunnerTest.test_ptestrunner_expectsuccess: 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.RustCompileTest.test_cargo_compile: SKIPPED (0.00s) RESULTS - rust.RustCompileTest.test_rust_compile: SKIPPED (0.00s) RESULTS - rust.RustHelloworldTest.test_rusthelloworld: SKIPPED (0.00s) RESULTS - scp.ScpTest.test_scp_file: 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 - weston.WestonTest.test_weston_supports_xwayland: SKIPPED (0.00s) SUMMARY: core-image-sato () - Ran 71 tests in 34.561s core-image-sato - OK - All required tests passed (successes=14, skipped=57, failures=0, errors=0) DEBUG: Python function do_testimage finished