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

To: port-i386-maintainer%netbsd.org@localhost,gnats-admin%netbsd.org@localhost,netbsd-bugs%netbsd.org@localhost,als%thangorodrim.ch@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 08:20:02 +0000 (UTC)

The following reply was made to PR port-i386/57662; it has been noted by GNATS.

From: Alexander Schreiber <als%thangorodrim.ch@localhost>
To: Taylor R Campbell <riastradh%netbsd.org@localhost>
Cc: matthew green <mrg%eterna.com.au@localhost>, port-i386-maintainer%netbsd.org@localhost,
	gnats-admin%netbsd.org@localhost, netbsd-bugs%netbsd.org@localhost,
	gnats-bugs%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
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

Prev by Date: Re: port-i386/57662: StarTech ICUSB23208FD 8-Port USB-to-Serial Adapter Hub fails on Alix with NetBSD/i386 9.3
Next by Date: Re: kern/46522 (wscons: deleting a screen causes kernel crash)
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: PR/57650 CVS commit: src/sys/net/lagg
Indexes:

Home | Main Index | Thread Index | Old Index