DEBUG: Executing python function do_testimage
NOTE: rootfs file: /home/pokybuild/yocto-autobuilder/yocto-worker/nightly-arm64/build/build/tmp/work/qemuarm64-poky-linux/core-image-sato/1.0-r0/testimage/core-image-sato-qemuarm64-testimage.ext4
NOTE: Qemu log file: /home/pokybuild/yocto-autobuilder/yocto-worker/nightly-arm64/build/build/tmp/work/qemuarm64-poky-linux/core-image-sato/1.0-r0/testimage/qemu_boot_log.20160216131902
NOTE: SSH log file: /home/pokybuild/yocto-autobuilder/yocto-worker/nightly-arm64/build/build/tmp/work/qemuarm64-poky-linux/core-image-sato/1.0-r0/testimage/ssh_target_log.20160216131902
NOTE: Created listening socket for qemu serial console on: 127.0.0.1:57305
NOTE: Created listening socket for qemu serial console on: 127.0.0.1:47691
NOTE: runqemu started, pid is 13923
NOTE: waiting at most 60 seconds for qemu pid
NOTE: qemu started - qemu procces pid is 14142
NOTE: qemu cmdline used:
/home/pokybuild/yocto-autobuilder/yocto-worker/nightly-arm64/build/build/tmp/sysroots/x86_64-linux/usr/bin/qemu-system-aarch64-kernel/home/pokybuild/yocto-autobuilder/yocto-worker/nightly-arm64/build/build/tmp/deploy/images/qemuarm64/Image-qemuarm64.bin-netdevtap,id=net0,ifname=tap1,script=no,downscript=no-devicevirtio-net-device,netdev=net0-machinevirt-cpucortex-a57-driveid=disk0,file=/home/pokybuild/yocto-autobuilder/yocto-worker/nightly-arm64/build/build/tmp/work/qemuarm64-poky-linux/core-image-sato/1.0-r0/testimage/core-image-sato-qemuarm64-testimage.ext4,if=none,format=raw-devicevirtio-blk-device,drive=disk0-nographic-no-reboot-serialtcp:127.0.0.1:57305-m512-devicevirtio-serial-device-chardevsocket,id=virtcon,port=47691,host=127.0.0.1-devicevirtconsole,chardev=virtcon--appendroot=/dev/vda rw console=ttyAMA0,38400 mem=512M highres=off ip=192.168.7.4::192.168.7.3:255.255.255.0 rootfstype=ext4 console=tty1 console=ttyS0,115200n8 printk.time=1
NOTE: Target IP: 192.168.7.4
NOTE: Server IP: 192.168.7.3
NOTE: Starting logging thread
NOTE: Starting thread event loop
NOTE: Connection request received
NOTE: Setting connection established event
NOTE: Output from runqemu:
Please use simplified serial or kvm options instead
Continuing with the following parameters:
KERNEL: [/home/pokybuild/yocto-autobuilder/yocto-worker/nightly-arm64/build/build/tmp/deploy/images/qemuarm64/Image-qemuarm64.bin]
ROOTFS: [/home/pokybuild/yocto-autobuilder/yocto-worker/nightly-arm64/build/build/tmp/work/qemuarm64-poky-linux/core-image-sato/1.0-r0/testimage/core-image-sato-qemuarm64-testimage.ext4]
FSTYPE: [ext4]
stty: standard input: Invalid argument
Acquiring lockfile for tap0...
Acquiring lockfile for tap1...
Using preconfigured tap device 'tap1'
If this is not intended, touch /tmp/qemu-tap-locks/tap1.skip to make runqemu skip tap1.
-netdev tap,id=net0,ifname=tap1,script=no,downscript=no -device virtio-net-device,netdev=net0 -machine virt -cpu cortex-a57 -drive id=disk0,file=/home/pokybuild/yocto-autobuilder/yocto-worker/nightly-arm64/build/build/tmp/work/qemuarm64-poky-linux/core-image-sato/1.0-r0/testimage/core-image-sato-qemuarm64-testimage.ext4,if=none,format=raw -device virtio-blk-device,drive=disk0 -nographic -serial tcp:127.0.0.1:57305 -m 512 -device virtio-serial-device -chardev socket,id=virtcon,port=47691,host=127.0.0.1 -device virtconsole,chardev=virtcon
Running qemu-system-aarch64...
/home/pokybuild/yocto-autobuilder/yocto-worker/nightly-arm64/build/build/tmp/sysroots/x86_64-linux/usr/bin/qemu-system-aarch64 -kernel /home/pokybuild/yocto-autobuilder/yocto-worker/nightly-arm64/build/build/tmp/deploy/images/qemuarm64/Image-qemuarm64.bin -netdev tap,id=net0,ifname=tap1,script=no,downscript=no -device virtio-net-device,netdev=net0 -machine virt -cpu cortex-a57 -drive id=disk0,file=/home/pokybuild/yocto-autobuilder/yocto-worker/nightly-arm64/build/build/tmp/work/qemuarm64-poky-linux/core-image-sato/1.0-r0/testimage/core-image-sato-qemuarm64-testimage.ext4,if=none,format=raw -device virtio-blk-device,drive=disk0 -nographic -no-reboot -serial tcp:127.0.0.1:57305 -m 512 -device virtio-serial-device -chardev socket,id=virtcon,port=47691,host=127.0.0.1 -device virtconsole,chardev=virtcon --append "root=/dev/vda rw console=ttyAMA0,38400 mem=512M highres=off ip=192.168.7.4::192.168.7.3:255.255.255.0 rootfstype=ext4 console=tty1 console=ttyS0,115200n8 printk.time=1"
QEMU 2.5.0 monitor - type 'help' for more information
(qemu)
NOTE: Waiting at most 1500 seconds for login banner
NOTE: Connection from 127.0.0.1:41011
NOTE: Reached login banner
NOTE: Logged as root in serial console
NOTE: Test modules ['oeqa.runtime.ping', 'oeqa.runtime.ssh', 'oeqa.runtime.df', 'oeqa.runtime.connman', 'oeqa.runtime.syslog', 'oeqa.runtime.scp', 'oeqa.runtime.date', 'oeqa.runtime.parselogs', 'oeqa.runtime.smart', 'oeqa.runtime.rpm', 'oeqa.runtime.python']
NOTE: Found 42 tests
test_ping (oeqa.runtime.ping.PingTest) ... ok
test_ssh (oeqa.runtime.ssh.SshTest) ... ok
test_df (oeqa.runtime.df.DfTest) ... ok
test_connmand_help (oeqa.runtime.connman.ConnmanTest) ... ok
test_connmand_running (oeqa.runtime.connman.ConnmanTest) ... ok
test_syslog_running (oeqa.runtime.syslog.SyslogTest) ... ok
test_syslog_logger (oeqa.runtime.syslog.SyslogTestConfig) ... ok
test_syslog_restart (oeqa.runtime.syslog.SyslogTestConfig) ... ok
test_syslog_startup_config (oeqa.runtime.syslog.SyslogTestConfig) ... ok
test_scp_file (oeqa.runtime.scp.ScpTest) ... ok
test_date (oeqa.runtime.date.DateTest) ... ok
test_parselogs (oeqa.runtime.parselogs.ParseLogsTest) ... Machine information:
*******************************
Machine name: qemuarm64
CPU:
Arch: aarch64
Physical cores:
Logical cores: 1
*******************************
FAIL
test_parselogs dump data stored in /tmp/oe-saved-tests/201602161321_test_parselogs
test_smart_help (oeqa.runtime.smart.SmartBasicTest) ... ok
test_smart_info (oeqa.runtime.smart.SmartBasicTest) ... ok
test_smart_query (oeqa.runtime.smart.SmartBasicTest) ... ok
test_smart_search (oeqa.runtime.smart.SmartBasicTest) ... ok
test_smart_stats (oeqa.runtime.smart.SmartBasicTest) ... ok
test_smart_version (oeqa.runtime.smart.SmartBasicTest) ... ok
test_smart_add_rpmdir (oeqa.runtime.smart.SmartRepoTest) ... ok
test_smart_channel (oeqa.runtime.smart.SmartRepoTest) ... ok
test_smart_channel_add (oeqa.runtime.smart.SmartRepoTest) ... ok
test_smart_channel_help (oeqa.runtime.smart.SmartRepoTest) ... ok
test_smart_channel_list (oeqa.runtime.smart.SmartRepoTest) ... ok
test_smart_channel_rpmsys (oeqa.runtime.smart.SmartRepoTest) ... ok
test_smart_channel_show (oeqa.runtime.smart.SmartRepoTest) ... ok
test_smart_install (oeqa.runtime.smart.SmartRepoTest) ... ok
test_smart_install_dependency (oeqa.runtime.smart.SmartRepoTest) ... ok
test_smart_install_from_disk (oeqa.runtime.smart.SmartRepoTest) ... ok
test_smart_install_from_http (oeqa.runtime.smart.SmartRepoTest) ... ok
test_smart_local_dir (oeqa.runtime.smart.SmartRepoTest) ... ok
test_smart_reinstall (oeqa.runtime.smart.SmartRepoTest) ... ok
test_smart_remote_repo (oeqa.runtime.smart.SmartRepoTest) ... ok
test_smart_remove_package (oeqa.runtime.smart.SmartRepoTest) ... ok
test_rpm_help (oeqa.runtime.rpm.RpmBasicTest) ... ok
test_rpm_query (oeqa.runtime.rpm.RpmBasicTest) ... ok
test_check_rpm_install_removal_log_file_size (oeqa.runtime.rpm.RpmInstallRemoveTest) ... ok
test_rpm_install (oeqa.runtime.rpm.RpmInstallRemoveTest) ... ok
test_rpm_query_nonroot (oeqa.runtime.rpm.RpmInstallRemoveTest) ... ok
test_rpm_remove (oeqa.runtime.rpm.RpmInstallRemoveTest) ... ok
test_python_exists (oeqa.runtime.python.PythonTest) ... ok
test_python_stdout (oeqa.runtime.python.PythonTest) ... ok
test_python_testfile (oeqa.runtime.python.PythonTest) ... ok
======================================================================
FAIL: test_parselogs (oeqa.runtime.parselogs.ParseLogsTest)
----------------------------------------------------------------------
Traceback (most recent call last):
File "/home/pokybuild/yocto-autobuilder/yocto-worker/nightly-arm64/build/meta/lib/oeqa/utils/decorators.py", line 106, in wrapped_f
return func(*args, **kwargs)
File "/home/pokybuild/yocto-autobuilder/yocto-worker/nightly-arm64/build/meta/lib/oeqa/utils/decorators.py", line 94, in wrapped_f
return f(*args, **kwargs)
File "/home/pokybuild/yocto-autobuilder/yocto-worker/nightly-arm64/build/meta/lib/oeqa/runtime/parselogs.py", line 253, in test_parselogs
self.assertEqual(errcount, 0, msg=self.msg)
AssertionError: 2 != 0 : Log: dmesg_output.log
-----------------------
Central error: [ 1.999799] dmi: Firmware registration failed.
***********************
[ 1.578647] raid6: neonx1 xor() 171 MB/s
[ 1.648204] raid6: neonx2 gen() 337 MB/s
[ 1.717524] raid6: neonx2 xor() 136 MB/s
[ 1.787067] raid6: neonx4 gen() 349 MB/s
[ 1.856482] raid6: neonx4 xor() 186 MB/s
[ 1.926039] raid6: neonx8 gen() 346 MB/s
[ 1.995394] raid6: neonx8 xor() 184 MB/s
[ 1.995696] raid6: using algorithm int64x2 gen() 1059 MB/s
[ 1.996024] raid6: .... xor() 464 MB/s, rmw enabled
[ 1.996366] raid6: using intx1 recovery algorithm
[ 1.999799] dmi: Firmware registration failed.
[ 2.009581] clocksource: Switched to clocksource arch_sys_counter
[ 2.307852] NET: Registered protocol family 2
[ 2.314999] TCP established hash table entries: 4096 (order: 3, 32768 bytes)
[ 2.315551] TCP bind hash table entries: 4096 (order: 4, 65536 bytes)
[ 2.316073] TCP: Hash tables configured (established 4096 bind 4096)
[ 2.317909] UDP hash table entries: 256 (order: 1, 8192 bytes)
[ 2.318418] UDP-Lite hash table entries: 256 (order: 1, 8192 bytes)
[ 2.320718] NET: Registered protocol family 1
[ 2.331627] RPC: Registered named UNIX socket transport module.
[ 2.332004] RPC: Registered udp transport module.
***********************
Log: dmesg
-----------------------
Central error: [ 1.999799] dmi: Firmware registration failed.
***********************
[ 1.578647] raid6: neonx1 xor() 171 MB/s
[ 1.648204] raid6: neonx2 gen() 337 MB/s
[ 1.717524] raid6: neonx2 xor() 136 MB/s
[ 1.787067] raid6: neonx4 gen() 349 MB/s
[ 1.856482] raid6: neonx4 xor() 186 MB/s
[ 1.926039] raid6: neonx8 gen() 346 MB/s
[ 1.995394] raid6: neonx8 xor() 184 MB/s
[ 1.995696] raid6: using algorithm int64x2 gen() 1059 MB/s
[ 1.996024] raid6: .... xor() 464 MB/s, rmw enabled
[ 1.996366] raid6: using intx1 recovery algorithm
[ 1.999799] dmi: Firmware registration failed.
[ 2.009581] clocksource: Switched to clocksource arch_sys_counter
[ 2.307852] NET: Registered protocol family 2
[ 2.314999] TCP established hash table entries: 4096 (order: 3, 32768 bytes)
[ 2.315551] TCP bind hash table entries: 4096 (order: 4, 65536 bytes)
[ 2.316073] TCP: Hash tables configured (established 4096 bind 4096)
[ 2.317909] UDP hash table entries: 256 (order: 1, 8192 bytes)
[ 2.318418] UDP-Lite hash table entries: 256 (order: 1, 8192 bytes)
[ 2.320718] NET: Registered protocol family 1
[ 2.331627] RPC: Registered named UNIX socket transport module.
[ 2.332004] RPC: Registered udp transport module.
***********************
2 errors found in logs.
----------------------------------------------------------------------
Ran 42 tests in 1094.102s
FAILED (failures=1)
NOTE: Stopping logging thread
NOTE: Stop event received
NOTE: Tearing down logging thread
NOTE: Sending SIGTERM to runqemu
DEBUG: Python function do_testimage finished
ERROR: Function failed: core-image-sato - FAILED - check the task log and the ssh log