[Xen-users] oom-killer keeps killing big un-tars

[Andrew Thompson <xenuser@xxxxxxxxxxx>]

Greetings,

Several times now, I've been untarring some files in my dom0 and they've 
been killed by the oom-killer. I originally thought it might be because 
they were not in the foreground as I normally use screen. I just tried 
again without screen and it was killed again.
I compiled Xen from a nightly snapshot that I downloaded a couple of 
weeks ago.
xen root # ls -al xen-2.0-testing-src.tgz
-rw-r--r--  1 root root 2432727 Apr 29 08:29 xen-2.0-testing-src.tgz

The system is a dual processor P2 300 Dell Workstation. It has only 
128MB RAM total. I've allocated 42MB RAM to dom0. dom0 also mounts a 
256MB swap partition.
Each time I've reviewed the /var/log/messages, there had been plenty of 
free swap space when the process was killed.
I'm attaching sections from /var/log/messages where the process is 
killed.  Please let me know what else would be useful.
What should my next step be?

--
Andrew Thompson
http://aktzero.com/

Boot:

May 18 00:38:49 xen syslog-ng[4960]: syslog-ng version 1.6.5 starting
May 18 00:38:49 xen syslog-ng[4960]: Changing permissions on special file 
/dev/tty12
May 18 00:38:49 xen Linux version 2.6.11-xen0 (root@xen) (gcc version 
3.3.5-20050130 (Gentoo Linux 3.3.5.20050130-r1, ssp-3.3.5.20050130-1, 
pie-8.7.7.1)) #1 Fri Apr 29 11:15:24 EDT 2005
May 18 00:38:49 xen BIOS-provided physical RAM map:
May 18 00:38:49 xen Xen: 0000000000000000 - 0000000002a00000 (usable)
May 18 00:38:49 xen 42MB LOWMEM available.
May 18 00:38:49 xen On node 0 totalpages: 10752
May 18 00:38:49 xen DMA zone: 10752 pages, LIFO batch:2
May 18 00:38:49 xen Normal zone: 0 pages, LIFO batch:1
May 18 00:38:49 xen HighMem zone: 0 pages, LIFO batch:1
May 18 00:38:49 xen DMI not present.
May 18 00:38:49 xen IRQ lockup detection disabled
May 18 00:38:49 xen Allocating PCI resources starting at 02a00000 (gap: 
02a00000:fd600000)
May 18 00:38:49 xen Built 1 zonelists
May 18 00:38:49 xen Kernel command line: root=/dev/sda8 console=tty0
May 18 00:38:49 xen Initializing CPU#0
May 18 00:38:49 xen PID hash table entries: 256 (order: 8, 4096 bytes)
May 18 00:38:49 xen Xen reported: 299.155 MHz processor.
May 18 00:38:49 xen Using tsc for high-res timesource
May 18 00:38:49 xen Console: colour VGA+ 80x25
May 18 00:38:49 xen Dentry cache hash table entries: 8192 (order: 3, 32768 
bytes)
May 18 00:38:49 xen Inode-cache hash table entries: 4096 (order: 2, 16384 bytes)
May 18 00:38:49 xen Memory: 38040k/43008k available (2881k kernel code, 4924k 
reserved, 981k data, 320k init, 0k highmem)
May 18 00:38:49 xen Checking if this processor honours the WP bit even in 
supervisor mode... Ok.
May 18 00:38:49 xen Calibrating delay loop... 596.37 BogoMIPS (lpj=2981888)
May 18 00:38:49 xen Mount-cache hash table entries: 512 (order: 0, 4096 bytes)
May 18 00:38:49 xen CPU: After generic identify, caps: 0080fbff 00000000 
00000000 00000000 00000000 00000000 00000000
May 18 00:38:49 xen CPU: After vendor identify, caps: 0080fbff 00000000 
00000000 00000000 00000000 00000000 00000000
May 18 00:38:49 xen CPU: L1 I cache: 16K, L1 D cache: 16K
May 18 00:38:49 xen CPU: L2 cache: 512K
May 18 00:38:49 xen CPU: After all inits, caps: 0080d3f1 00000000 00000000 
00000040 00000000 00000000 00000000
May 18 00:38:49 xen CPU: Intel Pentium II (Klamath) stepping 04
May 18 00:38:49 xen Checking 'hlt' instruction... disabled
May 18 00:38:49 xen NET: Registered protocol family 16
May 18 00:38:49 xen PCI: Using configuration type Xen
May 18 00:38:49 xen xen_mem: Initialising balloon driver.
May 18 00:38:49 xen SCSI subsystem initialized
May 18 00:38:49 xen PCI: Probing PCI hardware
May 18 00:38:49 xen PCI: Probing PCI hardware (bus 00)
May 18 00:38:49 xen PCI: Probing PCI hardware (bus 01)
May 18 00:38:49 xen PCI: Probing PCI hardware
May 18 00:38:49 xen IA-32 Microcode Update Driver: v1.14-xen 
<tigran@xxxxxxxxxxx>
May 18 00:38:49 xen Initializing Cryptographic API
May 18 00:38:49 xen Limiting direct PCI/PCI transfers.
May 18 00:38:49 xen Activating ISA DMA hang workarounds.
May 18 00:38:49 xen serio: i8042 AUX port at 0x60,0x64 irq 12
May 18 00:38:49 xen serio: i8042 KBD port at 0x60,0x64 irq 1
May 18 00:38:49 xen io scheduler noop registered
May 18 00:38:49 xen io scheduler anticipatory registered
May 18 00:38:49 xen io scheduler deadline registered
May 18 00:38:49 xen io scheduler cfq registered
May 18 00:38:49 xen Floppy drive(s): fd0 is 1.44M
May 18 00:38:49 xen FDC 0 is a National Semiconductor PC87306
May 18 00:38:49 xen RAMDISK driver initialized: 16 RAM disks of 4096K size 1024 
blocksize
May 18 00:38:49 xen loop: loaded (max 8 devices)
May 18 00:38:49 xen HP CISS Driver (v 2.6.4)
May 18 00:38:49 xen Intel(R) PRO/1000 Network Driver - version 5.6.10.1-k2
May 18 00:38:49 xen Copyright (c) 1999-2004 Intel Corporation.
May 18 00:38:49 xen PCI: Obtained IRQ 19 for device 0000:01:08.0
May 18 00:38:49 xen 3c59x: Donald Becker and others. 
www.scyld.com/network/vortex.html
May 18 00:38:49 xen 0000:01:08.0: 3Com PCI 3c905 Boomerang 100baseTx at 0xfcc0. 
Vers LK1.1.19
May 18 00:38:49 xen eth0: Dropping NETIF_F_SG since no checksum feature.
May 18 00:38:49 xen pcnet32.c:v1.30i 06.28.2004 tsbogend@xxxxxxxxxxxxxxxx
May 18 00:38:49 xen e100: Intel(R) PRO/100 Network Driver, 3.3.6-k2-NAPI
May 18 00:38:49 xen e100: Copyright(c) 1999-2004 Intel Corporation
May 18 00:38:49 xen Xen virtual console successfully installed as ttyS
May 18 00:38:49 xen Event-channel device installed.
May 18 00:38:49 xen Initialising Xen netif backend
May 18 00:38:49 xen Uniform Multi-Platform E-IDE driver Revision: 7.00alpha2
May 18 00:38:49 xen ide: Assuming 33MHz system bus speed for PIO modes; 
override with idebus=xx
May 18 00:38:49 xen PIIX3: IDE controller at PCI slot 0000:00:0d.1
May 18 00:38:49 xen PCI: Enabling device 0000:00:0d.1 (0000 -> 0001)
May 18 00:38:49 xen PIIX3: chipset revision 0
May 18 00:38:49 xen PIIX3: not 100% native mode: will probe irqs later
May 18 00:38:49 xen PIIX3: neither IDE port enabled (BIOS)
May 18 00:38:49 xen Probing IDE interface ide0...
May 18 00:38:49 xen Probing IDE interface ide1...
May 18 00:38:49 xen Probing IDE interface ide2...
May 18 00:38:49 xen Probing IDE interface ide3...
May 18 00:38:49 xen Probing IDE interface ide4...
May 18 00:38:49 xen Probing IDE interface ide5...
May 18 00:38:49 xen PCI: Obtained IRQ 19 for device 0000:01:09.0
May 18 00:38:49 xen PCI: Obtained IRQ 16 for device 0000:01:09.1
May 18 00:38:49 xen scsi0 : Adaptec AIC7XXX EISA/VLB/PCI SCSI HBA DRIVER, Rev 
6.2.36
May 18 00:38:49 xen <Adaptec 2940/DUAL Ultra SCSI adapter>
May 18 00:38:49 xen aic7895: Ultra Wide Channel A, SCSI Id=7, 32/253 SCBs
May 18 00:38:49 xen 
May 18 00:38:49 xen (scsi0:A:0): 40.000MB/s transfers (20.000MHz, offset 8, 
16bit)
May 18 00:38:49 xen Vendor: QUANTUM   Model: ATLAS IV 9 WLS    Rev: 0707
May 18 00:38:49 xen Type:   Direct-Access                      ANSI SCSI 
revision: 03
May 18 00:38:49 xen scsi0:A:0:0: Tagged Queuing enabled.  Depth 32
May 18 00:38:49 xen scsi1 : Adaptec AIC7XXX EISA/VLB/PCI SCSI HBA DRIVER, Rev 
6.2.36
May 18 00:38:49 xen <Adaptec 2940/DUAL Ultra SCSI adapter>
May 18 00:38:49 xen aic7895: Ultra Single Channel B, SCSI Id=7, 32/253 SCBs
May 18 00:38:49 xen 
May 18 00:38:49 xen Red Hat/Adaptec aacraid driver (1.1.2-lk2 Apr 29 2005)
May 18 00:38:49 xen 3ware Storage Controller device driver for Linux 
v1.26.02.000.
May 18 00:38:49 xen libata version 1.10 loaded.
May 18 00:38:49 xen SCSI device sda: 17783249 512-byte hdwr sectors (9105 MB)
May 18 00:38:49 xen SCSI device sda: drive cache: write through
May 18 00:38:49 xen SCSI device sda: 17783249 512-byte hdwr sectors (9105 MB)
May 18 00:38:49 xen SCSI device sda: drive cache: write through
May 18 00:38:49 xen sda: sda1 sda2 < sda5 sda6 sda7 sda8 sda9 sda10 sda11 sda12 
sda13 sda14 sda15 >
May 18 00:38:49 xen Attached scsi disk sda at scsi0, channel 0, id 0, lun 0
May 18 00:38:49 xen Fusion MPT base driver 3.01.18
May 18 00:38:49 xen Copyright (c) 1999-2004 LSI Logic Corporation
May 18 00:38:49 xen Fusion MPT SCSI Host driver 3.01.18
May 18 00:38:49 xen mice: PS/2 mouse device common for all mice
May 18 00:38:49 xen input: AT Translated Set 2 keyboard on isa0060/serio0
May 18 00:38:49 xen md: raid0 personality registered as nr 2
May 18 00:38:49 xen md: raid1 personality registered as nr 3
May 18 00:38:49 xen md: raid5 personality registered as nr 4
May 18 00:38:49 xen raid5: measuring checksumming speed
May 18 00:38:49 xen 8regs     :   839.200 MB/sec
May 18 00:38:49 xen 8regs_prefetch:   463.600 MB/sec
May 18 00:38:49 xen 32regs    :   277.600 MB/sec
May 18 00:38:49 xen 32regs_prefetch:   263.200 MB/sec
May 18 00:38:49 xen pII_mmx   :   455.200 MB/sec
May 18 00:38:49 xen p5_mmx    :   467.200 MB/sec
May 18 00:38:49 xen raid5: using function: 8regs (839.200 MB/sec)
May 18 00:38:49 xen md: md driver 0.90.1 MAX_MD_DEVS=256, MD_SB_DISKS=27
May 18 00:38:49 xen device-mapper: 4.4.0-ioctl (2005-01-12) initialised: 
dm-devel@xxxxxxxxxx
May 18 00:38:49 xen NET: Registered protocol family 2
May 18 00:38:49 xen IP: routing cache hash table of 512 buckets, 4Kbytes
May 18 00:38:49 xen TCP established hash table entries: 2048 (order: 2, 16384 
bytes)
May 18 00:38:49 xen TCP bind hash table entries: 2048 (order: 1, 8192 bytes)
May 18 00:38:49 xen TCP: Hash tables configured (established 2048 bind 2048)
May 18 00:38:49 xen NET: Registered protocol family 1
May 18 00:38:49 xen NET: Registered protocol family 17
May 18 00:38:49 xen Bridge firewalling registered
May 18 00:38:49 xen md: Autodetecting RAID arrays.
May 18 00:38:49 xen md: autorun ...
May 18 00:38:49 xen md: ... autorun DONE.
May 18 00:38:49 xen ReiserFS: sda8: found reiserfs format "3.6" with standard 
journal
May 18 00:38:49 xen ReiserFS: sda8: using ordered data mode
May 18 00:38:49 xen ReiserFS: sda8: journal params: device sda8, size 8192, 
journal first block 18, max trans len 1024, max batch 900, max commit age 30, 
max trans age 30
May 18 00:38:49 xen ReiserFS: sda8: checking transaction log (sda8)
May 18 00:38:49 xen ReiserFS: sda8: Using r5 hash to sort names
May 18 00:38:49 xen VFS: Mounted root (reiserfs filesystem).
May 18 00:38:49 xen Freeing unused kernel memory: 320k freed
May 18 00:38:49 xen Adding 265032k swap on /dev/sda5.  Priority:-1 extents:1
May 18 00:38:49 xen ReiserFS: sda10: found reiserfs format "3.6" with standard 
journal
May 18 00:38:49 xen ReiserFS: sda10: using ordered data mode
May 18 00:38:49 xen ReiserFS: sda10: journal params: device sda10, size 8192, 
journal first block 18, max trans len 1024, max batch 900, max commit age 30, 
max trans age 30
May 18 00:38:49 xen ReiserFS: sda10: checking transaction log (sda10)
May 18 00:38:49 xen ReiserFS: sda10: Using r5 hash to sort names
May 18 00:38:49 xen ReiserFS: sda9: found reiserfs format "3.6" with standard 
journal
May 18 00:38:49 xen ReiserFS: sda9: using ordered data mode
May 18 00:38:49 xen ReiserFS: sda9: journal params: device sda9, size 8192, 
journal first block 18, max trans len 1024, max batch 900, max commit age 30, 
max trans age 30
May 18 00:38:49 xen ReiserFS: sda9: checking transaction log (sda9)
May 18 00:38:49 xen ReiserFS: sda9: Using r5 hash to sort names
May 18 00:38:51 xen PCI: Obtained IRQ 19 for device 0000:01:08.0
May 18 00:38:53 xen sshd[5230]: Server listening on 0.0.0.0 port 22.
May 18 00:38:54 xen cron[5273]: (CRON) STARTUP (V5.0)
May 18 00:38:59 xen eth0: Setting promiscuous mode.
May 18 00:38:59 xen device eth0 entered promiscuous mode
May 18 00:38:59 xen xen-br0: port 1(eth0) entering learning state
May 18 00:38:59 xen xen-br0: topology change detected, propagating
May 18 00:38:59 xen xen-br0: port 1(eth0) entering forwarding state
May 18 00:39:02 xen device vif1.0 entered promiscuous mode
May 18 00:39:02 xen xen-br0: port 2(vif1.0) entering learning state
May 18 00:39:02 xen xen-br0: topology change detected, propagating
May 18 00:39:02 xen xen-br0: port 2(vif1.0) entering forwarding state
May 18 00:39:08 xen login(pam_unix)[5350]: session opened for user root by 
(uid=0)
May 18 00:40:01 xen cron[5417]: (root) CMD (test -x /usr/sbin/run-crons && 
/usr/sbin/run-crons )
May 18 00:41:49 xen login(pam_unix)[5350]: session closed for user root

Crash 1:

May 18 11:13:21 xen oom-killer: gfp_mask=0xd0
May 18 11:13:22 xen DMA per-cpu:
May 18 11:13:22 xen cpu 0 hot: low 4, high 12, batch 2
May 18 11:13:22 xen cpu 0 cold: low 0, high 4, batch 2
May 18 11:13:22 xen Normal per-cpu: empty
May 18 11:13:22 xen HighMem per-cpu: empty
May 18 11:13:22 xen 
May 18 11:13:22 xen Free pages:        1200kB (0kB HighMem)
May 18 11:13:22 xen Active:1432 inactive:4145 dirty:81 writeback:239 unstable:0 
free:300 slab:1623 mapped:790 pagetables:72
May 18 11:13:22 xen DMA free:1200kB min:828kB low:1032kB high:1240kB 
active:5728kB inactive:16580kB present:43008kB pages_scanned:4740 
all_unreclaimable? no
May 18 11:13:22 xen lowmem_reserve[]: 0 0 0
May 18 11:13:22 xen Normal free:0kB min:0kB low:0kB high:0kB active:0kB 
inactive:0kB present:0kB pages_scanned:0 all_unreclaimable? no
May 18 11:13:22 xen lowmem_reserve[]: 0 0 0
May 18 11:13:22 xen HighMem free:0kB min:128kB low:160kB high:192kB active:0kB 
inactive:0kB present:0kB pages_scanned:0 all_unreclaimable? no
May 18 11:13:22 xen lowmem_reserve[]: 0 0 0
May 18 11:13:22 xen DMA: 88*4kB 4*8kB 3*16kB 0*32kB 0*64kB 0*128kB 1*256kB 
1*512kB 0*1024kB 0*2048kB 0*4096kB = 1200kB
May 18 11:13:22 xen Normal: empty
May 18 11:13:22 xen HighMem: empty
May 18 11:13:22 xen Swap cache: add 67030, delete 66063, find 32109/47217, race 
0+3
May 18 11:13:22 xen Free swap  = 253920kB
May 18 11:13:22 xen Total swap = 265032kB
May 18 11:13:22 xen Out of Memory: Killed process 6202 (bash).


Crash 2:

May 18 11:30:57 xen oom-killer: gfp_mask=0xd0
May 18 11:30:58 xen DMA per-cpu:
May 18 11:30:58 xen cpu 0 hot: low 4, high 12, batch 2
May 18 11:30:58 xen cpu 0 cold: low 0, high 4, batch 2
May 18 11:30:58 xen Normal per-cpu: empty
May 18 11:30:58 xen HighMem per-cpu: empty
May 18 11:30:58 xen 
May 18 11:30:58 xen Free pages:         944kB (0kB HighMem)
May 18 11:30:58 xen Active:2583 inactive:3035 dirty:0 writeback:41 unstable:0 
free:236 slab:1671 mapped:1274 pagetables:72
May 18 11:30:58 xen DMA free:944kB min:828kB low:1032kB high:1240kB 
active:10332kB inactive:12140kB present:43008kB pages_scanned:33 
all_unreclaimable? no
May 18 11:30:58 xen lowmem_reserve[]: 0 0 0
May 18 11:30:58 xen Normal free:0kB min:0kB low:0kB high:0kB active:0kB 
inactive:0kB present:0kB pages_scanned:0 all_unreclaimable? no
May 18 11:30:58 xen lowmem_reserve[]: 0 0 0
May 18 11:30:58 xen HighMem free:0kB min:128kB low:160kB high:192kB active:0kB 
inactive:0kB present:0kB pages_scanned:0 all_unreclaimable? no
May 18 11:30:58 xen lowmem_reserve[]: 0 0 0
May 18 11:30:58 xen DMA: 30*4kB 1*8kB 3*16kB 0*32kB 0*64kB 0*128kB 1*256kB 
1*512kB 0*1024kB 0*2048kB 0*4096kB = 944kB
May 18 11:30:58 xen Normal: empty
May 18 11:30:58 xen HighMem: empty
May 18 11:30:58 xen Swap cache: add 106564, delete 105433, find 45919/68254, 
race 0+3
May 18 11:30:58 xen Free swap  = 253916kB
May 18 11:30:58 xen Total swap = 265032kB
May 18 11:30:58 xen Out of Memory: Killed process 6295 (bash).

_______________________________________________
Xen-users mailing list
Xen-users@xxxxxxxxxxxxxxxxxxx
http://lists.xensource.com/xen-users