Bug 7416

Summary: [Test Case 1059] Check logs for errors and failures (auto)
Product: [Build System, Metadata & Runtime] BSPs Reporter: Alexandru Georgescu <alexandru.c.georgescu>
Component: bsps-oe-coreAssignee: Saul Wold <sgw>
Status: RESOLVED FIXED QA Contact: Lucian Musat <georgex.l.musat>
Severity: minor    
Priority: Medium CC: dvhart
Version: 1.8   
Target Milestone: 1.8 M4   
Hardware: Netbook   
OS: x86   
Whiteboard:
OS type for building Yocto: --- Type of Regression: Regression (Used to work)
Verified: Documentation change: No (bug/feature does not impact docs)

Description Alexandru Georgescu 2015-03-06 15:24:39 UTC
STATUS: FAILED
BUILD: 1.8_M3.RC3: 5c1c8af083ca7b57cc339d16ce0513b27c5dac90
ENVIRONMENT: generic-x86 on AtomPC
STEPS TO REPRODUCE: 
  
 Parse dmesg log and all log files in /var/log for keywords like: "error" , "failed" , "cannot", "can't" etc.
Filter out the known and invalid errors or errors that appear in certain situations and need to be ignored.



EXPECTED OUTCOME: 
  
 No errors or fails should be present in logs

ACTUAL RESULTS:

21:18:33 - bitbake-worker - RESULTS - Testcase 215:
Traceback (most recent call last):
  File /work/weekly/runtime/genericx86/poky/meta/lib/oeqa/utils/decorators.py, line 97, in wrapped_f
    return func(*args)
  File /work/weekly/runtime/genericx86/poky/meta/lib/oeqa/utils/decorators.py, line 86, in wrapped_f
    return f(*args)
  File /work/weekly/runtime/genericx86/poky/meta/lib/oeqa/runtime/dmesg.py, line 12, in test_dmesg
    self.assertEqual(status, 1, msg = Error messages in dmesg log: %s % output)
AssertionError: 0 != 1 : Error messages in dmesg log: [    0.249673] ACPI Error: [CAPB] Namespace lookup failure, AE_ALREADY_EXISTS (20131218/dsfield-211)
[    0.249687] ACPI Error: Method parse/execution failed [\_SB_.PCI0._OSC] (Node f681f0d8), AE_ALREADY_EXISTS (20131218/psparse-536)
[    0.249705] ACPI: Marking method _OSC as Serialized because of AE_ALREADY_EXISTS error
21:18:59 - bitbake-worker - RESULTS - Testcase 203: PASSED
21:19:12 - bitbake-worker - RESULTS - Testcase test_gpp2_compile: PASSED
21:19:23 - bitbake-worker - RESULTS - Testcase 200: PASSED
21:19:28 - bitbake-worker - RESULTS - Testcase 204: PASSED
21:20:54 - bitbake-worker - RESULTS - Testcase 316: PASSED
21:20:59 - bitbake-worker - RESULTS - Testcase test_ldd_exists: PASSED
21:21:06 - bitbake-worker - RESULTS - Testcase 239: PASSED
21:23:49 - bitbake-worker - RESULTS - Testcase test_parselogs: FAILED
21:23:49 - bitbake-worker - RESULTS - Testcase test_parselogs:
Traceback (most recent call last):
  File /work/weekly/runtime/genericx86/poky/meta/lib/oeqa/utils/decorators.py, line 86, in wrapped_f
    return f(*args)
  File /work/weekly/runtime/genericx86/poky/meta/lib/oeqa/runtime/parselogs.py, line 203, in test_parselogs
    self.assertEqual(errcount, 0, msg=self.msg)
AssertionError: 13 != 0 : No ignore list found for this machine, using default
No ignore list found for this machine, using default
No ignore list found for this machine, using default
Log: /tmp/dmesg_output.log
-----------------------
Central error: [    0.000000] Notice: NX (Execute Disable) protection cannot be enabled: non-PAE kernel!
***********************
[    0.000000] BIOS-e820: [mem 0x00000000000dc000-0x00000000000dffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000000e4000-0x00000000000fffff] reserved
[    0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003f5cffff] usable
[    0.000000] BIOS-e820: [mem 0x000000003f5d0000-0x000000003f5dffff] ACPI data
[    0.000000] BIOS-e820: [mem 0x000000003f5e0000-0x000000003f5e2fff] ACPI NVS
[    0.000000] BIOS-e820: [mem 0x000000003f5e3000-0x000000003fffffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000e0000000-0x00000000efffffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000fec00000-0x00000000fec0ffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000fee00000-0x00000000fee00fff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000ff000000-0x00000000ffffffff] reserved
[    0.000000] Notice: NX (Execute Disable) protection cannot be enabled: non-PAE kernel!
[    0.000000] SMBIOS 2.5 present.
[    0.000000] DMI: LENOVO                           S10-3           /Mariana3A                       , BIOS 2ACN27WW   05/05/2010
[    0.000000] e820: update [mem 0x00000000-0x00000fff] usable ==> reserved
[    0.000000] e820: remove [mem 0x000a0000-0x000fffff] usable
[    0.000000] e820: last_pfn = 0x3f5d0 max_arch_pfn = 0x100000
[    0.000000] MTRR default type: uncachable
[    0.000000] MTRR fixed ranges enabled:
[    0.000000]   00000-9FFFF write-back
[    0.000000]   A0000-BFFFF uncachable
[    0.000000]   C0000-CFFFF write-protect
***********************
Central error: [    4.990368] r8169 0000:05:00.0: can't disable ASPM; OS doesn't have ASPM control
***********************
[    3.681069] uvesafb: framebuffer at 0xd0000000, mapped to 0xf8600000, using 4800k, total 8128k
[    3.681072] fb1: VESA VGA frame buffer device
[    4.069496] kjournald starting.  Commit interval 5 seconds
[    4.070188] EXT3-fs (sda1): using internal journal
[    4.070193] EXT3-fs (sda1): mounted filesystem with ordered data mode
[    4.173974] Adding 12209148k swap on /dev/sda3.  Priority:-1 extents:1 across:12209148k 
[    4.563089] udevd[104]: starting version 182
[    4.941283] snd_hda_intel 0000:00:1b.0: enabling device (0104 -> 0106)
[    4.941609] snd_hda_intel 0000:00:1b.0: irq 44 for MSI/MSI-X
[    4.990334] r8169 Gigabit Ethernet driver 2.3LK-NAPI loaded
[    4.990368] r8169 0000:05:00.0: can't disable ASPM; OS doesn't have ASPM control
[    4.990748] r8169 0000:05:00.0: irq 45 for MSI/MSI-X
[    4.991868] r8169 0000:05:00.0 eth0: RTL8102e at 0xf817c000, c8:0a:a9:cd:30:3d, XID 04e00000 IRQ 45
[    5.017134] bcma-pci-bridge 0000:09:00.0: enabling device (0104 -> 0106)
[    5.017336] bcma: bus0: Found chip with id 0x4313, rev 0x01 and package 0x08
[    5.017381] bcma: bus0: Core 0 found: ChipCommon (manuf 0x4BF, id 0x800, rev 0x24, class 0x0)
[    5.017420] bcma: bus0: Core 1 found: IEEE 802.11 (manuf 0x4BF, id 0x812, rev 0x18, class 0x0)
[    5.017482] bcma: bus0: Core 2 found: PCIe (manuf 0x4BF, id 0x820, rev 0x11, class 0x0)
[    5.055186] bcma: bus0: Bus registered
[    5.118658] media: Linux media interface: v0.10
[    5.122887] Linux video capture interface: v2.00
***********************
Central error: [    0.249721] acpi PNP0A08:00: _OSC failed (AE_ALREADY_EXISTS); disabling ASPM
***********************
[    0.231133] ACPI: Interpreter enabled
[    0.231174] ACPI Exception: AE_NOT_FOUND, While evaluating Sleep State [\_S2_] (20131218/hwxface-580)
[    0.231201] ACPI: (supports S0 S1 S3 S5)
[    0.231205] ACPI: Using IOAPIC for interrupt routing
[    0.231294] PCI: Using host bridge windows from ACPI; if necessary, use pci=nocrs and report a bug
[    0.249491] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-3f])
[    0.249507] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI]
[    0.249673] ACPI Error: [CAPB] Namespace lookup failure, AE_ALREADY_EXISTS (20131218/dsfield-211)
[    0.249687] ACPI Error: Method parse/execution failed [\_SB_.PCI0._OSC] (Node f681f0d8), AE_ALREADY_EXISTS (20131218/psparse-536)
[    0.249705] ACPI: Marking method _OSC as Serialized because of AE_ALREADY_EXISTS error
[    0.249721] acpi PNP0A08:00: _OSC failed (AE_ALREADY_EXISTS); disabling ASPM
[    0.252943] acpi PNP0A08:00: [Firmware Info]: MMCONFIG for domain 0000 [bus 00-10] only partially covers this bridge
[    0.253016] PCI host bridge to bus 0000:00
[    0.253023] pci_bus 0000:00: root bus resource [bus 00-3f]
[    0.253029] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7]
[    0.253035] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff]
[    0.253040] pci_bus 0000:00: root bus resource [mem 0x000d0000-0x000d3fff]
[    0.253044] pci_bus 0000:00: root bus resource [mem 0x000d4000-0x000d7fff]
[    0.253049] pci_bus 0000:00: root bus resource [mem 0x000d8000-0x000dbfff]
[    0.253054] pci_bus 0000:00: root bus resource [mem 0x000e0000-0x000e3fff]
[    0.253060] pci_bus 0000:00: root bus resource [mem 0x40000000-0xf7ffffff]
***********************
Central error: [    0.249673] ACPI Error: [CAPB] Namespace lookup failure, AE_ALREADY_EXISTS (20131218/dsfield-211)
***********************
***********************
Central error: [    0.249687] ACPI Error: Method parse/execution failed [\_SB_.PCI0._OSC] (Node f681f0d8), AE_ALREADY_EXISTS (20131218/psparse-536)
***********************
***********************
Central error: [    0.249705] ACPI: Marking method _OSC as Serialized because of AE_ALREADY_EXISTS error
***********************
[    0.216171] ACPI: SSDT   (null) 000085 (v02  PmRef  Cpu1Cst 00003000 INTL 20050624)
[    0.231133] ACPI: Interpreter enabled
[    0.231174] ACPI Exception: AE_NOT_FOUND, While evaluating Sleep State [\_S2_] (20131218/hwxface-580)
[    0.231201] ACPI: (supports S0 S1 S3 S5)
[    0.231205] ACPI: Using IOAPIC for interrupt routing
[    0.231294] PCI: Using host bridge windows from ACPI; if necessary, use pci=nocrs and report a bug
[    0.249491] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-3f])
[    0.249507] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI]
[    0.249673] ACPI Error: [CAPB] Namespace lookup failure, AE_ALREADY_EXISTS (20131218/dsfield-211)
[    0.249687] ACPI Error: Method parse/execution failed [\_SB_.PCI0._OSC] (Node f681f0d8), AE_ALREADY_EXISTS (20131218/psparse-536)
[    0.249705] ACPI: Marking method _OSC as Serialized because of AE_ALREADY_EXISTS error
[    0.249721] acpi PNP0A08:00: _OSC failed (AE_ALREADY_EXISTS); disabling ASPM
[    0.252943] acpi PNP0A08:00: [Firmware Info]: MMCONFIG for domain 0000 [bus 00-10] only partially covers this bridge
[    0.253016] PCI host bridge to bus 0000:00
[    0.253023] pci_bus 0000:00: root bus resource [bus 00-3f]
[    0.253029] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7]
[    0.253035] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff]
[    0.253040] pci_bus 0000:00: root bus resource [mem 0x000d0000-0x000d3fff]
[    0.253044] pci_bus 0000:00: root bus resource [mem 0x000d4000-0x000d7fff]
[    0.253049] pci_bus 0000:00: root bus resource [mem 0x000d8000-0x000dbfff]
[    0.253054] pci_bus 0000:00: root bus resource [mem 0x000e0000-0x000e3fff]
***********************
Log: /var/log/dmesg
-----------------------
Central error: [    0.000000] Notice: NX (Execute Disable) protection cannot be enabled: non-PAE kernel!
***********************
[    0.000000] BIOS-e820: [mem 0x00000000000dc000-0x00000000000dffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000000e4000-0x00000000000fffff] reserved
[    0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003f5cffff] usable
[    0.000000] BIOS-e820: [mem 0x000000003f5d0000-0x000000003f5dffff] ACPI data
[    0.000000] BIOS-e820: [mem 0x000000003f5e0000-0x000000003f5e2fff] ACPI NVS
[    0.000000] BIOS-e820: [mem 0x000000003f5e3000-0x000000003fffffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000e0000000-0x00000000efffffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000fec00000-0x00000000fec0ffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000fee00000-0x00000000fee00fff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000ff000000-0x00000000ffffffff] reserved
[    0.000000] Notice: NX (Execute Disable) protection cannot be enabled: non-PAE kernel!
[    0.000000] SMBIOS 2.5 present.
[    0.000000] DMI: LENOVO                           S10-3           /Mariana3A                       , BIOS 2ACN27WW   05/05/2010
[    0.000000] e820: update [mem 0x00000000-0x00000fff] usable ==> reserved
[    0.000000] e820: remove [mem 0x000a0000-0x000fffff] usable
[    0.000000] e820: last_pfn = 0x3f5d0 max_arch_pfn = 0x100000
[    0.000000] MTRR default type: uncachable
[    0.000000] MTRR fixed ranges enabled:
[    0.000000]   00000-9FFFF write-back
[    0.000000]   A0000-BFFFF uncachable
[    0.000000]   C0000-CFFFF write-protect
***********************
Central error: [    4.990368] r8169 0000:05:00.0: can't disable ASPM; OS doesn't have ASPM control
***********************
[    3.681069] uvesafb: framebuffer at 0xd0000000, mapped to 0xf8600000, using 4800k, total 8128k
[    3.681072] fb1: VESA VGA frame buffer device
[    4.069496] kjournald starting.  Commit interval 5 seconds
[    4.070188] EXT3-fs (sda1): using internal journal
[    4.070193] EXT3-fs (sda1): mounted filesystem with ordered data mode
[    4.173974] Adding 12209148k swap on /dev/sda3.  Priority:-1 extents:1 across:12209148k 
[    4.563089] udevd[104]: starting version 182
[    4.941283] snd_hda_intel 0000:00:1b.0: enabling device (0104 -> 0106)
[    4.941609] snd_hda_intel 0000:00:1b.0: irq 44 for MSI/MSI-X
[    4.990334] r8169 Gigabit Ethernet driver 2.3LK-NAPI loaded
[    4.990368] r8169 0000:05:00.0: can't disable ASPM; OS doesn't have ASPM control
[    4.990748] r8169 0000:05:00.0: irq 45 for MSI/MSI-X
[    4.991868] r8169 0000:05:00.0 eth0: RTL8102e at 0xf817c000, c8:0a:a9:cd:30:3d, XID 04e00000 IRQ 45
[    5.017134] bcma-pci-bridge 0000:09:00.0: enabling device (0104 -> 0106)
[    5.017336] bcma: bus0: Found chip with id 0x4313, rev 0x01 and package 0x08
[    5.017381] bcma: bus0: Core 0 found: ChipCommon (manuf 0x4BF, id 0x800, rev 0x24, class 0x0)
[    5.017420] bcma: bus0: Core 1 found: IEEE 802.11 (manuf 0x4BF, id 0x812, rev 0x18, class 0x0)
[    5.017482] bcma: bus0: Core 2 found: PCIe (manuf 0x4BF, id 0x820, rev 0x11, class 0x0)
[    5.055186] bcma: bus0: Bus registered
[    5.118658] media: Linux media interface: v0.10
[    5.122887] Linux video capture interface: v2.00
***********************
Central error: [    0.249721] acpi PNP0A08:00: _OSC failed (AE_ALREADY_EXISTS); disabling ASPM
***********************
[    0.231133] ACPI: Interpreter enabled
[    0.231174] ACPI Exception: AE_NOT_FOUND, While evaluating Sleep State [\_S2_] (20131218/hwxface-580)
[    0.231201] ACPI: (supports S0 S1 S3 S5)
[    0.231205] ACPI: Using IOAPIC for interrupt routing
[    0.231294] PCI: Using host bridge windows from ACPI; if necessary, use pci=nocrs and report a bug
[    0.249491] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-3f])
[    0.249507] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI]
[    0.249673] ACPI Error: [CAPB] Namespace lookup failure, AE_ALREADY_EXISTS (20131218/dsfield-211)
[    0.249687] ACPI Error: Method parse/execution failed [\_SB_.PCI0._OSC] (Node f681f0d8), AE_ALREADY_EXISTS (20131218/psparse-536)
[    0.249705] ACPI: Marking method _OSC as Serialized because of AE_ALREADY_EXISTS error
[    0.249721] acpi PNP0A08:00: _OSC failed (AE_ALREADY_EXISTS); disabling ASPM
[    0.252943] acpi PNP0A08:00: [Firmware Info]: MMCONFIG for domain 0000 [bus 00-10] only partially covers this bridge
[    0.253016] PCI host bridge to bus 0000:00
[    0.253023] pci_bus 0000:00: root bus resource [bus 00-3f]
[    0.253029] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7]
[    0.253035] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff]
[    0.253040] pci_bus 0000:00: root bus resource [mem 0x000d0000-0x000d3fff]
[    0.253044] pci_bus 0000:00: root bus resource [mem 0x000d4000-0x000d7fff]
[    0.253049] pci_bus 0000:00: root bus resource [mem 0x000d8000-0x000dbfff]
[    0.253054] pci_bus 0000:00: root bus resource [mem 0x000e0000-0x000e3fff]
[    0.253060] pci_bus 0000:00: root bus resource [mem 0x40000000-0xf7ffffff]
***********************
Central error: [    0.249673] ACPI Error: [CAPB] Namespace lookup failure, AE_ALREADY_EXISTS (20131218/dsfield-211)
***********************
***********************
Central error: [    0.249687] ACPI Error: Method parse/execution failed [\_SB_.PCI0._OSC] (Node f681f0d8), AE_ALREADY_EXISTS (20131218/psparse-536)
***********************
***********************
Central error: [    0.249705] ACPI: Marking method _OSC as Serialized because of AE_ALREADY_EXISTS error
***********************
[    0.216171] ACPI: SSDT   (null) 000085 (v02  PmRef  Cpu1Cst 00003000 INTL 20050624)
[    0.231133] ACPI: Interpreter enabled
[    0.231174] ACPI Exception: AE_NOT_FOUND, While evaluating Sleep State [\_S2_] (20131218/hwxface-580)
[    0.231201] ACPI: (supports S0 S1 S3 S5)
[    0.231205] ACPI: Using IOAPIC for interrupt routing
[    0.231294] PCI: Using host bridge windows from ACPI; if necessary, use pci=nocrs and report a bug
[    0.249491] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-3f])
[    0.249507] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI]
[    0.249673] ACPI Error: [CAPB] Namespace lookup failure, AE_ALREADY_EXISTS (20131218/dsfield-211)
[    0.249687] ACPI Error: Method parse/execution failed [\_SB_.PCI0._OSC] (Node f681f0d8), AE_ALREADY_EXISTS (20131218/psparse-536)
[    0.249705] ACPI: Marking method _OSC as Serialized because of AE_ALREADY_EXISTS error
[    0.249721] acpi PNP0A08:00: _OSC failed (AE_ALREADY_EXISTS); disabling ASPM
[    0.252943] acpi PNP0A08:00: [Firmware Info]: MMCONFIG for domain 0000 [bus 00-10] only partially covers this bridge
[    0.253016] PCI host bridge to bus 0000:00
[    0.253023] pci_bus 0000:00: root bus resource [bus 00-3f]
[    0.253029] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7]
[    0.253035] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff]
[    0.253040] pci_bus 0000:00: root bus resource [mem 0x000d0000-0x000d3fff]
[    0.253044] pci_bus 0000:00: root bus resource [mem 0x000d4000-0x000d7fff]
[    0.253049] pci_bus 0000:00: root bus resource [mem 0x000d8000-0x000dbfff]
[    0.253054] pci_bus 0000:00: root bus resource [mem 0x000e0000-0x000e3fff]
***********************
Log: /var/log//Xorg.0.log
-----------------------
Central error: [1300725.101] (WW) Open ACPI failed (/var/run/acpid.socket) (No such file or directory)
***********************
	If no devices become available, reconfigure udev or disable AutoAddDevices.
[1300725.097] (II) Loader magic: 0x827b040
[1300725.097] (II) Module ABI versions:
[1300725.097] 	X.Org ANSI C Emulation: 0.4
[1300725.097] 	X.Org Video Driver: 18.0
[1300725.097] 	X.Org XInput driver : 21.0
[1300725.097] 	X.Org Server Extension : 8.0
[1300725.098] (II) xfree86: Adding drm device (/dev/dri/card0)
[1300725.101] (--) PCI:*(0:0:2:0) 8086:a011:17aa:3908 rev 0, Mem @ 0xf0200000/524288, 0xd0000000/268435456, 0xf0000000/1048576, I/O @ 0x000018e8/8
[1300725.101] (--) PCI: (0:0:2:1) 8086:a012:17aa:3908 rev 0, Mem @ 0xf0280000/524288
[1300725.101] (WW) Open ACPI failed (/var/run/acpid.socket) (No such file or directory)
[1300725.102] (II) LoadModule: glx
[1300725.119] (II) Loading /usr/lib/xorg/modules/extensions/libglx.so
[1300725.381] (II) Module glx: vendor=X.Org Foundation
[1300725.381] 	compiled for 1.16.3, module version = 1.0.0
[1300725.381] 	ABI class: X.Org Server Extension, version 8.0
[1300725.381] (==) AIGLX enabled
[1300725.382] (==) Matched intel as autoconfigured driver 0
[1300725.382] (==) Matched intel as autoconfigured driver 1
[1300725.382] (==) Matched modesetting as autoconfigured driver 2
[1300725.382] (==) Matched fbdev as autoconfigured driver 3
***********************
13 errors found in logs.
Comment 1 Lucian Musat 2015-03-13 12:58:17 UTC
Patch for the log parser was sent.
Comment 2 Lucian Musat 2015-03-27 12:33:28 UTC
Fixed in commit b7a9a06b2dd0ee4abcd3f0c90f9c5c04edf78a95