When attempting to rip a dvd the system slows down drastically and appears to be suffering an interrupt storm. (See boot prompt arguments below and http://bugs.gentoo.org/show_bug.cgi?id=119833 for another problem on the same system,) Command line: mencoder dvd://3 -oac copy -ovc copy -o testdvd.avi (mounting the dvd then running vobcopy produces much the same result + errors/warnings) Symptoms: 1) Typing lags a second behind. Mouse movement is jerky. Everything appears much slower 2) Typical "top" outputs are Cpu(s): 24.6% us, 6.6% sy, 0.0% ni, 0.0% id, 9.8% wa, 4.9% hi, 54.1% si Cpu(s): 21.4% us, 8.9% sy, 0.0% ni, 0.0% id, 12.5% wa, 42.9% hi, 14.3% si Cpu(s): 25.0% us, 7.1% sy, 0.0% ni, 0.0% id, 7.1% wa, 28.6% hi, 32.1% si 3) dmesg fills up with hundreds of messages like Jan 21 17:03:45 localhost time.c: Lost 6 timer tick(s)! rip default_idle+0x2a/0x52) Jan 21 17:03:45 localhost time.c: Lost 6 timer tick(s)! rip default_idle+0x2a/0x52) Jan 21 17:03:45 localhost time.c: Lost 6 timer tick(s)! rip default_idle+0x2a/0x52) Jan 21 17:03:45 localhost time.c: Lost 6 timer tick(s)! rip default_idle+0x2a/0x52) Jan 21 17:03:45 localhost time.c: Lost 6 timer tick(s)! rip __do_softirq+0x39/0x9b) Jan 21 17:03:45 localhost time.c: Lost 2 timer tick(s)! rip 0x1207b52) Jan 21 17:03:48 localhost time.c: Lost 5 timer tick(s)! rip default_idle+0x2a/0x52) Jan 21 17:03:48 localhost time.c: Lost 5 timer tick(s)! rip __do_softirq+0x39/0x9b) Jan 21 17:03:48 localhost Losing some ticks... checking if CPU frequency changed. and Jan 21 17:03:53 localhost time.c: Lost 6 timer tick(s)! rip ehci_irq+0x15/0x141 [ehci_hcd]) Jan 21 17:03:53 localhost time.c: Lost 5 timer tick(s)! rip 0x4ce45b) Jan 21 17:03:53 localhost time.c: Lost 6 timer tick(s)! rip 0x54e09e) Jan 21 17:03:53 localhost time.c: Lost 5 timer tick(s)! rip ehci_irq+0x26/0x141 [ehci_hcd]) and eventually (after a few minutes) Jan 21 17:03:59 localhost time.c: Lost 6 timer tick(s)! rip ata_interrupt+0x126/0x13a [libata]) (some more examples) Jan 21 17:04:43 localhost time.c: Lost 5 timer tick(s)! rip __mod_timer+0x7c/0x85) Jan 21 17:04:43 localhost time.c: Lost 6 timer tick(s)! rip __mod_timer+0x7c/0x85) Jan 21 17:04:43 localhost time.c: Lost 6 timer tick(s)! rip __mod_timer+0x7c/0x85) Jan 21 17:04:43 localhost time.c: Lost 6 timer tick(s)! rip 0x83a5991) This also appears in dmesg Jan 21 17:04:22 localhost warning: many lost ticks. Jan 21 17:04:22 localhost Your time source seems to be instable or some driver is hogging interupts Jan 21 17:04:22 localhost rip __mod_timer+0x7c/0x85 Debug info to follow --------- emerge --info ------------------ emerge --info Portage 2.1_pre2 (default-linux/amd64/2005.1, gcc-3.4.4, glibc-2.3.5-r3, 2.6.16-rc1 x86_64) ================================================================= System uname: 2.6.16-rc1 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 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 gphoto2 gpm gstreamer gtk gtk2 gtkhtml 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 sqlite ssl subtitles svg tcpd tetex theora tiff truetype truetype-fonts type1-fonts udev unicode usb userlocales vorbis wxgtk1 wxwindows xine xml xml2 xmms xpm xv xvid yahoo zlib elibc_glibc kernel_linux userland_GNU" Unset: ASFLAGS, CTARGET, LANG, LC_ALL, LDFLAGS, LINGUAS, MAKEOPTS ---------lspci -------------------- 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) --------- cat /proc/interrupts -------------- cat /proc/interrupts CPU0 0: 907829 XT-PIC timer 1: 10552 XT-PIC i8042 2: 0 XT-PIC cascade 3: 1 XT-PIC ATI IXP 5: 7222 XT-PIC eth0 8: 0 XT-PIC rtc 10: 46070 XT-PIC ehci_hcd:usb1, ohci_hcd:usb2, ohci_hcd:usb3, libata 11: 19970 XT-PIC libata 14: 12436 XT-PIC ide0 NMI: 676 LOC: 0 ERR: 32 MIS: 0 --------------- dmesg ------------------ Jan 21 16:15:51 localhost syslog-ng[7965]: syslog-ng version 1.6.8 starting Jan 21 16:15:51 localhost syslog-ng[7965]: Changing permissions on special file /dev/tty12 Jan 21 16:15:51 localhost Bootdata ok (command line is root=/dev/ram0 init=/linuxrc ramdisk=8192 real_root=/dev/sda5 udev report_lost_ticks=100 noapictimer no_timer_check acpi=off) Jan 21 16:15:51 localhost Linux version 2.6.16-rc1 (root@localhost) (gcc version 3.4.4 (Gentoo 3.4.4-r1, ssp-3.4.4-1.0, pie-8.7.8)) #1 Sat Jan 21 14:56:38 GMT 2006 Jan 21 16:15:51 localhost BIOS-provided physical RAM map: Jan 21 16:15:51 localhost BIOS-e820: 0000000000000000 - 000000000009f800 (usable) Jan 21 16:15:51 localhost BIOS-e820: 000000000009f800 - 00000000000a0000 (reserved) Jan 21 16:15:51 localhost BIOS-e820: 00000000000f0000 - 0000000000100000 (reserved) Jan 21 16:15:51 localhost BIOS-e820: 0000000000100000 - 000000003bef0000 (usable) Jan 21 16:15:51 localhost BIOS-e820: 000000003bef0000 - 000000003bef3000 (ACPI NVS) Jan 21 16:15:51 localhost BIOS-e820: 000000003bef3000 - 000000003bf00000 (ACPI data) Jan 21 16:15:51 localhost BIOS-e820: 00000000e0000000 - 00000000f0000000 (reserved) Jan 21 16:15:51 localhost BIOS-e820: 00000000fec00000 - 0000000100000000 (reserved) Jan 21 16:15:51 localhost On node 0 totalpages: 240915 Jan 21 16:15:51 localhost DMA zone: 2823 pages, LIFO batch:0 Jan 21 16:15:51 localhost DMA32 zone: 238092 pages, LIFO batch:31 Jan 21 16:15:51 localhost Normal zone: 0 pages, LIFO batch:0 Jan 21 16:15:51 localhost HighMem zone: 0 pages, LIFO batch:0 Jan 21 16:15:51 localhost Intel MultiProcessor Specification v1.4 Jan 21 16:15:51 localhost Virtual Wire compatibility mode. Jan 21 16:15:51 localhost OEM ID: OEM00000 <6>Product ID: PROD00000000 <6>APIC at: 0xFEE00000 Jan 21 16:15:51 localhost Processor #0 6:8 APIC version 17 Jan 21 16:15:51 localhost I/O APIC #2 Version 17 at 0xFEC00000. Jan 21 16:15:51 localhost Setting APIC routing to flat Jan 21 16:15:51 localhost Processors: 1 Jan 21 16:15:51 localhost Allocating PCI resources starting at 40000000 (gap: 3bf00000:a4100000) Jan 21 16:15:51 localhost Checking aperture... Jan 21 16:15:51 localhost CPU 0: aperture @ 258a000000 size 32 MB Jan 21 16:15:51 localhost Aperture from northbridge cpu 0 too small (32 MB) Jan 21 16:15:51 localhost No AGP bridge found Jan 21 16:15:51 localhost Built 1 zonelists Jan 21 16:15:51 localhost Kernel command line: root=/dev/ram0 init=/linuxrc ramdisk=8192 real_root=/dev/sda5 udev report_lost_ticks=100 noapictimer no_timer_check acpi=off Jan 21 16:15:51 localhost Initializing CPU#0 Jan 21 16:15:51 localhost PID hash table entries: 4096 (order: 12, 131072 bytes) Jan 21 16:15:51 localhost time.c: Using 1.193182 MHz PIT timer. Jan 21 16:15:51 localhost time.c: Detected 1790.870 MHz processor. Jan 21 16:15:51 localhost time.c: Using PIT/TSC based timekeeping. Jan 21 16:15:51 localhost Console: colour VGA+ 80x25 Jan 21 16:15:51 localhost Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes) Jan 21 16:15:51 localhost Inode-cache hash table entries: 65536 (order: 7, 524288 bytes) Jan 21 16:15:51 localhost PCI-DMA: Disabling IOMMU. Jan 21 16:15:51 localhost Memory: 960000k/981952k available (2786k kernel code, 21036k reserved, 1087k data, 200k init) Jan 21 16:15:51 localhost Calibrating delay using timer specific routine.. 3589.36 BogoMIPS (lpj=7178725) Jan 21 16:15:51 localhost Mount-cache hash table entries: 256 Jan 21 16:15:51 localhost CPU: L1 I Cache: 64K (64 bytes/line), D cache 64K (64 bytes/line) Jan 21 16:15:51 localhost CPU: L2 Cache: 512K (64 bytes/line) Jan 21 16:15:51 localhost mtrr: v2.0 (20020519) Jan 21 16:15:51 localhost CPU: AMD Athlon(tm) 64 Processor 3000+ stepping 00 Jan 21 16:15:51 localhost Disabling APIC timer Jan 21 16:15:51 localhost testing NMI watchdog ... OK. Jan 21 16:15:51 localhost checking if image is initramfs... it is Jan 21 16:15:51 localhost Freeing initrd memory: 1433k freed Jan 21 16:15:51 localhost DMI 2.4 present. Jan 21 16:15:51 localhost NET: Registered protocol family 16 Jan 21 16:15:51 localhost PCI: Using configuration type 1 Jan 21 16:15:51 localhost ACPI: Subsystem revision 20050902 Jan 21 16:15:51 localhost ACPI: Interpreter disabled. Jan 21 16:15:51 localhost SCSI subsystem initialized Jan 21 16:15:51 localhost usbcore: registered new driver usbfs Jan 21 16:15:51 localhost usbcore: registered new driver hub Jan 21 16:15:51 localhost PCI: Probing PCI hardware Jan 21 16:15:51 localhost PCI: Probing PCI hardware (bus 00) Jan 21 16:15:51 localhost PCI: Ignoring BAR0-3 of IDE controller 0000:00:14.1 Jan 21 16:15:51 localhost Boot video device is 0000:01:05.0 Jan 21 16:15:51 localhost PCI: Transparent bridge - 0000:00:14.4 Jan 21 16:15:51 localhost PCI: Cannot allocate resource region 3 of device 0000:00:00.0 Jan 21 16:15:51 localhost TC classifier action (bugs to netdev@vger.kernel.org cc hadi@cyberus.ca) Jan 21 16:15:51 localhost PCI: Bridge: 0000:00:01.0 Jan 21 16:15:51 localhost IO window: e000-efff Jan 21 16:15:51 localhost MEM window: fdd00000-fddfffff Jan 21 16:15:51 localhost PREFETCH window: d8000000-dfffffff Jan 21 16:15:51 localhost PCI: Bridge: 0000:00:14.4 Jan 21 16:15:51 localhost IO window: d000-dfff Jan 21 16:15:51 localhost MEM window: fdc00000-fdcfffff Jan 21 16:15:51 localhost PREFETCH window: fde00000-fdefffff Jan 21 16:15:51 localhost IA32 emulation $Id: sys_ia32.c,v 1.32 2002/03/24 13:02:28 ak Exp $ Jan 21 16:15:51 localhost Total HugeTLB memory allocated, 0 Jan 21 16:15:51 localhost JFS: nTxBlock = 7515, nTxLock = 60122 Jan 21 16:15:51 localhost SGI XFS with ACLs, large block/inode numbers, no debug enabled Jan 21 16:15:51 localhost Initializing Cryptographic API Jan 21 16:15:51 localhost io scheduler noop registered Jan 21 16:15:51 localhost io scheduler anticipatory registered Jan 21 16:15:51 localhost io scheduler deadline registered Jan 21 16:15:51 localhost io scheduler cfq registered Jan 21 16:15:51 localhost time.c: Lost 2 timer tick(s)! rip msleep+0x3/0x3a) Jan 21 16:15:51 localhost time.c: Lost 2 timer tick(s)! rip msleep+0x3/0x3a) Jan 21 16:15:51 localhost pci_hotplug: PCI Hot Plug PCI Core version: 0.5 Jan 21 16:15:51 localhost acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Jan 21 16:15:51 localhost Real Time Clock Driver v1.12ac Jan 21 16:15:51 localhost Non-volatile memory driver v1.2 Jan 21 16:15:51 localhost Linux agpgart interface v0.101 (c) Dave Jones Jan 21 16:15:51 localhost serio: i8042 AUX port at 0x60,0x64 irq 12 Jan 21 16:15:51 localhost serio: i8042 KBD port at 0x60,0x64 irq 1 Jan 21 16:15:51 localhost Serial: 8250/16550 driver $Revision: 1.90 $ 4 ports, IRQ sharing disabled Jan 21 16:15:51 localhost serial8250: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A Jan 21 16:15:51 localhost RAMDISK driver initialized: 16 RAM disks of 8192K size 1024 blocksize Jan 21 16:15:51 localhost loop: loaded (max 8 devices) Jan 21 16:15:51 localhost Uniform Multi-Platform E-IDE driver Revision: 7.00alpha2 Jan 21 16:15:51 localhost ide: Assuming 33MHz system bus speed for PIO modes; override with idebus=xx Jan 21 16:15:51 localhost Probing IDE interface ide0... Jan 21 16:15:51 localhost spurious 8259A interrupt: IRQ7. Jan 21 16:15:51 localhost hda: BENQ DVD DD DW1640, ATAPI CD/DVD-ROM drive Jan 21 16:15:51 localhost Probing IDE interface ide1... Jan 21 16:15:51 localhost ide0 at 0x1f0-0x1f7,0x3f6 on irq 14 Jan 21 16:15:51 localhost hda: ATAPI 94X DVD-ROM DVD-R CD-R/RW drive, 2048kB Cache Jan 21 16:15:51 localhost Uniform CD-ROM driver Revision: 3.20 Jan 21 16:15:51 localhost usbmon: debugfs is not available Jan 21 16:15:51 localhost mice: PS/2 mouse device common for all mice Jan 21 16:15:51 localhost md: linear personality registered for level -1 Jan 21 16:15:51 localhost md: raid0 personality registered for level 0 Jan 21 16:15:51 localhost md: raid1 personality registered for level 1 Jan 21 16:15:51 localhost md: raid5 personality registered for level 5 Jan 21 16:15:51 localhost md: raid4 personality registered for level 4 Jan 21 16:15:51 localhost raid5: automatically using best checksumming function: generic_sse Jan 21 16:15:51 localhost generic_sse: 5463.000 MB/sec Jan 21 16:15:51 localhost raid5: using function: generic_sse (5463.000 MB/sec) Jan 21 16:15:51 localhost raid6: int64x1 1668 MB/s Jan 21 16:15:51 localhost raid6: int64x2 2081 MB/s Jan 21 16:15:51 localhost raid6: int64x4 2231 MB/s Jan 21 16:15:51 localhost raid6: int64x8 1629 MB/s Jan 21 16:15:51 localhost raid6: sse2x1 2206 MB/s Jan 21 16:15:51 localhost raid6: sse2x2 2918 MB/s Jan 21 16:15:51 localhost raid6: sse2x4 3347 MB/s Jan 21 16:15:51 localhost raid6: using algorithm sse2x4 (3347 MB/s) Jan 21 16:15:51 localhost md: raid6 personality registered for level 6 Jan 21 16:15:51 localhost md: multipath personality registered for level -4 Jan 21 16:15:51 localhost md: md driver 0.90.3 MAX_MD_DEVS=256, MD_SB_DISKS=27 Jan 21 16:15:51 localhost md: bitmap version 4.39 Jan 21 16:15:51 localhost device-mapper: 4.5.0-ioctl (2005-10-04) initialised: dm-devel@redhat.com Jan 21 16:15:51 localhost NET: Registered protocol family 2 Jan 21 16:15:51 localhost IP route cache hash table entries: 32768 (order: 6, 262144 bytes) Jan 21 16:15:51 localhost TCP established hash table entries: 131072 (order: 8, 1048576 bytes) Jan 21 16:15:51 localhost TCP bind hash table entries: 65536 (order: 7, 524288 bytes) Jan 21 16:15:51 localhost TCP: Hash tables configured (established 131072 bind 65536) Jan 21 16:15:51 localhost TCP reno registered Jan 21 16:15:51 localhost TCP bic registered Jan 21 16:15:51 localhost NET: Registered protocol family 1 Jan 21 16:15:51 localhost NET: Registered protocol family 10 Jan 21 16:15:51 localhost lo: Disabled Privacy Extensions Jan 21 16:15:51 localhost IPv6 over IPv4 tunneling driver Jan 21 16:15:51 localhost NET: Registered protocol family 17 Jan 21 16:15:51 localhost NET: Registered protocol family 15 Jan 21 16:15:51 localhost powernow-k8: Found 1 AMD Athlon 64 / Opteron processors (version 1.60.0) Jan 21 16:15:51 localhost powernow-k8: 0 : fid 0x2 (1000 MHz), vid 0x12 (1100 mV) Jan 21 16:15:51 localhost powernow-k8: 1 : fid 0xa (1800 MHz), vid 0x6 (1400 mV) Jan 21 16:15:51 localhost cpu_init done, current fid 0xa, vid 0x6 Jan 21 16:15:51 localhost BIOS EDD facility v0.16 2004-Jun-25, 1 devices found Jan 21 16:15:51 localhost Freeing unused kernel memory: 200k freed Jan 21 16:15:51 localhost ehci_hcd 0000:00:13.2: EHCI Host Controller Jan 21 16:15:51 localhost ehci_hcd 0000:00:13.2: new USB bus registered, assigned bus number 1 Jan 21 16:15:51 localhost ehci_hcd 0000:00:13.2: irq 10, io mem 0xfe02b000 Jan 21 16:15:51 localhost ehci_hcd 0000:00:13.2: USB 2.0 started, EHCI 1.00, driver 10 Dec 2004 Jan 21 16:15:51 localhost usb usb1: configuration #1 chosen from 1 choice Jan 21 16:15:51 localhost hub 1-0:1.0: USB hub found Jan 21 16:15:51 localhost hub 1-0:1.0: 8 ports detected Jan 21 16:15:51 localhost input: AT Translated Set 2 keyboard as /class/input/input0 Jan 21 16:15:51 localhost usb 1-7: new high speed USB device using ehci_hcd and address 4 Jan 21 16:15:51 localhost usb 1-7: configuration #1 chosen from 1 choice Jan 21 16:15:51 localhost usbcore: registered new driver hiddev Jan 21 16:15:51 localhost usbcore: registered new driver usbhid Jan 21 16:15:51 localhost drivers/usb/input/hid-core.c: v2.6:USB HID core driver Jan 21 16:15:51 localhost Initializing USB Mass Storage driver... Jan 21 16:15:51 localhost scsi0 : SCSI emulation for USB Mass Storage devices Jan 21 16:15:51 localhost usbcore: registered new driver usb-storage Jan 21 16:15:51 localhost USB Mass Storage support registered. Jan 21 16:15:51 localhost usb-storage: device found at 4 Jan 21 16:15:51 localhost usb-storage: waiting for device to settle before scanning Jan 21 16:15:51 localhost USB Universal Host Controller Interface driver v2.3 Jan 21 16:15:51 localhost ohci_hcd: 2005 April 22 USB 1.1 'Open' Host Controller (OHCI) Driver (PCI) Jan 21 16:15:51 localhost ohci_hcd 0000:00:13.0: OHCI Host Controller Jan 21 16:15:51 localhost ohci_hcd 0000:00:13.0: new USB bus registered, assigned bus number 2 Jan 21 16:15:51 localhost ohci_hcd 0000:00:13.0: irq 10, io mem 0xfe02d000 Jan 21 16:15:51 localhost usb usb2: configuration #1 chosen from 1 choice Jan 21 16:15:51 localhost hub 2-0:1.0: USB hub found Jan 21 16:15:51 localhost hub 2-0:1.0: 4 ports detected Jan 21 16:15:51 localhost ohci_hcd 0000:00:13.1: OHCI Host Controller Jan 21 16:15:51 localhost ohci_hcd 0000:00:13.1: new USB bus registered, assigned bus number 3 Jan 21 16:15:51 localhost ohci_hcd 0000:00:13.1: irq 10, io mem 0xfe02c000 Jan 21 16:15:51 localhost usb usb3: configuration #1 chosen from 1 choice Jan 21 16:15:51 localhost hub 3-0:1.0: USB hub found Jan 21 16:15:51 localhost hub 3-0:1.0: 4 ports detected Jan 21 16:15:51 localhost libata version 1.20 loaded. Jan 21 16:15:51 localhost sata_sil 0000:00:11.0: version 0.9 Jan 21 16:15:51 localhost ata1: SATA max UDMA/100 cmd 0xFFFFC20000550080 ctl 0xFFFFC2000055008A bmdma 0xFFFFC20000550000 irq 10 Jan 21 16:15:51 localhost ata2: SATA max UDMA/100 cmd 0xFFFFC200005500C0 ctl 0xFFFFC200005500CA bmdma 0xFFFFC20000550008 irq 10 Jan 21 16:15:51 localhost ata1: SATA link down (SStatus 0) Jan 21 16:15:51 localhost scsi1 : sata_sil Jan 21 16:15:51 localhost ata2: SATA link down (SStatus 0) Jan 21 16:15:51 localhost scsi2 : sata_sil Jan 21 16:15:51 localhost ata3: SATA max UDMA/100 cmd 0xFFFFC20000552080 ctl 0xFFFFC2000055208A bmdma 0xFFFFC20000552000 irq 11 Jan 21 16:15:51 localhost ata4: SATA max UDMA/100 cmd 0xFFFFC200005520C0 ctl 0xFFFFC200005520CA bmdma 0xFFFFC20000552008 irq 11 Jan 21 16:15:51 localhost ata3: SATA link up 1.5 Gbps (SStatus 113) Jan 21 16:15:51 localhost usb 2-3: new full speed USB device using ohci_hcd and address 2 Jan 21 16:15:51 localhost ata3: dev 0 cfg 49:2f00 82:746b 83:7f01 84:4023 85:7468 86:3c01 87:4023 88:40ff Jan 21 16:15:51 localhost ata3: dev 0 ATA-7, max UDMA7, 390721968 sectors: LBA48 Jan 21 16:15:51 localhost ata3: dev 0 configured for UDMA/100 Jan 21 16:15:51 localhost scsi3 : sata_sil Jan 21 16:15:51 localhost usb 2-3: configuration #1 chosen from 1 choice Jan 21 16:15:51 localhost ata4: SATA link down (SStatus 0) Jan 21 16:15:51 localhost scsi4 : sata_sil Jan 21 16:15:51 localhost Vendor: ATA Model: SAMSUNG SP2004C Rev: VM10 Jan 21 16:15:51 localhost Type: Direct-Access ANSI SCSI revision: 05 Jan 21 16:15:51 localhost SCSI device sda: 390721968 512-byte hdwr sectors (200050 MB) Jan 21 16:15:51 localhost sda: Write Protect is off Jan 21 16:15:51 localhost sda: Mode Sense: 00 3a 00 00 Jan 21 16:15:51 localhost SCSI device sda: drive cache: write back Jan 21 16:15:51 localhost SCSI device sda: 390721968 512-byte hdwr sectors (200050 MB) Jan 21 16:15:51 localhost sda: Write Protect is off Jan 21 16:15:51 localhost sda: Mode Sense: 00 3a 00 00 Jan 21 16:15:51 localhost SCSI device sda: drive cache: write back Jan 21 16:15:51 localhost sda: sda1 sda2 sda3 < sda5<6>usb 3-1: new low speed USB device using ohci_hcd and address 2 Jan 21 16:15:51 localhost sda6 > sda4 Jan 21 16:15:51 localhost sd 3:0:0:0: Attached scsi disk sda Jan 21 16:15:51 localhost sd 3:0:0:0: Attached scsi generic sg0 type 0 Jan 21 16:15:51 localhost usb 3-1: configuration #1 chosen from 1 choice Jan 21 16:15:51 localhost input: Microsoft Microsoft 3-Button Mouse with IntelliEye(TM) as /class/input/input1 Jan 21 16:15:51 localhost input: USB HID v1.10 Mouse [Microsoft Microsoft 3-Button Mouse with IntelliEye(TM)] on usb-0000:00:13.1-1 Jan 21 16:15:51 localhost ReiserFS: sda5: warning: sh-2021: reiserfs_fill_super: can not find reiserfs on sda5 Jan 21 16:15:51 localhost kjournald starting. Commit interval 5 seconds Jan 21 16:15:51 localhost EXT3-fs warning: maximal mount count reached, running e2fsck is recommended Jan 21 16:15:51 localhost EXT3 FS on sda5, internal journal Jan 21 16:15:51 localhost EXT3-fs: mounted filesystem with ordered data mode. Jan 21 16:15:51 localhost Vendor: USB2.0 Model: CF CardReader Rev: Jan 21 16:15:51 localhost Type: Direct-Access ANSI SCSI revision: 00 Jan 21 16:15:51 localhost sd 0:0:0:0: Attached scsi removable disk sdb Jan 21 16:15:51 localhost sd 0:0:0:0: Attached scsi generic sg1 type 0 Jan 21 16:15:51 localhost Vendor: USB2.0 Model: CBO CardReader Rev: Jan 21 16:15:51 localhost Type: Direct-Access ANSI SCSI revision: 00 Jan 21 16:15:51 localhost sd 0:0:0:1: Attached scsi removable disk sdc Jan 21 16:15:51 localhost sd 0:0:0:1: Attached scsi generic sg2 type 0 Jan 21 16:15:51 localhost usb-storage: device scan complete Jan 21 16:15:51 localhost Adding 2811332k swap on /dev/sda6. Priority:-1 extents:1 across:2811332k Jan 21 16:15:51 localhost EXT3-fs warning: maximal mount count reached, running e2fsck is recommended Jan 21 16:15:51 localhost EXT3 FS on sda5, internal journal Jan 21 16:15:51 localhost kjournald starting. Commit interval 5 seconds Jan 21 16:15:51 localhost EXT3-fs warning: maximal mount count reached, running e2fsck is recommended Jan 21 16:15:51 localhost EXT3 FS on sda4, internal journal Jan 21 16:15:51 localhost EXT3-fs: mounted filesystem with ordered data mode. Jan 21 16:15:51 localhost kjournald starting. Commit interval 5 seconds Jan 21 16:15:51 localhost EXT3-fs warning: mounting unchecked fs, running e2fsck is recommended Jan 21 16:15:51 localhost EXT3 FS on sda2, internal journal Jan 21 16:15:51 localhost EXT3-fs: mounted filesystem with ordered data mode. Jan 21 16:15:51 localhost NTFS driver 2.1.25 [Flags: R/O MODULE]. Jan 21 16:15:51 localhost NTFS volume version 3.1. Jan 21 16:15:51 localhost time.c: Lost 13 timer tick(s)! rip 0x444b50) Jan 21 16:15:51 localhost 8139too Fast Ethernet driver 0.9.27 Jan 21 16:15:51 localhost eth0: RealTek RTL8139 at 0xffffc200006e2000, 00:13:d3:2e:29:c6, IRQ 5 Jan 21 16:15:51 localhost eth0: Identified 8139 chip type 'RTL-8100B/8139D' Jan 21 16:15:51 localhost time.c: Lost 15 timer tick(s)! rip default_idle+0x2a/0x52) Jan 21 16:15:51 localhost time.c: Lost 12 timer tick(s)! rip 0x423282) Jan 21 16:15:51 localhost eth0: link up, 100Mbps, full-duplex, lpa 0x41E1 Jan 21 16:15:51 localhost smsc47m1: Found SMSC LPC47M15x/LPC47M192/LPC47M997 Jan 21 16:15:51 localhost Driver 'smsc47m1' needs updating - please use bus_type methods Jan 21 16:15:53 localhost time.c: Lost 15 timer tick(s)! rip default_idle+0x2a/0x52) Jan 21 16:15:58 localhost eth0: no IPv6 routers present Jan 21 16:14:36 localhost sshd[8307]: Server listening on :: port 22. Jan 21 16:14:36 localhost sshd[8307]: error: Bind to port 22 on 0.0.0.0 failed: Address already in use. Jan 21 16:14:36 localhost cron[8376]: (CRON) STARTUP (V5.0) Jan 21 16:14:37 localhost xfs: CONFIG: unknown parameter "cache-hi-mark" Jan 21 16:14:37 localhost xfs: CONFIG: unknown parameter "cache-low-mark" Jan 21 16:14:37 localhost xfs: CONFIG: unknown parameter "cache-balance" Jan 21 16:14:37 localhost xfs[8539]: ignoring font path element /usr/share/fonts/freefont (unreadable) Jan 21 16:14:37 localhost init: Activating demand-procedures for 'A' Jan 21 16:15:38 localhost gdm(pam_unix)[8739]: session opened for user raph by (uid=0) Jan 21 16:16:41 localhost time.c: Lost 13 timer tick(s)! rip 0x2ae0f4203435) Jan 21 16:16:42 localhost time.c: Lost 14 timer tick(s)! rip default_idle+0x2a/0x52) Raphael
Yeah, the BIOS and hardware apparently sucks, but I don't see what we could do about it. Try with latest BIOS and latest firmware for your DVD drive and/or complain to the manufacturer.
Hi, These tests are done with both of those things. Are you sure the problem is not related to the fact that I can't boot with apic or acpi enabled or at least that it wouldn't be fixed if I could get /proc/interrupts to list APIC and not XT-PIC? Note what look like timing problems not related to the dvd drive at http://bugs.gentoo.org/show_bug.cgi?id=119833. Also, the drive does not cause these problems in all OSes (mentioning no names : ) ) so it might at least be worth while trying to work out what the problem is I was thinking. I am happy to try anything out you suggest. Note: The kernel will boot with "noapictimer apic=noirq" it turns out. Also, the mobo has had timing problems that have been known about for a while and some of which have workarounds in the kernel. For example, the no_timer_check boot prompt option. See http://bugme.osdl.org/show_bug.cgi?id=3927 Raphael
(In reply to comment #2) > Hi, > > These tests are done with both of those things. Are you sure the problem is not > related to the fact that I can't boot with apic or acpi enabled or at least > that it wouldn't be fixed if I could get /proc/interrupts to list APIC and not > XT-PIC? Note what look like timing problems not related to the dvd drive at > http://bugs.gentoo.org/show_bug.cgi?id=119833. As already said, the BIOS (ACPI implementation at least) seems to be broken, as well as hardware clock - so yeah, this bug is more or less dupe of Bug 119833. If there's a new version available, try to upgrade it.
BIOS version is the latest (3.80) and query has been sent to MSI (no reply yet).
Note that the hardware clock problem seemed to be a linux problem in the end (counting twice) and was (is being) fixed in the kernel. I don't think it is fair to say the hardware is broken at this point, although of course it may well turn out to be in the end. Raphael
The bug which we thought this relied on (preventing the system from booting with apic and acpi on http://bugs.gentoo.org/show_bug.cgi?id=119833) has been fixed without fixing this bug. I attach a new output from dmesg using 2.6.15-r2.
Created attachment 78966 [details] dmesg of boot with 2.6.15-r2 and acpi and apic on
Created attachment 78967 [details] /proc/interrupts on 2.6.15-r2 kernel
I was wondering if the problem is as simple as not having dma on the dvd drive?? hdparm -d1 /dev/dvd /dev/dvd: setting using_dma to 1 (on) HDIO_SET_DMA failed: Operation not permitted using_dma = 0 (off) Can this bug be reoponed??
The solution to the interrupt storm is to compile in the ATI IXP IDE controller driver in the kernel under ATA/ATAPI/MFM/RLL support.I will change the status of the bug if I can. It certainly isn't "CAN'T FIX"!
(In reply to comment #10) > The solution to the interrupt storm is to compile in the ATI IXP IDE controller > driver in the kernel under ATA/ATAPI/MFM/RLL support. So, why don't you compile it?
Sorry for any misunderstanding. What I meant was that I compiled the driver in and now dma works and playing a dvd works as a result. It seems that there is some complication with SATA and IDE which makes this necessary. I am sure there are plenty of people with my mobo for whom this would benefit too. I also notice the "No DMA on DVD drive" section at http://www.thinkwiki.org/wiki/Problems_with_SATA_and_Linux so this problem is clearly widespread. (I still get timer errors but not so many and they don't seem to cause a probem anymore)