From owner-freebsd-usb@freebsd.org  Fri Jul 13 20:58:01 2018
Return-Path: <owner-freebsd-usb@freebsd.org>
Delivered-To: freebsd-usb@mailman.ysv.freebsd.org
Received: from mx1.freebsd.org (mx1.freebsd.org [IPv6:2610:1c1:1:606c::19:1])
 by mailman.ysv.freebsd.org (Postfix) with ESMTP id F02F5104CA4D
 for <freebsd-usb@mailman.ysv.freebsd.org>;
 Fri, 13 Jul 2018 20:58:00 +0000 (UTC) (envelope-from stb@lassitu.de)
Received: from gilb.zs64.net (gilb.zs64.net [IPv6:2a00:14b0:4200:32e0::1ea])
 (using TLSv1.2 with cipher ECDHE-RSA-AES256-GCM-SHA384 (256/256 bits))
 (Client CN "gilb.zs64.net", Issuer "Let's Encrypt Authority X3" (verified OK))
 by mx1.freebsd.org (Postfix) with ESMTPS id 83D7378514
 for <freebsd-usb@freebsd.org>; Fri, 13 Jul 2018 20:58:00 +0000 (UTC)
 (envelope-from stb@lassitu.de)
Received: by gilb.zs64.net (Postfix, from stb@lassitu.de) id B994A1D9C2A
 for <freebsd-usb@freebsd.org>; Fri, 13 Jul 2018 20:57:58 +0000 (UTC)
From: Stefan Bethke <stb@lassitu.de>
Content-Type: text/plain;
	charset=utf-8
Content-Transfer-Encoding: quoted-printable
Mime-Version: 1.0 (Mac OS X Mail 11.5 \(3445.9.1\))
Subject: Re: USB problems on PC Engines APU2C4
Date: Fri, 13 Jul 2018 22:57:57 +0200
References: <CF83933C-DECF-4B93-A4F3-3B4C5271F6BB@lassitu.de>
To: freebsd-usb@freebsd.org
In-Reply-To: <CF83933C-DECF-4B93-A4F3-3B4C5271F6BB@lassitu.de>
Message-Id: <43997AE2-089D-48AE-9285-9B3C6864DC9D@lassitu.de>
X-Mailer: Apple Mail (2.3445.9.1)
X-BeenThere: freebsd-usb@freebsd.org
X-Mailman-Version: 2.1.27
Precedence: list
List-Id: FreeBSD support for USB <freebsd-usb.freebsd.org>
List-Unsubscribe: <https://lists.freebsd.org/mailman/options/freebsd-usb>,
 <mailto:freebsd-usb-request@freebsd.org?subject=unsubscribe>
List-Archive: <http://lists.freebsd.org/pipermail/freebsd-usb/>
List-Post: <mailto:freebsd-usb@freebsd.org>
List-Help: <mailto:freebsd-usb-request@freebsd.org?subject=help>
List-Subscribe: <https://lists.freebsd.org/mailman/listinfo/freebsd-usb>,
 <mailto:freebsd-usb-request@freebsd.org?subject=subscribe>
X-List-Received-Date: Fri, 13 Jul 2018 20:58:01 -0000

Am 12.07.2018 um 09:27 schrieb Stefan Bethke <stb@lassitu.de>:
>=20
> First, I had trouble installing from:
> ugen0.2: <Kingston DataTraveler G3> at usbus0
> umass0 on uhub1
> umass0: <Kingston DataTraveler G3, class 0/0, rev 2.00/1.00, addr 1> =
on usbus0
> umass0:  SCSI over Bulk-Only; quirks =3D 0x8100
> umass0:2:0: Attached to scbus2
>=20
> This is all that gets probed on most attempts to connect the stick. =
Sometimes, it succeeds immediately, or after a couple of retries, like =
this:
> ugen0.2: <Kingston DataTraveler G3> at usbus0
> umass0 on uhub1
> umass0: <Kingston DataTraveler G3, class 0/0, rev 2.00/1.00, addr 1> =
on usbus0
> umass0:  SCSI over Bulk-Only; quirks =3D 0x8100
> umass0:2:0: Attached to scbus2
> (probe0:umass-sim0:0:0:0): INQUIRY. CDB: 12 00 00 00 24 00=20
> (probe0:umass-sim0:0:0:0): CAM status: CCB request completed with an =
error
> (probe0:umass-sim0:0:0:0): Retrying command
> da0 at umass-sim0 bus 0 scbus2 target 0 lun 0
> da0: <Kingston DataTraveler G3 PMAP> Removable Direct Access SCSI =
device
> da0: Serial Number 001CC0EC34F4BB6155F10168
> da0: 40.000MB/s transfers
> da0: 15252MB (31236096 512 byte sectors)
> da0: quirks=3D0x2<NO_6_BYTE>
>=20
> On other machines, that stick works just fine.

Here=E2=80=99s the console output interspersed with the output from =
usbdump. In this instance, the stick did attach after some time; other =
times, kernel messages stop after "umass0:2:0: Attached to scbus2=E2=80=9C=
, or I see a large number of inquiry errors.

[root@apu2 ~]# usbdump -i usbus0
22:51:25.704227 usbus0.2 =
SUBM-CTRL-EP=3D00000080,SPD=3DHIGH,NFR=3D2,SLEN=3D8,IVAL=3D0
22:51:25.705750 usbus0.2 =
DONE-CTRL-EP=3D00000080,SPD=3DHIGH,NFR=3D2,SLEN=3D20,IVAL=3D0,ERR=3D0
22:51:25.708174 usbus0.2 =
SUBM-CTRL-EP=3D00000080,SPD=3DHIGH,NFR=3D2,SLEN=3D8,IVAL=3D0
22:51:25.709762 usbus0.2 =
DONE-CTRL-EP=3D00000080,SPD=3DHIGH,NFR=3D2,SLEN=3D4,IVAL=3D0,ERR=3D0
22:51:25.709778 usbus0.2 =
SUBM-CTRL-EP=3D00000080,SPD=3DHIGH,NFR=3D2,SLEN=3D8,IVAL=3D0
22:51:25.710754 usbus0.2 =
DONE-CTRL-EP=3D00000080,SPD=3DHIGH,NFR=3D2,SLEN=3D4,IVAL=3D0,ERR=3D0
22:51:25.710770 usbus0.2 =
SUBM-CTRL-EP=3D00000080,SPD=3DHIGH,NFR=3D2,SLEN=3D8,IVAL=3D0
22:51:25.712893 usbus0.2 =
DONE-CTRL-EP=3D00000080,SPD=3DHIGH,NFR=3D2,SLEN=3D4,IVAL=3D0,ERR=3D0
22:51:25.712910 usbus0.2 =
SUBM-CTRL-EP=3D00000080,SPD=3DHIGH,NFR=3D2,SLEN=3D8,IVAL=3D0
22:51:25.715125 usbus0.2 =
DONE-CTRL-EP=3D00000080,SPD=3DHIGH,NFR=3D2,SLEN=3D52,IVAL=3D0,ERR=3D0
22:51:25.715168 usbus0.2 =
SUBM-CTRL-EP=3D00000080,SPD=3DHIGH,NFR=3D2,SLEN=3D8,IVAL=3D0
22:51:25.717134 usbus0.2 =
DONE-CTRL-EP=3D00000080,SPD=3DHIGH,NFR=3D2,SLEN=3D4,IVAL=3D0,ERR=3D0
22:51:25.717149 usbus0.2 =
SUBM-CTRL-EP=3D00000080,SPD=3DHIGH,NFR=3D2,SLEN=3D8,IVAL=3D0
22:51:25.719249 usbus0.2 =
DONE-CTRL-EP=3D00000080,SPD=3DHIGH,NFR=3D2,SLEN=3D20,IVAL=3D0,ERR=3D0
22:51:25.719265 usbus0.2 =
SUBM-CTRL-EP=3D00000080,SPD=3DHIGH,NFR=3D2,SLEN=3D8,IVAL=3D0
22:51:25.721372 usbus0.2 =
DONE-CTRL-EP=3D00000080,SPD=3DHIGH,NFR=3D2,SLEN=3D4,IVAL=3D0,ERR=3D0
22:51:25.721414 usbus0.2 =
SUBM-CTRL-EP=3D00000080,SPD=3DHIGH,NFR=3D2,SLEN=3D8,IVAL=3D0
22:51:25.723498 usbus0.2 =
DONE-CTRL-EP=3D00000080,SPD=3DHIGH,NFR=3D2,SLEN=3D32,IVAL=3D0,ERR=3D0
22:51:25.723573 usbus0.2 =
SUBM-CTRL-EP=3D00000080,SPD=3DHIGH,NFR=3D2,SLEN=3D8,IVAL=3D0
22:51:25.724495 usbus0.2 =
DONE-CTRL-EP=3D00000080,SPD=3DHIGH,NFR=3D2,SLEN=3D12,IVAL=3D0,ERR=3D0
22:51:25.724511 usbus0.2 =
SUBM-CTRL-EP=3D00000080,SPD=3DHIGH,NFR=3D2,SLEN=3D8,IVAL=3D0
22:51:25.725497 usbus0.2 =
DONE-CTRL-EP=3D00000080,SPD=3DHIGH,NFR=3D2,SLEN=3D32,IVAL=3D0,ERR=3D0
22:51:25.725694 usbus0.2 =
SUBM-CTRL-EP=3D00000000,SPD=3DHIGH,NFR=3D1,SLEN=3D8,IVAL=3D0
22:51:25.726245 usbus0.2 =
DONE-CTRL-EP=3D00000000,SPD=3DHIGH,NFR=3D1,SLEN=3D0,IVAL=3D0,ERR=3D0
22:51:26.745113 usbus0.2 =
SUBM-CTRL-EP=3D00000080,SPD=3DHIGH,NFR=3D2,SLEN=3D8,IVAL=3D0
22:51:26.746140 usbus0.2 =
DONE-CTRL-EP=3D00000080,SPD=3DHIGH,NFR=3D2,SLEN=3D4,IVAL=3D0,ERR=3D0
22:51:26.746168 usbus0.2 =
SUBM-BULK-EP=3D00000002,SPD=3DHIGH,NFR=3D1,SLEN=3D32,IVAL=3D0
22:51:26.746928 usbus0.2 =
DONE-BULK-EP=3D00000002,SPD=3DHIGH,NFR=3D1,SLEN=3D0,IVAL=3D0,ERR=3D0
22:51:26.746938 usbus0.2 =
SUBM-BULK-EP=3D00000081,SPD=3DHIGH,NFR=3D1,SLEN=3D0,IVAL=3D0
22:51:26.777984 usbus0.2 =
DONE-BULK-EP=3D00000081,SPD=3DHIGH,NFR=3D1,SLEN=3D36,IVAL=3D0,ERR=3D0
22:51:26.777994 usbus0.2 =
SUBM-BULK-EP=3D00000081,SPD=3DHIGH,NFR=3D1,SLEN=3D0,IVAL=3D0
22:51:26.778122 usbus0.2 =
DONE-BULK-EP=3D00000081,SPD=3DHIGH,NFR=3D1,SLEugen0.2: <Kingston =
DataTraveler G3> at usbus0
umass0 on uhub1
umass0: <Kingston DataTraveler G3, class 0/0, rev 2.00/1.00, addr 1> on =
usbus0
umass0:  SCSI over Bulk-Only; quirks =3D 0x8100
umass0:2:0: Attached to scbus2
N=3D16,IVAL=3D0,ERR=3D0
22:51:26.778142 usbus0.2 =
SUBM-BULK-EP=3D00000002,SPD=3DHIGH,NFR=3D1,SLEN=3D32,IVAL=3D0
22:51:26.778237 usbus0.2 =
DONE-BULK-EP=3D00000002,SPD=3DHIGH,NFR=3D1,SLEN=3D0,IVAL=3D0,ERR=3D0
22:51:26.778243 usbus0.2 =
SUBM-BULK-EP=3D00000081,SPD=3DHIGH,NFR=3D1,SLEN=3D0,IVAL=3D0
22:51:26.778378 usbus0.2 =
DONE-BULK-EP=3D00000081,SPD=3DHIGH,NFR=3D1,SLEN=3D16,IVAL=3D0,ERR=3D0
22:51:26.778391 usbus0.2 =
SUBM-BULK-EP=3D00000002,SPD=3DHIGH,NFR=3D1,SLEN=3D32,IVAL=3D0
22:51:26.778492 usbus0.2 =
DONE-BULK-EP=3D00000002,SPD=3DHIGH,NFR=3D1,SLEN=3D0,IVAL=3D0,ERR=3D0
22:51:26.778497 usbus0.2 =
SUBM-BULK-EP=3D00000081,SPD=3DHIGH,NFR=3D1,SLEN=3D0,IVAL=3D0
22:51:26.778620 usbus0.2 =
DONE-BULK-EP=3D00000081,SPD=3DHIGH,NFR=3D1,SLEN=3D16,IVAL=3D0,ERR=3D0
22:51:26.778632 usbus0.2 =
SUBM-BULK-EP=3D00000002,SPD=3DHIGH,NFR=3D1,SLEN=3D32,IVAL=3D0
22:51:26.778748 usbus0.2 =
DONE-BULK-EP=3D00000002,SPD=3DHIGH,NFR=3D1,SLEN=3D0,IVAL=3D0,ERR=3D0
22:51:26.778753 usbus0.2 =
SUBM-BULK-EP=3D00000081,SPD=3DHIGH,NFR=3D1,SLEN=3D0,IVAL=3D0
22:51:26.778876 usbus0.2 =
DONE-BULK-EP=3D00000081,SPD=3DHIGH,NFR=3D1,SLEN=3D16,IVAL=3D0,ERR=3D0
22:51:26.778888 usbus0.2 =
SUBM-BULK-EP=3D00000002,SPD=3DHIGH,NFR=3D1,SLEN=3D32,IVAL=3D0
22:51:26.779004 usbus0.2 =
DONE-BULK-EP=3D00000002,SPD=3DHIGH,NFR=3D1,SLEN=3D0,IVAL=3D0,ERR=3D0
22:51:26.779010 usbus0.2 =
SUBM-BULK-EP=3D00000081,SPD=3DHIGH,NFR=3D1,SLEN=3D0,IVAL=3D0
22:51:26.780288 usbus0.2 =
DONE-BULK-EP=3D00000081,SPD=3DHIGH,NFR=3D1,SLEN=3D0,IVAL=3D0,ERR=3DSTALLED=

22:51:26.780298 usbus0.2 =
SUBM-CTRL-EP=3D00000000,SPD=3DHIGH,NFR=3D1,SLEN=3D8,IVAL=3D0
22:51:26.780883 usbus0.2 =
DONE-CTRL-EP=3D00000000,SPD=3DHIGH,NFR=3D1,SLEN=3D0,IVAL=3D0,ERR=3D0
22:51:26.780889 usbus0.2 =
SUBM-BULK-EP=3D00000081,SPD=3DHIGH,NFR=3D1,SLEN=3D0,IVAL=3D0
22:51:26.781886 usbus0.2 =
DONE-BULK-EP=3D00000081,SPD=3DHIGH,NFR=3D1,SLEN=3D16,IVAL=3D0,ERR=3D0
22:51:26.781900 usbus0.2 =
SUBM-BULK-EP=3D00000002,SPD=3DHIGH,NFR=3D1,SLEN=3D32,IVAL=3D0
22:51:26.782027 usbus0.2 =
DONE-BULK-EP=3D00000002,SPD=3DHIGH,NFR=3D1,SLEN=3D0,IVAL=3D0,ERR=3D0
22:51:26.782033 usbus0.2 =
SUBM-BULK-EP=3D00000081,SPD=3DHIGH,NFR=3D1,SLEN=3D0,IVAL=3D0
22:51:26.783036 usbus0.2 =
DONE-BULK-EP=3D00000081,SPD=3DHIGH,NFR=3D1,SLEN=3D0,IVAL=3D0,ERR=3DSTALLED=

22:51:26.783045 usbus0.2 =
SUBM-CTRL-EP=3D00000000,SPD=3DHIGH,NFR=3D1,SLEN=3D8,IVAL=3D0
22:51:26.783640 usbus0.2 =
DONE-CTRL-EP=3D00000000,SPD=3DHIGH,NFR=3D1,SLEN=3D0,IVAL=3D0,ERR=3D0
22:51:26.783646 usbus0.2 =
SUBM-BULK-EP=3D00000081,SPD=3DHIGH,NFR=3D1,SLEN=3D0,IVAL=3D0
22:51:26.784781 usbus0.2 =
DONE-BULK-EP=3D00000081,SPD=3DHIGH,NFR=3D1,SLEN=3D16,IVAL=3D0,ERR=3D0
22:51:26.784794 usbus0.2 =
SUBM-BULK-EP=3D00000002,SPD=3DHIGH,NFR=3D1,SLEN=3D32,IVAL=3D0
22:51:26.784889 usbus0.2 =
DONE-BULK-EP=3D00000002,SPD=3DHIGH,NFR=3D1,SLEN=3D0,IVAL=3D0,ERR=3D0
22:51:26.784894 usbus0.2 =
SUBM-BULK-EP=3D00000081,SPD=3DHIGH,NFR=3D1,SLEN=3D0,IVAL=3D0
22:51:26.785624 usbus0.2 =
DONE-BULK-EP=3D00000081,SPD=3DHIGH,NFR=3D1,SLEN=3D36,IVAL=3D0,ERR=3D0
22:51:26.785630 usbus0.2 =
SUBM-BULK-EP=3D00000081,SPD=3DHIGH,NFR=3D1,SLEN=3D0,IVAL=3D0
22:51:26.785743 usbus0.2 =
DONE-BULK-EP=3D00000081,SPD=3DHIGH,NFR=3D1,SLEN=3D16,IVAL=3D0,ERR=3D0
22:51:26.785754 usbus0.2 =
SUBM-BULK-EP=3D00000002,SPD=3DHIGH,NFR=3D1,SLEN=3D32,IVAL=3D0
22:51:26.785870 usbus0.2 =
DONE-BULK-EP=3D00000002,SPD=3DHIGH,NFR=3D1,SLEN=3D0,IVAL=3D0,ERR=3D0
22:51:26.785875 usbus0.2 =
SUBM-BULK-EP=3D00000081,SPD=3DHIGH,NFR=3D1,SLEN=3D0,IVAL=3D0
22:51:27.441870 usbus0.2 =
DONE-BULK-EP=3D00000081,SPD=3DHIGH,NFR=3D1,SLEN=3D20,IVAL=3D0,ERR=3D0
22:51:27.441894 usbus0.2 =
SUBM-BULK-EP=3D00000081,SPD=3DHIGH,NFR=3D1,SLEN=3D0,IVAL=3D0
22:51:27.441929 usbus0.2 =
DONE-BULK-EP=3D00000081,SPD=3DHIGH,NFR=3D1,SLEN=3D16,IVAL=3D0,ERR=3D0
22:51:27.466359 usbus0.2 =
SUBM-BULK-EP=3D00000002,SPD=3DHIGH,NFR=3D1,SLEN=3D32,IVAL=3D0
(probe0:umass-sim0:0:0:0): INQUIRY. CDB: 12 00 00 00 24 00=20
(probe0:umass-sim0:0:0:0): CAM status: CCB request completed with an =
error
(probe0:umass-sim0:0:0:0): Retrying command
22:51:32.351126 usbus0.2 =
DONE-BULK-EP=3D00000002,SPD=3DHIGH,NFR=3D0,SLEN=3D0,IVAL=3D0,ERR=3DTIMEOUT=

22:51:32.858070 usbus0.2 =
SUBM-CTRL-EP=3D00000000,SPD=3DHIGH,NFR=3D1,SLEN=3D8,IVAL=3D500
(probe0:umass-sim0:0:0:0): INQUIRY. CDB: 12 00 00 00 24 00=20
(probe0:umass-sim0:0:0:0): CAM status: CCB request completed with an =
error
(probe0:umass-sim0:0:0:0): Retrying command
da0 at umass-sim0 bus 0 scbus2 target 0 lun 0
da0: <Kingston DataTraveler G3 PMAP> Removable Direct Access SCSI device
da0: Serial Number 001CC0EC34F4BB6155F10168
da0: 40.000MB/s transfers
da0: 15252MB (31236096 512 byte sectors)
da0: quirks=3D0x2<NO_6_BYTE>
22:51:37.742852 usbus0.2 =
DONE-CTRL-EP=3D00000000,SPD=3DHIGH,NFR=3D0,SLEN=3D0,IVAL=3D500,ERR=3DTIMEO=
UT
22:51:38.250072 usbus0.2 =
SUBM-CTRL-EP=3D00000000,SPD=3DHIGH,NFR=3D1,SLEN=3D8,IVAL=3D500
22:51:38.250800 usbus0.2 =
DONE-CTRL-EP=3D00000000,SPD=3DHIGH,NFR=3D1,SLEN=3D0,IVAL=3D500,ERR=3D0
22:51:38.300066 usbus0.2 =
SUBM-CTRL-EP=3D00000000,SPD=3DHIGH,NFR=3D1,SLEN=3D8,IVAL=3D50
22:51:38.300692 usbus0.2 =
DONE-CTRL-EP=3D00000000,SPD=3DHIGH,NFR=3D1,SLEN=3D0,IVAL=3D50,ERR=3D0

--=20
Stefan Bethke <stb@lassitu.de>   Fon +49 151 14070811