DEBUG: Executing python function extend_recipe_sysroot
NOTE: Direct dependencies are ['virtual:native:TOPDIR/sources/openembedded-core/meta/recipes-devtools/opkg-utils/opkg-utils_0.4.3.bb:do_populate_sysroot', 'virtual:native:TOPDIR/sources/openembedded-core/meta/recipes-devtools/opkg/opkg_0.4.4.bb:do_populate_sysroot', 'TOPDIR/sources/openembedded-core/meta/recipes-devtools/qemu/qemu-helper-native_1.0.bb:do_populate_sysroot', 'TOPDIR/sources/openembedded-core/meta/recipes-devtools/qemu/qemu-system-native_5.2.0.bb:do_populate_sysroot', 'TOPDIR/sources/openembedded-core/meta/recipes-devtools/qemu/qemu-native_5.2.0.bb:do_populate_sysroot']
NOTE: Installed into sysroot: []
NOTE: Skipping as already exists in sysroot: ['opkg-utils-native', 'opkg-native', 'qemu-helper-native', 'qemu-system-native', 'qemu-native', 'perl-native', 'quilt-native', 'xz-native', 'pkgconfig-native', 'dtc-native', 'glib-2.0-native', 'alsa-lib-native', 'zlib-native', 'mesa-native', 'virglrenderer-native', 'pixman-native', 'autoconf-native', 'libtool-native', 'libarchive-native', 'libsolv-native', 'automake-native', 'db-native', 'gdbm-native', 'gettext-minimal-native', 'flex-native', 'meson-native', 'gtk-doc-native', 'libpcre-native', 'libffi-native', 'python3-native', 'ninja-native', 'gettext-native', 'util-linux-native', 'xorgproto-native', 'libxext-native', 'python3-mako-native', 'xrandr-native', 'libxdamage-native', 'libxml2-native', 'expat-native', 'libx11-native', 'libxxf86vm-native', 'libxfixes-native', 'makedepend-native', 'libdrm-native', 'chrpath-native', 'libepoxy-native', 'm4-native', 'gnu-config-native', 'texinfo-dummy-native', 'e2fsprogs-native', 'lzo-native', 'bzip2-native', 'cmake-native', 'python3-setuptools-native', 'openssl-native', 'readline-native', 'libtirpc-native', 'libnsl2-native', 'sqlite3-native', 're2c-native', 'libpcre2-native', 'ncurses-native', 'libcap-ng-native', 'util-macros-native', 'libxrandr-native', 'libxrender-native', 'xtrans-native', 'libxcb-native', 'libpciaccess-native', 'libpthread-stubs-native', 'attr-native', 'curl-native', 'libxdmcp-native', 'xcb-proto-native', 'libxau-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_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_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_xorg_running (xorg.XorgTest)
DEBUG: Not using kvm for runqemu
DEBUG: Created listening socket for qemu serial console on: 127.0.0.1:41879
DEBUG: Created listening socket for qemu serial console on: 127.0.0.1:54747
DEBUG: launchcmd=runqemu snapshot publicvnc qemuarm TOPDIR/build/tmp/deploy/images/qemuarm/yoe-simple-image-qemuarm.ext4 tcpserial=41879:54747 bootparams="console=tty1 console=ttyS0,115200n8 printk.time=1" qemuparams="-pidfile TOPDIR/pidfile_1215145"
DEBUG: runqemu started, pid is 1215148
DEBUG: waiting at most 120 seconds for qemu pid (02/26/21 07:45:44)
DEBUG: qemu started in 0.501173734664917 seconds - qemu procces pid is 1215178 (02/26/21 07:45:44)
DEBUG: qemu cmdline used:
TOPDIR/build/tmp/work/x86_64-linux/qemu-helper-native/1.0-r1/recipe-sysroot-native/usr/bin/qemu-system-arm -device virtio-net-device,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 id=disk0,file=TOPDIR/build/tmp/deploy/images/qemuarm/yoe-simple-image-qemuarm.ext4,if=none,format=raw -device virtio-blk-device,drive=disk0 -device VGA,edid=on -device qemu-xhci -device usb-tablet -device usb-kbd -vnc :0 -machine virt,highmem=off -cpu cortex-a15 -smp 2 -m 1024 -device virtio-serial-device -chardev socket,id=virtcon,port=41879,host=127.0.0.1 -device virtconsole,chardev=virtcon -serial tcp:127.0.0.1:54747 -pidfile TOPDIR/pidfile_1215145 -snapshot -kernel TOPDIR/build/tmp/deploy/images/qemuarm/zImage--5.10.16+git0+5beca08578_57a6fbd33d-r0.0-qemuarm-20210226110122.bin -append root=/dev/vda rw  mem=1024M ip=192.168.7.2::192.168.7.1:255.255.255.0 console=ttyAMA0 vmalloc=256 console=tty1 console=ttyS0,115200n8 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: [TOPDIR/build/tmp/deploy/images/qemuarm/zImage--5.10.16+git0+5beca08578_57a6fbd33d-r0.0-qemuarm-20210226110122.bin]
MACHINE: [qemuarm]
FSTYPE: [ext4]
ROOTFS: [TOPDIR/build/tmp/deploy/images/qemuarm/yoe-simple-image-qemuarm.ext4]
CONFFILE: [TOPDIR/build/tmp/deploy/images/qemuarm/yoe-simple-image-qemuarm.qemuboot.conf]

runqemu - INFO - Setting up tap interface under sudo
runqemu - INFO - Network configuration: ip=192.168.7.2::192.168.7.1:255.255.255.0
runqemu - INFO - Running TOPDIR/build/tmp/work/x86_64-linux/qemu-helper-native/1.0-r1/recipe-sysroot-native/usr/bin/qemu-system-arm -device virtio-net-device,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 id=disk0,file=TOPDIR/build/tmp/deploy/images/qemuarm/yoe-simple-image-qemuarm.ext4,if=none,format=raw -device virtio-blk-device,drive=disk0 -device VGA,edid=on -device qemu-xhci -device usb-tablet -device usb-kbd  -vnc :0 -machine virt,highmem=off -cpu cortex-a15 -smp 2 -m 1024 -device virtio-serial-device -chardev socket,id=virtcon,port=41879,host=127.0.0.1 -device virtconsole,chardev=virtcon -serial tcp:127.0.0.1:54747  -pidfile TOPDIR/pidfile_1215145 -snapshot -kernel TOPDIR/build/tmp/deploy/images/qemuarm/zImage--5.10.16+git0+5beca08578_57a6fbd33d-r0.0-qemuarm-20210226110122.bin -append 'root=/dev/vda rw  mem=1024M ip=192.168.7.2::192.168.7.1:255.255.255.0 console=ttyAMA0 vmalloc=256 console=tty1 console=ttyS0,115200n8 printk.time=1'


DEBUG: Waiting at most 1000 seconds for login banner (02/26/21 07:45:44)
DEBUG: Connection from 127.0.0.1:33824
DEBUG: Reached login banner in 29.33943748474121 seconds (02/26/21 07:46:13)
DEBUG: Logged as root in serial console
Not starting HTTPService for directory build/tmp/deploy/deb/all 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
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 UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR 192.168.7.2 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; uname -a
DEBUG: time: 1614354377.4818633, endtime: 1614354677.4744241
DEBUG: Partial data from SSH call: Linux qemuarm 5.10.16-yocto-standard #1 SMP PREEMPT Fri Feb 26 11:14:00 UTC 2021 armv7l GNU/Linux

DEBUG: time: 1614354378.1814601, endtime: 1614354678.181457
DEBUG: [Command returned '0' after 0.71 seconds]
DEBUG: Command: uname -a
Output:  Linux qemuarm 5.10.16-yocto-standard #1 SMP PREEMPT Fri Feb 26 11:14:00 UTC 2021 armv7l GNU/Linux

DEBUG: [Running]$ ssh -l root -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR 192.168.7.2 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; cat /etc/masterimage
DEBUG: time: 1614354378.1911833, endtime: 1614354678.182706
DEBUG: Partial data from SSH call: cat: can't open '/etc/masterimage': No such file or directory

DEBUG: time: 1614354378.6337137, endtime: 1614354678.6337109
DEBUG: [Command returned '1' after 0.45 seconds]
DEBUG: Command: cat /etc/masterimage
Output:  cat: can't open '/etc/masterimage': 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
NOTE:  ... skipped 'Test requires connman to be installed'
Test requires connman to be installed
NOTE: test_connmand_running (connman.ConnmanTest)
NOTE:  ... skipped "Test case connman.ConnmanTest.test_connmand_running depends on connman.ConnmanTest.test_connmand_help but it didn't pass/run."
Test case connman.ConnmanTest.test_connmand_running depends on connman.ConnmanTest.test_connmand_help but it didn't pass/run.
NOTE: test_date (date.DateTest)
DEBUG: Checking if at least one of busybox, coreutils is installed
DEBUG: [Running]$ ssh -l root -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: time: 1614354378.64926, endtime: 1614354678.6417875
DEBUG: Partial data from SSH call: 2021-02-26 15:46:18

DEBUG: time: 1614354379.1090343, endtime: 1614354679.1090317
DEBUG: [Command returned '0' after 0.47 seconds]
DEBUG: Command: date +"%Y-%m-%d %T"
Output:  2021-02-26 15:46:18

DEBUG: [Running]$ ssh -l root -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR 192.168.7.2 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; date -s "2016-08-09 10:00:00"
DEBUG: time: 1614354379.1180556, endtime: 1614354679.1103098
DEBUG: Partial data from SSH call: Tue Aug  9 10:00:00 UTC 2016

DEBUG: time: 1614354379.6312592, endtime: 1614354679.6312563
DEBUG: [Command returned '0' after 0.52 seconds]
DEBUG: Command: date -s "2016-08-09 10:00:00"
Output:  Tue Aug  9 10:00:00 UTC 2016

DEBUG: [Running]$ ssh -l root -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR 192.168.7.2 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; date -R
DEBUG: time: 1614354379.6401548, endtime: 1614354679.6325192
DEBUG: Partial data from SSH call: Tue, 09 Aug 2016 10:00:00 +0000

DEBUG: time: 1614354380.1230116, endtime: 1614354680.123009
DEBUG: [Command returned '0' after 0.49 seconds]
DEBUG: Command: date -R
Output:  Tue, 09 Aug 2016 10:00:00 +0000

DEBUG: [Running]$ ssh -l root -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR 192.168.7.2 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; date -s "2021-02-26 15:46:18"
DEBUG: time: 1614354380.1322653, endtime: 1614354680.12473
DEBUG: Partial data from SSH call: Fri Feb 26 15:46:18 UTC 2021

DEBUG: time: 1614354380.6472435, endtime: 1614354680.6472409
DEBUG: [Command returned '0' after 0.52 seconds]
DEBUG: Command: date -s "2021-02-26 15:46:18"
Output:  Fri Feb 26 15:46:18 UTC 2021

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 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: time: 1614354380.6571977, endtime: 1614354680.6500714
DEBUG: Partial data from SSH call: 27513

DEBUG: time: 1614354381.1426308, endtime: 1614354681.1426284
DEBUG: [Command returned '0' after 0.49 seconds]
DEBUG: Command: df -P / | sed -n '2p' | awk '{print $4}'
Output:  27513

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.
Not starting HTTPService for directory TOPDIR/build/tmp/work/qemuarm-yoe-linux-gnueabi/yoe-simple-image/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 usrmege'
Test run when enable usrmege
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.
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_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 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: time: 1614354381.1672597, endtime: 1614354681.1593335
DEBUG: Partial data from SSH call: cp: can't stat '/etc/logrotate.d/wtmp': No such file or directory

DEBUG: time: 1614354381.571097, endtime: 1614354681.5710943
DEBUG: [Command returned '1' after 0.41 seconds]
DEBUG: Command: cp /etc/logrotate.d/wtmp $HOME/wtmp.oeqabak
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 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 $HOME/logrotate_dir
DEBUG: time: 1614354381.5820189, endtime: 1614354681.574207
DEBUG: Partial data from SSH call: mv: can't rename '/home/root/wtmp.oeqabak': No such file or directory

DEBUG: time: 1614354382.095135, endtime: 1614354682.0951319
DEBUG: [Command returned '1' after 0.52 seconds]
DEBUG: Command: mv -f $HOME/wtmp.oeqabak /etc/logrotate.d/wtmp && rm -rf $HOME/logrotate_dir
Output:  mv: can't rename '/home/root/wtmp.oeqabak': No such file or directory

DEBUG: [Running]$ ssh -l root -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: time: 1614354382.1053915, endtime: 1614354682.0964444
DEBUG: [Command returned '0' after 0.53 seconds]
DEBUG: Command: rm -rf /var/log/logrotate_testfile && rm -rf /etc/logrotate.d/logrotate_testfile
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 UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR 192.168.7.2 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; ps
DEBUG: time: 1614354382.6351676, endtime: 1614354682.6271322
DEBUG: Partial data from SSH call:   PID USER       VSZ STAT COMMAND
    1 root      1440 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   [kworker/0:0-eve]
    6 root         0 IW<  [kworker/0:0H-kb]
    7 root         0 IW   [kworker/u4:0-ev]
    8 root         0 IW<  [mm_percpu_wq]
    9 root         0 SW   [rcu_tasks_kthre]
   10 root         0 SW   [rcu_tasks_rude_]
   11 root         0 SW   [rcu_tasks_trace]
   12 root         0 SW   [ksoftirqd/0]
   13 root         0 IW   [rcu_preempt]
   14 root         0 SW   [migration/0]
   15 root         0 SW   [cpuhp/0]
   16 root         0 SW   [cpuhp/1]
   17 root         0 SW   [migration/1]
   18 root         0 SW   [ksoftirqd/1]
   19 root         0 IW   [kworker/1:0-mm_]
   20 root         0 IW<  [kworker/1:0H-ev]
   21 root         0 SW   [kdevtmpfs]
   22 root         0 IW<  [netns]
   23 root         0 IW   [kworker/0:1]
   24 root         0 SW   [oom_reaper]
   25 root         0 IW<  [writeback]
   26 root         0 SW   [kcompactd0]
   42 root         0 IW<  [cryptd]
   60 root         0 IW<  [kblockd]
   61 root         0 IW<  [blkcg_punt_bio]
   62 root         0 IW   [kworker/1:1-mm_]
   63 root         0 IW<  [md]
   64 root         0 SW   [watchdogd]
   65 root         0 IW<  [kworker/1:1H-kb]
   66 root         0 IW<  [rpciod]
   67 root         0 IW<  [kworker/u5:0]
   68 root         0 IW<  [xprtiod]
   88 root         0 SW   [kswapd0]
   89 root         0 IW<  [nfsiod]
   90 root         0 IW<  [cifsiod]
   91 root         0 IW<  [smb3decryptd]
   92 root         0 IW<  [cifsfileinfoput]
   93 root         0 IW<  [cifsoplockd]
   95 root         0 SW   [khvcd]
   96 root         0 IW   [kworker/0:2]
   97 root         0 IW<  [raid5wq]
   98 root         0 IW<  [dm_bufio_cache]
   99 root         0 IW<  [ipv6_addrconf]
  106 root         0 IW   [kworker/u4:1-fl]
  113 root         0 SW   [jbd2/vda-8]
  114 root         0 IW<  [ext4-rsv-conver]
  115 root         0 IW<  [kworker/0:1H-kb]
  147 root      2248 S    /sbin/udevd -d
  155 root         0 IW   [kworker/u4:2-ev]
  333 messageb  2208 S    /usr/bin/dbus-daemon --system
  339 root      2008 S    /usr/sbin/dropbear -r /etc/dropbear/dropbear_rsa_hos
  344 rpc       1872 S    /usr/sbin/rpcbind
  361 root      2284 S    /sbin/syslogd -n -O /var/log/messages
  365 root      2284 S    /sbin/klogd -n
  372 avahi     2848 S    avahi-daemon: running [qemuarm.local]
  373 avahi     2744 S    avahi-daemon: chroot helper
  382 root      2284 S    {start_getty} /bin/sh /bin/start_getty 115200 ttyAMA
  383 root      2284 S    /sbin/getty 38400 tty1
  397 root      3184 S    /bin/login --
  398 root      2372 S    -sh
  426 root      2440 S    /usr/sbin/dropbear -r /etc/dropbear/dropbear_rsa_hos
  427 root      2372 R    ps

DEBUG: time: 1614354383.2088716, endtime: 1614354683.2088692
DEBUG: [Command returned '0' after 0.58 seconds]
DEBUG: Command: ps
Output:    PID USER       VSZ STAT COMMAND
    1 root      1440 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   [kworker/0:0-eve]
    6 root         0 IW<  [kworker/0:0H-kb]
    7 root         0 IW   [kworker/u4:0-ev]
    8 root         0 IW<  [mm_percpu_wq]
    9 root         0 SW   [rcu_tasks_kthre]
   10 root         0 SW   [rcu_tasks_rude_]
   11 root         0 SW   [rcu_tasks_trace]
   12 root         0 SW   [ksoftirqd/0]
   13 root         0 IW   [rcu_preempt]
   14 root         0 SW   [migration/0]
   15 root         0 SW   [cpuhp/0]
   16 root         0 SW   [cpuhp/1]
   17 root         0 SW   [migration/1]
   18 root         0 SW   [ksoftirqd/1]
   19 root         0 IW   [kworker/1:0-mm_]
   20 root         0 IW<  [kworker/1:0H-ev]
   21 root         0 SW   [kdevtmpfs]
   22 root         0 IW<  [netns]
   23 root         0 IW   [kworker/0:1]
   24 root         0 SW   [oom_reaper]
   25 root         0 IW<  [writeback]
   26 root         0 SW   [kcompactd0]
   42 root         0 IW<  [cryptd]
   60 root         0 IW<  [kblockd]
   61 root         0 IW<  [blkcg_punt_bio]
   62 root         0 IW   [kworker/1:1-mm_]
   63 root         0 IW<  [md]
   64 root         0 SW   [watchdogd]
   65 root         0 IW<  [kworker/1:1H-kb]
   66 root         0 IW<  [rpciod]
   67 root         0 IW<  [kworker/u5:0]
   68 root         0 IW<  [xprtiod]
   88 root         0 SW   [kswapd0]
   89 root         0 IW<  [nfsiod]
   90 root         0 IW<  [cifsiod]
   91 root         0 IW<  [smb3decryptd]
   92 root         0 IW<  [cifsfileinfoput]
   93 root         0 IW<  [cifsoplockd]
   95 root         0 SW   [khvcd]
   96 root         0 IW   [kworker/0:2]
   97 root         0 IW<  [raid5wq]
   98 root         0 IW<  [dm_bufio_cache]
   99 root         0 IW<  [ipv6_addrconf]
  106 root         0 IW   [kworker/u4:1-fl]
  113 root         0 SW   [jbd2/vda-8]
  114 root         0 IW<  [ext4-rsv-conver]
  115 root         0 IW<  [kworker/0:1H-kb]
  147 root      2248 S    /sbin/udevd -d
  155 root         0 IW   [kworker/u4:2-ev]
  333 messageb  2208 S    /usr/bin/dbus-daemon --system
  339 root      2008 S    /usr/sbin/dropbear -r /etc/dropbear/dropbear_rsa_hos
  344 rpc       1872 S    /usr/sbin/rpcbind
  361 root      2284 S    /sbin/syslogd -n -O /var/log/messages
  365 root      2284 S    /sbin/klogd -n
  372 avahi     2848 S    avahi-daemon: running [qemuarm.local]
  373 avahi     2744 S    avahi-daemon: chroot helper
  382 root      2284 S    {start_getty} /bin/sh /bin/start_getty 115200 ttyAMA
  383 root      2284 S    /sbin/getty 38400 tty1
  397 root      3184 S    /bin/login --
  398 root      2372 S    -sh
  426 root      2440 S    /usr/sbin/dropbear -r /etc/dropbear/dropbear_rsa_hos
  427 root      2372 R    ps

NOTE:  ... ok
NOTE: test_syslog_logger (oe_syslog.SyslogTestConfig)
DEBUG: [Running]$ ssh -l root -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: time: 1614354383.219352, endtime: 1614354683.211634
DEBUG: [Command returned '0' after 0.52 seconds]
DEBUG: Command: logger foobar
Output:  

DEBUG: [Running]$ ssh -l root -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: time: 1614354384.747078, endtime: 1614354684.737139
DEBUG: Partial data from SSH call: Feb 26 15:46:21 qemuarm user.notice root: foobar

DEBUG: time: 1614354385.2584856, endtime: 1614354685.2584827
DEBUG: [Command returned '0' after 0.52 seconds]
DEBUG: Command: grep foobar /var/log/messages
Output:  Feb 26 15:46:21 qemuarm user.notice root: foobar

NOTE:  ... ok
NOTE: test_syslog_restart (oe_syslog.SyslogTestConfig)
DEBUG: [Running]$ ssh -l root -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: time: 1614354385.2698443, endtime: 1614354685.2611609
DEBUG: [Command returned '1' after 0.54 seconds]
DEBUG: Command: pidof systemd-journald
Output:  

DEBUG: systemd-journald is not running
DEBUG: [Running]$ ssh -l root -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: time: 1614354385.8102572, endtime: 1614354685.8008535
DEBUG: [Command returned '1' after 0.57 seconds]
DEBUG: Command: pidof rsyslogd
Output:  

DEBUG: rsyslogd is not running
DEBUG: [Running]$ ssh -l root -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: time: 1614354386.3820732, endtime: 1614354686.37362
DEBUG: Partial data from SSH call: 361

DEBUG: time: 1614354386.9248338, endtime: 1614354686.9248312
DEBUG: [Command returned '0' after 0.55 seconds]
DEBUG: Command: pidof syslogd
Output:  361

DEBUG: [Running]$ ssh -l root -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: time: 1614354386.9338264, endtime: 1614354686.926062
DEBUG: Partial data from SSH call: 365

DEBUG: time: 1614354387.508539, endtime: 1614354687.5085363
DEBUG: [Command returned '0' after 0.58 seconds]
DEBUG: Command: pidof klogd
Output:  365

DEBUG: [Running]$ ssh -l root -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: time: 1614354387.5178382, endtime: 1614354687.5097857
DEBUG: Partial data from SSH call: Stopping syslogd/klogd: stopped syslogd (pid 361)
stopped klogd (pid 365)
done
Starting syslogd/klogd: done

DEBUG: time: 1614354388.7124572, endtime: 1614354688.712454
DEBUG: [Command returned '0' after 1.20 seconds]
DEBUG: Command: /etc/init.d/syslog restart
Output:  Stopping syslogd/klogd: stopped syslogd (pid 361)
stopped klogd (pid 365)
done
Starting syslogd/klogd: done

DEBUG: [Running]$ ssh -l root -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR 192.168.7.2 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; kill -0 361
DEBUG: time: 1614354388.7240105, endtime: 1614354688.7139072
DEBUG: Partial data from SSH call: sh: can't kill pid 361: No such process

DEBUG: time: 1614354389.1348052, endtime: 1614354689.1348023
DEBUG: [Command returned '1' after 0.42 seconds]
DEBUG: Command: kill -0 361
Output:  sh: can't kill pid 361: No such process

DEBUG: [Running]$ ssh -l root -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR 192.168.7.2 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; kill -0 365
DEBUG: time: 1614354389.1450722, endtime: 1614354689.1361654
DEBUG: Partial data from SSH call: sh: can't kill pid 365: No such process

DEBUG: time: 1614354389.585641, endtime: 1614354689.5856383
DEBUG: [Command returned '1' after 0.45 seconds]
DEBUG: Command: kill -0 365
Output:  sh: can't kill pid 365: No such process

DEBUG: [Running]$ ssh -l root -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: time: 1614354389.5956666, endtime: 1614354689.5871115
DEBUG: Partial data from SSH call: 450

DEBUG: time: 1614354390.1538093, endtime: 1614354690.153807
DEBUG: [Command returned '0' after 0.57 seconds]
DEBUG: Command: pidof syslogd
Output:  450

DEBUG: [Running]$ ssh -l root -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: time: 1614354390.1635394, endtime: 1614354690.155083
DEBUG: Partial data from SSH call: 453

DEBUG: time: 1614354390.6983328, endtime: 1614354690.69833
DEBUG: [Command returned '0' after 0.54 seconds]
DEBUG: Command: pidof klogd
Output:  453

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 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: time: 1614354390.7095976, endtime: 1614354690.7013245
DEBUG: [Command returned '0' after 0.47 seconds]
DEBUG: Command: echo "LOGFILE=/var/log/test" >> /etc/syslog-startup.conf
Output:  

DEBUG: [Running]$ ssh -l root -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: time: 1614354391.184468, endtime: 1614354691.1767898
DEBUG: [Command returned '1' after 0.56 seconds]
DEBUG: Command: pidof systemd-journald
Output:  

DEBUG: systemd-journald is not running
DEBUG: [Running]$ ssh -l root -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: time: 1614354391.7469676, endtime: 1614354691.7387786
DEBUG: [Command returned '1' after 0.53 seconds]
DEBUG: Command: pidof rsyslogd
Output:  

DEBUG: rsyslogd is not running
DEBUG: [Running]$ ssh -l root -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: time: 1614354392.2738347, endtime: 1614354692.2657344
DEBUG: Partial data from SSH call: 450

DEBUG: time: 1614354392.8302808, endtime: 1614354692.830278
DEBUG: [Command returned '0' after 0.57 seconds]
DEBUG: Command: pidof syslogd
Output:  450

DEBUG: [Running]$ ssh -l root -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: time: 1614354392.8402896, endtime: 1614354692.8315673
DEBUG: Partial data from SSH call: 453

DEBUG: time: 1614354393.3498359, endtime: 1614354693.3498333
DEBUG: [Command returned '0' after 0.52 seconds]
DEBUG: Command: pidof klogd
Output:  453

DEBUG: [Running]$ ssh -l root -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: time: 1614354393.3599246, endtime: 1614354693.351155
DEBUG: Partial data from SSH call: Stopping syslogd/klogd: stopped syslogd (pid 450)
stopped klogd (pid 453)
done
Starting syslogd/klogd: done

DEBUG: time: 1614354394.5408084, endtime: 1614354694.5408056
DEBUG: [Command returned '0' after 1.19 seconds]
DEBUG: Command: /etc/init.d/syslog restart
Output:  Stopping syslogd/klogd: stopped syslogd (pid 450)
stopped klogd (pid 453)
done
Starting syslogd/klogd: done

DEBUG: [Running]$ ssh -l root -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR 192.168.7.2 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; kill -0 450
DEBUG: time: 1614354394.5508103, endtime: 1614354694.5421681
DEBUG: Partial data from SSH call: sh: can't kill pid 450: No such process

DEBUG: time: 1614354394.9529104, endtime: 1614354694.9529078
DEBUG: [Command returned '1' after 0.41 seconds]
DEBUG: Command: kill -0 450
Output:  sh: can't kill pid 450: No such process

DEBUG: [Running]$ ssh -l root -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR 192.168.7.2 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; kill -0 453
DEBUG: time: 1614354394.961909, endtime: 1614354694.9542305
DEBUG: Partial data from SSH call: sh: can't kill pid 453: No such process

DEBUG: time: 1614354395.3908808, endtime: 1614354695.3908782
DEBUG: [Command returned '1' after 0.44 seconds]
DEBUG: Command: kill -0 453
Output:  sh: can't kill pid 453: No such process

DEBUG: [Running]$ ssh -l root -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: time: 1614354395.4000452, endtime: 1614354695.3921335
DEBUG: Partial data from SSH call: 482

DEBUG: time: 1614354395.910276, endtime: 1614354695.9102728
DEBUG: [Command returned '0' after 0.52 seconds]
DEBUG: Command: pidof syslogd
Output:  482

DEBUG: [Running]$ ssh -l root -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: time: 1614354395.9207032, endtime: 1614354695.9116035
DEBUG: Partial data from SSH call: 485

DEBUG: time: 1614354396.385182, endtime: 1614354696.3851793
DEBUG: [Command returned '0' after 0.47 seconds]
DEBUG: Command: pidof klogd
Output:  485

DEBUG: [Running]$ ssh -l root -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR 192.168.7.2 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; logger foobar && grep foobar /var/log/test
DEBUG: time: 1614354396.3948882, endtime: 1614354696.386643
DEBUG: Partial data from SSH call: Feb 26 15:46:34 qemuarm user.notice root: foobar

DEBUG: time: 1614354396.9336598, endtime: 1614354696.9336572
DEBUG: [Command returned '0' after 0.55 seconds]
DEBUG: Command: logger foobar && grep foobar /var/log/test
Output:  Feb 26 15:46:34 qemuarm user.notice root: foobar

DEBUG: [Running]$ ssh -l root -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: time: 1614354396.943835, endtime: 1614354696.9349713
DEBUG: [Command returned '0' after 0.47 seconds]
DEBUG: Command: sed -i 's#LOGFILE=/var/log/test##' /etc/syslog-startup.conf
Output:  

DEBUG: [Running]$ ssh -l root -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: time: 1614354397.4154685, endtime: 1614354697.406492
DEBUG: [Command returned '1' after 0.48 seconds]
DEBUG: Command: pidof systemd-journald
Output:  

DEBUG: systemd-journald is not running
DEBUG: [Running]$ ssh -l root -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: time: 1614354397.8904212, endtime: 1614354697.8826213
DEBUG: [Command returned '1' after 0.47 seconds]
DEBUG: Command: pidof rsyslogd
Output:  

DEBUG: rsyslogd is not running
DEBUG: [Running]$ ssh -l root -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: time: 1614354398.3600872, endtime: 1614354698.352278
DEBUG: Partial data from SSH call: 482

DEBUG: time: 1614354398.838693, endtime: 1614354698.8386898
DEBUG: [Command returned '0' after 0.49 seconds]
DEBUG: Command: pidof syslogd
Output:  482

DEBUG: [Running]$ ssh -l root -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: time: 1614354398.848144, endtime: 1614354698.8399158
DEBUG: Partial data from SSH call: 485

DEBUG: time: 1614354399.3468611, endtime: 1614354699.3468585
DEBUG: [Command returned '0' after 0.51 seconds]
DEBUG: Command: pidof klogd
Output:  485

DEBUG: [Running]$ ssh -l root -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: time: 1614354399.3559842, endtime: 1614354699.348092
DEBUG: Partial data from SSH call: Stopping syslogd/klogd: stopped syslogd (pid 482)
stopped klogd (pid 485)
done
Starting syslogd/klogd: done

DEBUG: time: 1614354400.2777567, endtime: 1614354700.2777536
DEBUG: [Command returned '0' after 0.93 seconds]
DEBUG: Command: /etc/init.d/syslog restart
Output:  Stopping syslogd/klogd: stopped syslogd (pid 482)
stopped klogd (pid 485)
done
Starting syslogd/klogd: done

DEBUG: [Running]$ ssh -l root -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR 192.168.7.2 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; kill -0 482
DEBUG: time: 1614354400.2876751, endtime: 1614354700.279093
DEBUG: Partial data from SSH call: sh: can't kill pid 482: No such process

DEBUG: time: 1614354400.6752586, endtime: 1614354700.6752558
DEBUG: [Command returned '1' after 0.40 seconds]
DEBUG: Command: kill -0 482
Output:  sh: can't kill pid 482: No such process

DEBUG: [Running]$ ssh -l root -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR 192.168.7.2 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; kill -0 485
DEBUG: time: 1614354400.684618, endtime: 1614354700.6764898
DEBUG: Partial data from SSH call: sh: can't kill pid 485: No such process

DEBUG: time: 1614354401.0947406, endtime: 1614354701.094738
DEBUG: [Command returned '1' after 0.42 seconds]
DEBUG: Command: kill -0 485
Output:  sh: can't kill pid 485: No such process

DEBUG: [Running]$ ssh -l root -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: time: 1614354401.1044006, endtime: 1614354701.096028
DEBUG: Partial data from SSH call: 517

DEBUG: time: 1614354401.5396798, endtime: 1614354701.539677
DEBUG: [Command returned '0' after 0.44 seconds]
DEBUG: Command: pidof syslogd
Output:  517

DEBUG: [Running]$ ssh -l root -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: time: 1614354401.5486262, endtime: 1614354701.5409064
DEBUG: Partial data from SSH call: 520

DEBUG: time: 1614354402.0264556, endtime: 1614354702.0264528
DEBUG: [Command returned '0' after 0.49 seconds]
DEBUG: Command: pidof klogd
Output:  520

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
Started HTTPService on 0.0.0.0:34765
DEBUG: [Running]$ ssh -l root -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:34765/ >> opkg.conf
DEBUG: time: 1614354402.0512307, endtime: 1614354702.043631
DEBUG: [Command returned '0' after 0.42 seconds]
DEBUG: Command: cd /etc/opkg/; echo src/gz all http://192.168.7.1:34765/ >> opkg.conf
Output:  

DEBUG: [Running]$ ssh -l root -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: time: 1614354402.469402, endtime: 1614355902.4617236
DEBUG: Partial data from SSH call: Downloading http://10.0.0.10:8000/all/Packages.gz.
Updated source 'uri-all-0'.
Downloading http://10.0.0.10:8000/armv7vet2hf-neon/Packages.gz.
Updated source 'uri-armv7vet2hf-neon-0'.
Downloading http://10.0.0.10:8000/qemuarm/Packages.gz.
Updated source 'uri-qemuarm-0'.
Downloading http://192.168.7.1:34765//Packages.gz.
Updated source 'all'.

DEBUG: time: 1614354404.9088945, endtime: 1614355904.9088912
DEBUG: [Command returned '0' after 2.45 seconds]
DEBUG: Command: opkg update
Output:  Downloading http://10.0.0.10:8000/all/Packages.gz.
Updated source 'uri-all-0'.
Downloading http://10.0.0.10:8000/armv7vet2hf-neon/Packages.gz.
Updated source 'uri-armv7vet2hf-neon-0'.
Downloading http://10.0.0.10:8000/qemuarm/Packages.gz.
Updated source 'uri-qemuarm-0'.
Downloading http://192.168.7.1:34765//Packages.gz.
Updated source 'all'.

DEBUG: [Running]$ ssh -l root -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: time: 1614354404.919044, endtime: 1614355904.9102783
DEBUG: Partial data from SSH call: No packages installed or removed.

DEBUG: time: 1614354405.77074, endtime: 1614355905.770737
DEBUG: [Command returned '0' after 0.86 seconds]
DEBUG: Command: opkg remove run-postinsts-dev
Output:  No packages installed or removed.

DEBUG: [Running]$ ssh -l root -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: time: 1614354405.7806687, endtime: 1614355905.7720761
DEBUG: time: 1614354410.7863865, endtime: 1614355905.7720761
DEBUG: Partial data from SSH call: Installing update-rc.d-dev (0.8) on root
Downloading http://192.168.7.1:34765//update-rc.d-dev_0.8-r0.3_all.ipk.
Installing initscripts-dev (1.0) on root
Downloading http://10.0.0.10:8000/armv7vet2hf-neon/initscripts-dev_1.0-r155.0_armv7vet2hf-neon.ipk.
Installing run-postinsts-dev (1.0) on root
Downloading http://192.168.7.1:34765//run-postinsts-dev_1.0-r10.3_all.ipk.
Configuring update-rc.d-dev.
Configuring initscripts-dev.
Configuring run-postinsts-dev.

DEBUG: time: 1614354414.3251245, endtime: 1614355914.3251216
DEBUG: [Command returned '0' after 8.55 seconds]
DEBUG: Command: opkg install run-postinsts-dev
Output:  Installing update-rc.d-dev (0.8) on root
Downloading http://192.168.7.1:34765//update-rc.d-dev_0.8-r0.3_all.ipk.
Installing initscripts-dev (1.0) on root
Downloading http://10.0.0.10:8000/armv7vet2hf-neon/initscripts-dev_1.0-r155.0_armv7vet2hf-neon.ipk.
Installing run-postinsts-dev (1.0) on root
Downloading http://192.168.7.1:34765//run-postinsts-dev_1.0-r10.3_all.ipk.
Configuring update-rc.d-dev.
Configuring initscripts-dev.
Configuring run-postinsts-dev.

DEBUG: [Running]$ ssh -l root -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: time: 1614354414.3349783, endtime: 1614354714.3264742
DEBUG: [Command returned '0' after 0.52 seconds]
DEBUG: Command: cd /etc/opkg/; sed -i "/^src/d" opkg.conf
Output:  

Stopped HTTPService on 0.0.0.0:34765
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
DEBUG: [Running]$ ssh -l root -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR 192.168.7.2 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; login --help
DEBUG: time: 1614354414.8638306, endtime: 1614354714.8564894
DEBUG: Partial data from SSH call: Usage: login [-p] [name]
       login [-p] [-h host] [-f name]
       login [-p] -r host

DEBUG: time: 1614354415.3206682, endtime: 1614354715.3206656
DEBUG: [Command returned '1' after 0.46 seconds]
DEBUG: Command: login --help
Output:  Usage: login [-p] [name]
       login [-p] [-h host] [-f name]
       login [-p] -r host

DEBUG: [Running]$ ssh -l root -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR 192.168.7.2 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; passwd --help
DEBUG: time: 1614354415.32983, endtime: 1614354715.3219578
DEBUG: Partial data from SSH call: Usage: passwd [options] [LOGIN]

Options:
  -a, --all                     report password status on all accounts
  -d, --delete                  delete the password for the named account
  -e, --expire                  force expire the password for the named account
  -h, --help                    display this help message and exit
  -k, --keep-tokens             change password only if expired
  -i, --inactive INACTIVE       set password inactive after expiration
                                to INACTIVE
  -l, --lock                    lock the password of the named account
  -n, --mindays MIN_DAYS        set minimum number of days before password
                                change to MIN_DAYS
  -q, --quiet                   quiet mode
  -r, --repository REPOSITORY   change password in REPOSITORY repository
  -R, --root CHROOT_DIR         directory to chroot into
  -S, --status                  report password status on the named account
  -u, --unlock                  unlock the password of the named account
  -w, --warndays WARN_DAYS      set expiration warning days to WARN_DAYS
  -x, --maxdays MAX_DAYS        set maximum number of days before password
                                change to MAX_DAYS


DEBUG: time: 1614354415.819354, endtime: 1614354715.8193512
DEBUG: [Command returned '0' after 0.50 seconds]
DEBUG: Command: passwd --help
Output:  Usage: passwd [options] [LOGIN]

Options:
  -a, --all                     report password status on all accounts
  -d, --delete                  delete the password for the named account
  -e, --expire                  force expire the password for the named account
  -h, --help                    display this help message and exit
  -k, --keep-tokens             change password only if expired
  -i, --inactive INACTIVE       set password inactive after expiration
                                to INACTIVE
  -l, --lock                    lock the password of the named account
  -n, --mindays MIN_DAYS        set minimum number of days before password
                                change to MIN_DAYS
  -q, --quiet                   quiet mode
  -r, --repository REPOSITORY   change password in REPOSITORY repository
  -R, --root CHROOT_DIR         directory to chroot into
  -S, --status                  report password status on the named account
  -u, --unlock                  unlock the password of the named account
  -w, --warndays WARN_DAYS      set expiration warning days to WARN_DAYS
  -x, --maxdays MAX_DAYS        set maximum number of days before password
                                change to MAX_DAYS

DEBUG: [Running]$ ssh -l root -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR 192.168.7.2 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; su --help
DEBUG: time: 1614354415.8287966, endtime: 1614354715.820615
DEBUG: Partial data from SSH call: Usage: su [options] [-] [username [args]]

Options:
  -c, --command COMMAND         pass COMMAND to the invoked shell
  -h, --help                    display this help message and exit
  -, -l, --login                make the shell a login shell
  -m, -p,
  --preserve-environment        do not reset environment variables, and
                                keep the same shell
  -s, --shell SHELL             use SHELL instead of the default in passwd

If no username is given, assume root.

DEBUG: time: 1614354416.3164525, endtime: 1614354716.3164499
DEBUG: [Command returned '0' after 0.50 seconds]
DEBUG: Command: su --help
Output:  Usage: su [options] [-] [username [args]]

Options:
  -c, --command COMMAND         pass COMMAND to the invoked shell
  -h, --help                    display this help message and exit
  -, -l, --login                make the shell a login shell
  -m, -p,
  --preserve-environment        do not reset environment variables, and
                                keep the same shell
  -s, --shell SHELL             use SHELL instead of the default in passwd

If no username is given, assume root.

DEBUG: [Running]$ ssh -l root -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR 192.168.7.2 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; useradd --help
DEBUG: time: 1614354416.325485, endtime: 1614354716.3177354
DEBUG: Partial data from SSH call: Usage: useradd [options] LOGIN
       useradd -D
       useradd -D [options]

Options:
      --badnames                do not check for bad names
  -b, --base-dir BASE_DIR       base directory for the home directory of the
                                new account
      --btrfs-subvolume-home    use BTRFS subvolume for home directory
  -c, --comment COMMENT         GECOS field of the new account
  -d, --home-dir HOME_DIR       home directory of the new account
  -D, --defaults                print or change default useradd configuration
  -e, --expiredate EXPIRE_DATE  expiration date of the new account
  -f, --inactive INACTIVE       password inactivity period of the new account
  -g, --gid GROUP               name or ID of the primary group of the new
                                account
  -G, --groups GROUPS           list of supplementary groups of the new
                                account
  -h, --help                    display this help message and exit
  -k, --skel SKEL_DIR           use this alternative skeleton directory
  -K, --key KEY=VALUE           override /etc/login.defs defaults
  -l, --no-log-init             do not add the user to the lastlog and
                                faillog databases
  -m, --create-home             create the user's home directory
  -M, --no-create-home          do not create the user's home directory
  -N, --no-user-group           do not create a group with the same name as
                                the user
  -o, --non-unique              allow to create users with duplicate
                                (non-unique) UID
  -p, --password PASSWORD       encrypted password of the new account
  -r, --system                  create a system account
  -R, --root CHROOT_DIR         directory to chroot into
  -P, --prefix PREFIX_DIR       prefix directory where are located the /etc/* files
  -s, --shell SHELL             login shell of the new account
  -u, --uid UID                 user ID of the new account
  -U, --user-group              create a group with the same name as the user


DEBUG: time: 1614354416.8528209, endtime: 1614354716.8528183
DEBUG: [Command returned '0' after 0.54 seconds]
DEBUG: Command: useradd --help
Output:  Usage: useradd [options] LOGIN
       useradd -D
       useradd -D [options]

Options:
      --badnames                do not check for bad names
  -b, --base-dir BASE_DIR       base directory for the home directory of the
                                new account
      --btrfs-subvolume-home    use BTRFS subvolume for home directory
  -c, --comment COMMENT         GECOS field of the new account
  -d, --home-dir HOME_DIR       home directory of the new account
  -D, --defaults                print or change default useradd configuration
  -e, --expiredate EXPIRE_DATE  expiration date of the new account
  -f, --inactive INACTIVE       password inactivity period of the new account
  -g, --gid GROUP               name or ID of the primary group of the new
                                account
  -G, --groups GROUPS           list of supplementary groups of the new
                                account
  -h, --help                    display this help message and exit
  -k, --skel SKEL_DIR           use this alternative skeleton directory
  -K, --key KEY=VALUE           override /etc/login.defs defaults
  -l, --no-log-init             do not add the user to the lastlog and
                                faillog databases
  -m, --create-home             create the user's home directory
  -M, --no-create-home          do not create the user's home directory
  -N, --no-user-group           do not create a group with the same name as
                                the user
  -o, --non-unique              allow to create users with duplicate
                                (non-unique) UID
  -p, --password PASSWORD       encrypted password of the new account
  -r, --system                  create a system account
  -R, --root CHROOT_DIR         directory to chroot into
  -P, --prefix PREFIX_DIR       prefix directory where are located the /etc/* files
  -s, --shell SHELL             login shell of the new account
  -u, --uid UID                 user ID of the new account
  -U, --user-group              create a group with the same name as the user

NOTE:  ... ok
DEBUG: [Running]$ ssh -l root -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: time: 1614354416.8638058, endtime: 1614354716.855365
DEBUG: [Command returned '1' after 0.42 seconds]
DEBUG: Command: which LSB_Test.sh
Output:  

NOTE: test_parselogs (parselogs.ParseLogsTest)
DEBUG: [Running]$ ssh -l root -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: time: 1614354417.2856352, endtime: 1614354717.2779527
DEBUG: [Command returned '0' after 0.44 seconds]
DEBUG: Command: dmesg > /tmp/dmesg_output.log
Output:  

DEBUG: [Running]$ ssh -l root -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: time: 1614354417.7274837, endtime: 1614354717.7194803
DEBUG: [Command returned '1' after 0.41 seconds]
DEBUG: Command: test -f /var/log/
Output:  

DEBUG: [Running]$ ssh -l root -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: time: 1614354418.1360312, endtime: 1614354718.1284478
DEBUG: [Command returned '0' after 0.41 seconds]
DEBUG: Command: test -d /var/log/
Output:  

DEBUG: [Running]$ ssh -l root -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: time: 1614354418.5458076, endtime: 1614354718.5378566
DEBUG: Partial data from SSH call: /var/log//postinstall.log

DEBUG: time: 1614354418.9513566, endtime: 1614354718.9513543
DEBUG: [Command returned '0' after 0.41 seconds]
DEBUG: Command: find /var/log//*.log -maxdepth 1 -type f
Output:  /var/log//postinstall.log

DEBUG: [Running]$ ssh -l root -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: time: 1614354418.9600308, endtime: 1614354718.9525874
DEBUG: [Command returned '0' after 0.39 seconds]
DEBUG: Command: test -f /var/log/dmesg
Output:  

DEBUG: [Running]$ ssh -l root -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: time: 1614354419.3516142, endtime: 1614354719.3434758
DEBUG: [Command returned '0' after 0.40 seconds]
DEBUG: Command: test -f /tmp/dmesg_output.log
Output:  

DEBUG: [Running]$ scp -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR root@192.168.7.2:/var/log//postinstall.log TOPDIR/build/tmp/work/qemuarm-yoe-linux-gnueabi/yoe-simple-image/1.0-r0/target_logs
DEBUG: Data from SSH call: 
DEBUG: [Command returned '0' after 0.53 seconds]
DEBUG: [Running]$ scp -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR root@192.168.7.2:/var/log/dmesg TOPDIR/build/tmp/work/qemuarm-yoe-linux-gnueabi/yoe-simple-image/1.0-r0/target_logs
DEBUG: Data from SSH call: 
DEBUG: [Command returned '0' after 0.58 seconds]
DEBUG: [Running]$ scp -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR root@192.168.7.2:/tmp/dmesg_output.log TOPDIR/build/tmp/work/qemuarm-yoe-linux-gnueabi/yoe-simple-image/1.0-r0/target_logs
DEBUG: Data from SSH call: 
DEBUG: [Command returned '0' after 0.54 seconds]
DEBUG: [Running]$ ssh -l root -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: time: 1614354421.5420084, endtime: 1614354721.5342703
DEBUG: Partial data from SSH call:  ARMv7 Processor rev 1 (v7l)

DEBUG: time: 1614354422.1060348, endtime: 1614354722.1060321
DEBUG: [Command returned '0' after 0.57 seconds]
DEBUG: Command: cat /proc/cpuinfo | grep "model name" | head -n1 |  awk 'BEGIN{FS=":"}{print $2}'
Output:   ARMv7 Processor rev 1 (v7l)

DEBUG: [Running]$ ssh -l root -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: time: 1614354422.1159837, endtime: 1614354722.1073017
DEBUG: [Command returned '0' after 0.58 seconds]
DEBUG: Command: cat /proc/cpuinfo | grep "cpu cores" | head -n1 | awk {'print $4'}
Output:  

DEBUG: [Running]$ ssh -l root -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: time: 1614354422.6971588, endtime: 1614354722.6883194
DEBUG: Partial data from SSH call: 2

DEBUG: time: 1614354423.1749606, endtime: 1614354723.1749578
DEBUG: [Command returned '0' after 0.49 seconds]
DEBUG: Command: cat /proc/cpuinfo | grep "processor" | wc -l
Output:  2

DEBUG: [Running]$ ssh -l root -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: time: 1614354423.1847975, endtime: 1614354723.1762245
DEBUG: Partial data from SSH call: armv7l

DEBUG: time: 1614354423.6363401, endtime: 1614354723.6363375
DEBUG: [Command returned '0' after 0.46 seconds]
DEBUG: Command: uname -m
Output:  armv7l

NOTE:  ... FAIL
Traceback (most recent call last):
  File "TOPDIR/sources/openembedded-core/meta/lib/oeqa/core/decorator/__init__.py", line 36, in wrapped_f
    return func(*args, **kwargs)
  File "TOPDIR/sources/openembedded-core/meta/lib/oeqa/runtime/cases/parselogs.py", line 380, in test_parselogs
    self.assertEqual(errcount, 0, msg=self.msg)
AssertionError: 6 != 0 : Log: TOPDIR/build/tmp/work/qemuarm-yoe-linux-gnueabi/yoe-simple-image/1.0-r0/target_logs/dmesg
-----------------------
Central error: [    4.496039] vmap allocation for size 1052672 failed: use vmalloc=<size> to increase size
***********************
[    4.487255]    neon            :   828 MB/sec
[    4.487484] xor: using function: 8regs (864 MB/sec)
[    4.487720] async_tx: api initialized (async)
[    4.488166] bounce: pool size: 64 pages
[    4.488872] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252)
[    4.489278] io scheduler mq-deadline registered
[    4.489554] io scheduler kyber registered
[    4.491771] pci-host-generic 3f000000.pcie: host bridge /pcie@10000000 ranges:
[    4.492744] pci-host-generic 3f000000.pcie:       IO 0x003eff0000..0x003effffff -> 0x0000000000
[    4.493561] pci-host-generic 3f000000.pcie:      MEM 0x0010000000..0x003efeffff -> 0x0010000000
[    4.496039] vmap allocation for size 1052672 failed: use vmalloc=<size> to increase size
[    4.496419] pci-host-generic 3f000000.pcie: ECAM ioremap failed
[    4.497771] pci-host-generic: probe of 3f000000.pcie failed with error -12
[    4.605656] brd: module loaded
[    4.626356] virtio_blk virtio1: [vda] 131072 512-byte logical blocks (67.1 MB/64.0 MiB)
[    4.627124] vda: detected capacity change from 0 to 67108864
[    4.659784] rtc-pl031 9010000.pl031: registered as rtc0
[    4.661144] rtc-pl031 9010000.pl031: setting system clock to 2021-02-26T15:45:50 UTC (1614354350)
[    4.664191] device-mapper: ioctl: 4.43.0-ioctl (2020-10-01) initialised: dm-devel@redhat.com
[    4.667882] usbcore: registered new interface driver usbhid
[    4.668117] usbhid: USB HID core driver

***********************
Central error: [    4.496419] pci-host-generic 3f000000.pcie: ECAM ioremap failed
***********************
[    4.487484] xor: using function: 8regs (864 MB/sec)
[    4.487720] async_tx: api initialized (async)
[    4.488166] bounce: pool size: 64 pages
[    4.488872] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252)
[    4.489278] io scheduler mq-deadline registered
[    4.489554] io scheduler kyber registered
[    4.491771] pci-host-generic 3f000000.pcie: host bridge /pcie@10000000 ranges:
[    4.492744] pci-host-generic 3f000000.pcie:       IO 0x003eff0000..0x003effffff -> 0x0000000000
[    4.493561] pci-host-generic 3f000000.pcie:      MEM 0x0010000000..0x003efeffff -> 0x0010000000
[    4.496039] vmap allocation for size 1052672 failed: use vmalloc=<size> to increase size
[    4.496419] pci-host-generic 3f000000.pcie: ECAM ioremap failed
[    4.497771] pci-host-generic: probe of 3f000000.pcie failed with error -12
[    4.605656] brd: module loaded
[    4.626356] virtio_blk virtio1: [vda] 131072 512-byte logical blocks (67.1 MB/64.0 MiB)
[    4.627124] vda: detected capacity change from 0 to 67108864
[    4.659784] rtc-pl031 9010000.pl031: registered as rtc0
[    4.661144] rtc-pl031 9010000.pl031: setting system clock to 2021-02-26T15:45:50 UTC (1614354350)
[    4.664191] device-mapper: ioctl: 4.43.0-ioctl (2020-10-01) initialised: dm-devel@redhat.com
[    4.667882] usbcore: registered new interface driver usbhid
[    4.668117] usbhid: USB HID core driver
[    4.669492] u32 classifier

***********************
Central error: [    4.497771] pci-host-generic: probe of 3f000000.pcie failed with error -12
***********************
[    4.487720] async_tx: api initialized (async)
[    4.488166] bounce: pool size: 64 pages
[    4.488872] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252)
[    4.489278] io scheduler mq-deadline registered
[    4.489554] io scheduler kyber registered
[    4.491771] pci-host-generic 3f000000.pcie: host bridge /pcie@10000000 ranges:
[    4.492744] pci-host-generic 3f000000.pcie:       IO 0x003eff0000..0x003effffff -> 0x0000000000
[    4.493561] pci-host-generic 3f000000.pcie:      MEM 0x0010000000..0x003efeffff -> 0x0010000000
[    4.496039] vmap allocation for size 1052672 failed: use vmalloc=<size> to increase size
[    4.496419] pci-host-generic 3f000000.pcie: ECAM ioremap failed
[    4.497771] pci-host-generic: probe of 3f000000.pcie failed with error -12
[    4.605656] brd: module loaded
[    4.626356] virtio_blk virtio1: [vda] 131072 512-byte logical blocks (67.1 MB/64.0 MiB)
[    4.627124] vda: detected capacity change from 0 to 67108864
[    4.659784] rtc-pl031 9010000.pl031: registered as rtc0
[    4.661144] rtc-pl031 9010000.pl031: setting system clock to 2021-02-26T15:45:50 UTC (1614354350)
[    4.664191] device-mapper: ioctl: 4.43.0-ioctl (2020-10-01) initialised: dm-devel@redhat.com
[    4.667882] usbcore: registered new interface driver usbhid
[    4.668117] usbhid: USB HID core driver
[    4.669492] u32 classifier
[    4.669851]     input device check on

***********************
Log: TOPDIR/build/tmp/work/qemuarm-yoe-linux-gnueabi/yoe-simple-image/1.0-r0/target_logs/dmesg_output.log
-----------------------
Central error: [    4.496039] vmap allocation for size 1052672 failed: use vmalloc=<size> to increase size
***********************
[    4.487255]    neon            :   828 MB/sec
[    4.487484] xor: using function: 8regs (864 MB/sec)
[    4.487720] async_tx: api initialized (async)
[    4.488166] bounce: pool size: 64 pages
[    4.488872] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252)
[    4.489278] io scheduler mq-deadline registered
[    4.489554] io scheduler kyber registered
[    4.491771] pci-host-generic 3f000000.pcie: host bridge /pcie@10000000 ranges:
[    4.492744] pci-host-generic 3f000000.pcie:       IO 0x003eff0000..0x003effffff -> 0x0000000000
[    4.493561] pci-host-generic 3f000000.pcie:      MEM 0x0010000000..0x003efeffff -> 0x0010000000
[    4.496039] vmap allocation for size 1052672 failed: use vmalloc=<size> to increase size
[    4.496419] pci-host-generic 3f000000.pcie: ECAM ioremap failed
[    4.497771] pci-host-generic: probe of 3f000000.pcie failed with error -12
[    4.605656] brd: module loaded
[    4.626356] virtio_blk virtio1: [vda] 131072 512-byte logical blocks (67.1 MB/64.0 MiB)
[    4.627124] vda: detected capacity change from 0 to 67108864
[    4.659784] rtc-pl031 9010000.pl031: registered as rtc0
[    4.661144] rtc-pl031 9010000.pl031: setting system clock to 2021-02-26T15:45:50 UTC (1614354350)
[    4.664191] device-mapper: ioctl: 4.43.0-ioctl (2020-10-01) initialised: dm-devel@redhat.com
[    4.667882] usbcore: registered new interface driver usbhid
[    4.668117] usbhid: USB HID core driver

***********************
Central error: [    4.496419] pci-host-generic 3f000000.pcie: ECAM ioremap failed
***********************
[    4.487484] xor: using function: 8regs (864 MB/sec)
[    4.487720] async_tx: api initialized (async)
[    4.488166] bounce: pool size: 64 pages
[    4.488872] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252)
[    4.489278] io scheduler mq-deadline registered
[    4.489554] io scheduler kyber registered
[    4.491771] pci-host-generic 3f000000.pcie: host bridge /pcie@10000000 ranges:
[    4.492744] pci-host-generic 3f000000.pcie:       IO 0x003eff0000..0x003effffff -> 0x0000000000
[    4.493561] pci-host-generic 3f000000.pcie:      MEM 0x0010000000..0x003efeffff -> 0x0010000000
[    4.496039] vmap allocation for size 1052672 failed: use vmalloc=<size> to increase size
[    4.496419] pci-host-generic 3f000000.pcie: ECAM ioremap failed
[    4.497771] pci-host-generic: probe of 3f000000.pcie failed with error -12
[    4.605656] brd: module loaded
[    4.626356] virtio_blk virtio1: [vda] 131072 512-byte logical blocks (67.1 MB/64.0 MiB)
[    4.627124] vda: detected capacity change from 0 to 67108864
[    4.659784] rtc-pl031 9010000.pl031: registered as rtc0
[    4.661144] rtc-pl031 9010000.pl031: setting system clock to 2021-02-26T15:45:50 UTC (1614354350)
[    4.664191] device-mapper: ioctl: 4.43.0-ioctl (2020-10-01) initialised: dm-devel@redhat.com
[    4.667882] usbcore: registered new interface driver usbhid
[    4.668117] usbhid: USB HID core driver
[    4.669492] u32 classifier

***********************
Central error: [    4.497771] pci-host-generic: probe of 3f000000.pcie failed with error -12
***********************
[    4.487720] async_tx: api initialized (async)
[    4.488166] bounce: pool size: 64 pages
[    4.488872] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252)
[    4.489278] io scheduler mq-deadline registered
[    4.489554] io scheduler kyber registered
[    4.491771] pci-host-generic 3f000000.pcie: host bridge /pcie@10000000 ranges:
[    4.492744] pci-host-generic 3f000000.pcie:       IO 0x003eff0000..0x003effffff -> 0x0000000000
[    4.493561] pci-host-generic 3f000000.pcie:      MEM 0x0010000000..0x003efeffff -> 0x0010000000
[    4.496039] vmap allocation for size 1052672 failed: use vmalloc=<size> to increase size
[    4.496419] pci-host-generic 3f000000.pcie: ECAM ioremap failed
[    4.497771] pci-host-generic: probe of 3f000000.pcie failed with error -12
[    4.605656] brd: module loaded
[    4.626356] virtio_blk virtio1: [vda] 131072 512-byte logical blocks (67.1 MB/64.0 MiB)
[    4.627124] vda: detected capacity change from 0 to 67108864
[    4.659784] rtc-pl031 9010000.pl031: registered as rtc0
[    4.661144] rtc-pl031 9010000.pl031: setting system clock to 2021-02-26T15:45:50 UTC (1614354350)
[    4.664191] device-mapper: ioctl: 4.43.0-ioctl (2020-10-01) initialised: dm-devel@redhat.com
[    4.667882] usbcore: registered new interface driver usbhid
[    4.668117] usbhid: USB HID core driver
[    4.669492] u32 classifier
[    4.669851]     input device check on

***********************
6 errors found in logs.

Stdout:
Machine information: 
*******************************
Machine name: qemuarm
CPU:  ARMv7 Processor rev 1 (v7l)
Arch: armv7l
Physical cores: 
Logical cores: 2
*******************************


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
NOTE:  ... skipped 'Test requires python3-core to be installed'
Test requires python3-core to be installed
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_scp_file (scp.ScpTest)
DEBUG: Checking if at least one of dropbear, openssh-scp is installed
DEBUG: [Running]$ scp -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR /tmp/tmp33crhaiz root@192.168.7.2:/tmp/test_scp_file
DEBUG: Data from SSH call: 
DEBUG: [Command returned '0' after 0.90 seconds]
DEBUG: [Running]$ ssh -l root -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR 192.168.7.2 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; ls -la /tmp/test_scp_file
DEBUG: time: 1614354424.5582497, endtime: 1614354724.5517826
DEBUG: Partial data from SSH call: -rw-------    1 root     root       4194304 Feb 26 15:47 /tmp/test_scp_file

DEBUG: time: 1614354424.9451828, endtime: 1614354724.9451802
DEBUG: [Command returned '0' after 0.39 seconds]
DEBUG: Command: ls -la /tmp/test_scp_file
Output:  -rw-------    1 root     root       4194304 Feb 26 15:47 /tmp/test_scp_file

DEBUG: [Running]$ ssh -l root -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o LogLevel=ERROR 192.168.7.2 export PATH=/usr/sbin:/sbin:/usr/bin:/bin; rm /tmp/test_scp_file
DEBUG: time: 1614354424.9542894, endtime: 1614354724.94643
DEBUG: [Command returned '0' after 0.39 seconds]
DEBUG: Command: rm /tmp/test_scp_file
Output:  

NOTE:  ... ok
NOTE: test_stap (stap.StapTest)
DEBUG: Checking if at least one of systemtap is installed
NOTE:  ... skipped 'Test requires systemtap to be installed'
Test requires systemtap 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
DEBUG: [Running]$ ssh -l root -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.log
DEBUG: time: 1614354425.3600237, endtime: 1614354725.3522232
DEBUG: Partial data from SSH call: rm: can't remove '/tmp/weston.log': No such file or directory

DEBUG: time: 1614354425.7540364, endtime: 1614354725.7540336
DEBUG: [Command returned '1' after 0.40 seconds]
DEBUG: Command: rm /tmp/weston.log
Output:  rm: can't remove '/tmp/weston.log': No such file or directory

NOTE: test_xorg_running (xorg.XorgTest)
DEBUG: Checking if at least one of xserver-nodm-init is installed
NOTE:  ... skipped 'Test requires xserver-nodm-init to be installed'
Test requires xserver-nodm-init to be installed
NOTE: ======================================================================
NOTE: FAIL: test_parselogs (parselogs.ParseLogsTest)
NOTE: ----------------------------------------------------------------------
NOTE: Traceback (most recent call last):
  File "TOPDIR/sources/openembedded-core/meta/lib/oeqa/core/decorator/__init__.py", line 36, in wrapped_f
    return func(*args, **kwargs)
  File "TOPDIR/sources/openembedded-core/meta/lib/oeqa/runtime/cases/parselogs.py", line 380, in test_parselogs
    self.assertEqual(errcount, 0, msg=self.msg)
AssertionError: 6 != 0 : Log: TOPDIR/build/tmp/work/qemuarm-yoe-linux-gnueabi/yoe-simple-image/1.0-r0/target_logs/dmesg
-----------------------
Central error: [    4.496039] vmap allocation for size 1052672 failed: use vmalloc=<size> to increase size
***********************
[    4.487255]    neon            :   828 MB/sec
[    4.487484] xor: using function: 8regs (864 MB/sec)
[    4.487720] async_tx: api initialized (async)
[    4.488166] bounce: pool size: 64 pages
[    4.488872] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252)
[    4.489278] io scheduler mq-deadline registered
[    4.489554] io scheduler kyber registered
[    4.491771] pci-host-generic 3f000000.pcie: host bridge /pcie@10000000 ranges:
[    4.492744] pci-host-generic 3f000000.pcie:       IO 0x003eff0000..0x003effffff -> 0x0000000000
[    4.493561] pci-host-generic 3f000000.pcie:      MEM 0x0010000000..0x003efeffff -> 0x0010000000
[    4.496039] vmap allocation for size 1052672 failed: use vmalloc=<size> to increase size
[    4.496419] pci-host-generic 3f000000.pcie: ECAM ioremap failed
[    4.497771] pci-host-generic: probe of 3f000000.pcie failed with error -12
[    4.605656] brd: module loaded
[    4.626356] virtio_blk virtio1: [vda] 131072 512-byte logical blocks (67.1 MB/64.0 MiB)
[    4.627124] vda: detected capacity change from 0 to 67108864
[    4.659784] rtc-pl031 9010000.pl031: registered as rtc0
[    4.661144] rtc-pl031 9010000.pl031: setting system clock to 2021-02-26T15:45:50 UTC (1614354350)
[    4.664191] device-mapper: ioctl: 4.43.0-ioctl (2020-10-01) initialised: dm-devel@redhat.com
[    4.667882] usbcore: registered new interface driver usbhid
[    4.668117] usbhid: USB HID core driver

***********************
Central error: [    4.496419] pci-host-generic 3f000000.pcie: ECAM ioremap failed
***********************
[    4.487484] xor: using function: 8regs (864 MB/sec)
[    4.487720] async_tx: api initialized (async)
[    4.488166] bounce: pool size: 64 pages
[    4.488872] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252)
[    4.489278] io scheduler mq-deadline registered
[    4.489554] io scheduler kyber registered
[    4.491771] pci-host-generic 3f000000.pcie: host bridge /pcie@10000000 ranges:
[    4.492744] pci-host-generic 3f000000.pcie:       IO 0x003eff0000..0x003effffff -> 0x0000000000
[    4.493561] pci-host-generic 3f000000.pcie:      MEM 0x0010000000..0x003efeffff -> 0x0010000000
[    4.496039] vmap allocation for size 1052672 failed: use vmalloc=<size> to increase size
[    4.496419] pci-host-generic 3f000000.pcie: ECAM ioremap failed
[    4.497771] pci-host-generic: probe of 3f000000.pcie failed with error -12
[    4.605656] brd: module loaded
[    4.626356] virtio_blk virtio1: [vda] 131072 512-byte logical blocks (67.1 MB/64.0 MiB)
[    4.627124] vda: detected capacity change from 0 to 67108864
[    4.659784] rtc-pl031 9010000.pl031: registered as rtc0
[    4.661144] rtc-pl031 9010000.pl031: setting system clock to 2021-02-26T15:45:50 UTC (1614354350)
[    4.664191] device-mapper: ioctl: 4.43.0-ioctl (2020-10-01) initialised: dm-devel@redhat.com
[    4.667882] usbcore: registered new interface driver usbhid
[    4.668117] usbhid: USB HID core driver
[    4.669492] u32 classifier

***********************
Central error: [    4.497771] pci-host-generic: probe of 3f000000.pcie failed with error -12
***********************
[    4.487720] async_tx: api initialized (async)
[    4.488166] bounce: pool size: 64 pages
[    4.488872] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252)
[    4.489278] io scheduler mq-deadline registered
[    4.489554] io scheduler kyber registered
[    4.491771] pci-host-generic 3f000000.pcie: host bridge /pcie@10000000 ranges:
[    4.492744] pci-host-generic 3f000000.pcie:       IO 0x003eff0000..0x003effffff -> 0x0000000000
[    4.493561] pci-host-generic 3f000000.pcie:      MEM 0x0010000000..0x003efeffff -> 0x0010000000
[    4.496039] vmap allocation for size 1052672 failed: use vmalloc=<size> to increase size
[    4.496419] pci-host-generic 3f000000.pcie: ECAM ioremap failed
[    4.497771] pci-host-generic: probe of 3f000000.pcie failed with error -12
[    4.605656] brd: module loaded
[    4.626356] virtio_blk virtio1: [vda] 131072 512-byte logical blocks (67.1 MB/64.0 MiB)
[    4.627124] vda: detected capacity change from 0 to 67108864
[    4.659784] rtc-pl031 9010000.pl031: registered as rtc0
[    4.661144] rtc-pl031 9010000.pl031: setting system clock to 2021-02-26T15:45:50 UTC (1614354350)
[    4.664191] device-mapper: ioctl: 4.43.0-ioctl (2020-10-01) initialised: dm-devel@redhat.com
[    4.667882] usbcore: registered new interface driver usbhid
[    4.668117] usbhid: USB HID core driver
[    4.669492] u32 classifier
[    4.669851]     input device check on

***********************
Log: TOPDIR/build/tmp/work/qemuarm-yoe-linux-gnueabi/yoe-simple-image/1.0-r0/target_logs/dmesg_output.log
-----------------------
Central error: [    4.496039] vmap allocation for size 1052672 failed: use vmalloc=<size> to increase size
***********************
[    4.487255]    neon            :   828 MB/sec
[    4.487484] xor: using function: 8regs (864 MB/sec)
[    4.487720] async_tx: api initialized (async)
[    4.488166] bounce: pool size: 64 pages
[    4.488872] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252)
[    4.489278] io scheduler mq-deadline registered
[    4.489554] io scheduler kyber registered
[    4.491771] pci-host-generic 3f000000.pcie: host bridge /pcie@10000000 ranges:
[    4.492744] pci-host-generic 3f000000.pcie:       IO 0x003eff0000..0x003effffff -> 0x0000000000
[    4.493561] pci-host-generic 3f000000.pcie:      MEM 0x0010000000..0x003efeffff -> 0x0010000000
[    4.496039] vmap allocation for size 1052672 failed: use vmalloc=<size> to increase size
[    4.496419] pci-host-generic 3f000000.pcie: ECAM ioremap failed
[    4.497771] pci-host-generic: probe of 3f000000.pcie failed with error -12
[    4.605656] brd: module loaded
[    4.626356] virtio_blk virtio1: [vda] 131072 512-byte logical blocks (67.1 MB/64.0 MiB)
[    4.627124] vda: detected capacity change from 0 to 67108864
[    4.659784] rtc-pl031 9010000.pl031: registered as rtc0
[    4.661144] rtc-pl031 9010000.pl031: setting system clock to 2021-02-26T15:45:50 UTC (1614354350)
[    4.664191] device-mapper: ioctl: 4.43.0-ioctl (2020-10-01) initialised: dm-devel@redhat.com
[    4.667882] usbcore: registered new interface driver usbhid
[    4.668117] usbhid: USB HID core driver

***********************
Central error: [    4.496419] pci-host-generic 3f000000.pcie: ECAM ioremap failed
***********************
[    4.487484] xor: using function: 8regs (864 MB/sec)
[    4.487720] async_tx: api initialized (async)
[    4.488166] bounce: pool size: 64 pages
[    4.488872] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252)
[    4.489278] io scheduler mq-deadline registered
[    4.489554] io scheduler kyber registered
[    4.491771] pci-host-generic 3f000000.pcie: host bridge /pcie@10000000 ranges:
[    4.492744] pci-host-generic 3f000000.pcie:       IO 0x003eff0000..0x003effffff -> 0x0000000000
[    4.493561] pci-host-generic 3f000000.pcie:      MEM 0x0010000000..0x003efeffff -> 0x0010000000
[    4.496039] vmap allocation for size 1052672 failed: use vmalloc=<size> to increase size
[    4.496419] pci-host-generic 3f000000.pcie: ECAM ioremap failed
[    4.497771] pci-host-generic: probe of 3f000000.pcie failed with error -12
[    4.605656] brd: module loaded
[    4.626356] virtio_blk virtio1: [vda] 131072 512-byte logical blocks (67.1 MB/64.0 MiB)
[    4.627124] vda: detected capacity change from 0 to 67108864
[    4.659784] rtc-pl031 9010000.pl031: registered as rtc0
[    4.661144] rtc-pl031 9010000.pl031: setting system clock to 2021-02-26T15:45:50 UTC (1614354350)
[    4.664191] device-mapper: ioctl: 4.43.0-ioctl (2020-10-01) initialised: dm-devel@redhat.com
[    4.667882] usbcore: registered new interface driver usbhid
[    4.668117] usbhid: USB HID core driver
[    4.669492] u32 classifier

***********************
Central error: [    4.497771] pci-host-generic: probe of 3f000000.pcie failed with error -12
***********************
[    4.487720] async_tx: api initialized (async)
[    4.488166] bounce: pool size: 64 pages
[    4.488872] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252)
[    4.489278] io scheduler mq-deadline registered
[    4.489554] io scheduler kyber registered
[    4.491771] pci-host-generic 3f000000.pcie: host bridge /pcie@10000000 ranges:
[    4.492744] pci-host-generic 3f000000.pcie:       IO 0x003eff0000..0x003effffff -> 0x0000000000
[    4.493561] pci-host-generic 3f000000.pcie:      MEM 0x0010000000..0x003efeffff -> 0x0010000000
[    4.496039] vmap allocation for size 1052672 failed: use vmalloc=<size> to increase size
[    4.496419] pci-host-generic 3f000000.pcie: ECAM ioremap failed
[    4.497771] pci-host-generic: probe of 3f000000.pcie failed with error -12
[    4.605656] brd: module loaded
[    4.626356] virtio_blk virtio1: [vda] 131072 512-byte logical blocks (67.1 MB/64.0 MiB)
[    4.627124] vda: detected capacity change from 0 to 67108864
[    4.659784] rtc-pl031 9010000.pl031: registered as rtc0
[    4.661144] rtc-pl031 9010000.pl031: setting system clock to 2021-02-26T15:45:50 UTC (1614354350)
[    4.664191] device-mapper: ioctl: 4.43.0-ioctl (2020-10-01) initialised: dm-devel@redhat.com
[    4.667882] usbcore: registered new interface driver usbhid
[    4.668117] usbhid: USB HID core driver
[    4.669492] u32 classifier
[    4.669851]     input device check on

***********************
6 errors found in logs.

Stdout:
Machine information: 
*******************************
Machine name: qemuarm
CPU:  ARMv7 Processor rev 1 (v7l)
Arch: armv7l
Physical cores: 
Logical cores: 2
*******************************


NOTE: ----------------------------------------------------------------------
NOTE: Ran 66 tests in 48.375s
NOTE: FAILED
NOTE:  (failures=1, skipped=54)
DEBUG: Stopping logging thread
DEBUG: Stop event received
DEBUG: Tearing down logging thread
DEBUG: Sending SIGTERM to runqemu
RESULTS:
RESULTS - date.DateTest.test_date: PASSED (2.01s)
RESULTS - df.DfTest.test_df: PASSED (0.50s)
RESULTS - oe_syslog.SyslogTest.test_syslog_running: PASSED (0.58s)
RESULTS - oe_syslog.SyslogTestConfig.test_syslog_logger: PASSED (2.05s)
RESULTS - oe_syslog.SyslogTestConfig.test_syslog_restart: PASSED (5.44s)
RESULTS - oe_syslog.SyslogTestConfig.test_syslog_startup_config: PASSED (11.33s)
RESULTS - opkg.OpkgRepoTest.test_opkg_install_from_repo: PASSED (12.83s)
RESULTS - pam.PamBasicTest.test_pam: PASSED (2.00s)
RESULTS - ping.PingTest.test_ping: PASSED (0.09s)
RESULTS - scp.ScpTest.test_scp_file: PASSED (1.69s)
RESULTS - ssh.SSHTest.test_ssh: PASSED (1.16s)
RESULTS - apt.AptRepoTest.test_apt_install_from_repo: SKIPPED (0.00s)
RESULTS - buildcpio.BuildCpioTest.test_cpio: SKIPPED (0.00s)
RESULTS - buildgalculator.GalculatorTest.test_galculator: SKIPPED (0.00s)
RESULTS - buildlzip.BuildLzipTest.test_lzip: SKIPPED (0.00s)
RESULTS - connman.ConnmanTest.test_connmand_help: SKIPPED (0.00s)
RESULTS - connman.ConnmanTest.test_connmand_running: SKIPPED (0.00s)
RESULTS - dnf.DnfBasicTest.test_dnf_help: SKIPPED (0.00s)
RESULTS - dnf.DnfBasicTest.test_dnf_history: SKIPPED (0.00s)
RESULTS - dnf.DnfBasicTest.test_dnf_info: SKIPPED (0.00s)
RESULTS - dnf.DnfBasicTest.test_dnf_search: SKIPPED (0.00s)
RESULTS - dnf.DnfBasicTest.test_dnf_version: SKIPPED (0.00s)
RESULTS - dnf.DnfRepoTest.test_dnf_exclude: SKIPPED (0.00s)
RESULTS - dnf.DnfRepoTest.test_dnf_install: SKIPPED (0.00s)
RESULTS - dnf.DnfRepoTest.test_dnf_install_dependency: SKIPPED (0.00s)
RESULTS - dnf.DnfRepoTest.test_dnf_install_from_disk: SKIPPED (0.00s)
RESULTS - dnf.DnfRepoTest.test_dnf_install_from_http: SKIPPED (0.00s)
RESULTS - dnf.DnfRepoTest.test_dnf_installroot: SKIPPED (0.00s)
RESULTS - dnf.DnfRepoTest.test_dnf_installroot_usrmerge: SKIPPED (0.00s)
RESULTS - dnf.DnfRepoTest.test_dnf_makecache: SKIPPED (0.00s)
RESULTS - dnf.DnfRepoTest.test_dnf_reinstall: SKIPPED (0.00s)
RESULTS - dnf.DnfRepoTest.test_dnf_repoinfo: SKIPPED (0.00s)
RESULTS - gcc.GccCompileTest.test_gcc_compile: SKIPPED (0.00s)
RESULTS - gcc.GccCompileTest.test_gpp2_compile: SKIPPED (0.00s)
RESULTS - gcc.GccCompileTest.test_gpp_compile: SKIPPED (0.00s)
RESULTS - gcc.GccCompileTest.test_make: SKIPPED (0.00s)
RESULTS - gi.GObjectIntrospectionTest.test_python: SKIPPED (0.00s)
RESULTS - 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 - 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 - python.PythonTest.test_python3: SKIPPED (0.00s)
RESULTS - rpm.RpmBasicTest.test_rpm_help: SKIPPED (0.00s)
RESULTS - rpm.RpmBasicTest.test_rpm_query: SKIPPED (0.00s)
RESULTS - rpm.RpmBasicTest.test_rpm_query_nonroot: SKIPPED (0.00s)
RESULTS - rpm.RpmInstallRemoveTest.test_check_rpm_install_removal_log_file_size: SKIPPED (0.00s)
RESULTS - rpm.RpmInstallRemoveTest.test_rpm_install: SKIPPED (0.00s)
RESULTS - rpm.RpmInstallRemoveTest.test_rpm_remove: SKIPPED (0.00s)
RESULTS - stap.StapTest.test_stap: SKIPPED (0.00s)
RESULTS - systemd.SystemdBasicTests.test_systemd_basic: SKIPPED (0.00s)
RESULTS - systemd.SystemdBasicTests.test_systemd_failed: SKIPPED (0.00s)
RESULTS - systemd.SystemdBasicTests.test_systemd_list: SKIPPED (0.00s)
RESULTS - systemd.SystemdJournalTests.test_systemd_boot_time: SKIPPED (0.00s)
RESULTS - systemd.SystemdJournalTests.test_systemd_journal: SKIPPED (0.00s)
RESULTS - systemd.SystemdServiceTests.test_systemd_disable_enable: SKIPPED (0.00s)
RESULTS - systemd.SystemdServiceTests.test_systemd_disable_enable_ro: SKIPPED (0.00s)
RESULTS - systemd.SystemdServiceTests.test_systemd_status: SKIPPED (0.00s)
RESULTS - systemd.SystemdServiceTests.test_systemd_stop_start: SKIPPED (0.00s)
RESULTS - weston.WestonTest.test_wayland_info: SKIPPED (0.00s)
RESULTS - weston.WestonTest.test_weston_can_initialize_new_wayland_compositor: SKIPPED (0.00s)
RESULTS - weston.WestonTest.test_weston_running: SKIPPED (0.00s)
RESULTS - xorg.XorgTest.test_xorg_running: SKIPPED (0.00s)
RESULTS - parselogs.ParseLogsTest.test_parselogs: FAILED (6.36s)
SUMMARY:
yoe-simple-image () - Ran 66 tests in 48.377s
yoe-simple-image - FAIL - Required tests failed (successes=11, skipped=54, failures=1, errors=0)
ERROR: yoe-simple-image - FAILED - check the task log and the ssh log
DEBUG: Python function do_testimage finished

Error details

Submitted on:
26/02/21 09:47
Error type:
Recipe
Task:
do_testimage
Recipe:
yoe-simple-image
Recipe version:
1.0-r0
Machine:
qemuarm
Distro:
yoe
Build system:
x86_64-linux
Target system:
arm-yoe-linux-gnueabi
Host distro:
universal/arch
Branch:
yoe/mut
Commit:
1eb8801eedeef7c71ac8b7350ee15778e4bdb764
Build:
#117465
Submitter:
raj.khem@gmail.com
Similar errors:
0
Open a bug