From owner-aic7xxx Mon Dec 3 23:18:53 2001 Delivered-To: aic7xxx@freebsd.org Received: from pinkroccadefinance.nl (mail.roccadefinance.nl [193.78.32.2]) by hub.freebsd.org (Postfix) with SMTP id 8807937B419 for ; Mon, 3 Dec 2001 23:18:33 -0800 (PST) Received: from [150.150.6.50] ([150.150.6.50]) by gateway.pinkroccadefinance.nl with ESMTP id <117130>; Tue, 4 Dec 2001 08:16:14 +0100 Subject: Re: Still problems with SCSI scanning... From: Tom Wirschell To: aic7xxx@freebsd.org In-Reply-To: <1006804370.555.0.camel@NIL8> References: <1006804370.555.0.camel@NIL8> Content-Type: text/plain Content-Transfer-Encoding: 7bit X-Mailer: Evolution/0.99.2 (Preview Release) Date: 04 Dec 2001 08:15:57 +0100 Message-Id: <1007450158.807.3.camel@NIL8> Mime-Version: 1.0 Sender: owner-aic7xxx@FreeBSD.ORG Precedence: bulk List-ID: List-Archive: (Web Archive) List-Help: (List Instructions) List-Subscribe: List-Unsubscribe: X-Loop: FreeBSD.org (I sent this message twice in the last 4 days with a 100kb attachment. It didn't make it into the list, so I'll assume the attachment is the issue. I've pasted the last few lines of the log at the bottom. If someone wants the original 100 kb attachment, ask and I'll send it to you directly) My machine locked up during scanning again, but this time it happened with full logging enabled and directed to a remote machine to make sure it survives the reboot. Since the full log (I started them last week) is 126 mb, I figured that would be a bit much, so I decided to send the last 100000 lines of the log to this list. The attached logs represent the last 2 minutes of my machine being up. I hope this will allow folks in here to make the driver more robust against such problems and/or offer suggestions w.r.t. what I can/should do to fix this. If anybody should need the rest of the logs, let me know and I'll see if I can get them your way. Note that the attached bzip2'd log will expand to roughly 7 MB. Sincerely, Tom Wirschell -- "...very few phenomena can pull someone out of Deep Hack Mode, with two noted exceptions: being struck by lightning, or worse, your *computer* being struck by lightning." -------------------------- messages ---------------------------- Dec 1 16:16:02 lev8 kernel: Notifying upper driver of completion for device 0 0 Dec 1 16:16:02 lev8 kernel: sda : rw_intr(0, 0 [0 0]) Dec 1 16:16:02 lev8 kernel: 16 sectors total, 16 sectors done. Dec 1 16:16:02 lev8 kernel: use_sg is 2 Dec 1 16:16:02 lev8 kernel: Deactivating command for device 0 (active=43, failed=0) Dec 1 16:16:02 lev8 kernel: sg_write: dev=3, count=42 Dec 1 16:16:02 lev8 kernel: Open returning 1 Dec 1 16:16:02 lev8 kernel: sg_write: scsi opcode=0x08, cmd_size=6 Dec 1 16:16:02 lev8 kernel: sg_common_write: scsi opcode=0x08, cmd_size=6 Dec 1 16:16:02 lev8 kernel: sg_start_req: dxfer_len=32768 Dec 1 16:16:02 lev8 kernel: sg_link_reserve: size=32768 Dec 1 16:16:02 lev8 kernel: scsi_do_req (host = 0, channel = 0 target = 5, buffer =c54a0000, bufflen = 32768, done = c01995b0, timeout = 60000, retries = 1) Dec 1 16:16:02 lev8 kernel: command : 08 00 00 80 00 00 af e7 07 00 Dec 1 16:16:02 lev8 kernel: Activating command for device 5 (44) Dec 1 16:16:02 lev8 kernel: Leaving scsi_init_cmd_from_req() Dec 1 16:16:02 lev8 kernel: Adding timer for command e7e43a00 at 60000 (c017b090) Dec 1 16:16:02 lev8 kernel: scsi_dispatch_cmnd (host = 0, channel = 0, target = 5, command = e7e43a58, buffer = c54a0000, Dec 1 16:16:02 lev8 kernel: bufflen = 32768, done = c01995b0) Dec 1 16:16:02 lev8 kernel: queuecommand : routine at c01822c0 Dec 1 16:16:02 lev8 kernel: leaving scsi_dispatch_cmnd() Dec 1 16:16:02 lev8 kernel: Leaving scsi_do_req() Dec 1 16:16:02 lev8 kernel: sg_poll: dev=3, res=0x104 Dec 1 16:16:02 lev8 kernel: Clearing timer for command e7e94000 1 Dec 1 16:16:02 lev8 kernel: Command finished 44 0 0x0 Dec 1 16:16:02 lev8 kernel: Notifying upper driver of completion for device 0 0 Dec 1 16:16:02 lev8 kernel: sda : rw_intr(0, 0 [0 0]) Dec 1 16:16:02 lev8 kernel: 24 sectors total, 24 sectors done. Dec 1 16:16:02 lev8 kernel: use_sg is 3 Dec 1 16:16:02 lev8 kernel: Deactivating command for device 0 (active=43, failed=0) Dec 1 16:16:02 lev8 kernel: Clearing timer for command e7e92200 1 Dec 1 16:16:02 lev8 kernel: Command finished 43 0 0x0 Dec 1 16:16:02 lev8 kernel: Notifying upper driver of completion for device 0 0 Dec 1 16:16:02 lev8 kernel: sda : rw_intr(0, 0 [0 0]) Dec 1 16:16:02 lev8 kernel: 32 sectors total, 32 sectors done. Dec 1 16:16:02 lev8 kernel: use_sg is 4 Dec 1 16:16:02 lev8 kernel: Deactivating command for device 0 (active=42, failed=0) Dec 1 16:16:02 lev8 kernel: Clearing timer for command e7e95400 1 Dec 1 16:16:02 lev8 kernel: Command finished 42 0 0x0 Dec 1 16:16:02 lev8 kernel: Notifying upper driver of completion for device 0 0 Dec 1 16:16:02 lev8 kernel: sda : rw_intr(0, 0 [0 0]) Dec 1 16:16:02 lev8 kernel: 56 sectors total, 56 sectors done. Dec 1 16:16:02 lev8 kernel: use_sg is 7 Dec 1 16:16:02 lev8 kernel: Deactivating command for device 0 (active=41, failed=0) Dec 1 16:16:02 lev8 kernel: Clearing timer for command e7e43800 1 Dec 1 16:16:02 lev8 kernel: Command finished 41 0 0x0 Dec 1 16:16:02 lev8 kernel: Notifying upper driver of completion for device 5 0 Dec 1 16:16:02 lev8 kernel: sg...bh: dev=3, pack_id=19364, res=0x0 Dec 1 16:16:02 lev8 kernel: Deactivating command for device 5 (active=40, failed=0) Dec 1 16:16:02 lev8 kernel: sg_poll: dev=3, res=0x145 Dec 1 16:16:02 lev8 kernel: sg_read: dev=3, count=32804 Dec 1 16:16:02 lev8 kernel: sg_read_oxfer: num_read_xfer=32768 Dec 1 16:16:02 lev8 kernel: sg_finish_rem_req: res_used=0 Dec 1 16:16:02 lev8 kernel: sg_remove_scat: k_use_sg=0 Dec 1 16:16:02 lev8 kernel: sg_free: buff=0xc7ba8000, size=32768 Dec 1 16:16:02 lev8 kernel: Clearing timer for command e7e8f400 1 Dec 1 16:16:02 lev8 kernel: Command finished 40 0 0x0 Dec 1 16:16:02 lev8 kernel: Notifying upper driver of completion for device 0 0 Dec 1 16:16:02 lev8 kernel: sda : rw_intr(0, 0 [0 0]) Dec 1 16:16:02 lev8 kernel: 32 sectors total, 32 sectors done. Dec 1 16:16:02 lev8 kernel: use_sg is 4 Dec 1 16:16:02 lev8 kernel: Deactivating command for device 0 (active=39, failed=0) Dec 1 16:16:02 lev8 kernel: sg_write: dev=3, count=42 Dec 1 16:16:02 lev8 kernel: Open returning 1 Dec 1 16:16:02 lev8 kernel: sg_write: scsi opcode=0x08, cmd_size=6 Dec 1 16:16:02 lev8 kernel: sg_common_write: scsi opcode=0x08, cmd_size=6 Dec 1 16:16:02 lev8 kernel: sg_start_req: dxfer_len=32768 Dec 1 16:16:02 lev8 kernel: sg_build_indi: buff_size=32768, blk_size=32768 Dec 1 16:16:02 lev8 kernel: sg_malloc: size=32768, ms=1, ret=0xc7ba8000 Dec 1 16:16:02 lev8 kernel: scsi_do_req (host = 0, channel = 0 target = 5, buffer =c7ba8000, bufflen = 32768, done = c01995b0, timeout = 60000, retries = 1) Dec 1 16:16:02 lev8 kernel: command : 08 00 00 80 00 00 af e7 07 00 Dec 1 16:16:02 lev8 kernel: Activating command for device 5 (40) Dec 1 16:16:02 lev8 kernel: Leaving scsi_init_cmd_from_req() Dec 1 16:16:02 lev8 kernel: Adding timer for command e7e43800 at 60000 (c017b090) Dec 1 16:16:02 lev8 kernel: scsi_dispatch_cmnd (host = 0, channel = 0, target = 5, command = e7e43858, buffer = c7ba8000, Dec 1 16:16:02 lev8 kernel: bufflen = 32768, done = c01995b0) Dec 1 16:16:02 lev8 kernel: queuecommand : routine at c01822c0 Dec 1 16:16:02 lev8 kernel: leaving scsi_dispatch_cmnd() Dec 1 16:16:02 lev8 kernel: Leaving scsi_do_req() Dec 1 16:16:02 lev8 kernel: sg_poll: dev=3, res=0x104 Dec 1 16:16:02 lev8 kernel: Clearing timer for command e7e90c00 1 Dec 1 16:16:02 lev8 kernel: Command finished 40 0 0x0 Dec 1 16:16:02 lev8 kernel: Notifying upper driver of completion for device 0 0 Dec 1 16:16:02 lev8 kernel: sda : rw_intr(0, 0 [0 0]) Dec 1 16:16:02 lev8 kernel: 24 sectors total, 24 sectors done. Dec 1 16:16:02 lev8 kernel: use_sg is 3 Dec 1 16:16:02 lev8 kernel: Deactivating command for device 0 (active=39, failed=0) Dec 1 16:16:02 lev8 kernel: Clearing timer for command e7e43a00 1 Dec 1 16:16:02 lev8 kernel: Command finished 39 0 0x0 Dec 1 16:16:02 lev8 kernel: Notifying upper driver of completion for device 5 0 Dec 1 16:16:02 lev8 kernel: sg...bh: dev=3, pack_id=19365, res=0x0 Dec 1 16:16:02 lev8 kernel: Deactivating command for device 5 (active=38, failed=0) Dec 1 16:16:02 lev8 kernel: sg_poll: dev=3, res=0x145 Dec 1 16:16:02 lev8 kernel: sg_read: dev=3, count=32804 Dec 1 16:16:02 lev8 kernel: sg_read_oxfer: num_read_xfer=32768 Dec 1 16:16:02 lev8 kernel: Clearing timer for command e7e91e00 1 Dec 1 16:16:02 lev8 kernel: Command finished 38 0 0x0 Dec 1 16:16:02 lev8 kernel: Notifying upper driver of completion for device 0 0 Dec 1 16:16:02 lev8 kernel: sda : rw_intr(0, 0 [0 0]) Dec 1 16:16:02 lev8 kernel: 8 sectors total, 8 sectors done. Dec 1 16:16:02 lev8 kernel: use_sg is 0 Dec 1 16:16:02 lev8 kernel: Deactivating command for device 0 (active=37, failed=0) Dec 1 16:16:02 lev8 kernel: sg_finish_rem_req: res_used=1 Dec 1 16:16:02 lev8 kernel: sg_unlink_reserve: req->k_use_sg=0 Dec 1 16:16:02 lev8 kernel: Clearing timer for command e7e8fe00 1 Dec 1 16:16:02 lev8 kernel: Command finished 37 0 0x0 Dec 1 16:16:02 lev8 kernel: Notifying upper driver of completion for device 0 0 Dec 1 16:16:02 lev8 kernel: sda : rw_intr(0, 0 [0 0]) Dec 1 16:16:02 lev8 kernel: 8 sectors total, 8 sectors done. Dec 1 16:16:02 lev8 kernel: use_sg is 0 Dec 1 16:16:02 lev8 kernel: Deactivating command for device 0 (active=36, failed=0) Dec 1 16:16:02 lev8 kernel: sg_write: dev=3, count=42 Dec 1 16:16:02 lev8 kernel: Open returning 1 Dec 1 16:16:02 lev8 kernel: sg_write: scsi opcode=0x08, cmd_size=6 Dec 1 16:16:02 lev8 kernel: sg_common_write: scsi opcode=0x08, cmd_size=6 Dec 1 16:16:02 lev8 kernel: sg_start_req: dxfer_len=32768 Dec 1 16:16:02 lev8 kernel: sg_link_reserve: size=32768 Dec 1 16:16:02 lev8 kernel: scsi_do_req (host = 0, channel = 0 target = 5, buffer =c54a0000, bufflen = 32768, done = c01995b0, timeout = 60000, retries = 1) Dec 1 16:16:02 lev8 kernel: command : 08 00 00 80 00 00 af e7 07 00 Dec 1 16:16:02 lev8 kernel: Activating command for device 5 (37) Dec 1 16:16:02 lev8 kernel: Leaving scsi_init_cmd_from_req() Dec 1 16:16:02 lev8 kernel: Adding timer for command e7e43a00 at 60000 (c017b090) Dec 1 16:16:02 lev8 kernel: scsi_dispatch_cmnd (host = 0, channel = 0, target = 5, command = e7e43a58, buffer = c54a0000, Dec 1 16:16:02 lev8 kernel: bufflen = 32768, done = c01995b0) Dec 1 16:16:02 lev8 kernel: queuecommand : routine at c01822c0 Dec 1 16:16:02 lev8 kernel: leaving scsi_dispatch_cmnd() Dec 1 16:16:02 lev8 kernel: Leaving scsi_do_req() Dec 1 16:16:02 lev8 kernel: sg_poll: dev=3, res=0x104 Dec 1 16:16:02 lev8 kernel: Clearing timer for command e7e86800 1 Dec 1 16:16:02 lev8 kernel: Command finished 37 0 0x0 Dec 1 16:16:02 lev8 kernel: Notifying upper driver of completion for device 0 0 Dec 1 16:16:02 lev8 kernel: sda : rw_intr(0, 0 [0 0]) Dec 1 16:16:02 lev8 kernel: 8 sectors total, 8 sectors done. Dec 1 16:16:02 lev8 kernel: use_sg is 0 Dec 1 16:16:02 lev8 kernel: Deactivating command for device 0 (active=36, failed=0) Dec 1 16:16:02 lev8 kernel: Clearing timer for command e7e43800 1 Dec 1 16:16:02 lev8 kernel: Command finished 36 0 0x0 Dec 1 16:16:02 lev8 kernel: Notifying upper driver of completion for device 5 0 Dec 1 16:16:02 lev8 kernel: sg...bh: dev=3, pack_id=19366, res=0x0 Dec 1 16:16:02 lev8 kernel: Deactivating command for device 5 (active=35, failed=0) Dec 1 16:16:02 lev8 kernel: sg_poll: dev=3, res=0x145 Dec 1 16:16:02 lev8 kernel: sg_read: dev=3, count=32804 Dec 1 16:16:02 lev8 kernel: sg_read_oxfer: num_read_xfer=32768 Dec 1 16:16:02 lev8 kernel: sg_finish_rem_req: res_used=0 Dec 1 16:16:02 lev8 kernel: sg_remove_scat: k_use_sg=0 Dec 1 16:16:02 lev8 kernel: sg_free: buff=0xc7ba8000, size=32768 Dec 1 16:16:02 lev8 kernel: Clearing timer for command e7e88000 1 Dec 1 16:16:02 lev8 kernel: Command finished 35 0 0x0 Dec 1 16:16:02 lev8 kernel: Notifying upper driver of completion for device 0 0 Dec 1 16:16:02 lev8 kernel: sda : rw_intr(0, 0 [0 0]) Dec 1 16:16:02 lev8 kernel: 16 sectors total, 16 sectors done. Dec 1 16:16:02 lev8 kernel: use_sg is 2 Dec 1 16:16:02 lev8 kernel: Deactivating command for device 0 (active=34, failed=0) Dec 1 16:16:02 lev8 kernel: sg_write: dev=3, count=42 Dec 1 16:16:02 lev8 kernel: Open returning 1 Dec 1 16:16:02 lev8 kernel: sg_write: scsi opcode=0x08, cmd_size=6 Dec 1 16:16:02 lev8 kernel: sg_common_write: scsi opcode=0x08, cmd_size=6 Dec 1 16:16:02 lev8 kernel: sg_start_req: dxfer_len=32768 Dec 1 16:16:02 lev8 kernel: sg_build_indi: buff_size=32768, blk_size=32768 Dec 1 16:16:02 lev8 kernel: sg_malloc: size=32768, ms=1, ret=0xc7ba8000 Dec 1 16:16:02 lev8 kernel: scsi_do_req (host = 0, channel = 0 target = 5, buffer =c7ba8000, bufflen = 32768, done = c01995b0, timeout = 60000, retries = 1) Dec 1 16:16:02 lev8 kernel: command : 08 00 00 80 00 00 af e7 07 00 Dec 1 16:16:02 lev8 kernel: Activating command for device 5 (35) Dec 1 16:16:02 lev8 kernel: Leaving scsi_init_cmd_from_req() Dec 1 16:16:02 lev8 kernel: Adding timer for command e7e43800 at 60000 (c017b090) Dec 1 16:16:02 lev8 kernel: scsi_dispatch_cmnd (host = 0, channel = 0, target = 5, command = e7e43858, buffer = c7ba8000, Dec 1 16:16:02 lev8 kernel: bufflen = 32768, done = c01995b0) Dec 1 16:16:02 lev8 kernel: queuecommand : routine at c01822c0 Dec 1 16:16:02 lev8 kernel: leaving scsi_dispatch_cmnd() Dec 1 16:16:02 lev8 kernel: Leaving scsi_do_req() Dec 1 16:16:02 lev8 kernel: sg_poll: dev=3, res=0x104 Dec 1 16:16:02 lev8 kernel: Clearing timer for command e7e87400 1 Dec 1 16:16:02 lev8 kernel: Command finished 35 0 0x0 Dec 1 16:16:02 lev8 kernel: Notifying upper driver of completion for device 0 0 Dec 1 16:16:02 lev8 kernel: sda : rw_intr(0, 0 [0 0]) Dec 1 16:16:02 lev8 kernel: 32 sectors total, 32 sectors done. Dec 1 16:16:02 lev8 kernel: use_sg is 4 Dec 1 16:16:02 lev8 kernel: Deactivating command for device 0 (active=34, failed=0) Dec 1 16:16:02 lev8 kernel: Clearing timer for command e7e89200 1 Dec 1 16:16:02 lev8 kernel: Command finished 34 0 0x0 Dec 1 16:16:02 lev8 kernel: Notifying upper driver of completion for device 0 0 Dec 1 16:16:02 lev8 kernel: sda : rw_intr(0, 0 [0 0]) Dec 1 16:16:02 lev8 kernel: 40 sectors total, 40 sectors done. Dec 1 16:16:02 lev8 kernel: use_sg is 5 Dec 1 16:16:02 lev8 kernel: Deactivating command for device 0 (active=33, failed=0) Dec 1 16:16:02 lev8 kernel: Clearing timer for command e7e43a00 1 Dec 1 16:16:02 lev8 kernel: Command finished 33 0 0x0 Dec 1 16:16:02 lev8 kernel: Notifying upper driver of completion for device 5 0 Dec 1 16:16:02 lev8 kernel: sg...bh: dev=3, pack_id=19367, res=0x0 Dec 1 16:16:02 lev8 kernel: Deactivating command for device 5 (active=32, failed=0) Dec 1 16:16:02 lev8 kernel: sg_poll: dev=3, res=0x145 Dec 1 16:16:02 lev8 kernel: sg_read: dev=3, count=32804 Dec 1 16:16:02 lev8 kernel: sg_read_oxfer: num_read_xfer=32768 Dec 1 16:16:02 lev8 kernel: sg_finish_rem_req: res_used=1 Dec 1 16:16:02 lev8 kernel: sg_unlink_reserve: req->k_use_sg=0 Dec 1 16:16:02 lev8 kernel: Clearing timer for command e7e89400 1 Dec 1 16:16:02 lev8 kernel: Command finished 32 0 0x0 Dec 1 16:16:02 lev8 kernel: Notifying upper driver of completion for device 0 0 Dec 1 16:16:02 lev8 kernel: sda : rw_intr(0, 0 [0 0]) Dec 1 16:16:02 lev8 kernel: 40 sectors total, 40 sectors done. Dec 1 16:16:02 lev8 kernel: use_sg is 5 Dec 1 16:16:02 lev8 kernel: Deactivating command for device 0 (active=31, failed=0) Dec 1 16:16:02 lev8 kernel: sg_write: dev=3, count=42 Dec 1 16:16:02 lev8 kernel: Open returning 1 Dec 1 16:16:02 lev8 kernel: sg_write: scsi opcode=0x08, cmd_size=6 Dec 1 16:16:02 lev8 kernel: sg_common_write: scsi opcode=0x08, cmd_size=6 Dec 1 16:16:02 lev8 kernel: sg_start_req: dxfer_len=32768 Dec 1 16:16:02 lev8 kernel: sg_link_reserve: size=32768 Dec 1 16:16:02 lev8 kernel: scsi_do_req (host = 0, channel = 0 target = 5, buffer =c54a0000, bufflen = 32768, done = c01995b0, timeout = 60000, retries = 1) Dec 1 16:16:02 lev8 kernel: command : 08 00 00 80 00 00 af e7 07 00 Dec 1 16:16:02 lev8 kernel: Activating command for device 5 (32) Dec 1 16:16:02 lev8 kernel: Leaving scsi_init_cmd_from_req() Dec 1 16:16:02 lev8 kernel: Adding timer for command e7e43a00 at 60000 (c017b090) Dec 1 16:16:02 lev8 kernel: scsi_dispatch_cmnd (host = 0, channel = 0, target = 5, command = e7e43a58, buffer = c54a0000, Dec 1 16:16:02 lev8 kernel: bufflen = 32768, done = c01995b0) Dec 1 16:16:02 lev8 kernel: queuecommand : routine at c01822c0 Dec 1 16:16:02 lev8 kernel: leaving scsi_dispatch_cmnd() Dec 1 16:16:02 lev8 kernel: Leaving scsi_do_req() Dec 1 16:16:02 lev8 kernel: sg_poll: dev=3, res=0x104 Dec 1 16:16:02 lev8 kernel: Clearing timer for command e7e8ae00 1 Dec 1 16:16:02 lev8 kernel: Command finished 32 0 0x0 Dec 1 16:16:02 lev8 kernel: Notifying upper driver of completion for device 0 0 Dec 1 16:16:02 lev8 kernel: sda : rw_intr(0, 0 [0 0]) Dec 1 16:16:02 lev8 kernel: 64 sectors total, 64 sectors done. Dec 1 16:16:02 lev8 kernel: use_sg is 8 Dec 1 16:16:02 lev8 kernel: Deactivating command for device 0 (active=31, failed=0) Dec 1 16:16:02 lev8 kernel: Clearing timer for command e7e97000 1 Dec 1 16:16:02 lev8 kernel: Command finished 31 0 0x0 Dec 1 16:16:02 lev8 kernel: Notifying upper driver of completion for device 0 0 Dec 1 16:16:02 lev8 kernel: sda : rw_intr(0, 0 [0 0]) Dec 1 16:16:02 lev8 kernel: 8 sectors total, 8 sectors done. Dec 1 16:16:02 lev8 kernel: use_sg is 0 Dec 1 16:16:02 lev8 kernel: Deactivating command for device 0 (active=30, failed=0) Dec 1 16:16:02 lev8 kernel: Clearing timer for command e7e43800 1 Dec 1 16:16:02 lev8 kernel: Command finished 30 0 0x0 Dec 1 16:16:02 lev8 kernel: Notifying upper driver of completion for device 5 0 Dec 1 16:16:02 lev8 kernel: sg...bh: dev=3, pack_id=19368, res=0x0 Dec 1 16:16:02 lev8 kernel: Deactivating command for device 5 (active=29, failed=0) Dec 1 16:16:02 lev8 kernel: sg_poll: dev=3, res=0x145 Dec 1 16:16:02 lev8 kernel: sg_read: dev=3, count=32804 Dec 1 16:16:02 lev8 kernel: sg_read_oxfer: num_read_xfer=32768 Dec 1 16:16:02 lev8 kernel: sg_finish_rem_req: res_used=0 Dec 1 16:16:02 lev8 kernel: sg_remove_scat: k_use_sg=0 Dec 1 16:16:02 lev8 kernel: sg_free: buff=0xc7ba8000, size=32768 Dec 1 16:16:02 lev8 kernel: Clearing timer for command e7e98c00 1 Dec 1 16:16:02 lev8 kernel: Command finished 29 0 0x0 Dec 1 16:16:02 lev8 kernel: Notifying upper driver of completion for device 0 0 Dec 1 16:16:02 lev8 kernel: sda : rw_intr(0, 0 [0 0]) Dec 1 16:16:02 lev8 kernel: 48 sectors total, 48 sectors done. Dec 1 16:16:02 lev8 kernel: use_sg is 6 Dec 1 16:16:02 lev8 kernel: Deactivating command for device 0 (active=28, failed=0) Dec 1 16:16:02 lev8 kernel: Clearing timer for command e7e90200 1 Dec 1 16:16:02 lev8 kernel: Command finished 28 0 0x0 Dec 1 16:16:02 lev8 kernel: Notifying upper driver of completion for device 0 0 Dec 1 16:16:02 lev8 kernel: sda : rw_intr(0, 0 [0 0]) Dec 1 16:16:02 lev8 kernel: 48 sectors total, 48 sectors done. Dec 1 16:16:02 lev8 kernel: use_sg is 6 Dec 1 16:16:02 lev8 kernel: Deactivating command for device 0 (active=27, failed=0) Dec 1 16:16:02 lev8 kernel: Clearing timer for command e7e90800 1 Dec 1 16:16:02 lev8 kernel: Command finished 27 0 0x0 Dec 1 16:16:02 lev8 kernel: Notifying upper driver of completion for device 0 0 Dec 1 16:16:02 lev8 kernel: sda : rw_intr(0, 0 [0 0]) Dec 1 16:16:02 lev8 kernel: 16 sectors total, 16 sectors done. Dec 1 16:16:02 lev8 kernel: use_sg is 2 Dec 1 16:16:02 lev8 kernel: Deactivating command for device 0 (active=26, failed=0) Dec 1 16:16:02 lev8 kernel: sg_write: dev=3, count=42 Dec 1 16:16:02 lev8 kernel: Open returning 1 Dec 1 16:16:02 lev8 kernel: sg_write: scsi opcode=0x08, cmd_size=6 Dec 1 16:16:02 lev8 kernel: sg_common_write: scsi opcode=0x08, cmd_size=6 Dec 1 16:16:02 lev8 kernel: sg_start_req: dxfer_len=32768 Dec 1 16:16:02 lev8 kernel: sg_build_indi: buff_size=32768, blk_size=32768 Dec 1 16:16:02 lev8 kernel: sg_malloc: size=32768, ms=1, ret=0xc7ba8000 Dec 1 16:16:02 lev8 kernel: scsi_do_req (host = 0, channel = 0 target = 5, buffer =c7ba8000, bufflen = 32768, done = c01995b0, timeout = 60000, retries = 1) Dec 1 16:16:02 lev8 kernel: command : 08 00 00 80 00 00 af e7 07 00 Dec 1 16:16:02 lev8 kernel: Activating command for device 5 (27) Dec 1 16:16:02 lev8 kernel: Leaving scsi_init_cmd_from_req() Dec 1 16:16:02 lev8 kernel: Adding timer for command e7e43800 at 60000 (c017b090) Dec 1 16:16:02 lev8 kernel: scsi_dispatch_cmnd (host = 0, channel = 0, target = 5, command = e7e43858, buffer = c7ba8000, Dec 1 16:16:02 lev8 kernel: bufflen = 32768, done = c01995b0) Dec 1 16:16:02 lev8 kernel: queuecommand : routine at c01822c0 Dec 1 16:16:02 lev8 kernel: leaving scsi_dispatch_cmnd() Dec 1 16:16:02 lev8 kernel: Leaving scsi_do_req() Dec 1 16:16:02 lev8 kernel: sg_poll: dev=3, res=0x104 Dec 1 16:16:02 lev8 kernel: Clearing timer for command e7e43a00 1 Dec 1 16:16:02 lev8 kernel: Command finished 27 0 0x0 Dec 1 16:16:02 lev8 kernel: Notifying upper driver of completion for device 5 0 Dec 1 16:16:02 lev8 kernel: sg...bh: dev=3, pack_id=19369, res=0x0 Dec 1 16:16:02 lev8 kernel: Deactivating command for device 5 (active=26, failed=0) Dec 1 16:16:02 lev8 kernel: sg_poll: dev=3, res=0x145 Dec 1 16:16:02 lev8 kernel: sg_read: dev=3, count=32804 Dec 1 16:16:02 lev8 kernel: sg_read_oxfer: num_read_xfer=32768 Dec 1 16:16:02 lev8 kernel: sg_finish_rem_req: res_used=1 Dec 1 16:16:02 lev8 kernel: sg_unlink_reserve: req->k_use_sg=0 Dec 1 16:16:02 lev8 kernel: Clearing timer for command e7e91000 1 Dec 1 16:16:02 lev8 kernel: Command finished 26 0 0x0 Dec 1 16:16:02 lev8 kernel: Notifying upper driver of completion for device 0 0 Dec 1 16:16:02 lev8 kernel: sda : rw_intr(0, 0 [0 0]) Dec 1 16:16:02 lev8 kernel: 32 sectors total, 32 sectors done. Dec 1 16:16:02 lev8 kernel: use_sg is 4 Dec 1 16:16:02 lev8 kernel: Deactivating command for device 0 (active=25, failed=0) Dec 1 16:16:02 lev8 kernel: sg_write: dev=3, count=42 Dec 1 16:16:02 lev8 kernel: Open returning 1 Dec 1 16:16:02 lev8 kernel: sg_write: scsi opcode=0x08, cmd_size=6 Dec 1 16:16:02 lev8 kernel: sg_common_write: scsi opcode=0x08, cmd_size=6 Dec 1 16:16:02 lev8 kernel: sg_start_req: dxfer_len=32768 Dec 1 16:16:02 lev8 kernel: sg_link_reserve: size=32768 Dec 1 16:16:02 lev8 kernel: scsi_do_req (host = 0, channel = 0 target = 5, buffer =c54a0000, bufflen = 32768, done = c01995b0, timeout = 60000, retries = 1) Dec 1 16:16:02 lev8 kernel: command : 08 00 00 80 00 00 af e7 07 00 Dec 1 16:16:02 lev8 kernel: Activating command for device 5 (26) Dec 1 16:16:02 lev8 kernel: Leaving scsi_init_cmd_from_req() Dec 1 16:16:02 lev8 kernel: Adding timer for command e7e43a00 at 60000 (c017b090) Dec 1 16:16:02 lev8 kernel: scsi_dispatch_cmnd (host = 0, channel = 0, target = 5, command = e7e43a58, buffer = c54a0000, Dec 1 16:16:02 lev8 kernel: bufflen = 32768, done = c01995b0) Dec 1 16:16:02 lev8 kernel: queuecommand : routine at c01822c0 Dec 1 16:16:02 lev8 kernel: leaving scsi_dispatch_cmnd() Dec 1 16:16:02 lev8 kernel: Leaving scsi_do_req() Dec 1 16:16:02 lev8 kernel: sg_poll: dev=3, res=0x104 Dec 1 16:16:02 lev8 kernel: (scsi0:A:0:0): parity error detected in Message-in phase. SEQADDR(0x1a5) SCSIRATE(0x95) Dec 1 16:16:31 lev8 kernel: Command timed out active=26 busy=26 failed=1 Dec 1 16:16:31 lev8 kernel: Command timed out active=26 busy=26 failed=2 Dec 1 16:16:31 lev8 kernel: Command timed out active=26 busy=26 failed=3 Dec 1 16:16:31 lev8 kernel: Command timed out active=26 busy=26 failed=4 Dec 1 16:16:31 lev8 kernel: Command timed out active=26 busy=26 failed=5 Dec 1 16:16:31 lev8 kernel: Command timed out active=26 busy=26 failed=6 Dec 1 16:16:31 lev8 kernel: Command timed out active=26 busy=26 failed=7 Dec 1 16:16:31 lev8 kernel: Command timed out active=26 busy=26 failed=8 Dec 1 16:16:31 lev8 kernel: Command timed out active=26 busy=26 failed=9 Dec 1 16:16:31 lev8 kernel: Command timed out active=26 busy=26 failed=10 Dec 1 16:16:31 lev8 kernel: Command timed out active=26 busy=26 failed=11 Dec 1 16:16:31 lev8 kernel: Command timed out active=26 busy=26 failed=12 Dec 1 16:16:31 lev8 kernel: Command timed out active=26 busy=26 failed=13 Dec 1 16:16:31 lev8 kernel: Command timed out active=26 busy=26 failed=14 Dec 1 16:16:31 lev8 kernel: Command timed out active=26 busy=26 failed=15 Dec 1 16:16:31 lev8 kernel: Command timed out active=26 busy=26 failed=16 Dec 1 16:16:31 lev8 kernel: Command timed out active=26 busy=26 failed=17 Dec 1 16:16:31 lev8 kernel: Command timed out active=26 busy=26 failed=18 Dec 1 16:16:31 lev8 kernel: Command timed out active=26 busy=26 failed=19 Dec 1 16:16:31 lev8 kernel: Command timed out active=26 busy=26 failed=20 Dec 1 16:16:31 lev8 kernel: Command timed out active=26 busy=26 failed=21 Dec 1 16:16:31 lev8 kernel: Command timed out active=26 busy=26 failed=22 Dec 1 16:16:31 lev8 kernel: Command timed out active=26 busy=26 failed=23 Dec 1 16:16:31 lev8 kernel: Command timed out active=26 busy=26 failed=24 At this point I rebooted the machine... These crashes are fairly reproducable for me, so if there are some suggestions to get better logging out of the machine, just tell me what to do. To Unsubscribe: send mail to majordomo@FreeBSD.org with "unsubscribe aic7xxx" in the body of the message