Date: Fri, 27 Apr 2001 11:02:31 +0200 (MET DST) From: j@uriah.heep.sax.de (J Wunsch) To: freebsd-scsi@freebsd.org Subject: Re: sa(4) jamming Message-ID: <200104270902.f3R92V555575@uriah.heep.sax.de> References: <20010427091852.L50185@uriah.heep.sax.de>
next in thread | previous in thread | raw e-mail | index | archive | help
J Wunsch <j@uriah.heep.sax.de> 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
Want to link to this message? Use this URL: <https://mail-archive.FreeBSD.org/cgi/mid.cgi?200104270902.f3R92V555575>