From owner-freebsd-scsi Fri Apr 27 2:20: 1 2001 Delivered-To: freebsd-scsi@freebsd.org Received: from sax.sax.de (sax.sax.de [193.175.26.33]) by hub.freebsd.org (Postfix) with ESMTP id A198C37B424 for ; Fri, 27 Apr 2001 02:19:56 -0700 (PDT) (envelope-from j@uriah.heep.sax.de) Received: (from uucp@localhost) by sax.sax.de (8.9.3/8.9.3) with UUCP id LAA27039 for freebsd-scsi@freebsd.org; Fri, 27 Apr 2001 11:19:55 +0200 (CEST) Received: (from j@localhost) by uriah.heep.sax.de (8.11.3/8.11.3) id f3R92V555575; Fri, 27 Apr 2001 11:02:31 +0200 (MET DST) (envelope-from j) Date: Fri, 27 Apr 2001 11:02:31 +0200 (MET DST) Message-Id: <200104270902.f3R92V555575@uriah.heep.sax.de> Mime-Version: 1.0 X-Newsreader: knews 1.0b.1 Reply-To: joerg_wunsch@uriah.heep.sax.de (Joerg Wunsch) Organization: Private BSD site, Dresden X-Phone: +49-351-2012 669 X-PGP-Fingerprint: DC 47 E6 E4 FF A6 E9 8F 93 21 E0 7D F9 12 D6 4E References: <20010427091852.L50185@uriah.heep.sax.de> From: j@uriah.heep.sax.de (J Wunsch) Subject: Re: sa(4) jamming X-Original-Newsgroups: local.freebsd.scsi To: freebsd-scsi@freebsd.org Content-Type: text/plain; charset=us-ascii Sender: owner-freebsd-scsi@FreeBSD.ORG Precedence: bulk X-Loop: FreeBSD.org J Wunsch wrote: > Tonight, my daily backup was completely jamming. The process trying > to write to the tape was: > > 0 54453 54451 0 -8 0 1708 368 physst DWL ?? 0:00.33 team 1m 3 The problem seems to be reproducible. After finishing 16 % of the dump, it jammed again. I also noted that the tape is in constant start-stop operation, so completing those 16 % took already 1.5 hours, where normally that filesystem had been dumped completely within half an hour. mt -f /dev/sa0.ctl stat says: Mode Density Blocksize bpi Compression Current: default variable 0 0x3 ---------available modes--------- 0: default variable 0 0x3 1: default variable 0 0x3 2: default variable 0 0x3 3: default variable 0 0x3 --------------------------------- Current Driver State: Writing. --------------------------------- File Number: 0 Record Number: 2101 Residual Count 0 That is after aborting the dump itself, but with the last child process of `team' still jamming in status `D' (physstrat()), I turned on "camcontrol debug -ITc 1:4:0", here are the kernel messages so far: Apr 27 10:49:13 uriah /boot/kernel/kernel: (sa0:sym1:0:4:0): saopen(0): dev=0x0 softc=0xc2d Apr 27 10:49:13 uriah /boot/kernel/kernel: (sa0:sym1:0:4:0): saopen(0): dev=0x0 softc=0xc2d Apr 27 10:49:13 uriah /boot/kernel/kernel: (sa0:sym1:0:4:0): saclose(0): dev=0x0 softc=0xc2d Apr 27 10:53:35 uriah /boot/kernel/kernel: (sa0:sym1:0:4:0): xpt_done Apr 27 10:53:35 uriah /boot/kernel/kernel: (sa0:sym1:0:4:0): camisr(sa0:sym1:0:4:0): sastrategy: enqueuing a 65536 variable byte write queue count now 1 Apr 27 10:53:35 uriah /boot/kernel/kernel: (sa0:sym1:0:4:0): xpt_schedule Apr 27 10:53:35 uriah /boot/kernel/kernel: (sa0:sym1:0:4:0): xpt_setup_ccb Apr 27 10:53:35 uriah /boot/kernel/kernel: (sa0:sym1:0:4:0): sastart(sa0:sym1:0:4:0): Variable Record Count is 65536 Apr 27 10:53:35 uriah /boot/kernel/kernel: (sa0:sym1:0:4:0): xpt_action Apr 27 10:53:35 uriah /boot/kernel/kernel: (sa0:sym1:0:4:0): WRITE(06). CDB: a 0 1 0 0 0 Apr 27 10:53:35 uriah /boot/kernel/kernel: (sa0:sym1:0:4:0): sym_action That single WRITE(06) occurred all of a sudden. No idea when the next one will happen... The kernel is still from ~ April 01, plus my recent fix for the ILI/filemark handling. Oh, here's another one: Apr 27 11:00:35 uriah /boot/kernel/kernel: (sa0:sym1:0:4:0): xpt_done Apr 27 11:00:35 uriah /boot/kernel/kernel: (sa0:sym1:0:4:0): camisr(sa0:sym1:0:4:0): sastrategy: enqueuing a 65536 variable byte write queue count now 1 Apr 27 11:00:35 uriah /boot/kernel/kernel: (sa0:sym1:0:4:0): xpt_schedule Apr 27 11:00:35 uriah /boot/kernel/kernel: (sa0:sym1:0:4:0): xpt_setup_ccb Apr 27 11:00:35 uriah /boot/kernel/kernel: (sa0:sym1:0:4:0): sastart(sa0:sym1:0:4:0): Variable Record Count is 65536 Apr 27 11:00:35 uriah /boot/kernel/kernel: (sa0:sym1:0:4:0): xpt_action Apr 27 11:00:35 uriah /boot/kernel/kernel: (sa0:sym1:0:4:0): WRITE(06). CDB: a 0 1 0 0 0 Apr 27 11:00:36 uriah /boot/kernel/kernel: (sa0:sym1:0:4:0): sym_action Seven minutes pause between writing two records... mt stat increases the record count, still. I aborted the operation by now. -- cheers, J"org .-.-. --... ...-- -.. . DL8DTL http://www.sax.de/~joerg/ NIC: JW11-RIPE Never trust an operating system you don't have sources for. ;-) To Unsubscribe: send mail to majordomo@FreeBSD.org with "unsubscribe freebsd-scsi" in the body of the message