[Libdlo] Another kernel panic

Fri Oct 22 11:54:02 PDT 2010

Bernie Thompson wrote:
> Hi Alexander,
> 

> Possibly a race condition, with removals/re-insertions. Does how quickly you
> do the re-insertion make a difference?
> 

I didn't notice a difference. However that seems to happen when 1 of the USB 
hubs is connected to a PCI card that provides additional USB ports (PCI 4+1 
card) and the other hub is connect to one of the factory default USB ports.

I've also noticed that the fbX device is not removed when pulling the hub out. 
My theory is that it's still busy hence not removed.

> Do you have the rest of the call stack?  Maybe post more of the panic and
> any surrounding udlfb output from kern.log?
> 

This is as much as I can get from the system. Hope it helps.

Message from syslogd@ at Fri Oct 22 21:46:21 2010 ...
192.168.0.111 sumu01 kernel: Oops: 0000 [#1] SMP

Message from syslogd@ at Fri Oct 22 21:46:21 2010 ...
192.168.0.111 sumu01 kernel: last sysfs file: 
/sys/devices/pci0000:00/0000:00:1d.7/usb2/idVendor

Message from syslogd@ at Fri Oct 22 21:46:21 2010 ...
192.168.0.111 sumu01 kernel: Process events/0 (pid: 15, ti=f70d4000 
task=f70b4030 task.ti=f70d4000)

Message from syslogd@ at Fri Oct 22 21:46:21 2010 ...
192.168.0.111 sumu01 kernel: Stack:

Message from syslogd@ at Fri Oct 22 21:46:22 2010 ...
192.168.0.111 sumu01 kernel: Call Trace:
Oct 22 21:46:21 192.168.0.111 sumu01 kernel: usb 2-3: USB disconnect, address 27
Oct 22 21:46:21 192.168.0.111 sumu01 kernel: usb 2-3.2: USB disconnect, address 31
Oct 22 21:46:21 192.168.0.111 sumu01 kernel: usb 2-3.3: USB disconnect, address 29
Oct 22 21:46:21 192.168.0.111 sumu01 kernel: usb 2-3.4: USB disconnect, address 30
Oct 22 21:46:21 192.168.0.111 sumu01 kernel: udlfb: USB disconnect starting
Oct 22 21:46:21 192.168.0.111 sumu01 kernel: udlfb: fb_info for /dev/fb1 has 
been freed
Oct 22 21:46:21 192.168.0.111 sumu01 kernel: udlfb: Waiting for completes and 
freeing all render urbs
Oct 22 21:46:21 192.168.0.111 sumu01 kernel: BUG: unable to handle kernel paging 
request at 3d4e4f49
Oct 22 21:46:21 192.168.0.111 sumu01 kernel: IP: [<c06f577b>] 
hcd_buffer_free+0x1b/0x110
Oct 22 21:46:21 192.168.0.111 sumu01 kernel: *pdpt = 0000000000ac7001 *pde = 
0000000000000000
Oct 22 21:46:21 192.168.0.111 sumu01 kernel: Oops: 0000 [#1] SMP
Oct 22 21:46:21 192.168.0.111 sumu01 kernel: last sysfs file: 
/sys/devices/pci0000:00/0000:00:1d.7/usb2/idVendor
Oct 22 21:46:21 192.168.0.111 sumu01 kernel: Modules linked in: sunrpc 
cpufreq_ondemand acpi_cpufreq ipt_REJECT nf_conntrack_ipv4 nf_defrag_ipv4 
iptable_filter ip_tables ip6t_REJECT nf_conntrack_ipv6 xt_state nf_conntrack 
ip6table_filter ip6_tables ipv6 ext3 jbd uinput ppdev parport_pc parport sg 
udlfb(U) serio_raw i2c_i801 iTCO_wdt iTCO_vendor_support e1000e i3200_edac 
edac_core ext4 mbcache jbd2 dm_mirror dm_region_hash dm_log sr_mod cdrom sd_mod 
crc_t10dif ahci radeon ttm drm_kms_helper drm i2c_algo_bit i2c_core dm_mod [last 
unloaded: microcode]
Oct 22 21:46:21 192.168.0.111 sumu01 kernel:
Oct 22 21:46:21 192.168.0.111 sumu01 kernel: Pid: 15, comm: events/0 Not tainted 
(2.6.32-71.el6.i686 #1) PRIMERGY TX100 S1
Oct 22 21:46:21 192.168.0.111 sumu01 kernel: EIP: 0060:[<c06f577b>] EFLAGS: 
00010286 CPU: 0
Oct 22 21:46:21 192.168.0.111 sumu01 kernel: EIP is at hcd_buffer_free+0x1b/0x110
Oct 22 21:46:21 192.168.0.111 sumu01 kernel: EAX: 3d4e4f49 EBX: 0000fe00 ECX: 
e9b50000 EDX: 0000fe00
Oct 22 21:46:21 192.168.0.111 sumu01 kernel: ESI: e9b50000 EDI: 29b50000 EBP: 
00000000 ESP: f70d5f14
Oct 22 21:46:21 192.168.0.111 sumu01 kernel: DS: 007b ES: 007b FS: 00d8 GS: 00e0 
SS: 0068
Oct 22 21:46:21 192.168.0.111 sumu01 kernel: Process events/0 (pid: 15, 
ti=f70d4000 task=f70b4030 task.ti=f70d4000)
Oct 22 21:46:21 192.168.0.111 sumu01 kernel: Stack:
Oct 22 21:46:21 192.168.0.111 sumu01 kernel: 903f0203 eaf68034 00000296 00000296 
f04550c0 eaea0bc0 eaf68000 00000003
Oct 22 21:46:21 192.168.0.111 sumu01 kernel: <0> f916c134 29b50000 00000000 
f175e000 eaf68014 eaf68018 eaf68034 f916c0b0
Oct 22 21:46:21 192.168.0.111 sumu01 kernel: <0> c5a09660 f916c790 c05e44ca 
c5a09660 eaf68044 c046c83b c0ac0060 00000000
Oct 22 21:46:21 192.168.0.111 sumu01 kernel: Call Trace:
Oct 22 21:46:21 192.168.0.111 sumu01 kernel: [<f916c134>] ? dlfb_free+0x84/0xe0 
[udlfb]
Oct 22 21:46:21 192.168.0.111 sumu01 kernel: [<f916c0b0>] ? dlfb_free+0x0/0xe0 
[udlfb]
Oct 22 21:46:21 192.168.0.111 sumu01 kernel: [<f916c790>] ? 
dlfb_free_framebuffer_work+0x0/0xa0 [udlfb]
Oct 22 21:46:21 192.168.0.111 sumu01 kernel: [<c05e44ca>] ? kref_put+0x2a/0x60
Oct 22 21:46:21 192.168.0.111 sumu01 kernel: [<c046c83b>] ? 
worker_thread+0x11b/0x230
Oct 22 21:46:21 192.168.0.111 sumu01 kernel: [<c0470d10>] ? 
autoremove_wake_function+0x0/0x40
Oct 22 21:46:21 192.168.0.111 sumu01 kernel: [<c046c720>] ? worker_thread+0x0/0x230

Message from syslogd@ at Fri Oct 22 21:46:22 2010 ...
192.168.0.111 sumu01 kernel: Code: 89 54 24 08 75 e0 eb 85 31 c0 eb 84 90 90 90 
90 55 57 56 89 ce 53 89 d3 83 ec 10 85 c9 8b 7c 24 24 8b 6c 24 28 0f 84 8d 00 00 
00 <8b> 10 8b 92 e0 00 00 00 85 d2 0f 84 a5 00 00 00 31 d2 8d 76 00

Message from syslogd@ at Fri Oct 22 21:46:22 2010 ...
192.168.0.111 sumu01 kernel: EIP: [<c06f577b>] hcd_buffer_free+0x1b/0x110 SS:ESP 
0068:f70d5f14

Message from syslogd@ at Fri Oct 22 21:46:22 2010 ...
192.168.0.111 sumu01 kernel: CR2: 000000003d4e4f49

Message from syslogd@ at Fri Oct 22 21:46:22 2010 ...
192.168.0.111 sumu01 kernel: Kernel panic - not syncing: Fatal exception
Oct 22 21:46:21 192.168.0.111 sumu01 kernel: [<c0470ad4>] ? kthread+0x74/0x80
Oct 22 21:46:21 192.168.0.111 sumu01 kernel: [<c0470a60>] ? kthread+0x0/0x80
Oct 22 21:46:21 192.168.0.111 sumu01 kernel: [<c040a647>] ? 
kernel_thread_helper+0x7/0x10
Oct 22 21:46:21 192.168.0.111 sumu01 kernel: Code: 89 54 24 08 75 e0 eb 85 31 c0 
eb 84 90 90 90 90 55 57 56 89 ce 53 89 d3 83 ec 10 85 c9 8b 7c 24 24 8b 6c 24 28 
0f 84 8d 00 00 00 <8b> 10 8b 92 e0 00 00 00 85 d2 0f 84 a5 00 00 00 31 d2 8d 76 00
Oct 22 21:46:21 192.168.0.111 sumu01 kernel: EIP: [<c06f577b>] 
hcd_buffer_free+0x1b/0x110 SS:ESP 0068:f70d5f14
Oct 22 21:46:21 192.168.0.111 sumu01 kernel: CR2: 000000003d4e4f49
Oct 22 21:46:21 192.168.0.111 sumu01 kernel: ---[ end trace 134e663ff0cd85e7 ]---
Oct 22 21:46:21 192.168.0.111 sumu01 kernel: Kernel panic - not syncing: Fatal 
exception
Oct 22 21:46:21 192.168.0.111 sumu01 kernel: Pid: 15, comm: events/0 Tainted: G 
      D    ----------------  2.6.32-71.el6.i686 #1
Oct 22 21:46:21 192.168.0.111 sumu01 kernel: Call Trace:
Oct 22 21:46:21 192.168.0.111 sumu01 kernel: [<c080ad18>] ? panic+0x42/0xed
Oct 22 21:46:21 192.168.0.111 sumu01 kernel: [<c080e42c>] ? oops_end+0xbc/0xd0
Oct 22 21:46:21 192.168.0.111 sumu01 kernel: [<c04308a2>] ? no_context+0xc2/0x190
Oct 22 21:46:21 192.168.0.111 sumu01 kernel: [<c080f730>] ? do_page_fault+0x0/0x480
Oct 22 21:46:21 192.168.0.111 sumu01 kernel: [<c0430adf>] ? 
bad_area_nosemaphore+0xf/0x20
Oct 22 21:46:21 192.168.0.111 sumu01 kernel: [<c080fad8>] ? 
do_page_fault+0x3a8/0x480
Oct 22 21:46:21 192.168.0.111 sumu01 kernel: [<c040a435>] ? 
apic_timer_interrupt+0x31/0x38
Oct 22 21:46:21 192.168.0.111 sumu01 kernel: [<c0450cca>] ? vprintk+0x1aa/0x490
Oct 22 21:46:21 192.168.0.111 sumu01 kernel: [<c0450cca>] ? vprintk+0x1aa/0x490
Oct 22 21:46:21 192.168.0.111 sumu01 kernel: [<c080f730>] ? do_page_fault+0x0/0x480
Oct 22 21:46:21 192.168.0.111 sumu01 kernel: [<c080d80b>] ? error_code+0x73/0x78
Oct 22 21:46:21 192.168.0.111 sumu01 kernel: [<c06f577b>] ? 
hcd_buffer_free+0x1b/0x110
Oct 22 21:46:22 192.168.0.111 sumu01 kernel: [<f916c134>] ? dlfb_free+0x84/0xe0 
[udlfb]
Oct 22 21:46:22 192.168.0.111 sumu01 kernel: [<f916c0b0>] ? dlfb_free+0x0/0xe0 
[udlfb]
Oct 22 21:46:22 192.168.0.111 sumu01 kernel: [<f916c790>] ? 
dlfb_free_framebuffer_work+0x0/0xa0 [udlfb]
Oct 22 21:46:22 192.168.0.111 sumu01 kernel: [<c05e44ca>] ? kref_put+0x2a/0x60
Oct 22 21:46:22 192.168.0.111 sumu01 kernel: [<c046c83b>] ? 
worker_thread+0x11b/0x230
Oct 22 21:46:22 192.168.0.111 sumu01 kernel: [<c0470d10>] ? 
autoremove_wake_function+0x0/0x40
Oct 22 21:46:22 192.168.0.111 sumu01 kernel: [<c046c720>] ? worker_thread+0x0/0x230
Oct 22 21:46:22 192.168.0.111 sumu01 kernel: [<c0470ad4>] ? kthread+0x74/0x80
Oct 22 21:46:22 192.168.0.111 sumu01 kernel: [<c0470a60>] ? kthread+0x0/0x80
Oct 22 21:46:22 192.168.0.111 sumu01 kernel: [<c040a647>] ? 
kernel_thread_helper+0x7/0x10

> 
> Also, can you post uname -a info?
> 

Linux sumu01 2.6.32-71.el6.i686 #1 SMP Wed Sep 1 01:26:34 EDT 2010 i686 i686 
i386 GNU/Linux

--
Alexander.