Bug 5052

Summary: USB Palm Error Causing Keyboard Removal
Product: Drivers Reporter: Jeremey Wise (Jeremey.wise)
Component: USBAssignee: Greg Kroah-Hartman (greg)
Status: REJECTED INVALID    
Severity: high    
Priority: P2    
Hardware: i386   
OS: Linux   
Kernel Version: 2.6.12.3 Subsystem:
Regression: --- Bisected commit-id:
Bug Depends on:    
Bug Blocks: 5089    

Description Jeremey Wise 2005-08-12 08:10:52 UTC 
Distribution:
Hardware Environment: IBM T41 (though other laptops have same issue),Samsung i500
Software Environment: SuSE 9.3,pilot-link-0.11.8-127
Problem Description:

Other maillist history/threads:
http://lists.pilot-link.org/pipermail/pilot-link-general/2005-July/002477.html
http://lists.pilot-link.org/pipermail/pilot-link-general/2005-August/thread.html

****************************************************

So if you bothered to read all the above you can get a small glimse of the crude
I have had going on.
The phone worked with SuSE 9.0, 9.1, 9.2 but now I have this issue. I have tried
three T41 laptops.
I have trapped the output of /var/log/messages (see posts please) several times
with as much debug
as the modules will allow. I am not a programmer but am willing to help. It
appears to be a USB kernel
module. BUT not specific to UHCI_HCD or EHCI_HCD as I can remove one or the
other in the testing and
the error is still consistant. I for a while thought it to be a USB controller
compat issue in the 
IBM T41 units but I started (see above for an exaple of my pushback to the
Pilot-link group of one
direct inquiry to me) getting people with other laptops etc. with the same error
/ issue.

Steps to reproduce:
1)boot system
2)plug phone/palm in
3)load up modules (I blacklist them to keep them from loading)
  a) modprobe ehci_hcd (phone registers ok at this point)
  b) modprobe usbserial
  c) modprobe visor
4) While capturing output from /var/log/messages:>) type "dlpsh -p /dev/usb/ttyUSB0
5) hit sync button on palm/phone
6) Oops reports in /var/log/messages and keyboard is lost on system.


The above test can be repeated every time, with ehci_hcd or uhci_hcd. With a
SuSE stock kernel or one compiled from kernel.org

As aluded to in the documentation of the email threads on the pilot-link site,
their are other sytems with the same issue (aka other hardware profiles) and I
have tried multiple T41 laptops (our corp standard).
Comment 1 Greg Kroah-Hartman 2005-08-12 09:26:13 UTC 
Please post the oops you receive here, and reopen the bug.

Can you also test 2.6.13-rc6?
Comment 2 Jeremey Wise 2005-08-12 13:55:20 UTC 
Here is the entire test process I ran: Sorry for the length... but it has all
the details I can provide:

**************************************
lspci -v -v
<snip>
0000:00:1d.0 USB Controller: Intel Corporation 82801DB/DBL/DBM
(ICH4/ICH4-L/ICH4-M) USB UHCI Controller #1 (rev 01) (prog-if 00 [UHCI])
        Subsystem: IBM: Unknown device 052d
        Control: I/O+ Mem- BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr-
Stepping- SERR- FastB2B-
        Status: Cap- 66Mhz- UDF- FastB2B+ ParErr- DEVSEL=medium >TAbort-
<TAbort- <MAbort- >SERR- <PERR-
        Latency: 0
        Interrupt: pin A routed to IRQ 11
        Region 4: I/O ports at 1800 [size=32]

0000:00:1d.1 USB Controller: Intel Corporation 82801DB/DBL/DBM
(ICH4/ICH4-L/ICH4-M) USB UHCI Controller #2 (rev 01) (prog-if 00 [UHCI])
        Subsystem: IBM: Unknown device 052d
        Control: I/O+ Mem- BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr-
Stepping- SERR- FastB2B-
        Status: Cap- 66Mhz- UDF- FastB2B+ ParErr- DEVSEL=medium >TAbort-
<TAbort- <MAbort- >SERR- <PERR-
        Latency: 0
        Interrupt: pin B routed to IRQ 11
        Region 4: I/O ports at 1820 [size=32]

0000:00:1d.2 USB Controller: Intel Corporation 82801DB/DBL/DBM
(ICH4/ICH4-L/ICH4-M) USB UHCI Controller #3 (rev 01) (prog-if 00 [UHCI])
        Subsystem: IBM: Unknown device 052d
        Control: I/O+ Mem- BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr-
Stepping- SERR- FastB2B-
        Status: Cap- 66Mhz- UDF- FastB2B+ ParErr- DEVSEL=medium >TAbort-
<TAbort- <MAbort- >SERR- <PERR-
        Latency: 0
        Interrupt: pin C routed to IRQ 11
        Region 4: I/O ports at 1840 [size=32]

0000:00:1d.7 USB Controller: Intel Corporation 82801DB/DBM (ICH4/ICH4-M) USB2
EHCI Controller (rev 01) (prog-if 20 [EHCI])
        Subsystem: IBM: Unknown device 052e
        Control: I/O- Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr-
Stepping- SERR+ FastB2B-
        Status: Cap+ 66Mhz- UDF- FastB2B+ ParErr- DEVSEL=medium >TAbort-
<TAbort- <MAbort- >SERR- <PERR-
        Latency: 0
        Interrupt: pin D routed to IRQ 11
        Region 0: Memory at c0000000 (32-bit, non-prefetchable) [size=1K]
        Capabilities: [50] Power Management version 2
                Flags: PMEClk- DSI- D1- D2- AuxCurrent=375mA
PME(D0+,D1-,D2-,D3hot+,D3cold+)
                Status: D0 PME-Enable- DSel=0 DScale=0 PME-
        Capabilities: [58] #0a [2080]
<snip>
***************************************************************************
wizej:~ # uname -a
Linux wizej 2.6.13-rc6-T41 #1 Fri Aug 12 14:35:25 EDT 2005 i686 i686 i386 GNU/Linux
***************************************************************************
wizej:~ # lsmod |grep hcd
wizej:~ #
***************************************************************************
modprobe ehci_hcd
Aug 12 16:28:32 wizej kernel: ACPI: PCI Interrupt Link [LNKH] enabled at IRQ 11
Aug 12 16:28:32 wizej kernel: ACPI: PCI Interrupt 0000:00:1d.7[D] -> Link [LNKH]
-> GSI 11 (level, low) -> IRQ 11
Aug 12 16:28:32 wizej kernel: PCI: Setting latency timer of device 0000:00:1d.7
to 64
Aug 12 16:28:32 wizej kernel: ehci_hcd 0000:00:1d.7: Intel Corporation
82801DB/DBM (ICH4/ICH4-M) USB2 EHCI Controller
Aug 12 16:28:32 wizej kernel: ehci_hcd 0000:00:1d.7: debug port 1
Aug 12 16:28:32 wizej kernel: ehci_hcd 0000:00:1d.7: new USB bus registered,
assigned bus number 1
Aug 12 16:28:32 wizej kernel: ehci_hcd 0000:00:1d.7: irq 11, io mem 0xc0000000
Aug 12 16:28:32 wizej kernel: PCI: cache line size of 32 is not supported by
device 0000:00:1d.7
Aug 12 16:28:32 wizej kernel: ehci_hcd 0000:00:1d.7: USB 2.0 initialized, EHCI
1.00, driver 10 Dec 2004
Aug 12 16:28:32 wizej kernel: hub 1-0:1.0: USB hub found
Aug 12 16:28:32 wizej kernel: hub 1-0:1.0: 6 ports detected
Aug 12 16:28:32 wizej kernel: usb 1-1: new high speed USB device using ehci_hcd
and address 2
Aug 12 16:28:33 wizej kernel: hub 1-1:1.0: USB hub found
Aug 12 16:28:33 wizej kernel: hub 1-1:1.0: 4 ports detected
                                                                      
modprobe usbserial
**********************************************************************
modprobe visor
Aug 12 16:30:30 wizej kernel: drivers/usb/serial/usb-serial.c: USB Serial
support registered for Handspring Visor / Palm OS
Aug 12 16:30:30 wizej kernel: drivers/usb/serial/usb-serial.c: USB Serial
support registered for Sony Clie 3.5
Aug 12 16:30:30 wizej kernel: drivers/usb/serial/usb-serial.c: USB Serial
support registered for Sony Clie 5.0
Aug 12 16:30:30 wizej kernel: usbcore: registered new driver visor
Aug 12 16:30:30 wizej kernel: drivers/usb/serial/visor.c: USB HandSpring Visor /
Palm OS driver v2.1
***********************************************************************
<Plug phone/palm in>
Aug 12 16:34:47 wizej kernel: usb 1-1.3: new full speed USB device using
ehci_hcd and address 3
Aug 12 16:34:47 wizej kernel: usb 1-1.3: palm_os_4_probe - error -32 getting
connection info
Aug 12 16:34:47 wizej kernel: visor 1-1.3:1.0: Handspring Visor / Palm OS
converter detected
Aug 12 16:34:47 wizej kernel: usb 1-1.3: Handspring Visor / Palm OS converter
now attached to ttyUSB0
Aug 12 16:34:47 wizej kernel: usb 1-1.3: Handspring Visor / Palm OS converter
now attached to ttyUSB1
Aug 12 16:34:47 wizej kernel: usb 1-1.3: palm_os_4_probe - error -32 getting
connection info
Aug 12 16:34:47 wizej kernel: visor 1-1.3:1.1: Handspring Visor / Palm OS
converter detected
Aug 12 16:34:47 wizej kernel: usb 1-1.3: Handspring Visor / Palm OS converter
now attached to ttyUSB2
Aug 12 16:34:47 wizej kernel: usb 1-1.3: Handspring Visor / Palm OS converter
now attached to ttyUSB3
Aug 12 16:34:54 wizej /etc/hotplug.d/ttyUSB0/50-visor.hotplug[4609]: add tty
device /class/tty/ttyUSB0
***********************************************************************
modprobe visor
***********************************************************************
lsmod |less
<snip>
Module                  Size  Used by
visor                  19212  0
ehci_hcd               36488  0
usbserial              31720  1 visor
serial_core            23808  1 8250
***********************************************************************
dlpsh -p /dev/usb/ttyUSB0
Aug 12 16:37:16 wizej kernel: visor ttyUSB1: Device lied about number of ports,
please use a lower one.
Aug 12 16:37:16 wizej kernel: visor ttyUSB1: Device lied about number of ports,
please use a lower one.
Aug 12 16:37:18 wizej kernel: visor ttyUSB1: Device lied about number of ports,
please use a lower one.
Aug 12 16:37:18 wizej kernel: visor ttyUSB1: Device lied about number of ports,
please use a lower one.
Aug 12 16:37:19 wizej kernel: usb 1-1.3: USB disconnect, address 3
Aug 12 16:37:19 wizej kernel: visor ttyUSB0: Handspring Visor / Palm OS
converter now disconnected from ttyUSB0
Aug 12 16:37:19 wizej kernel: visor ttyUSB1: Handspring Visor / Palm OS
converter now disconnected from ttyUSB1
Aug 12 16:37:19 wizej kernel: visor 1-1.3:1.0: device disconnected
Aug 12 16:37:19 wizej kernel: visor ttyUSB2: Handspring Visor / Palm OS
converter now disconnected from ttyUSB2
Aug 12 16:37:19 wizej kernel: visor ttyUSB3: Handspring Visor / Palm OS
converter now disconnected from ttyUSB3
Aug 12 16:37:19 wizej kernel: visor 1-1.3:1.1: device disconnected
Aug 12 16:37:19 wizej kernel: usb 1-1.3: new full speed USB device using
ehci_hcd and address 4
Aug 12 16:37:24 wizej kernel: usb 1-1.3: config index 0 descriptor too short
(expected 46, got 40)
Aug 12 16:37:24 wizej kernel: usb 1-1.3: config 1 descriptor has 1 excess byte,
ignoring
Aug 12 16:37:24 wizej kernel: usb 1-1.3: config 1 interface 0 altsetting 0 has 3
endpoint descriptors, different from the interface descriptor's value: 4
Aug 12 16:37:24 wizej kernel: usb 1-1.3: string descriptor 0 read error: -32
Aug 12 16:37:24 wizej kernel: usb 1-1.3: string descriptor 0 read error: -32
Aug 12 16:37:24 wizej kernel: usb 1-1.3: string descriptor 0 read error: -32
Aug 12 16:37:24 wizej kernel: usb 1-1.3: can't set config #1, error -32
Aug 12 16:37:24 wizej kernel: usb 1-1.3: new full speed USB device using
ehci_hcd and address 5
Aug 12 16:37:25 wizej kernel: visor 1-1.3:1.0: Handspring Visor / Palm OS
converter detected
Aug 12 16:37:25 wizej kernel: usb 1-1.3: Handspring Visor / Palm OS converter
now attached to ttyUSB0
Aug 12 16:37:25 wizej kernel: usb 1-1.3: Handspring Visor / Palm OS converter
now attached to ttyUSB1
Aug 12 16:37:32 wizej /etc/hotplug.d/ttyUSB0/50-visor.hotplug[5054]: add tty
device /class/tty/ttyUSB0

***********************************************************************
Aug 12 16:38:39 wizej /etc/hotplug.d/ttyUSB0/50-visor.hotplug[5329]: add tty
device /class/tty/ttyUSB0
Aug 12 16:38:42 wizej kernel: visor ttyUSB1: Device lied about number of ports,
please use a lower one.
Aug 12 16:38:42 wizej kernel: visor ttyUSB1: Device lied about number of ports,
please use a lower one.
Aug 12 16:38:44 wizej kernel: usb 1-1.3: USB disconnect, address 6
Aug 12 16:38:44 wizej kernel: visor ttyUSB0: Handspring Visor / Palm OS
converter now disconnected from ttyUSB0
Aug 12 16:38:44 wizej kernel: visor ttyUSB1: Handspring Visor / Palm OS
converter now disconnected from ttyUSB1
Aug 12 16:38:44 wizej kernel: visor 1-1.3:1.0: device disconnected
Aug 12 16:38:44 wizej kernel: visor ttyUSB2: Handspring Visor / Palm OS
converter now disconnected from ttyUSB2
Aug 12 16:38:44 wizej kernel: visor ttyUSB3: Handspring Visor / Palm OS
converter now disconnected from ttyUSB3
Aug 12 16:38:44 wizej kernel: visor 1-1.3:1.1: device disconnected
Aug 12 16:38:44 wizej kernel: Unable to handle kernel paging request at virtual
address 37333194
Aug 12 16:38:44 wizej kernel:  printing eip:
Aug 12 16:38:44 wizej kernel: c0273541
Aug 12 16:38:44 wizej kernel: *pde = 00000000
Aug 12 16:38:44 wizej kernel: Oops: 0000 [#1]
Aug 12 16:38:44 wizej kernel: PREEMPT
Aug 12 16:38:44 wizej kernel: Modules linked in: visor ehci_hcd ipt_LOG
ipt_limit usbserial sg st pcmcia firmware_class yenta_socket rsrc_nonstatic
pcmcia_core ipt_state ipt_REJECT iptable_mangle iptable_filter ip_nat_ftp
iptable_nat ip_conntrack_ftp ip_conntrack ip_tables raw1394 ieee1394 ext3 jbd
sr_mod sd_mod scsi_mod ide_cd cdrom 8250 serial_core
Aug 12 16:38:44 wizej kernel: CPU:    0
Aug 12 16:38:44 wizej kernel: EIP:    0060:[<c0273541>]    Not tainted VLI
Aug 12 16:38:44 wizej kernel: EFLAGS: 00010246   (2.6.13-rc6-T41)
Aug 12 16:38:44 wizej kernel: EIP is at check_tty_count+0x51/0xc0
Aug 12 16:38:44 wizej kernel: eax: 37333120   ebx: 00000000   ecx: e2b53980  
edx: e2b53980
Aug 12 16:38:44 wizej kernel: esi: e2b53000   edi: e2b53948   ebp: 00000000  
esp: efe05efc
Aug 12 16:38:44 wizej kernel: ds: 007b   es: 007b   ss: 0068
Aug 12 16:38:44 wizej kernel: Process events/0 (pid: 3, threadinfo=efe04000
task=c16ef020)
Aug 12 16:38:44 wizej kernel: Stack: 000000ad 0001bd47 efe3f590 c76059d3
000000ad efe04000 e2b53000 c02740a8
Aug 12 16:38:44 wizej kernel:        e2b53000 c03cc400 000000ad 00000000
00000000 e2b53944 efe04000 e2b53948
Aug 12 16:38:44 wizej kernel:        00000206 c012e3bd e2b53000 efe05f6c
00000000 c16db658 e2b53000 c0274040
Aug 12 16:38:44 wizej kernel: Call Trace:
Aug 12 16:38:44 wizej kernel:  [<c02740a8>] do_tty_hangup+0x68/0x400
Aug 12 16:38:44 wizej kernel:  [<c012e3bd>] worker_thread+0x1dd/0x2c0
Aug 12 16:38:44 wizej kernel:  [<c0274040>] do_tty_hangup+0x0/0x400
Aug 12 16:38:44 wizej kernel:  [<c0119db0>] default_wake_function+0x0/0x20
Aug 12 16:38:44 wizej kernel:  [<c0119db0>] default_wake_function+0x0/0x20
Aug 12 16:38:44 wizej kernel:  [<c012e1e0>] worker_thread+0x0/0x2c0
Aug 12 16:38:44 wizej kernel:  [<c0132b5d>] kthread+0xad/0xf0
Aug 12 16:38:44 wizej kernel:  [<c0132ab0>] kthread+0x0/0xf0
Aug 12 16:38:44 wizej kernel:  [<c010138d>] kernel_thread_helper+0x5/0x18
Aug 12 16:38:44 wizej kernel: Code: 00 39 ca 74 12 90 8d 74 26 00 89 c2 43 8b 00
0f 18 00 90 39 ca 75 f3 b8 00 e0 ff ff 21 e0 ff 48 14 8b 40 08 a8 08 75 5f 8b 46
04 <81> 78 74 04 00 02 00 74 37 8b 96 bc 00 00 00 31 c0 39 da 74 25
Aug 12 16:38:44 wizej kernel:  <6>usb 1-1.3: new full speed USB device using
ehci_hcd and address 7
Aug 12 16:38:44 wizej kernel: usb 1-1.3: palm_os_4_probe - error -32 getting
connection info
Aug 12 16:38:44 wizej kernel: visor 1-1.3:1.0: Handspring Visor / Palm OS
converter detected
Aug 12 16:38:44 wizej kernel: usb 1-1.3: Handspring Visor / Palm OS converter
now attached to ttyUSB0
Aug 12 16:38:44 wizej kernel: usb 1-1.3: Handspring Visor / Palm OS converter
now attached to ttyUSB1
Aug 12 16:38:51 wizej /etc/hotplug.d/ttyUSB0/50-visor.hotplug[5547]: add tty
device /class/tty/ttyUSB0

***********************************************************************
Comment 3 Greg Kroah-Hartman 2005-08-12 14:02:01 UTC 
Please put stuff like that in attachments, not in the comments :(
Comment 4 Greg Kroah-Hartman 2005-08-12 14:04:39 UTC 
Follow the description of what the driver is telling you to do, and it should
work properly...

Anyway, care to enable debugging in the driver:
  modprobe visor debug=1
and then running the program that causes the oops?

Then post (attach as an attachment) the resulting oops here.  That should give me
some hints as to how to fix this.
Comment 5 Jeremey Wise 2005-08-19 05:26:38 UTC 
I have tried to redirect the klogd messages out into a seperate file and
reproduce error in a consistant state but without luck. I do not know if this is
related to (how I do not know) change of the debug state to debug=1 but I have
not been able to capture the hang. At this time communication just times out. 
Still looking into what if anything could have changed or what I can do to get
the error out for your debuging.

I will post when my day-job allows me the time to build the test model to
recreate the error.
Comment 6 Greg Kroah-Hartman 2005-08-19 09:29:48 UTC 
Ok, going to mark as unreproducable then for now.
Comment 7 Pedro Garrett 2005-09-16 17:35:38 UTC 
I can reproduce the bug with kernel 2.6.13-rc6 .  This time it happened after I
pressed hotsync, while kpilot was probing the port and generating "Device lied
about number of ports" errors in the syslog, then pressed cancel on the pilot
device.  

Also note that the following always occurs in the syslog before a BUG or Oops:

Sep 16 20:13:52 thelorax usb 5-1: khubd timed out on ep0in len=40/46
Sep 16 20:13:52 thelorax usb 5-1: config index 0 descriptor too short (expected
46, got 40)
Sep 16 20:13:52 thelorax usb 5-1: config 1 descriptor has 1 excess byte, ignoring
Sep 16 20:13:52 thelorax usb 5-1: config 1 interface 0 altsetting 0 has 3
endpoint descriptors, different from the interface desncriptor's value: 4


Here's the BUG syslog output:

Sep 16 20:14:08 thelorax ------------[ cut here ]------------
Sep 16 20:14:08 thelorax kernel BUG at kernel/workqueue.c:104!
Sep 16 20:14:08 thelorax invalid operand: 0000 [#1]
Sep 16 20:14:08 thelorax PREEMPT 
Sep 16 20:14:08 thelorax Modules linked in: visor usbserial kqemu tun ppp_async
crc_ccitt sha1 ppp_mppe_mppc ppp_generic slhc smbfs usbhid rtc i8k
cpufreq_ondemand cpufreq_performance cpufreq_powersave speedstep_centrino
freq_table thermal processor fan ac battery button ipw2200 ieee80211
ieee80211_crypt firmware_class evdev nvidia agpgart uhci_hcd ehci_hcd usbcore
Sep 16 20:14:08 thelorax CPU:    0
Sep 16 20:14:08 thelorax EIP:    0060:[<c012937a>]    Tainted: P      VLI
Sep 16 20:14:08 thelorax EFLAGS: 00010217   (2.6.13-rc6) 
Sep 16 20:14:08 thelorax EIP is at queue_work+0x5a/0x70
Sep 16 20:14:08 thelorax eax: d4338948   ebx: 00000000   ecx: c17e9780   edx:
d4338944
Sep 16 20:14:08 thelorax esi: e4670cc0   edi: dea31014   ebp: ea2ef4f8   esp:
f6947df4
Sep 16 20:14:08 thelorax ds: 007b   es: 007b   ss: 0068
Sep 16 20:14:08 thelorax Process khubd (pid: 5558, threadinfo=f6946000
task=f6c7b0a0)
Sep 16 20:14:08 thelorax Stack: c01190b7 f8b5d674 00000001 f8b5c1a8 d4338000
f8b5d8a0 f8b5d4a0 00000003 
Sep 16 20:14:08 thelorax dea31000 f8bf6fe0 f8bf7000 f89f5196 dea31000 dea31000
dea31014 dea31074 
Sep 16 20:14:08 thelorax dea31014 c02712b6 dea31014 c03c794d dea31014 ea2ef464
dea31000 c02712ef 
Sep 16 20:14:08 thelorax Call Trace:
Sep 16 20:14:08 thelorax [<c01190b7>] printk+0x17/0x20
Sep 16 20:14:08 thelorax [<f8b5c1a8>] usb_serial_disconnect+0xb8/0x110 [usbserial]
Sep 16 20:14:08 thelorax [<f89f5196>] usb_unbind_interface+0x86/0x90 [usbcore]
Sep 16 20:14:08 thelorax [<c02712b6>] __device_release_driver+0x86/0xa0
Sep 16 20:14:08 thelorax [<c02712ef>] device_release_driver+0x1f/0x40
Sep 16 20:14:08 thelorax [<c0270a26>] bus_remove_device+0x76/0x90
Sep 16 20:14:08 thelorax [<c026fa22>] device_del+0x32/0x90
Sep 16 20:14:08 thelorax [<f89fe4ca>] usb_disable_device+0x16a/0x1a0 [usbcore]
Sep 16 20:14:08 thelorax [<f89f7f56>] usb_disconnect+0xa6/0x190 [usbcore]
Sep 16 20:14:08 thelorax [<f89f97c4>] hub_port_connect_change+0x484/0x4b0 [usbcore]
Sep 16 20:14:08 thelorax [<f89f9cd6>] hub_events+0x4e6/0x600 [usbcore]
Sep 16 20:14:08 thelorax [<f89f9e15>] hub_thread+0x25/0x130 [usbcore]
Sep 16 20:14:08 thelorax [<c012e050>] autoremove_wake_function+0x0/0x60
Sep 16 20:14:08 thelorax [<c012e050>] autoremove_wake_function+0x0/0x60
Sep 16 20:14:08 thelorax [<f89f9df0>] hub_thread+0x0/0x130 [usbcore]
Sep 16 20:14:08 thelorax [<c012db7a>] kthread+0xaa/0xb0
Sep 16 20:14:08 thelorax [<c012dad0>] kthread+0x0/0xb0
Sep 16 20:14:08 thelorax [<c0100fad>] kernel_thread_helper+0x5/0x18
Sep 16 20:14:08 thelorax Code: 00 89 0c 24 e8 28 ff ff ff b8 00 e0 ff ff 21 e0
ff 48 14 8b 40 08 a8 08 75 0a 89 d8 8b 5c 24 08 83 c4 0c c3 e8 68 ef 26 00 eb ef
<0f> 0b 68 00 2b e1 3a c0 eb c3 8d b6 00 00 00 00 8d bf 00 00 00 
Sep 16 20:14:08 thelorax <6>note: khubd[5558] exited with preempt_count 1
Comment 8 Greg Kroah-Hartman 2005-10-05 10:41:15 UTC 
Still happen on 2.6.14-rc3?
Comment 9 Jeremey Wise 2005-10-07 06:36:43 UTC 
Due to the lack of compatability with my phone and needing to get the investment
back out of the device, AND an unrelated bug in Evolution which stopped me from
being able to accept meetings with consistancy, I have had to move back to
Windows XP(:>(]. I can not offer any more testing at this time. Thanks to those
who offered help. Some day (soon I hope) I will revert back to the OS of my heart.
Comment 10 Greg Kroah-Hartman 2005-10-07 11:06:22 UTC 
Ok, am closing then.  Please reopen if you can test again.
Comment 11 Pedro Garrett 2005-10-17 13:55:18 UTC 
I still get the BUG in 2.6.14-rc3 .  I believe that it might be related to a
touchy USB connector, as I don't have as many problems when I hold the connector
in tight while trying operations (but the kernel still shouldn't BUG, I guess).


Oct 17 16:48:27 thelorax usb 5-1: Handspring Visor / Palm OS converter now
attached to ttyUSB0
Oct 17 16:48:27 thelorax usb 5-1: Handspring Visor / Palm OS converter now
attached to ttyUSB1
Oct 17 16:48:27 thelorax hub 5-0:1.0: state 5 ports 2 chg 0000 evt 0002
Oct 17 16:48:43 thelorax visor ttyUSB1: Device lied about number of ports,
please use a lower one.
Oct 17 16:48:45 thelorax visor ttyUSB1: Device lied about number of ports,
please use a lower one.
Oct 17 16:48:47 thelorax visor ttyUSB1: Device lied about number of ports,
please use a lower one.
Oct 17 16:48:49 thelorax visor ttyUSB1: Device lied about number of ports,
please use a lower one.
Oct 17 16:48:50 thelorax hub 1-0:1.0: state 5 ports 8 chg 0000 evt ff80
Oct 17 16:48:50 thelorax ehci_hcd 0000:00:1d.7: GetStatus port 7 status 001803
POWER sig=j CSC CONNECT
Oct 17 16:48:50 thelorax hub 1-0:1.0: port 7, status 0501, change 0001, 480 Mb/s
Oct 17 16:48:50 thelorax hub 1-0:1.0: debounce: port 7: total 100ms stable 100ms
status 0x501
Oct 17 16:48:50 thelorax ehci_hcd 0000:00:1d.7: port 7 full speed --> companion
Oct 17 16:48:50 thelorax ehci_hcd 0000:00:1d.7: GetStatus port 7 status 003801
POWER OWNER sig=j CONNECT
Oct 17 16:48:50 thelorax hub 5-0:1.0: state 5 ports 2 chg 0000 evt 0002
Oct 17 16:48:50 thelorax uhci_hcd 0000:00:1d.3: port 1 portsc 008b,00
Oct 17 16:48:50 thelorax hub 5-0:1.0: port 1, status 0101, change 0003, 12 Mb/s
Oct 17 16:48:50 thelorax usb 5-1: USB disconnect, address 6
Oct 17 16:48:50 thelorax usb 5-1: usb_disable_device nuking all URBs
Oct 17 16:48:50 thelorax usb 5-1: unregistering interface 5-1:1.0
Oct 17 16:48:50 thelorax ------------[ cut here ]------------
Oct 17 16:48:50 thelorax kernel BUG at kernel/workqueue.c:104!
Oct 17 16:48:50 thelorax invalid operand: 0000 [#1]
Oct 17 16:48:50 thelorax PREEMPT 
Oct 17 16:48:50 thelorax Modules linked in: nvidia agpgart visor usbserial
usbhid rtc i8k cpufreq_ondemand cpufreq_performance cpufreq_powersave
speedstep_centrino freq_table thermal processor fan ac battery button evdev
uhci_hcd ehci_hcd usbcore
Oct 17 16:48:50 thelorax CPU:    0
Oct 17 16:48:50 thelorax EIP:    0060:[<c0128eaa>]    Tainted: P      VLI
Oct 17 16:48:50 thelorax EFLAGS: 00010217   (2.6.14-rc3) 
Oct 17 16:48:50 thelorax EIP is at queue_work+0x5a/0x70
Oct 17 16:48:50 thelorax eax: d7a50948   ebx: 00000000   ecx: c17ea6c0   edx:
d7a50944
Oct 17 16:48:50 thelorax esi: dca0d1c0   edi: f6557a14   ebp: e03b0d00   esp:
f6d5fdf8
Oct 17 16:48:50 thelorax ds: 007b   es: 007b   ss: 0068
Oct 17 16:48:50 thelorax Process khubd (pid: 5564, threadinfo=f6d5e000
task=f6b19a90)
Oct 17 16:48:50 thelorax Stack: c039c310 f8a57000 00000001 f8a4d148 d7a50000
e03b0c00 00000003 f6557a00 
Oct 17 16:48:50 thelorax f8a56fe0 f8a57000 f89f61d6 f6557a00 f6557a00 f6557a14
f6557a7c f6557a14 
Oct 17 16:48:50 thelorax c0273056 f6557a14 c03ceb90 f6557a14 e03b0c64 f6557a00
c027308f f6557a14 
Oct 17 16:48:50 thelorax Call Trace:
Oct 17 16:48:50 thelorax [<c039c310>] klist_release+0x0/0x60
Oct 17 16:48:50 thelorax [<f8a4d148>] usb_serial_disconnect+0xa8/0xe0 [usbserial]
Oct 17 16:48:50 thelorax [<f89f61d6>] usb_unbind_interface+0x86/0x90 [usbcore]
Oct 17 16:48:50 thelorax [<c0273056>] __device_release_driver+0x86/0xa0
Oct 17 16:48:50 thelorax [<c027308f>] device_release_driver+0x1f/0x40
Oct 17 16:48:50 thelorax [<c0272736>] bus_remove_device+0x76/0x90
Oct 17 16:48:50 thelorax [<c02715a2>] device_del+0x32/0x90
Oct 17 16:48:50 thelorax [<f89ff61a>] usb_disable_device+0x16a/0x1a0 [usbcore]
Oct 17 16:48:50 thelorax [<f89f9196>] usb_disconnect+0xa6/0x1a0 [usbcore]
Oct 17 16:48:50 thelorax [<f89fa997>] hub_port_connect_change+0x487/0x4c0 [usbcore]
Oct 17 16:48:50 thelorax [<f89faeb6>] hub_events+0x4e6/0x600 [usbcore]
Oct 17 16:48:50 thelorax [<f89faff5>] hub_thread+0x25/0x130 [usbcore]
Oct 17 16:48:50 thelorax [<c012db60>] autoremove_wake_function+0x0/0x60
Oct 17 16:48:50 thelorax [<c012db60>] autoremove_wake_function+0x0/0x60
Oct 17 16:48:50 thelorax [<f89fafd0>] hub_thread+0x0/0x130 [usbcore]
Oct 17 16:48:50 thelorax [<c012d68a>] kthread+0xaa/0xb0
Oct 17 16:48:50 thelorax [<c012d5e0>] kthread+0x0/0xb0
Oct 17 16:48:50 thelorax [<c0101009>] kernel_thread_helper+0x5/0xc
Oct 17 16:48:50 thelorax Code: 00 89 0c 24 e8 28 ff ff ff b8 00 e0 ff ff 21 e0
ff 48 14 8b 40 08 a8 08 75 0a 89 d8 8b 5c 24 08 83 c4 0c c3 e8 f8 3e 27 00 eb ef
<0f> 0b 68 00 eb 55 3b c0 eb c3 8d b6 00 00 00 00 8d bf 00 00 00 
Oct 17 16:48:50 thelorax <6>note: khubd[5564] exited with preempt_count 1
Comment 12 Greg Kroah-Hartman 2005-11-14 21:23:59 UTC 
ugh, does 2.6.14 fix this?
Comment 13 Jeremey Wise 2005-11-15 05:08:58 UTC 
Due to this bug in part, but also an unrelated error in Evolution injecting
email one hour in the future (into MS Exchange and Lotus Notes), I was forced
dragging and kicking back to Windows XP. I can not at this time perform any
additional testing to the resolution of this issue.

What I can offer is this. My hope was to at a minimum leverage the phones full
functionality under Windows. Bzzzzz.. not so! Same issue. Plug phone in and try
to sync and I bluescreen Windows. IMHO the phone sucks on sync (and of corse out
of return policy) but on the bright side I feel better about Linux.

Thanks for all your help.

Sincerly,

"The $500 Cell phone owner with lots of bling factor but no use, owner"
Comment 14 Jeremey Wise 2005-11-15 05:09:39 UTC 
Due to this bug in part, but also an unrelated error in Evolution injecting
email one hour in the future (into MS Exchange and Lotus Notes), I was forced
dragging and kicking back to Windows XP. I can not at this time perform any
additional testing to the resolution of this issue.

What I can offer is this. My hope was to at a minimum leverage the phones full
functionality under Windows. Bzzzzz.. not so! Same issue. Plug phone in and try
to sync and I bluescreen Windows. IMHO the phone sucks on sync (and of corse out
of return policy) but on the bright side I feel better about Linux.

Thanks for all your help.

Sincerly,

"The $500 Cell phone owner with lots of bling factor but no use, owner"
Comment 15 Greg Kroah-Hartman 2005-11-15 09:17:48 UTC 
Ok, marking closed as this looks like a hardware issue.