#12030 NORM 12.1.0: "irq 22: nobody cared" when going into suspend

[Zarro Boogs per Child]

#12030: "irq 22: nobody cared" when going into suspend
--------------------+-------------------------------------------------------
 Reporter:  dsd     |                 Owner:  dsd          
     Type:  defect  |                Status:  new          
 Priority:  normal  |             Milestone:  12.1.0       
Component:  kernel  |               Version:  not specified
 Keywords:          |           Next_action:  diagnose     
 Verified:  0       |   Deployment_affected:               
Blockedby:          |              Blocking:               
--------------------+-------------------------------------------------------
 Sam caught:

 {{{
 [ 3197.485974] PM: Syncing filesystems ...
 [ 3199.589460] done.
 [ 3199.591509] Freezing user space processes ... (elapsed 0.01 seconds)
 done.
 [ 3199.612752] Freezing remaining freezable tasks ...
 [ 3199.630048] (elapsed 0.01 seconds) done.
 [ 3199.634494] dcon_source_switch to DCON
 [ 3199.678995] olpc-dcon: The DCON has control
 [ 3199.683678] i8042 kbd 00:04: wake-up capability enabled by ACPI
 [ 3199.689660] i8042 aux 00:03: wake-up capability disabled by ACPI
 [ 3199.698000] libertas_sdio mmc1:0001:1: mmc1:0001:1: suspend: PM flags =
 0x3
 [ 3199.705071] libertas_sdio mmc1:0001:1: Suspend without wake params --
 powering down card
 [ 3199.713988] viafb_suspend!
 [ 3199.720780] mmc1: card 0001 removed
 [ 3205.023176] irq 22: nobody cared (try booting with the "irqpoll"
 option)
 [ 3205.029983] Pid: 0, comm: swapper Not tainted
 3.3.8_xo1.5-20120727.1114.olpc.1268f69 #1
 [ 3205.033166] Call Trace:
 [ 3205.033166]  [<b045728b>] __report_bad_irq+0x19/0xa3
 [ 3205.033166]  [<b0457480>] note_interrupt+0x12e/0x1ae
 [ 3205.033166]  [<b0455fbe>] handle_irq_event_percpu+0x13d/0x176
 [ 3205.033166]  [<b0456028>] handle_irq_event+0x31/0x49
 [ 3205.033166]  [<b0457b33>] ? handle_level_irq+0x92/0x92
 [ 3205.033166]  [<b0457ba0>] handle_fasteoi_irq+0x6d/0x9a
 [ 3205.033166]  <IRQ>  [<b0403170>] ? do_IRQ+0x37/0x8a
 [ 3205.033166]  [<b07385a9>] ? common_interrupt+0x29/0x30
 [ 3205.033166]  [<b05aa963>] ? acpi_idle_enter_simple+0x101/0x13a
 [ 3205.033166]  [<b0624f39>] ? cpuidle_idle_call+0xc0/0x1be
 [ 3205.033166]  [<b040159d>] ? cpu_idle+0x2f/0x58
 [ 3205.033166]  [<b0725287>] ? rest_init+0x57/0x59
 [ 3205.033166]  [<b08a166e>] ? start_kernel+0x291/0x296
 [ 3205.033166]  [<b08a1078>] ? i386_start_kernel+0x78/0x7d
 [ 3205.033166] handlers:
 [ 3205.033166] [<b05eeab8>] usb_hcd_irq
 [ 3205.033166] Disabling IRQ #22
 [ 3214.040024] mmc2: Timeout waiting for hardware interrupt.
 [ 3214.045486] ------------[ cut here ]------------
 [ 3214.050014] WARNING: at drivers/mmc/host/sdhci.c:962
 sdhci_send_command+0x2b/0x87e()
 [ 3214.050014] Hardware name: XO
 [ 3214.050014] Modules linked in: mousedev syscopyarea sysfillrect
 sysimgblt fb_sys_fops sisusbvga serio_raw psmouse libertas_sdio via_camera
 videobuf_dma_sg libertas videobuf_core [last unloaded: udlfb]
 [ 3214.050014] Pid: 0, comm: swapper Not tainted
 3.3.8_xo1.5-20120727.1114.olpc.1268f69 #1
 [ 3214.050014] Call Trace:
 [ 3214.050014]  [<b0419354>] warn_slowpath_common+0x68/0x7d
 [ 3214.050014]  [<b0632828>] ? sdhci_send_command+0x2b/0x87e
 [ 3214.050014]  [<b041937d>] warn_slowpath_null+0x14/0x18
 [ 3214.050014]  [<b0632828>] sdhci_send_command+0x2b/0x87e
 [ 3214.050014]  [<b0633774>] sdhci_finish_data+0x213/0x236
 [ 3214.050014]  [<b06338eb>] sdhci_timeout_timer+0x5e/0xa6
 [ 3214.050014]  [<b04227b1>] run_timer_softirq+0x16c/0x264
 [ 3214.050014]  [<b063388d>] ? sdhci_finish_command+0xf6/0xf6
 [ 3214.050014]  [<b041dc7a>] __do_softirq+0x73/0x14f
 [ 3214.050014]  [<b041dc07>] ?
 ftrace_define_fields_irq_handler_entry+0x45/0x45
 [ 3214.050014]  <IRQ>  [<b041de7a>] ? irq_exit+0x39/0x86
 [ 3214.050014]  [<b04031b0>] ? do_IRQ+0x77/0x8a
 [ 3214.050014]  [<b07385a9>] ? common_interrupt+0x29/0x30
 [ 3214.050014]  [<b05aa963>] ? acpi_idle_enter_simple+0x101/0x13a
 [ 3214.050014]  [<b0624f39>] ? cpuidle_idle_call+0xc0/0x1be
 [ 3214.050014]  [<b040159d>] ? cpu_idle+0x2f/0x58
 [ 3214.050014]  [<b0725287>] ? rest_init+0x57/0x59
 [ 3214.050014]  [<b08a166e>] ? start_kernel+0x291/0x296
 [ 3214.050014]  [<b08a1078>] ? i386_start_kernel+0x78/0x7d
 [ 3214.050014] ---[ end trace cb5175d539b25875 ]---
 [ 3224.060024] mmc2: Timeout waiting for hardware interrupt.
 [ 3234.080027] mmc2: Timeout waiting for hardware interrupt.
 [ 3234.085531] mmcblk0: error -110 sending status command, retrying
 [ 3244.120019] mmc2: Timeout waiting for hardware interrupt.
 [ 3244.125490] mmcblk0: error -110 sending status command, retrying
 [ 3254.160022] mmc2: Timeout waiting for hardware interrupt.
 [ 3254.165504] mmcblk0: error -110 sending status command, aborting
 [ 3254.171581] end_request: I/O error, dev mmcblk0, sector 1764144
 [ 3254.177561] end_request: I/O error, dev mmcblk0, sector 1764152
 [ 3254.181568] end_request: I/O error, dev mmcblk0, sector 1764160
 [ 3254.181568] end_request: I/O error, dev mmcblk0, sector 1764168
 [ 3254.181568] end_request: I/O error, dev mmcblk0, sector 1764176
 [ 3254.181568] end_request: I/O error, dev mmcblk0, sector 1764184
 [ 3264.220026] mmc2: Timeout waiting for hardware interrupt.
 }}}

 This is strange. IRQ 22 is normally uchi_hcd, mmc0, mmc1 and mmc2.
 It suggests that libertas going into power-down is somehow removing the
 IRQ handler at the mmc layer.

-- 
Ticket URL: <http://dev.laptop.org/ticket/12030>
One Laptop Per Child <http://laptop.org/>
OLPC bug tracking system