Skip site navigation (1)Skip section navigation (2)
Date:      Mon, 3 Oct 2011 14:26:56 +0200
From:      Fabian Keil <freebsd-listen@fabiankeil.de>
To:        "Kenneth D. Merry" <ken@freebsd.org>
Cc:        freebsd-current@freebsd.org
Subject:   Re: SCSI descriptor sense changes, testing needed
Message-ID:  <20111003142656.5dbe1ecd@fabiankeil.de>
In-Reply-To: <20111001052005.GA27368@nargothrond.kdm.org>
References:  <20110922193305.GA24939@nargothrond.kdm.org> <20110924212722.4ce229e9@fabiankeil.de> <20110926225842.GA88443@nargothrond.kdm.org> <20110927214603.427e6992@fabiankeil.de> <20111001052005.GA27368@nargothrond.kdm.org>

next in thread | previous in thread | raw e-mail | index | archive | help
--Sig_/uEkN_MwK1d+hyupj0uB1fSU
Content-Type: text/plain; charset=US-ASCII
Content-Transfer-Encoding: quoted-printable

"Kenneth D. Merry" <ken@freebsd.org> wrote:

> On Tue, Sep 27, 2011 at 21:46:03 +0200, Fabian Keil wrote:
> > "Kenneth D. Merry" <ken@freebsd.org> wrote:
> >=20
> > > On Sat, Sep 24, 2011 at 21:27:22 +0200, Fabian Keil wrote:
> > > > "Kenneth D. Merry" <ken@freebsd.org> wrote:
> > > >=20
> > > > > I have attached a set of patches against head that implement SCSI
> > > > > descriptor sense support for CAM.
> > > >=20
> > > > > Anyway, I'd appreciate any testing and feedback on these changes.=
  As I
> > > > > said, they will probably be in 9.0, so if there are any issues it=
 would
> > > > > be better to find them now. :)
> > > >=20
> > > > I've been using the patch on a ThinkPad R500 since yesterday and
> > > > just reverted it today again to get my kernel closer to HEAD before
> > > > looking into some (probably unrelated) panics.
> > > >=20
> > > > I didn't notice it while using the patch, but it looks like the
> > > > kernel wasn't able to pick up cd0 anymore:
> > >=20
> > > Hmm.  I don't think any of the changes would have caused this, but
> > > evidently something did...
> > >=20
> > > Let's see if we can debug it...
> > >=20
> > > I have attached a patch to add some debugging output, and I see at le=
ast
> > > one interesting thing in the logs below.
> > >=20
> > > Can you re-apply the descriptor sense patch, and then try the attached
> > > debugging patch as well?
> >=20
> > Sure.
>=20
> I believe this is fixed with my latest set of patches.  Can you try them
> and let me know?

The device is indeed properly picked up now:

Oct  3 12:09:26 r500 kernel: ahcich0: AHCI reset...
Oct  3 12:09:26 r500 kernel: ahcich0: SATA connect time=3D900us status=3D00=
000113
Oct  3 12:09:26 r500 kernel: ahcich0: AHCI reset: device found
Oct  3 12:09:26 r500 kernel: ahcich1: AHCI reset...
Oct  3 12:09:26 r500 kernel: ahcich1: SATA connect time=3D1000us status=3D0=
0000113
Oct  3 12:09:26 r500 kernel: ahcich1: AHCI reset: device found
Oct  3 12:09:26 r500 kernel: ugen0.1: <Intel> at usbus0
Oct  3 12:09:26 r500 kernel: uhub0: <Intel UHCI root HUB, class 9/0, rev 1.=
00/1.00, addr 1> on usbus0
Oct  3 12:09:26 r500 kernel: ugen1.1: <Intel> at usbus1
Oct  3 12:09:26 r500 kernel: uhub1: <Intel UHCI root HUB, class 9/0, rev 1.=
00/1.00, addr 1> on usbus1
Oct  3 12:09:26 r500 kernel: ugen2.1: <Intel> at usbus2
Oct  3 12:09:26 r500 kernel: uhub2: <Intel UHCI root HUB, class 9/0, rev 1.=
00/1.00, addr 1> on usbus2
Oct  3 12:09:26 r500 kernel: ugen3.1: <Intel> at usbus3
Oct  3 12:09:26 r500 kernel: uhub3: <Intel EHCI root HUB, class 9/0, rev 2.=
00/1.00, addr 1> on usbus3
Oct  3 12:09:26 r500 kernel: ugen4.1: <Intel> at usbus4
Oct  3 12:09:26 r500 kernel: uhub4: <Intel UHCI root HUB, class 9/0, rev 1.=
00/1.00, addr 1> on usbus4
Oct  3 12:09:26 r500 kernel: ugen5.1: <Intel> at usbus5
Oct  3 12:09:26 r500 kernel: uhub5: <Intel UHCI root HUB, class 9/0, rev 1.=
00/1.00, addr 1> on usbus5
Oct  3 12:09:26 r500 kernel: ugen6.1: <Intel> at usbus6
Oct  3 12:09:26 r500 kernel: uhub6: <Intel UHCI root HUB, class 9/0, rev 1.=
00/1.00, addr 1> on usbus6
Oct  3 12:09:26 r500 kernel: ugen7.1: <Intel> at usbus7
Oct  3 12:09:26 r500 kernel: uhub7: <Intel EHCI root HUB, class 9/0, rev 2.=
00/1.00, addr 1> on usbus7
Oct  3 12:09:26 r500 kernel: battery0: battery initialization start
Oct  3 12:09:26 r500 kernel: acpi_acad0: acline initialization start
Oct  3 12:09:26 r500 kernel: battery0: battery initialization done, tried 1=
 times
Oct  3 12:09:26 r500 kernel: acpi_acad0: On Line
Oct  3 12:09:26 r500 kernel: acpi_acad0: acline initialization done, tried =
1 times
Oct  3 12:09:26 r500 kernel: ahcich0: AHCI reset: device ready after 100ms
Oct  3 12:09:26 r500 kernel: (aprobe0:ahcich0:0:0:0): SIGNATURE: 0000
Oct  3 12:09:26 r500 kernel: ahcich1: AHCI reset: device ready after 100ms
Oct  3 12:09:26 r500 kernel: (aprobe1:ahcich1:0:0:0): SIGNATURE: eb14
Oct  3 12:09:26 r500 kernel: GEOM: new disk ada0
Oct  3 12:09:26 r500 kernel: ada0 at ahcich0 bus 0 scbus0 target 0 lun 0
Oct  3 12:09:26 r500 kernel: ada0: <HITACHI HTS543225L9SA00 FBEZC4EC> ATA-8=
 SATA 1.x device
Oct  3 12:09:26 r500 kernel: ada0: Serial Number 090509FB2F32LLEY6D8A
Oct  3 12:09:26 r500 kernel: ada0: 150.000MB/s transfers (SATA 1.x, UDMA5, =
PIO 8192bytes)
Oct  3 12:09:26 r500 kernel: ada0: Command Queueing enabled
Oct  3 12:09:26 r500 kernel: ada0: 238475MB (488397168 512 byte sectors: 16=
H 63S/T 16383C)
Oct  3 12:09:26 r500 kernel: ada0: Previously was known as ad4
Oct  3 12:09:26 r500 kernel: pass0 at ahcich0 bus 0 scbus0 target 0 lun 0
Oct  3 12:09:26 r500 kernel: pass0: <HITACHI HTS543225L9SA00 FBEZC4EC> ATA-=
8 SATA 1.x device
Oct  3 12:09:26 r500 kernel: pass0: Serial Number 090509FB2F32LLEY6D8A
Oct  3 12:09:26 r500 kernel: pass0: 150.000MB/s transfers (SATA 1.x, UDMA5,=
 PIO 8192bytes)
Oct  3 12:09:26 r500 kernel: pass0: Command Queueing enabled
Oct  3 12:09:26 r500 kernel: pass1 at ahcich1 bus 0 scbus1 target 0 lun 0
Oct  3 12:09:26 r500 kernel: pass1: <HL-DT-ST DVDRAM GSA-T50N RX05> Removab=
le CD-ROM SCSI-0 device
Oct  3 12:09:26 r500 kernel: pass1: Serial Number M2R96NC0647
Oct  3 12:09:26 r500 kernel: pass1: 150.000MB/s transfers (SATA 1.x, UDMA6,=
 ATAPI 12bytes, PIO 8192bytes)
Oct  3 12:09:26 r500 kernel: (cd0:ahcich1:0:0:0): SCSI status error
Oct  3 12:09:26 r500 kernel: (cd0:ahcich1:0:0:0): READ CAPACITY. CDB: 25 0 =
0 0 0 0 0 0 0 0
Oct  3 12:09:26 r500 kernel: (cd0:ahcich1:0:0:0): CAM status: SCSI Status E=
rror
Oct  3 12:09:26 r500 kernel: (cd0:ahcich1:0:0:0): SCSI status: Check Condit=
ion
Oct  3 12:09:26 r500 kernel: (cd0:ahcich1:0:0:0): SCSI sense: UNIT ATTENTIO=
N asc:29,0 (Power on, reset, or bus device reset occurred)
Oct  3 12:09:26 r500 kernel: (cd0:ahcich1:0:0:0): Retrying command (per sen=
se data)
Oct  3 12:09:26 r500 kernel: SMP: AP CPU #1 Launched!
Oct  3 12:09:26 r500 kernel: cpu1 AP:
Oct  3 12:09:26 r500 kernel: ID: 0x01000000   VER: 0x00050014 LDR: 0x000000=
00 DFR: 0xffffffff
Oct  3 12:09:26 r500 kernel: lint0: 0x00010700 lint1: 0x00000400 TPR: 0x000=
00000 SVR: 0x000001ff
Oct  3 12:09:26 r500 kernel: timer: 0x000100ef therm: 0x00010200 err: 0x000=
000f0 pmc: 0x00010400
Oct  3 12:09:26 r500 kernel: TSC timecounter disabled: C3 enabled.
Oct  3 12:09:26 r500 kernel: Timecounter "TSC" frequency 1995039830 Hz qual=
ity -1000
Oct  3 12:09:26 r500 kernel: WARNING: WITNESS option enabled, expect reduce=
d performance.
Oct  3 12:09:26 r500 kernel: GEOM: new disk cd0
Oct  3 12:09:26 r500 kernel: GEOM: ada0s1: geometry does not match label (2=
55h,63s !=3D 16h,63s).
Oct  3 12:09:26 r500 kernel: (cd0:ahcich1:0:0:0): SCSI status error
Oct  3 12:09:26 r500 kernel: (cd0:ahcich1:0:0:0): READ CAPACITY. CDB: 25 0 =
0 0 0 0 0 0 0 0
Oct  3 12:09:26 r500 kernel: (cd0:ahcich1:0:0:0): CAM status: SCSI Status E=
rror
Oct  3 12:09:26 r500 kernel: (cd0:ahcich1:0:0:0): SCSI status: Check Condit=
ion
Oct  3 12:09:26 r500 kernel: (cd0:ahcich1:0:0:0): SCSI sense: NOT READY asc=
:4,1 (Logical unit is in process of becoming ready)
Oct  3 12:09:26 r500 kernel: (cd0:ahcich1:0:0:0): Polling device for readin=
ess
Oct  3 12:09:26 r500 kernel: uhub0: 2 ports with 2 removable, self powered
Oct  3 12:09:26 r500 kernel: uhub1: 2 ports with 2 removable, self powered
Oct  3 12:09:26 r500 kernel: uhub2: 2 ports with 2 removable, self powered
Oct  3 12:09:26 r500 kernel: uhub4: 2 ports with 2 removable, self powered
Oct  3 12:09:26 r500 kernel: uhub5: 2 ports with 2 removable, self powered
Oct  3 12:09:26 r500 kernel: uhub6: 2 ports with 2 removable, self powered
Oct  3 12:09:26 r500 kernel: (cd0:ahcich1:0:0:0): SCSI status error
Oct  3 12:09:26 r500 kernel: (cd0:ahcich1:0:0:0): READ CAPACITY. CDB: 25 0 =
0 0 0 0 0 0 0 0
Oct  3 12:09:26 r500 kernel: (cd0:ahcich1:0:0:0): CAM status: SCSI Status E=
rror
Oct  3 12:09:26 r500 kernel: (cd0:ahcich1:0:0:0): SCSI status: Check Condit=
ion
Oct  3 12:09:26 r500 kernel: (cd0:ahcich1:0:0:0): SCSI sense: NOT READY asc=
:3a,1 (Medium not present - tray closed)
Oct  3 12:09:26 r500 kernel: (cd0:ahcich1:0:0:0): Error 6, Unretryable error
Oct  3 12:09:26 r500 kernel: cd0 at ahcich1 bus 0 scbus1 target 0 lun 0
Oct  3 12:09:26 r500 kernel: cd0: <HL-DT-ST DVDRAM GSA-T50N RX05> Removable=
 CD-ROM SCSI-0 device
Oct  3 12:09:26 r500 kernel: cd0: Serial Number M2R96NC0647
Oct  3 12:09:26 r500 kernel: cd0: 150.000MB/s transfers (SATA 1.x, UDMA6, A=
TAPI 12bytes, PIO 8192bytes)
Oct  3 12:09:26 r500 kernel: cd0: Attempt to query device size failed: NOT =
READY, Medium not present - tray closed
Oct  3 12:09:26 r500 kernel: (cd0:ahcich1:0:0:0): SCSI status error
Oct  3 12:09:26 r500 kernel: (cd0:ahcich1:0:0:0): READ CAPACITY. CDB: 25 0 =
0 0 0 0 0 0 0 0
Oct  3 12:09:26 r500 kernel: (cd0:ahcich1:0:0:0): CAM status: SCSI Status E=
rror
Oct  3 12:09:26 r500 kernel: (cd0:ahcich1:0:0:0): SCSI status: Check Condit=
ion
Oct  3 12:09:26 r500 kernel: (cd0:ahcich1:0:0:0): SCSI sense: NOT READY asc=
:3a,1 (Medium not present - tray closed)
Oct  3 12:09:26 r500 kernel: (cd0:ahcich1:0:0:0): Error 6, Unretryable error
Oct  3 12:09:26 r500 kernel: (cd0:ahcich1:0:0:0): SCSI status error
Oct  3 12:09:26 r500 kernel: (cd0:ahcich1:0:0:0): READ CAPACITY. CDB: 25 0 =
0 0 0 0 0 0 0 0
Oct  3 12:09:26 r500 kernel: (cd0:ahcich1:0:0:0): CAM status: SCSI Status E=
rror
Oct  3 12:09:26 r500 kernel: (cd0:ahcich1:0:0:0): SCSI status: Check Condit=
ion
Oct  3 12:09:26 r500 kernel: (cd0:ahcich1:0:0:0): SCSI sense: NOT READY asc=
:3a,1 (Medium not present - tray closed)
Oct  3 12:09:26 r500 kernel: (cd0:ahcich1:0:0:0): Error 6, Unretryable error
Oct  3 12:09:26 r500 kernel: (cd0:ahcich1:0:0:0): SCSI status error
Oct  3 12:09:26 r500 kernel: (cd0:ahcich1:0:0:0): READ CAPACITY. CDB: 25 0 =
0 0 0 0 0 0 0 0
Oct  3 12:09:26 r500 kernel: (cd0:ahcich1:0:0:0): CAM status: SCSI Status E=
rror
Oct  3 12:09:26 r500 kernel: (cd0:ahcich1:0:0:0): SCSI status: Check Condit=
ion
Oct  3 12:09:26 r500 kernel: (cd0:ahcich1:0:0:0): SCSI sense: NOT READY asc=
:3a,1 (Medium not present - tray closed)
Oct  3 12:09:26 r500 kernel: (cd0:ahcich1:0:0:0): Error 6, Unretryable error
Oct  3 12:09:26 r500 kernel: (cd0:ahcich1:0:0:0): SCSI status error
Oct  3 12:09:26 r500 kernel: (cd0:ahcich1:0:0:0): READ CAPACITY. CDB: 25 0 =
0 0 0 0 0 0 0 0
Oct  3 12:09:26 r500 kernel: (cd0:ahcich1:0:0:0): CAM status: SCSI Status E=
rror
Oct  3 12:09:26 r500 kernel: (cd0:ahcich1:0:0:0): SCSI status: Check Condit=
ion
Oct  3 12:09:26 r500 kernel: (cd0:ahcich1:0:0:0): SCSI sense: NOT READY asc=
:3a,1 (Medium not present - tray closed)
Oct  3 12:09:26 r500 kernel: (cd0:ahcich1:0:0:0): Error 6, Unretryable error

Thanks
Fabian

--Sig_/uEkN_MwK1d+hyupj0uB1fSU
Content-Type: application/pgp-signature; name=signature.asc
Content-Disposition: attachment; filename=signature.asc

-----BEGIN PGP SIGNATURE-----
Version: GnuPG v2.0.18 (FreeBSD)

iEYEARECAAYFAk6JqhQACgkQBYqIVf93VJ1IiQCfZbPhEDFL2G1OZgp6aA3olJiK
/ucAnjQqcXgkHOG6JIAjr+cXcHF0syAr
=BUK7
-----END PGP SIGNATURE-----

--Sig_/uEkN_MwK1d+hyupj0uB1fSU--



Want to link to this message? Use this URL: <https://mail-archive.FreeBSD.org/cgi/mid.cgi?20111003142656.5dbe1ecd>