lkml.org 
[lkml]   [2004]   [Jun]   [15]   [last100]   RSS Feed
Views: [wrap][no wrap]   [headers]  [forward] 
 
Messages in this thread
/
Date
From
SubjectRe: PROBLEM: 2.6.6 grinds to a halt with moderate I/O

>Thanks for the bugreport. I'm going to file this in the Debian BTS
>after I get the FPU fixes out. Could you send along a dmesg
>(/var/log/dmesg on Debian) and /proc/meminfo and /proc/cpuinfo at some
>point when you can log into the box? I'll also try to reproduce this.

I am not sure why this would be filed in the Debian BTS, yes the
underlying OS is Debian, but this is not a Debian Kernel, it is a
vanilla 2.6.6 kernel that I compiled by hand.

Please find attached the dmesg and the /proc/meminfo, the
/proc/cpuinfo was already included in the original email.

1. dmesg
Linux version 2.6.6 (root@willow) (gcc version 3.3.3 (Debian 20040422)) #10 SMP Tue Jun 15 09:25:44 PDT 2004
BIOS-provided physical RAM map:
BIOS-e820: 0000000000000000 - 000000000009f800 (usable)
BIOS-e820: 000000000009f800 - 00000000000a0000 (reserved)
BIOS-e820: 00000000000e9400 - 0000000000100000 (reserved)
BIOS-e820: 0000000000100000 - 0000000080000000 (usable)
BIOS-e820: 00000000fec00000 - 00000000fec10000 (reserved)
BIOS-e820: 00000000fee00000 - 00000000fee01000 (reserved)
BIOS-e820: 00000000fff80000 - 0000000100000000 (reserved)
1152MB HIGHMEM available.
896MB LOWMEM available.
found SMP MP-table at 000f7580
On node 0 totalpages: 524288
DMA zone: 4096 pages, LIFO batch:1
Normal zone: 225280 pages, LIFO batch:16
HighMem zone: 294912 pages, LIFO batch:16
DMI 2.3 present.
ACPI: Unable to locate RSDP
Intel MultiProcessor Specification v1.4
Virtual Wire compatibility mode.
OEM ID: HP Product ID: LP 1Kr/2Kr APIC at: 0xFEE00000
Processor #0 6:8 APIC version 17
Processor #3 6:8 APIC version 17
I/O APIC #1 Version 17 at 0xFEC00000.
I/O APIC #2 Version 17 at 0xFEC01000.
Enabling APIC mode: Flat. Using 2 I/O APICs
Processors: 2
Built 1 zonelists
Kernel command line: apic=off root=/dev/sda1 ro
Initializing CPU#0
CPU 0 irqstacks, hard=c03e4000 soft=c03e2000
PID hash table entries: 4096 (order 12: 32768 bytes)
Detected 934.115 MHz processor.
Using tsc for high-res timesource
Console: colour VGA+ 80x25
Memory: 2076156k/2097152k available (1991k kernel code, 19820k reserved, 565k data, 368k init, 1179648k highmem)
Checking if this processor honours the WP bit even in supervisor mode... Ok.
Calibrating delay loop... 1843.20 BogoMIPS
Dentry cache hash table entries: 262144 (order: 8, 1048576 bytes)
Inode-cache hash table entries: 131072 (order: 7, 524288 bytes)
Mount-cache hash table entries: 512 (order: 0, 4096 bytes)
CPU: After generic identify, caps: 0387fbff 00000000 00000000 00000000
CPU: After vendor identify, caps: 0387fbff 00000000 00000000 00000000
CPU: L1 I cache: 16K, L1 D cache: 16K
CPU: L2 cache: 256K
CPU serial number disabled.
CPU: After all inits, caps: 0383fbff 00000000 00000000 00000040
Intel machine check architecture supported.
Intel machine check reporting enabled on CPU#0.
Enabling fast FPU save and restore... done.
Enabling unmasked SIMD FPU exception support... done.
Checking 'hlt' instruction... OK.
POSIX conformance testing by UNIFIX
CPU0: Intel Pentium III (Coppermine) stepping 06
per-CPU timeslice cutoff: 731.34 usecs.
task migration cache decay timeout: 1 msecs.
enabled ExtINT on CPU#0
ESR value before enabling vector: 00000000
ESR value after enabling vector: 00000000
Booting processor 1/3 eip 2000
CPU 1 irqstacks, hard=c03e5000 soft=c03e3000
Initializing CPU#1
masked ExtINT on CPU#1
ESR value before enabling vector: 00000000
ESR value after enabling vector: 00000000
Calibrating delay loop... 1863.68 BogoMIPS
CPU: After generic identify, caps: 0387fbff 00000000 00000000 00000000
CPU: After vendor identify, caps: 0387fbff 00000000 00000000 00000000
CPU: L1 I cache: 16K, L1 D cache: 16K
CPU: L2 cache: 256K
CPU serial number disabled.
CPU: After all inits, caps: 0383fbff 00000000 00000000 00000040
Intel machine check architecture supported.
Intel machine check reporting enabled on CPU#1.
CPU1: Intel Pentium III (Coppermine) stepping 0a
Total of 2 processors activated (3706.88 BogoMIPS).
ENABLING IO-APIC IRQs
Setting 1 in the phys_id_present_map
...changing IO-APIC physical APIC ID to 1 ... ok.
Setting 2 in the phys_id_present_map
...changing IO-APIC physical APIC ID to 2 ... ok.
init IO_APIC IRQs
IO-APIC (apicid-pin) 1-0, 1-5, 1-9, 1-10, 1-11, 1-15, 2-1, 2-2, 2-3, 2-4, 2-5, 2-10, 2-11, 2-12, 2-14, 2-15 not connected.
..TIMER: vector=0x31 pin1=-1 pin2=0
...trying to set up timer (IRQ0) through the 8259A ...
..... (found pin 0) ...works.
number of MP IRQ sources: 18.
number of IO-APIC #1 registers: 16.
number of IO-APIC #2 registers: 16.
testing the IO APIC.......................
IO APIC #1......
.... register #00: 01000000
....... : physical APIC id: 01
....... : Delivery Type: 0
....... : LTS : 0
.... register #01: 000F0011
....... : max redirection entries: 000F
....... : PRQ implemented: 0
....... : IO APIC version: 0011
.... register #02: 00000000
....... : arbitration: 00
.... IRQ redirection table:
NR Log Phy Mask Trig IRR Pol Stat Dest Deli Vect:
00 001 01 0 0 0 0 0 1 1 31
01 001 01 0 0 0 0 0 1 1 39
02 000 00 1 0 0 0 0 0 0 00
03 001 01 0 0 0 0 0 1 1 41
04 001 01 0 0 0 0 0 1 1 49
05 000 00 1 0 0 0 0 0 0 00
06 001 01 0 0 0 0 0 1 1 51
07 001 01 0 0 0 0 0 1 1 59
08 001 01 0 0 0 0 0 1 1 61
09 000 00 1 0 0 0 0 0 0 00
0a 000 00 1 0 0 0 0 0 0 00
0b 000 00 1 0 0 0 0 0 0 00
0c 001 01 0 0 0 0 0 1 1 69
0d 001 01 0 0 0 0 0 1 1 71
0e 001 01 0 0 0 0 0 1 1 79
0f 000 00 1 0 0 0 0 0 0 00
IO APIC #2......
.... register #00: 02000000
....... : physical APIC id: 02
....... : Delivery Type: 0
....... : LTS : 0
.... register #01: 000F0011
....... : max redirection entries: 000F
....... : PRQ implemented: 0
....... : IO APIC version: 0011
.... register #02: 0D000000
....... : arbitration: 0D
.... IRQ redirection table:
NR Log Phy Mask Trig IRR Pol Stat Dest Deli Vect:
00 001 01 1 1 0 1 0 1 1 81
01 000 00 1 0 0 0 0 0 0 00
02 000 00 1 0 0 0 0 0 0 00
03 000 00 1 0 0 0 0 0 0 00
04 000 00 1 0 0 0 0 0 0 00
05 000 00 1 0 0 0 0 0 0 00
06 001 01 1 1 0 1 0 1 1 89
07 001 01 1 1 0 1 0 1 1 91
08 001 01 1 1 0 1 0 1 1 99
09 001 01 1 1 0 1 0 1 1 A1
0a 000 00 1 0 0 0 0 0 0 00
0b 000 00 1 0 0 0 0 0 0 00
0c 000 00 1 0 0 0 0 0 0 00
0d 001 01 1 1 0 1 0 1 1 A9
0e 000 00 1 0 0 0 0 0 0 00
0f 000 00 1 0 0 0 0 0 0 00
IRQ to pin mappings:
IRQ0 -> 0:0
IRQ1 -> 0:1
IRQ2 -> 0:2
IRQ3 -> 0:3
IRQ4 -> 0:4
IRQ6 -> 0:6
IRQ7 -> 0:7
IRQ8 -> 0:8
IRQ12 -> 0:12
IRQ13 -> 0:13
IRQ14 -> 0:14
IRQ16 -> 1:0
IRQ22 -> 1:6
IRQ23 -> 1:7
IRQ24 -> 1:8
IRQ25 -> 1:9
IRQ29 -> 1:13
.................................... done.
Using local APIC timer interrupts.
calibrating APIC timer ...
..... CPU clock speed is 933.0294 MHz.
..... host bus clock speed is 133.0327 MHz.
checking TSC synchronization across 2 CPUs: passed.
Brought up 2 CPUs
NET: Registered protocol family 16
PCI: PCI BIOS revision 2.10 entry at 0xfda11, last bus=3
PCI: Using configuration type 1
mtrr: v2.0 (20020519)
Linux Plug and Play Support v0.97 (c) Adam Belay
SCSI subsystem initialized
PCI: Probing PCI hardware
PCI: Probing PCI hardware (bus 00)
PCI: Discovered peer bus 01
PCI->APIC IRQ transform: (B0,I2,P0) -> 22
PCI->APIC IRQ transform: (B0,I8,P0) -> 23
PCI->APIC IRQ transform: (B0,I15,P0) -> 33
PCI->APIC IRQ transform: (B1,I5,P0) -> 24
PCI->APIC IRQ transform: (B1,I5,P1) -> 25
PCI->APIC IRQ transform: (B2,I1,P0) -> 29
PCI->APIC IRQ transform: (B3,I0,P0) -> 16
Machine check exception polling timer started.
Starting balanced_irq
highmem bounce pool size: 64 pages
Initializing Cryptographic API
isapnp: Scanning for PnP cards...
isapnp: No Plug & Play device found
Serial: 8250/16550 driver $Revision: 1.90 $ 8 ports, IRQ sharing disabled
ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A
Using anticipatory io scheduler
Floppy drive(s): fd0 is 1.44M
FDC 0 is a National Semiconductor PC87306
e100: Intel(R) PRO/100 Network Driver, 3.0.17
e100: Copyright(c) 1999-2004 Intel Corporation
e100: eth0: e100_probe: addr 0xe8001000, irq 22, MAC addr 00:30:6E:05:E9:D0
e100: eth1: e100_probe: addr 0xe8003000, irq 23, MAC addr 00:30:6E:05:E9:D1
Uniform Multi-Platform E-IDE driver Revision: 7.00alpha2
ide: Assuming 33MHz system bus speed for PIO modes; override with idebus=xx
hda: CD-224E, ATAPI CD/DVD-ROM drive
hdc: IRQ probe failed (0xffffffba)
hdc: IRQ probe failed (0xffffffba)
hdd: IRQ probe failed (0xffffffba)
hdd: IRQ probe failed (0xffffffba)
ide0 at 0x1f0-0x1f7,0x3f6 on irq 14
hda: ATAPI 24X CD-ROM drive, 128kB Cache
Uniform CD-ROM driver Revision: 3.20
megaraid: found 0x101e:0x1960:bus 3:slot 0:func 0
scsi0:Found MegaRAID controller at 0xf8804000, IRQ:16
megaraid: [K01.04:J01.01] detected 1 logical drives.
megaraid: supports extended CDBs.
megaraid: channel[0] is raid.
megaraid: channel[1] is raid.
scsi0 : LSI Logic MegaRAID K01.04 254 commands 16 targs 5 chans 7 luns
scsi0: scanning scsi channel 0 for logical drives.
Vendor: MegaRAID Model: LD 0 RAID5 140G Rev: K
Type: Direct-Access ANSI SCSI revision: 02
scsi0: scanning scsi channel 1 for logical drives.
scsi0: scanning scsi channel 2 for logical drives.
scsi0: scanning scsi channel 4 [P0] for physical devices.
scsi0: scanning scsi channel 5 [P1] for physical devices.
Vendor: SDR Model: GEM318 Rev: 0
Type: Processor ANSI SCSI revision: 02
SCSI device sda: 286744576 512-byte hdwr sectors (146813 MB)
sda: asking for cache data failed
sda: assuming drive cache: write through
sda: sda1 sda2 < sda5 sda6 sda7 sda8 sda9 >
Attached scsi disk sda at scsi0, channel 0, id 0, lun 0
Attached scsi generic sg0 at scsi0, channel 0, id 0, lun 0, type 0
Attached scsi generic sg1 at scsi0, channel 5, id 11, lun 0, type 3
mice: PS/2 mouse device common for all mice
serio: i8042 AUX port at 0x60,0x64 irq 12
serio: i8042 KBD port at 0x60,0x64 irq 1
input: AT Translated Set 2 keyboard on isa0060/serio0
md: linear personality registered as nr 1
md: raid0 personality registered as nr 2
md: raid1 personality registered as nr 3
md: raid5 personality registered as nr 4
raid5: measuring checksumming speed
8regs : 1704.000 MB/sec
8regs_prefetch: 1364.000 MB/sec
32regs : 900.000 MB/sec
32regs_prefetch: 796.000 MB/sec
pIII_sse : 1900.000 MB/sec
pII_mmx : 2340.000 MB/sec
p5_mmx : 2504.000 MB/sec
raid5: using function: pIII_sse (1900.000 MB/sec)
md: md driver 0.90.0 MAX_MD_DEVS=256, MD_SB_DISKS=27
NET: Registered protocol family 2
IP: routing cache hash table of 16384 buckets, 128Kbytes
TCP: Hash tables configured (established 524288 bind 65536)
ip_conntrack version 2.1 (8192 buckets, 65536 max) - 300 bytes per conntrack
ip_tables: (C) 2000-2002 Netfilter core team
ipt_recent v0.3.1: Stephen Frost <sfrost@snowman.net>. http://snowman.net/projects/ipt_recent/
arp_tables: (C) 2002 David S. Miller
NET: Registered protocol family 1
NET: Registered protocol family 17
md: Autodetecting RAID arrays.
md: autorun ...
md: ... autorun DONE.
EXT3-fs: INFO: recovery required on readonly filesystem.
EXT3-fs: write access will be enabled during recovery.
kjournald starting. Commit interval 5 seconds
EXT3-fs: recovery complete.
EXT3-fs: mounted filesystem with ordered data mode.
VFS: Mounted root (ext3 filesystem) readonly.
Freeing unused kernel memory: 368k freed
Adding 979920k swap on /dev/sda6. Priority:-1 extents:1
EXT3 FS on sda1, internal journal
kjournald starting. Commit interval 5 seconds
EXT3 FS on sda5, internal journal
EXT3-fs: mounted filesystem with ordered data mode.
kjournald starting. Commit interval 5 seconds
EXT3 FS on sda7, internal journal
EXT3-fs: mounted filesystem with ordered data mode.
kjournald starting. Commit interval 5 seconds
EXT3 FS on sda8, internal journal
EXT3-fs: mounted filesystem with ordered data mode.
e100: eth0: e100_watchdog: link up, 100Mbps, full-duplex
e100: eth1: e100_watchdog: link up, 100Mbps, full-duplex


2. /proc/meminfo
MemTotal: 2077312 kB
MemFree: 1583540 kB
Buffers: 27804 kB
Cached: 374892 kB
SwapCached: 0 kB
Active: 65300 kB
Inactive: 375884 kB
HighTotal: 1179648 kB
HighFree: 765056 kB
LowTotal: 897664 kB
LowFree: 818484 kB
SwapTotal: 979920 kB
SwapFree: 979920 kB
Dirty: 64 kB
Writeback: 0 kB
Mapped: 51084 kB
Slab: 41584 kB
Committed_AS: 184572 kB
PageTables: 1032 kB
VmallocTotal: 114680 kB
VmallocUsed: 788 kB
VmallocChunk: 113892 kB




On Tue, 15 Jun 2004, William Lee Irwin III wrote:

> On Tue, Jun 15, 2004 at 10:47:45AM -0500, Micah Anderson wrote:
> > Following the format from REPORTING-BUGS please see the below information.
> > I unfortunately cannot subscribe to the list, but will follow the thread. I
> > have searched high and low, read a number of threads somewhat tangential to
> > this problem, and asked a few times in #kernelnewbies before I got to my
> > wits end and now will try here. I really appreciate any insight anyone has,
> > and will be happy to provide more information or additional tests
> > 1. When doing moderate I/O on a 2.6.6 system the machine becomes unusable.
> > 2. I found that with HIGHMEM support compiled into the kernel, when I
> > did a cp -vr /var /usr/tmp it would work fine until it got about
> > halfway through the large ldap.log file (approximately 500 megs) when
> > the system would no longer be able to fork new processes. Your
> > existing shell would function, but if you tried to run top, free, etc.
> > it would hang. vmstat 1 would print the first line, but never
> > continue. I ran a million different kernel configs to try and isolate
> > things, and I thought I had it nailed down with passing apic=off to
> > the kernel at boot because the large logfile copy test would
> > pass, but when rsyncing maildirs tonight the same problem appeared. Early
> > in my tests I thought the problem was dm-crypt, but the problem existed
> > even when no encrypted filesystems were involved, and existed when I
> > removed dm-crypt support from the kernel. Disabling HIGHMEM support seems
> > to make the problem go away.
>
> Thanks for the bugreport. I'm going to file this in the Debian BTS
> after I get the FPU fixes out. Could you send along a dmesg
> (/var/log/dmesg on Debian) and /proc/meminfo and /proc/cpuinfo at some
> point when you can log into the box? I'll also try to reproduce this.
>
> Thanks.
>
>
> -- wli
[unhandled content-type:application/pgp-signature]
\
 
 \ /
  Last update: 2005-03-22 14:03    [W:0.393 / U:0.032 seconds]
©2003-2020 Jasper Spaans|hosted at Digital Ocean and TransIP|Read the blog|Advertise on this site