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