G'day mates!
I've been running a tape backup (Sony SDX 420C, IDE) on my Red Hat 7.3 server for about 3 months and everything has been working like a dream.
Then at the beginning of last week I noticed that the tape backup was failing and tar was raising error messages: tar (child): Error is not recoverable: exiting now
After about 12 hours of investigation it was revealed that the 2.4.20 series of kernels cannot communicate with the tape drive at all. Errors were raised during bootup. See extract from /var/log/messages below.
However, when I reverted to the 2.4.18-27.7.xsmp kernel it works just fine, see below.
Has anyone else experienced similiar problems with the 2.4.20 series of kernels? Is there any solution to this problem?
Cheers!
Justin Freeman
*********
ERRORS RAISED USING KERNEL 2.4.20-18.7smp
Jun 22 08:15:40 spider kernel: Linux version 2.4.20-18.7smp ([EMAIL PROTECTED]) (gcc version 2.96 20000731 (Red Hat Linux 7.3 2.96-113)) #1 SMP Thu May 29 07:49:23 EDT 2003
Jun 22 08:15:44 spider kernel: ide-floppy driver 0.99.newide
Jun 22 08:15:44 spider kernel: hda: attached ide-tape driver.
Jun 22 08:15:44 spider kernel: hda: status timeout: status=0xd0 { Busy }
Jun 22 08:15:44 spider kernel: hda: DMA disabled
Jun 22 08:15:44 spider kernel: ide-tape: Strange, packet command initiated yet DRQ isn't asserted
Jun 22 08:15:44 spider kernel: hda: ATAPI reset complete
Jun 22 08:15:44 spider kernel: ide-tape: ht0: can't get INQUIRY results
Jun 22 08:15:44 spider kernel: ide-tape: ht0: DSC timeout
Jun 22 08:15:44 spider kernel: hda: ATAPI reset complete
Jun 22 08:15:44 spider kernel: ide-tape: hda: overriding capabilities->speed (assuming 650KB/sec)
Jun 22 08:15:44 spider kernel: ide-tape: hda: overriding capabilities->max_speed (assuming 650KB/sec
)
Jun 22 08:15:44 spider kernel: ide-tape: decreasing stage size
Jun 22 08:15:44 spider last message repeated 2 times
Jun 22 08:15:44 spider kernel: ide-tape: hda <-> ht0: 650KBps, 62*32kB buffer, 6336kB pipeline, 100m
s tDSC
Jun 22 08:15:44 spider kernel: hda: DMA disabled
And while running a backup:
Jun 22 08:28:54 spider kernel: ide-tape: bug: nr_stages should be 0 now
Jun 22 08:29:25 spider kernel: ide-tape: ht0: I/O error, pc = 11, key = 8, asc = 0, ascq = 5
Jun 22 08:31:25 spider kernel: ide-tape: ide-tape pipeline bug, first_stage 00000000, next_stage 000
00000, last_stage 00000000, nr_stages 39
Jun 22 08:32:27 spider kernel: hda: irq timeout: status=0xd0 { Busy }
Jun 22 08:32:27 spider kernel: hda: ATAPI reset complete
Jun 22 08:33:27 spider kernel: hda: irq timeout: status=0xd0 { Busy }
Jun 22 08:33:27 spider kernel: hda: ATAPI reset complete
Jun 22 08:34:27 spider kernel: hda: irq timeout: status=0xd0 { Busy }
Jun 22 08:34:32 spider kernel: ide-tape: ide-tape pipeline bug, first_stage 00000000, next_stage 000
00000, last_stage 00000000, nr_stages 39
Jun 22 08:34:32 spider kernel: hda: status timeout: status=0xd0 { Busy }
Jun 22 08:34:32 spider kernel: ide-tape: Strange, packet command initiated yet DRQ isn't asserted
Jun 22 08:34:32 spider kernel: hda: ATAPI reset complete
Jun 22 08:34:32 spider kernel: ide-tape: Couldn't write a filemark
Jun 22 08:36:33 spider kernel: ide-tape: ht0: DSC timeout
Jun 22 08:36:33 spider kernel: hda: ATAPI reset complete
Jun 22 08:36:33 spider kernel: ide-tape: ht0: I/O error, pc = 10, key = 8, asc = 0, ascq = 5
Jun 22 08:36:33 spider kernel: ide-tape: ht0: I/O error, pc = 10, key = 8, asc = 0, ascq = 5
Jun 22 08:36:33 spider kernel: ide-tape: ht0: I/O error, pc = 1e, key = 0, asc = 0, ascq = 0
Jun 22 08:46:03 spider sshd(pam_unix)[1665]: session opened for user root by (uid=0)
Jun 22 08:46:23 spider kernel: ide-tape: ht0: I/O error, pc = 34, key = 0, asc = 0, ascq = 0
Jun 22 08:46:23 spider kernel: ide-tape: ht0: I/O error, pc = 0, key = 8, asc = 0, ascq = 5
Jun 22 08:46:23 spider kernel: ide-tape: ht0: drive not ready
Jun 22 08:46:23 spider kernel: ide-tape: ht0: I/O error, pc = 34, key = 6, asc = 29, ascq = 1
Jun 22 08:46:23 spider kernel: ide-tape: ht0: I/O error, pc = 0, key = 0, asc = 0, ascq = 1
Jun 22 08:46:23 spider kernel: ide-tape: ht0: drive not ready
Jun 22 08:46:27 spider kernel: ide-tape: ht0: I/O error, pc = 34, key = 8, asc = 0, ascq = 5
Jun 22 08:46:27 spider kernel: ide-tape: ht0: I/O error, pc = 0, key = 0, asc = 0, ascq = 0
Jun 22 08:46:27 spider kernel: ide-tape: ht0: drive not ready
Jun 22 08:46:27 spider kernel: ide-tape: ht0: I/O error, pc = 34, key = 0, asc = 0, ascq = 0
Jun 22 08:46:27 spider kernel: ide-tape: ht0: I/O error, pc = 0, key = 8, asc = 0, ascq = 5
Jun 22 08:46:27 spider kernel: ide-tape: ht0: drive not ready
Jun 22 08:46:29 spider kernel: ide-tape: ht0: I/O error, pc = 34, key = 0, asc = 0, ascq = 0
Jun 22 08:46:29 spider kernel: ide-tape: ht0: I/O error, pc = 0, key = 6, asc = 29, ascq = 1
Jun 22 08:46:29 spider kernel: ide-tape: ht0: drive not ready
Jun 22 08:46:29 spider kernel: ide-tape: ht0: I/O error, pc = 34, key = 8, asc = 0, ascq = 5
Jun 22 08:46:29 spider kernel: ide-tape: ht0: I/O error, pc = 0, key = 8, asc = 0, ascq = 5
Jun 22 08:46:29 spider kernel: ide-tape: ht0: drive not ready
Jun 22 08:46:30 spider kernel: ide-tape: ht0: I/O error, pc = 34, key = 0, asc = 0, ascq = 0
Jun 22 08:46:30 spider kernel: ide-tape: ht0: I/O error, pc = 0, key = 0, asc = 0, ascq = 0
Jun 22 08:46:30 spider kernel: ide-tape: ht0: drive not ready
Jun 22 08:46:30 spider kernel: ide-tape: ht0: I/O error, pc = 34, key = 8, asc = 0, ascq = 5
Jun 22 08:46:30 spider kernel: ide-tape: ht0: I/O error, pc = 0, key = 6, asc = 29, ascq = 1
Jun 22 08:46:30 spider kernel: ide-tape: ht0: drive not ready
Jun 22 08:46:47 spider shutdown: shutting down for system halt
WORKS FINE USING KERNEL 2.4.18-27.7.xsmp
Jun 22 08:58:13 spider kernel: Linux version 2.4.18-27.7.xsmp ([EMAIL PROTECTED]) (gcc version 2.96 20000731 (Red Hat Linux 7.3 2.96-112)) #1 SMP Fri Mar 14 05:52:30 EST 2003
Jun 22 08:58:13 spider kernel: ide-floppy driver 0.99.newide
Jun 22 08:58:13 spider kernel: ide-tape: Dumping ATAPI Identify Device tape parameters
Jun 22 08:58:13 spider kernel: ide-tape: Protocol Type: <6>ATAPI
Jun 22 08:58:13 spider kernel: ide-tape: Device Type: 1 - <6>Streaming Tape Device
Jun 22 08:58:13 spider kernel: ide-tape: Removable: Yes
Jun 22 08:58:13 spider kernel: ide-tape: Command Packet DRQ Type: <6>Accelerated DRQ
Jun 22 08:58:13 spider kernel: ide-tape: Command Packet Size: <6>12 bytes
Jun 22 08:58:13 spider kernel: ide-tape: Model: SONY SDX-420C
Jun 22 08:58:13 spider kernel: ide-tape: Firmware Revision: 0103
Jun 22 08:58:14 spider kernel: ide-tape: Serial Number: 0008500013
Jun 22 08:58:14 spider kernel: ide-tape: Write buffer size: 0 bytes
Jun 22 08:58:14 spider kernel: ide-tape: DMA: Yes
Jun 22 08:58:14 spider kernel: ide-tape: LBA: Yes
Jun 22 08:58:14 spider kernel: ide-tape: IORDY can be disabled: Yes
Jun 22 08:58:14 spider kernel: ide-tape: IORDY supported: Yes
Jun 22 08:58:14 spider kernel: ide-tape: ATAPI overlap supported: No
Jun 22 08:58:14 spider kernel: ide-tape: PIO Cycle Timing Category: 0
Jun 22 08:58:14 spider kernel: ide-tape: DMA Cycle Timing Category: 0
Jun 22 08:58:14 spider kernel: ide-tape: Single Word DMA supported modes: <6>
Jun 22 08:58:14 spider kernel: ide-tape: Multi Word DMA supported modes: <6>0 <6>1 <6>2 <6>
Jun 22 08:58:14 spider kernel: ide-tape: Enhanced PIO Modes: Mode 3
Jun 22 08:58:14 spider kernel: ide-tape: Minimum Multi-word DMA cycle per word: <6>120 ns
Jun 22 08:58:14 spider kernel: ide-tape: Manufacturer's Recommended Multi-word cycle: <6>120 ns
Jun 22 08:58:14 spider kernel: ide-tape: Minimum PIO cycle without IORDY: <6>240 ns
Jun 22 08:58:14 spider kernel: ide-tape: Minimum PIO cycle with IORDY: <6>120 ns
Jun 22 08:58:14 spider kernel: hda: status timeout: status=0xd0 { Busy }
Jun 22 08:58:14 spider kernel: hda: DMA disabled
Jun 22 08:58:14 spider kernel: ide-tape: Strange, packet command initiated yet DRQ isn't asserted
Jun 22 08:58:14 spider kernel: hda: ATAPI reset complete
Jun 22 08:58:14 spider kernel: ide-tape: ht0: can't get INQUIRY results
Jun 22 08:58:14 spider kernel: ide-tape: ht0: DSC timeout
Jun 22 08:58:14 spider kernel: hda: ATAPI reset complete
Jun 22 08:58:14 spider kernel: ide-tape: hda: overriding capabilities->speed (assuming 650KB/sec)
Jun 22 08:58:14 spider kernel: ide-tape: hda: overriding capabilities->max_speed (assuming 650KB/sec)
Jun 22 08:58:14 spider kernel: ide-tape: Dumping the results of the MODE SENSE packet command
Jun 22 08:58:14 spider kernel: ide-tape: Mode Parameter Header:
Jun 22 08:58:14 spider kernel: ide-tape: Mode Data Length - 23
Jun 22 08:58:14 spider kernel: ide-tape: Medium Type - 0
Jun 22 08:58:14 spider kernel: ide-tape: Device Specific Parameter - 16
Jun 22 08:58:14 spider kernel: ide-tape: Block Descriptor Length - 0
Jun 22 08:58:14 spider kernel: ide-tape: Capabilities and Mechanical Status Page:
Jun 22 08:58:14 spider kernel: ide-tape: Page code - 42
Jun 22 08:58:15 spider kernel: ide-tape: Page length - 18
Jun 22 08:58:15 spider kernel: ide-tape: Read only - No
Jun 22 08:58:15 spider kernel: ide-tape: Supports reverse space - Yes
Jun 22 08:58:15 spider kernel: ide-tape: Supports erase initiated formatting - No
Jun 22 08:58:15 spider kernel: ide-tape: Supports QFA two Partition format - Yes
Jun 22 08:58:15 spider kernel: ide-tape: Supports locking the medium - Yes
Jun 22 08:58:15 spider kernel: ide-tape: The volume is currently locked - No
Jun 22 08:58:15 spider kernel: ide-tape: The device defaults in the prevent state - No
Jun 22 08:58:15 spider kernel: ide-tape: Supports ejecting the medium - Yes
Jun 22 08:58:15 spider kernel: ide-tape: Supports error correction - Yes
Jun 22 08:58:15 spider kernel: ide-tape: Supports data compression - Yes
Jun 22 08:58:15 spider kernel: ide-tape: Supports 512 bytes block size - Yes
Jun 22 08:58:15 spider kernel: ide-tape: Supports 1024 bytes block size - Yes
Jun 22 08:58:15 spider kernel: ide-tape: Supports 32768 bytes block size / Restricted byte count for PIO tra
nsfers - No
Jun 22 08:58:15 spider kernel: ide-tape: Maximum supported speed in KBps - 650
Jun 22 08:55:41 spider rc.sysinit: Mounting proc filesystem: succeeded
Jun 22 08:58:15 spider kernel: ide-tape: Continuous transfer limits in blocks - 512
Jun 22 08:55:41 spider rc.sysinit: Unmounting initrd: succeeded
Jun 22 08:58:15 spider kernel: ide-tape: Current speed in KBps - 650
Jun 22 08:55:41 spider sysctl: net.ipv4.ip_forward = 0
Jun 22 08:58:15 spider kernel: ide-tape: Buffer size - 2061824
Jun 22 08:55:41 spider sysctl: net.ipv4.conf.default.rp_filter = 1
Jun 22 08:58:15 spider kernel: ide-tape: Adjusted block size - 512
Jun 22 08:55:41 spider sysctl: kernel.core_uses_pid = 1
Jun 22 08:58:15 spider kernel: ide-tape: decreasing stage size
Jun 22 08:55:41 spider sysctl: kernel.sysrq = 0
Jun 22 08:58:15 spider kernel: ide-tape: decreasing stage size
Jun 22 08:55:41 spider rc.sysinit: Configuring kernel parameters: succeeded
Jun 22 08:58:15 spider kernel: ide-tape: decreasing stage size
Jun 22 08:55:41 spider date: Sun Jun 22 08:55:35 EST 2003
Jun 22 08:58:15 spider kernel: ide-tape: hda <-> ht0: 650KBps, 62*32kB buffer, 6336kB pipeline, 99ms tDSC
.....
Jun 22 08:59:20 spider kernel: ide-tape: Reached idetape_chrdev_open
