#9563 NORM 1.5-F11: unexpected data interrupt after libertas suspend/resume

Zarro Boogs per Child bugtracker at laptop.org
Tue Oct 27 16:50:11 EDT 2009


#9563: unexpected data interrupt after libertas suspend/resume
--------------------------+-------------------------------------------------
 Reporter:  pgf           |                 Owner:               
     Type:  defect        |                Status:  new          
 Priority:  normal        |             Milestone:  1.5-F11      
Component:  not assigned  |               Version:  not specified
 Keywords:                |           Next_action:  never set    
 Verified:  0             |   Deployment_affected:               
Blockedby:                |              Blocking:               
--------------------------+-------------------------------------------------
 did "echo mem > /sys/power/state" on B2 with no wlan ECO applied.

 got this on the second suspend/resume after booting:

 {{{
 [root at localhost dev]# echo mem > /sys/power/state
 [  159.185335] PM: Syncing filesystems ... done.
 [  163.609199] PM: Preparing system for mem sleep
 [  163.614302] Freezing user space processes ... (elapsed 0.00 seconds)
 done.
 [  163.623410] Freezing remaining freezable tasks ... (elapsed 0.00
 seconds) done.
 [  163.631379] PM: Entering mem sleep
 [  163.635574] dcon_source_switch to DCON
 [  164.130117] olpc-dcon: Timeout entering DCON mode; expect a screen
 glitch.
 [  164.137445] olpc-dcon: The DCON has control
 [  164.142080] HDA Intel 0000:00:14.0: PCI INT A disabled
 [  164.160118] ehci_hcd 0000:00:10.4: PCI INT D disabled
 [  164.165541] ehci_hcd 0000:00:10.4: PME# disabled
 [  164.190120] uhci_hcd 0000:00:10.2: PCI INT C disabled
 [  164.195538] uhci_hcd 0000:00:10.2: PME# disabled
 [  164.220117] uhci_hcd 0000:00:10.1: PCI INT B disabled
 [  164.225531] uhci_hcd 0000:00:10.1: PME# disabled
 [  164.250116] uhci_hcd 0000:00:10.0: PCI INT A disabled
 [  164.255536] uhci_hcd 0000:00:10.0: PME# disabled
 [  164.280228] pata_via 0000:00:0f.0: PCI INT A disabled
 [  164.300162] mmc_suspend_host: turning off power on mmc0
 [  164.305734] sdhci_set_ios: power off for mmc0 from b0646688
 [  164.310138] sdhci_set_power: new power value = 0
 [  164.316622] mmc_suspend_host: turning off power on mmc1
 [  164.322195] sdhci_set_ios: power off for mmc1 from b0646688
 [  164.328122] mmc_suspend_host: turning off power on mmc2
 [  164.333711] sdhci_set_ios: power off for mmc2 from b0646688
 [  164.339650] sdhci_set_power: new power value = 0
 [  164.344651] sdhci-pci 0000:00:0c.0: PME# disabled
 [  164.360220] ACPI handle has no context!
 [  164.384982] ACPI: Preparing to enter system sleep state S3
 +r[  164.490027] Back to C!
 [  164.490027] ACPI: Waking up from system sleep state S3
 [  164.490027] sdhci-pci 0000:00:0c.0: restoring config space at offset
 0xf (was 0x100, writing 0x10a)
 [  164.507175] sdhci-pci 0000:00:0c.0: restoring config space at offset
 0x6 (was 0x0, writing 0x80002000)
 [  164.517094] sdhci-pci 0000:00:0c.0: restoring config space at offset
 0x5 (was 0x0, writing 0x80001000)
 [  164.527028] sdhci-pci 0000:00:0c.0: restoring config space at offset
 0x4 (was 0x0, writing 0x80000000)
 [  164.536953] sdhci-pci 0000:00:0c.0: restoring config space at offset
 0x1 (was 0x2100000, writing 0x2100006)
 [  164.547350] pata_via 0000:00:0f.0: restoring config space at offset 0xf
 (was 0x100, writing 0x109)
 [  164.556926] pata_via 0000:00:0f.0: restoring config space at offset 0x8
 (was 0xcc01, writing 0x8021)
 [  164.566659] pata_via 0000:00:0f.0: restoring config space at offset 0x7
 (was 0x375, writing 0x8019)
 [  164.576314] pata_via 0000:00:0f.0: restoring config space at offset 0x6
 (was 0x171, writing 0x8011)
 [  164.585969] pata_via 0000:00:0f.0: restoring config space at offset 0x5
 (was 0x3f5, writing 0x8009)
 [  164.595624] pata_via 0000:00:0f.0: restoring config space at offset 0x4
 (was 0x1f1, writing 0x8001)
 [  164.605278] pata_via 0000:00:0f.0: restoring config space at offset 0x3
 (was 0x0, writing 0x2000)
 [  164.614744] pata_via 0000:00:0f.0: restoring config space at offset 0x1
 (was 0x2900000, writing 0x2900005)
 [  164.640044] uhci_hcd 0000:00:10.0: restoring config space at offset 0xf
 (was 0x100, writing 0x10a)
 [  164.649594] uhci_hcd 0000:00:10.0: restoring config space at offset 0x3
 (was 0x800000, writing 0x802000)
 [  164.659717] uhci_hcd 0000:00:10.0: restoring config space at offset 0x1
 (was 0x2100000, writing 0x2100003)
 [  164.690037] uhci_hcd 0000:00:10.1: restoring config space at offset 0xf
 (was 0x200, writing 0x209)
 [  164.699589] uhci_hcd 0000:00:10.1: restoring config space at offset 0x3
 (was 0x800000, writing 0x802000)
 [  164.709703] uhci_hcd 0000:00:10.1: restoring config space at offset 0x1
 (was 0x2100000, writing 0x2100003)
 [  164.730032] uhci_hcd 0000:00:10.2: restoring config space at offset 0xf
 (was 0x300, writing 0x30b)
 [  164.739594] uhci_hcd 0000:00:10.2: restoring config space at offset 0x3
 (was 0x800000, writing 0x802000)
 [  164.749701] uhci_hcd 0000:00:10.2: restoring config space at offset 0x1
 (was 0x2100000, writing 0x2100003)
 [  164.770040] ehci_hcd 0000:00:10.4: restoring config space at offset 0xf
 (was 0x400, writing 0x40a)
 [  164.779602] ehci_hcd 0000:00:10.4: restoring config space at offset 0x3
 (was 0x800000, writing 0x802008)
 [  164.789725] ehci_hcd 0000:00:10.4: restoring config space at offset 0x1
 (was 0x2100000, writing 0x2100012)
 [  164.800086] pci 0000:00:11.7: restoring config space at offset 0x3 (was
 0x0, writing 0x2000)
 [  164.809091] HDA Intel 0000:00:14.0: restoring config space at offset
 0xf (was 0x100, writing 0x109)
 [  164.818761] HDA Intel 0000:00:14.0: restoring config space at offset
 0x4 (was 0x4, writing 0x80004004)
 [  164.828681] HDA Intel 0000:00:14.0: restoring config space at offset
 0x1 (was 0x100000, writing 0x100002)
 [  164.942318] pci 0000:00:00.0: wake-up capability disabled by ACPI
 [  164.948940] viafb 0000:00:01.0: PCI INT A -> GSI 16 (level, low) -> IRQ
 16
 [  164.956365] sdhci-pci 0000:00:0c.0: setting latency timer to 64
 [  164.962724] sdhci_set_power: new power value = 14
 [  165.137830] sdhci-pci 0000:00:0c.0: setting latency timer to 64
 [  165.144204] sdhci-pci 0000:00:0c.0: setting latency timer to 64
 [  165.150525] sdhci_set_power: new power value = 14
 [  165.165572] sdhci_set_power: new power value = 14
 [  165.203231] sdhci_set_ios: power off for mmc1 from b0646688
 [  165.209167] sdhci_set_power: new power value = 0
 [  165.230827] mmc2: error -110 during resume (card was removed?)
 [  165.237329] mmc2: card 0001 removed
 [  165.241267] sdhci_set_ios: power off for mmc2 from b0646688
 [  165.247190] sdhci_set_power: new power value = 0
 [  165.252198] pata_via 0000:00:0f.0: PCI INT A -> GSI 21 (level, low) ->
 IRQ 21
 [  165.266225] sdhci_set_power: new power value = 14
 [  165.281384] uhci_hcd 0000:00:10.0: PME# disabled
 [  165.286327] uhci_hcd 0000:00:10.0: PCI INT A -> GSI 20 (level, low) ->
 IRQ 20
 [  165.293962] uhci_hcd 0000:00:10.0: PME# disabled
 [  165.298900] usb usb2: root hub lost power or was reset
 [  165.305470] uhci_hcd 0000:00:10.1: PME# disabled
 [  165.310402] uhci_hcd 0000:00:10.1: PCI INT B -> GSI 22 (level, low) ->
 IRQ 22
 [  165.318004] uhci_hcd 0000:00:10.1: PME# disabled
 [  165.323982] usb usb3: root hub lost power or was reset
 [  165.331531] uhci_hcd 0000:00:10.2: PME# disabled
 [  165.336473] uhci_hcd 0000:00:10.2: PCI INT C -> GSI 21 (level, low) ->
 IRQ 21
 [  165.344117] uhci_hcd 0000:00:10.2: PME# disabled
 [  165.349078] usb usb4: root hub lost power or was reset
 [  165.354637] ehci_hcd 0000:00:10.4: PME# disabled
 [  165.359578] ehci_hcd 0000:00:10.4: PCI INT D -> GSI 23 (level, low) ->
 IRQ 23
 [  165.367211] ehci_hcd 0000:00:10.4: PME# disabled
 [  165.372191] pci 0000:00:11.0: PME# disabled
 [  165.376729] HDA Intel 0000:00:14.0: PCI INT A -> GSI 17 (level, low) ->
 IRQ 17
 [  165.384455] HDA Intel 0000:00:14.0: setting latency timer to 64
 [  165.414299] dcon_source_switch to CPU
 [  165.428853] mmc2: new SDIO card at address 0001
 [  165.433751] mmc mmc2:0001: parent mmc2 should not be sleeping
 [  165.443786] libertas_sdio mmc2:0001:1: firmware: requesting
 sd8686_helper.bin
 [  165.451440] olpc-dcon: scanline interrupt w/CPU
 [  165.465022] PM: Finishing wakeup.
 [  165.468569] olpc-dcon: scanline interrupt w/CPU
 [  165.473421] Restarting tasks ... <7>olpc-dcon: scanline interrupt w/CPU
 [  165.499847] done.
 [root at localhost dev]# [  165.504814] olpc-dcon: scanline interrupt w/CPU
 [  165.524688] olpc-dcon: scanline interrupt w/CPU
 [  165.544557] olpc-dcon: scanline interrupt w/CPU
 [  165.564460] olpc-dcon: scanline interrupt w/CPU
 [  165.584297] olpc-dcon: scanline interrupt w/CPU
 [  165.604166] olpc-dcon: scanline interrupt w/CPU
 [  165.624032] olpc-dcon: scanline interrupt w/CPU
 [  165.644200] olpc-dcon: scanline interrupt w/CPU
 [  165.743255] olpc-dcon: scanline interrupt w/CPU
 [  165.763125] olpc-dcon: scanline interrupt w/CPU
 [  165.882576] olpc-dcon: scanline interrupt w/CPU
 [  165.922412] olpc-dcon: scanline interrupt w/CPU
 [  165.962323] olpc-dcon: scanline interrupt w/CPU
 [  166.220121] olpc-dcon: scanline interrupt w/CPU
 [  166.420105] olpc-dcon:  Timeout entering CPU mode; expect a screen
 glitch.
 [  166.430904] olpc-dcon: The CPU has control
 [  166.838541] input: PS/2 Synaptics TouchPad as
 /devices/platform/i8042/serio1/input/input7
 [  168.434086] mmc2: Got data interrupt 0x00100000 even though no data
 operation was in progress.
 [  168.443250] sdhci: ============== REGISTER DUMP ==============
 [  168.444062] sdhci: Sys addr: 0x00000000 | Version:  0x00000000
 [  168.444062] sdhci: Blk size: 0x00007040 | Blk cnt:  0x00000001
 [  168.444062] sdhci: Argument: 0x80022000 | Trn mode: 0x00000003
 [  168.444062] sdhci: Present:  0x01e70001 | Host ctl: 0x00000003
 [  168.444062] sdhci: Power:    0x0000000f | Blk gap:  0x00000000
 [  168.444062] sdhci: Wake-up:  0x00000000 | Clock:    0x00000107
 [  168.444062] sdhci: Timeout:  0x0000000d | Int stat: 0x00000000
 [  168.444062] sdhci: Int enab: 0x00ff01cb | Sig enab: 0x00ff01cb
 [  168.444062] sdhci: AC12 err: 0x00000000 | Slot int: 0x00000000
 [  168.444062] sdhci: Caps:     0x056030b0 | Max curr: 0x00f001f0
 [  168.444062] sdhci: ===========================================

 [root at localhost dev]#
 [root at localhost dev]#
 [root at localhost dev]#
 [root at localhost dev]#
 [root at localhost dev]# [  177.030104] mmc2: Timeout waiting for hardware
 interrupt.
 [  177.035860] sdhci: ============== REGISTER DUMP ==============
 [  177.040086] sdhci: Sys addr: 0x00000000 | Version:  0x00000000
 [  177.040086] sdhci: Blk size: 0x00007040 | Blk cnt:  0x00000001
 [  177.040086] sdhci: Argument: 0x80022000 | Trn mode: 0x00000003
 [  177.040086] sdhci: Present:  0x01e70001 | Host ctl: 0x00000003
 [  177.040086] sdhci: Power:    0x0000000f | Blk gap:  0x00000000
 [  177.040086] sdhci: Wake-up:  0x00000000 | Clock:    0x00000107
 [  177.040086] sdhci: Timeout:  0x0000000d | Int stat: 0x00000000
 [  177.040086] sdhci: Int enab: 0x00ff01cb | Sig enab: 0x00ff01cb
 [  177.040086] sdhci: AC12 err: 0x00000000 | Slot int: 0x00000000
 [  177.040086] sdhci: Caps:     0x056030b0 | Max curr: 0x00f001f0
 [  177.040086] sdhci: ===========================================
 [  177.111509] libertas: failed to load helper firmware
 [  178.508687] mmc2: Got data interrupt 0x00100000 even though no data
 operation was in progress.
 [  178.517859] sdhci: ============== REGISTER DUMP ==============
 [  178.518665] sdhci: Sys addr: 0x00000000 | Version:  0x00000000
 [  178.518665] sdhci: Blk size: 0x00007040 | Blk cnt:  0x00000001
 [  178.518665] sdhci: Argument: 0x00000800 | Trn mode: 0x00000003
 [  178.518665] sdhci: Present:  0x01e70001 | Host ctl: 0x00000003
 [  178.518665] sdhci: Power:    0x0000000f | Blk gap:  0x00000000
 [  178.518665] sdhci: Wake-up:  0x00000000 | Clock:    0x00000107
 [  178.518665] sdhci: Timeout:  0x0000000d | Int stat: 0x00000000
 [  178.518665] sdhci: Int enab: 0x00ff00cb | Sig enab: 0x00ff00cb
 [  178.518665] sdhci: AC12 err: 0x00000000 | Slot int: 0x00000000
 [  178.518665] sdhci: Caps:     0x056030b0 | Max curr: 0x00f001f0
 [  178.518665] sdhci: ===========================================
 [  187.110103] mmc2: Timeout waiting for hardware interrupt.
 [  187.115853] sdhci: ============== REGISTER DUMP ==============
 [  187.120089] sdhci: Sys addr: 0x00000000 | Version:  0x00000000
 [  187.120089] sdhci: Blk size: 0x00007040 | Blk cnt:  0x00000001
 [  187.120089] sdhci: Argument: 0x00000800 | Trn mode: 0x00000003
 [  187.120089] sdhci: Present:  0x01e70001 | Host ctl: 0x00000003
 [  187.120089] sdhci: Power:    0x0000000f | Blk gap:  0x00000000
 [  187.120089] sdhci: Wake-up:  0x00000000 | Clock:    0x00000107
 [  187.120089] sdhci: Timeout:  0x0000000d | Int stat: 0x00000000
 [  187.120089] sdhci: Int enab: 0x00ff00cb | Sig enab: 0x00ff00cb
 [  187.120089] sdhci: AC12 err: 0x00000000 | Slot int: 0x00000000
 [  187.120089] sdhci: Caps:     0x056030b0 | Max curr: 0x00f001f0
 [  187.120089] sdhci: ===========================================
 [  188.588674] mmc2: Got data interrupt 0x00100000 even though no data
 operation was in progress.
 [  188.597845] sdhci: ============== REGISTER DUMP ==============
 [  188.598654] sdhci: Sys addr: 0x00000000 | Version:  0x00000000
 [  188.598654] sdhci: Blk size: 0x00007040 | Blk cnt:  0x00000001
 [  188.598654] sdhci: Argument: 0x00000400 | Trn mode: 0x00000003
 [  188.598654] sdhci: Present:  0x01e70001 | Host ctl: 0x00000003
 [  188.598654] sdhci: Power:    0x0000000f | Blk gap:  0x00000000
 [  188.598654] sdhci: Wake-up:  0x00000000 | Clock:    0x00000107
 [  188.598654] sdhci: Timeout:  0x0000000d | Int stat: 0x00000000
 [  188.598654] sdhci: Int enab: 0x00ff00cb | Sig enab: 0x00ff00cb
 [  188.598654] sdhci: AC12 err: 0x00000000 | Slot int: 0x00000000
 [  188.598654] sdhci: Caps:     0x056030b0 | Max curr: 0x00f001f0
 [  188.598654] sdhci: ===========================================
 [  197.190099] mmc2: Timeout waiting for hardware interrupt.
 [  197.195856] sdhci: ============== REGISTER DUMP ==============
 [  197.200086] sdhci: Sys addr: 0x00000000 | Version:  0x00000000
 [  197.200086] sdhci: Blk size: 0x00007040 | Blk cnt:  0x00000001
 [  197.200086] sdhci: Argument: 0x00000400 | Trn mode: 0x00000003
 [  197.200086] sdhci: Present:  0x01e70001 | Host ctl: 0x00000003
 [  197.200086] sdhci: Power:    0x0000000f | Blk gap:  0x00000000
 [  197.200086] sdhci: Wake-up:  0x00000000 | Clock:    0x00000107
 [  197.200086] sdhci: Timeout:  0x0000000d | Int stat: 0x00000000
 [  197.200086] sdhci: Int enab: 0x00ff00cb | Sig enab: 0x00ff00cb
 [  197.200086] sdhci: AC12 err: 0x00000000 | Slot int: 0x00000000
 [  197.200086] sdhci: Caps:     0x056030b0 | Max curr: 0x00f001f0
 [  197.200086] sdhci: ===========================================
 [  197.271591] libertas_sdio: probe of mmc2:0001:1 failed with error -110
 [  198.668688] mmc2: Got data interrupt 0x00100000 even though no data
 operation was in progress.
 [  198.677855] sdhci: ============== REGISTER DUMP ==============
 [  198.678666] sdhci: Sys addr: 0x00000000 | Version:  0x00000000
 [  198.678666] sdhci: Blk size: 0x00007040 | Blk cnt:  0x00000001
 [  198.678666] sdhci: Argument: 0x00000400 | Trn mode: 0x00000003
 [  198.678666] sdhci: Present:  0x01e70000 | Host ctl: 0x00000002
 [  198.678666] sdhci: Power:    0x0000000f | Blk gap:  0x00000000
 [  198.678666] sdhci: Wake-up:  0x00000000 | Clock:    0x00000107
 [  198.678666] sdhci: Timeout:  0x0000000d | Int stat: 0x00000000
 [  198.678666] sdhci: Int enab: 0x00ff00cb | Sig enab: 0x00ff00cb
 [  198.678666] sdhci: AC12 err: 0x00000000 | Slot int: 0x00000000
 [  198.678666] sdhci: Caps:     0x056030b0 | Max curr: 0x00f001f0
 [  198.678666] sdhci: ===========================================

 }}}

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


More information about the Bugs mailing list