Re: port-i386/57662: StarTech ICUSB23208FD 8-Port USB-to-Serial Adapter Hub fails on Alix with NetBSD/i386 9.3

To: Taylor R Campbell <riastradh%netbsd.org@localhost>
Subject: Re: port-i386/57662: StarTech ICUSB23208FD 8-Port USB-to-Serial Adapter Hub fails on Alix with NetBSD/i386 9.3
From: Alexander Schreiber <als%thangorodrim.ch@localhost>
Date: Mon, 13 Nov 2023 09:12:00 +0100

On Sat, Oct 28, 2023 at 09:29:09PM +0000, Taylor R Campbell wrote:
> > Date: Sat, 28 Oct 2023 22:26:06 +0200
> > From: Alexander Schreiber <als%thangorodrim.ch@localhost>
> > 
> > Both patches applied and things do look better. Now, instead of hanging
> > forever, after the second (so, at the third) connection attempt, cu
> > errors out with: "cu: link down" after a few seconds. This behavior then
> > sticks.
> > 
> > Relevant snippet from the trace:
> > 
> >   1699   1699 cu       0.000010616 CALL  open(0xbfb9a56e,6,0)
> >   1699   1699 cu       0.000011733 NAMI  "/dev/ttyU1"
> >   1699   1699 cu       11.991814027 RET   open -1 errno 5 Input/output error
> > 
> > vmstat usbhist dump at:
> > 
> > https://www.thangorodrim.ch/tmp/NetBSD10BETA_serial_vmstat-usbhist_patch-1
> 
> Great, thanks!  So we've fixed the logic to abort on timeout that I
> broke last year (which is good!), but now we have to figure out why
> the transfer is timing out at all.

I've done another test: Boot up machine, plug in USB2serial device,
try some connections, wait for them to time out and things to settle.
Unplug USB2serial device, wait a bit for things to settle, plug it
back in. Result:
 - unplugging the device is noticed and the expected messages show
   up in the kernel log, but with a scattering of time out messages
 - plugging the device yields USB problems, ports disabled, device
   no longer recognized

So, maybe the hardware side of the USB stack for this little Geode CPU
manages to get wedged?

Syslog snippet attached:

--------------------- /var/log/messages begins ----------------------


Oct 31 14:47:42 console /netbsd: [ 394.1174957] ehci_sync_hc: timed out
Oct 31 14:47:42 console /netbsd: [ 400.1176959] ehci_sync_hc: timed out
Oct 31 14:47:42 console /netbsd: [ 406.1178962] ehci_sync_hc: timed out
Oct 31 14:47:42 console /netbsd: [ 154334.7451176] ehci_sync_hc: timed out
Oct 31 14:47:42 console /netbsd: [ 154335.7451510] ehci_sync_hc: timed out
Oct 31 14:47:42 console /netbsd: [ 154335.7451510] ucom0: detached
Oct 31 14:47:42 console /netbsd: [ 154335.7451510] uftdi0: detached
Oct 31 14:47:42 console /netbsd: [ 154335.7551535] uftdi0: at uhub2 port 2 (addr 3) disconnected
Oct 31 14:47:44 console /netbsd: [ 154336.7551844] ehci_sync_hc: timed out
Oct 31 14:47:44 console /netbsd: [ 154337.7552181] ehci_sync_hc: timed out
Oct 31 14:47:44 console /netbsd: [ 154337.7552181] ucom1: detached
Oct 31 14:47:44 console /netbsd: [ 154337.7552181] uftdi1: detached
Oct 31 14:47:44 console /netbsd: [ 154337.7652254] uftdi1: at uhub2 port 2 (addr 3) disconnected
Oct 31 14:47:46 console /netbsd: [ 154338.7652515] ehci_sync_hc: timed out
Oct 31 14:47:46 console /netbsd: [ 154339.7652849] ehci_sync_hc: timed out
Oct 31 14:47:46 console /netbsd: [ 154339.7652849] ucom2: detached
Oct 31 14:47:46 console /netbsd: [ 154339.7652849] uftdi2: detached
Oct 31 14:47:46 console /netbsd: [ 154339.7752871] uftdi2: at uhub2 port 2 (addr 3) disconnected
Oct 31 14:47:48 console /netbsd: [ 154340.7753188] ehci_sync_hc: timed out
Oct 31 14:47:48 console /netbsd: [ 154341.7753519] ehci_sync_hc: timed out
Oct 31 14:47:48 console /netbsd: [ 154341.7753519] ucom3: detached
Oct 31 14:47:48 console /netbsd: [ 154341.7753519] uftdi3: detached
Oct 31 14:47:48 console /netbsd: [ 154341.7853609] uftdi3: at uhub2 port 2 (addr 3) disconnected
Oct 31 14:47:51 console /netbsd: [ 154342.7853857] ehci_sync_hc: timed out
Oct 31 14:47:51 console /netbsd: [ 154343.7854193] ehci_sync_hc: timed out
Oct 31 14:47:51 console /netbsd: [ 154344.7854541] ehci_sync_hc: timed out
Oct 31 14:47:51 console /netbsd: [ 154344.7854541] ucom4: detached
Oct 31 14:47:51 console /netbsd: [ 154344.7854541] uftdi4: detached
Oct 31 14:47:51 console /netbsd: [ 154344.7854541] uftdi4: at uhub2 port 3 (addr 4) disconnected
Oct 31 14:47:53 console /netbsd: [ 154345.7954864] ehci_sync_hc: timed out
Oct 31 14:47:53 console /netbsd: [ 154346.7955198] ehci_sync_hc: timed out
Oct 31 14:47:53 console /netbsd: [ 154346.7955198] ucom5: detached
Oct 31 14:47:53 console /netbsd: [ 154346.7955198] uftdi5: detached
Oct 31 14:47:53 console /netbsd: [ 154346.8055240] uftdi5: at uhub2 port 3 (addr 4) disconnected
Oct 31 14:47:55 console /netbsd: [ 154347.8055532] ehci_sync_hc: timed out
Oct 31 14:47:55 console /netbsd: [ 154348.8055869] ehci_sync_hc: timed out
Oct 31 14:47:55 console /netbsd: [ 154348.8055869] ucom6: detached
Oct 31 14:47:55 console /netbsd: [ 154348.8055869] uftdi6: detached
Oct 31 14:47:55 console /netbsd: [ 154348.8155903] uftdi6: at uhub2 port 3 (addr 4) disconnected
Oct 31 14:47:57 console /netbsd: [ 154349.8156214] ehci_sync_hc: timed out
Oct 31 14:47:57 console /netbsd: [ 154350.8156548] ehci_sync_hc: timed out
Oct 31 14:47:57 console /netbsd: [ 154350.8156548] ucom7: detached
Oct 31 14:47:57 console /netbsd: [ 154350.8156548] uftdi7: detached
Oct 31 14:47:57 console /netbsd: [ 154350.8256604] uftdi7: at uhub2 port 3 (addr 4) disconnected
Oct 31 14:48:00 console /netbsd: [ 154351.8256876] ehci_sync_hc: timed out
Oct 31 14:48:00 console /netbsd: [ 154352.8257210] ehci_sync_hc: timed out
Oct 31 14:48:00 console /netbsd: [ 154353.8257544] ehci_sync_hc: timed out
Oct 31 14:48:00 console /netbsd: [ 154353.8257544] uhub2: detached
Oct 31 14:48:00 console /netbsd: [ 154353.8257544] uhub2: at uhub1 port 1 (addr 2) disconnected
Oct 31 14:48:30 console ntpd[732]: Soliciting pool server 195.186.1.101
Oct 31 14:49:13 console /netbsd: [ 154354.8357878] ehci_sync_hc: timed out
Oct 31 14:49:13 console /netbsd: [ 154369.6462824] ehci_sync_hc: timed out
Oct 31 14:49:13 console /netbsd: [ 154375.8564896] ehci_sync_hc: timed out
Oct 31 14:49:13 console /netbsd: [ 154382.0666969] ehci_sync_hc: timed out
Oct 31 14:49:13 console /netbsd: [ 154388.2769042] ehci_sync_hc: timed out
Oct 31 14:49:13 console /netbsd: [ 154394.8471234] ehci_sync_hc: timed out
Oct 31 14:49:13 console /netbsd: [ 154401.0573307] ehci_sync_hc: timed out
Oct 31 14:49:13 console /netbsd: [ 154407.2675380] ehci_sync_hc: timed out
Oct 31 14:49:13 console /netbsd: [ 154413.4777449] ehci_sync_hc: timed out
Oct 31 14:49:13 console /netbsd: [ 154413.9677616] gcscehci0: handing over full speed device on port 1 to ohci0
Oct 31 14:49:13 console /netbsd: [ 154420.0279638] ehci_sync_hc: timed out
Oct 31 14:49:13 console /netbsd: [ 154426.2381711] ehci_sync_hc: timed out
Oct 31 14:49:13 console /netbsd: [ 154427.4482115] ehci_sync_hc: timed out
Oct 31 14:49:13 console /netbsd: [ 154427.4482115] uhub1: autoconfiguration error: device problem, disabling port 1
Oct 31 14:49:34 console ntpd[732]: Soliciting pool server 81.6.2.165
Oct 31 14:50:00 console cron[5467]: (root) CMD START (/usr/libexec/atrun)
Oct 31 14:50:00 console cron[4190]: (root) CMD FINISH (/usr/libexec/atrun)
Oct 31 14:50:39 console ntpd[732]: Soliciting pool server 81.94.123.16
Oct 31 14:50:58 console /netbsd: [ 154474.8897948] ehci_sync_hc: timed out
Oct 31 14:50:58 console /netbsd: [ 154481.1000032] ehci_sync_hc: timed out
Oct 31 14:50:58 console /netbsd: [ 154487.3102093] ehci_sync_hc: timed out
Oct 31 14:50:58 console /netbsd: [ 154493.5204177] ehci_sync_hc: timed out
Oct 31 14:50:58 console /netbsd: [ 154494.0104329] gcscehci0: handing over full speed device on port 1 to ohci0
Oct 31 14:50:58 console /netbsd: [ 154500.0706352] ehci_sync_hc: timed out
Oct 31 14:50:58 console /netbsd: [ 154506.2808422] ehci_sync_hc: timed out
Oct 31 14:50:58 console /netbsd: [ 154512.4910497] ehci_sync_hc: timed out
Oct 31 14:50:58 console /netbsd: [ 154518.7012567] ehci_sync_hc: timed out
Oct 31 14:50:58 console /netbsd: [ 154519.1912733] gcscehci0: handing over full speed device on port 1 to ohci0
Oct 31 14:50:58 console /netbsd: [ 154525.2514756] ehci_sync_hc: timed out
Oct 31 14:50:58 console /netbsd: [ 154531.4616828] ehci_sync_hc: timed out
Oct 31 14:50:58 console /netbsd: [ 154532.6717232] ehci_sync_hc: timed out
Oct 31 14:50:58 console /netbsd: [ 154532.6717232] uhub1: autoconfiguration error: device problem, disabling port 1


--------------------- /var/log/messages ends   ----------------------

Kind regards,
           Alex.
-- 
"Opportunity is missed by most people because it is dressed in overalls and
 looks like work."                                      -- Thomas A. Edison

References:
- Re: port-i386/57662: StarTech ICUSB23208FD 8-Port USB-to-Serial Adapter Hub fails on Alix with NetBSD/i386 9.3
  - From: Alexander Schreiber
- Re: port-i386/57662: StarTech ICUSB23208FD 8-Port USB-to-Serial Adapter Hub fails on Alix with NetBSD/i386 9.3
  - From: Taylor R Campbell

Prev by Date: kern/57696: NetBSD-10.0_RC1-amd64-install.img.gz kernel panics at reboot or shutdown on mid 2010 21.5" iMac
Next by Date: Re: port-i386/57662: StarTech ICUSB23208FD 8-Port USB-to-Serial Adapter Hub fails on Alix with NetBSD/i386 9.3
Previous by Thread: Re: port-i386/57662: StarTech ICUSB23208FD 8-Port USB-to-Serial Adapter Hub fails on Alix with NetBSD/i386 9.3
Next by Thread: Re: port-i386/57662: StarTech ICUSB23208FD 8-Port USB-to-Serial Adapter Hub fails on Alix with NetBSD/i386 9.3
Indexes:

Home | Main Index | Thread Index | Old Index