From owner-freebsd-current@FreeBSD.ORG Mon Oct 3 12:45:38 2011 Return-Path: Delivered-To: freebsd-current@freebsd.org Received: from mx1.freebsd.org (mx1.freebsd.org [IPv6:2001:4f8:fff6::34]) by hub.freebsd.org (Postfix) with ESMTP id 3FC66106566C; Mon, 3 Oct 2011 12:45:38 +0000 (UTC) (envelope-from freebsd-listen@fabiankeil.de) Received: from smtprelay03.ispgateway.de (smtprelay03.ispgateway.de [80.67.31.26]) by mx1.freebsd.org (Postfix) with ESMTP id ACFDB8FC08; Mon, 3 Oct 2011 12:45:37 +0000 (UTC) Received: from [87.79.150.108] (helo=fabiankeil.de) by smtprelay03.ispgateway.de with esmtpsa (TLSv1:AES128-SHA:128) (Exim 4.68) (envelope-from ) id 1RAhto-0002yp-2z; Mon, 03 Oct 2011 14:45:36 +0200 Date: Mon, 3 Oct 2011 14:26:56 +0200 From: Fabian Keil To: "Kenneth D. Merry" 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> Mime-Version: 1.0 Content-Type: multipart/signed; micalg=PGP-SHA1; boundary="Sig_/uEkN_MwK1d+hyupj0uB1fSU"; protocol="application/pgp-signature" X-Df-Sender: Nzc1MDY3 Cc: freebsd-current@freebsd.org Subject: Re: SCSI descriptor sense changes, testing needed X-BeenThere: freebsd-current@freebsd.org X-Mailman-Version: 2.1.5 Precedence: list List-Id: Discussions about the use of FreeBSD-current List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , X-List-Received-Date: Mon, 03 Oct 2011 12:45:38 -0000 --Sig_/uEkN_MwK1d+hyupj0uB1fSU Content-Type: text/plain; charset=US-ASCII Content-Transfer-Encoding: quoted-printable "Kenneth D. Merry" wrote: > On Tue, Sep 27, 2011 at 21:46:03 +0200, Fabian Keil wrote: > > "Kenneth D. Merry" wrote: > >=20 > > > On Sat, Sep 24, 2011 at 21:27:22 +0200, Fabian Keil wrote: > > > > "Kenneth D. Merry" 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: at usbus0 Oct 3 12:09:26 r500 kernel: uhub0: on usbus0 Oct 3 12:09:26 r500 kernel: ugen1.1: at usbus1 Oct 3 12:09:26 r500 kernel: uhub1: on usbus1 Oct 3 12:09:26 r500 kernel: ugen2.1: at usbus2 Oct 3 12:09:26 r500 kernel: uhub2: on usbus2 Oct 3 12:09:26 r500 kernel: ugen3.1: at usbus3 Oct 3 12:09:26 r500 kernel: uhub3: on usbus3 Oct 3 12:09:26 r500 kernel: ugen4.1: at usbus4 Oct 3 12:09:26 r500 kernel: uhub4: on usbus4 Oct 3 12:09:26 r500 kernel: ugen5.1: at usbus5 Oct 3 12:09:26 r500 kernel: uhub5: on usbus5 Oct 3 12:09:26 r500 kernel: ugen6.1: at usbus6 Oct 3 12:09:26 r500 kernel: uhub6: on usbus6 Oct 3 12:09:26 r500 kernel: ugen7.1: at usbus7 Oct 3 12:09:26 r500 kernel: uhub7: 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: 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: 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: 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: 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--