2006-10-06 07:20:07

by Martin Lorenz

[permalink] [raw]
Subject: 2.6.19-rc1 lost ACPI events after suspend

*Waving hallo again*

After partially solving one problem another occurs...

With my most recent kernel

commit d223a60106891bfe46febfacf46b20cd8509aaad
tree ca81ba555de7a9a68605ef98f13fbc027439cdd2
parent 77dc2db6d1d2703ee4e83d4b3dbecf4e06a910e6
author Linus Torvalds <[email protected]> Wed, 04 Oct 2006 19:57:05 -0700

with additional patch tp_smapi 0.30

I can suspend exactly twice. will say, after second wakeup I don't get
any ACPI events. I already searched for error messages in the logs and
found this:

Oct 6 08:49:09 gimli kernel: [45058.156000] ACPI Exception (evxface-0545):
AE_BAD_PARAMETER, Removing notify handler [20060707]

it occurs on unloading ibm_acpi module after resume
reloading ibm_acpi dosen't change anything

one message that is in the log for the first suspend/resume but not in the
second is
- Breaking affinity for irq 219

and in the second I see this, which I guess has nothing to do with my
problem...

+ e1000: eth0: e1000_watchdog: NIC Link is Down
+ BUG: warning at drivers/pci/msi.c:680/pci_enable_msi()
+ [<c0103bbd>] dump_trace+0x69/0x1af
+ [<c0103d1b>] show_trace_log_lvl+0x18/0x2c
+ [<c01043ba>] show_trace+0xf/0x11
+ [<c01044bd>] dump_stack+0x15/0x17
+ [<c0208d36>] pci_enable_msi+0x78/0x22e
+ [<c025808b>] e1000_open+0x64/0x176
+ [<c029b281>] dev_open+0x2b/0x62
+ [<c0299d8f>] dev_change_flags+0x47/0xe4
+ [<c02cde47>] devinet_ioctl+0x252/0x556
+ [<c0290ffa>] sock_ioctl+0x19e/0x1c2
+ [<c01697df>] do_ioctl+0x1f/0x62
+ [<c0169a67>] vfs_ioctl+0x245/0x257
+ [<c0169ac5>] sys_ioctl+0x4c/0x67
+ [<c0102da7>] syscall_call+0x7/0xb
+ DWARF2 unwinder stuck at syscall_call+0x7/0xb
+
+ Leftover inexact backtrace:
+
+ =======================

the complete logs are attached


gruss
mlo
--
Dipl.-Ing. Martin Lorenz

They that can give up essential liberty
to obtain a little temporary safety
deserve neither liberty nor safety.
Benjamin Franklin

please encrypt your mail to me
GnuPG key-ID: F1AAD37D
get it here:
http://blackhole.pca.dfn.de:11371/pks/lookup?op=get&search=0xF1AAD37D

ICQ UIN: 33588107


2006-10-06 07:40:36

by Martin Lorenz

[permalink] [raw]
Subject: Re: 2.6.19-rc1 lost ACPI events after suspend

after a little more playing around:


I unloaded all acpi-related modules (still without having rebooted)
# for a in ibm_acpi ac battery button dock fan i2c_ec processor thermal video container; do modprobe -r $a; done
FATAL: Module processor is in use.

than restarting acpid which triggers loading of all modules
I get this in the log:

Oct 6 09:29:10 gimli kernel: [47459.529000] ACPI Exception (evxface-0545): AE_BAD_PARAMETER, Removing notify handler [20060707]
Oct 6 09:29:36 gimli kernel: [47485.131000] BUG: warning at fs/proc/generic.c:732/remove_proc_entry()
Oct 6 09:29:36 gimli kernel: [47485.131000] [<c0103bbd>] dump_trace+0x69/0x1af
Oct 6 09:29:36 gimli kernel: [47485.131000] [<c0103d1b>] show_trace_log_lvl+0x18/0x2c
Oct 6 09:29:36 gimli kernel: [47485.131000] [<c01043ba>] show_trace+0xf/0x11
Oct 6 09:29:36 gimli kernel: [47485.131000] [<c01044bd>] dump_stack+0x15/0x17
Oct 6 09:29:36 gimli kernel: [47485.131000] [<c018a1e0>] remove_proc_entry+0x142/0x19c
Oct 6 09:29:36 gimli kernel: [47485.131000] [<f928d09a>] acpi_video_bus_remove_fs+0x65/0x73 [video]
Oct 6 09:29:36 gimli kernel: [47485.131000] [<f928d469>] acpi_video_bus_remove+0x3a/0x55 [video]
Oct 6 09:29:36 gimli kernel: [47485.131000] [<c022910e>] acpi_bus_unregister_driver+0x3a/0x9f
Oct 6 09:29:36 gimli kernel: [47485.132000] [<f928e82e>] acpi_video_exit+0xa/0x1a [video]
Oct 6 09:29:36 gimli kernel: [47485.132000] [<c0139006>] sys_delete_module+0x17f/0x1a5
Oct 6 09:29:36 gimli kernel: [47485.132000] [<c0102da7>] syscall_call+0x7/0xb
Oct 6 09:29:36 gimli kernel: [47485.132000] DWARF2 unwinder stuck at syscall_call+0x7/0xb
Oct 6 09:29:36 gimli kernel: [47485.132000]
Oct 6 09:29:36 gimli kernel: [47485.132000] Leftover inexact backtrace:
Oct 6 09:29:36 gimli kernel: [47485.132000]
Oct 6 09:29:36 gimli kernel: [47485.132000] [<c02e007b>] unix_dgram_connect+0x13f/0x140
Oct 6 09:29:36 gimli kernel: [47485.132000] =======================
Oct 6 09:29:54 gimli kernel: [47503.375000] ibm_acpi: IBM ThinkPad ACPI Extras v0.12a
Oct 6 09:29:54 gimli kernel: [47503.375000] ibm_acpi:
http://ibm-acpi.sf.net/
Oct 6 09:29:55 gimli kernel: [47503.932000] ACPI: AC Adapter [AC] (on-line)
Oct 6 09:30:01 gimli kernel: [47510.250000] ACPI: Battery Slot [BAT0] (battery present)
Oct 6 09:30:01 gimli kernel: [47510.267000] ACPI: Power Button (FF) [PWRF]
Oct 6 09:30:01 gimli kernel: [47510.267000] ACPI: Lid Switch [LID]
Oct 6 09:30:01 gimli kernel: [47510.267000] ACPI: Sleep Button (CM) [SLPB]
Oct 6 09:30:01 gimli kernel: [47510.282000] ACPI: ACPI Dock Station Driver
Oct 6 09:30:02 gimli kernel: [47510.944000] ACPI: Thermal Zone [THM0] (47 C)
Oct 6 09:30:02 gimli kernel: [47510.946000] ACPI: Thermal Zone [THM1] (50 C)
Oct 6 09:30:02 gimli kernel: [47510.965000] ACPI: Video Device [VID] (multi-head: yes rom: no post: no)
Oct 6 09:30:02 gimli kernel: [47510.966000] ACPI: Video Device [VID] (multi-head: yes rom: no post: no)


more info

# cat /proc/acpi/ibm/hotkey
status: enabled
mask: 0xff9f
commands: enable, disable, reset, <mask>

# cat /proc/acpi/ibm/ecdump
EC +00 +01 +02 +03 +04 +05 +06 +07 +08 +09 +0a +0b +0c +0d +0e +0f
EC 0x00: *a7 *05 *a0 *41 *fe *96 00 00 *1f *02 *47 00 00 00 *80 00
EC 0x10: 00 00 *ff *ff *f4 *3c *87 *09 *43 *ff *83 *01 *ff *ff *2d 00
EC 0x20: 00 *09 00 00 *4b 00 00 00 00 00 00 00 *89 00 00 *80
EC 0x30: *03 *07 *02 00 *30 *04 *04 00 *85 00 *30 *10 *2a *50 00 00
EC 0x40: 00 00 00 00 00 00 *14 *01 *0a 00 00 00 00 00 *04 00
EC 0x50: 00 *c0 *02 *23 *41 00 *3c *2d *03 *3f *2d *06 *25 *23 *30 00
EC 0x60: *80 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
EC 0x70: 00 00 00 00 00 *12 *30 *80 *2f *2c *80 *2d *1f *80 *1d *80
EC 0x80: 00 00 00 *06 *82 *0b *03 00 00 00 *0e *78 00 00 00 00
EC 0x90: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
EC 0xa0: *e7 *08 *bd *0a *ff *ff *53 00 00 00 *6d *3f *ff *ff *c0 00
EC 0xb0: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
EC 0xc0: *2b *29 *80 *80 *80 *80 *80 *80 00 *41 00 00 00 00 *10 00
EC 0xd0: 06 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
EC 0xe0: 00 00 00 00 00 00 00 00 10 80 a6 04 24 2e 55 03
EC 0xf0: 37 42 48 54 33 35 57 57 0c f5 60 7c 0c e7 63 93


and now the log from two suspend/resume cycles attached (my fingers were
faster than my braon on the last message)

thanks
mlo

On Fri, Oct 06, 2006 at 09:18:12AM +0200, Dipl.-Ing. Martin Lorenz wrote:
> *Waving hallo again*
>
> After partially solving one problem another occurs...
>
> With my most recent kernel
>
> commit d223a60106891bfe46febfacf46b20cd8509aaad
> tree ca81ba555de7a9a68605ef98f13fbc027439cdd2
> parent 77dc2db6d1d2703ee4e83d4b3dbecf4e06a910e6
> author Linus Torvalds <[email protected]> Wed, 04 Oct 2006 19:57:05 -0700
>
> with additional patch tp_smapi 0.30
>
> I can suspend exactly twice. will say, after second wakeup I don't get
> any ACPI events. I already searched for error messages in the logs and
> found this:
>
> Oct 6 08:49:09 gimli kernel: [45058.156000] ACPI Exception (evxface-0545):
> AE_BAD_PARAMETER, Removing notify handler [20060707]
>
> it occurs on unloading ibm_acpi module after resume
> reloading ibm_acpi dosen't change anything
>
> one message that is in the log for the first suspend/resume but not in the
> second is
> - Breaking affinity for irq 219
>
> and in the second I see this, which I guess has nothing to do with my
> problem...
>
> + e1000: eth0: e1000_watchdog: NIC Link is Down
> + BUG: warning at drivers/pci/msi.c:680/pci_enable_msi()
> + [<c0103bbd>] dump_trace+0x69/0x1af
> + [<c0103d1b>] show_trace_log_lvl+0x18/0x2c
> + [<c01043ba>] show_trace+0xf/0x11
> + [<c01044bd>] dump_stack+0x15/0x17
> + [<c0208d36>] pci_enable_msi+0x78/0x22e
> + [<c025808b>] e1000_open+0x64/0x176
> + [<c029b281>] dev_open+0x2b/0x62
> + [<c0299d8f>] dev_change_flags+0x47/0xe4
> + [<c02cde47>] devinet_ioctl+0x252/0x556
> + [<c0290ffa>] sock_ioctl+0x19e/0x1c2
> + [<c01697df>] do_ioctl+0x1f/0x62
> + [<c0169a67>] vfs_ioctl+0x245/0x257
> + [<c0169ac5>] sys_ioctl+0x4c/0x67
> + [<c0102da7>] syscall_call+0x7/0xb
> + DWARF2 unwinder stuck at syscall_call+0x7/0xb
> +
> + Leftover inexact backtrace:
> +
> + =======================
>
> the complete logs are attached
>
>
> gruss
> mlo
> --
> Dipl.-Ing. Martin Lorenz
>
> They that can give up essential liberty
> to obtain a little temporary safety
> deserve neither liberty nor safety.
> Benjamin Franklin
>
> please encrypt your mail to me
> GnuPG key-ID: F1AAD37D
> get it here:
> http://blackhole.pca.dfn.de:11371/pks/lookup?op=get&search=0xF1AAD37D
>
> ICQ UIN: 33588107
> -
> To unsubscribe from this list: send the line "unsubscribe linux-kernel" in
> the body of a message to [email protected]
> More majordomo info at http://vger.kernel.org/majordomo-info.html
> Please read the FAQ at http://www.tux.org/lkml/

gruss
mlo
--
Dipl.-Ing. Martin Lorenz

They that can give up essential liberty
to obtain a little temporary safety
deserve neither liberty nor safety.
Benjamin Franklin

please encrypt your mail to me
GnuPG key-ID: F1AAD37D
get it here:
http://blackhole.pca.dfn.de:11371/pks/lookup?op=get&search=0xF1AAD37D

ICQ UIN: 33588107


Attachments:
(No filename) (7.30 kB)
messages_2suspends (17.75 kB)
Download all attachments