Kernel 2.6.39 fails to boot correctly

VSpike · 2011-05-24 14:49:12

Hi!

I just updated this machine after a short gap, and the kernel went from 2.6.38.6-2 -> 2.6.39-1. This caused a failure to boot. It hangs on "Loading Modules". Alt-SysRq + R,S,E gets it back and it gives a login prompt, although root is still mounted RO and other partitions not yet mounted.

After a while, it tends to spew some kernel errors to the console.

I reverted the kernel to 2.6.38.6-2 (keeping the latest udev) and it works fine.

I saved the dmesg output:-

[  240.370049] INFO: task modprobe:511 blocked for more than 120 seconds.
[  240.373353] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[  240.376640] modprobe        D 00000000     0   511      1 0x00000004
[  240.379938]  f4c91bfc 00000086 00000000 00000000 00000001 f4c91c63 ee42e9c3 00000001
[  240.383373]  38383b94 32393333 2fa517a2 00000000 f4869400 f4c91c30 00000000 c14e1440
[  240.386779]  f6006a00 c14e1440 f6006440 f4f95140 f4aed9e0 f4c91bc8 c11ad279 f4c91bfc
[  240.390197] Call Trace:
[  240.393614]  [<c11ad279>] ? format_decode+0x319/0x380
[  240.396978]  [<c11af1aa>] ? vsnprintf+0x2ea/0x3d0
[  240.400138]  [<c132f1ed>] __mutex_lock_slowpath+0x10d/0x2b0
[  240.403175]  [<c132f39b>] mutex_lock+0xb/0x20
[  240.406166]  [<f803920d>] i2c_add_adapter+0x2d/0xa0 [i2c_core]
[  240.409132]  [<f807190d>] __i2c_bit_add_bus+0x17d/0x2f0 [i2c_algo_bit]
[  240.412119]  [<f80391e0>] ? i2c_add_numbered_adapter+0xd0/0xd0 [i2c_core]
[  240.415135]  [<f8071a9d>] i2c_bit_add_bus+0xd/0x10 [i2c_algo_bit]
[  240.418083]  [<f854d52b>] intel_gpio_create+0x11b/0x170 [i915]
[  240.421069]  [<f854e4aa>] gmbus_xfer+0xbca/0xdf0 [i915]
[  240.423903]  [<f80393c5>] i2c_transfer+0x85/0xb0 [i2c_core]
[  240.426592]  [<f80396c4>] i2c_smbus_xfer+0x234/0x540 [i2c_core]
[  240.429153]  [<c11a0001>] ? blk_throtl_work+0x291/0x4d0
[  240.431842]  [<c131515b>] ? klist_add_tail+0x3b/0x50
[  240.434460]  [<c124c0df>] ? put_device+0xf/0x20
[  240.437043]  [<c124d123>] ? device_add+0x73/0x5f0
[  240.439554]  [<f8039f99>] i2c_default_probe+0xe9/0x130 [i2c_core]
[  240.442195]  [<c124c335>] ? device_for_each_child+0x45/0x50
[  240.444652]  [<f803a153>] i2c_do_add_adapter+0x173/0x260 [i2c_core]
[  240.447144]  [<c11a8fd8>] ? kobject_uevent_env+0xf8/0x430
[  240.449646]  [<c11a8e90>] ? add_uevent_var+0xc0/0xc0
[  240.452149]  [<f803a630>] ? i2c_del_adapter+0x1f0/0x1f0 [i2c_core]
[  240.454704]  [<f803a652>] __process_new_driver+0x22/0x2b [i2c_core]
[  240.457225]  [<c124e8e1>] bus_for_each_dev+0x41/0x70
[  240.459732]  [<f803a630>] ? i2c_del_adapter+0x1f0/0x1f0 [i2c_core]
[  240.462282]  [<f80387ab>] i2c_for_each_dev+0x2b/0x50 [i2c_core]
[  240.464883]  [<f803a630>] ? i2c_del_adapter+0x1f0/0x1f0 [i2c_core]
[  240.467405]  [<f803a3bf>] i2c_register_driver+0x4f/0xa0 [i2c_core]
[  240.469914]  [<c1066c65>] ? notifier_call_chain.isra.0+0x45/0x60
[  240.472509]  [<f83b5012>] eeprom_init+0x12/0x14 [eeprom]
[  240.475053]  [<c1001120>] do_one_initcall+0x30/0x170
[  240.477603]  [<f83b5000>] ? 0xf83b4fff
[  240.480168]  [<c107b453>] sys_init_module+0xe03/0x1a20
[  240.482702]  [<c1330edf>] sysenter_do_call+0x12/0x28
[  240.485177] INFO: task modprobe:848 blocked for more than 120 seconds.
[  240.487885] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[  240.490602] modprobe        D f4fb1da4     0   848      1 0x00000004
[  240.493130]  f4fb1dfc 00000086 c15bd2f0 f4fb1da4 00000000 00000001 f35a8384 00000001
[  240.495662]  fffff801 0000000b 00000000 f49f3184 f4fb1dcc c11a753c f4fb1de0 c14e1440
[  240.498258]  c15bd2f0 c14e1440 f6206440 f4959140 f49ade30 f4a99de0 c1453a34 f4fb1dc4
[  240.500944] Call Trace:
[  240.503489]  [<c11a753c>] ? ida_get_new_above+0x4c/0x230
[  240.506034]  [<c132f0d8>] ? mutex_unlock+0x8/0x10
[  240.508560]  [<c115ee75>] ? sysfs_addrm_finish+0x15/0xb0
[  240.511042]  [<c132f39b>] ? mutex_lock+0xb/0x20
[  240.513523]  [<c132f1ed>] __mutex_lock_slowpath+0x10d/0x2b0
[  240.515989]  [<c132f39b>] mutex_lock+0xb/0x20
[  240.518392]  [<c124f786>] __driver_attach+0x46/0x90
[  240.520781]  [<c124f740>] ? driver_probe_device+0x170/0x170
[  240.523191]  [<c124e8e1>] bus_for_each_dev+0x41/0x70
[  240.525578]  [<c124f36c>] driver_attach+0x1c/0x30
[  240.527966]  [<c124f740>] ? driver_probe_device+0x170/0x170
[  240.530404]  [<c124f06f>] bus_add_driver+0x17f/0x260
[  240.532773]  [<c11c6750>] ? pci_dev_put+0x20/0x20
[  240.535260]  [<c124fc75>] driver_register+0x65/0x110
[  240.537774]  [<c10695d5>] ? jump_label_module_notify+0x105/0x260
[  240.540162]  [<c11c763e>] __pci_register_driver+0x3e/0xb0
[  240.542662]  [<f8d48062>] ehci_hcd_init+0x62/0x74 [ehci_hcd]
[  240.545113]  [<c1001120>] do_one_initcall+0x30/0x170
[  240.547587]  [<f8d48000>] ? 0xf8d47fff
[  240.549939]  [<c107b453>] sys_init_module+0xe03/0x1a20
[  240.552431]  [<c1330edf>] sysenter_do_call+0x12/0x28
[  240.554756] INFO: task modprobe:884 blocked for more than 120 seconds.
[  240.557288] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[  240.559721] modprobe        D 00000097     0   884      1 0x00000004
[  240.562287]  f4f2fd1c 00000086 00000097 00000097 f4f2fc9c c1279d36 d3309f15 00000001
[  240.564951]  00000004 f4f2fcf0 f4f2fcbc c127b6f4 00000004 f4868db8 00000000 c14e1440
[  240.567596]  00000000 c14e1440 f6206440 f49a9140 f49a8cf0 61306380 f4f23138 c10f7869
[  240.570138] Call Trace:
[  240.572634]  [<c1279d36>] ? pci_conf1_read+0xa6/0x100
[  240.575170]  [<c127b6f4>] ? raw_pci_read+0x64/0x80
[  240.577675]  [<c10f7869>] ? kmem_cache_alloc_trace+0x119/0x140
[  240.580172]  [<c11c0c9c>] ? pci_bus_read_config_word+0x7c/0x90
[  240.582628]  [<c104b621>] ? __request_region+0x51/0x160
[  240.585135]  [<c11ad279>] ? format_decode+0x319/0x380
[  240.587616]  [<c132f1ed>] __mutex_lock_slowpath+0x10d/0x2b0
[  240.590072]  [<c132f39b>] mutex_lock+0xb/0x20
[  240.592547]  [<f803920d>] i2c_add_adapter+0x2d/0xa0 [i2c_core]
[  240.595054]  [<f857cf4b>] i801_probe+0x28d/0x335 [i2c_i801]
[  240.597557]  [<c1257461>] ? __pm_runtime_set_status+0x101/0x1c0
[  240.600052]  [<c1256110>] ? pm_runtime_enable+0x40/0x70
[  240.604836]  [<c11c6315>] ? pci_match_device+0xb5/0xd0
[  240.607330]  [<c11c68ed>] pci_device_probe+0x8d/0x120
[  240.609724]  [<c115fad2>] ? sysfs_create_link+0x12/0x20
[  240.612182]  [<c124f633>] driver_probe_device+0x63/0x170
[  240.614588]  [<c124f7c9>] __driver_attach+0x89/0x90
[  240.616959]  [<c124f740>] ? driver_probe_device+0x170/0x170
[  240.619267]  [<c124e8e1>] bus_for_each_dev+0x41/0x70
[  240.621534]  [<c124f36c>] driver_attach+0x1c/0x30
[  240.623810]  [<c124f740>] ? driver_probe_device+0x170/0x170
[  240.626089]  [<c124f06f>] bus_add_driver+0x17f/0x260
[  240.628351]  [<c11c6750>] ? pci_dev_put+0x20/0x20
[  240.630612]  [<c124fc75>] driver_register+0x65/0x110
[  240.632908]  [<c11c763e>] __pci_register_driver+0x3e/0xb0
[  240.635218]  [<f8bfd084>] i2c_i801_init+0x84/0x9d [i2c_i801]
[  240.637536]  [<c1001120>] do_one_initcall+0x30/0x170
[  240.639833]  [<f8bfd000>] ? 0xf8bfcfff
[  240.642098]  [<c107b453>] sys_init_module+0xe03/0x1a20
[  240.644437]  [<c1330edf>] sysenter_do_call+0x12/0x28
[  240.646725] INFO: task modprobe:1161 blocked for more than 120 seconds.
[  240.649167] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[  240.651694] modprobe        D f4a25dac     0  1161      1 0x00000004
[  240.654253]  f4a25e04 00000086 c15bd2f0 f4a25dac 00000000 00000001 0844b554 00000002
[  240.656766]  fffff001 0000000c 00000000 f4d91604 f4a25dd4 c11a753c f4a25de8 c14e1440
[  240.659369]  c15bd2f0 c14e1440 f6206440 f4e4a6d0 f4875e30 f4b016c0 c1453a34 f4a25dcc
[  240.661983] Call Trace:
[  240.664451]  [<c11a753c>] ? ida_get_new_above+0x4c/0x230
[  240.666924]  [<c132f0d8>] ? mutex_unlock+0x8/0x10
[  240.669384]  [<c115ee75>] ? sysfs_addrm_finish+0x15/0xb0
[  240.671889]  [<c132f39b>] ? mutex_lock+0xb/0x20
[  240.674351]  [<c132f1ed>] __mutex_lock_slowpath+0x10d/0x2b0
[  240.676791]  [<c132f39b>] mutex_lock+0xb/0x20
[  240.679134]  [<c124f786>] __driver_attach+0x46/0x90
[  240.681490]  [<c124f740>] ? driver_probe_device+0x170/0x170
[  240.683817]  [<c124e8e1>] bus_for_each_dev+0x41/0x70
[  240.686087]  [<c124f36c>] driver_attach+0x1c/0x30
[  240.688331]  [<c124f740>] ? driver_probe_device+0x170/0x170
[  240.690610]  [<c124f06f>] bus_add_driver+0x17f/0x260
[  240.692883]  [<c11c6750>] ? pci_dev_put+0x20/0x20
[  240.695138]  [<c124fc75>] driver_register+0x65/0x110
[  240.697371]  [<c11c763e>] __pci_register_driver+0x3e/0xb0
[  240.699596]  [<f908f017>] alsa_card_azx_init+0x17/0x19 [snd_hda_intel]
[  240.701892]  [<c1001120>] do_one_initcall+0x30/0x170
[  240.704323]  [<f908f000>] ? 0xf908efff
[  240.706569]  [<c107b453>] sys_init_module+0xe03/0x1a20
[  240.709050]  [<c1330edf>] sysenter_do_call+0x12/0x28
[  240.711342] INFO: task modprobe:1336 blocked for more than 120 seconds.
[  240.713790] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[  240.716198] modprobe        D f4a8bda0     0  1336      1 0x00000004
[  240.718539]  f4a8bdf8 00000086 c15bd2f0 f4a8bda0 00000000 00000001 f7b78ebf 00000001
[  240.721128]  fffff801 0000000b 00000000 f49f3184 f4a8bdc8 c11a753c f4a8bddc c14e1440
[  240.723784]  c15bd2f0 c14e1440 f6006440 f4b408a0 f49aeb20 f4b1a180 c1453a34 f4a8bdc0
[  240.726378] Call Trace:
[  240.728847]  [<c11a753c>] ? ida_get_new_above+0x4c/0x230
[  240.731463]  [<c132f0d8>] ? mutex_unlock+0x8/0x10
[  240.734053]  [<c115ee75>] ? sysfs_addrm_finish+0x15/0xb0
[  240.736515]  [<c132f39b>] ? mutex_lock+0xb/0x20
[  240.738983]  [<c132f1ed>] __mutex_lock_slowpath+0x10d/0x2b0
[  240.741456]  [<c132f39b>] mutex_lock+0xb/0x20
[  240.743922]  [<c124f786>] __driver_attach+0x46/0x90
[  240.746383]  [<c124f740>] ? driver_probe_device+0x170/0x170
[  240.748848]  [<c124e8e1>] bus_for_each_dev+0x41/0x70
[  240.751310]  [<c124f36c>] driver_attach+0x1c/0x30
[  240.753753]  [<c124f740>] ? driver_probe_device+0x170/0x170
[  240.756202]  [<c124f06f>] bus_add_driver+0x17f/0x260
[  240.758676]  [<c11c6750>] ? pci_dev_put+0x20/0x20
[  240.761130]  [<c124fc75>] driver_register+0x65/0x110
[  240.763558]  [<c11c763e>] __pci_register_driver+0x3e/0xb0
[  240.765954]  [<f8f62082>] uhci_hcd_init+0x82/0xbc [uhci_hcd]
[  240.768324]  [<c1001120>] do_one_initcall+0x30/0x170
[  240.770678]  [<f8f62000>] ? 0xf8f61fff
[  240.772969]  [<c107b453>] sys_init_module+0xe03/0x1a20
[  240.775291]  [<c1330edf>] sysenter_do_call+0x12/0x28

Here's my lspci:-

00:00.0 Host bridge: Intel Corporation Mobile 945GM/PM/GMS, 943/940GML and 945GT Express Memory Controller Hub (rev 03)
00:02.0 VGA compatible controller: Intel Corporation Mobile 945GM/GMS, 943/940GML Express Integrated Graphics Controller (rev 03)
00:02.1 Display controller: Intel Corporation Mobile 945GM/GMS/GME, 943/940GML Express Integrated Graphics Controller (rev 03)
00:1b.0 Audio device: Intel Corporation N10/ICH 7 Family High Definition Audio Controller (rev 02)
00:1c.0 PCI bridge: Intel Corporation N10/ICH 7 Family PCI Express Port 1 (rev 02)
00:1c.1 PCI bridge: Intel Corporation N10/ICH 7 Family PCI Express Port 2 (rev 02)
00:1c.2 PCI bridge: Intel Corporation N10/ICH 7 Family PCI Express Port 3 (rev 02)
00:1c.3 PCI bridge: Intel Corporation N10/ICH 7 Family PCI Express Port 4 (rev 02)
00:1d.0 USB Controller: Intel Corporation N10/ICH 7 Family USB UHCI Controller #1 (rev 02)
00:1d.1 USB Controller: Intel Corporation N10/ICH 7 Family USB UHCI Controller #2 (rev 02)
00:1d.2 USB Controller: Intel Corporation N10/ICH 7 Family USB UHCI Controller #3 (rev 02)
00:1d.3 USB Controller: Intel Corporation N10/ICH 7 Family USB UHCI Controller #4 (rev 02)
00:1d.7 USB Controller: Intel Corporation N10/ICH 7 Family USB2 EHCI Controller (rev 02)
00:1e.0 PCI bridge: Intel Corporation 82801 Mobile PCI Bridge (rev e2)
00:1f.0 ISA bridge: Intel Corporation 82801GBM (ICH7-M) LPC Interface Bridge (rev 02)
00:1f.1 IDE interface: Intel Corporation 82801G (ICH7 Family) IDE Controller (rev 02)
00:1f.3 SMBus: Intel Corporation N10/ICH 7 Family SMBus Controller (rev 02)
02:00.0 Ethernet controller: Marvell Technology Group Ltd. 88E8055 PCI-E Gigabit Ethernet Controller (rev 13)
03:00.0 Network controller: Intel Corporation PRO/Wireless 3945ABG [Golan] Network Connection (rev 02)
09:04.0 CardBus bridge: Ricoh Co Ltd RL5c476 II (rev ba)
09:04.1 FireWire (IEEE 1394): Ricoh Co Ltd R5C832 IEEE 1394 Controller (rev 04)
09:04.4 System peripheral: Ricoh Co Ltd R5C592 Memory Stick Bus Host Adapter (rev 11)

Any idea what is causing this issue? I can't even work out which module it is from the dmesg output.

Thanks!

John

Last edited by VSpike (2011-05-24 17:37:41)

VSpike · 2011-05-24 16:48:10

I solved the problem. Some googling eventually led me to http://permalink.gmane.org/gmane.linux. … ral/831016 which hinted that the eeprom module might be at fault.

I removed this module from my MODULES line and upgraded the kernel to latest again, and now it boots fine.

Is it worth reporting this bug upstream somewhere, and if so, where? I've never quite figured out the right place to report kernel issues like that one, or to search for existing reports.

chr0nik · 2011-05-24 17:09:39

Someone beat you to it.

https://bugs.archlinux.org/24376
https://bugzilla.kernel.org/show_bug.cgi?id=35572

litemotiv · 2011-05-24 17:14:24

VSpike, could you please place your log output between code tags?

VSpike · 2011-05-24 17:40:01

@litemotiv Done!

@chr0nik Thanks for the links

Arch Linux

#1 2011-05-24 14:49:12

Kernel 2.6.39 fails to boot correctly

#2 2011-05-24 16:48:10

Re: Kernel 2.6.39 fails to boot correctly

#3 2011-05-24 17:09:39

Re: Kernel 2.6.39 fails to boot correctly

#4 2011-05-24 17:14:24

Re: Kernel 2.6.39 fails to boot correctly

#5 2011-05-24 17:40:01

Re: Kernel 2.6.39 fails to boot correctly

Board footer