#12031 NORM Not Tri: Hung kworker & other tasks on XO-1.5
Zarro Boogs per Child
bugtracker at laptop.org
Fri Jul 27 15:28:13 EDT 2012
#12031: Hung kworker & other tasks on XO-1.5
-----------------------+----------------------------------------------------
Reporter: greenfeld | Owner:
Type: defect | Status: new
Priority: normal | Milestone: Not Triaged
Component: kernel | Version: not specified
Keywords: | Next_action: diagnose
Verified: 0 | Deployment_affected:
Blockedby: | Blocking:
-----------------------+----------------------------------------------------
Seen with aggressive runin testing and a test kernel
(3.3.8_xo1.5-20120727.1114.olpc.1268f69) trying to fix a libertas issue.
{{{
[ 2360.157498] done.
[ 2360.628030] mmc1: new SDIO card at address 0001
[ 2362.090541] libertas_sdio mmc1:0001:1: (unregistered net_device):
20:7c:8f:43:87:56, fw 9.70.20p0, cap 0x000003a3
[ 2362.111322] libertas_sdio mmc1:0001:1: wlan0: Marvell WLAN 802.11
adapter
[ 2362.469666] udevd[2936]: renamed network interface wlan0 to eth0
[ 2362.705806] ADDRCONF(NETDEV_UP): eth0: link is not ready
[ 2377.020455] hpet1: lost 1 rtc interrupts
[ 2377.282191] hpet1: lost 1 rtc interrupts
[ 2384.774289] PM: Syncing filesystems ... done.
[ 2385.979669] Freezing user space processes ... (elapsed 0.07 seconds)
done.
[ 2386.062847] Freezing remaining freezable tasks ... (elapsed 0.01
seconds) done.
[ 2386.083182] dcon_source_switch to DCON
[ 2386.123243] olpc-dcon: The DCON has control
[ 2386.127900] i8042 kbd 00:04: wake-up capability enabled by ACPI
[ 2386.133940] i8042 aux 00:03: wake-up capability disabled by ACPI
[ 2386.141990] libertas_sdio mmc1:0001:1: mmc1:0001:1: suspend: PM flags =
0x3
[ 2386.149019] libertas_sdio mmc1:0001:1: Suspend without wake params --
powering down card
[ 2386.160774] mmc1: card 0001 removed
[ 2521.000220] INFO: task kworker/u:11:3211 blocked for more than 120
seconds.
[ 2521.007277] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables
this message.
[ 2521.015159] kworker/u:11 D e9a6e4b0 6624 3211 2 0x00000000
[ 2521.021730] e9fd3e50 00000046 b086fdf4 e9a6e4b0 9210fd44 0000022b
e9fd04b0 e9fd04b0
[ 2521.029724] e9da3240 000001f5 e9fd04dc b086fdf4 5d2a11bf e9fd3e3c
b0439409 00000225
[ 2521.038050] e94be4e4 00000000 b086fdf4 e9fd3e3c b0439678 ffffffff
e9fd169c ffffffff
[ 2521.046151] Call Trace:
[ 2521.048675] [<b0439409>] ? update_curr+0xa9/0x13e
[ 2521.053544] [<b0439678>] ? __enqueue_entity+0x5e/0x63
[ 2521.058742] [<b043a2be>] ? enqueue_task_fair+0x3f6/0x439
[ 2521.064211] [<b0732a92>] schedule+0x4d/0x4f
[ 2521.068545] [<b0731774>] schedule_timeout+0x1c/0x1d3
[ 2521.073661] [<b0439409>] ? update_curr+0xa9/0x13e
[ 2521.078499] [<b07322e1>] wait_for_common+0xb6/0xdc
[ 2521.083492] [<b04370f6>] ? try_to_wake_up+0xfd/0xfd
[ 2521.088515] [<b05d4fec>] ? device_pm_wait_for_dev+0x24/0x24
[ 2521.094235] [<b073231e>] wait_for_completion+0x17/0x19
[ 2521.099506] [<b05d4fc6>] dpm_wait+0x27/0x29
[ 2521.103833] [<b05d4ffc>] dpm_wait_fn+0x10/0x14
[ 2521.108430] [<b05ce565>] device_for_each_child+0x2d/0x5c
[ 2521.113945] [<b05d556b>] __device_suspend+0x20/0x17a
[ 2521.119097] [<b05d56e1>] async_suspend+0x1c/0x78
[ 2521.123865] [<b0433dcd>] async_run_entry_fn+0x94/0x11b
[ 2521.129187] [<b042bde9>] process_one_work+0x163/0x2dd
[ 2521.134393] [<b0433d39>] ? async_schedule+0x16/0x16
[ 2521.139417] [<b042c1ce>] worker_thread+0x138/0x208
[ 2521.144365] [<b042c096>] ? rescuer_thread+0x10d/0x10d
[ 2521.149604] [<b042ed57>] kthread+0x6b/0x70
[ 2521.153853] [<b042ecec>] ? __init_kthread_worker+0x2d/0x2d
[ 2521.159480] [<b07385b6>] kernel_thread_helper+0x6/0x10
[ 2521.164784] INFO: task rtcwake:3778 blocked for more than 120 seconds.
[ 2521.171418] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables
this message.
[ 2521.179343] rtcwake D b07fedfa 6460 3778 3735 0x00000000
[ 2521.185817] e9e83e70 00000082 00000002 b07fedfa 94022ab8 0000022b
e94bf200 e94bf200
[ 2521.193911] b0868500 00000404 00000000 e9e83e70 b09aab48 00000000
b088062c e9e83e80
[ 2521.201985] b059a4c7 04000000 00000159 00000246 00000dbd 00000246
e9e83e60 00000246
[ 2521.210182] Call Trace:
[ 2521.212698] [<b059a4c7>] ? acpi_read_bit_register+0x6b/0x80
[ 2521.218420] [<b0732a92>] schedule+0x4d/0x4f
[ 2521.222759] [<b0433f3f>] async_synchronize_cookie_domain+0xeb/0x141
[ 2521.229204] [<b042f229>] ? __init_waitqueue_head+0x26/0x26
[ 2521.234854] [<b0433fa7>] async_synchronize_cookie+0x12/0x14
[ 2521.240572] [<b0433fc1>] async_synchronize_full+0x18/0x32
[ 2521.246115] [<b05d5e34>] dpm_suspend+0x143/0x193
[ 2521.250899] [<b05d6024>] dpm_suspend_start+0x44/0x4c
[ 2521.256020] [<b043eb6f>] suspend_devices_and_enter+0x56/0x223
[ 2521.261971] [<b043ee20>] enter_state+0xe4/0x158
[ 2521.266648] [<b043de85>] state_store+0x92/0xd5
[ 2521.271241] [<b043ddf3>] ? pm_async_store+0x35/0x35
[ 2521.276263] [<b0548bb7>] kobj_attr_store+0x10/0x1d
[ 2521.281217] [<b04e12f1>] sysfs_write_file+0xb0/0xeb
[ 2521.286264] [<b04e1241>] ? sysfs_poll+0x71/0x71
[ 2521.290965] [<b049caaa>] vfs_write+0x84/0x102
[ 2521.295457] [<b049ccc9>] sys_write+0x3b/0x67
[ 2521.299869] [<b0738090>] sysenter_do_call+0x12/0x26
[ 2641.300103] INFO: task kworker/u:11:3211 blocked for more than 120
seconds.
[ 2641.307141] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables
this message.
[ 2641.315097] kworker/u:11 D e9a6e4b0 6624 3211 2 0x00000000
[ 2641.321625] e9fd3e50 00000046 b086fdf4 e9a6e4b0 9210fd44 0000022b
e9fd04b0 e9fd04b0
[ 2641.329639] e9da3240 000001f5 e9fd04dc b086fdf4 5d2a11bf e9fd3e3c
b0439409 00000225
[ 2641.337784] e94be4e4 00000000 b086fdf4 e9fd3e3c b0439678 ffffffff
e9fd169c ffffffff
[ 2641.345877] Call Trace:
[ 2641.348399] [<b0439409>] ? update_curr+0xa9/0x13e
[ 2641.353292] [<b0439678>] ? __enqueue_entity+0x5e/0x63
[ 2641.358495] [<b043a2be>] ? enqueue_task_fair+0x3f6/0x439
[ 2641.363969] [<b0732a92>] schedule+0x4d/0x4f
[ 2641.368292] [<b0731774>] schedule_timeout+0x1c/0x1d3
[ 2641.373413] [<b0439409>] ? update_curr+0xa9/0x13e
[ 2641.378299] [<b07322e1>] wait_for_common+0xb6/0xdc
[ 2641.383247] [<b04370f6>] ? try_to_wake_up+0xfd/0xfd
[ 2641.388263] [<b05d4fec>] ? device_pm_wait_for_dev+0x24/0x24
[ 2641.393990] [<b073231e>] wait_for_completion+0x17/0x19
[ 2641.399275] [<b05d4fc6>] dpm_wait+0x27/0x29
[ 2641.403621] [<b05d4ffc>] dpm_wait_fn+0x10/0x14
[ 2641.408206] [<b05ce565>] device_for_each_child+0x2d/0x5c
[ 2641.413690] [<b05d556b>] __device_suspend+0x20/0x17a
[ 2641.418828] [<b05d56e1>] async_suspend+0x1c/0x78
[ 2641.423599] [<b0433dcd>] async_run_entry_fn+0x94/0x11b
[ 2641.428884] [<b042bde9>] process_one_work+0x163/0x2dd
[ 2641.434134] [<b0433d39>] ? async_schedule+0x16/0x16
[ 2641.439155] [<b042c1ce>] worker_thread+0x138/0x208
[ 2641.444109] [<b042c096>] ? rescuer_thread+0x10d/0x10d
[ 2641.449308] [<b042ed57>] kthread+0x6b/0x70
[ 2641.453561] [<b042ecec>] ? __init_kthread_worker+0x2d/0x2d
[ 2641.459199] [<b07385b6>] kernel_thread_helper+0x6/0x10
[ 2641.464532] INFO: task rtcwake:3778 blocked for more than 120 seconds.
[ 2641.471117] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables
this message.
[ 2641.479028] rtcwake D b07fedfa 6460 3778 3735 0x00000000
[ 2641.485552] e9e83e70 00000082 00000002 b07fedfa 94022ab8 0000022b
e94bf200 e94bf200
[ 2641.493680] b0868500 00000404 00000000 e9e83e70 b09aab48 00000000
b088062c e9e83e80
[ 2641.501845] b059a4c7 04000000 00000159 00000246 00000dbd 00000246
e9e83e60 00000246
[ 2641.509941] Call Trace:
[ 2641.512475] [<b059a4c7>] ? acpi_read_bit_register+0x6b/0x80
[ 2641.518193] [<b0732a92>] schedule+0x4d/0x4f
[ 2641.522530] [<b0433f3f>] async_synchronize_cookie_domain+0xeb/0x141
[ 2641.528995] [<b042f229>] ? __init_waitqueue_head+0x26/0x26
[ 2641.534661] [<b0433fa7>] async_synchronize_cookie+0x12/0x14
[ 2641.540448] [<b0433fc1>] async_synchronize_full+0x18/0x32
[ 2641.545988] [<b05d5e34>] dpm_suspend+0x143/0x193
[ 2641.550761] [<b05d6024>] dpm_suspend_start+0x44/0x4c
[ 2641.555885] [<b043eb6f>] suspend_devices_and_enter+0x56/0x223
[ 2641.561791] [<b043ee20>] enter_state+0xe4/0x158
[ 2641.566456] [<b043de85>] state_store+0x92/0xd5
[ 2641.571064] [<b043ddf3>] ? pm_async_store+0x35/0x35
[ 2641.576089] [<b0548bb7>] kobj_attr_store+0x10/0x1d
[ 2641.581041] [<b04e12f1>] sysfs_write_file+0xb0/0xeb
[ 2641.586064] [<b04e1241>] ? sysfs_poll+0x71/0x71
[ 2641.590762] [<b049caaa>] vfs_write+0x84/0x102
[ 2641.595269] [<b049ccc9>] sys_write+0x3b/0x67
[ 2641.599680] [<b0738090>] sysenter_do_call+0x12/0x26
[ 2761.600656] INFO: task kworker/u:11:3211 blocked for more than 120
seconds.
[ 2761.607681] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables
this message.
[ 2761.615619] kworker/u:11 D e9a6e4b0 6624 3211 2 0x00000000
[ 2761.622159] e9fd3e50 00000046 b086fdf4 e9a6e4b0 9210fd44 0000022b
e9fd04b0 e9fd04b0
[ 2761.630431] e9da3240 000001f5 e9fd04dc b086fdf4 5d2a11bf e9fd3e3c
b0439409 00000225
[ 2761.638685] e94be4e4 00000000 b086fdf4 e9fd3e3c b0439678 ffffffff
e9fd169c ffffffff
[ 2761.646919] Call Trace:
[ 2761.649417] [<b0439409>] ? update_curr+0xa9/0x13e
[ 2761.654287] [<b0439678>] ? __enqueue_entity+0x5e/0x63
[ 2761.659476] [<b043a2be>] ? enqueue_task_fair+0x3f6/0x439
[ 2761.664950] [<b0732a92>] schedule+0x4d/0x4f
[ 2761.669281] [<b0731774>] schedule_timeout+0x1c/0x1d3
[ 2761.674450] [<b0439409>] ? update_curr+0xa9/0x13e
[ 2761.679290] [<b07322e1>] wait_for_common+0xb6/0xdc
[ 2761.684233] [<b04370f6>] ? try_to_wake_up+0xfd/0xfd
[ 2761.689300] [<b05d4fec>] ? device_pm_wait_for_dev+0x24/0x24
[ 2761.695054] [<b073231e>] wait_for_completion+0x17/0x19
[ 2761.700347] [<b05d4fc6>] dpm_wait+0x27/0x29
[ 2761.704668] [<b05d4ffc>] dpm_wait_fn+0x10/0x14
[ 2761.709259] [<b05ce565>] device_for_each_child+0x2d/0x5c
[ 2761.714734] [<b05d556b>] __device_suspend+0x20/0x17a
[ 2761.719844] [<b05d56e1>] async_suspend+0x1c/0x78
[ 2761.724622] [<b0433dcd>] async_run_entry_fn+0x94/0x11b
[ 2761.729901] [<b042bde9>] process_one_work+0x163/0x2dd
[ 2761.735122] [<b0433d39>] ? async_schedule+0x16/0x16
[ 2761.740149] [<b042c1ce>] worker_thread+0x138/0x208
[ 2761.745104] [<b042c096>] ? rescuer_thread+0x10d/0x10d
[ 2761.750315] [<b042ed57>] kthread+0x6b/0x70
[ 2761.754551] [<b042ecec>] ? __init_kthread_worker+0x2d/0x2d
[ 2761.760189] [<b07385b6>] kernel_thread_helper+0x6/0x10
[ 2761.765520] INFO: task rtcwake:3778 blocked for more than 120 seconds.
[ 2761.772103] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables
this message.
[ 2761.780032] rtcwake D b07fedfa 6460 3778 3735 0x00000000
[ 2761.786535] e9e83e70 00000082 00000002 b07fedfa 94022ab8 0000022b
e94bf200 e94bf200
[ 2761.794654] b0868500 00000404 00000000 e9e83e70 b09aab48 00000000
b088062c e9e83e80
[ 2761.802835] b059a4c7 04000000 00000159 00000246 00000dbd 00000246
e9e83e60 00000246
[ 2761.811026] Call Trace:
[ 2761.813542] [<b059a4c7>] ? acpi_read_bit_register+0x6b/0x80
[ 2761.819255] [<b0732a92>] schedule+0x4d/0x4f
[ 2761.823599] [<b0433f3f>] async_synchronize_cookie_domain+0xeb/0x141
[ 2761.830038] [<b042f229>] ? __init_waitqueue_head+0x26/0x26
[ 2761.835660] [<b0433fa7>] async_synchronize_cookie+0x12/0x14
[ 2761.841382] [<b0433fc1>] async_synchronize_full+0x18/0x32
[ 2761.846925] [<b05d5e34>] dpm_suspend+0x143/0x193
[ 2761.851694] [<b05d6024>] dpm_suspend_start+0x44/0x4c
[ 2761.856798] [<b043eb6f>] suspend_devices_and_enter+0x56/0x223
[ 2761.862726] [<b043ee20>] enter_state+0xe4/0x158
[ 2761.867394] [<b043de85>] state_store+0x92/0xd5
[ 2761.872007] [<b043ddf3>] ? pm_async_store+0x35/0x35
[ 2761.877081] [<b0548bb7>] kobj_attr_store+0x10/0x1d
[ 2761.882028] [<b04e12f1>] sysfs_write_file+0xb0/0xeb
[ 2761.887092] [<b04e1241>] ? sysfs_poll+0x71/0x71
[ 2761.891778] [<b049caaa>] vfs_write+0x84/0x102
[ 2761.896287] [<b049ccc9>] sys_write+0x3b/0x67
[ 2761.900704] [<b0738090>] sysenter_do_call+0x12/0x26
[ 2881.901111] INFO: task kworker/u:11:3211 blocked for more than 120
seconds.
[ 2881.908152] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables
this message.
[ 2881.916040] kworker/u:11 D e9a6e4b0 6624 3211 2 0x00000000
[ 2881.922509] e9fd3e50 00000046 b086fdf4 e9a6e4b0 9210fd44 0000022b
e9fd04b0 e9fd04b0
[ 2881.930814] e9da3240 000001f5 e9fd04dc b086fdf4 5d2a11bf e9fd3e3c
b0439409 00000225
[ 2881.939069] e94be4e4 00000000 b086fdf4 e9fd3e3c b0439678 ffffffff
e9fd169c ffffffff
[ 2881.947278] Call Trace:
[ 2881.949800] [<b0439409>] ? update_curr+0xa9/0x13e
[ 2881.954656] [<b0439678>] ? __enqueue_entity+0x5e/0x63
[ 2881.959888] [<b043a2be>] ? enqueue_task_fair+0x3f6/0x439
[ 2881.965355] [<b0732a92>] schedule+0x4d/0x4f
[ 2881.969689] [<b0731774>] schedule_timeout+0x1c/0x1d3
[ 2881.974800] [<b0439409>] ? update_curr+0xa9/0x13e
[ 2881.979649] [<b07322e1>] wait_for_common+0xb6/0xdc
[ 2881.984592] [<b04370f6>] ? try_to_wake_up+0xfd/0xfd
[ 2881.989617] [<b05d4fec>] ? device_pm_wait_for_dev+0x24/0x24
[ 2881.995365] [<b073231e>] wait_for_completion+0x17/0x19
[ 2882.000699] [<b05d4fc6>] dpm_wait+0x27/0x29
[ 2882.005031] [<b05d4ffc>] dpm_wait_fn+0x10/0x14
[ 2882.009618] [<b05ce565>] device_for_each_child+0x2d/0x5c
[ 2882.015076] [<b05d556b>] __device_suspend+0x20/0x17a
[ 2882.020197] [<b05d56e1>] async_suspend+0x1c/0x78
[ 2882.024961] [<b0433dcd>] async_run_entry_fn+0x94/0x11b
[ 2882.030257] [<b042bde9>] process_one_work+0x163/0x2dd
[ 2882.035438] [<b0433d39>] ? async_schedule+0x16/0x16
[ 2882.040474] [<b042c1ce>] worker_thread+0x138/0x208
[ 2882.045412] [<b042c096>] ? rescuer_thread+0x10d/0x10d
[ 2882.050606] [<b042ed57>] kthread+0x6b/0x70
[ 2882.054844] [<b042ecec>] ? __init_kthread_worker+0x2d/0x2d
[ 2882.060486] [<b07385b6>] kernel_thread_helper+0x6/0x10
(...)
}}}
--
Ticket URL: <http://dev.laptop.org/ticket/12031>
One Laptop Per Child <http://laptop.org/>
OLPC bug tracking system
More information about the Bugs
mailing list