#4322 NORM Never A: Kernel crash while suspending
Zarro Boogs per Child
bugtracker at laptop.org
Fri Oct 19 10:50:49 EDT 2007
#4322: Kernel crash while suspending
--------------------+-------------------------------------------------------
Reporter: wad | Owner: jg
Type: defect | Status: new
Priority: normal | Milestone: Never Assigned
Component: distro | Version:
Keywords: | Verified: 0
--------------------+-------------------------------------------------------
A laptop, running q2c28k and build 581, crashed after 8000+ cycles while
running the wake-on-ping suspend/resume rest.
serial number SHF73300118.
The log of the crash is attached, starting with a complete cycle w. no
error, then the crash:
+r[ 0.006593] olpc-ec: running cmd 0x24
[ 0.011385] olpc-ec: running cmd 0x24
[ 0.027721] rtc_cmos rtc_cmos: EARLY resume
[ 0.031927] platform powerbutton: EARLY resume
[ 0.036388] platform lid: EARLY resume
[ 0.040157] pci 0000:00:01.0: EARLY resume
[ 0.044275] lxfb 0000:00:01.1: EARLY resume
[ 0.048477] Geode LX AES 0000:00:01.2: EARLY resume
[ 0.053371] CAFÉ NAND 0000:00:0c.0: EARLY resume
[ 0.058093] sdhci 0000:00:0c.1: EARLY resume
[ 0.062383] cafe1000-ccic 0000:00:0c.2: EARLY resume
[ 0.067365] pci 0000:00:0f.0: EARLY resume
[ 0.071481] cs5535audio 0000:00:0f.3: EARLY resume
[ 0.076290] ohci_hcd 0000:00:0f.4: EARLY resume
[ 0.080840] ehci_hcd 0000:00:0f.5: EARLY resume
[ 0.085390] serial8250 serial8250.0: EARLY resume
[ 0.090110] platform pcspkr: EARLY resume
[ 0.094140] platform vesafb.0: EARLY resume
[ 0.098360] serial8250 serial8250: EARLY resume
[ 0.102912] i8042 i8042: EARLY resume
[ 0.106590] platform dcon: EARLY resume
[ 0.110447] platform olpc-battery.0: EARLY resume
[ 0.115474] olpc-ec: running cmd 0x84
[ 0.122255] olpc-ec: received 0x20
[ 0.125854] olpc-pm: SCI 0x20 received
[ 0.129708] olpc-ec: running cmd 0x84
[ 0.135487] olpc-ec: received 0x0
[ 0.139004] olpc-pm: SCI 0x0 received
[ 0.142780] PM: Finishing wakeup.
[ 0.146210] rtc_cmos rtc_cmos: resuming
[ 0.150064] platform powerbutton: resuming
[ 0.154257] platform lid: resuming
[ 0.157679] pci 0000:00:01.0: resuming
[ 0.161572] lxfb 0000:00:01.1: resuming
[ 0.215202] Geode LX AES 0000:00:01.2: resuming
[ 0.236457] CAFÉ NAND 0000:00:0c.0: resuming
[ 0.257701] sdhci 0000:00:0c.1: resuming
[ 0.285457] PM: Writing back config space on device 0000:00:0c.1 at
offset f (was 8080100, writing 808010b)
[ 0.331213] PM: Writing back config space on device 0000:00:0c.1 at
offset 3 (was 800000, writing 802000)
[ 0.379362] PM: Writing back config space on device 0000:00:0c.1 at
offset 1 (was 2b00002, writing 2b00006)
[ 0.430481] cafe1000-ccic 0000:00:0c.2: resuming
[ 0.460213] PM: Writing back config space on device 0000:00:0c.2 at
offset f (was 8080100, writing 808010b)
[ 0.515899] PM: Writing back config space on device 0000:00:0c.2 at
offset 3 (was 800000, writing 802000)
[ 0.572063] PM: Writing back config space on device 0000:00:0c.2 at
offset 1 (was 2b00002, writing 2b00006)
[ 0.636321] pci 0000:00:0f.0: resuming
[ 0.665154] cs5535audio 0000:00:0f.3: resuming
[ 0.694785] PCI: Setting latency timer of device 0000:00:0f.3 to 64
[ 0.731250] ohci_hcd 0000:00:0f.4: resuming
[ 0.768271] PCI: Setting latency timer of device 0000:00:0f.4 to 64
[ 0.830839] ehci_hcd 0000:00:0f.5: resuming
[ 0.868351] PCI: Setting latency timer of device 0000:00:0f.5 to 64
[ 0.902017] usb usb1: root hub lost power or was reset
[ 0.934685] PCI: cache line size of 32 is not supported by device
0000:00:0f.5
[ 1.030521] serial8250 serial8250.0: resuming
[ 1.063182] platform pcspkr: resuming
[ 1.095113] platform vesafb.0: resuming
[ 1.127123] serial8250 serial8250: resuming
[ 1.159614] ov7670 0-0042: resuming
[ 1.191312] usb usb1: resuming
[ 1.378610] hub 1-0:1.0: resuming
[ 1.410269] usb usb2: resuming
[ 1.441489] usb usb2: root hub lost power or was reset
[ 1.700556] hub 2-0:1.0: resuming
[ 1.732173] usb 1-1: resuming
[ 1.763204] usb_reset_device!
[ 1.850419] hub_port_wait_reset: portstatus=503 portchange=10
[ 1.949309] usb 1-1: reset high speed USB device using ehci_hcd and
address 2
[ 2.050026] hub_port_wait_reset: portstatus=503 portchange=10
[ 2.181418] USB_REQ_SET_CONFIGURATION!
[ 2.215311] PM: Removing info for No Bus:usbdev1.2_ep02
[ 2.249997] PM: Removing info for No Bus:usbdev1.2_ep83
[ 2.284399] PM: Adding info for No Bus:usbdev1.2_ep02
[ 2.317482] PM: Adding info for No Bus:usbdev1.2_ep83
[ 2.351061] usb8xxx 1-1:1.0: resuming
[ 2.380904] i8042 i8042: resuming
[ 2.418428] atkbd serio0: resuming
[ 2.446436] psmouse serio1: resuming
[ 2.528946] OLPC-DCON 1-000d: resuming
[ 2.581416] platform dcon: resuming
[ 2.607952] platform olpc-battery.0: resuming
[ 2.635160] olpc-dcon: The CPU has control
[ 2.661044] ac97 0-0:AD1888: resuming
[ 2.685784] Restarting tasks ... done.
[ 2.828643] PM: Preparing system for mem sleep
[ 2.853914] Stopping tasks ... done.
[ 2.879351] ac97 0-0:AD1888: suspend
[ 2.902696] platform olpc-battery.0: suspend
[ 2.926576] platform dcon: suspend
[ 2.949105] OLPC-DCON 1-000d: suspend
[ 2.997823] olpc-dcon: The DCON has control
[ 3.020631] psmouse serio1: suspend
[ 3.042278] atkbd serio0: suspend
[ 3.063212] i8042 i8042: suspend
[ 3.083897] usb8xxx 1-1:1.0: suspend
[ 3.105075] usb 1-1: suspend
[ 3.128212] hub 2-0:1.0: suspend
[ 3.148678] usb usb2: suspend, may wakeup
[ 3.170079] hub 1-0:1.0: suspend
[ 3.190629] usb usb1: suspend, may wakeup
[ 3.212551] ov7670 0-0042: suspend
[ 3.233410] serial8250 serial8250: suspend
[ 3.255030] platform vesafb.0: suspend
[ 3.276309] platform pcspkr: suspend
[ 3.297390] serial8250 serial8250.0: suspend
[ 3.319080] ehci_hcd 0000:00:0f.5: suspend
[ 3.346929] ohci_hcd 0000:00:0f.4: suspend
[ 3.374309] cs5535audio 0000:00:0f.3: suspend
[ 3.395058] pci 0000:00:0f.0: suspend
[ 3.414542] cafe1000-ccic 0000:00:0c.2: suspend
[ 3.436411] sdhci 0000:00:0c.1: suspend
[ 3.470431] CAFÉ NAND 0000:00:0c.0: suspend
[ 3.491197] Geode LX AES 0000:00:01.2: suspend
[ 3.511968] lxfb 0000:00:01.1: suspend
[ 3.533202] pci 0000:00:01.0: suspend
[ 3.536902] platform lid: suspend, may wakeup
[ 3.541365] platform powerbutton: suspend, may wakeup
[ 3.546436] rtc_cmos rtc_cmos: suspend, may wakeup
[ 3.551349] PM: Entering mem sleep
[ 3.554776] platform olpc-battery.0: LATE suspend
[ 3.559500] platform dcon: LATE suspend
[ 3.563360] serial8250 serial8250: LATE suspend
[ 3.567915] platform vesafb.0: LATE suspend
[ 3.572117] platform pcspkr: LATE suspend
[ 3.576144] serial8250 serial8250.0: LATE suspend
[ 3.580868] cs5535audio 0000:00:0f.3: LATE suspend
[ 3.585675] pci 0000:00:0f.0: LATE suspend
[ 3.589792] cafe1000-ccic 0000:00:0c.2: LATE suspend
[ 3.594774] sdhci 0000:00:0c.1: LATE suspend
[ 3.599064] CAFÉ NAND 0000:00:0c.0: LATE suspend
[ 3.603786] Geode LX AES 0000:00:01.2: LATE suspend
[ 3.608681] pci 0000:00:01.0: LATE suspend
[ 3.612797] platform lid: LATE suspend, may wakeup
[ 3.617605] platform powerbutton: LATE suspend, may wakeup
[ 3.623109] rtc_cmos rtc_cmos: LATE suspend, may wakeup
[ 3.628652] olpc_do_sleep!
+r[ 0.006584] olpc-ec: running cmd 0x24
[ 0.011370] olpc-ec: running cmd 0x24
[ 0.026706] rtc_cmos rtc_cmos: EARLY resume
[ 0.030905] platform powerbutton: EARLY resume
[ 0.035366] platform lid: EARLY resume
[ 0.039127] pci 0000:00:01.0: EARLY resume
[ 0.043247] lxfb 0000:00:01.1: EARLY resume
[ 0.047447] Geode LX AES 0000:00:01.2: EARLY resume
[ 0.052342] CAFÉ NAND 0000:00:0c.0: EARLY resume
[ 0.057065] sdhci 0000:00:0c.1: EARLY resume
[ 0.061353] cafe1000-ccic 0000:00:0c.2: EARLY resume
[ 0.066335] pci 0000:00:0f.0: EARLY resume
[ 0.070452] cs5535audio 0000:00:0f.3: EARLY resume
[ 0.075261] ohci_hcd 0000:00:0f.4: EARLY resume
[ 0.079810] ehci_hcd 0000:00:0f.5: EARLY resume
[ 0.084363] serial8250 serial8250.0: EARLY resume
[ 0.089080] platform pcspkr: EARLY resume
[ 0.093110] platform vesafb.0: EARLY resume
[ 0.097332] serial8250 serial8250: EARLY resume
[ 0.101884] i8042 i8042: EARLY resume
[ 0.105562] platform dcon: EARLY resume
[ 0.109416] platform olpc-battery.0: EARLY resume
[ 0.114462] olpc-ec: running cmd 0x84
[ 0.121244] olpc-ec: received 0x20
[ 0.124838] olpc-pm: SCI 0x20 received
[ 0.128697] olpc-ec: running cmd 0x84
[ 0.134476] olpc-ec: received 0x0
[ 0.137988] olpc-pm: SCI 0x0 received
[ 0.141947] PM: Finishing wakeup.
[ 0.145379] rtc_cmos rtc_cmos: resuming
[ 0.149234] platform powerbutton: resuming
[ 0.153427] platform lid: resuming
[ 0.156849] pci 0000:00:01.0: resuming
[ 0.160637] lxfb 0000:00:01.1: resuming
[ 0.214276] Geode LX AES 0000:00:01.2: resuming
[ 0.235561] CAFÉ NAND 0000:00:0c.0: resuming
[ 0.256839] sdhci 0000:00:0c.1: resuming
[ 0.284411] PM: Writing back config space on device 0000:00:0c.1 at
offset f (was 8080100, writing 808010b)
[ 0.330153] PM: Writing back config space on device 0000:00:0c.1 at
offset 3 (was 800000, writing 802000)
[ 0.378313] PM: Writing back config space on device 0000:00:0c.1 at
offset 1 (was 2b00002, writing 2b00006)
[ 0.429437] cafe1000-ccic 0000:00:0c.2: resuming
[ 0.459136] PM: Writing back config space on device 0000:00:0c.2 at
offset f (was 8080100, writing 808010b)
[ 0.514793] PM: Writing back config space on device 0000:00:0c.2 at
offset 3 (was 800000, writing 802000)
[ 0.570956] PM: Writing back config space on device 0000:00:0c.2 at
offset 1 (was 2b00002, writing 2b00006)
[ 0.635212] pci 0000:00:0f.0: resuming
[ 0.663967] cs5535audio 0000:00:0f.3: resuming
[ 0.693599] PCI: Setting latency timer of device 0000:00:0f.3 to 64
[ 0.730032] ohci_hcd 0000:00:0f.4: resuming
[ 0.766743] PCI: Setting latency timer of device 0000:00:0f.4 to 64
[ 0.828328] ehci_hcd 0000:00:0f.5: resuming
[ 0.865840] PCI: Setting latency timer of device 0000:00:0f.5 to 64
[ 0.899445] usb usb1: root hub lost power or was reset
[ 0.932083] PCI: cache line size of 32 is not supported by device
0000:00:0f.5
[ 1.028010] serial8250 serial8250.0: resuming
[ 1.060641] platform pcspkr: resuming
[ 1.092512] platform vesafb.0: resuming
[ 1.124524] serial8250 serial8250: resuming
[ 1.157042] ov7670 0-0042: resuming
[ 1.188710] usb usb1: resuming
[ 1.376100] hub 1-0:1.0: resuming
[ 1.407758] usb usb2: resuming
[ 1.439008] usb usb2: root hub lost power or was reset
[ 1.695857] hub 2-0:1.0: resuming
[ 1.727435] usb 1-1: resuming
[ 1.758465] usb_reset_device!
[ 1.848410] hub_port_wait_reset: portstatus=503 portchange=10
[ 1.947288] usb 1-1: reset high speed USB device using ehci_hcd and
address 2
[ 2.048016] hub_port_wait_reset: portstatus=503 portchange=10
[ 2.179406] USB_REQ_SET_CONFIGURATION!
[ 2.213224] PM: Removing info for No Bus:usbdev1.2_ep02
[ 2.247862] PM: Removing info for No Bus:usbdev1.2_ep83
[ 2.282262] PM: Adding info for No Bus:usbdev1.2_ep02
[ 2.315322] PM: Adding info for No Bus:usbdev1.2_ep83
[ 2.349305] usb8xxx 1-1:1.0: resuming
[ 2.379176] i8042 i8042: resuming
[ 2.419939] atkbd serio0: resuming
[ 2.447923] psmouse serio1: resuming
[ 2.527563] OLPC-DCON 1-000d: resuming
[ 2.579388] platform dcon: resuming
[ 2.605963] platform olpc-battery.0: resuming
[ 2.633147] olpc-dcon: The CPU has control
[ 2.658961] ac97 0-0:AD1888: resuming
[ 2.683638] Restarting tasks ... done.
[ 2.879621] PM: Preparing system for mem sleep
[ 3.055045] Stopping tasks ... done.
[ 3.080586] ac97 0-0:AD1888: suspend
[ 3.103950] platform olpc-battery.0: suspend
[ 3.127795] platform dcon: suspend
[ 3.150291] OLPC-DCON 1-000d: suspend
[ 3.196906] olpc-dcon: The DCON has control
[ 3.219745] psmouse serio1: suspend
[ 3.241362] atkbd serio0: suspend
[ 3.262296] i8042 i8042: suspend
[ 3.282982] usb8xxx 1-1:1.0: suspend
[ 3.304130] usb 1-1: suspend
[ 3.327308] hub 2-0:1.0: suspend
[ 3.347732] usb usb2: suspend, may wakeup
[ 3.369072] hub 1-0:1.0: suspend
[ 3.389643] usb usb1: suspend, may wakeup
[ 3.412024] ov7670 0-0042: suspend
[ 3.432873] BUG: unable to handle kernel NULL pointer dereference at
virtual address 00000068
[ 3.460035] printing eip:
[ 3.481151] c0570414
[ 3.501685] *pde = 00000000
[ 3.522563] Oops: 0002 [#1]
[ 3.542735] PREEMPT
[ 3.561679] Modules linked in: ipt_MASQUERADE xt_state nf_nat
nf_conntrack_ipv4 nf_conntrack x_tables i2c_dev mousedev joydev usb8xxx
libertas ieee80211 ieee80211_crypt psmouse serio_raw cs5535_gpio
[ 3.635437] CPU: 0
[ 3.635444] EIP: 0060:[<c0570414>] Not tainted VLI
[ 3.635455] EFLAGS: 00010202 (2.6.22-20071014.1.olpc.ab7afec1af5bfa4
#1)
[ 3.707099] EIP is at end_unlink_async+0x15/0xc1
[ 3.731479] eax: ce0658ec ebx: ce0658ec ecx: 0009aa5c edx:
c0572f14
[ 3.758703] esi: 00000000 edi: 00000024 ebp: ce0658ec esp:
c0748f40
[ 3.786092] ds: 007b es: 007b fs: 0000 gs: 0033 ss: 0068
[ 3.812738] Process run_fping1 (pid: 2863[#0], ti=c0748000
task=ccb5a030 task.ti=c7a79000)
[ 3.822009] Stack: ffffffff ce0658ec 00000024 c0570947 00000000
c076fa20 00000282 ce06586c
[ 3.852682] c0748f8c c06408f5 c076fa20 c076fa20 c04194e8
ce06586c 00000000 c0640a51
[ 3.883935] ce06586c c0419613 0009aa5c 00000282 ffffffff
ce0658ec 00000024 0000003c
[ 3.915477] Call Trace:
[ 3.962450] [<c0570947>] ehci_work+0x1c/0x5f7
[ 3.989875] [<c06408f5>] _spin_lock_irqsave+0x18/0x1d
[ 4.018015] [<c04194e8>] lock_timer_base+0x19/0x35
[ 4.045865] [<c0640a51>] _spin_unlock_irqrestore+0x14/0x2a
[ 4.074663] [<c0419613>] __mod_timer+0x90/0x99
[ 4.102345] [<c0573ceb>] ehci_irq+0x143/0x16b
[ 4.130006] [<c0562bbc>] usb_hcd_irq+0x21/0x4d
[ 4.157768] [<c04459a8>] handle_IRQ_event+0x1a/0x3f
[ 4.186045] [<c0446bfc>] handle_level_irq+0x7a/0xbe
[ 4.214132] [<c0446b82>] handle_level_irq+0x0/0xbe
[ 4.241971] [<c04053bc>] do_IRQ+0xaa/0xd6
[ 4.269051] [<c06409e3>] _spin_unlock+0xf/0x23
[ 4.296670] [<c0403ee7>] common_interrupt+0x23/0x28
[ 4.324870] [<c0412e9b>] vprintk+0x295/0x2ec
[ 4.352543] [<c0565e3c>] usb_external_suspend_device+0x1f1/0x1fb
[ 4.382393] [<c0412f0d>] printk+0x1b/0x1f
[ 4.410193] [<c0540023>] suspend_device+0x30d/0x376
[ 4.439041] [<c040ec94>] sys_sched_yield+0x9c/0xa1
[ 4.467739] [<c0540121>] device_suspend+0x95/0x164
[ 4.495913] [<c0412f0d>] printk+0x1b/0x1f
[ 4.522690] [<c043a1dd>] enter_state+0xd6/0x196
[ 4.550209] [<c043a39b>] state_store+0xc9/0xde
[ 4.577622] [<c043a2d2>] state_store+0x0/0xde
[ 4.604906] [<c049f963>] subsys_attr_store+0x27/0x2b
[ 4.633111] [<c049fa84>] sysfs_write_file+0xaf/0xd8
[ 4.660598] [<c049f9d5>] sysfs_write_file+0x0/0xd8
[ 4.686980] [<c0466242>] vfs_write+0xa8/0x130
[ 4.712165] [<c0466751>] sys_write+0x41/0x67
[ 4.737273] [<c0403cfa>] sysenter_past_esp+0x5f/0x85
[ 4.763160] =======================
[ 4.787127] Code: 83 c4 30 89 e8 5b 5e 5f 5d e9 73 18 ef ff 83 c4 30
5b 5e 5f 5d c3 57 56 53 89 c3 8b 70 24 0f ba b0 d0 00 00 00 01 ba 14 2f 57
c0 <c6> 46 68 03 8d 46 60 c7 46 48 00 00 00 00 e8 ec b2 f8 ff 8b 7e
[ 4.873665] History: SEQ: 1 NR_CPUS: 1
[ 4.899297] (#0001,*0):c04046d3 oops
[ 4.923601] EIP: [<c0570414>] end_unlink_async+0x15/0xc1 SS:ESP
0068:c0748f40
[ 4.951937] Kernel panic - not syncing: Fatal exception in interrupt
[ 122.460696] atkbd.c: Spurious ACK on isa0060/serio0. Some program
might be trying access hardware directly.
[ 399.554759] atkbd.c: Spurious ACK on isa0060/serio0. Some program
might be trying access hardware directly.
[ 904.920863] atkbd.c: Spurious ACK on isa0060/serio0. Some program
might be trying access hardware directly.
[ 2187.067261] atkbd.c: Spurious ACK on isa0060/serio0. Some program
might be trying access hardware directly.
[ 7134.646277] atkbd.c: Spurious ACK on isa0060/serio0. Some program
might be trying access hardware directly.
[ 8254.484858] atkbd.c: Spurious ACK on isa0060/serio0. Some program
might be trying access hardware directly.
[ 9639.638088] atkbd.c: Spurious ACK on isa0060/serio0. Some program
might be trying access hardware directly.
[10892.402136] atkbd.c: Spurious ACK on isa0060/serio0. Some program
might be trying access hardware directly.
--
Ticket URL: <https://dev.laptop.org/ticket/4322>
One Laptop Per Child <https://dev.laptop.org>
OLPC bug tracking system
More information about the Bugs
mailing list