Bug 12763 - [Meta-Intel 2.5 M4 RC1] [test_parselogs] Error present in parselog for core-image-sato-sdk image on NUC7
Summary: [Meta-Intel 2.5 M4 RC1] [test_parselogs] Error present in parselog for core-i...
Status: RESOLVED FIXED
Alias: None
Product: BSPs
Classification: Build System, Metadata & Runtime
Component: bsps-meta-intel (show other bugs)
Version: 2.5.1
Hardware: x86 Multiple
: Medium normal
Target Milestone: 2.6
Assignee: Anuj Mittal
QA Contact:
URL:
Whiteboard:
: 12764 (view as bug list)
Depends on:
Blocks:
 
Reported: 2018-05-31 03:34 UTC by pankaj yadav
Modified: 2018-07-12 05:50 UTC (History)
3 users (show)

See Also:
OS type for building Yocto: ---
Type of Regression: ---
Verified:
Documentation change: No (bug/feature does not impact docs)


Attachments
log.do_testimage_NUC7_Error_ParseLog (22.25 KB, application/octet-stream)
2018-05-31 03:34 UTC, pankaj yadav
no flags Details
log.do_testimage (835.87 KB, text/plain)
2018-06-19 06:07 UTC, pravin
no flags Details

Note You need to log in before you can comment on or make changes to this bug.
Description pankaj yadav 2018-05-31 03:34:11 UTC
Created attachment 4316 [details]
log.do_testimage_NUC7_Error_ParseLog

DEBUG: time: 1527733545.0099678, endtime: 1527733845.009966
DEBUG: [Command returned '0' after 0.14 seconds]
DEBUG: Command: uname -m
Output:  x86_64

NOTE:  ... FAIL
NOTE: ======================================================================
NOTE: FAIL: test_parselogs (parselogs.ParseLogsTest)
NOTE: ----------------------------------------------------------------------
NOTE: Traceback (most recent call last):
  File "/data/pankajsx/sato_poky_regression/poky/meta/lib/oeqa/core/decorator/__init__.py", line 32, in wrapped_f
    return func(*args, **kwargs)
  File "/data/pankajsx/sato_poky_regression/poky/meta/lib/oeqa/core/decorator/__init__.py", line 32, in wrapped_f
    return func(*args, **kwargs)
  File "/data/pankajsx/sato_poky_regression/poky/meta/lib/oeqa/runtime/cases/parselogs.py", line 363, in test_parselogs
    self.assertEqual(errcount, 0, msg=self.msg)
AssertionError: 6 != 0 : Log: /data/pankajsx/sato_poky_regression/poky/build/tmp/work/intel_corei7_64-poky-linux/core-image-sato-sdk/1.0-r0/Xorg.0.log
-----------------------
Central error: [    12.362] (II) event5  - failed to create input device '/dev/input/event5'.
***********************
[    12.349] (II) event5  - (II) PixArt USB Optical Mouse: (II) device removed
[    12.355] (**) Option "config_info" "udev:/sys/devices/pci0000:00/0000:00:14.0/usb1/1-1/1-1:1.0/0003:04CA:0061.0009/input/input18/event5"
[    12.355] (II) XINPUT: Adding extended input device "PixArt USB Optical Mouse" (type: MOUSE, id 13)
[    12.355] (**) Option "AccelerationScheme" "none"
[    12.355] (**) PixArt USB Optical Mouse: (accel) selected scheme none/0
[    12.355] (**) PixArt USB Optical Mouse: (accel) acceleration factor: 2.000
[    12.355] (**) PixArt USB Optical Mouse: (accel) acceleration threshold: 4
[    12.362] (EE) xf86OpenSerial: Cannot open device /dev/input/event5
	No such device.
[    12.362] (II) event5: opening input device '/dev/input/event5' failed (No such device).
[    12.362] (II) event5  - failed to create input device '/dev/input/event5'.
[    12.362] [dix] couldn't enable device 13
[    12.362] (EE) Couldn't init device "PixArt USB Optical Mouse"
[    12.362] (II) UnloadModule: "libinput"
[    12.362] (II) config/udev: removing device Lite-On Technology Corp HP USB Slim Keyboard - Skylab
[    12.362] (II) UnloadModule: "libinput"
[    12.363] (II) config/udev: removing device Lite-On Technology Corp HP USB Slim Keyboard - Skylab
[    12.363] (II) event3  - (II) Lite-On Technology Corp HP USB Slim Keyboard - Skylab: (II) device removed
[    12.371] (II) UnloadModule: "libinput"
[    12.371] (II) config/udev: removing device Lite-On Technology Corp HP USB Slim Keyboard - Skylab
[    12.371] (II) event4  - (II) Lite-On Technology Corp HP USB Slim Keyboard - Skylab: (II) device removed

***********************
Central error: [    12.362] (II) event5: opening input device '/dev/input/event5' failed (No such device).
***********************
[    12.349] (II) event5  - (II) PixArt USB Optical Mouse: (II) device is a pointer
[    12.349] (II) event5  - (II) PixArt USB Optical Mouse: (II) device removed
[    12.355] (**) Option "config_info" "udev:/sys/devices/pci0000:00/0000:00:14.0/usb1/1-1/1-1:1.0/0003:04CA:0061.0009/input/input18/event5"
[    12.355] (II) XINPUT: Adding extended input device "PixArt USB Optical Mouse" (type: MOUSE, id 13)
[    12.355] (**) Option "AccelerationScheme" "none"
[    12.355] (**) PixArt USB Optical Mouse: (accel) selected scheme none/0
[    12.355] (**) PixArt USB Optical Mouse: (accel) acceleration factor: 2.000
[    12.355] (**) PixArt USB Optical Mouse: (accel) acceleration threshold: 4
[    12.362] (EE) xf86OpenSerial: Cannot open device /dev/input/event5
	No such device.
[    12.362] (II) event5: opening input device '/dev/input/event5' failed (No such device).
[    12.362] (II) event5  - failed to create input device '/dev/input/event5'.
[    12.362] [dix] couldn't enable device 13
[    12.362] (EE) Couldn't init device "PixArt USB Optical Mouse"
[    12.362] (II) UnloadModule: "libinput"
[    12.362] (II) config/udev: removing device Lite-On Technology Corp HP USB Slim Keyboard - Skylab
[    12.362] (II) UnloadModule: "libinput"
[    12.363] (II) config/udev: removing device Lite-On Technology Corp HP USB Slim Keyboard - Skylab
[    12.363] (II) event3  - (II) Lite-On Technology Corp HP USB Slim Keyboard - Skylab: (II) device removed
[    12.371] (II) UnloadModule: "libinput"
[    12.371] (II) config/udev: removing device Lite-On Technology Corp HP USB Slim Keyboard - Skylab

***********************
Central error: [    12.362] (EE) xf86OpenSerial: Cannot open device /dev/input/event5
***********************
[    12.294] (**) Option "_source" "server/udev"
[    12.349] (II) event5  - (II) PixArt USB Optical Mouse: (II) is tagged by udev as: Mouse
[    12.349] (II) event5  - (II) PixArt USB Optical Mouse: (II) device is a pointer
[    12.349] (II) event5  - (II) PixArt USB Optical Mouse: (II) device removed
[    12.355] (**) Option "config_info" "udev:/sys/devices/pci0000:00/0000:00:14.0/usb1/1-1/1-1:1.0/0003:04CA:0061.0009/input/input18/event5"
[    12.355] (II) XINPUT: Adding extended input device "PixArt USB Optical Mouse" (type: MOUSE, id 13)
[    12.355] (**) Option "AccelerationScheme" "none"
[    12.355] (**) PixArt USB Optical Mouse: (accel) selected scheme none/0
[    12.355] (**) PixArt USB Optical Mouse: (accel) acceleration factor: 2.000
[    12.355] (**) PixArt USB Optical Mouse: (accel) acceleration threshold: 4
[    12.362] (EE) xf86OpenSerial: Cannot open device /dev/input/event5
	No such device.
[    12.362] (II) event5: opening input device '/dev/input/event5' failed (No such device).
[    12.362] (II) event5  - failed to create input device '/dev/input/event5'.
[    12.362] [dix] couldn't enable device 13
[    12.362] (EE) Couldn't init device "PixArt USB Optical Mouse"
[    12.362] (II) UnloadModule: "libinput"
[    12.362] (II) config/udev: removing device Lite-On Technology Corp HP USB Slim Keyboard - Skylab
[    12.362] (II) UnloadModule: "libinput"
[    12.363] (II) config/udev: removing device Lite-On Technology Corp HP USB Slim Keyboard - Skylab
[    12.363] (II) event3  - (II) Lite-On Technology Corp HP USB Slim Keyboard - Skylab: (II) device removed

***********************
Log: /data/pankajsx/sato_poky_regression/poky/build/tmp/work/intel_corei7_64-poky-linux/core-image-sato-sdk/1.0-r0/dmesg_output.log
-----------------------
Central error: [    7.490691] udevd[446]: inotify_add_watch(6, /dev/loop0, 10) failed: No such file or directory
***********************
[    2.727289]  sdb:
[    2.728285] sd 1:0:0:0: [sdb] Attached SCSI removable disk
[    3.300769] urandom_read: 4 callbacks suppressed
[    3.300772] random: udevd: uninitialized urandom read (16 bytes read)
[    3.305963] random: udevd: uninitialized urandom read (16 bytes read)
[    3.306992] random: udevd: uninitialized urandom read (16 bytes read)
[    3.310661] EXT4-fs (loop0): couldn't mount as ext3 due to feature incompatibilities
[    3.311741] EXT4-fs (loop0): couldn't mount as ext2 due to feature incompatibilities
[    4.319070] random: crng init done
[    5.285838] EXT4-fs (loop0): mounted filesystem with ordered data mode. Opts: (null)
[    7.490691] udevd[446]: inotify_add_watch(6, /dev/loop0, 10) failed: No such file or directory
[    7.836388] udevd[487]: starting version 3.2.5
[    7.885252] udevd[488]: starting eudev-3.2.5
[    8.092043] EFI Variables Facility v0.08 2004-May-17
[    8.134115] mei_me 0000:00:16.0: enabling device (0000 -> 0002)
[    8.135228] usbcore: registered new interface driver btusb
[    8.138638] Bluetooth: hci0: Firmware revision 0.1 build 103 week 50 2016
[    8.178658] [drm] Found 64MB of eDRAM
[    8.179217] [drm] Memory usable by graphics device = 4096M
[    8.179523] checking generic (90000000 7e9000) vs hw (90000000 10000000)
[    8.179524] fb: switching to inteldrmfb from EFI VGA

***********************
Central error: [   11.665721] [drm:intel_pipe_update_end [i915]] *ERROR* Atomic update failure on pipe A (start=1310 end=1311) time 19686 us, min 1073, max 1079, scanline start 53, end 257
***********************
[   11.660952]  entry_SYSCALL_64_after_hwframe+0x3d/0xa2
[   11.661237] RIP: 0033:0x3a680e98f7
[   11.661404] RSP: 002b:00007ffec1114048 EFLAGS: 00003246 ORIG_RAX: 0000000000000010
[   11.661872] RAX: ffffffffffffffda RBX: 00007ffec111409c RCX: 0000003a680e98f7
[   11.662307] RDX: 00007ffec1114080 RSI: 00000000c06864a2 RDI: 000000000000000d
[   11.662742] RBP: 00007ffec1114080 R08: 0000000000000000 R09: 00000000017fdad0
[   11.663177] R10: 00007ffec1114150 R11: 0000000000003246 R12: 00000000c06864a2
[   11.663611] R13: 000000000000000d R14: 0000000000d8ceb0 R15: 0000000000c334a0
[   11.664048] Code: 5b 41 5c 41 5d 5d c3 45 84 ed 48 c7 c0 c6 47 36 c0 44 89 e2 48 c7 c6 bc 47 36 c0 48 c7 c7 cf 47 36 c0 48 0f 44 f0 e8 73 81 5f e7 <0f> 0b 83 2d 4d 2e 0c 00 01 5b 41 5c 41 5d 5d c3 66 90 0f 1f 44 
[   11.665395] ---[ end trace 72018b967117da08 ]---
[   11.665721] [drm:intel_pipe_update_end [i915]] *ERROR* Atomic update failure on pipe A (start=1310 end=1311) time 19686 us, min 1073, max 1079, scanline start 53, end 257
[   11.822503] e1000e: eth0 NIC Link is Up 100 Mbps Half Duplex, Flow Control: Rx/Tx
[   11.822969] e1000e 0000:00:1f.6 eth0: 10/100 speed: disabling TSO
[   11.824497] input: Lite-On Technology Corp HP USB Slim Keyboard - Skylab as /devices/pci0000:00/0000:00:14.0/usb1/1-2/1-2:1.1/0003:03F0:2A4A.0004/input/input13
[   11.825107] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[   11.828699] 8021q: 802.1Q VLAN Support v1.8
[   11.877318] hid-generic 0003:03F0:2A4A.0004: input,hiddev96: USB HID v1.10 Device [Lite-On Technology Corp HP USB Slim Keyboard - Skylab] on usb-0000:00:14.0-2/input1
[   11.882361] input: Lite-On Technology Corp HP USB Slim Keyboard - Skylab as /devices/pci0000:00/0000:00:14.0/usb1/1-2/1-2:1.0/0003:03F0:2A4A.0005/input/input14
[   11.935390] hid-generic 0003:03F0:2A4A.0005: input: USB HID v1.10 Keyboard [Lite-On Technology Corp HP USB Slim Keyboard - Skylab] on usb-0000:00:14.0-2/input0
[   11.938138] input: PixArt USB Optical Mouse as /devices/pci0000:00/0000:00:14.0/usb1/1-1/1-1:1.0/0003:04CA:0061.0006/input/input15
[   11.991330] hid-generic 0003:04CA:0061.0006: input: USB HID v1.11 Mouse [PixArt USB Optical Mouse] on usb-0000:00:14.0-1/input0

***********************
Log: /data/pankajsx/sato_poky_regression/poky/build/tmp/work/intel_corei7_64-poky-linux/core-image-sato-sdk/1.0-r0/dmesg
-----------------------
Central error: [    7.490691] udevd[446]: inotify_add_watch(6, /dev/loop0, 10) failed: No such file or directory
***********************
[    2.727289]  sdb:
[    2.728285] sd 1:0:0:0: [sdb] Attached SCSI removable disk
[    3.300769] urandom_read: 4 callbacks suppressed
[    3.300772] random: udevd: uninitialized urandom read (16 bytes read)
[    3.305963] random: udevd: uninitialized urandom read (16 bytes read)
[    3.306992] random: udevd: uninitialized urandom read (16 bytes read)
[    3.310661] EXT4-fs (loop0): couldn't mount as ext3 due to feature incompatibilities
[    3.311741] EXT4-fs (loop0): couldn't mount as ext2 due to feature incompatibilities
[    4.319070] random: crng init done
[    5.285838] EXT4-fs (loop0): mounted filesystem with ordered data mode. Opts: (null)
[    7.490691] udevd[446]: inotify_add_watch(6, /dev/loop0, 10) failed: No such file or directory
[    7.836388] udevd[487]: starting version 3.2.5
[    7.885252] udevd[488]: starting eudev-3.2.5
[    8.092043] EFI Variables Facility v0.08 2004-May-17
[    8.134115] mei_me 0000:00:16.0: enabling device (0000 -> 0002)
[    8.135228] usbcore: registered new interface driver btusb
[    8.138638] Bluetooth: hci0: Firmware revision 0.1 build 103 week 50 2016
[    8.178658] [drm] Found 64MB of eDRAM
[    8.179217] [drm] Memory usable by graphics device = 4096M
[    8.179523] checking generic (90000000 7e9000) vs hw (90000000 10000000)
[    8.179524] fb: switching to inteldrmfb from EFI VGA

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

NOTE: ----------------------------------------------------------------------
NOTE: Ran 3 tests in 5.074s
NOTE: FAILED
NOTE:  (failures=1)
RESULTS:
RESULTS - parselogs.ParseLogsTest.test_parselogs - Testcase 1059: FAILED
RESULTS - ping.PingTest.test_ping - Testcase 964: PASSED
RESULTS - ssh.SSHTest.test_ssh - Testcase 224: PASSED
SUMMARY:
core-image-sato-sdk () - Ran 3 tests in 5.075s
core-image-sato-sdk - FAIL - Required tests failed
ERROR: core-image-sato-sdk - FAILED - check the task log and the ssh log
DEBUG: Python function do_testimage finished
ERROR: Function failed: do_testimage
Machine information: 
*******************************
Machine name: intel-corei7-64
CPU:  Intel(R) Core(TM) i7-7567U CPU @ 3.50GHz
Arch: x86_64
Physical cores: 2
Logical cores: 4
*******************************
Comment 1 pravin 2018-06-19 06:05:40 UTC
In 2.6 M1 rc1 same error coming with different log.
I have attached log file
Comment 2 pravin 2018-06-19 06:07:07 UTC
Created attachment 4328 [details]
log.do_testimage
Comment 3 Anuj Mittal 2018-07-05 07:33:23 UTC
*** Bug 12764 has been marked as a duplicate of this bug. ***
Comment 4 Anuj Mittal 2018-07-10 02:20:39 UTC
I believe that the errors in original report have been fixed in latest meta-intel with the kernel updates. 

If you can still reproduce, please re-open and provide instructions.

If there are different errors present, please open a new bug.