From mboxrd@z Thu Jan 1 00:00:00 1970 From: Vlad Codrea Subject: Re: DVD drive fails in 2.6.20.2 Date: Sat, 17 Mar 2007 20:26:04 -0500 Message-ID: <45FC952C.1040708@mail.utexas.edu> References: <45F631F7.6020106@mail.utexas.edu> <45F7760B.10102@gmail.com> <45F81177.9070106@mail.utexas.edu> <45F8E628.2010004@gmail.com> <45F9516D.4090008@mail.utexas.edu> <45F98923.1040601@tw.ibm.com> <45FA07E2.6080008@mail.utexas.edu> <45FAC6FC.4020105@tw.ibm.com> <45FADD53.5030500@mail.utexas.edu> <45FB71EA.7070602@tw.ibm.com> Mime-Version: 1.0 Content-Type: multipart/mixed; boundary="------------080602060105010407010708" Return-path: Received: from ironmaiden.mail.utexas.edu ([128.83.32.53]:28579 "EHLO ironmaiden.mail.utexas.edu" rhost-flags-OK-OK-OK-OK) by vger.kernel.org with ESMTP id S1753045AbXCRB0J (ORCPT ); Sat, 17 Mar 2007 21:26:09 -0400 In-Reply-To: <45FB71EA.7070602@tw.ibm.com> Sender: linux-ide-owner@vger.kernel.org List-Id: linux-ide@vger.kernel.org To: albertl@mail.com Cc: Tejun Heo , linux-ide@vger.kernel.org This is a multi-part message in MIME format. --------------080602060105010407010708 Content-Type: text/plain; charset=ISO-8859-1; format=flowed Content-Transfer-Encoding: 7bit Albert Lee wrote: > Vlad Codrea wrote: > >> Albert Lee wrote: >> >> >>> ata2: protocol 7 task_state 4 <=== The device interrupts to receive CDB >>> ata2: protocol 7 task_state 4 (dev_stat 0x58) <== Good, DRQ seen. CDB >>> sent. >>> ata2: protocol 7 task_state 2 <=== interrupt. We expect DMA complete. >>> ata2: protocol 7 task_state 2 (dev_stat 0x58) <== What? DRQ set? No good. >>> ata2: protocol 7 task_state 3 (dev_stat 0x58) <== HSM violation. >>> (BMDMA stat 0x5) <=== Interrupted, DMA active. >>> >>> Still no clue why the device wrongly sets DRQ here for a DMA transfer... >>> Possibilities: >>> 1. HSM sent all CDB to the device, but the device is waiting for more >>> CDB data? >>> 2. HSM asked the drive to do ATAPI DMA, but the drive is doing PIO? >>> >>> Hi Vlad, >>> >>> Could you please turn off ATAPI DMA and see if the drive works under PIO, >>> thanks. >>> >>> -- >>> albert >>> >>> (This is not a patch. Please edit drivers/ata/libata-core.c as below.) >>> >>> int ata_check_atapi_dma(struct ata_queued_cmd *qc) >>> { >>> struct ata_port *ap = qc->ap; >>> int rc = 0; /* Assume ATAPI DMA is OK by default */ >>> >>> if (ap->ops->check_atapi_dma) >>> rc = ap->ops->check_atapi_dma(qc); >>> >>> rc = 1; (<==== Please add "rc = 1;" here to turn off ATAPI DMA. ) >>> >>> return rc; >>> } >>> >>> >> Hi, >> >> After adding "rc = 1;", the drive now appears as /dev/sr0. I've attached >> an annotated dmesg: the first part contains the messages immediately >> after booting. The second part contains messages after I inserted a CD >> and ran "mount /dev/sr0 /root/cdrom". The third part contains messages >> after I ran "cp -r /root/cdrom /root/cdrom_copy". I separated the parts >> by adding lines containing "#####". >> >> The recursive copy fails after about 10 seconds, even though a few files >> are copied. >> >> > > Ok, as Tejun said, PIO looks better than DMA, but still has problems. > Two problems seen: > > >> ata2: protocol 5 task_state 4 >> ata2: protocol 5 task_state 4 (dev_stat 0x58) >> ata2: protocol 5 task_state 1 >> ata2: protocol 5 task_state 1 (dev_stat 0x58) >> ata2: protocol 5 task_state 1 >> ata2: protocol 5 task_state 1 >> ata2: protocol 5 task_state 1 >> ata2: protocol 5 task_state 1 >> ata2: protocol 5 task_state 1 >> ata2: protocol 5 task_state 1 (dev_stat 0x58) >> ata2: protocol 5 task_state 1 >> ata2: protocol 5 task_state 1 (dev_stat 0x58) >> ata2: protocol 5 task_state 2 >> ata2: protocol 5 task_state 2 >> ata2: protocol 5 task_state 2 (dev_stat 0x50) >> > > 1. The drive interrups when it is still BSY. > > >> ata2: protocol 5 task_state 4 >> ata2: protocol 5 task_state 4 (dev_stat 0x58) >> ata2: protocol 5 task_state 1 >> ata2: protocol 5 task_state 1 (dev_stat 0x58) >> ata2: protocol 5 task_state 1 >> ata2: protocol 5 task_state 1 (dev_stat 0x58) >> ata2: protocol 5 task_state 1 >> ata2: protocol 5 task_state 1 (dev_stat 0x58) >> ata2: protocol 5 task_state 1 >> ata2: protocol 5 task_state 1 (dev_stat 0x50) >> ata2: protocol 5 task_state 2 (dev_stat 0x50) >> ata2: protocol 5 task_state 4 >> ata2: protocol 5 task_state 4 (dev_stat 0x58) >> ata2: protocol 5 task_state 1 >> ata2: protocol 5 task_state 1 (dev_stat 0x58) >> ata2: protocol 5 task_state 1 >> ata2: protocol 5 task_state 1 (dev_stat 0x58) >> ata2: protocol 5 task_state 1 >> ata2: protocol 5 task_state 1 (dev_stat 0x58) >> ata2.00: 59392 bytes trailing data >> ata2: protocol 5 task_state 2 >> ata2: protocol 5 task_state 2 >> ata2: protocol 5 task_state 2 (dev_stat 0x58) >> ata2: protocol 5 task_state 3 (dev_stat 0x58) >> ata2.00: ata_eh_analyze_tf, AC_ERR_HSM, 0x58 >> ata2.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen >> ata2.00: cmd a0/00:00:00:00:20/00:00:00:00:00/a0 tag 0 cdb 0x28 data 79872 in >> res 58/00:02:00:00:f8/00:00:00:00:00/a0 Emask 0x2 (HSM violation) >> > > 2. The drive wants to transfer more data than expected. > Libata transfers some of the trailing data. But when the drive asks > to tranfer even more trailing data, libata forces it to stop by HSM violation. > > >> ata2: soft resetting port >> ata2: port is slow to respond, please be patient (Status 0xd8) >> ata2: port failed to respond (30 secs, Status 0xd8) >> ATA: abnormal status 0xD8 on port 0x177 >> ATA: abnormal status 0xD8 on port 0x177 >> ATA: abnormal status 0xD8 on port 0x177 >> ATA: abnormal status 0xD8 on port 0x177 >> ATA: abnormal status 0xD8 on port 0x177 >> ata2.00: qc timeout (cmd 0xa1) >> ata2.00: failed to IDENTIFY (I/O error, err_mask=0x4) >> ata2.00: revalidation failed (errno=-5) >> ata2: failed to recover some devices, retrying in 5 secs >> > > 3. After the HSM violation, the drive stays BSY after the EH resets it. > It seems doesn't like the recovery actions and totally lost. > > Could you please try the attached patch (together with the previous debug patches). > This patch let the drive transfer all of the trailing data it wants to. > > The data transferred might be junk. So, please also verify the files copied. > (e.g. If the file copied from the CD is *.gz file, please do gunzip -tv foo.gz.) > > -- > albert > > --- linux-2.6.20.3/drivers/ata/libata-core.c 2007-03-14 02:27:08.000000000 +0800 > +++ linux-2.6.20.3-mod/drivers/ata/libata-core.c 2007-03-17 12:16:36.000000000 +0800 > @@ -4143,8 +4143,8 @@ static void __atapi_pio_bytes(struct ata > unsigned char *buf; > unsigned int offset, count; > > - if (qc->curbytes + bytes >= qc->nbytes) > - ap->hsm_task_state = HSM_ST_LAST; > + //if (qc->curbytes + bytes >= qc->nbytes) > + // ap->hsm_task_state = HSM_ST_LAST; > > next_sg: > if (unlikely(qc->cursg >= qc->n_elem)) { > @@ -4166,7 +4166,7 @@ next_sg: > for (i = 0; i < words; i++) > ap->ops->data_xfer(qc->dev, (unsigned char*)pad_buf, 2, do_write); > > - ap->hsm_task_state = HSM_ST_LAST; > + //ap->hsm_task_state = HSM_ST_LAST; > return; > } > > Hi, Attached is the dmesg after applying the patch to transfer all trailing data. There are three lines that appear repeatedly for about 1 minute while the CD keeps spinning: ata2.00: 63488 bytes trailing data ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) The recursive copy eventually fails after copying a few files. Thanks, Vlad --------------080602060105010407010708 Content-Type: text/plain; name="dmesg.txt" Content-Transfer-Encoding: 7bit Content-Disposition: inline; filename="dmesg.txt" Linux version 2.6.20.2 (root@debian) (gcc version 4.1.2 20061115 (prerelease) (Debian 4.1.1-21)) #1 SMP PREEMPT Mon Mar 12 05:24:53 CDT 2007 BIOS-provided physical RAM map: sanitize start sanitize end copy_e820_map() start: 0000000000000000 size: 000000000009f800 end: 000000000009f800 type: 1 copy_e820_map() type is E820_RAM copy_e820_map() start: 000000000009f800 size: 0000000000000800 end: 00000000000a0000 type: 2 copy_e820_map() start: 00000000000f0400 size: 000000000000fc00 end: 0000000000100000 type: 2 copy_e820_map() start: 0000000000100000 size: 0000000005f00000 end: 0000000006000000 type: 1 copy_e820_map() type is E820_RAM copy_e820_map() start: 00000000ffff0400 size: 000000000000fc00 end: 0000000100000000 type: 2 BIOS-e820: 0000000000000000 - 000000000009f800 (usable) BIOS-e820: 000000000009f800 - 00000000000a0000 (reserved) BIOS-e820: 00000000000f0400 - 0000000000100000 (reserved) BIOS-e820: 0000000000100000 - 0000000006000000 (usable) BIOS-e820: 00000000ffff0400 - 0000000100000000 (reserved) 0MB HIGHMEM available. 96MB LOWMEM available. Entering add_active_range(0, 0, 24576) 0 entries of 256 used Zone PFN ranges: DMA 0 -> 4096 Normal 4096 -> 24576 HighMem 24576 -> 24576 early_node_map[1] active PFN ranges 0: 0 -> 24576 On node 0 totalpages: 24576 DMA zone: 32 pages used for memmap DMA zone: 0 pages reserved DMA zone: 4064 pages, LIFO batch:0 Normal zone: 160 pages used for memmap Normal zone: 20320 pages, LIFO batch:3 HighMem zone: 0 pages used for memmap DMI not present or invalid. Allocating PCI resources starting at 10000000 (gap: 06000000:f9ff0400) Detected 232.124 MHz processor. Built 1 zonelists. Total pages: 24384 Kernel command line: root=/dev/sda2 ro Local APIC disabled by BIOS -- you can enable it with "lapic" mapped APIC to ffffd000 (010c9000) Enabling fast FPU save and restore... done. Initializing CPU#0 PID hash table entries: 512 (order: 9, 2048 bytes) Console: colour VGA+ 80x25 Dentry cache hash table entries: 16384 (order: 4, 65536 bytes) Inode-cache hash table entries: 8192 (order: 3, 32768 bytes) Memory: 90960k/98304k available (1471k kernel code, 6888k reserved, 540k data, 256k init, 0k highmem) virtual kernel memory layout: fixmap : 0xfff54000 - 0xfffff000 ( 684 kB) pkmap : 0xff800000 - 0xffc00000 (4096 kB) vmalloc : 0xc6800000 - 0xff7fe000 ( 911 MB) lowmem : 0xc0000000 - 0xc6000000 ( 96 MB) .init : 0xc02fd000 - 0xc033d000 ( 256 kB) .data : 0xc026fd35 - 0xc02f6fb4 ( 540 kB) .text : 0xc0100000 - 0xc026fd35 (1471 kB) Checking if this processor honours the WP bit even in supervisor mode... Ok. Calibrating delay using timer specific routine.. 465.03 BogoMIPS (lpj=930069) Security Framework v1.0.0 initialized SELinux: Disabled at boot. Capability LSM initialized Mount-cache hash table entries: 512 CPU: After generic identify, caps: 0183f9ff 00000000 00000000 00000000 00000000 00000000 00000000 CPU: L1 I cache: 16K, L1 D cache: 16K CPU: L2 cache: 512K CPU: After all inits, caps: 0183f9ff 00000000 00000000 00000040 00000000 00000000 00000000 Intel machine check architecture supported. Intel machine check reporting enabled on CPU#0. Compat vDSO mapped to ffffe000. Checking 'hlt' instruction... OK. SMP alternatives: switching to UP code Freeing SMP alternatives: 10k freed CPU0: Intel Pentium II (Deschutes) stepping 00 SMP motherboard not detected. Local APIC not detected. Using dummy APIC emulation. Brought up 1 CPUs PM: Adding info for No Bus:platform Time: 21:44:12 Date: 02/13/107 NET: Registered protocol family 16 PM: Adding info for No Bus:vtcon0 EISA bus registered PCI: PCI BIOS revision 2.10 entry at 0xfda13, last bus=0 PCI: Using configuration type 1 Setting up standard PCI resources Linux Plug and Play Support v0.97 (c) Adam Belay PnPBIOS: Scanning system for PnP BIOS support... PnPBIOS: Found PnP BIOS installation structure at 0xc00f68e0 PnPBIOS: PnP BIOS version 1.0, entry 0xf0000:0xb27c, dseg 0x400 PM: Adding info for No Bus:pnp0 PM: Adding info for pnp:00:00 PM: Adding info for pnp:00:01 PM: Adding info for pnp:00:02 PM: Adding info for pnp:00:03 PM: Adding info for pnp:00:04 PM: Adding info for pnp:00:05 PM: Adding info for pnp:00:06 PM: Adding info for pnp:00:07 PM: Adding info for pnp:00:08 PM: Adding info for pnp:00:09 PM: Adding info for pnp:00:0a PM: Adding info for pnp:00:0b PM: Adding info for pnp:00:10 PnPBIOS: Unknown tag '0x82', length '6'. PM: Adding info for pnp:00:11 PnPBIOS: Unknown tag '0x82', length '4'. PM: Adding info for pnp:00:15 PM: Adding info for pnp:00:16 PM: Adding info for pnp:00:18 PM: Adding info for pnp:00:1a PnPBIOS: 18 nodes reported by PnP BIOS; 18 recorded by driver PCI: Probing PCI hardware PCI: Probing PCI hardware (bus 00) PM: Adding info for No Bus:pci0000:00 Boot video device is 0000:00:02.0 PCI quirk: region 8000-803f claimed by PIIX4 ACPI PCI quirk: region 2180-218f claimed by PIIX4 SMB PIIX4 devres B PIO at 0530-0537 PIIX4 devres C PIO at 0388-038b PIIX4 devres G PIO at 0f00-0f07 PM: Adding info for pci:0000:00:00.0 PM: Adding info for pci:0000:00:02.0 PM: Adding info for pci:0000:00:04.0 PM: Adding info for pci:0000:00:04.1 PM: Adding info for pci:0000:00:07.0 PM: Adding info for pci:0000:00:07.1 PM: Adding info for pci:0000:00:07.2 PM: Adding info for pci:0000:00:07.3 PCI: Using IRQ router PIIX/ICH [8086/7110] at 0000:00:07.0 NET: Registered protocol family 8 NET: Registered protocol family 20 pnp: the driver 'system' has been registered pnp: match found with the PnP device '00:04' and the driver 'system' pnp: 00:04: ioport range 0x401-0x407 has been reserved pnp: match found with the PnP device '00:05' and the driver 'system' pnp: match found with the PnP device '00:0a' and the driver 'system' pnp: 00:0a: ioport range 0x4d0-0x4d1 has been reserved pnp: 00:0a: ioport range 0x8000-0x803f has been reserved pnp: 00:0a: ioport range 0x2180-0x218f has been reserved PM: Adding info for No Bus:mem PM: Adding info for No Bus:kmem PM: Adding info for No Bus:null PM: Adding info for No Bus:port PM: Adding info for No Bus:zero PM: Adding info for No Bus:full PM: Adding info for No Bus:random PM: Adding info for No Bus:urandom PM: Adding info for No Bus:kmsg PCI: Ignore bogus resource 6 [0:0] of 0000:00:02.0 PCI: Bus 1, cardbus bridge: 0000:00:04.0 IO window: 00001000-000010ff IO window: 00001400-000014ff PREFETCH window: 10000000-13ffffff MEM window: 14000000-17ffffff PCI: Bus 5, cardbus bridge: 0000:00:04.1 IO window: 00001800-000018ff IO window: 00001c00-00001cff PREFETCH window: 18000000-1bffffff MEM window: 1c000000-1fffffff PCI: Enabling device 0000:00:04.0 (0000 -> 0003) PCI: setting IRQ 11 as level-triggered PCI: Assigned IRQ 11 for device 0000:00:04.0 PCI: Enabling device 0000:00:04.1 (0000 -> 0003) PCI: Assigned IRQ 11 for device 0000:00:04.1 NET: Registered protocol family 2 IP route cache hash table entries: 1024 (order: 0, 4096 bytes) TCP established hash table entries: 4096 (order: 3, 49152 bytes) TCP bind hash table entries: 2048 (order: 2, 24576 bytes) TCP: Hash tables configured (established 4096 bind 2048) TCP reno registered checking if image is initramfs... it is Freeing initrd memory: 3441k freed PM: Adding info for platform:pcspkr audit: initializing netlink socket (disabled) audit(1173822255.424:1): initialized VFS: Disk quotas dquot_6.5.1 Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) io scheduler noop registered io scheduler anticipatory registered io scheduler deadline registered io scheduler cfq registered (default) Limiting direct PCI/PCI transfers. PM: Adding info for platform:vesafb.0 PM: Adding info for No Bus:pnp1 isapnp: Scanning for PnP cards... isapnp: No Plug & Play device found PM: Adding info for No Bus:tty PM: Adding info for No Bus:console PM: Adding info for No Bus:ptmx PM: Adding info for No Bus:tty0 PM: Adding info for No Bus:vcs PM: Adding info for No Bus:vcsa PM: Adding info for No Bus:tty1 PM: Adding info for No Bus:tty2 PM: Adding info for No Bus:tty3 PM: Adding info for No Bus:tty4 PM: Adding info for No Bus:tty5 PM: Adding info for No Bus:tty6 PM: Adding info for No Bus:tty7 PM: Adding info for No Bus:tty8 PM: Adding info for No Bus:tty9 PM: Adding info for No Bus:tty10 PM: Adding info for No Bus:tty11 PM: Adding info for No Bus:tty12 PM: Adding info for No Bus:tty13 PM: Adding info for No Bus:tty14 PM: Adding info for No Bus:tty15 PM: Adding info for No Bus:tty16 PM: Adding info for No Bus:tty17 PM: Adding info for No Bus:tty18 PM: Adding info for No Bus:tty19 PM: Adding info for No Bus:tty20 PM: Adding info for No Bus:tty21 PM: Adding info for No Bus:tty22 PM: Adding info for No Bus:tty23 PM: Adding info for No Bus:tty24 PM: Adding info for No Bus:tty25 PM: Adding info for No Bus:tty26 PM: Adding info for No Bus:tty27 PM: Adding info for No Bus:tty28 PM: Adding info for No Bus:tty29 PM: Adding info for No Bus:tty30 PM: Adding info for No Bus:tty31 PM: Adding info for No Bus:tty32 PM: Adding info for No Bus:tty33 PM: Adding info for No Bus:tty34 PM: Adding info for No Bus:tty35 PM: Adding info for No Bus:tty36 PM: Adding info for No Bus:tty37 PM: Adding info for No Bus:tty38 PM: Adding info for No Bus:tty39 PM: Adding info for No Bus:tty40 PM: Adding info for No Bus:tty41 PM: Adding info for No Bus:tty42 PM: Adding info for No Bus:tty43 PM: Adding info for No Bus:tty44 PM: Adding info for No Bus:tty45 PM: Adding info for No Bus:tty46 PM: Adding info for No Bus:tty47 PM: Adding info for No Bus:tty48 PM: Adding info for No Bus:tty49 PM: Adding info for No Bus:tty50 PM: Adding info for No Bus:tty51 PM: Adding info for No Bus:tty52 PM: Adding info for No Bus:tty53 PM: Adding info for No Bus:tty54 PM: Adding info for No Bus:tty55 PM: Adding info for No Bus:tty56 PM: Adding info for No Bus:tty57 PM: Adding info for No Bus:tty58 PM: Adding info for No Bus:tty59 PM: Adding info for No Bus:tty60 PM: Adding info for No Bus:tty61 PM: Adding info for No Bus:tty62 PM: Adding info for No Bus:tty63 PM: Adding info for No Bus:ptyp0 PM: Adding info for No Bus:ptyp1 PM: Adding info for No Bus:ptyp2 PM: Adding info for No Bus:ptyp3 PM: Adding info for No Bus:ptyp4 PM: Adding info for No Bus:ptyp5 PM: Adding info for No Bus:ptyp6 PM: Adding info for No Bus:ptyp7 PM: Adding info for No Bus:ptyp8 PM: Adding info for No Bus:ptyp9 PM: Adding info for No Bus:ptypa PM: Adding info for No Bus:ptypb PM: Adding info for No Bus:ptypc PM: Adding info for No Bus:ptypd PM: Adding info for No Bus:ptype PM: Adding info for No Bus:ptypf PM: Adding info for No Bus:ttyp0 PM: Adding info for No Bus:ttyp1 PM: Adding info for No Bus:ttyp2 PM: Adding info for No Bus:ttyp3 PM: Adding info for No Bus:ttyp4 PM: Adding info for No Bus:ttyp5 PM: Adding info for No Bus:ttyp6 PM: Adding info for No Bus:ttyp7 PM: Adding info for No Bus:ttyp8 PM: Adding info for No Bus:ttyp9 PM: Adding info for No Bus:ttypa PM: Adding info for No Bus:ttypb PM: Adding info for No Bus:ttypc PM: Adding info for No Bus:ttypd PM: Adding info for No Bus:ttype PM: Adding info for No Bus:ttypf Serial: 8250/16550 driver $Revision: 1.90 $ 4 ports, IRQ sharing enabled PM: Adding info for platform:serial8250 serial8250: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A PM: Adding info for No Bus:ttyS0 serial8250: ttyS1 at I/O 0x2f8 (irq = 3) is a 16550A PM: Adding info for No Bus:ttyS1 serial8250: ttyS2 at I/O 0x3e8 (irq = 4) is a 16550A PM: Adding info for No Bus:ttyS2 PM: Adding info for No Bus:ttyS3 pnp: the driver 'serial' has been registered pnp: match found with the PnP device '00:0b' and the driver 'serial' PM: Removing info for No Bus:ttyS0 00:0b: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A PM: Adding info for No Bus:ttyS0 PM: Adding info for No Bus:isa RAMDISK driver initialized: 16 RAM disks of 8192K size 1024 blocksize pnp: the driver 'i8042 kbd' has been registered pnp: match found with the PnP device '00:06' and the driver 'i8042 kbd' pnp: the driver 'i8042 aux' has been registered pnp: match found with the PnP device '00:16' and the driver 'i8042 aux' PNP: PS/2 Controller [PNP0303,PNP0f13] at 0x60,0x64 irq 1,12 PM: Adding info for platform:i8042 serio: i8042 KBD port at 0x60,0x64 irq 1 serio: i8042 AUX port at 0x60,0x64 irq 12 PM: Adding info for serio:serio0 PM: Adding info for serio:serio1 PM: Adding info for No Bus:psaux mice: PS/2 mouse device common for all mice PM: Adding info for platform:eisa.0 EISA: Probing bus 0 at eisa.0 PM: Removing info for platform:eisa.0 TCP bic registered NET: Registered protocol family 1 NET: Registered protocol family 17 Using IPI Shortcut mode Magic number: 15:868:752 hash matches device ttyS3 Time: tsc clocksource has been installed. Freeing unused kernel memory: 256k freed PM: Adding info for No Bus:vcs1 PM: Adding info for No Bus:vcsa1 input: AT Translated Set 2 keyboard as /class/input/input0 SCSI subsystem initialized libata version 2.00 loaded. ata_piix 0000:00:07.1: version 2.00ac7 ata1: PATA max UDMA/33 cmd 0x1F0 ctl 0x3F6 bmdma 0xFCF0 irq 14 ata2: PATA max UDMA/33 cmd 0x170 ctl 0x376 bmdma 0xFCF8 irq 15 scsi0 : ata_piix PM: Adding info for No Bus:host0 usbcore: registered new interface driver usbfs usbcore: registered new interface driver hub usbcore: registered new device driver usb USB Universal Host Controller Interface driver v3.0 ata1.00: ATA-4, max UDMA/33, 12685680 sectors: LBA ata1.00: ata1: dev 0 multi count 16 ata1.00: configured for UDMA/33 scsi1 : ata_piix PM: Adding info for No Bus:host1 ata2.00: ATAPI, max MWDMA2, CDB intr ata2.00: configured for MWDMA2 PM: Adding info for No Bus:target0:0:0 scsi 0:0:0:0: Direct-Access ATA IBM-DADA-26480 AD6O PQ: 0 ANSI: 5 PM: Adding info for scsi:0:0:0:0 PM: Adding info for No Bus:target1:0:0 ata2.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen ata2.00: (BMDMA stat 0x25) ata2.00: cmd a0/01:00:00:00:00/00:00:00:00:00/a0 tag 0 cdb 0x12 data 36 in res 58/00:02:00:24:00/00:00:00:00:00/a0 Emask 0x2 (HSM violation) ata2: soft resetting port ata2: port is slow to respond, please be patient (Status 0xd8) ata2: port failed to respond (30 secs, Status 0xd8) ATA: abnormal status 0xD8 on port 0x177 ATA: abnormal status 0xD8 on port 0x177 ATA: abnormal status 0xD8 on port 0x177 ATA: abnormal status 0xD8 on port 0x177 ATA: abnormal status 0xD8 on port 0x177 ata2.00: qc timeout (cmd 0xa1) ata2.00: failed to IDENTIFY (I/O error, err_mask=0x4) ata2.00: revalidation failed (errno=-5) ata2: failed to recover some devices, retrying in 5 secs ata2: port is slow to respond, please be patient (Status 0xd8) ata2: port failed to respond (30 secs, Status 0xd8) ata2: soft resetting port ata2: port is slow to respond, please be patient (Status 0xd8) ata2: port failed to respond (30 secs, Status 0xd8) SCSI device sda: 12685680 512-byte hdwr sectors (6495 MB) sda: Write Protect is off sda: Mode Sense: 00 3a 00 00 SCSI device sda: write cache: enabled, read cache: enabled, doesn't support DPO or FUA SCSI device sda: 12685680 512-byte hdwr sectors (6495 MB) sda: Write Protect is off sda: Mode Sense: 00 3a 00 00 SCSI device sda: write cache: enabled, read cache: enabled, doesn't support DPO or FUA sda: sda1 sda2 sda3 sd 0:0:0:0: Attached scsi disk sda kjournald starting. Commit interval 5 seconds EXT3-fs: mounted filesystem with ordered data mode. PM: Removing info for No Bus:vcs1 PM: Removing info for No Bus:vcsa1 PM: Adding info for No Bus:vcs1 PM: Adding info for No Bus:vcsa1 PM: Removing info for No Bus:vcs1 PM: Removing info for No Bus:vcsa1 PM: Adding info for No Bus:vcs1 PM: Adding info for No Bus:vcsa1 PM: Removing info for No Bus:vcs1 PM: Removing info for No Bus:vcsa1 PM: Adding info for No Bus:vcs1 PM: Adding info for No Bus:vcsa1 PM: Removing info for No Bus:vcs1 PM: Removing info for No Bus:vcsa1 PM: Adding info for No Bus:vcs1 PM: Adding info for No Bus:vcsa1 PM: Removing info for No Bus:vcs1 PM: Removing info for No Bus:vcsa1 PM: Adding info for No Bus:vcs1 PM: Adding info for No Bus:vcsa1 ATA: abnormal status 0xD8 on port 0x177 ATA: abnormal status 0xD8 on port 0x177 ATA: abnormal status 0xD8 on port 0x177 ATA: abnormal status 0xD8 on port 0x177 ATA: abnormal status 0xD8 on port 0x177 input: PC Speaker as /class/input/input1 Synaptics Touchpad, model: 1, fw: 4.1, id: 0x148a1, caps: 0x0/0x0 input: SynPS/2 Synaptics TouchPad as /class/input/input2 PM: Adding info for No Bus:rtc Real Time Clock Driver v1.12ac pnp: the driver 'parport_pc' has been registered pnp: match found with the PnP device '00:10' and the driver 'parport_pc' parport: PnPBIOS parport detected. parport0: PC-style at 0x378 (0x778), irq 7, dma 0 [PCSPP,TRISTATE,COMPAT,ECP,DMA] Floppy drive(s): fd0 is 1.44M FDC 0 is a post-1991 82077 PM: Adding info for platform:floppy.0 PM: Adding info for No Bus:timer PM: Adding info for No Bus:seq PM: Adding info for No Bus:sequencer PM: Adding info for No Bus:sequencer2 pnp: the driver 'cs4232-pnpbios' has been registered pnp: match found with the PnP device '00:11' and the driver 'cs4232-pnpbios' CS4232 WSS PnP manual resources are invalid, using auto config CS4232 WSS PnP configure failed for WSS (out of resources?) PnP BIOS detection failed for CS4232 pnp: Device 00:11 disabled. cs4232-pnpbios: probe of 00:11 failed with error -16 pnp: the driver 'cs4232_isapnp' has been registered CS4232 soundcard not found or device busy pnp: the driver 'cs4232_isapnp' has been unregistered pnp: the driver 'cs4232-pnpbios' has been unregistered ata2.00: qc timeout (cmd 0xa1) ata2.00: failed to IDENTIFY (I/O error, err_mask=0x4) ata2.00: revalidation failed (errno=-5) ata2: failed to recover some devices, retrying in 5 secs ata2: port is slow to respond, please be patient (Status 0xd8) ata2: port failed to respond (30 secs, Status 0xd8) ata2: soft resetting port ata2: port is slow to respond, please be patient (Status 0xd8) ata2: port failed to respond (30 secs, Status 0xd8) ATA: abnormal status 0xD8 on port 0x177 ATA: abnormal status 0xD8 on port 0x177 ATA: abnormal status 0xD8 on port 0x177 ATA: abnormal status 0xD8 on port 0x177 ATA: abnormal status 0xD8 on port 0x177 ata2.00: qc timeout (cmd 0xa1) ata2.00: failed to IDENTIFY (I/O error, err_mask=0x4) ata2.00: revalidation failed (errno=-5) ata2.00: disabled ata2: EH complete PM: Removing info for No Bus:target1:0:0 PCI: Enabling device 0000:00:07.2 (0000 -> 0001) PCI: setting IRQ 10 as level-triggered PCI: Assigned IRQ 10 for device 0000:00:07.2 uhci_hcd 0000:00:07.2: UHCI Host Controller uhci_hcd 0000:00:07.2: new USB bus registered, assigned bus number 1 uhci_hcd 0000:00:07.2: irq 10, io base 0x0000fcc0 PM: Adding info for usb:usb1 PM: Adding info for No Bus:usbdev1.1_ep00 usb usb1: configuration #1 chosen from 1 choice PM: Adding info for usb:1-0:1.0 hub 1-0:1.0: USB hub found hub 1-0:1.0: 2 ports detected PM: Adding info for No Bus:usbdev1.1_ep81 Yenta: CardBus bridge found at 0000:00:04.0 [1028:007e] Yenta: ISA IRQ mask 0x0238, PCI irq 11 Socket status: 30000059 Yenta: CardBus bridge found at 0000:00:04.1 [1028:007e] Yenta: ISA IRQ mask 0x0238, PCI irq 11 Socket status: 30000069 piix4_smbus 0000:00:07.3: Found 0000:00:07.3 device PM: Adding info for No Bus:i2c-0 pccard: PCMCIA card inserted into slot 0 pccard: CardBus card inserted into slot 1 PM: Adding info for pci:0000:05:00.0 PCI: Enabling device 0000:05:00.0 (0000 -> 0001) 3c59x: Donald Becker and others. www.scyld.com/network/vortex.html 0000:05:00.0: 3Com PCI 3c575 Boomerang CardBus at 00011800. PCI: Setting latency timer of device 0000:05:00.0 to 64 cs: IO port probe 0x100-0x3af: clean. cs: IO port probe 0x3e0-0x4ff: clean. cs: IO port probe 0x820-0x8ff: clean. cs: IO port probe 0xc00-0xcf7: clean. cs: IO port probe 0xa00-0xaff: clean. cs: memory probe 0xa0000000-0xa0ffffff: clean. pcmcia: registering new device pcmcia0.0 PM: Adding info for pcmcia:0.0 cs: IO port probe 0x100-0x3af: clean. cs: IO port probe 0x3e0-0x4ff: clean. cs: IO port probe 0x820-0x8ff: clean. cs: IO port probe 0xc00-0xcf7: clean. cs: IO port probe 0xa00-0xaff: clean. pcmcia: CIS filename is too long PM: Removing info for No Bus:ttyS3 0.0: ttyS3 at I/O 0x2e8 (irq = 3) is a 16550A PM: Adding info for No Bus:ttyS3 PM: Adding info for No Bus:vcs2 PM: Adding info for No Bus:vcsa2 PM: Removing info for No Bus:vcs2 PM: Removing info for No Bus:vcsa2 PM: Adding info for No Bus:vcs3 PM: Adding info for No Bus:vcsa3 PM: Removing info for No Bus:vcs3 PM: Removing info for No Bus:vcsa3 PM: Adding info for No Bus:vcs4 PM: Adding info for No Bus:vcsa4 PM: Removing info for No Bus:vcs4 PM: Removing info for No Bus:vcsa4 PM: Adding info for No Bus:vcs5 PM: Adding info for No Bus:vcsa5 PM: Removing info for No Bus:vcs5 PM: Removing info for No Bus:vcsa5 PM: Adding info for No Bus:vcs6 PM: Adding info for No Bus:vcsa6 PM: Removing info for No Bus:vcs6 PM: Removing info for No Bus:vcsa6 EXT3 FS on sda2, internal journal loop: loaded (max 8 devices) eth0: setting half-duplex. PM: Adding info for No Bus:vcs2 PM: Adding info for No Bus:vcsa2 PM: Removing info for No Bus:vcs2 PM: Removing info for No Bus:vcsa2 PM: Adding info for No Bus:vcs3 PM: Adding info for No Bus:vcsa3 PM: Removing info for No Bus:vcs3 PM: Removing info for No Bus:vcsa3 PM: Adding info for No Bus:vcs4 PM: Adding info for No Bus:vcsa4 PM: Removing info for No Bus:vcs4 PM: Removing info for No Bus:vcsa4 PM: Adding info for No Bus:vcs5 PM: Adding info for No Bus:vcsa5 PM: Removing info for No Bus:vcs5 PM: Removing info for No Bus:vcsa5 PM: Adding info for No Bus:vcs2 PM: Adding info for No Bus:vcsa2 PM: Removing info for No Bus:vcs2 PM: Removing info for No Bus:vcsa2 PM: Adding info for No Bus:vcs3 PM: Adding info for No Bus:vcsa3 PM: Removing info for No Bus:vcs3 PM: Removing info for No Bus:vcsa3 PM: Adding info for No Bus:vcs4 PM: Adding info for No Bus:vcsa4 PM: Removing info for No Bus:vcs4 PM: Removing info for No Bus:vcsa4 PM: Adding info for No Bus:vcs5 PM: Adding info for No Bus:vcsa5 PM: Removing info for No Bus:vcs5 PM: Removing info for No Bus:vcsa5 PM: Adding info for No Bus:vcs6 PM: Adding info for No Bus:vcsa6 PM: Removing info for No Bus:vcs6 PM: Removing info for No Bus:vcsa6 PM: Removing info for No Bus:vcs1 PM: Removing info for No Bus:vcsa1 PM: Adding info for No Bus:vcs1 PM: Adding info for No Bus:vcsa1 PM: Removing info for No Bus:vcs1 PM: Removing info for No Bus:vcsa1 PM: Adding info for No Bus:vcs1 PM: Adding info for No Bus:vcsa1 PM: Removing info for No Bus:vcs1 PM: Removing info for No Bus:vcsa1 PM: Adding info for No Bus:vcs1 PM: Adding info for No Bus:vcsa1 PM: Removing info for No Bus:vcs1 PM: Removing info for No Bus:vcsa1 PM: Adding info for No Bus:vcs1 PM: Adding info for No Bus:vcsa1 NET: Registered protocol family 10 lo: Disabled Privacy Extensions PM: Removing info for No Bus:vcs1 PM: Removing info for No Bus:vcsa1 PM: Adding info for No Bus:vcs1 PM: Adding info for No Bus:vcsa1 PM: Adding info for No Bus:vcs2 PM: Adding info for No Bus:vcsa2 PM: Adding info for No Bus:vcs5 PM: Adding info for No Bus:vcsa5 PM: Adding info for No Bus:vcs6 PM: Adding info for No Bus:vcsa6 PM: Adding info for No Bus:vcs3 PM: Adding info for No Bus:vcsa3 PM: Adding info for No Bus:vcs4 PM: Adding info for No Bus:vcsa4 eth0: no IPv6 routers present ##### Inserted CD and ran "mount /dev/sr0 /root/cdrom" ##### ata2: protocol 6 task_state 4 ata2: protocol 6 task_state 4 (dev_stat 0x58) ata2: protocol 6 task_state 2 ata2: protocol 6 task_state 2 (dev_stat 0x51) ata2: protocol 6 task_state 3 (dev_stat 0x51) ata2: protocol 5 task_state 4 ata2: protocol 5 task_state 4 (dev_stat 0x58) ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x50) ata2: protocol 5 task_state 2 (dev_stat 0x50) ata2: protocol 6 task_state 4 ata2: protocol 6 task_state 4 (dev_stat 0x58) ata2: protocol 6 task_state 2 ata2: protocol 6 task_state 2 (dev_stat 0x50) ata2: protocol 6 task_state 4 ata2: protocol 6 task_state 4 (dev_stat 0x58) ata2: protocol 6 task_state 2 ata2: protocol 6 task_state 2 (dev_stat 0x50) ata2: protocol 5 task_state 4 ata2: protocol 5 task_state 4 (dev_stat 0x58) ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x50) ata2: protocol 5 task_state 2 (dev_stat 0x50) ata2: protocol 5 task_state 4 ata2: protocol 5 task_state 4 (dev_stat 0x58) ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x50) ata2: protocol 5 task_state 2 (dev_stat 0x50) ata2: protocol 6 task_state 4 ata2: protocol 6 task_state 4 (dev_stat 0x58) ata2: protocol 6 task_state 2 ata2: protocol 6 task_state 2 (dev_stat 0x50) ata2: protocol 5 task_state 4 ata2: protocol 5 task_state 4 (dev_stat 0x58) ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x51) ata2: protocol 5 task_state 2 (dev_stat 0x51) ata2: protocol 5 task_state 3 (dev_stat 0x51) ata2: protocol 5 task_state 4 ata2: protocol 5 task_state 4 (dev_stat 0x58) ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x50) ata2: protocol 5 task_state 2 (dev_stat 0x50) ata2: protocol 6 task_state 4 ata2: protocol 6 task_state 4 (dev_stat 0x58) ata2: protocol 6 task_state 2 ata2: protocol 6 task_state 2 (dev_stat 0x50) ata2: protocol 6 task_state 4 ata2: protocol 6 task_state 4 (dev_stat 0x58) ata2: protocol 6 task_state 2 ata2: protocol 6 task_state 2 (dev_stat 0x50) ata2: protocol 5 task_state 4 ata2: protocol 5 task_state 4 (dev_stat 0x58) ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x50) ata2: protocol 5 task_state 2 (dev_stat 0x50) ata2: protocol 5 task_state 4 ata2: protocol 5 task_state 4 (dev_stat 0x58) ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x50) ata2: protocol 5 task_state 2 (dev_stat 0x50) ata2: protocol 5 task_state 4 ata2: protocol 5 task_state 4 (dev_stat 0x58) ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x50) ata2: protocol 5 task_state 2 (dev_stat 0x50) ata2: protocol 5 task_state 4 ata2: protocol 5 task_state 4 (dev_stat 0x58) ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x50) ata2: protocol 5 task_state 2 (dev_stat 0x50) ata2: protocol 5 task_state 4 ata2: protocol 5 task_state 4 (dev_stat 0x58) ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x50) ata2: protocol 5 task_state 2 (dev_stat 0x50) ata2: protocol 6 task_state 4 ata2: protocol 6 task_state 4 (dev_stat 0x58) ata2: protocol 6 task_state 2 ata2: protocol 6 task_state 2 (dev_stat 0x50) ata2: protocol 6 task_state 4 ata2: protocol 6 task_state 4 (dev_stat 0x58) ata2: protocol 6 task_state 2 ata2: protocol 6 task_state 2 (dev_stat 0x50) ata2: protocol 6 task_state 4 ata2: protocol 6 task_state 4 (dev_stat 0x58) ata2: protocol 6 task_state 2 ata2: protocol 6 task_state 2 (dev_stat 0x50) ata2: protocol 5 task_state 4 ata2: protocol 5 task_state 4 (dev_stat 0x58) ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x50) ata2: protocol 5 task_state 2 (dev_stat 0x50) ata2: protocol 5 task_state 4 ata2: protocol 5 task_state 4 (dev_stat 0x58) ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x50) ata2: protocol 5 task_state 2 (dev_stat 0x50) ata2: protocol 6 task_state 4 ata2: protocol 6 task_state 4 (dev_stat 0x58) ata2: protocol 6 task_state 2 ata2: protocol 6 task_state 2 (dev_stat 0x50) ata2: protocol 5 task_state 4 ata2: protocol 5 task_state 4 (dev_stat 0x58) ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x51) ata2: protocol 5 task_state 2 (dev_stat 0x51) ata2: protocol 5 task_state 3 (dev_stat 0x51) ata2: protocol 5 task_state 4 ata2: protocol 5 task_state 4 (dev_stat 0x58) ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x50) ata2: protocol 5 task_state 2 (dev_stat 0x50) ata2: protocol 6 task_state 4 ata2: protocol 6 task_state 4 (dev_stat 0x58) ata2: protocol 6 task_state 2 ata2: protocol 6 task_state 2 (dev_stat 0x50) ata2: protocol 5 task_state 4 ata2: protocol 5 task_state 4 (dev_stat 0x58) ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x51) ata2: protocol 5 task_state 2 (dev_stat 0x51) ata2: protocol 5 task_state 3 (dev_stat 0x51) ata2: protocol 5 task_state 4 ata2: protocol 5 task_state 4 (dev_stat 0x58) ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x50) ata2: protocol 5 task_state 2 (dev_stat 0x50) ata2: protocol 6 task_state 4 ata2: protocol 6 task_state 4 (dev_stat 0x58) ata2: protocol 6 task_state 2 ata2: protocol 6 task_state 2 (dev_stat 0x50) ata2: protocol 5 task_state 4 ata2: protocol 5 task_state 4 (dev_stat 0x58) ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x51) ata2: protocol 5 task_state 2 (dev_stat 0x51) ata2: protocol 5 task_state 3 (dev_stat 0x51) ata2: protocol 5 task_state 4 ata2: protocol 5 task_state 4 (dev_stat 0x58) ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x50) ata2: protocol 5 task_state 2 (dev_stat 0x50) ata2: protocol 6 task_state 4 ata2: protocol 6 task_state 4 (dev_stat 0x58) ata2: protocol 6 task_state 2 ata2: protocol 6 task_state 2 (dev_stat 0x50) ata2: protocol 6 task_state 4 ata2: protocol 6 task_state 4 (dev_stat 0x58) ata2: protocol 6 task_state 2 ata2: protocol 6 task_state 2 (dev_stat 0x50) ata2: protocol 6 task_state 4 ata2: protocol 6 task_state 4 (dev_stat 0x58) ata2: protocol 6 task_state 2 ata2: protocol 6 task_state 2 (dev_stat 0x50) ata2: protocol 5 task_state 4 ata2: protocol 5 task_state 4 (dev_stat 0x58) ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x50) ata2: protocol 5 task_state 2 (dev_stat 0x50) ata2: protocol 5 task_state 4 ata2: protocol 5 task_state 4 (dev_stat 0x58) ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x50) ata2: protocol 5 task_state 2 (dev_stat 0x50) ata2: protocol 6 task_state 4 ata2: protocol 6 task_state 4 (dev_stat 0x58) ata2: protocol 6 task_state 2 ata2: protocol 6 task_state 2 (dev_stat 0x50) ata2: protocol 5 task_state 4 ata2: protocol 5 task_state 4 (dev_stat 0x58) ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x51) ata2: protocol 5 task_state 2 (dev_stat 0x51) ata2: protocol 5 task_state 3 (dev_stat 0x51) ata2: protocol 5 task_state 4 ata2: protocol 5 task_state 4 (dev_stat 0x58) ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x50) ata2: protocol 5 task_state 2 (dev_stat 0x50) ata2: protocol 6 task_state 4 ata2: protocol 6 task_state 4 (dev_stat 0x58) ata2: protocol 6 task_state 2 ata2: protocol 6 task_state 2 (dev_stat 0x50) ata2: protocol 6 task_state 4 ata2: protocol 6 task_state 4 (dev_stat 0x58) ata2: protocol 6 task_state 2 ata2: protocol 6 task_state 2 (dev_stat 0x50) ata2: protocol 5 task_state 4 ata2: protocol 5 task_state 4 (dev_stat 0x58) ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x50) ata2: protocol 5 task_state 2 (dev_stat 0x50) ata2: protocol 5 task_state 4 ata2: protocol 5 task_state 4 (dev_stat 0x58) ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x50) ata2: protocol 5 task_state 2 (dev_stat 0x50) ISO 9660 Extensions: Microsoft Joliet Level 3 ata2: protocol 5 task_state 4 ata2: protocol 5 task_state 4 (dev_stat 0x58) ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x50) ata2: protocol 5 task_state 2 (dev_stat 0x50) ISOFS: changing to secondary root ata2: protocol 5 task_state 4 ata2: protocol 5 task_state 4 (dev_stat 0x58) ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x50) ata2: protocol 5 task_state 2 (dev_stat 0x50) ##### Ran "cp -r /root/cdrom /root/cdrom_copy" ##### ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2.00: 63488 bytes trailing data ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2.00: 63488 bytes trailing data ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2.00: 63488 bytes trailing data ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2.00: 63488 bytes trailing data ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2.00: 63488 bytes trailing data ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2.00: 63488 bytes trailing data ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2.00: 63488 bytes trailing data ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2.00: 63488 bytes trailing data ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2.00: 63488 bytes trailing data ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2.00: 63488 bytes trailing data ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2.00: 63488 bytes trailing data ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2.00: 63488 bytes trailing data ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2.00: 63488 bytes trailing data ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2.00: 63488 bytes trailing data ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2.00: 63488 bytes trailing data ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2.00: 63488 bytes trailing data ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2.00: 63488 bytes trailing data ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2.00: 63488 bytes trailing data ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2.00: 63488 bytes trailing data ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2.00: 63488 bytes trailing data ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2.00: 63488 bytes trailing data ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2.00: 63488 bytes trailing data ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2.00: 63488 bytes trailing data ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2.00: 63488 bytes trailing data ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2.00: 63488 bytes trailing data ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2.00: 63488 bytes trailing data ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2.00: 63488 bytes trailing data ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2.00: 63488 bytes trailing data ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2.00: 63488 bytes trailing data ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2.00: 63488 bytes trailing data ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2.00: 63488 bytes trailing data ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2.00: 63488 bytes trailing data ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2.00: 63488 bytes trailing data ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2.00: 63488 bytes trailing data ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2.00: 63488 bytes trailing data ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2.00: 63488 bytes trailing data ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2.00: 63488 bytes trailing data ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2.00: 63488 bytes trailing data ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2.00: 63488 bytes trailing data ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2.00: 63488 bytes trailing data ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2.00: 63488 bytes trailing data ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2.00: 63488 bytes trailing data ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2.00: 63488 bytes trailing data ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2.00: 63488 bytes trailing data ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2.00: 63488 bytes trailing data ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2.00: 63488 bytes trailing data ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2.00: 63488 bytes trailing data ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2.00: 63488 bytes trailing data ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2.00: 63488 bytes trailing data ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2.00: 63488 bytes trailing data ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2.00: 63488 bytes trailing data ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2.00: 63488 bytes trailing data ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2.00: 63488 bytes trailing data ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2.00: 63488 bytes trailing data ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2.00: 63488 bytes trailing data ata2: protocol 5 task_state 1 ata2: protocol 5 task_state 1 (dev_stat 0x58) ata2.00: 63488 bytes trailing data ata2.00: ata_eh_analyze_tf, AC_ERR_HSM, 0x58 ata2.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen ata2.00: cmd a0/00:00:00:00:20/00:00:00:00:00/a0 tag 0 cdb 0x28 data 124928 in res 58/00:02:00:00:f8/00:00:00:00:00/a0 Emask 0x6 (timeout) ata2: soft resetting port ata2: port is slow to respond, please be patient (Status 0xd8) ata2: port failed to respond (30 secs, Status 0xd8) ATA: abnormal status 0xD8 on port 0x177 ATA: abnormal status 0xD8 on port 0x177 ATA: abnormal status 0xD8 on port 0x177 ATA: abnormal status 0xD8 on port 0x177 ATA: abnormal status 0xD8 on port 0x177 ata2.00: qc timeout (cmd 0xa1) ata2.00: failed to IDENTIFY (I/O error, err_mask=0x4) ata2.00: revalidation failed (errno=-5) ata2: failed to recover some devices, retrying in 5 secs ata2: port is slow to respond, please be patient (Status 0xd8) ata2: port failed to respond (30 secs, Status 0xd8) ata2: soft resetting port ata2: port is slow to respond, please be patient (Status 0xd8) ata2: port failed to respond (30 secs, Status 0xd8) ATA: abnormal status 0xD8 on port 0x177 ATA: abnormal status 0xD8 on port 0x177 ATA: abnormal status 0xD8 on port 0x177 ATA: abnormal status 0xD8 on port 0x177 ATA: abnormal status 0xD8 on port 0x177 ata2.00: qc timeout (cmd 0xa1) --------------080602060105010407010708--