Still problems with SCSI scanning...
Tom Wirschell
T.Wirschell at fin.pinkroccade.nl
Mon Dec 3 23:18:53 PST 2001
(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 at FreeBSD.org
with "unsubscribe aic7xxx" in the body of the message
More information about the aic7xxx
mailing list