Return-Path: Received: (majordomo@vger.kernel.org) by vger.kernel.org via listexpand id S1760928AbXLQS6k (ORCPT ); Mon, 17 Dec 2007 13:58:40 -0500 Received: (majordomo@vger.kernel.org) by vger.kernel.org id S932144AbXLQS56 (ORCPT ); Mon, 17 Dec 2007 13:57:58 -0500 Received: from Mycroft.westnet.com ([216.187.52.7]:40883 "EHLO Mycroft.westnet.com" rhost-flags-OK-OK-OK-OK) by vger.kernel.org with ESMTP id S1760525AbXLQS5z (ORCPT ); Mon, 17 Dec 2007 13:57:55 -0500 MIME-Version: 1.0 Content-Type: text/plain; charset=us-ascii Content-Transfer-Encoding: 7bit Message-ID: <18278.50849.879162.723545@stoffel.org> Date: Mon, 17 Dec 2007 13:57:37 -0500 From: "John Stoffel" To: Andrew Morton Cc: FUJITA Tomonori , john@stoffel.org, linux-kernel@vger.kernel.org, linux-scsi@vger.kernel.org Subject: Re: 2.6.24-rc5: tape drive not responding In-Reply-To: <20071217031510.c3d500fd.akpm@linux-foundation.org> References: <18277.52079.718004.969358@stoffel.org> <20071217112551H.fujita.tomonori@lab.ntt.co.jp> <20071217031510.c3d500fd.akpm@linux-foundation.org> X-Mailer: VM 7.19 under Emacs 21.4.1 Sender: linux-kernel-owner@vger.kernel.org List-ID: X-Mailing-List: linux-kernel@vger.kernel.org Content-Length: 8928 Lines: 174 >>>>> "Andrew" == Andrew Morton writes: Andrew> On Mon, 17 Dec 2007 11:25:51 +0900 FUJITA Tomonori wrote: >> On Sun, 16 Dec 2007 20:05:51 -0500 >> "John Stoffel" wrote: >> >> > [ 215.007701] sym1: SCSI parity error detected: SCR1=1 DBC=11000028 SBCL=ae >> > [ 215.008145] sym1: SCSI parity error detected: SCR1=1 DBC=11000028 SBCL=ae >> > [ 215.008678] sym1: SCSI parity error detected: SCR1=1 DBC=11000028 SBCL=ae >> > [ 215.009122] sym1: SCSI parity error detected: SCR1=1 DBC=11000028 SBCL=ae >> > [ 215.009598] sym1: SCSI parity error detected: SCR1=1 DBC=11000028 SBCL=ae >> > [ 215.010042] sym1: SCSI parity error detected: SCR1=1 DBC=11000028 SBCL=ae >> > [ 215.010516] sym1: SCSI parity error detected: SCR1=1 DBC=11000028 SBCL=ae >> > [ 215.010959] sym1: SCSI parity error detected: SCR1=1 DBC=11000028 SBCL=ae >> > [ 215.011403] sym1: SCSI parity error detected: SCR1=1 DBC=11000028 SBCL=ae >> > [ 215.011850] sym1: SCSI parity error detected: SCR1=1 DBC=11000028 SBCL=ae >> > . >> > . >> > . >> > [ 232.954629] sym1: SCSI parity error detected: SCR1=1 DBC=11000028 SBCL=ae >> > [ 233.035902] scsi 3:0:3:0: DEVICE RESET operation started >> > [ 233.099514] sym1: SCSI parity error detected: SCR1=1 DBC=11000028 SBCL=ae >> > . >> > . >> > . >> > >> > These repeat for about 15 seconds or so. They're really annoying and >> > I'd love to see some sort of rate limiting put in here. The messages >> > and end with: >> > . >> > . >> > . >> > [ 238.084175] sym1: SCSI parity error detected: SCR1=1 DBC=11000028 SBCL=ae >> > [ 238.165887] sym1: SCSI parity error detected: SCR1=1 DBC=11000028 SBCL=ae >> > [ 238.247157] scsi 3:0:3:0: DEVICE RESET operation timed-out. >> > [ 238.313892] sym1: SCSI parity error detected: SCR1=1 DBC=11000028 SBCL=ae >> > [ 238.395192] scsi 3:0:3:0: BUS RESET operation started >> > [ 238.455690] sym1: SCSI parity error detected: SCR1=1 DBC=11000028 SBCL=ae >> > [ 238.539216] sym1: SCSI BUS reset detected. >> > [ 238.592552] sym1: SCSI BUS has been reset. >> > [ 238.641576] scsi 3:0:3:0: BUS RESET operation complete. >> > [ 248.700373] target3:0:3: wide asynchronous >> > [ 248.752026] target3:0:3: Wide Transfers Fail >> > [ 248.805220] target3:0:3: FAST-10 SCSI 10.0 MB/s ST (100 ns, offset 15) >> > [ 248.886729] target3:0:3: Domain Validation skipping write tests >> > [ 248.958666] target3:0:3: Ending Domain Validation >> > [ 252.264086] scsi 3:0:0:0: Attached scsi generic sg2 type 8 >> > [ 252.331257] st 3:0:2:0: Attached scsi tape st0 >> > [ 252.384549] st 3:0:2:0: st0: try direct i/o: yes (alignment 512 B) >> > [ 252.458875] st 3:0:2:0: Attached scsi generic sg3 type 1 >> > [ 252.523963] st 3:0:3:0: Attached scsi tape st1 >> > [ 252.577184] st 3:0:3:0: st1: try direct i/o: yes (alignment 512 B) >> > [ 252.651484] st 3:0:3:0: Attached scsi generic sg4 type 1 >> > >> > >> > I've also got an ATL P1000 SCSI tape library hooked up to this same >> > controller and port, and I can manipulate it properly using the 'mtx' >> > program pointed to the /dev/changer alias, which points to the correct >> > /dev/sg# device. >> > >> > Here's my /proc/scsi/scsi output, as you can see, I've got a bunch of >> > devices on this system: >> > >> > # cat /proc/scsi/scsi >> > Attached devices: >> > Host: scsi0 Channel: 00 Id: 00 Lun: 00 >> > Vendor: COMPAQ Model: HC01841729 Rev: 3208 >> > Type: Direct-Access ANSI SCSI revision: 02 >> > Host: scsi0 Channel: 00 Id: 01 Lun: 00 >> > Vendor: COMPAQ Model: BD018222CA Rev: B016 >> > Type: Direct-Access ANSI SCSI revision: 02 >> > Host: scsi3 Channel: 00 Id: 00 Lun: 00 >> > Vendor: ATL Model: P1000 6220051 Rev: 1.20 >> > Type: Medium Changer ANSI SCSI revision: 02 >> > Host: scsi3 Channel: 00 Id: 02 Lun: 00 >> > Vendor: QUANTUM Model: DLT7000 Rev: 2565 >> > Type: Sequential-Access ANSI SCSI revision: 02 >> > Host: scsi3 Channel: 00 Id: 03 Lun: 00 >> > Vendor: QUANTUM Model: DLT7000 Rev: 2565 >> > Type: Sequential-Access ANSI SCSI revision: 02 >> > Host: scsi4 Channel: 00 Id: 00 Lun: 00 >> > Vendor: SAMSUNG Model: CDRW/DVD SM-352B Rev: T806 >> > Type: CD-ROM ANSI SCSI revision: 05 >> > Host: scsi6 Channel: 00 Id: 00 Lun: 00 >> > Vendor: ATA Model: ST3320620AS Rev: 3.AA >> > Type: Direct-Access ANSI SCSI revision: 05 >> > Host: scsi7 Channel: 00 Id: 00 Lun: 00 >> > Vendor: ATA Model: WDC WD3200AAKS-0 Rev: 12.0 >> > Type: Direct-Access ANSI SCSI revision: 05 >> > Host: scsi10 Channel: 00 Id: 00 Lun: 00 >> > Vendor: ATA Model: WDC WD1200JB-00C Rev: 17.0 >> > Type: Direct-Access ANSI SCSI revision: 05 >> > Host: scsi11 Channel: 00 Id: 00 Lun: 00 >> > Vendor: ATA Model: WDC WD1200JB-00E Rev: 15.0 >> > Type: Direct-Access ANSI SCSI revision: 05 >> > Host: scsi12 Channel: 00 Id: 00 Lun: 00 >> > Vendor: Generic Model: STORAGE DEVICE Rev: 0001 >> > Type: Direct-Access ANSI SCSI revision: 00 >> > Host: scsi12 Channel: 00 Id: 00 Lun: 01 >> > Vendor: Generic Model: STORAGE DEVICE Rev: 0001 >> > Type: Direct-Access ANSI SCSI revision: 00 >> > Host: scsi12 Channel: 00 Id: 00 Lun: 02 >> > Vendor: Generic Model: STORAGE DEVICE Rev: 0001 >> > Type: Direct-Access ANSI SCSI revision: 00 >> > Host: scsi12 Channel: 00 Id: 00 Lun: 03 >> > Vendor: Generic Model: STORAGE DEVICE Rev: 0001 >> > Type: Direct-Access ANSI SCSI revision: 00 >> > >> > >> > When I try to access my tape devices, I get the following: >> > >> > # mt -f /dev/st0 status >> > /dev/st0: Device or resource busy >> > >> > But lsof doesn't show any other processes accessing this device, and >> > I've stopped my backup software since it keeps hanging on tape device >> > access. >> > >> > Here's the output of dmesg where I get the OOPS: >> > >> > [ 273.382057] sd 12:0:0:3: Attached scsi generic sg13 type 0 >> > [ 276.244872] ------------[ cut here ]------------ >> > [ 276.300215] kernel BUG at include/linux/scatterlist.h:59! >> > [ 276.364873] invalid opcode: 0000 [#1] SMP >> > [ 276.414346] Modules linked in: >> > [ 276.451148] >> > [ 276.469036] Pid: 1824, comm: stinit Not tainted (2.6.24-rc5 #2) >> > [ 276.539940] EIP: 0060:[] EFLAGS: 00010213 CPU: 0 >> > [ 276.605651] EIP is at st_do_scsi+0x2e0/0x340 >> > [ 276.656788] EAX: 00000000 EBX: 00000000 ECX: c16ef780 EDX: f7c4f050 >> > [ 276.731847] ESI: f7c4f7d0 EDI: 00001000 EBP: f7c4f000 ESP: f712bdf8 >> > [ 276.806904] DS: 007b ES: 007b FS: 00d8 GS: 0033 SS: 0068 >> > [ 276.871568] Process stinit (pid: 1824, ti=f712b000 task=f750a030 task.ti=f712b000) >> > [ 276.960139] Stack: 00000003 f7c4f050 00000000 00000000 00d59f80 00000000 f776fe20 c03468a0 >> > [ 277.062012] 000000d0 f712be9c f7d2a000 f776fe20 f7d2a018 00000000 00000006 f712be9c >> > [ 277.163890] f7d2a000 f712beac f7c4f000 c0345790 00000006 00000002 000dbba0 00000000 >> > [ 277.265771] Call Trace: >> > [ 277.297383] [] st_sleep_done+0x0/0x70 >> > [ 277.352894] [] check_tape+0x510/0x640 >> > [ 277.408414] [] st_open+0x18b/0x220 >> > [ 277.460803] [] exact_match+0x0/0x10 >> > [ 277.514237] [] st_open+0x0/0x220 >> > [ 277.564553] [] chrdev_open+0x9f/0x190 >> > [ 277.620069] [] chrdev_open+0x0/0x190 >> > [ 277.674543] [] __dentry_open+0xaf/0x1b0 >> > [ 277.732136] [] nameidata_to_filp+0x35/0x40 >> > [ 277.792847] [] do_filp_open+0x4b/0x60 >> > [ 277.848364] [] get_unused_fd_flags+0x52/0xd0 >> > [ 277.911153] [] do_sys_open+0x4c/0xe0 >> > [ 277.965629] [] sys_open+0x1c/0x20 >> >> I think that you need the following patch for the scatterlist problem: >> >> http://marc.info/?l=linux-scsi&m=119770154127770&w=2 Andrew> err, you sent that patch to John a day earlier too. Andrew> John, can you please apply, test and report? I didn't see the reply for some reason. I'm applying the patch now to 2.6.24-rc5-mm1 and I'll reboot and test. My git bisection has been a failure too, since I found a situation where I thought what was a bad kernel now works. Sigh... might be an issue with Bacula interactions, since I've now turned it off on startup for testing. More in a bit. John -- To unsubscribe from this list: send the line "unsubscribe linux-kernel" in the body of a message to majordomo@vger.kernel.org More majordomo info at http://vger.kernel.org/majordomo-info.html Please read the FAQ at http://www.tux.org/lkml/