Reading the files from a film dvd fails after some time and produces numerous errors in /var/log/messages. uname -a Linux localhost 2.6.14-gentoo-r5 #1 Wed Dec 28 14:47:24 GMT 2005 x86_64 AMD Athlon(tm) 64 Processor 3000+ AuthenticAMD GNU/Linux DVD drive is internal Benq 1640dw read-writer Command line (with any film dvd mounted at /mnt/dvd): cp -r /mnt/dvd/* . Result: Files are read for an hour or so to roughly 5GB of data. During this time the system is noticably slower with typing, for example, showing a lag of a couple of seconds. Over time errors start appearing in /var/log/messages. Finally the reading stops with cp: reading `/mnt/dvd/VIDEO_TS/VTS_03_2.VOB': Input/output error cp: reading `/mnt/dvd/VIDEO_TS/VTS_03_3.VOB': Input/output error cp: reading `/mnt/dvd/VIDEO_TS/VTS_03_5.VOB': Input/output error cp: reading `/mnt/dvd/VIDEO_TS/VTS_04_1.VOB': Input/output error Example errors: Dec 28 18:53:00 localhost hda: media error (bad sector): status=0x51 { DriveReady SeekComplete Error } Dec 28 18:53:00 localhost hda: media error (bad sector): error=0x34 { AbortedCommand LastFailedSense=0x03 } Dec 28 18:53:00 localhost ide: failed opcode was: unknown Dec 28 18:53:00 localhost hda: media error (bad sector): status=0x51 { DriveReady SeekComplete Error } Dec 28 18:53:00 localhost hda: media error (bad sector): error=0x34 { AbortedCommand LastFailedSense=0x03 } Dec 28 18:53:00 localhost ide: failed opcode was: unknown Dec 28 18:53:00 localhost end_request: I/O error, dev hda, sector 4002944 Dec 28 18:53:00 localhost Buffer I/O error on device hda, logical block 1000736 Dec 28 18:53:04 localhost hda: media error (bad sector): status=0x51 { DriveReady SeekComplete Error } Dec 28 18:53:04 localhost hda: media error (bad sector): error=0x34 { AbortedCommand LastFailedSense=0x03 } Dec 28 18:53:04 localhost ide: failed opcode was: unknown Dec 28 18:53:04 localhost hda: media error (bad sector): status=0x51 { DriveReady SeekComplete Error } Dec 28 18:53:04 localhost hda: media error (bad sector): error=0x34 { AbortedCommand LastFailedSense=0x03 } Dec 28 18:53:04 localhost ide: failed opcode was: unknown Dec 28 18:53:04 localhost end_request: I/O error, dev hda, sector 4003136 Dec 28 18:53:04 localhost Buffer I/O error on device hda, logical block 1000784 Dec 28 18:53:45 localhost hda: media error (bad sector): status=0x51 { DriveReady SeekComplete Error } Dec 28 18:53:45 localhost hda: media error (bad sector): error=0x34 { AbortedCommand LastFailedSense=0x03 } Dec 28 18:53:45 localhost ide: failed opcode was: unknown Dec 28 18:53:46 localhost hda: media error (bad sector): status=0x51 { DriveReady SeekComplete Error } Dec 28 18:53:46 localhost hda: media error (bad sector): error=0x34 { AbortedCommand LastFailedSense=0x03 } Dec 28 18:53:46 localhost ide: failed opcode was: unknown Dec 28 18:53:46 localhost end_request: I/O error, dev hda, sector 4803968 Dec 28 18:53:46 localhost Buffer I/O error on device hda, logical block 1200992 Dec 28 18:53:51 localhost hda: media error (bad sector): status=0x51 { DriveReady SeekComplete Error } Dec 28 18:53:51 localhost hda: media error (bad sector): error=0x34 { AbortedCommand LastFailedSense=0x03 } Dec 28 18:53:51 localhost ide: failed opcode was: unknown Dec 28 18:53:51 localhost end_request: I/O error, dev hda, sector 4803972 Dec 28 18:53:51 localhost Buffer I/O error on device hda, logical block 1200993 Dec 28 18:53:56 localhost hda: media error (bad sector): status=0x51 { DriveReady SeekComplete Error } Dec 28 18:53:56 localhost hda: media error (bad sector): error=0x34 { AbortedCommand LastFailedSense=0x03 } Dec 28 18:53:56 localhost ide: failed opcode was: unknown Dec 28 18:53:56 localhost end_request: I/O error, dev hda, sector 4803976 Dec 28 18:53:56 localhost Buffer I/O error on device hda, logical block 1200994 Dec 28 18:54:03 localhost hda: media error (bad sector): status=0x51 { DriveReady SeekComplete Error } Dec 28 18:54:03 localhost hda: media error (bad sector): error=0x34 { AbortedCommand LastFailedSense=0x03 } Dec 28 18:54:03 localhost ide: failed opcode was: unknown Dec 28 18:54:03 localhost end_request: I/O error, dev hda, sector 4803980 Dec 28 18:54:03 localhost Buffer I/O error on device hda, logical block 1200995 Dec 28 18:54:14 localhost hda: media error (bad sector): status=0x51 { DriveReady SeekComplete Error } Dec 28 18:54:14 localhost hda: media error (bad sector): error=0x34 { AbortedCommand LastFailedSense=0x03 } Dec 28 18:54:14 localhost ide: failed opcode was: unknown Dec 28 18:54:14 localhost hda: media error (bad sector): status=0x51 { DriveReady SeekComplete Error } Dec 28 18:54:14 localhost hda: media error (bad sector): error=0x34 { AbortedCommand LastFailedSense=0x03 } Dec 28 18:54:14 localhost ide: failed opcode was: unknown Dec 28 18:54:14 localhost end_request: I/O error, dev hda, sector 4804096 Dec 28 18:54:14 localhost Buffer I/O error on device hda, logical block 1201024 Dec 28 18:54:20 localhost hda: media error (bad sector): status=0x51 { DriveReady SeekComplete Error } Dec 28 18:54:20 localhost hda: media error (bad sector): error=0x34 { AbortedCommand LastFailedSense=0x03 } Dec 28 18:54:20 localhost ide: failed opcode was: unknown Dec 28 18:54:26 localhost hda: media error (bad sector): status=0x51 { DriveReady SeekComplete Error } Dec 28 18:54:26 localhost hda: media error (bad sector): error=0x34 { AbortedCommand LastFailedSense=0x03 } Dec 28 18:54:26 localhost ide: failed opcode was: unknown Dec 28 18:54:26 localhost end_request: I/O error, dev hda, sector 4804160 Dec 28 18:54:26 localhost Buffer I/O error on device hda, logical block 1201040 Dec 28 18:54:32 localhost hda: media error (bad sector): status=0x51 { DriveReady SeekComplete Error } Dec 28 18:54:32 localhost hda: media error (bad sector): error=0x34 { AbortedCommand LastFailedSense=0x03 } Dec 28 18:54:32 localhost ide: failed opcode was: unknown Dec 28 18:54:32 localhost end_request: I/O error, dev hda, sector 4804164 Dec 28 18:54:32 localhost Buffer I/O error on device hda, logical block 1201041 Dec 28 18:54:38 localhost hda: media error (bad sector): status=0x51 { DriveReady SeekComplete Error } Dec 28 18:54:38 localhost hda: media error (bad sector): error=0x34 { AbortedCommand LastFailedSense=0x03 } Dec 28 18:54:38 localhost ide: failed opcode was: unknown Dec 28 18:54:38 localhost end_request: I/O error, dev hda, sector 4804168 Dec 28 18:54:38 localhost Buffer I/O error on device hda, logical block 1201042 Dec 28 18:54:44 localhost hda: media error (bad sector): status=0x51 { DriveReady SeekComplete Error } Dec 28 18:54:44 localhost hda: media error (bad sector): error=0x34 { AbortedCommand LastFailedSense=0x03 } Dec 28 18:54:44 localhost ide: failed opcode was: unknown Dec 28 18:54:44 localhost end_request: I/O error, dev hda, sector 4804172 Dec 28 18:54:44 localhost Buffer I/O error on device hda, logical block 1201043 Dec 28 18:54:50 localhost hda: media error (bad sector): status=0x51 { DriveReady SeekComplete Error } Dec 28 18:54:50 localhost hda: media error (bad sector): error=0x34 { AbortedCommand LastFailedSense=0x03 } Dec 28 18:54:50 localhost ide: failed opcode was: unknown Dec 28 18:54:50 localhost end_request: I/O error, dev hda, sector 4804176 Dec 28 18:54:50 localhost Buffer I/O error on device hda, logical block 1201044 Dec 28 18:54:57 localhost hda: media error (bad sector): status=0x51 { DriveReady SeekComplete Error } Dec 28 18:54:57 localhost hda: media error (bad sector): error=0x34 { AbortedCommand LastFailedSense=0x03 } Dec 28 18:54:57 localhost ide: failed opcode was: unknown Dec 28 18:54:57 localhost end_request: I/O error, dev hda, sector 4804180 Dec 28 18:54:57 localhost Buffer I/O error on device hda, logical block 1201045 Dec 28 18:55:02 localhost hda: media error (bad sector): status=0x51 { DriveReady SeekComplete Error } Dec 28 18:55:02 localhost hda: media error (bad sector): error=0x34 { AbortedCommand LastFailedSense=0x03 } Dec 28 18:55:02 localhost ide: failed opcode was: unknown Dec 28 18:55:02 localhost end_request: I/O error, dev hda, sector 4804184 Dec 28 18:55:02 localhost Buffer I/O error on device hda, logical block 1201046 Dec 28 18:55:09 localhost hda: media error (bad sector): status=0x51 { DriveReady SeekComplete Error } Dec 28 18:55:09 localhost hda: media error (bad sector): error=0x34 { AbortedCommand LastFailedSense=0x03 } Dec 28 18:55:09 localhost ide: failed opcode was: unknown Dec 28 18:55:09 localhost end_request: I/O error, dev hda, sector 4804188 [...] Dec 28 19:20:19 localhost end_request: I/O error, dev hda, sector 11336384 Dec 28 19:20:19 localhost Buffer I/O error on device hda, logical block 2834096 Dec 28 19:20:22 localhost hda: media error (bad sector): status=0x51 { DriveReady SeekComplete Error } Dec 28 19:20:22 localhost hda: media error (bad sector): error=0x34 { AbortedCommand LastFailedSense=0x03 } Dec 28 19:20:22 localhost ide: failed opcode was: unknown Dec 28 19:20:22 localhost end_request: I/O error, dev hda, sector 11336388 Dec 28 19:20:22 localhost Buffer I/O error on device hda, logical block 2834097 Dec 28 19:20:24 localhost hda: media error (bad sector): status=0x51 { DriveReady SeekComplete Error } Dec 28 19:20:24 localhost hda: media error (bad sector): error=0x34 { AbortedCommand LastFailedSense=0x03 } Dec 28 19:20:24 localhost ide: failed opcode was: unknown Dec 28 19:20:24 localhost end_request: I/O error, dev hda, sector 11336392 Dec 28 19:20:24 localhost Buffer I/O error on device hda, logical block 2834098 Dec 28 19:20:28 localhost hda: media error (bad sector): status=0x51 { DriveReady SeekComplete Error } Dec 28 19:20:28 localhost hda: media error (bad sector): error=0x34 { AbortedCommand LastFailedSense=0x03 } Dec 28 19:20:28 localhost ide: failed opcode was: unknown Dec 28 19:20:28 localhost end_request: I/O error, dev hda, sector 11336396 Dec 28 19:20:28 localhost Buffer I/O error on device hda, logical block 2834099 Dec 28 19:20:31 localhost hda: media error (bad sector): status=0x51 { DriveReady SeekComplete Error } Dec 28 19:20:31 localhost hda: media error (bad sector): error=0x34 { AbortedCommand LastFailedSense=0x03 } Dec 28 19:20:31 localhost ide: failed opcode was: unknown Dec 28 19:20:31 localhost end_request: I/O error, dev hda, sector 11336400 Dec 28 19:20:31 localhost Buffer I/O error on device hda, logical block 2834100 At other times other strange errors are caused by reading from the dvd drive such as Dec 28 18:16:13 localhost hda: irq timeout: status=0x90 { Busy } Dec 28 18:16:13 localhost ide: failed opcode was: unknown Dec 28 18:16:13 localhost hda: ATAPI reset complete Dec 28 18:20:01 localhost atkbd.c: Keyboard on isa0060/serio0 reports too many keys pressed. Dec 28 18:20:01 localhost atkbd.c: Unknown key released (translated set 2, code 0x7f on isa0060/serio0). Dec 28 18:20:01 localhost atkbd.c: Use 'setkeycodes 7f <keycode>' to make it known. Dec 28 18:20:01 localhost cron[7983]: (root) CMD (test -x /usr/sbin/run-crons && /usr/sbin/run-crons ) Dec 28 18:20:01 localhost atkbd.c: Keyboard on isa0060/serio0 reports too many keys pressed. Dec 28 18:20:01 localhost atkbd.c: Unknown key released (translated set 2, code 0x7f on isa0060/serio0). Dec 28 18:20:01 localhost atkbd.c: Use 'setkeycodes 7f <keycode>' to make it known. Dec 28 18:20:01 localhost atkbd.c: Keyboard on isa0060/serio0 reports too many keys pressed. Dec 28 18:20:01 localhost atkbd.c: Unknown key released (translated set 2, code 0x7f on isa0060/serio0). Dec 28 18:20:01 localhost atkbd.c: Use 'setkeycodes 7f <keycode>' to make it known. Dec 28 18:20:01 localhost atkbd.c: Keyboard on isa0060/serio0 reports too many keys pressed. and Dec 28 15:56:05 localhost warning: many lost ticks. Dec 28 15:56:05 localhost Your time source seems to be instable or some driver is hogging interupts Dec 28 15:56:05 localhost rip default_idle+0x20/0x30 dmesg output at boot is Dec 28 18:06:11 localhost syslog-ng[6972]: syslog-ng version 1.6.8 starting Dec 28 18:06:11 localhost syslog-ng[6972]: Changing permissions on special file /dev/tty12 Dec 28 18:06:11 localhost Bootdata ok (command line is root=/dev/ram0 init=/linuxrc ramdisk=8192 real_root=/dev/sd a5 udev noapic acpi=noirq) Dec 28 18:06:11 localhost Linux version 2.6.14-gentoo-r5 (root@localhost) (gcc version 3.4.4 (Gentoo 3.4.4-r1, ssp -3.4.4-1.0, pie-8.7.8)) #1 Wed Dec 28 14:47:24 GMT 2005 Dec 28 18:06:11 localhost BIOS-provided physical RAM map: Dec 28 18:06:11 localhost BIOS-e820: 0000000000000000 - 000000000009f800 (usable) Dec 28 18:06:11 localhost BIOS-e820: 000000000009f800 - 00000000000a0000 (reserved) Dec 28 18:06:11 localhost BIOS-e820: 00000000000f0000 - 0000000000100000 (reserved) Dec 28 18:06:11 localhost BIOS-e820: 0000000000100000 - 000000003bf00000 (usable) Dec 28 18:06:11 localhost BIOS-e820: 00000000e0000000 - 00000000f0000000 (reserved) Dec 28 18:06:11 localhost BIOS-e820: 00000000fec00000 - 0000000100000000 (reserved) Dec 28 18:06:11 localhost ACPI: Unable to locate RSDP Dec 28 18:06:11 localhost No mptable found. Dec 28 18:06:11 localhost On node 0 totalpages: 245407 Dec 28 18:06:11 localhost DMA zone: 3999 pages, LIFO batch:1 Dec 28 18:06:11 localhost Normal zone: 241408 pages, LIFO batch:31 Dec 28 18:06:11 localhost HighMem zone: 0 pages, LIFO batch:1 Dec 28 18:06:11 localhost Allocating PCI resources starting at 40000000 (gap: 3bf00000:a4100000) Dec 28 18:06:11 localhost Checking aperture... Dec 28 18:06:11 localhost CPU 0: aperture @ 218a000000 size 32 MB Dec 28 18:06:11 localhost Aperture from northbridge cpu 0 too small (32 MB) Dec 28 18:06:11 localhost No AGP bridge found Dec 28 18:06:11 localhost Built 1 zonelists Dec 28 18:06:11 localhost Kernel command line: root=/dev/ram0 init=/linuxrc ramdisk=8192 real_root=/dev/sda5 udev noapic acpi=noirq Dec 28 18:06:11 localhost Initializing CPU#0 Dec 28 18:06:11 localhost PID hash table entries: 4096 (order: 12, 131072 bytes) Dec 28 18:06:11 localhost time.c: Using 1.193182 MHz PIT timer. Dec 28 18:06:11 localhost time.c: Detected 1790.924 MHz processor. Dec 28 18:06:11 localhost time.c: Using PIT/TSC based timekeeping. Dec 28 18:06:11 localhost Console: colour VGA+ 80x25 Dec 28 18:06:11 localhost Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes) Dec 28 18:06:11 localhost Inode-cache hash table entries: 65536 (order: 7, 524288 bytes) Dec 28 18:06:11 localhost Memory: 959564k/982016k available (3192k kernel code, 21548k reserved, 1089k data, 200k init) Dec 28 18:06:11 localhost Calibrating delay using timer specific routine.. 3588.90 BogoMIPS (lpj=7177802) Dec 28 18:06:11 localhost Mount-cache hash table entries: 256 Dec 28 18:06:11 localhost CPU: L1 I Cache: 64K (64 bytes/line), D cache 64K (64 bytes/line) Dec 28 18:06:11 localhost CPU: L2 Cache: 512K (64 bytes/line) Dec 28 18:06:11 localhost mtrr: v2.0 (20020519) Dec 28 18:06:11 localhost CPU: AMD Athlon(tm) 64 Processor 3000+ stepping 00 Dec 28 18:06:11 localhost Using local APIC timer interrupts. Dec 28 18:06:11 localhost Detected 12.436 MHz APIC timer. Dec 28 18:06:11 localhost testing NMI watchdog ... OK. Dec 28 18:06:11 localhost checking if image is initramfs... it is Dec 28 18:06:11 localhost NET: Registered protocol family 16 Dec 28 18:06:11 localhost PCI: Using configuration type 1 Dec 28 18:06:11 localhost ACPI: Subsystem revision 20050902 Dec 28 18:06:11 localhost ACPI: Interpreter disabled. Dec 28 18:06:11 localhost SCSI subsystem initialized Dec 28 18:06:11 localhost usbcore: registered new driver usbfs Dec 28 18:06:11 localhost usbcore: registered new driver hub Dec 28 18:06:11 localhost PCI: Probing PCI hardware Dec 28 18:06:11 localhost PCI: Probing PCI hardware (bus 00) Dec 28 18:06:11 localhost PCI: Ignoring BAR0-3 of IDE controller 0000:00:14.1 Dec 28 18:06:11 localhost Boot video device is 0000:01:05.0 Dec 28 18:06:11 localhost PCI: Transparent bridge - 0000:00:14.4 Dec 28 18:06:11 localhost PCI: Cannot allocate resource region 3 of device 0000:00:00.0 Dec 28 18:06:11 localhost TC classifier action (bugs to netdev@vger.kernel.org cc hadi@cyberus.ca) Dec 28 18:06:11 localhost PCI-DMA: Disabling IOMMU. Dec 28 18:06:11 localhost PCI: Bridge: 0000:00:01.0 Dec 28 18:06:11 localhost IO window: e000-efff Dec 28 18:06:11 localhost MEM window: fdd00000-fddfffff Dec 28 18:06:11 localhost PREFETCH window: d8000000-dfffffff Dec 28 18:06:11 localhost PCI: Bridge: 0000:00:14.4 Dec 28 18:06:11 localhost IO window: d000-dfff Dec 28 18:06:11 localhost MEM window: fdc00000-fdcfffff Dec 28 18:06:11 localhost PREFETCH window: fde00000-fdefffff Dec 28 18:06:11 localhost IA32 emulation $Id: sys_ia32.c,v 1.32 2002/03/24 13:02:28 ak Exp $ Dec 28 18:06:11 localhost Total HugeTLB memory allocated, 0 Dec 28 18:06:11 localhost Squashfs 2.2 (released 2005/07/03) (C) 2002-2005 Phillip Lougher Dec 28 18:06:11 localhost JFS: nTxBlock = 7500, nTxLock = 60005 Dec 28 18:06:11 localhost SGI XFS with ACLs, large block/inode numbers, no debug enabled Dec 28 18:06:11 localhost Initializing Cryptographic API Dec 28 18:06:11 localhost pci_hotplug: PCI Hot Plug PCI Core version: 0.5 Dec 28 18:06:11 localhost acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Dec 28 18:06:11 localhost Real Time Clock Driver v1.12 Dec 28 18:06:11 localhost Non-volatile memory driver v1.2 Dec 28 18:06:11 localhost Linux agpgart interface v0.101 (c) Dave Jones Dec 28 18:06:11 localhost [drm] Initialized drm 1.0.0 20040925 Dec 28 18:06:11 localhost serio: i8042 AUX port at 0x60,0x64 irq 12 Dec 28 18:06:11 localhost serio: i8042 KBD port at 0x60,0x64 irq 1 Dec 28 18:06:11 localhost Serial: 8250/16550 driver $Revision: 1.90 $ 4 ports, IRQ sharing disabled Dec 28 18:06:11 localhost ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A Dec 28 18:06:11 localhost mice: PS/2 mouse device common for all mice Dec 28 18:06:11 localhost io scheduler noop registered Dec 28 18:06:11 localhost io scheduler anticipatory registered Dec 28 18:06:11 localhost io scheduler deadline registered Dec 28 18:06:11 localhost io scheduler cfq registered Dec 28 18:06:11 localhost RAMDISK driver initialized: 16 RAM disks of 8192K size 1024 blocksize Dec 28 18:06:11 localhost loop: loaded (max 8 devices) Dec 28 18:06:11 localhost Uniform Multi-Platform E-IDE driver Revision: 7.00alpha2 Dec 28 18:06:11 localhost ide: Assuming 33MHz system bus speed for PIO modes; override with idebus=xx Dec 28 18:06:11 localhost Probing IDE interface ide0... Dec 28 18:06:11 localhost input: AT Translated Set 2 keyboard on isa0060/serio0 Dec 28 18:06:11 localhost input: PS/2 Generic Mouse on isa0060/serio1 Dec 28 18:06:11 localhost hda: BENQ DVD DD DW1640, ATAPI CD/DVD-ROM drive Dec 28 18:06:11 localhost Probing IDE interface ide1... Dec 28 18:06:11 localhost ide0 at 0x1f0-0x1f7,0x3f6 on irq 14 Dec 28 18:06:11 localhost hda: ATAPI 94X DVD-ROM DVD-R CD-R/RW drive, 2048kB Cache Dec 28 18:06:11 localhost Uniform CD-ROM driver Revision: 3.20 Dec 28 18:06:11 localhost usbmon: debugfs is not available Dec 28 18:06:11 localhost md: linear personality registered as nr 1 Dec 28 18:06:11 localhost md: raid0 personality registered as nr 2 Dec 28 18:06:11 localhost md: raid1 personality registered as nr 3 Dec 28 18:06:11 localhost md: raid5 personality registered as nr 4 Dec 28 18:06:11 localhost raid5: automatically using best checksumming function: generic_sse Dec 28 18:06:11 localhost generic_sse: 5468.000 MB/sec Dec 28 18:06:11 localhost raid5: using function: generic_sse (5468.000 MB/sec) Dec 28 18:06:11 localhost raid6: int64x1 1636 MB/s Dec 28 18:06:11 localhost raid6: int64x2 2360 MB/s Dec 28 18:06:11 localhost raid6: int64x4 2334 MB/s Dec 28 18:06:11 localhost raid6: int64x8 1562 MB/s Dec 28 18:06:11 localhost raid6: sse2x1 1267 MB/s Dec 28 18:06:11 localhost raid6: sse2x2 1961 MB/s Dec 28 18:06:11 localhost raid6: sse2x4 2414 MB/s Dec 28 18:06:11 localhost raid6: using algorithm sse2x4 (2414 MB/s) Dec 28 18:06:11 localhost md: raid6 personality registered as nr 8 Dec 28 18:06:11 localhost md: multipath personality registered as nr 7 Dec 28 18:06:11 localhost md: md driver 0.90.2 MAX_MD_DEVS=256, MD_SB_DISKS=27 Dec 28 18:06:11 localhost md: bitmap version 3.39 Dec 28 18:06:11 localhost device-mapper: 4.4.0-ioctl (2005-01-12) initialised: dm-devel@redhat.com Dec 28 18:06:11 localhost NET: Registered protocol family 2 Dec 28 18:06:11 localhost IP route cache hash table entries: 32768 (order: 6, 262144 bytes) Dec 28 18:06:11 localhost TCP established hash table entries: 131072 (order: 8, 1048576 bytes) Dec 28 18:06:11 localhost TCP bind hash table entries: 65536 (order: 7, 524288 bytes) Dec 28 18:06:11 localhost TCP: Hash tables configured (established 131072 bind 65536) Dec 28 18:06:11 localhost TCP reno registered Dec 28 18:06:11 localhost TCP bic registered Dec 28 18:06:11 localhost NET: Registered protocol family 1 Dec 28 18:06:11 localhost NET: Registered protocol family 10 Dec 28 18:06:11 localhost Disabled Privacy Extensions on device ffffffff804dce00(lo) Dec 28 18:06:11 localhost IPv6 over IPv4 tunneling driver Dec 28 18:06:11 localhost NET: Registered protocol family 17 Dec 28 18:06:11 localhost NET: Registered protocol family 15 Dec 28 18:06:11 localhost powernow-k8: Found 1 AMD Athlon 64 / Opteron processors (version 1.50.4) Dec 28 18:06:11 localhost powernow-k8: BIOS error - no PSB or ACPI _PSS objects Dec 28 18:06:11 localhost BIOS EDD facility v0.16 2004-Jun-25, 1 devices found Dec 28 18:06:11 localhost Freeing unused kernel memory: 200k freed Dec 28 18:06:11 localhost ehci_hcd 0000:00:13.2: EHCI Host Controller Dec 28 18:06:11 localhost ehci_hcd 0000:00:13.2: new USB bus registered, assigned bus number 1 Dec 28 18:06:11 localhost ehci_hcd 0000:00:13.2: irq 10, io mem 0xfe02b000 Dec 28 18:06:11 localhost ehci_hcd 0000:00:13.2: USB 2.0 initialized, EHCI 1.00, driver 10 Dec 2004 Dec 28 18:06:11 localhost spurious 8259A interrupt: IRQ7. Dec 28 18:06:11 localhost hub 1-0:1.0: USB hub found Dec 28 18:06:11 localhost hub 1-0:1.0: 8 ports detected Dec 28 18:06:11 localhost usb 1-7: new high speed USB device using ehci_hcd and address 3 Dec 28 18:06:11 localhost usbcore: registered new driver hiddev Dec 28 18:06:11 localhost usbcore: registered new driver usbhid Dec 28 18:06:11 localhost drivers/usb/input/hid-core.c: v2.6:USB HID core driver Dec 28 18:06:11 localhost Initializing USB Mass Storage driver... Dec 28 18:06:11 localhost scsi0 : SCSI emulation for USB Mass Storage devices Dec 28 18:06:11 localhost usb-storage: device found at 3 Dec 28 18:06:11 localhost usb-storage: waiting for device to settle before scanning Dec 28 18:06:11 localhost usbcore: registered new driver usb-storage Dec 28 18:06:11 localhost USB Mass Storage support registered. Dec 28 18:06:11 localhost USB Universal Host Controller Interface driver v2.3 Dec 28 18:06:11 localhost ohci_hcd: 2005 April 22 USB 1.1 'Open' Host Controller (OHCI) Driver (PCI) Dec 28 18:06:11 localhost ohci_hcd 0000:00:13.0: OHCI Host Controller Dec 28 18:06:11 localhost ohci_hcd 0000:00:13.0: new USB bus registered, assigned bus number 2 Dec 28 18:06:11 localhost ohci_hcd 0000:00:13.0: irq 10, io mem 0xfe02d000 Dec 28 18:06:11 localhost hub 2-0:1.0: USB hub found Dec 28 18:06:11 localhost hub 2-0:1.0: 4 ports detected Dec 28 18:06:11 localhost ohci_hcd 0000:00:13.1: OHCI Host Controller Dec 28 18:06:11 localhost ohci_hcd 0000:00:13.1: new USB bus registered, assigned bus number 3 Dec 28 18:06:11 localhost ohci_hcd 0000:00:13.1: irq 10, io mem 0xfe02c000 Dec 28 18:06:11 localhost hub 3-0:1.0: USB hub found Dec 28 18:06:11 localhost hub 3-0:1.0: 4 ports detected Dec 28 18:06:11 localhost ieee1394: Initialized config rom entry `ip1394' Dec 28 18:06:11 localhost ohci1394: $Rev: 1313 $ Ben Collins <bcollins@debian.org> Dec 28 18:06:11 localhost ohci1394: fw-host0: OHCI-1394 1.1 (PCI): IRQ=[11] MMIO=[fdcfe000-fdcfe7ff] Max Packet= [2048] Dec 28 18:06:11 localhost sbp2: $Rev: 1306 $ Ben Collins <bcollins@debian.org> Dec 28 18:06:11 localhost ieee1394: sbp2: Driver forced to serialize I/O (serialize_io=1) Dec 28 18:06:11 localhost ieee1394: sbp2: Try serialize_io=0 for better performance Dec 28 18:06:11 localhost libata version 1.12 loaded. Dec 28 18:06:11 localhost sata_sil version 0.9 Dec 28 18:06:11 localhost ata1: SATA max UDMA/100 cmd 0xFFFFC20000582080 ctl 0xFFFFC2000058208A bmdma 0xFFFFC20000 582000 irq 10 Dec 28 18:06:11 localhost ata2: SATA max UDMA/100 cmd 0xFFFFC200005820C0 ctl 0xFFFFC200005820CA bmdma 0xFFFFC20000 582008 irq 10 Dec 28 18:06:11 localhost ata1: no device found (phy stat 00000000) Dec 28 18:06:11 localhost scsi1 : sata_sil Dec 28 18:06:11 localhost ata2: no device found (phy stat 00000000) Dec 28 18:06:11 localhost scsi2 : sata_sil Dec 28 18:06:11 localhost ata3: SATA max UDMA/100 cmd 0xFFFFC20000584080 ctl 0xFFFFC2000058408A bmdma 0xFFFFC20000 584000 irq 11 Dec 28 18:06:11 localhost ata4: SATA max UDMA/100 cmd 0xFFFFC200005840C0 ctl 0xFFFFC200005840CA bmdma 0xFFFFC20000 584008 irq 11 Dec 28 18:06:11 localhost usb 3-1: new low speed USB device using ohci_hcd and address 2 Dec 28 18:06:11 localhost input: USB HID v1.10 Mouse [Microsoft Microsoft 3-Button Mouse with IntelliEye(TM)] on u sb-0000:00:13.1-1 Dec 28 18:06:11 localhost ata3: dev 0 cfg 49:2f00 82:746b 83:7f01 84:4023 85:7468 86:3c01 87:4023 88:40ff Dec 28 18:06:11 localhost ata3: dev 0 ATA, max UDMA7, 390721968 sectors: lba48 Dec 28 18:06:11 localhost ata3: dev 0 configured for UDMA/100 Dec 28 18:06:11 localhost scsi3 : sata_sil Dec 28 18:06:11 localhost ata4: no device found (phy stat 00000000) Dec 28 18:06:11 localhost scsi4 : sata_sil Dec 28 18:06:11 localhost Vendor: ATA Model: SAMSUNG SP2004C Rev: VM10 Dec 28 18:06:11 localhost Type: Direct-Access ANSI SCSI revision: 05 Dec 28 18:06:11 localhost SCSI device sda: 390721968 512-byte hdwr sectors (200050 MB) Dec 28 18:06:11 localhost SCSI device sda: drive cache: write back Dec 28 18:06:11 localhost SCSI device sda: 390721968 512-byte hdwr sectors (200050 MB) Dec 28 18:06:11 localhost SCSI device sda: drive cache: write back Dec 28 18:06:11 localhost sda: sda1 sda2 sda3 < sda5 sda6 > sda4 Dec 28 18:06:11 localhost Attached scsi disk sda at scsi3, channel 0, id 0, lun 0 Dec 28 18:06:11 localhost Attached scsi generic sg0 at scsi3, channel 0, id 0, lun 0, type 0 Dec 28 18:06:11 localhost ieee1394: Host added: ID:BUS[0-00:1023] GUID[0010dc0000b1dbe6] Dec 28 18:06:11 localhost ReiserFS: sda5: warning: sh-2021: reiserfs_fill_super: can not find reiserfs on sda5 Dec 28 18:06:11 localhost kjournald starting. Commit interval 5 seconds Dec 28 18:06:11 localhost EXT3-fs warning: maximal mount count reached, running e2fsck is recommended Dec 28 18:06:11 localhost EXT3 FS on sda5, internal journal Dec 28 18:06:11 localhost EXT3-fs: mounted filesystem with ordered data mode. Dec 28 18:06:11 localhost Adding 2811332k swap on /dev/sda6. Priority:-1 extents:1 across:2811332k Dec 28 18:06:11 localhost EXT3-fs warning: maximal mount count reached, running e2fsck is recommended Dec 28 18:06:11 localhost EXT3 FS on sda5, internal journal Dec 28 18:06:11 localhost Vendor: USB2.0 Model: CF CardReader Rev: Dec 28 18:06:11 localhost Type: Direct-Access ANSI SCSI revision: 00 Dec 28 18:06:11 localhost Attached scsi removable disk sdb at scsi0, channel 0, id 0, lun 0 Dec 28 18:06:11 localhost Attached scsi generic sg1 at scsi0, channel 0, id 0, lun 0, type 0 Dec 28 18:06:11 localhost Vendor: USB2.0 Model: CBO CardReader Rev: Dec 28 18:06:11 localhost Type: Direct-Access ANSI SCSI revision: 00 Dec 28 18:06:11 localhost Attached scsi removable disk sdc at scsi0, channel 0, id 0, lun 1 Dec 28 18:06:11 localhost Attached scsi generic sg2 at scsi0, channel 0, id 0, lun 1, type 0 Dec 28 18:06:11 localhost usb-storage: device scan complete Dec 28 18:06:11 localhost kjournald starting. Commit interval 5 seconds Dec 28 18:06:11 localhost EXT3-fs warning: maximal mount count reached, running e2fsck is recommended Dec 28 18:06:11 localhost EXT3 FS on sda4, internal journal Dec 28 18:06:11 localhost EXT3-fs: mounted filesystem with ordered data mode. Dec 28 18:06:11 localhost kjournald starting. Commit interval 5 seconds Dec 28 18:06:11 localhost EXT3-fs warning: mounting unchecked fs, running e2fsck is recommended Dec 28 18:06:11 localhost EXT3 FS on sda2, internal journal Dec 28 18:06:11 localhost EXT3-fs: mounted filesystem with ordered data mode. Dec 28 18:06:11 localhost NTFS driver 2.1.24 [Flags: R/O MODULE]. Dec 28 18:06:11 localhost NTFS volume version 3.1. Dec 28 18:06:11 localhost 8139too Fast Ethernet driver 0.9.27 Dec 28 18:06:11 localhost eth0: RealTek RTL8139 at 0xffffc20000742000, 00:13:d3:2e:29:c6, IRQ 5 Dec 28 18:06:11 localhost eth0: Identified 8139 chip type 'RTL-8100B/8139D' Dec 28 18:06:11 localhost eth0: link up, 100Mbps, full-duplex, lpa 0x41E1 Dec 28 18:06:15 localhost sshd[7302]: Server listening on :: port 22. Dec 28 18:06:15 localhost sshd[7302]: error: Bind to port 22 on 0.0.0.0 failed: Address already in use. Dec 28 18:06:15 localhost cron[7371]: (CRON) STARTUP (V5.0) Dec 28 18:06:16 localhost xfs: CONFIG: unknown parameter "cache-hi-mark" Dec 28 18:06:16 localhost xfs: CONFIG: unknown parameter "cache-low-mark" Dec 28 18:06:16 localhost xfs: CONFIG: unknown parameter "cache-balance" Dec 28 18:06:16 localhost xfs[7534]: ignoring font path element /usr/share/fonts/freefont (unreadable) Dec 28 18:06:16 localhost init: Activating demand-procedures for 'A' Dec 28 18:06:17 localhost eth0: no IPv6 routers present Output of emerge --info Portage 2.1_pre2 (default-linux/amd64/2005.1, gcc-3.4.4, glibc-2.3.5-r3, 2.6.14-gentoo-r5 x86_64) ================================================================= System uname: 2.6.14-gentoo-r5 x86_64 AMD Athlon(tm) 64 Processor 3000+ Gentoo Base System version 1.12.0_pre12 dev-lang/python: 2.3.5-r2, 2.4.2 sys-apps/sandbox: 1.2.17 sys-devel/autoconf: 2.13, 2.59-r7 sys-devel/automake: 1.4_p6, 1.5, 1.6.3, 1.7.9-r1, 1.8.5-r3, 1.9.6-r1 sys-devel/binutils: 2.16.1-r1 sys-devel/libtool: 1.5.20-r1 virtual/os-headers: 2.6.11-r3 ACCEPT_KEYWORDS="amd64 ~amd64" AUTOCLEAN="yes" CBUILD="x86_64-pc-linux-gnu" CFLAGS="-march=k8 -O2" CHOST="x86_64-pc-linux-gnu" CONFIG_PROTECT="/etc /usr/kde/2/share/config /usr/kde/3.4/env /usr/kde/3.4/share/config /usr/kde/3.4/shutdown /usr/kde/3.5/env /usr/kde/3.5/share/config /usr/kde/3.5/shutdown /usr/kde/3/share/config /usr/lib/X11/xkb /usr/lib64/mozilla/defaults/pref /usr/share/config /var/qmail/control" CONFIG_PROTECT_MASK="/etc/gconf /etc/terminfo /etc/texmf/web2c /etc/env.d" CXXFLAGS="-march=k8 -O2" DISTDIR="/usr/portage/distfiles" FEATURES="autoconfig distlocks sandbox sfperms strict" GENTOO_MIRRORS="http://ftp.belnet.be/mirror/rsync.gentoo.org/gentoo/ http://www.mirror.ac.uk/sites/www.ibiblio.org/gentoo/ ftp://ftp.heanet.ie/pub/gentoo/ http://ftp.easynet.nl/mirror/gentoo/" PKGDIR="/usr/portage/packages" PORTAGE_TMPDIR="/var/tmp" PORTDIR="/usr/portage" PORTDIR_OVERLAY="/usr/local/portage" SYNC="rsync://rsync.gentoo.org/gentoo-portage" USE="amd64 X Xaw3d a52 aac aalib alsa artworkextra audiofile avi bash-completion berkdb bindinst bitmap-fonts bonobo bootsplash browserplugin bzip2 cdr crypt cups curl dv dvd dvdr dvdread eds emboss encode esd ethereal exif expat fam fame ffmpeg firefox font-server foomaticdb fortran gdbm gif glut gnome gpm gstreamer gtk gtk2 guile icq idn imagemagick imlib ipv6 irc jabber java jpeg junit kde lcms libwww lzw lzw-tiff mad mikmod mng motif mozilla mp3 mpeg msn ncurses nls nptl nsplugin offensive ogg oggvorbis opengl oss pam pcre pdflib perl png python qt quicktime readline real rss sdl speex spell ssl subtitles svg tcpd tetex theora tiff truetype truetype-fonts type1-fonts udev unicode usb userlocales vorbis wxgtk1 xine xml xml2 xmms xpm xv xvid yahoo zlib elibc_glibc kernel_linux userland_GNU" Unset: ASFLAGS, CTARGET, LANG, LC_ALL, LDFLAGS, LINGUAS, MAKEOPTS Output of lspci 00:00.0 Host bridge: ATI Technologies Inc RS480 Host Bridge 00:01.0 PCI bridge: ATI Technologies Inc Unknown device 5a3f 00:11.0 IDE interface: ATI Technologies Inc ATI 437A Serial ATA Controller 00:12.0 IDE interface: ATI Technologies Inc ATI 4379 Serial ATA Controller 00:13.0 USB Controller: ATI Technologies Inc IXP SB400 USB Host Controller 00:13.1 USB Controller: ATI Technologies Inc IXP SB400 USB Host Controller 00:13.2 USB Controller: ATI Technologies Inc IXP SB400 USB2 Host Controller 00:14.0 SMBus: ATI Technologies Inc IXP SB400 SMBus Controller (rev 04) 00:14.1 IDE interface: ATI Technologies Inc Standard Dual Channel PCI IDE Controller ATI 00:14.3 ISA bridge: ATI Technologies Inc IXP SB400 PCI-ISA Bridge 00:14.4 PCI bridge: ATI Technologies Inc IXP SB400 PCI-PCI Bridge 00:14.5 Multimedia audio controller: ATI Technologies Inc IXP SB400 AC'97 Audio Controller 00:18.0 Host bridge: Advanced Micro Devices [AMD] K8 [Athlon64/Opteron] HyperTransport Technology Configuration 00:18.1 Host bridge: Advanced Micro Devices [AMD] K8 [Athlon64/Opteron] Address Map 00:18.2 Host bridge: Advanced Micro Devices [AMD] K8 [Athlon64/Opteron] DRAM Controller 00:18.3 Host bridge: Advanced Micro Devices [AMD] K8 [Athlon64/Opteron] Miscellaneous Control 01:05.0 VGA compatible controller: ATI Technologies Inc RS480 [Radeon Xpress 200G Series] 02:03.0 Ethernet controller: Realtek Semiconductor Co., Ltd. RTL-8139/8139C/8139C+ (rev 10) 02:04.0 FireWire (IEEE 1394): VIA Technologies, Inc. IEEE 1394 Host Controller (rev 80)
One more data point. The output of hdparm -i /dev/hda is /dev/hda: Model=BENQ DVD DD DW1640, FwRev=BSOB, SerialNo=9JB5U150F251939550PP Config={ Fixed Removeable DTR<=5Mbs DTR>10Mbs nonMagnetic } RawCHS=0/0/0, TrkSize=0, SectSize=0, ECCbytes=0 BuffType=unknown, BuffSize=0kB, MaxMultSect=0 (maybe): CurCHS=0/0/0, CurSects=0, LBA=yes, LBAsects=0 IORDY=yes, tPIO={min:120,w/IORDY:120}, tDMA={min:120,rec:120} PIO modes: pio0 pio1 pio2 pio3 pio4 DMA modes: mdma0 mdma1 mdma2 UDMA modes: udma0 udma1 *udma2 AdvancedPM=no * signifies the current active mode
Provide emerge --info, please
Well, sorry but you can't exactly cp files from copy-protected DVDs. Bad sectors is one of the "protections"...
The emerge --info output is included in the original report. Look for "Output of emerge --info" If it is true that you can't cp encrypted files from a mounted dvd then I can provide the output from vobcopy which follows a very similar route (as does mplayer etc.). I chose cp in order to make the bug report as simple as possible.
Error messages from running vobcopy (as root in this case). Stopped at 157MB out of 2443MB (6%) Dec 28 22:28:41 localhost hda: media error (bad sector): status=0x51 { DriveReady SeekComplete Error } Dec 28 22:28:41 localhost hda: media error (bad sector): error=0x34 { AbortedCommand LastFailedSense=0x03 } Dec 28 22:28:41 localhost ide: failed opcode was: unknown Dec 28 22:28:41 localhost hda: media error (bad sector): status=0x51 { DriveReady SeekComplete Error } Dec 28 22:28:41 localhost hda: media error (bad sector): error=0x34 { AbortedCommand LastFailedSense=0x03 } Dec 28 22:28:41 localhost ide: failed opcode was: unknown Dec 28 22:28:41 localhost end_request: I/O error, dev hda, sector 11359444 Dec 28 22:28:41 localhost Buffer I/O error on device hda, logical block 2839861 Dec 28 22:28:45 localhost hda: media error (bad sector): status=0x51 { DriveReady SeekComplete Error } Dec 28 22:28:45 localhost hda: media error (bad sector): error=0x34 { AbortedCommand LastFailedSense=0x03 } Dec 28 22:28:45 localhost ide: failed opcode was: unknown Dec 28 22:28:47 localhost hda: media error (bad sector): status=0x51 { DriveReady SeekComplete Error } Dec 28 22:28:47 localhost hda: media error (bad sector): error=0x34 { AbortedCommand LastFailedSense=0x03 } Dec 28 22:28:47 localhost ide: failed opcode was: unknown Dec 28 22:28:52 localhost hda: media error (bad sector): status=0x51 { DriveReady SeekComplete Error } Dec 28 22:28:52 localhost hda: media error (bad sector): error=0x34 { AbortedCommand LastFailedSense=0x03 } Dec 28 22:28:52 localhost ide: failed opcode was: unknown Dec 28 22:28:52 localhost end_request: I/O error, dev hda, sector 11359444 Dec 28 22:28:52 localhost Buffer I/O error on device hda, logical block 2839861 Dec 28 22:28:55 localhost hda: media error (bad sector): status=0x51 { DriveReady SeekComplete Error } Dec 28 22:28:55 localhost hda: media error (bad sector): error=0x34 { AbortedCommand LastFailedSense=0x03 } Dec 28 22:28:55 localhost ide: failed opcode was: unknown Dec 28 22:28:55 localhost end_request: I/O error, dev hda, sector 11359444 Dec 28 22:28:55 localhost Buffer I/O error on device hda, logical block 2839861 Dec 28 22:28:58 localhost hda: media error (bad sector): status=0x51 { DriveReady SeekComplete Error } Dec 28 22:28:58 localhost hda: media error (bad sector): error=0x34 { AbortedCommand LastFailedSense=0x03 } Dec 28 22:28:58 localhost ide: failed opcode was: unknown Dec 28 22:28:58 localhost end_request: I/O error, dev hda, sector 11361408 Dec 28 22:28:58 localhost Buffer I/O error on device hda, logical block 2840352 Dec 28 22:28:58 localhost hda: media error (bad sector): status=0x51 { DriveReady SeekComplete Error } Dec 28 22:28:58 localhost hda: media error (bad sector): error=0x34 { AbortedCommand LastFailedSense=0x03 } Dec 28 22:28:58 localhost ide: failed opcode was: unknown Dec 28 22:28:58 localhost end_request: I/O error, dev hda, sector 11361412 Dec 28 22:28:58 localhost Buffer I/O error on device hda, logical block 2840353 Dec 28 22:29:00 localhost hda: media error (bad sector): status=0x51 { DriveReady SeekComplete Error } Dec 28 22:29:00 localhost hda: media error (bad sector): error=0x34 { AbortedCommand LastFailedSense=0x03 } Dec 28 22:29:00 localhost ide: failed opcode was: unknown Dec 28 22:29:00 localhost end_request: I/O error, dev hda, sector 11361416 Dec 28 22:29:00 localhost Buffer I/O error on device hda, logical block 2840354 Dec 28 22:30:02 localhost cron[8619]: (root) CMD (test -x /usr/sbin/run-crons && /usr/sbin/run-crons ) Dec 28 22:30:18 localhost su[8632]: + pts/4 raph:root Dec 28 22:30:18 localhost su(pam_unix)[8632]: session opened for user root by (uid=1000) Dec 28 22:30:29 localhost hda: media error (bad sector): status=0x51 { DriveReady SeekComplete Error } Dec 28 22:30:29 localhost hda: media error (bad sector): error=0x34 { AbortedCommand LastFailedSense=0x03 } Dec 28 22:30:29 localhost ide: failed opcode was: unknown Dec 28 22:30:29 localhost hda: media error (bad sector): status=0x51 { DriveReady SeekComplete Error } Dec 28 22:30:29 localhost hda: media error (bad sector): error=0x34 { AbortedCommand LastFailedSense=0x03 } Dec 28 22:30:29 localhost ide: failed opcode was: unknown Dec 28 22:30:29 localhost end_request: I/O error, dev hda, sector 11544344 Dec 28 22:30:29 localhost Buffer I/O error on device hda, logical block 2886086 Dec 28 22:30:33 localhost hda: media error (bad sector): status=0x51 { DriveReady SeekComplete Error } Dec 28 22:30:33 localhost hda: media error (bad sector): error=0x34 { AbortedCommand LastFailedSense=0x03 } Dec 28 22:30:33 localhost ide: failed opcode was: unknown Dec 28 22:30:33 localhost end_request: I/O error, dev hda, sector 11544348 Dec 28 22:30:33 localhost Buffer I/O error on device hda, logical block 2886087 Dec 28 22:30:37 localhost hda: media error (bad sector): status=0x51 { DriveReady SeekComplete Error } Dec 28 22:30:37 localhost hda: media error (bad sector): error=0x34 { AbortedCommand LastFailedSense=0x03 } Dec 28 22:30:37 localhost ide: failed opcode was: unknown Dec 28 22:30:37 localhost end_request: I/O error, dev hda, sector 11544352 Dec 28 22:30:37 localhost Buffer I/O error on device hda, logical block 2886088 Dec 28 22:30:42 localhost hda: media error (bad sector): status=0x51 { DriveReady SeekComplete Error } Dec 28 22:30:42 localhost hda: media error (bad sector): error=0x34 { AbortedCommand LastFailedSense=0x03 } Dec 28 22:30:42 localhost ide: failed opcode was: unknown Dec 28 22:30:42 localhost end_request: I/O error, dev hda, sector 11544356 Dec 28 22:30:42 localhost Buffer I/O error on device hda, logical block 2886089 Dec 28 22:30:46 localhost hda: media error (bad sector): status=0x51 { DriveReady SeekComplete Error } Dec 28 22:30:46 localhost hda: media error (bad sector): error=0x34 { AbortedCommand LastFailedSense=0x03 } Dec 28 22:30:46 localhost ide: failed opcode was: unknown Dec 28 22:30:46 localhost hda: media error (bad sector): status=0x51 { DriveReady SeekComplete Error } Dec 28 22:30:46 localhost hda: media error (bad sector): error=0x34 { AbortedCommand LastFailedSense=0x03 } Dec 28 22:30:46 localhost ide: failed opcode was: unknown Dec 28 22:30:46 localhost end_request: I/O error, dev hda, sector 11544576 Dec 28 22:30:46 localhost Buffer I/O error on device hda, logical block 2886144 Dec 28 22:30:50 localhost hda: media error (bad sector): status=0x51 { DriveReady SeekComplete Error } Dec 28 22:30:50 localhost hda: media error (bad sector): error=0x34 { AbortedCommand LastFailedSense=0x03 } Dec 28 22:30:50 localhost ide: failed opcode was: unknown Dec 28 22:30:50 localhost end_request: I/O error, dev hda, sector 11544580 Dec 28 22:30:50 localhost Buffer I/O error on device hda, logical block 2886145 Dec 28 22:30:54 localhost hda: media error (bad sector): status=0x51 { DriveReady SeekComplete Error } Dec 28 22:30:54 localhost hda: media error (bad sector): error=0x34 { AbortedCommand LastFailedSense=0x03 } Dec 28 22:30:54 localhost ide: failed opcode was: unknown Dec 28 22:30:54 localhost end_request: I/O error, dev hda, sector 11544584 Dec 28 22:30:54 localhost Buffer I/O error on device hda, logical block 2886146 Dec 28 22:30:58 localhost hda: media error (bad sector): status=0x51 { DriveReady SeekComplete Error } Dec 28 22:30:58 localhost hda: media error (bad sector): error=0x34 { AbortedCommand LastFailedSense=0x03 } Dec 28 22:30:58 localhost ide: failed opcode was: unknown Dec 28 22:31:02 localhost hda: media error (bad sector): status=0x51 { DriveReady SeekComplete Error } Dec 28 22:31:02 localhost hda: media error (bad sector): error=0x34 { AbortedCommand LastFailedSense=0x03 } Dec 28 22:31:02 localhost ide: failed opcode was: unknown Dec 28 22:31:02 localhost end_request: I/O error, dev hda, sector 11544612 Dec 28 22:31:02 localhost Buffer I/O error on device hda, logical block 2886153 Dec 28 22:31:06 localhost hda: media error (bad sector): status=0x51 { DriveReady SeekComplete Error } Dec 28 22:31:06 localhost hda: media error (bad sector): error=0x34 { AbortedCommand LastFailedSense=0x03 } Dec 28 22:31:06 localhost ide: failed opcode was: unknown Dec 28 22:31:06 localhost end_request: I/O error, dev hda, sector 11544616 Dec 28 22:31:06 localhost Buffer I/O error on device hda, logical block 2886154 Dec 28 22:31:11 localhost hda: media error (bad sector): status=0x51 { DriveReady SeekComplete Error } Dec 28 22:31:11 localhost hda: media error (bad sector): error=0x34 { AbortedCommand LastFailedSense=0x03 } Dec 28 22:31:11 localhost ide: failed opcode was: unknown Dec 28 22:31:11 localhost end_request: I/O error, dev hda, sector 11544620 Dec 28 22:31:11 localhost Buffer I/O error on device hda, logical block 2886155 Dec 28 22:31:15 localhost hda: media error (bad sector): status=0x51 { DriveReady SeekComplete Error } Dec 28 22:31:15 localhost hda: media error (bad sector): error=0x34 { AbortedCommand LastFailedSense=0x03 } Dec 28 22:31:15 localhost ide: failed opcode was: unknown Dec 28 22:31:15 localhost end_request: I/O error, dev hda, sector 11544624 Dec 28 22:31:15 localhost Buffer I/O error on device hda, logical block 2886156 ----------------------------------------------------- Output of vobcopy -v vobcopy -v Vobcopy 0.5.14 - GPL Copyright (c) 2001 - 2004 robos@muon.de [Hint] All lines starting with "libdvdread:" are not from vobcopy but from the libdvdread-library path to dvd: /dev/hda libdvdread: Using libdvdcss version 1.2.9 for DVD access name of dvd: P9161DVD There are 4 titles on this DVD. There are 53 chapters on the dvd. Most chapters has title 4 with 31 chapters. There are 4 angles on this dvd. Used the linux statfs In freespace_getter:for /home/raph/ : 128332857344 free In freespace_getter:part1 31331264, part2 4096 Using Title: 4 Title has 31 chapters and 1 angles Using Chapter: 1 Using Angle: 1 libdvdread: Attempting to retrieve all CSS keys libdvdread: This can take a _long_ time, please be patient libdvdread: Get key for /VIDEO_TS/VIDEO_TS.VOB at 0x00000133 libdvdread: Elapsed time 0 libdvdread: Get key for /VIDEO_TS/VTS_01_0.VOB at 0x00011e96 libdvdread: Elapsed time 0 libdvdread: Get key for /VIDEO_TS/VTS_01_1.VOB at 0x00011e9b libdvdread: Elapsed time 0 libdvdread: Get key for /VIDEO_TS/VTS_02_0.VOB at 0x00011eb2 libdvdread: Elapsed time 0 libdvdread: Get key for /VIDEO_TS/VTS_02_1.VOB at 0x00011eb7 libdvdread: Elapsed time 0 libdvdread: Get key for /VIDEO_TS/VTS_03_0.VOB at 0x0001c36b libdvdread: Elapsed time 0 libdvdread: Get key for /VIDEO_TS/VTS_03_1.VOB at 0x00021480 libdvdread: Elapsed time 0 libdvdread: Get key for /VIDEO_TS/VTS_04_0.VOB at 0x002acfe9 libdvdread: Elapsed time 0 libdvdread: Get key for /VIDEO_TS/VTS_04_1.VOB at 0x002acfee libdvdread: Elapsed time 1 libdvdread: Found 4 VTS's libdvdread: Elapsed time 1 difference between vobsize read from cd and size returned from libdvdread: vob_size (stat) = 2561581056 libdvdsize = 2561581056 diff = 0 DVD-name: P9161DVD Used the linux statfs In freespace_getter:for /home/raph/ : 128332857344 free In freespace_getter:part1 31331264, part2 4096 Outputting to /home/raph/P9161DVD4-1.vob Used the linux statfs In freespace_getter:for /home/raph/P9161DVD4-1.vob.partial : 128332857344 free In freespace_getter:part1 31331264, part2 4096 ---------------- cut -----------------------
I added report_lost_ticks=100, some boot prompt arguments from the web that people recommend for my motherboard and upgraded to vanilla-sources 2.6.15-rc7. I ran vobcopy as before as soon as the computer came up and I had mounted the drive. The behaviour was identical to before (i.e. jumpy and slow and eventually vobcopy stops having read a fraction of the DVD with the log full of errors). Dmesg now looks like this before we get to the read errors which are the same as before The "rip" lines mean nothing to me but hopefully they are helpful. (I should have mentioned before that ACPI and APIC are OFF n the BIOS.) Dec 29 12:21:22 localhost Bootdata ok (command line is root=/dev/ram0 init=/linuxrc ramdisk=8192 real_root =/dev/sda5 udev acpi=noirq pci=biosirq irqfixup nolapic notimercheck clock=pmtmr report_lost_ticks=100) Dec 29 12:21:22 localhost Linux version 2.6.15-rc7 (root@localhost) (gcc version 3.4.4 (Gentoo 3.4.4-r1, s sp-3.4.4-1.0, pie-8.7.8)) #1 Thu Dec 29 11:52:44 GMT 2005 Dec 29 12:21:22 localhost BIOS-provided physical RAM map: Dec 29 12:21:22 localhost BIOS-e820: 0000000000000000 - 000000000009f800 (usable) Dec 29 12:21:22 localhost BIOS-e820: 000000000009f800 - 00000000000a0000 (reserved) Dec 29 12:21:22 localhost BIOS-e820: 00000000000f0000 - 0000000000100000 (reserved) Dec 29 12:21:22 localhost BIOS-e820: 0000000000100000 - 000000003bf00000 (usable) Dec 29 12:21:22 localhost BIOS-e820: 00000000e0000000 - 00000000f0000000 (reserved) Dec 29 12:21:22 localhost BIOS-e820: 00000000fec00000 - 0000000100000000 (reserved) Dec 29 12:21:22 localhost ACPI: Unable to locate RSDP Dec 29 12:21:22 localhost No mptable found. Dec 29 12:21:22 localhost On node 0 totalpages: 240916 Dec 29 12:21:22 localhost DMA zone: 2808 pages, LIFO batch:0 Dec 29 12:21:22 localhost DMA32 zone: 238108 pages, LIFO batch:31 Dec 29 12:21:22 localhost Normal zone: 0 pages, LIFO batch:0 Dec 29 12:21:22 localhost HighMem zone: 0 pages, LIFO batch:0 Dec 29 12:21:22 localhost Allocating PCI resources starting at 40000000 (gap: 3bf00000:a4100000) Dec 29 12:21:22 localhost Checking aperture... Dec 29 12:21:22 localhost CPU 0: aperture @ 218a000000 size 32 MB Dec 29 12:21:22 localhost Aperture from northbridge cpu 0 too small (32 MB) Dec 29 12:21:22 localhost No AGP bridge found Dec 29 12:21:22 localhost Built 1 zonelists Dec 29 12:21:22 localhost Kernel command line: root=/dev/ram0 init=/linuxrc ramdisk=8192 real_root=/dev/sd a5 udev acpi=noirq pci=biosirq irqfixup nolapic notimercheck clock=pmtmr report_lost_ticks=100 Dec 29 12:21:22 localhost PCI: Unknown option `biosirq' Dec 29 12:21:22 localhost Misrouted IRQ fixup support enabled. Dec 29 12:21:22 localhost This may impact system performance. Dec 29 12:21:22 localhost Initializing CPU#0 Dec 29 12:21:22 localhost PID hash table entries: 4096 (order: 12, 131072 bytes) Dec 29 12:21:22 localhost time.c: Using 1.193182 MHz PIT timer. Dec 29 12:21:22 localhost time.c: Detected 1790.884 MHz processor. Dec 29 12:21:22 localhost time.c: Using PIT/TSC based timekeeping. Dec 29 12:21:22 localhost Console: colour VGA+ 80x25 Dec 29 12:21:22 localhost Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes) Dec 29 12:21:22 localhost Inode-cache hash table entries: 65536 (order: 7, 524288 bytes) Dec 29 12:21:22 localhost Memory: 960188k/982016k available (2848k kernel code, 21104k reserved, 1072k dat a, 196k init) Dec 29 12:21:22 localhost Calibrating delay using timer specific routine.. 3588.98 BogoMIPS (lpj=7177975) Dec 29 12:21:22 localhost Mount-cache hash table entries: 256 Dec 29 12:21:22 localhost CPU: L1 I Cache: 64K (64 bytes/line), D cache 64K (64 bytes/line) Dec 29 12:21:22 localhost CPU: L2 Cache: 512K (64 bytes/line) Dec 29 12:21:22 localhost mtrr: v2.0 (20020519) Dec 29 12:21:22 localhost CPU: AMD Athlon(tm) 64 Processor 3000+ stepping 00 Dec 29 12:21:22 localhost Apic disabled Dec 29 12:21:22 localhost checking if image is initramfs... it is Dec 29 12:21:22 localhost NET: Registered protocol family 16 Dec 29 12:21:22 localhost PCI: Using configuration type 1 Dec 29 12:21:22 localhost ACPI: Subsystem revision 20050902 Dec 29 12:21:22 localhost ACPI: Interpreter disabled. Dec 29 12:21:22 localhost SCSI subsystem initialized Dec 29 12:21:22 localhost usbcore: registered new driver usbfs Dec 29 12:21:22 localhost usbcore: registered new driver hub Dec 29 12:21:22 localhost PCI: Probing PCI hardware Dec 29 12:21:22 localhost PCI: Probing PCI hardware (bus 00) Dec 29 12:21:22 localhost PCI: Ignoring BAR0-3 of IDE controller 0000:00:14.1 Dec 29 12:21:22 localhost Boot video device is 0000:01:05.0 Dec 29 12:21:22 localhost PCI: Transparent bridge - 0000:00:14.4 Dec 29 12:21:22 localhost PCI: Cannot allocate resource region 3 of device 0000:00:00.0 Dec 29 12:21:22 localhost TC classifier action (bugs to netdev@vger.kernel.org cc hadi@cyberus.ca) Dec 29 12:21:22 localhost PCI-DMA: Disabling IOMMU. Dec 29 12:21:22 localhost PCI: Bridge: 0000:00:01.0 Dec 29 12:21:22 localhost IO window: e000-efff Dec 29 12:21:22 localhost MEM window: fdd00000-fddfffff Dec 29 12:21:22 localhost PREFETCH window: d8000000-dfffffff Dec 29 12:21:22 localhost PCI: Bridge: 0000:00:14.4 Dec 29 12:21:22 localhost IO window: d000-dfff Dec 29 12:21:22 localhost MEM window: fdc00000-fdcfffff Dec 29 12:21:22 localhost PREFETCH window: fde00000-fdefffff Dec 29 12:21:22 localhost IA32 emulation $Id: sys_ia32.c,v 1.32 2002/03/24 13:02:28 ak Exp $ Dec 29 12:21:22 localhost Total HugeTLB memory allocated, 0 Dec 29 12:21:22 localhost JFS: nTxBlock = 7504, nTxLock = 60032 Dec 29 12:21:22 localhost SGI XFS with ACLs, large block/inode numbers, no debug enabled Dec 29 12:21:22 localhost Initializing Cryptographic API Dec 29 12:21:22 localhost io scheduler noop registered Dec 29 12:21:22 localhost io scheduler anticipatory registered Dec 29 12:21:22 localhost io scheduler deadline registered Dec 29 12:21:22 localhost io scheduler cfq registered Dec 29 12:21:22 localhost time.c: Lost 2 timer tick(s)! rip msleep+0x3/0x3a) Dec 29 12:21:22 localhost time.c: Lost 2 timer tick(s)! rip msleep+0x3/0x3a) Dec 29 12:21:22 localhost pci_hotplug: PCI Hot Plug PCI Core version: 0.5 Dec 29 12:21:22 localhost acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Dec 29 12:21:22 localhost Real Time Clock Driver v1.12 Dec 29 12:21:22 localhost Non-volatile memory driver v1.2 Dec 29 12:21:22 localhost Linux agpgart interface v0.101 (c) Dave Jones Dec 29 12:21:22 localhost [drm] Initialized drm 1.0.0 20040925 Dec 29 12:21:22 localhost serio: i8042 AUX port at 0x60,0x64 irq 12 Dec 29 12:21:22 localhost serio: i8042 KBD port at 0x60,0x64 irq 1 Dec 29 12:21:22 localhost Serial: 8250/16550 driver $Revision: 1.90 $ 4 ports, IRQ sharing disabled Dec 29 12:21:22 localhost serial8250: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A Dec 29 12:21:22 localhost RAMDISK driver initialized: 16 RAM disks of 8192K size 1024 blocksize Dec 29 12:21:22 localhost loop: loaded (max 8 devices) Dec 29 12:21:22 localhost Uniform Multi-Platform E-IDE driver Revision: 7.00alpha2 Dec 29 12:21:22 localhost ide: Assuming 33MHz system bus speed for PIO modes; override with idebus=xx Dec 29 12:21:22 localhost Probing IDE interface ide0... Dec 29 12:21:22 localhost time.c: Lost 13 timer tick(s)! rip default_idle+0x34/0x72) Dec 29 12:21:22 localhost spurious 8259A interrupt: IRQ7. Dec 29 12:21:22 localhost time.c: Lost 15 timer tick(s)! rip default_idle+0x34/0x72) Dec 29 12:21:22 localhost hda: BENQ DVD DD DW1640, ATAPI CD/DVD-ROM drive Dec 29 12:21:22 localhost Probing IDE interface ide1... Dec 29 12:21:22 localhost ide0 at 0x1f0-0x1f7,0x3f6 on irq 14 Dec 29 12:21:22 localhost hda: ATAPI 94X DVD-ROM DVD-R CD-R/RW drive, 2048kB Cache Dec 29 12:21:22 localhost Uniform CD-ROM driver Revision: 3.20 Dec 29 12:21:22 localhost usbmon: debugfs is not available Dec 29 12:21:22 localhost mice: PS/2 mouse device common for all mice Dec 29 12:21:22 localhost md: linear personality registered as nr 1 Dec 29 12:21:22 localhost md: raid0 personality registered as nr 2 Dec 29 12:21:22 localhost md: raid1 personality registered as nr 3 Dec 29 12:21:22 localhost md: raid5 personality registered as nr 4 Dec 29 12:21:22 localhost raid5: automatically using best checksumming function: generic_sse Dec 29 12:21:22 localhost generic_sse: 5464.000 MB/sec Dec 29 12:21:22 localhost raid5: using function: generic_sse (5464.000 MB/sec) Dec 29 12:21:22 localhost raid6: int64x1 1805 MB/s Dec 29 12:21:22 localhost raid6: int64x2 2207 MB/s Dec 29 12:21:22 localhost raid6: int64x4 2140 MB/s Dec 29 12:21:22 localhost raid6: int64x8 1608 MB/s Dec 29 12:21:22 localhost raid6: sse2x1 2369 MB/s Dec 29 12:21:22 localhost raid6: sse2x2 2987 MB/s Dec 29 12:21:22 localhost raid6: sse2x4 3354 MB/s Dec 29 12:21:22 localhost raid6: using algorithm sse2x4 (3354 MB/s) Dec 29 12:21:22 localhost md: raid6 personality registered as nr 8 Dec 29 12:21:22 localhost md: multipath personality registered as nr 7 Dec 29 12:21:22 localhost md: md driver 0.90.3 MAX_MD_DEVS=256, MD_SB_DISKS=27 Dec 29 12:21:22 localhost md: bitmap version 4.39 Dec 29 12:21:22 localhost device-mapper: 4.4.0-ioctl (2005-01-12) initialised: dm-devel@redhat.com Dec 29 12:21:22 localhost NET: Registered protocol family 2 Dec 29 12:21:22 localhost IP route cache hash table entries: 32768 (order: 6, 262144 bytes) Dec 29 12:21:22 localhost TCP established hash table entries: 131072 (order: 8, 1048576 bytes) Dec 29 12:21:22 localhost TCP bind hash table entries: 65536 (order: 7, 524288 bytes) Dec 29 12:21:22 localhost TCP: Hash tables configured (established 131072 bind 65536) Dec 29 12:21:22 localhost TCP reno registered Dec 29 12:21:22 localhost TCP bic registered Dec 29 12:21:22 localhost NET: Registered protocol family 1 Dec 29 12:21:22 localhost NET: Registered protocol family 10 Dec 29 12:21:22 localhost lo: Disabled Privacy Extensions Dec 29 12:21:22 localhost IPv6 over IPv4 tunneling driver Dec 29 12:21:22 localhost NET: Registered protocol family 17 Dec 29 12:21:22 localhost NET: Registered protocol family 15 Dec 29 12:21:22 localhost powernow-k8: Found 1 AMD Athlon 64 / Opteron processors (version 1.50.4) Dec 29 12:21:22 localhost powernow-k8: BIOS error - no PSB or ACPI _PSS objects Dec 29 12:21:22 localhost BIOS EDD facility v0.16 2004-Jun-25, 1 devices found Dec 29 12:21:22 localhost Freeing unused kernel memory: 196k freed Dec 29 12:21:22 localhost ehci_hcd 0000:00:13.2: EHCI Host Controller Dec 29 12:21:22 localhost ehci_hcd 0000:00:13.2: new USB bus registered, assigned bus number 1 Dec 29 12:21:22 localhost ehci_hcd 0000:00:13.2: irq 10, io mem 0xfe02b000 Dec 29 12:21:22 localhost ehci_hcd 0000:00:13.2: USB 2.0 started, EHCI 1.00, driver 10 Dec 2004 Dec 29 12:21:22 localhost hub 1-0:1.0: USB hub found Dec 29 12:21:22 localhost hub 1-0:1.0: 8 ports detected Dec 29 12:21:22 localhost input: AT Translated Set 2 keyboard as /class/input/input0 Dec 29 12:21:22 localhost usb 1-7: new high speed USB device using ehci_hcd and address 3 Dec 29 12:21:22 localhost usbcore: registered new driver hiddev Dec 29 12:21:22 localhost usbcore: registered new driver usbhid Dec 29 12:21:22 localhost drivers/usb/input/hid-core.c: v2.6:USB HID core driver Dec 29 12:21:22 localhost Initializing USB Mass Storage driver... Dec 29 12:21:22 localhost scsi0 : SCSI emulation for USB Mass Storage devices Dec 29 12:21:22 localhost usbcore: registered new driver usb-storage Dec 29 12:21:22 localhost USB Mass Storage support registered. Dec 29 12:21:22 localhost usb-storage: device found at 3 Dec 29 12:21:22 localhost usb-storage: waiting for device to settle before scanning Dec 29 12:21:22 localhost USB Universal Host Controller Interface driver v2.3 Dec 29 12:21:22 localhost ohci_hcd: 2005 April 22 USB 1.1 'Open' Host Controller (OHCI) Driver (PCI) Dec 29 12:21:22 localhost ohci_hcd 0000:00:13.0: OHCI Host Controller Dec 29 12:21:22 localhost ohci_hcd 0000:00:13.0: new USB bus registered, assigned bus number 2 Dec 29 12:21:22 localhost ohci_hcd 0000:00:13.0: irq 10, io mem 0xfe02d000 Dec 29 12:21:22 localhost hub 2-0:1.0: USB hub found Dec 29 12:21:22 localhost hub 2-0:1.0: 4 ports detected Dec 29 12:21:22 localhost ohci_hcd 0000:00:13.1: OHCI Host Controller Dec 29 12:21:22 localhost ohci_hcd 0000:00:13.1: new USB bus registered, assigned bus number 3 Dec 29 12:21:22 localhost ohci_hcd 0000:00:13.1: irq 10, io mem 0xfe02c000 Dec 29 12:21:22 localhost hub 3-0:1.0: USB hub found Dec 29 12:21:22 localhost hub 3-0:1.0: 4 ports detected Dec 29 12:21:22 localhost libata version 1.20 loaded. Dec 29 12:21:22 localhost sata_sil 0000:00:11.0: version 0.9 Dec 29 12:21:22 localhost ata1: SATA max UDMA/100 cmd 0xFFFFC2000054E080 ctl 0xFFFFC2000054E08A bmdma 0xFF FFC2000054E000 irq 10 Dec 29 12:21:22 localhost ata2: SATA max UDMA/100 cmd 0xFFFFC2000054E0C0 ctl 0xFFFFC2000054E0CA bmdma 0xFF FFC2000054E008 irq 10 Dec 29 12:21:22 localhost ata1: no device found (phy stat 00000000) Dec 29 12:21:22 localhost scsi1 : sata_sil Dec 29 12:21:22 localhost ata2: no device found (phy stat 00000000) Dec 29 12:21:22 localhost scsi2 : sata_sil Dec 29 12:21:22 localhost ata3: SATA max UDMA/100 cmd 0xFFFFC20000550080 ctl 0xFFFFC2000055008A bmdma 0xFF FFC20000550000 irq 11 Dec 29 12:21:22 localhost ata4: SATA max UDMA/100 cmd 0xFFFFC200005500C0 ctl 0xFFFFC200005500CA bmdma 0xFF FFC20000550008 irq 11 Dec 29 12:21:22 localhost usb 3-1: new low speed USB device using ohci_hcd and address 2 Dec 29 12:21:22 localhost ata3: dev 0 cfg 49:2f00 82:746b 83:7f01 84:4023 85:7468 86:3c01 87:4023 88:40ff Dec 29 12:21:22 localhost ata3: dev 0 ATA-7, max UDMA7, 390721968 sectors: LBA48 Dec 29 12:21:22 localhost ata3: dev 0 configured for UDMA/100 Dec 29 12:21:22 localhost scsi3 : sata_sil Dec 29 12:21:22 localhost input: Microsoft Microsoft 3-Button Mouse with IntelliEye(TM) as /class/input/in put1 Dec 29 12:21:22 localhost input: USB HID v1.10 Mouse [Microsoft Microsoft 3-Button Mouse with IntelliEye(M)] on usb-0000:00:13.1-1 Dec 29 12:21:22 localhost ata4: no device found (phy stat 00000000) Dec 29 12:21:22 localhost scsi4 : sata_sil Dec 29 12:21:22 localhost Vendor: ATA Model: SAMSUNG SP2004C Rev: VM10 Dec 29 12:21:22 localhost Type: Direct-Access ANSI SCSI revision: 05 Dec 29 12:21:22 localhost SCSI device sda: 390721968 512-byte hdwr sectors (200050 MB) Dec 29 12:21:22 localhost SCSI device sda: drive cache: write back Dec 29 12:21:22 localhost SCSI device sda: 390721968 512-byte hdwr sectors (200050 MB) Dec 29 12:21:22 localhost SCSI device sda: drive cache: write back Dec 29 12:21:22 localhost sda: sda1 sda2 sda3 < sda5 sda6 > sda4 Dec 29 12:21:22 localhost sd 3:0:0:0: Attached scsi disk sda Dec 29 12:21:22 localhost sd 3:0:0:0: Attached scsi generic sg0 type 0 Dec 29 12:21:22 localhost ReiserFS: sda5: warning: sh-2021: reiserfs_fill_super: can not find reiserfs on sda5 Dec 29 12:21:22 localhost kjournald starting. Commit interval 5 seconds Dec 29 12:21:22 localhost EXT3-fs warning: maximal mount count reached, running e2fsck is recommended Dec 29 12:21:22 localhost EXT3 FS on sda5, internal journal Dec 29 12:21:22 localhost EXT3-fs: mounted filesystem with ordered data mode. Dec 29 12:21:22 localhost Vendor: USB2.0 Model: CF CardReader Rev: Dec 29 12:21:22 localhost Type: Direct-Access ANSI SCSI revision: 00 Dec 29 12:21:22 localhost sd 0:0:0:0: Attached scsi removable disk sdb Dec 29 12:21:22 localhost sd 0:0:0:0: Attached scsi generic sg1 type 0 Dec 29 12:21:22 localhost Vendor: USB2.0 Model: CBO CardReader Rev: Dec 29 12:21:22 localhost Type: Direct-Access ANSI SCSI revision: 00 Dec 29 12:21:22 localhost sd 0:0:0:1: Attached scsi removable disk sdc Dec 29 12:21:22 localhost sd 0:0:0:1: Attached scsi generic sg2 type 0 Dec 29 12:21:22 localhost usb-storage: device scan complete Dec 29 12:21:22 localhost Adding 2811332k swap on /dev/sda6. Priority:-1 extents:1 across:2811332k Dec 29 12:21:22 localhost EXT3-fs warning: maximal mount count reached, running e2fsck is recommended Dec 29 12:21:22 localhost EXT3 FS on sda5, internal journal Dec 29 12:21:22 localhost kjournald starting. Commit interval 5 seconds Dec 29 12:21:22 localhost EXT3-fs warning: maximal mount count reached, running e2fsck is recommended Dec 29 12:21:22 localhost EXT3 FS on sda4, internal journal Dec 29 12:21:22 localhost EXT3-fs: mounted filesystem with ordered data mode. Dec 29 12:21:22 localhost kjournald starting. Commit interval 5 seconds Dec 29 12:21:22 localhost EXT3-fs warning: mounting unchecked fs, running e2fsck is recommended Dec 29 12:21:22 localhost EXT3 FS on sda2, internal journal Dec 29 12:21:22 localhost EXT3-fs: mounted filesystem with ordered data mode. Dec 29 12:21:22 localhost NTFS driver 2.1.25 [Flags: R/O MODULE]. Dec 29 12:21:22 localhost NTFS volume version 3.1. Dec 29 12:21:22 localhost time.c: Lost 13 timer tick(s)! rip 0x422243) Dec 29 12:21:22 localhost 8139too Fast Ethernet driver 0.9.27 Dec 29 12:21:22 localhost eth0: RealTek RTL8139 at 0xffffc200006e0000, 00:13:d3:2e:29:c6, IRQ 5 Dec 29 12:21:22 localhost eth0: Identified 8139 chip type 'RTL-8100B/8139D' Dec 29 12:21:22 localhost eth0: link up, 100Mbps, full-duplex, lpa 0x41E1 Dec 29 12:21:22 localhost smsc47m1: Found SMSC LPC47M15x/LPC47M192/LPC47M997 Dec 29 12:21:23 localhost time.c: Lost 15 timer tick(s)! rip default_idle+0x34/0x72) Dec 29 12:21:23 localhost sshd[8292]: Server listening on :: port 22. Dec 29 12:21:23 localhost sshd[8292]: error: Bind to port 22 on 0.0.0.0 failed: Address already in use. Dec 29 12:21:23 localhost cron[8361]: (CRON) STARTUP (V5.0) Dec 29 12:21:25 localhost xfs: CONFIG: unknown parameter "cache-hi-mark" Dec 29 12:21:25 localhost xfs: CONFIG: unknown parameter "cache-low-mark" Dec 29 12:21:25 localhost xfs: CONFIG: unknown parameter "cache-balance" Dec 29 12:21:25 localhost xfs[8524]: ignoring font path element /usr/share/fonts/freefont (unreadable) Dec 29 12:21:25 localhost init: Activating demand-procedures for 'A' Dec 29 12:21:25 localhost eth0: no IPv6 routers present Dec 29 12:21:34 localhost time.c: Lost 13 timer tick(s)! rip 0x2aaaad69aa10) Dec 29 12:21:35 localhost time.c: Lost 15 timer tick(s)! rip default_idle+0x34/0x72) Dec 29 12:21:37 localhost gdm(pam_unix)[8724]: session opened for user raph by (uid=0) Dec 29 12:21:45 localhost su[8784]: + pts/1 raph:root Dec 29 12:21:45 localhost su(pam_unix)[8784]: session opened for user root by (uid=1000) Dec 29 12:21:50 localhost time.c: Lost 6 timer tick(s)! rip default_idle+0x34/0x72) Dec 29 12:21:51 localhost UDF-fs INFO UDF 0.9.8.1 (2004/29/09) Mounting volume 'P9161DVD', timestamp 2005/ 01/24 14:39 (1000) Dec 29 12:21:54 localhost time.c: Lost 6 timer tick(s)! rip default_idle+0x34/0x72) Dec 29 12:21:54 localhost time.c: Lost 4 timer tick(s)! rip default_idle+0x34/0x72) Dec 29 12:21:54 localhost time.c: Lost 5 timer tick(s)! rip default_idle+0x34/0x72) Dec 29 12:21:54 localhost time.c: Lost 6 timer tick(s)! rip default_idle+0x34/0x72) Dec 29 12:21:54 localhost time.c: Lost 5 timer tick(s)! rip ehci_irq+0x26/0x14b [ehci_hcd]) Dec 29 12:21:54 localhost time.c: Lost 1 timer tick(s)! rip 0xac0405) Dec 29 12:21:54 localhost time.c: Lost 6 timer tick(s)! rip udf_crc+0xc/0x30 [udf]) Dec 29 12:21:54 localhost time.c: Lost 6 timer tick(s)! rip ehci_irq+0x26/0x14b [ehci_hcd]) Dec 29 12:21:54 localhost time.c: Lost 5 timer tick(s)! rip ehci_irq+0x26/0x14b [ehci_hcd]) Dec 29 12:21:54 localhost time.c: Lost 6 timer tick(s)! rip ehci_irq+0x26/0x14b [ehci_hcd]) Dec 29 12:21:55 localhost time.c: Lost 6 timer tick(s)! rip default_idle+0x34/0x72) Dec 29 12:21:55 localhost time.c: Lost 6 timer tick(s)! rip default_idle+0x34/0x72) Dec 29 12:21:55 localhost time.c: Lost 6 timer tick(s)! rip __do_softirq+0x39/0x9b) Dec 29 12:21:55 localhost time.c: Lost 6 timer tick(s)! rip __do_softirq+0x39/0x9b) Dec 29 12:21:55 localhost time.c: Lost 6 timer tick(s)! rip __do_softirq+0x39/0x9b) Dec 29 12:21:55 localhost Losing some ticks... checking if CPU frequency changed. Dec 29 12:21:55 localhost time.c: Lost 6 timer tick(s)! rip 0xac0405) Dec 29 12:21:55 localhost time.c: Lost 5 timer tick(s)! rip __do_softirq+0x39/0x9b) Dec 29 12:21:58 localhost time.c: Lost 6 timer tick(s)! rip default_idle+0x34/0x72) Dec 29 12:21:58 localhost time.c: Lost 6 timer tick(s)! rip __do_softirq+0x39/0x9b) Dec 29 12:21:58 localhost time.c: Lost 6 timer tick(s)! rip __do_softirq+0x39/0x9b) Dec 29 12:21:58 localhost time.c: Lost 6 timer tick(s)! rip __do_softirq+0x39/0x9b) Dec 29 12:21:58 localhost time.c: Lost 6 timer tick(s)! rip 0x4aa674) Dec 29 12:21:58 localhost time.c: Lost 5 timer tick(s)! rip __do_softirq+0x39/0x9b) Dec 29 12:21:58 localhost time.c: Lost 6 timer tick(s)! rip default_idle+0x34/0x72) Dec 29 12:21:58 localhost time.c: Lost 6 timer tick(s)! rip __do_softirq+0x39/0x9b) Dec 29 12:21:58 localhost time.c: Lost 6 timer tick(s)! rip __do_softirq+0x39/0x9b) Dec 29 12:21:58 localhost time.c: Lost 5 timer tick(s)! rip __do_softirq+0x39/0x9b) Dec 29 12:21:58 localhost time.c: Lost 5 timer tick(s)! rip 0xac0405) Dec 29 12:21:58 localhost time.c: Lost 6 timer tick(s)! rip __do_softirq+0x39/0x9b) Dec 29 12:21:58 localhost time.c: Lost 6 timer tick(s)! rip ehci_irq+0x0/0x14b [ehci_hcd]) Dec 29 12:21:58 localhost time.c: Lost 6 timer tick(s)! rip ehci_irq+0x0/0x14b [ehci_hcd]) Dec 29 12:21:59 localhost time.c: Lost 5 timer tick(s)! rip default_idle+0x34/0x72) Dec 29 12:21:59 localhost time.c: Lost 5 timer tick(s)! rip ehci_irq+0x26/0x14b [ehci_hcd]) Dec 29 12:21:59 localhost time.c: Lost 6 timer tick(s)! rip ehci_irq+0x26/0x14b [ehci_hcd]) Dec 29 12:21:59 localhost time.c: Lost 6 timer tick(s)! rip ehci_irq+0x26/0x14b [ehci_hcd]) Dec 29 12:21:59 localhost time.c: Lost 6 timer tick(s)! rip 0x2aaaab008855) Dec 29 12:21:59 localhost time.c: Lost 6 timer tick(s)! rip ata_interrupt+0x11e/0x132 [libata]) Dec 29 12:21:59 localhost time.c: Lost 6 timer tick(s)! rip ata_interrupt+0x11e/0x132 [libata]) Dec 29 12:21:59 localhost time.c: Lost 6 timer tick(s)! rip ata_interrupt+0x11e/0x132 [libata]) Dec 29 12:21:59 localhost time.c: Lost 6 timer tick(s)! rip mousedev_notify_readers+0x1d/0x1ac) Dec 29 12:21:59 localhost time.c: Lost 5 timer tick(s)! rip ata_interrupt+0x11e/0x132 [libata]) Dec 29 12:21:59 localhost time.c: Lost 6 timer tick(s)! rip ata_interrupt+0x11e/0x132 [libata]) Dec 29 12:21:59 localhost time.c: Lost 6 timer tick(s)! rip ata_interrupt+0x11e/0x132 [libata]) Dec 29 12:21:59 localhost time.c: Lost 6 timer tick(s)! rip 0x2aaaaad867c2) Dec 29 12:21:59 localhost time.c: Lost 3 timer tick(s)! rip ata_interrupt+0x11e/0x132 [libata]) Dec 29 12:21:59 localhost time.c: Lost 6 timer tick(s)! rip dnotify_parent+0x12/0x43) Dec 29 12:21:59 localhost time.c: Lost 6 timer tick(s)! rip ata_interrupt+0x11e/0x132 [libata]) Dec 29 12:21:59 localhost time.c: Lost 2 timer tick(s)! rip ata_interrupt+0x11e/0x132 [libata]) Dec 29 12:21:59 localhost time.c: Lost 6 timer tick(s)! rip default_idle+0x34/0x72) Dec 29 12:21:59 localhost time.c: Lost 6 timer tick(s)! rip ehci_irq+0x26/0x14b [ehci_hcd]) Dec 29 12:21:59 localhost time.c: Lost 6 timer tick(s)! rip ehci_irq+0x26/0x14b [ehci_hcd]) Dec 29 12:21:59 localhost time.c: Lost 6 timer tick(s)! rip ehci_irq+0x26/0x14b [ehci_hcd]) Dec 29 12:21:59 localhost time.c: Lost 6 timer tick(s)! rip 0x40e7a6) Dec 29 12:21:59 localhost time.c: Lost 6 timer tick(s)! rip ehci_irq+0x26/0x14b [ehci_hcd]) Dec 29 12:21:59 localhost time.c: Lost 6 timer tick(s)! rip ehci_irq+0x26/0x14b [ehci_hcd]) Dec 29 12:21:59 localhost time.c: Lost 5 timer tick(s)! rip ehci_irq+0x26/0x14b [ehci_hcd]) Dec 29 12:21:59 localhost time.c: Lost 6 timer tick(s)! rip default_idle+0x34/0x72) Dec 29 12:21:59 localhost time.c: Lost 6 timer tick(s)! rip ehci_irq+0x26/0x14b [ehci_hcd]) Dec 29 12:21:59 localhost time.c: Lost 6 timer tick(s)! rip ehci_irq+0x26/0x14b [ehci_hcd]) Dec 29 12:21:59 localhost time.c: Lost 6 timer tick(s)! rip ehci_irq+0x26/0x14b [ehci_hcd]) Dec 29 12:21:59 localhost time.c: Lost 6 timer tick(s)! rip tty_poll+0x2/0x6d) Dec 29 12:21:59 localhost time.c: Lost 5 timer tick(s)! rip ehci_irq+0x26/0x14b [ehci_hcd]) Dec 29 12:21:59 localhost time.c: Lost 6 timer tick(s)! rip ehci_irq+0x26/0x14b [ehci_hcd]) Dec 29 12:21:59 localhost time.c: Lost 6 timer tick(s)! rip ehci_irq+0x26/0x14b [ehci_hcd]) Dec 29 12:22:00 localhost time.c: Lost 5 timer tick(s)! rip default_idle+0x34/0x72) [...] Raphael
Please post the contents of /proc/interrupts Have you been able to copy files from this dvd on other computers? Is it only one dvd that is affected, or does it happen to every DVD that you try?
Well.. I am completely mystified. Despite originally testing on 2 DVDs repeatedley, today I can't reproduce the problem at all (on the same DVDs). First, what still happens: a) I still get Losing some ticks... checking if CPU frequency changed. warning: many lost ticks. Your time source seems to be instable or some driver is hogging interupts rip handle_IRQ_event+0x20/0x60 b) The system still slows down as to be almost unusable (for example thunderbird didn't start up in the 5 minutes I waited for it). What's new: c) The read errors have gone d) The whole disk gets ripped if you leave it long enough. I am going away for a couple of weeks and will try to get to the bottom of this when I return. Raphael P.S. Kernel options used today Bootdata ok (command line is root=/dev/ram0 init=/linuxrc ramdisk=8192 real_root =/dev/sda5 udev acpi=noirq noapic) Linux version 2.6.14-gentoo-r5 (root@localhost) (gcc version 3.4.4 (Gentoo 3.4.4 -r1, ssp-3.4.4-1.0, pie-8.7.8)) #1 Wed Dec 28 14:47:24 GMT 2005 cat /proc/interrupts CPU0 0: 171087 XT-PIC timer 1: 2745 XT-PIC i8042 2: 0 XT-PIC cascade 3: 1 XT-PIC ATI IXP 5: 1316 XT-PIC eth0 8: 0 XT-PIC rtc 10: 3178 XT-PIC ehci_hcd:usb1, ohci_hcd:usb2, ohci_hcd:usb3, libata 11: 13454 XT-PIC ohci1394, libata 12: 112 XT-PIC i8042 14: 35406 XT-PIC ide0 NMI: 847 LOC: 153641 ERR: 42 MIS: 0 ACPI and APIC are off in the BIOS. (The mobo won't boot with ACPI or APIC on. To be more precise, noapictimer seems to let the system boot on the APIC end but ACPI seems to have to be off completely.)
OK, there appear to be at least three or four problems (b and c may be the same problem). a) The inability of libdvdcss to correctly read some copy protected dvds b) Errors such as Dec 28 15:56:05 localhost warning: many lost ticks. Dec 28 15:56:05 localhost Your time source seems to be instable or some driver is hogging interupts Dec 28 15:56:05 localhost rip default_idle+0x20/0x30 c) Massive interrupt problems when ripping. As demonstrated by the slowness of the computer. I notice that "top" reads like this too while ripping The Cpu(s): 22.4% us, 10.3% sy, 0.0% ni, 0.0% id, 6.9% wa, 55.2% hi, 5.2% si or Cpu(s): 16.4% us, 9.1% sy, 0.0% ni, 0.0% id, 10.9% wa, 34.5% hi, 29.1% si The "top" man page says nothing about "hi" and "si" but I assume they are hardware and software interrupts? d) The inability to boot at all without noapictimer and ACPI off. Let's ignore a) for the time being :) Shall I submit separate bug reports for b-d? Raphael
Yes, please do. Please also confirm that the problems still exist on the latest development kernel (currently 2.6.16-rc1)
Only error b) is still current. All others are fixed either by a kernel upgrade or compiling in the ATI IXP ide driver and turning on dma for the dvd drive. Raphael
