I wasn't seeing these in -rc5-1117.
Am suspecting this commit or related:
commit 9c8573ca6e35f327d53cb46aba017b95e05d8e08
Author: Bob Moore <[email protected]>
Date: Wed Nov 12 15:15:29 2008 +0800
ACPICA: Add a mechanism to escape infinite AML While() loops
Add a loop counter to force exit from AML While loops if the
count becomes too large. This can occur in poorly written AML
when the hardware does not respond within a while loop and the
loop does not implement a timeout. The maximum loop count is
configurable. A new exception code is returned when a loop is
broken, AE_AML_INFINITE_LOOP. Bob Moore, Alexey Starikovskiy.
Signed-off-by: Bob Moore <[email protected]>
Signed-off-by: Lin Ming <[email protected]>
Signed-off-by: Len Brown <[email protected]>
This is a Dell Latitude D820, and didn't seem to have any infinite AML
loops to escape, at least none that I actually hit with previous kernels...
[ 14.500963] usb 3-1.2: new full speed USB device using uhci_hcd and address 3
[ 14.508930] ACPI Error (psparse-0536): Method parse/execution failed [\SXX6] (Node ffff88007f851a98), AE_AML_INFINITE_LOOP
[ 14.508973] ACPI Error (psparse-0536): Method parse/execution failed [\SXX4] (Node ffff88007f851a58), AE_AML_INFINITE_LOOP
[ 14.509001] ACPI Error (psparse-0536): Method parse/execution failed [\SX11] (Node ffff88007f8519f8), AE_AML_INFINITE_LOOP
[ 14.509029] ACPI Error (psparse-0536): Method parse/execution failed [\_TZ_.THM_.GINF] (Node ffff88007f858858), AE_AML_INFINITE_LOOP
[ 14.509058] ACPI Error (psparse-0536): Method parse/execution failed [\_TZ_.THM_._TMP] (Node ffff88007f858878), AE_AML_INFINITE_LOOP
..
[ 14.749476] usb 3-1.2: configuration #1 chosen from 1 choice
[ 15.589404] ACPI Error (psparse-0536): Method parse/execution failed [\SMI_] (Node ffff88007f851238), AE_AML_INFINITE_LOOP
[ 15.603541] ACPI Error (psparse-0536): Method parse/execution failed [\_SB_.LID_._LID] (Node ffff88007f8581f8), AE_AML_INFINITE_LOOP
[ 15.617394] ACPI: Lid Switch [LID]
[ 15.632375] input: Power Button (CM) as /devices/LNXSYSTM:00/device:00/PNP0C0C:00/input/input6
[ 15.687289] ACPI: Power Button (CM) [PBTN]
[ 15.702897] input: Sleep Button (CM) as /devices/LNXSYSTM:00/device:00/PNP0C0E:00/input/input7
[ 15.737284] ACPI: Sleep Button (CM) [SBTN]
[ 16.624380] ACPI Error (psparse-0536): Method parse/execution failed [\SMI_] (Node ffff88007f851238), AE_AML_INFINITE_LOOP
[ 16.638972] ACPI Error (psparse-0536): Method parse/execution failed [\_SB_.PCI0.AGP_.VID_._DOD] (Node ffff88007f85ded8), AE_AML_INFINITE_LOOP
[ 16.654039] ACPI Exception (video-1638): AE_AML_INFINITE_LOOP, Evaluating _DOD [20080926]
[ 17.669507] ACPI Error (psparse-0536): Method parse/execution failed [\SMI_] (Node ffff88007f851238), AE_AML_INFINITE_LOOP
[ 17.685120] ACPI Error (psparse-0536): Method parse/execution failed [\_SB_.PCI0.AGP_.VID_.LCD_._ADR] (Node ffff88007f85dd58), AE_AML_INFINITE_LOOP
[ 17.701051] ACPI: Cant attach deviceACPI Error (psparse-0536): Method parse/execution failed [\SMI_] (Node ffff88007f851238), AE_AML_INFINITE_LOOP
[ 18.719111] ACPI Error (psparse-0536): Method parse/execution failed [\_SB_.PCI0.AGP_.VID_.DVI_._ADR] (Node ffff88007f85dcb8), AE_AML_INFINITE_LOOP
[ 18.735778] ACPI: Cant attach deviceACPI Error (psparse-0536): Method parse/execution failed [\SMI_] (Node ffff88007f851238), AE_AML_INFINITE_LOOP
[ 19.745706] ACPI Error (psparse-0536): Method parse/execution failed [\_SB_.PCI0.AGP_.VID_._DOS] (Node ffff88007f85def8), AE_AML_INFINITE_LOOP
[ 19.763195] input: Video Bus as /devices/LNXSYSTM:00/device:00/PNP0A03:00/device:22/device:23/input/input8
[ 19.784055] ACPI: Video Device [VID] (multi-head: yes rom: no post: no)
[ 19.893914] tpm_tis 00:0f: 1.2 TPM (device-id 0x1001, rev-id 2)
[ 20.044222] Real Time Clock Driver v1.12b
[ 23.722937] ACPI Error (psparse-0536): Method parse/execution failed [\SMI_] (Node ffff88007f851238), AE_AML_INFINITE_LOOP
[ 23.741371] ACPI Error (psparse-0536): Method parse/execution failed [\_SB_.AC__._PSR] (Node ffff88007f8584b8), AE_AML_INFINITE_LOOP
[ 23.759881] ACPI Exception (ac-0135): AE_AML_INFINITE_LOOP, Error reading AC Adapter state [20080926]
[ 25.138613] ACPI Error (psparse-0536): Method parse/execution failed [\SMI_] (Node ffff88007f851238), AE_AML_INFINITE_LOOP
[ 25.157545] ACPI Error (psparse-0536): Method parse/execution failed [\_SB_.AC__._PSR] (Node ffff88007f8584b8), AE_AML_INFINITE_LOOP
[ 25.176898] ACPI Exception (ac-0135): AE_AML_INFINITE_LOOP, Error reading AC Adapter state [20080926]
[ 26.447172] ACPI Error (psparse-0536): Method parse/execution failed [\SMI_] (Node ffff88007f851238), AE_AML_INFINITE_LOOP
[ 26.467212] ACPI Error (psparse-0536): Method parse/execution failed [\_SB_.AC__._PSR] (Node ffff88007f8584b8), AE_AML_INFINITE_LOOP
[ 26.487528] ACPI Exception (ac-0135): AE_AML_INFINITE_LOOP, Error reading AC Adapter state [20080926]
[ 27.684304] ACPI Error (psparse-0536): Method parse/execution failed [\SMI_] (Node ffff88007f851238), AE_AML_INFINITE_LOOP
[ 27.704947] ACPI Error (psparse-0536): Method parse/execution failed [\_SB_.AC__._PSR] (Node ffff88007f8584b8), AE_AML_INFINITE_LOOP
[ 27.725906] ACPI Exception (ac-0135): AE_AML_INFINITE_LOOP, Error reading AC Adapter state [20080926]
[ 28.907380] ACPI Error (psparse-0536): Method parse/execution failed [\SMI_] (Node ffff88007f851238), AE_AML_INFINITE_LOOP
[ 28.928958] ACPI Error (psparse-0536): Method parse/execution failed [\_SB_.AC__._PSR] (Node ffff88007f8584b8), AE_AML_INFINITE_LOOP
[ 28.950703] ACPI Exception (ac-0135): AE_AML_INFINITE_LOOP, Error reading AC Adapter state [20080926]
[ 30.485112] ACPI Error (psparse-0536): Method parse/execution failed [\SMI_] (Node ffff88007f851238), AE_AML_INFINITE_LOOP
[ 30.507460] ACPI Error (psparse-0536): Method parse/execution failed [\_SB_.AC__._PSR] (Node ffff88007f8584b8), AE_AML_INFINITE_LOOP
[ 30.530100] ACPI Exception (ac-0135): AE_AML_INFINITE_LOOP, Error reading AC Adapter state [20080926]
[ 30.507460] ACPI Error (psparse-0536): Method parse/execution failed [\_SB_.AC__._PSR] (Node ffff88007f8584b8), AE_AML_INFINITE_LOOP
[ 30.530100] ACPI Exception (ac-0135): AE_AML_INFINITE_LOOP, Error reading AC Adapter state [20080926]
[ 30.701061] Clocksource tsc unstable (delta = -6082993826 ns)
[ 32.770589] ACPI Error (psparse-0536): Method parse/execution failed [\SMI_] (Node ffff88007f851238), AE_AML_INFINITE_LOOP
[ 32.793800] ACPI Error (psparse-0536): Method parse/execution failed [\_SB_.AC__._PSR] (Node ffff88007f8584b8), AE_AML_INFINITE_LOOP
[ 32.817635] ACPI Exception (ac-0135): AE_AML_INFINITE_LOOP, Error reading AC Adapter state [20080926]
[ 33.984725] ACPI Error (psparse-0536): Method parse/execution failed [\SMI_] (Node ffff88007f851238), AE_AML_INFINITE_LOOP
[ 34.008812] ACPI Error (psparse-0536): Method parse/execution failed [\_SB_.AC__._PSR] (Node ffff88007f8584b8), AE_AML_INFINITE_LOOP
[ 34.033236] ACPI Exception (ac-0135): AE_AML_INFINITE_LOOP, Error reading AC Adapter state [20080926]
[ 35.550289] ACPI Error (psparse-0536): Method parse/execution failed [\SMI_] (Node ffff88007f851238), AE_AML_INFINITE_LOOP
[ 35.574761] ACPI Error (psparse-0536): Method parse/execution failed [\_SB_.AC__._PSR] (Node ffff88007f8584b8), AE_AML_INFINITE_LOOP
[ 35.599301] ACPI Exception (ac-0135): AE_AML_INFINITE_LOOP, Error reading AC Adapter state [20080926]
[ 36.985099] ACPI Error (psparse-0536): Method parse/execution failed [\SMI_] (Node ffff88007f851238), AE_AML_INFINITE_LOOP
[ 37.009318] ACPI Error (psparse-0536): Method parse/execution failed [\_SB_.AC__._PSR] (Node ffff88007f8584b8), AE_AML_INFINITE_LOOP
[ 37.034035] ACPI Exception (ac-0135): AE_AML_INFINITE_LOOP, Error reading AC Adapter state [20080926]
[ 38.169534] ACPI Error (psparse-0536): Method parse/execution failed [\SMI_] (Node ffff88007f851238), AE_AML_INFINITE_LOOP
[ 38.193902] ACPI Error (psparse-0536): Method parse/execution failed [\_SB_.AC__._PSR] (Node ffff88007f8584b8), AE_AML_INFINITE_LOOP
[ 38.218599] ACPI Exception (ac-0135): AE_AML_INFINITE_LOOP, Error reading AC Adapter state [20080926]
[ 38.323556] EXT3 FS on dm-0, internal journal
You could try making the max loop count larger, it is a 32-bit value:
acconfig.h
/* Maximum number of While() loop iterations before forced abort */
-#define ACPI_MAX_LOOP_ITERATIONS 0xFFFF
+#define ACPI_MAX_LOOP_ITERATIONS 0x00FFFFFF
>-----Original Message-----
>From: [email protected] [mailto:[email protected]]
>Sent: Wednesday, November 26, 2008 8:16 AM
>To: Andrew Morton; Moore, Robert
>Cc: [email protected]
>Subject: 2.6.28-rc6-mmotm1126 - acpi AE_AM_INFINITE_LOOP errors..
>
>I wasn't seeing these in -rc5-1117.
>Am suspecting this commit or related:
>
>commit 9c8573ca6e35f327d53cb46aba017b95e05d8e08
>Author: Bob Moore <[email protected]>
>Date: Wed Nov 12 15:15:29 2008 +0800
>
> ACPICA: Add a mechanism to escape infinite AML While() loops
>
> Add a loop counter to force exit from AML While loops if the
> count becomes too large. This can occur in poorly written AML
> when the hardware does not respond within a while loop and the
> loop does not implement a timeout. The maximum loop count is
> configurable. A new exception code is returned when a loop is
> broken, AE_AML_INFINITE_LOOP. Bob Moore, Alexey Starikovskiy.
>
> Signed-off-by: Bob Moore <[email protected]>
> Signed-off-by: Lin Ming <[email protected]>
> Signed-off-by: Len Brown <[email protected]>
>
>This is a Dell Latitude D820, and didn't seem to have any infinite AML
>loops to escape, at least none that I actually hit with previous kernels...
>
>[ 14.500963] usb 3-1.2: new full speed USB device using uhci_hcd and
>address 3
>[ 14.508930] ACPI Error (psparse-0536): Method parse/execution failed
>[\SXX6] (Node ffff88007f851a98), AE_AML_INFINITE_LOOP
>[ 14.508973] ACPI Error (psparse-0536): Method parse/execution failed
>[\SXX4] (Node ffff88007f851a58), AE_AML_INFINITE_LOOP
>[ 14.509001] ACPI Error (psparse-0536): Method parse/execution failed
>[\SX11] (Node ffff88007f8519f8), AE_AML_INFINITE_LOOP
>[ 14.509029] ACPI Error (psparse-0536): Method parse/execution failed
>[\_TZ_.THM_.GINF] (Node ffff88007f858858), AE_AML_INFINITE_LOOP
>[ 14.509058] ACPI Error (psparse-0536): Method parse/execution failed
>[\_TZ_.THM_._TMP] (Node ffff88007f858878), AE_AML_INFINITE_LOOP
>..
>[ 14.749476] usb 3-1.2: configuration #1 chosen from 1 choice
>[ 15.589404] ACPI Error (psparse-0536): Method parse/execution failed
>[\SMI_] (Node ffff88007f851238), AE_AML_INFINITE_LOOP
>[ 15.603541] ACPI Error (psparse-0536): Method parse/execution failed
>[\_SB_.LID_._LID] (Node ffff88007f8581f8), AE_AML_INFINITE_LOOP
>[ 15.617394] ACPI: Lid Switch [LID]
>[ 15.632375] input: Power Button (CM) as
>/devices/LNXSYSTM:00/device:00/PNP0C0C:00/input/input6
>[ 15.687289] ACPI: Power Button (CM) [PBTN]
>[ 15.702897] input: Sleep Button (CM) as
>/devices/LNXSYSTM:00/device:00/PNP0C0E:00/input/input7
>[ 15.737284] ACPI: Sleep Button (CM) [SBTN]
>[ 16.624380] ACPI Error (psparse-0536): Method parse/execution failed
>[\SMI_] (Node ffff88007f851238), AE_AML_INFINITE_LOOP
>[ 16.638972] ACPI Error (psparse-0536): Method parse/execution failed
>[\_SB_.PCI0.AGP_.VID_._DOD] (Node ffff88007f85ded8), AE_AML_INFINITE_LOOP
>[ 16.654039] ACPI Exception (video-1638): AE_AML_INFINITE_LOOP,
>Evaluating _DOD [20080926]
>[ 17.669507] ACPI Error (psparse-0536): Method parse/execution failed
>[\SMI_] (Node ffff88007f851238), AE_AML_INFINITE_LOOP
>[ 17.685120] ACPI Error (psparse-0536): Method parse/execution failed
>[\_SB_.PCI0.AGP_.VID_.LCD_._ADR] (Node ffff88007f85dd58),
>AE_AML_INFINITE_LOOP
>[ 17.701051] ACPI: Cant attach deviceACPI Error (psparse-0536): Method
>parse/execution failed [\SMI_] (Node ffff88007f851238),
>AE_AML_INFINITE_LOOP
>[ 18.719111] ACPI Error (psparse-0536): Method parse/execution failed
>[\_SB_.PCI0.AGP_.VID_.DVI_._ADR] (Node ffff88007f85dcb8),
>AE_AML_INFINITE_LOOP
>[ 18.735778] ACPI: Cant attach deviceACPI Error (psparse-0536): Method
>parse/execution failed [\SMI_] (Node ffff88007f851238),
>AE_AML_INFINITE_LOOP
>[ 19.745706] ACPI Error (psparse-0536): Method parse/execution failed
>[\_SB_.PCI0.AGP_.VID_._DOS] (Node ffff88007f85def8), AE_AML_INFINITE_LOOP
>[ 19.763195] input: Video Bus as
>/devices/LNXSYSTM:00/device:00/PNP0A03:00/device:22/device:23/input/input8
>[ 19.784055] ACPI: Video Device [VID] (multi-head: yes rom: no post:
>no)
>[ 19.893914] tpm_tis 00:0f: 1.2 TPM (device-id 0x1001, rev-id 2)
>[ 20.044222] Real Time Clock Driver v1.12b
>[ 23.722937] ACPI Error (psparse-0536): Method parse/execution failed
>[\SMI_] (Node ffff88007f851238), AE_AML_INFINITE_LOOP
>[ 23.741371] ACPI Error (psparse-0536): Method parse/execution failed
>[\_SB_.AC__._PSR] (Node ffff88007f8584b8), AE_AML_INFINITE_LOOP
>[ 23.759881] ACPI Exception (ac-0135): AE_AML_INFINITE_LOOP, Error
>reading AC Adapter state [20080926]
>[ 25.138613] ACPI Error (psparse-0536): Method parse/execution failed
>[\SMI_] (Node ffff88007f851238), AE_AML_INFINITE_LOOP
>[ 25.157545] ACPI Error (psparse-0536): Method parse/execution failed
>[\_SB_.AC__._PSR] (Node ffff88007f8584b8), AE_AML_INFINITE_LOOP
>[ 25.176898] ACPI Exception (ac-0135): AE_AML_INFINITE_LOOP, Error
>reading AC Adapter state [20080926]
>[ 26.447172] ACPI Error (psparse-0536): Method parse/execution failed
>[\SMI_] (Node ffff88007f851238), AE_AML_INFINITE_LOOP
>[ 26.467212] ACPI Error (psparse-0536): Method parse/execution failed
>[\_SB_.AC__._PSR] (Node ffff88007f8584b8), AE_AML_INFINITE_LOOP
>[ 26.487528] ACPI Exception (ac-0135): AE_AML_INFINITE_LOOP, Error
>reading AC Adapter state [20080926]
>[ 27.684304] ACPI Error (psparse-0536): Method parse/execution failed
>[\SMI_] (Node ffff88007f851238), AE_AML_INFINITE_LOOP
>[ 27.704947] ACPI Error (psparse-0536): Method parse/execution failed
>[\_SB_.AC__._PSR] (Node ffff88007f8584b8), AE_AML_INFINITE_LOOP
>[ 27.725906] ACPI Exception (ac-0135): AE_AML_INFINITE_LOOP, Error
>reading AC Adapter state [20080926]
>[ 28.907380] ACPI Error (psparse-0536): Method parse/execution failed
>[\SMI_] (Node ffff88007f851238), AE_AML_INFINITE_LOOP
>[ 28.928958] ACPI Error (psparse-0536): Method parse/execution failed
>[\_SB_.AC__._PSR] (Node ffff88007f8584b8), AE_AML_INFINITE_LOOP
>[ 28.950703] ACPI Exception (ac-0135): AE_AML_INFINITE_LOOP, Error
>reading AC Adapter state [20080926]
>[ 30.485112] ACPI Error (psparse-0536): Method parse/execution failed
>[\SMI_] (Node ffff88007f851238), AE_AML_INFINITE_LOOP
>[ 30.507460] ACPI Error (psparse-0536): Method parse/execution failed
>[\_SB_.AC__._PSR] (Node ffff88007f8584b8), AE_AML_INFINITE_LOOP
>[ 30.530100] ACPI Exception (ac-0135): AE_AML_INFINITE_LOOP, Error
>reading AC Adapter state [20080926]
>[ 30.507460] ACPI Error (psparse-0536): Method parse/execution failed
>[\_SB_.AC__._PSR] (Node ffff88007f8584b8), AE_AML_INFINITE_LOOP
>[ 30.530100] ACPI Exception (ac-0135): AE_AML_INFINITE_LOOP, Error
>reading AC Adapter state [20080926]
>[ 30.701061] Clocksource tsc unstable (delta = -6082993826 ns)
>[ 32.770589] ACPI Error (psparse-0536): Method parse/execution failed
>[\SMI_] (Node ffff88007f851238), AE_AML_INFINITE_LOOP
>[ 32.793800] ACPI Error (psparse-0536): Method parse/execution failed
>[\_SB_.AC__._PSR] (Node ffff88007f8584b8), AE_AML_INFINITE_LOOP
>[ 32.817635] ACPI Exception (ac-0135): AE_AML_INFINITE_LOOP, Error
>reading AC Adapter state [20080926]
>[ 33.984725] ACPI Error (psparse-0536): Method parse/execution failed
>[\SMI_] (Node ffff88007f851238), AE_AML_INFINITE_LOOP
>[ 34.008812] ACPI Error (psparse-0536): Method parse/execution failed
>[\_SB_.AC__._PSR] (Node ffff88007f8584b8), AE_AML_INFINITE_LOOP
>[ 34.033236] ACPI Exception (ac-0135): AE_AML_INFINITE_LOOP, Error
>reading AC Adapter state [20080926]
>[ 35.550289] ACPI Error (psparse-0536): Method parse/execution failed
>[\SMI_] (Node ffff88007f851238), AE_AML_INFINITE_LOOP
>[ 35.574761] ACPI Error (psparse-0536): Method parse/execution failed
>[\_SB_.AC__._PSR] (Node ffff88007f8584b8), AE_AML_INFINITE_LOOP
>[ 35.599301] ACPI Exception (ac-0135): AE_AML_INFINITE_LOOP, Error
>reading AC Adapter state [20080926]
>[ 36.985099] ACPI Error (psparse-0536): Method parse/execution failed
>[\SMI_] (Node ffff88007f851238), AE_AML_INFINITE_LOOP
>[ 37.009318] ACPI Error (psparse-0536): Method parse/execution failed
>[\_SB_.AC__._PSR] (Node ffff88007f8584b8), AE_AML_INFINITE_LOOP
>[ 37.034035] ACPI Exception (ac-0135): AE_AML_INFINITE_LOOP, Error
>reading AC Adapter state [20080926]
>[ 38.169534] ACPI Error (psparse-0536): Method parse/execution failed
>[\SMI_] (Node ffff88007f851238), AE_AML_INFINITE_LOOP
>[ 38.193902] ACPI Error (psparse-0536): Method parse/execution failed
>[\_SB_.AC__._PSR] (Node ffff88007f8584b8), AE_AML_INFINITE_LOOP
>[ 38.218599] ACPI Exception (ac-0135): AE_AML_INFINITE_LOOP, Error
>reading AC Adapter state [20080926]
>[ 38.323556] EXT3 FS on dm-0, internal journal
On Wed, 26 Nov 2008 08:24:26 PST, "Moore, Robert" said:
>
> You could try making the max loop count larger, it is a 32-bit value:
>
> acconfig.h
>
> /* Maximum number of While() loop iterations before forced abort */
>
> -#define ACPI_MAX_LOOP_ITERATIONS 0xFFFF
> +#define ACPI_MAX_LOOP_ITERATIONS 0x00FFFFFF
That "works", for some sub-optimal value of "works". It does indeed
shut up *some* of the messages, but boot was taking *forever* (or more correctly,
I gave up when it had taken more than 6 minutes to get through the initial
udev and modprobe flurry that usually takes all of 12 seconds or less to
complete.
I'm suspecting that something *else* is busticated in the ACPI code, and
loops that used to complete quickly are missing whatever terminating
condition they had, and the new infinite loop detector is in fact tripping
properly and catching the (newly introduced) error condition?
...
[ 13.042567] usb 3-1.2: configuration #1 chosen from 1 choice
[ 13.130251] ieee1394: Host added: ID:BUS[0-00:1023] GUID[4a4fc0000fbe9561]
[ 30.702013] Clocksource tsc unstable (delta = -6046116753 ns)
[ 41.726022] thermal: gave up waiting for init of module processor.
[ 41.741146] thermal: Unknown symbol acpi_processor_set_thermal_limit
[ 41.760231] yenta_cardbus 0000:03:01.0: CardBus bridge found [1028:01cc]
It went off to somewhere for 28 seconds trying to init the thermal code..
[ 42.213146] vendor=8086 device=27d2
[ 42.227850] iwl3945 0000:0c:00.0: PCI INT A disabled
and went nappies here.. finally I did a alt-sysrq-t, and we're here, with
several modprobes chugging along very slowly...
[ 127.995256] modprobe D ffff88007edcb800 5176 862 861
[ 127.995256] ffff88007ec639a8 0000000000000046 000000017e42f250 ffffffff8079d7c0
[ 127.995256] ffffffff8079d750 ffffffff8081e7c0 ffffffff8081e7c0 ffff88007eddd7f0
[ 127.995256] ffff88007f2677f0 ffff88007edddb48 000000008079ea80 ffff88007edddb48
[ 127.995256] Call Trace:
[ 127.995256] [<ffffffff80283516>] ? __alloc_pages_internal+0x10d/0x493
[ 127.995256] [<ffffffff8022f214>] ? get_parent_ip+0x11/0x41
[ 127.995256] [<ffffffff80568bc6>] schedule_timeout+0x22/0xb4
[ 127.995256] [<ffffffff8022f214>] ? get_parent_ip+0x11/0x41
[ 127.995256] [<ffffffff8056d949>] ? sub_preempt_count+0x35/0x49
[ 127.995256] [<ffffffff805699de>] __down_common+0x9d/0xdf
[ 127.995256] [<ffffffff80569a31>] __down_timeout+0x11/0x13
[ 127.995256] [<ffffffff8024f639>] down_timeout+0x48/0x61
[ 127.995256] [<ffffffff803a5b1d>] acpi_os_wait_semaphore+0x49/0x58
[ 127.995256] [<ffffffff803be893>] acpi_ut_acquire_mutex+0x3e/0x82
[ 127.995256] [<ffffffff803b29ce>] acpi_ex_enter_interpreter+0xb/0x2b
[ 127.995256] [<ffffffff803b5840>] acpi_ns_evaluate+0x1ac/0x230
[ 127.995256] [<ffffffff803b52b4>] acpi_evaluate_object+0xfc/0x204
[ 127.995256] [<ffffffff8038ebd3>] ? pci_get_subsys+0x7b/0x8f
[ 127.995256] [<ffffffffa005fe16>] acpi_processor_start+0x1ba/0x78a [processor]
[ 127.995256] [<ffffffff803c09b3>] acpi_start_single_object+0x2a/0x54
[ 127.995256] [<ffffffff803c1fc7>] acpi_device_probe+0x78/0x8c
[ 127.995256] [<ffffffff803f349f>] driver_probe_device+0xe7/0x195
[ 127.995256] [<ffffffff803f35af>] __driver_attach+0x62/0x8c
[ 127.995256] [<ffffffff803f354d>] ? __driver_attach+0x0/0x8c
[ 127.995256] [<ffffffff803f2d10>] bus_for_each_dev+0x4c/0x83
[ 127.995256] [<ffffffff803f32c3>] driver_attach+0x1c/0x1e
[ 127.995256] [<ffffffff803f260d>] bus_add_driver+0xb5/0x1ff
[ 127.995256] [<ffffffff803f37a0>] driver_register+0xa8/0x128
[ 127.995256] [<ffffffffa006a000>] ? acpi_processor_init+0x0/0x10a [processor]
[ 127.995256] [<ffffffff803c22f6>] acpi_bus_register_driver+0x3e/0x40
[ 127.995256] [<ffffffffa006a097>] acpi_processor_init+0x97/0x10a [processor]
[ 127.995256] [<ffffffff80209058>] _stext+0x58/0x138
[ 127.995256] [<ffffffff8022f214>] ? get_parent_ip+0x11/0x41
[ 127.995256] [<ffffffff8056d949>] ? sub_preempt_count+0x35/0x49
[ 127.995256] [<ffffffff8056aa0d>] ? _spin_unlock_irqrestore+0x5e/0x6c
[ 127.995256] [<ffffffff8037b6e6>] ? __up_read+0x7c/0x85
[ 127.995256] [<ffffffff8024ef3b>] ? up_read+0x9/0xb
[ 127.995256] [<ffffffff8024fa3e>] ? __blocking_notifier_call_chain+0x58/0x6a
[ 127.995256] [<ffffffff8025f536>] sys_init_module+0xbd/0x1db
[ 127.995256] [<ffffffff8020bb3b>] system_call_fastpath+0x16/0x1b
[ 127.995256] modprobe R running task 5240 878 875
[ 127.995256] ffff88007e4838a8 0000000000000046 0000007b8081bba8 ffffffff803b21c5
[ 127.995256] ffff88007e483858 ffffffff8081e7c0 ffffffff8081e7c0 ffff88007e76d040
[ 127.995256] ffff88007f2347f0 ffff88007e76d3a0 0000000100000000 ffff88007e76d398
[ 127.995256] Call Trace:
[ 127.995256] [<ffffffff803b21c5>] ? acpi_ex_system_io_space_handler+0x0/0x44
[ 127.995256] [<ffffffff802a2343>] ? __cache_free+0x41/0xf4
[ 127.995256] [<ffffffff80568acb>] preempt_schedule_irq+0x4d/0x91
[ 127.995256] [<ffffffff8020c676>] retint_kernel+0x26/0x30
[ 127.995256] [<ffffffff803ae5ae>] ? acpi_ex_name_segment+0x5a/0xed
[ 127.995256] [<ffffffff803ae5d3>] ? acpi_ex_name_segment+0x7f/0xed
[ 127.995256] [<ffffffff803ae730>] acpi_ex_get_name_string+0xef/0x1ef
[ 127.995256] [<ffffffff803a9463>] acpi_ds_create_operand+0x4e/0x209
[ 127.995256] [<ffffffff803a9667>] acpi_ds_evaluate_name_path+0x49/0xff
[ 127.995256] [<ffffffff803a832f>] acpi_ds_exec_end_op+0x94/0x3d9
[ 127.995256] [<ffffffff803b8c1f>] acpi_ps_parse_loop+0x6d4/0x859
[ 127.995256] [<ffffffff803b7e45>] acpi_ps_parse_aml+0x9a/0x2d9
[ 127.995256] [<ffffffff803b93f2>] acpi_ps_execute_method+0x195/0x267
[ 127.995256] [<ffffffff803b57e6>] acpi_ns_evaluate+0x152/0x230
[ 127.995256] [<ffffffff803b52b4>] acpi_evaluate_object+0xfc/0x204
[ 127.995256] [<ffffffffa0033403>] acpi_battery_update+0x9e/0x1df [battery]
[ 127.995256] [<ffffffffa0033f25>] acpi_battery_add+0x8d/0x1d4 [battery]
[ 127.995256] [<ffffffff803c1f98>] acpi_device_probe+0x49/0x8c
[ 127.995256] [<ffffffff803f349f>] driver_probe_device+0xe7/0x195
[ 127.995256] [<ffffffff803f35af>] __driver_attach+0x62/0x8c
[ 127.995256] [<ffffffff803f354d>] ? __driver_attach+0x0/0x8c
[ 127.995256] [<ffffffff803f2d10>] bus_for_each_dev+0x4c/0x83
[ 127.995256] [<ffffffff803f32c3>] driver_attach+0x1c/0x1e
[ 127.995256] [<ffffffff803f260d>] bus_add_driver+0xb5/0x1ff
[ 127.995256] [<ffffffff803f37a0>] driver_register+0xa8/0x128
[ 127.995256] [<ffffffffa0039000>] ? acpi_battery_init+0x0/0x45 [battery]
[ 127.995256] [<ffffffff803c22f6>] acpi_bus_register_driver+0x3e/0x40
[ 127.995256] [<ffffffffa003902a>] acpi_battery_init+0x2a/0x45 [battery]
[ 127.995256] [<ffffffff80209058>] _stext+0x58/0x138
[ 127.995256] [<ffffffff8022f214>] ? get_parent_ip+0x11/0x41
[ 127.995256] [<ffffffff8056d949>] ? sub_preempt_count+0x35/0x49
[ 127.995256] [<ffffffff8056aa0d>] ? _spin_unlock_irqrestore+0x5e/0x6c
[ 127.995256] [<ffffffff8037b6e6>] ? __up_read+0x7c/0x85
[ 127.995256] [<ffffffff8024ef3b>] ? up_read+0x9/0xb
[ 127.995256] [<ffffffff8024fa3e>] ? __blocking_notifier_call_chain+0x58/0x6a
[ 127.995256] [<ffffffff8025f536>] sys_init_module+0xbd/0x1db
[ 127.995256] [<ffffffff8020bb3b>] system_call_fastpath+0x16/0x1b
[ 127.995256] modprobe D ffff88007edcb440 5816 882 879
[ 127.995256] ffff88007e433c08 0000000000000046 ffff88007e433b78 ffffffff80376e5e
[ 127.995256] ffff88007f411090 ffffffff8081e7c0 ffffffff8081e7c0 ffff88007e4a57f0
[ 127.995256] ffff88007e7627f0 ffff88007e4a5b48 000000007e433b98 ffff88007e4a5b48
[ 127.995256] Call Trace:
[ 127.995256] [<ffffffff80376e5e>] ? _atomic_dec_and_lock+0x5a/0x98
[ 127.995256] [<ffffffff802f9623>] ? sysfs_addrm_finish+0x69/0x255
[ 127.995256] [<ffffffff802f90e6>] ? __sysfs_get+0x4d/0x58
[ 127.995256] [<ffffffff802f9266>] ? __sysfs_add_one+0x63/0x9e
[ 127.995256] [<ffffffff80568bc6>] schedule_timeout+0x22/0xb4
[ 127.995256] [<ffffffff802f993f>] ? create_dir+0x63/0x87
[ 127.995256] [<ffffffff8022f214>] ? get_parent_ip+0x11/0x41
[ 127.995256] [<ffffffff8056d949>] ? sub_preempt_count+0x35/0x49
[ 127.995256] [<ffffffff805699de>] __down_common+0x9d/0xdf
[ 127.995256] [<ffffffff803f354d>] ? __driver_attach+0x0/0x8c
[ 127.995256] [<ffffffff80569a7f>] __down+0x18/0x1a
[ 127.995256] [<ffffffff8024f741>] down+0x39/0x4c
[ 127.995256] [<ffffffff803f358b>] __driver_attach+0x3e/0x8c
[ 127.995256] [<ffffffff803f354d>] ? __driver_attach+0x0/0x8c
[ 127.995256] [<ffffffff803f2d10>] bus_for_each_dev+0x4c/0x83
[ 127.995256] [<ffffffff803f32c3>] driver_attach+0x1c/0x1e
[ 127.995256] [<ffffffff803f260d>] bus_add_driver+0xb5/0x1ff
[ 127.995256] [<ffffffff803f37a0>] driver_register+0xa8/0x128
[ 127.995256] [<ffffffffa0059000>] ? acpi_button_init+0x0/0x5e [button]
[ 127.995256] [<ffffffff803c22f6>] acpi_bus_register_driver+0x3e/0x40
[ 127.995256] [<ffffffffa005903c>] acpi_button_init+0x3c/0x5e [button]
[ 127.995256] [<ffffffff80209058>] _stext+0x58/0x138
[ 127.995256] [<ffffffff8022f214>] ? get_parent_ip+0x11/0x41
[ 127.995256] [<ffffffff8056d949>] ? sub_preempt_count+0x35/0x49
[ 127.995256] [<ffffffff8056aa0d>] ? _spin_unlock_irqrestore+0x5e/0x6c
[ 127.995256] [<ffffffff8037b6e6>] ? __up_read+0x7c/0x85
[ 127.995256] [<ffffffff8024ef3b>] ? up_read+0x9/0xb
[ 127.995256] [<ffffffff8024fa3e>] ? __blocking_notifier_call_chain+0x58/0x6a
[ 127.995256] [<ffffffff8025f536>] sys_init_module+0xbd/0x1db
[ 127.995256] [<ffffffff8020bb3b>] system_call_fastpath+0x16/0x1b
[ 127.995256] modprobe D ffff88007e736440 5816 953 952
[ 127.995256] ffff88007e751958 0000000000000046 0000000000000040 000000447e7cd440
[ 127.995256] 0000000000000003 ffffffff8081e7c0 ffffffff8081e7c0 ffff88007e1e17f0
[ 127.995256] ffff88007e64d7f0 ffff88007e1e1b48 000000018037b061 ffff88007e1e1b48
[ 127.995256] Call Trace:
[ 127.995256] [<ffffffff80568bc6>] schedule_timeout+0x22/0xb4
[ 127.995256] [<ffffffff803b4d36>] ? acpi_ns_search_and_enter+0xbc/0x18a
[ 127.995256] [<ffffffff8022f214>] ? get_parent_ip+0x11/0x41
[ 127.995256] [<ffffffff8056d949>] ? sub_preempt_count+0x35/0x49
[ 127.995256] [<ffffffff805699de>] __down_common+0x9d/0xdf
[ 127.995256] [<ffffffff80569a31>] __down_timeout+0x11/0x13
[ 127.995256] [<ffffffff8024f639>] down_timeout+0x48/0x61
[ 127.995256] [<ffffffff803a5b1d>] acpi_os_wait_semaphore+0x49/0x58
[ 127.995256] [<ffffffff803be893>] acpi_ut_acquire_mutex+0x3e/0x82
[ 127.995256] [<ffffffff803b29ce>] acpi_ex_enter_interpreter+0xb/0x2b
[ 127.995256] [<ffffffff803b5840>] acpi_ns_evaluate+0x1ac/0x230
[ 127.995256] [<ffffffff803b52b4>] acpi_evaluate_object+0xfc/0x204
[ 127.995256] [<ffffffff802a3146>] ? kmem_cache_alloc+0xcd/0xec
[ 127.995256] [<ffffffff803a652e>] acpi_evaluate_integer+0x93/0xd1
[ 127.995256] [<ffffffff803c0762>] acpi_get_physical_pci_device+0x1e/0x53
[ 127.995256] [<ffffffffa00cd7ce>] acpi_video_bus_add+0x1bc/0xe0a [video]
[ 127.995256] [<ffffffff803c1f98>] acpi_device_probe+0x49/0x8c
[ 127.995256] [<ffffffff803f349f>] driver_probe_device+0xe7/0x195
[ 127.995256] [<ffffffff803f35af>] __driver_attach+0x62/0x8c
[ 127.995256] [<ffffffff803f354d>] ? __driver_attach+0x0/0x8c
[ 127.995256] [<ffffffff803f2d10>] bus_for_each_dev+0x4c/0x83
[ 127.995256] [<ffffffff803f32c3>] driver_attach+0x1c/0x1e
[ 127.995256] [<ffffffff803f260d>] bus_add_driver+0xb5/0x1ff
[ 127.995256] [<ffffffff803f37a0>] driver_register+0xa8/0x128
[ 127.995256] [<ffffffffa000c000>] ? acpi_video_init+0x0/0x5e [video]
[ 127.995256] [<ffffffff803c22f6>] acpi_bus_register_driver+0x3e/0x40
[ 127.995256] [<ffffffffa000c03c>] acpi_video_init+0x3c/0x5e [video]
[ 127.995256] [<ffffffff80209058>] _stext+0x58/0x138
[ 127.995256] [<ffffffff8022f214>] ? get_parent_ip+0x11/0x41
[ 127.995256] [<ffffffff8056d949>] ? sub_preempt_count+0x35/0x49
[ 127.995256] [<ffffffff8056aa0d>] ? _spin_unlock_irqrestore+0x5e/0x6c
[ 127.995256] [<ffffffff8037b6e6>] ? __up_read+0x7c/0x85
[ 127.995256] [<ffffffff8024ef3b>] ? up_read+0x9/0xb
[ 127.995256] [<ffffffff8024fa3e>] ? __blocking_notifier_call_chain+0x58/0x6a
[ 127.995256] [<ffffffff8025f536>] sys_init_module+0xbd/0x1db
[ 127.995256] [<ffffffff8020bb3b>] system_call_fastpath+0x16/0x1b
Eventually:
[ 204.927554] tpm_tis 00:0f: 1.2 TPM (device-id 0x1001, rev-id 2)
[ 266.745556] ACPI Error (psparse-0536): Method parse/execution failed [\SXX6] (Node ffff88007f851a98), AE_AML_INFINITE_LOOP
[ 266.756705] ACPI Error (psparse-0536): Method parse/execution failed [\SXX4] (Node ffff88007f851a58), AE_AML_INFINITE_LOOP
[ 266.768276] ACPI Error (psparse-0536): Method parse/execution failed [\SX11] (Node ffff88007f8519f8), AE_AML_INFINITE_LOOP
[ 266.779900] ACPI Error (psparse-0536): Method parse/execution failed [\_SB_.BIF_] (Node ffff88007f858458), AE_AML_INFINITE_LOOP
[ 266.791807] ACPI Error (psparse-0536): Method parse/execution failed [\_SB_.BAT0._BIF] (Node ffff88007f858398), AE_AML_INFINITE_LOOP
[ 266.803600] ACPI Exception (battery-0329): AE_AML_INFINITE_LOOP, ACPI: SSDT 7FE82138, 0244 (r1 PmRef Cpu0Ist 3000 INTL 20050624)
[ 266.810266] Evaluating _BIF [20080926]
[ 266.810279] ACPI: Battery Slot [BAT0] (battery present)
[ 266.810394] input: Lid Switch as /devices/LNXSYSTM:00/device:00/PNP0C0D:00/input/input5
[ 266.853545] ACPI: SSDT 7FE81EED, 01C6 (r1 PmRef Cpu0Cst 3001 INTL 20050624)
[ 415.384183] SysRq : Resetting
Enough was enough.
Yes, that would be my guess. I'll have to let other experts look at this.
>-----Original Message-----
>From: [email protected] [mailto:[email protected]]
>Sent: Wednesday, November 26, 2008 9:36 AM
>To: Moore, Robert
>Cc: [email protected]
>Subject: Re: 2.6.28-rc6-mmotm1126 - acpi AE_AM_INFINITE_LOOP errors..
>
>On Wed, 26 Nov 2008 08:24:26 PST, "Moore, Robert" said:
>>
>> You could try making the max loop count larger, it is a 32-bit value:
>>
>> acconfig.h
>>
>> /* Maximum number of While() loop iterations before forced abort */
>>
>> -#define ACPI_MAX_LOOP_ITERATIONS 0xFFFF
>> +#define ACPI_MAX_LOOP_ITERATIONS 0x00FFFFFF
>
>That "works", for some sub-optimal value of "works". It does indeed
>shut up *some* of the messages, but boot was taking *forever* (or more
>correctly,
>I gave up when it had taken more than 6 minutes to get through the initial
>udev and modprobe flurry that usually takes all of 12 seconds or less to
>complete.
>
>I'm suspecting that something *else* is busticated in the ACPI code, and
>loops that used to complete quickly are missing whatever terminating
>condition they had, and the new infinite loop detector is in fact tripping
>properly and catching the (newly introduced) error condition?
>
>
>...
>[ 13.042567] usb 3-1.2: configuration #1 chosen from 1 choice
>[ 13.130251] ieee1394: Host added: ID:BUS[0-00:1023]
>GUID[4a4fc0000fbe9561]
>[ 30.702013] Clocksource tsc unstable (delta = -6046116753 ns)
>[ 41.726022] thermal: gave up waiting for init of module processor.
>[ 41.741146] thermal: Unknown symbol acpi_processor_set_thermal_limit
>[ 41.760231] yenta_cardbus 0000:03:01.0: CardBus bridge found [1028:01cc]
>
>It went off to somewhere for 28 seconds trying to init the thermal code..
>
>[ 42.213146] vendor=8086 device=27d2
>[ 42.227850] iwl3945 0000:0c:00.0: PCI INT A disabled
>
>and went nappies here.. finally I did a alt-sysrq-t, and we're here, with
>several modprobes chugging along very slowly...
>
>[ 127.995256] modprobe D ffff88007edcb800 5176 862 861
>[ 127.995256] ffff88007ec639a8 0000000000000046 000000017e42f250
>ffffffff8079d7c0
>[ 127.995256] ffffffff8079d750 ffffffff8081e7c0 ffffffff8081e7c0
>ffff88007eddd7f0
>[ 127.995256] ffff88007f2677f0 ffff88007edddb48 000000008079ea80
>ffff88007edddb48
>[ 127.995256] Call Trace:
>[ 127.995256] [<ffffffff80283516>] ? __alloc_pages_internal+0x10d/0x493
>[ 127.995256] [<ffffffff8022f214>] ? get_parent_ip+0x11/0x41
>[ 127.995256] [<ffffffff80568bc6>] schedule_timeout+0x22/0xb4
>[ 127.995256] [<ffffffff8022f214>] ? get_parent_ip+0x11/0x41
>[ 127.995256] [<ffffffff8056d949>] ? sub_preempt_count+0x35/0x49
>[ 127.995256] [<ffffffff805699de>] __down_common+0x9d/0xdf
>[ 127.995256] [<ffffffff80569a31>] __down_timeout+0x11/0x13
>[ 127.995256] [<ffffffff8024f639>] down_timeout+0x48/0x61
>[ 127.995256] [<ffffffff803a5b1d>] acpi_os_wait_semaphore+0x49/0x58
>[ 127.995256] [<ffffffff803be893>] acpi_ut_acquire_mutex+0x3e/0x82
>[ 127.995256] [<ffffffff803b29ce>] acpi_ex_enter_interpreter+0xb/0x2b
>[ 127.995256] [<ffffffff803b5840>] acpi_ns_evaluate+0x1ac/0x230
>[ 127.995256] [<ffffffff803b52b4>] acpi_evaluate_object+0xfc/0x204
>[ 127.995256] [<ffffffff8038ebd3>] ? pci_get_subsys+0x7b/0x8f
>[ 127.995256] [<ffffffffa005fe16>] acpi_processor_start+0x1ba/0x78a
>[processor]
>[ 127.995256] [<ffffffff803c09b3>] acpi_start_single_object+0x2a/0x54
>[ 127.995256] [<ffffffff803c1fc7>] acpi_device_probe+0x78/0x8c
>[ 127.995256] [<ffffffff803f349f>] driver_probe_device+0xe7/0x195
>[ 127.995256] [<ffffffff803f35af>] __driver_attach+0x62/0x8c
>[ 127.995256] [<ffffffff803f354d>] ? __driver_attach+0x0/0x8c
>[ 127.995256] [<ffffffff803f2d10>] bus_for_each_dev+0x4c/0x83
>[ 127.995256] [<ffffffff803f32c3>] driver_attach+0x1c/0x1e
>[ 127.995256] [<ffffffff803f260d>] bus_add_driver+0xb5/0x1ff
>[ 127.995256] [<ffffffff803f37a0>] driver_register+0xa8/0x128
>[ 127.995256] [<ffffffffa006a000>] ? acpi_processor_init+0x0/0x10a
>[processor]
>[ 127.995256] [<ffffffff803c22f6>] acpi_bus_register_driver+0x3e/0x40
>[ 127.995256] [<ffffffffa006a097>] acpi_processor_init+0x97/0x10a
>[processor]
>[ 127.995256] [<ffffffff80209058>] _stext+0x58/0x138
>[ 127.995256] [<ffffffff8022f214>] ? get_parent_ip+0x11/0x41
>[ 127.995256] [<ffffffff8056d949>] ? sub_preempt_count+0x35/0x49
>[ 127.995256] [<ffffffff8056aa0d>] ? _spin_unlock_irqrestore+0x5e/0x6c
>[ 127.995256] [<ffffffff8037b6e6>] ? __up_read+0x7c/0x85
>[ 127.995256] [<ffffffff8024ef3b>] ? up_read+0x9/0xb
>[ 127.995256] [<ffffffff8024fa3e>] ?
>__blocking_notifier_call_chain+0x58/0x6a
>[ 127.995256] [<ffffffff8025f536>] sys_init_module+0xbd/0x1db
>[ 127.995256] [<ffffffff8020bb3b>] system_call_fastpath+0x16/0x1b
>
>[ 127.995256] modprobe R running task 5240 878 875
>[ 127.995256] ffff88007e4838a8 0000000000000046 0000007b8081bba8
>ffffffff803b21c5
>[ 127.995256] ffff88007e483858 ffffffff8081e7c0 ffffffff8081e7c0
>ffff88007e76d040
>[ 127.995256] ffff88007f2347f0 ffff88007e76d3a0 0000000100000000
>ffff88007e76d398
>[ 127.995256] Call Trace:
>[ 127.995256] [<ffffffff803b21c5>] ?
>acpi_ex_system_io_space_handler+0x0/0x44
>[ 127.995256] [<ffffffff802a2343>] ? __cache_free+0x41/0xf4
>[ 127.995256] [<ffffffff80568acb>] preempt_schedule_irq+0x4d/0x91
>[ 127.995256] [<ffffffff8020c676>] retint_kernel+0x26/0x30
>[ 127.995256] [<ffffffff803ae5ae>] ? acpi_ex_name_segment+0x5a/0xed
>[ 127.995256] [<ffffffff803ae5d3>] ? acpi_ex_name_segment+0x7f/0xed
>[ 127.995256] [<ffffffff803ae730>] acpi_ex_get_name_string+0xef/0x1ef
>[ 127.995256] [<ffffffff803a9463>] acpi_ds_create_operand+0x4e/0x209
>[ 127.995256] [<ffffffff803a9667>] acpi_ds_evaluate_name_path+0x49/0xff
>[ 127.995256] [<ffffffff803a832f>] acpi_ds_exec_end_op+0x94/0x3d9
>[ 127.995256] [<ffffffff803b8c1f>] acpi_ps_parse_loop+0x6d4/0x859
>[ 127.995256] [<ffffffff803b7e45>] acpi_ps_parse_aml+0x9a/0x2d9
>[ 127.995256] [<ffffffff803b93f2>] acpi_ps_execute_method+0x195/0x267
>[ 127.995256] [<ffffffff803b57e6>] acpi_ns_evaluate+0x152/0x230
>[ 127.995256] [<ffffffff803b52b4>] acpi_evaluate_object+0xfc/0x204
>[ 127.995256] [<ffffffffa0033403>] acpi_battery_update+0x9e/0x1df
>[battery]
>[ 127.995256] [<ffffffffa0033f25>] acpi_battery_add+0x8d/0x1d4 [battery]
>[ 127.995256] [<ffffffff803c1f98>] acpi_device_probe+0x49/0x8c
>[ 127.995256] [<ffffffff803f349f>] driver_probe_device+0xe7/0x195
>[ 127.995256] [<ffffffff803f35af>] __driver_attach+0x62/0x8c
>[ 127.995256] [<ffffffff803f354d>] ? __driver_attach+0x0/0x8c
>[ 127.995256] [<ffffffff803f2d10>] bus_for_each_dev+0x4c/0x83
>[ 127.995256] [<ffffffff803f32c3>] driver_attach+0x1c/0x1e
>[ 127.995256] [<ffffffff803f260d>] bus_add_driver+0xb5/0x1ff
>[ 127.995256] [<ffffffff803f37a0>] driver_register+0xa8/0x128
>[ 127.995256] [<ffffffffa0039000>] ? acpi_battery_init+0x0/0x45 [battery]
>[ 127.995256] [<ffffffff803c22f6>] acpi_bus_register_driver+0x3e/0x40
>[ 127.995256] [<ffffffffa003902a>] acpi_battery_init+0x2a/0x45 [battery]
>[ 127.995256] [<ffffffff80209058>] _stext+0x58/0x138
>[ 127.995256] [<ffffffff8022f214>] ? get_parent_ip+0x11/0x41
>[ 127.995256] [<ffffffff8056d949>] ? sub_preempt_count+0x35/0x49
>[ 127.995256] [<ffffffff8056aa0d>] ? _spin_unlock_irqrestore+0x5e/0x6c
>[ 127.995256] [<ffffffff8037b6e6>] ? __up_read+0x7c/0x85
>[ 127.995256] [<ffffffff8024ef3b>] ? up_read+0x9/0xb
>[ 127.995256] [<ffffffff8024fa3e>] ?
>__blocking_notifier_call_chain+0x58/0x6a
>[ 127.995256] [<ffffffff8025f536>] sys_init_module+0xbd/0x1db
>[ 127.995256] [<ffffffff8020bb3b>] system_call_fastpath+0x16/0x1b
>
>[ 127.995256] modprobe D ffff88007edcb440 5816 882 879
>[ 127.995256] ffff88007e433c08 0000000000000046 ffff88007e433b78
>ffffffff80376e5e
>[ 127.995256] ffff88007f411090 ffffffff8081e7c0 ffffffff8081e7c0
>ffff88007e4a57f0
>[ 127.995256] ffff88007e7627f0 ffff88007e4a5b48 000000007e433b98
>ffff88007e4a5b48
>[ 127.995256] Call Trace:
>[ 127.995256] [<ffffffff80376e5e>] ? _atomic_dec_and_lock+0x5a/0x98
>[ 127.995256] [<ffffffff802f9623>] ? sysfs_addrm_finish+0x69/0x255
>[ 127.995256] [<ffffffff802f90e6>] ? __sysfs_get+0x4d/0x58
>[ 127.995256] [<ffffffff802f9266>] ? __sysfs_add_one+0x63/0x9e
>[ 127.995256] [<ffffffff80568bc6>] schedule_timeout+0x22/0xb4
>[ 127.995256] [<ffffffff802f993f>] ? create_dir+0x63/0x87
>[ 127.995256] [<ffffffff8022f214>] ? get_parent_ip+0x11/0x41
>[ 127.995256] [<ffffffff8056d949>] ? sub_preempt_count+0x35/0x49
>[ 127.995256] [<ffffffff805699de>] __down_common+0x9d/0xdf
>[ 127.995256] [<ffffffff803f354d>] ? __driver_attach+0x0/0x8c
>[ 127.995256] [<ffffffff80569a7f>] __down+0x18/0x1a
>[ 127.995256] [<ffffffff8024f741>] down+0x39/0x4c
>[ 127.995256] [<ffffffff803f358b>] __driver_attach+0x3e/0x8c
>[ 127.995256] [<ffffffff803f354d>] ? __driver_attach+0x0/0x8c
>[ 127.995256] [<ffffffff803f2d10>] bus_for_each_dev+0x4c/0x83
>[ 127.995256] [<ffffffff803f32c3>] driver_attach+0x1c/0x1e
>[ 127.995256] [<ffffffff803f260d>] bus_add_driver+0xb5/0x1ff
>[ 127.995256] [<ffffffff803f37a0>] driver_register+0xa8/0x128
>[ 127.995256] [<ffffffffa0059000>] ? acpi_button_init+0x0/0x5e [button]
>[ 127.995256] [<ffffffff803c22f6>] acpi_bus_register_driver+0x3e/0x40
>[ 127.995256] [<ffffffffa005903c>] acpi_button_init+0x3c/0x5e [button]
>[ 127.995256] [<ffffffff80209058>] _stext+0x58/0x138
>[ 127.995256] [<ffffffff8022f214>] ? get_parent_ip+0x11/0x41
>[ 127.995256] [<ffffffff8056d949>] ? sub_preempt_count+0x35/0x49
>[ 127.995256] [<ffffffff8056aa0d>] ? _spin_unlock_irqrestore+0x5e/0x6c
>[ 127.995256] [<ffffffff8037b6e6>] ? __up_read+0x7c/0x85
>[ 127.995256] [<ffffffff8024ef3b>] ? up_read+0x9/0xb
>[ 127.995256] [<ffffffff8024fa3e>] ?
>__blocking_notifier_call_chain+0x58/0x6a
>[ 127.995256] [<ffffffff8025f536>] sys_init_module+0xbd/0x1db
>[ 127.995256] [<ffffffff8020bb3b>] system_call_fastpath+0x16/0x1b
>
>[ 127.995256] modprobe D ffff88007e736440 5816 953 952
>[ 127.995256] ffff88007e751958 0000000000000046 0000000000000040
>000000447e7cd440
>[ 127.995256] 0000000000000003 ffffffff8081e7c0 ffffffff8081e7c0
>ffff88007e1e17f0
>[ 127.995256] ffff88007e64d7f0 ffff88007e1e1b48 000000018037b061
>ffff88007e1e1b48
>[ 127.995256] Call Trace:
>[ 127.995256] [<ffffffff80568bc6>] schedule_timeout+0x22/0xb4
>[ 127.995256] [<ffffffff803b4d36>] ? acpi_ns_search_and_enter+0xbc/0x18a
>[ 127.995256] [<ffffffff8022f214>] ? get_parent_ip+0x11/0x41
>[ 127.995256] [<ffffffff8056d949>] ? sub_preempt_count+0x35/0x49
>[ 127.995256] [<ffffffff805699de>] __down_common+0x9d/0xdf
>[ 127.995256] [<ffffffff80569a31>] __down_timeout+0x11/0x13
>[ 127.995256] [<ffffffff8024f639>] down_timeout+0x48/0x61
>[ 127.995256] [<ffffffff803a5b1d>] acpi_os_wait_semaphore+0x49/0x58
>[ 127.995256] [<ffffffff803be893>] acpi_ut_acquire_mutex+0x3e/0x82
>[ 127.995256] [<ffffffff803b29ce>] acpi_ex_enter_interpreter+0xb/0x2b
>[ 127.995256] [<ffffffff803b5840>] acpi_ns_evaluate+0x1ac/0x230
>[ 127.995256] [<ffffffff803b52b4>] acpi_evaluate_object+0xfc/0x204
>[ 127.995256] [<ffffffff802a3146>] ? kmem_cache_alloc+0xcd/0xec
>[ 127.995256] [<ffffffff803a652e>] acpi_evaluate_integer+0x93/0xd1
>[ 127.995256] [<ffffffff803c0762>] acpi_get_physical_pci_device+0x1e/0x53
>[ 127.995256] [<ffffffffa00cd7ce>] acpi_video_bus_add+0x1bc/0xe0a [video]
>[ 127.995256] [<ffffffff803c1f98>] acpi_device_probe+0x49/0x8c
>[ 127.995256] [<ffffffff803f349f>] driver_probe_device+0xe7/0x195
>[ 127.995256] [<ffffffff803f35af>] __driver_attach+0x62/0x8c
>[ 127.995256] [<ffffffff803f354d>] ? __driver_attach+0x0/0x8c
>[ 127.995256] [<ffffffff803f2d10>] bus_for_each_dev+0x4c/0x83
>[ 127.995256] [<ffffffff803f32c3>] driver_attach+0x1c/0x1e
>[ 127.995256] [<ffffffff803f260d>] bus_add_driver+0xb5/0x1ff
>[ 127.995256] [<ffffffff803f37a0>] driver_register+0xa8/0x128
>[ 127.995256] [<ffffffffa000c000>] ? acpi_video_init+0x0/0x5e [video]
>[ 127.995256] [<ffffffff803c22f6>] acpi_bus_register_driver+0x3e/0x40
>[ 127.995256] [<ffffffffa000c03c>] acpi_video_init+0x3c/0x5e [video]
>[ 127.995256] [<ffffffff80209058>] _stext+0x58/0x138
>[ 127.995256] [<ffffffff8022f214>] ? get_parent_ip+0x11/0x41
>[ 127.995256] [<ffffffff8056d949>] ? sub_preempt_count+0x35/0x49
>[ 127.995256] [<ffffffff8056aa0d>] ? _spin_unlock_irqrestore+0x5e/0x6c
>[ 127.995256] [<ffffffff8037b6e6>] ? __up_read+0x7c/0x85
>[ 127.995256] [<ffffffff8024ef3b>] ? up_read+0x9/0xb
>[ 127.995256] [<ffffffff8024fa3e>] ?
>__blocking_notifier_call_chain+0x58/0x6a
>[ 127.995256] [<ffffffff8025f536>] sys_init_module+0xbd/0x1db
>[ 127.995256] [<ffffffff8020bb3b>] system_call_fastpath+0x16/0x1b
>
>Eventually:
>
>[ 204.927554] tpm_tis 00:0f: 1.2 TPM (device-id 0x1001, rev-id 2)
>[ 266.745556] ACPI Error (psparse-0536): Method parse/execution failed
>[\SXX6] (Node ffff88007f851a98), AE_AML_INFINITE_LOOP
>[ 266.756705] ACPI Error (psparse-0536): Method parse/execution failed
>[\SXX4] (Node ffff88007f851a58), AE_AML_INFINITE_LOOP
>[ 266.768276] ACPI Error (psparse-0536): Method parse/execution failed
>[\SX11] (Node ffff88007f8519f8), AE_AML_INFINITE_LOOP
>[ 266.779900] ACPI Error (psparse-0536): Method parse/execution failed
>[\_SB_.BIF_] (Node ffff88007f858458), AE_AML_INFINITE_LOOP
>[ 266.791807] ACPI Error (psparse-0536): Method parse/execution failed
>[\_SB_.BAT0._BIF] (Node ffff88007f858398), AE_AML_INFINITE_LOOP
>[ 266.803600] ACPI Exception (battery-0329): AE_AML_INFINITE_LOOP, ACPI:
>SSDT 7FE82138, 0244 (r1 PmRef Cpu0Ist 3000 INTL 20050624)
>[ 266.810266] Evaluating _BIF [20080926]
>[ 266.810279] ACPI: Battery Slot [BAT0] (battery present)
>[ 266.810394] input: Lid Switch as
>/devices/LNXSYSTM:00/device:00/PNP0C0D:00/input/input5
>[ 266.853545] ACPI: SSDT 7FE81EED, 01C6 (r1 PmRef Cpu0Cst 3001 INTL
>20050624)
>[ 415.384183] SysRq : Resetting
>
>Enough was enough.
On Wed, 26 Nov 2008 12:35:50 EST, [email protected] said:
Adding come cc:s, and a quick recap for those who didn't see it before:
Dell Latitude D820 laptop, x86_64 kernel, and Robert Moore's patch to detect
infinite looping in the ACPI interpreter is tripping, apparently because
something else in ACPI changed and caused loops that used to terminate to
instead hang now. I've been able to narrow it down to something that
hit the linux-next tree between 11/17 (was good in mmotm1117) and 11/26.
> On Wed, 26 Nov 2008 08:24:26 PST, "Moore, Robert" said:
> >
> > You could try making the max loop count larger, it is a 32-bit value:
> >
> > acconfig.h
> >
> > /* Maximum number of While() loop iterations before forced abort */
> >
> > -#define ACPI_MAX_LOOP_ITERATIONS 0xFFFF
> > +#define ACPI_MAX_LOOP_ITERATIONS 0x00FFFFFF
>
> That "works", for some sub-optimal value of "works". It does indeed
> shut up *some* of the messages, but boot was taking *forever* (or more correctly,
> I gave up when it had taken more than 6 minutes to get through the initial
> udev and modprobe flurry that usually takes all of 12 seconds or less to
> complete.
>
> I'm suspecting that something *else* is busticated in the ACPI code, and
> loops that used to complete quickly are missing whatever terminating
> condition they had, and the new infinite loop detector is in fact tripping
> properly and catching the (newly introduced) error condition?
I'm still seeing this in -rc7-mmotm1203. For mmotm1126, I bisected it down
to almost certainly being in linux-next already, which makes me wonder why
I'm apparently the only person seeing it.
Here's one of the hanging ACPI calls again:
[ 127.995256] modprobe D ffff88007edcb800 5176 862 861
[ 127.995256] ffff88007ec639a8 0000000000000046 000000017e42f250 ffffffff8079d7c0
[ 127.995256] ffffffff8079d750 ffffffff8081e7c0 ffffffff8081e7c0 ffff88007eddd7f0
[ 127.995256] ffff88007f2677f0 ffff88007edddb48 000000008079ea80 ffff88007edddb48
[ 127.995256] Call Trace:
[ 127.995256] [<ffffffff80283516>] ? __alloc_pages_internal+0x10d/0x493
[ 127.995256] [<ffffffff8022f214>] ? get_parent_ip+0x11/0x41
[ 127.995256] [<ffffffff80568bc6>] schedule_timeout+0x22/0xb4
[ 127.995256] [<ffffffff8022f214>] ? get_parent_ip+0x11/0x41
[ 127.995256] [<ffffffff8056d949>] ? sub_preempt_count+0x35/0x49
[ 127.995256] [<ffffffff805699de>] __down_common+0x9d/0xdf
[ 127.995256] [<ffffffff80569a31>] __down_timeout+0x11/0x13
[ 127.995256] [<ffffffff8024f639>] down_timeout+0x48/0x61
[ 127.995256] [<ffffffff803a5b1d>] acpi_os_wait_semaphore+0x49/0x58
[ 127.995256] [<ffffffff803be893>] acpi_ut_acquire_mutex+0x3e/0x82
[ 127.995256] [<ffffffff803b29ce>] acpi_ex_enter_interpreter+0xb/0x2b
[ 127.995256] [<ffffffff803b5840>] acpi_ns_evaluate+0x1ac/0x230
[ 127.995256] [<ffffffff803b52b4>] acpi_evaluate_object+0xfc/0x204
[ 127.995256] [<ffffffff8038ebd3>] ? pci_get_subsys+0x7b/0x8f
[ 127.995256] [<ffffffffa005fe16>] acpi_processor_start+0x1ba/0x78a [processor]
[ 127.995256] [<ffffffff803c09b3>] acpi_start_single_object+0x2a/0x54
[ 127.995256] [<ffffffff803c1fc7>] acpi_device_probe+0x78/0x8c
[ 127.995256] [<ffffffff803f349f>] driver_probe_device+0xe7/0x195
[ 127.995256] [<ffffffff803f35af>] __driver_attach+0x62/0x8c
[ 127.995256] [<ffffffff803f354d>] ? __driver_attach+0x0/0x8c
[ 127.995256] [<ffffffff803f2d10>] bus_for_each_dev+0x4c/0x83
[ 127.995256] [<ffffffff803f32c3>] driver_attach+0x1c/0x1e
[ 127.995256] [<ffffffff803f260d>] bus_add_driver+0xb5/0x1ff
[ 127.995256] [<ffffffff803f37a0>] driver_register+0xa8/0x128
[ 127.995256] [<ffffffffa006a000>] ? acpi_processor_init+0x0/0x10a [processor]
[ 127.995256] [<ffffffff803c22f6>] acpi_bus_register_driver+0x3e/0x40
[ 127.995256] [<ffffffffa006a097>] acpi_processor_init+0x97/0x10a [processor]
[ 127.995256] [<ffffffff80209058>] _stext+0x58/0x138
[ 127.995256] [<ffffffff8022f214>] ? get_parent_ip+0x11/0x41
[ 127.995256] [<ffffffff8056d949>] ? sub_preempt_count+0x35/0x49
[ 127.995256] [<ffffffff8056aa0d>] ? _spin_unlock_irqrestore+0x5e/0x6c
[ 127.995256] [<ffffffff8037b6e6>] ? __up_read+0x7c/0x85
[ 127.995256] [<ffffffff8024ef3b>] ? up_read+0x9/0xb
[ 127.995256] [<ffffffff8024fa3e>] ? __blocking_notifier_call_chain+0x58/0x6a
[ 127.995256] [<ffffffff8025f536>] sys_init_module+0xbd/0x1db
[ 127.995256] [<ffffffff8020bb3b>] system_call_fastpath+0x16/0x1b
A sampling of the errors I get:
[ 7.334948] ACPI Error (psparse-0536): Method parse/execution failed [\SMI_] (Node ffff88007f851238), AE_AML_INFINITE_LOOP
[ 7.334987] ACPI Error (psparse-0536): Method parse/execution failed [\_SB_.AC__._PSR] (Node ffff88007f8584b8), AE_AML_INFINITE_LOOP
[ 7.335030] ACPI Exception (ac-0135): AE_AML_INFINITE_LOOP, Error reading AC Adapter state [20081031]
[ 8.421295] ACPI Error (psparse-0536): Method parse/execution failed [\SMI_] (Node ffff88007f851238), AE_AML_INFINITE_LOOP
[ 8.421331] ACPI Error (psparse-0536): Method parse/execution failed [\_SB_.AC__._PSR] (Node ffff88007f8584b8), AE_AML_INFINITE_LOOP
[ 8.421364] ACPI Exception (ac-0135): AE_AML_INFINITE_LOOP, Error reading AC Adapter state [20081031]
[ 9.601269] ACPI Error (psparse-0536): Method parse/execution failed [\SMI_] (Node ffff88007f851238), AE_AML_INFINITE_LOOP
[ 9.601305] ACPI Error (psparse-0536): Method parse/execution failed [\_SB_.AC__._PSR] (Node ffff88007f8584b8), AE_AML_INFINITE_LOOP
[ 9.601339] ACPI Exception (ac-0135): AE_AML_INFINITE_LOOP, Error reading AC Adapter state [20081031]
[ 10.688615] ACPI Error (psparse-0536): Method parse/execution failed [\SMI_] (Node ffff88007f851238), AE_AML_INFINITE_LOOP
... About 3,300 skipped..
[ 1330.394509] ACPI Exception (battery-0368): AE_AML_INFINITE_LOOP, Evaluating _BST [20081031]
[ 1331.365827] ACPI Error (psparse-0536): Method parse/execution failed [\SXX6] (Node ffff88007f851a98), AE_AML_INFINITE_LOOP
[ 1331.365864] ACPI Error (psparse-0536): Method parse/execution failed [\SXX4] (Node ffff88007f851a58), AE_AML_INFINITE_LOOP
[ 1331.365894] ACPI Error (psparse-0536): Method parse/execution failed [\SX11] (Node ffff88007f8519f8), AE_AML_INFINITE_LOOP
[ 1331.365923] ACPI Error (psparse-0536): Method parse/execution failed [\_SB_.BAT0._BST] (Node ffff88007f858378), AE_AML_INFINITE_LOOP
[ 1332.321972] ACPI Error (psparse-0536): Method parse/execution failed [\SMI_] (Node ffff88007f851238), AE_AML_INFINITE_LOOP
[ 1332.322004] ACPI Error (psparse-0536): Method parse/execution failed [\_SB_.PCI0.AGP_.VID_._DOS] (Node ffff88007f85def8), AE_AML_INFINITE_LOOP
[ 1332.322042] ACPI Exception (battery-0368): AE_AML_INFINITE_LOOP, Evaluating _BST [20081031]
[ 1333.338699] ACPI Error (psparse-0536): Method parse/execution failed [\SMI_] (Node ffff88007f851238), AE_AML_INFINITE_LOOP
[ 1333.338738] ACPI Error (psparse-0536): Method parse/execution failed [\_SB_.PCI0.VID_._DOS] (Node ffff88007f85dbf8), AE_AML_INFINITE_LOOP
The executive summary:
% grep 'ACPI E' messages-20081204 | cut -c54- | sort | uniq -c
29 ACPI Error (psparse-0536): Method parse/execution failed [\SMI_] (Node ffff88007f851238), AE_AML_INFINITE_LOOP
646 ACPI Error (psparse-0536): Method parse/execution failed [\SX11] (Node ffff88007f8519f8), AE_AML_INFINITE_LOOP
646 ACPI Error (psparse-0536): Method parse/execution failed [\SXX4] (Node ffff88007f851a58), AE_AML_INFINITE_LOOP
646 ACPI Error (psparse-0536): Method parse/execution failed [\SXX6] (Node ffff88007f851a98), AE_AML_INFINITE_LOOP
13 ACPI Error (psparse-0536): Method parse/execution failed [\_SB_.AC__._PSR] (Node ffff88007f8584b8), AE_AML_INFINITE_LOOP
440 ACPI Error (psparse-0536): Method parse/execution failed [\_SB_.BAT0._BST] (Node ffff88007f858378), AE_AML_INFINITE_LOOP
6 ACPI Error (psparse-0536): Method parse/execution failed [\_SB_.PCI0.AGP_.VID_._DOS] (Node ffff88007f85def8), AE_AML_INFINITE_LOOP
6 ACPI Error (psparse-0536): Method parse/execution failed [\_SB_.PCI0.PCIE.GDCK._STA] (Node ffff88007f85f678), AE_AML_INFINITE_LOOP
4 ACPI Error (psparse-0536): Method parse/execution failed [\_SB_.PCI0.VID_._DOS] (Node ffff88007f85dbf8), AE_AML_INFINITE_LOOP
206 ACPI Error (psparse-0536): Method parse/execution failed [\_TZ_.THM_.GINF] (Node ffff88007f858858), AE_AML_INFINITE_LOOP
206 ACPI Error (psparse-0536): Method parse/execution failed [\_TZ_.THM_._TMP] (Node ffff88007f858878), AE_AML_INFINITE_LOOP
13 ACPI Exception (ac-0135): AE_AML_INFINITE_LOOP, Error reading AC Adapter state [20081031]
440 ACPI Exception (battery-0368): AE_AML_INFINITE_LOOP, Evaluating _BST [20081031]
It isn't just one thing - looks like the battery, video, the sensor for AC/battery,
and the thermal sensor, and probably other stuff, are all affected.
I've got a -mmotm1203 kernel built with CONFIG_ACPI_DEBUG and CONFIG_ACPI_FUNCTION_TRACE
but discovered that booting with log_buf_len=64m still wasn't enough for
even one or two ACPI calls if you turn on *all* the debugging. Anybody have
a good suggestion on what logging levels and layers I should be starting with?