From mboxrd@z Thu Jan 1 00:00:00 1970 Return-Path: Received: (majordomo@vger.kernel.org) by vger.kernel.org via listexpand id ; Thu, 7 Jun 2001 12:29:26 -0400 Received: (majordomo@vger.kernel.org) by vger.kernel.org id ; Thu, 7 Jun 2001 12:29:17 -0400 Received: from ns.tasking.nl ([195.193.207.2]:60171 "EHLO ns.tasking.nl") by vger.kernel.org with ESMTP id ; Thu, 7 Jun 2001 12:29:00 -0400 Date: Thu, 7 Jun 2001 18:28:10 +0200 From: Frank van Maarseveen To: linux-kernel@vger.kernel.org Subject: 2.4.5: extreme sluggishness/strange OOM Message-ID: <20010607182810.A29223@espoo.tasking.nl> Reply-To: frank_van_maarseveen@tasking.com Mime-Version: 1.0 Content-Type: text/plain; charset=us-ascii X-Mailer: Mutt 1.0i (Linux) Organization: TASKING, Inc. Sender: linux-kernel-owner@vger.kernel.org X-Mailing-List: linux-kernel@vger.kernel.org A PIII with 64MB ram, 256MB swap became extremely sluggish. While still inside X11 the caps-lock LED responded only after about 10 seconds. Disk LED was continuously on. Impossible to connect from another system (just too slow) but the box still responded to ICMP echo. I played a bit with magic sysrq before the system was rebooted. This is an excerpt from /var/log/messages. I don't know how to interpret everything but certainly the long stack traces look suspicious to me. Obviously the system ran out of memory and swap but I'm not sure how. Yes I know here are some VM issues which _might_ be responsible. Jun 7 16:49:51 posio kernel: Out of Memory: Killed process 10165 (gnome-terminal). Jun 7 16:49:54 posio automount[4566]: expired /usr/src/olman Jun 7 16:50:40 posio automount[555]: attempting to mount entry /usr/src/olman Jun 7 16:53:51 posio automount[6550]: expired /usr/src/olman Jun 7 16:54:50 posio automount[555]: attempting to mount entry /usr/src/olman Jun 7 16:56:32 posio automount[6694]: expired /usr/src/olman Jun 7 16:58:08 posio kernel: Out of Memory: Killed process 6033 (gnome-terminal). Jun 7 16:58:10 posio kernel: Out of Memory: Killed process 12952 (gnome-terminal). Jun 7 16:58:10 posio kernel: Out of Memory: Killed process 16744 (gnomecal). Jun 7 16:58:10 posio kernel: Out of Memory: Killed process 16742 (gmc). Jun 7 16:58:10 posio kernel: Out of Memory: Killed process 16736 (panel). Jun 7 16:58:58 posio automount[555]: attempting to mount entry /usr/src/olman Jun 7 17:04:08 posio kernel: Out of Memory: Killed process 7882 (cpumemusage_app). Jun 7 17:04:40 posio kernel: Out of Memory: Killed process 6756 (gmc). Jun 7 17:05:06 posio automount[13344]: expired /usr/src/olman Jun 7 17:05:20 posio automount[555]: attempting to mount entry /usr/src/olman Jun 7 17:06:32 posio automount[13829]: expired /usr/src/olman Jun 7 17:07:53 posio kernel: Out of Memory: Killed process 6748 (panel). Jun 7 17:08:01 posio automount[555]: attempting to mount entry /usr/src/olman Jun 7 17:11:13 posio automount[15088]: expired /usr/src/olman Jun 7 17:12:09 posio automount[555]: attempting to mount entry /usr/src/olman Jun 7 17:13:30 posio automount[15543]: expired /usr/src/olman Jun 7 17:13:55 posio automount[555]: attempting to mount entry /usr/src/olman Jun 7 17:15:12 posio automount[15778]: expired /usr/src/olman Jun 7 17:19:42 posio automount[555]: attempting to mount entry /usr/src/olman Jun 7 17:20:57 posio automount[16168]: expired /usr/src/olman Jun 7 17:23:43 posio pam_rhosts_auth[16274]: allowed to fvm@espoo.tasking.nl as fvm Jun 7 17:24:04 posio kernel: SysRq: Show Memory Jun 7 17:24:04 posio kernel: Mem-info: Jun 7 17:24:04 posio kernel: Free pages: 1768kB ( 0kB HighMem) Jun 7 17:24:04 posio kernel: ( Active: 346, inactive_dirty: 96, inactive_clean: 201, free: 442 (222 444 666) ) Jun 7 17:24:04 posio kernel: 115*4kB 7*8kB 1*16kB 1*32kB 1*64kB 1*128kB 1*256kB 0*512kB 0*1024kB 0*2048kB = 1012kB) Jun 7 17:24:04 posio kernel: 85*4kB 8*8kB 2*16kB 0*32kB 1*64kB 0*128kB 1*256kB 0*512kB 0*1024kB 0*2048kB = 756kB) Jun 7 17:24:04 posio kernel: = 0kB) Jun 7 17:24:04 posio kernel: Swap cache: add 940496, delete 940331, find 2846139/4357658 Jun 7 17:24:04 posio kernel: Free swap: 0kB Jun 7 17:24:04 posio kernel: 16128 pages of RAM Jun 7 17:24:04 posio kernel: 0 pages of HIGHMEM Jun 7 17:24:04 posio kernel: 1029 reserved pages Jun 7 17:24:05 posio kernel: 2646 pages shared Jun 7 17:24:06 posio kernel: 165 pages swap cached Jun 7 17:24:07 posio kernel: 0 pages in page table cache Jun 7 17:24:07 posio kernel: Buffer memory: 152kB Alt-SysRq-T: Jun 7 17:24:10 posio kernel: >] [] [] [] Jun 7 17:24:10 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:10 posio last message repeated 32 times Jun 7 17:24:10 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:10 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:10 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:10 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:10 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:10 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:10 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:10 posio kernel: [] [] [] [] [] [] [] Jun 7 17:24:10 posio kernel: sh S 00000000 0 15683 15251 15686 (NOTLB) Jun 7 17:24:10 posio kernel: Call Trace: [] [] [] [] [] [] [] Jun 7 17:24:10 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:10 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:10 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:11 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:11 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:11 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:11 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:11 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:11 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:12 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:12 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:12 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:12 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:12 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:13 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:13 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:13 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:13 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:13 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:13 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:14 posio kernel: [] [] [] Jun 7 17:24:14 posio kernel: yam S 00000000 0 15686 15683 16156 (NOTLB) Jun 7 17:24:14 posio kernel: Call Trace: [] [] [] [] [] [] [] Jun 7 17:24:16 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:17 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:17 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:17 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:17 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:17 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:17 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:18 posio kernel: [] [] [] [] [] [] [] Jun 7 17:24:18 posio kernel: sh S 00000000 1796 16156 15686 16159 (NOTLB) Jun 7 17:24:18 posio kernel: Call Trace: [] [] [] [] [] [] [] Jun 7 17:24:19 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:19 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:19 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:20 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:20 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:20 posio kernel: [] [] [] [] [] [] [] Jun 7 17:24:20 posio kernel: yam S 00000000 0 16159 16156 16278 (NOTLB) Jun 7 17:24:23 posio kernel: Call Trace: [] [] [] [] [] [] [] Jun 7 17:24:23 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:23 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:23 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:23 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:24 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:24 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:24 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:24 posio kernel: sh S C2114000 1336 16278 16159 16290 (NOTLB) Jun 7 17:24:24 posio kernel: Call Trace: [] [] [] [] [] [] [] Jun 7 17:24:24 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:25 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:25 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:25 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:25 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:25 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:25 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:25 posio kernel: [] [] Jun 7 17:24:25 posio kernel: in.telnetd S 7FFFFFFF 0 16284 394 16289 (NOTLB) Jun 7 17:24:25 posio kernel: Call Trace: [] [] [] [] [] [] [] Jun 7 17:24:26 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:26 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:26 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:26 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:26 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:26 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:26 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:26 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:27 posio kernel: [] Jun 7 17:24:27 posio kernel: login D C166B4A0 1776 16289 16284 (NOTLB) Jun 7 17:24:27 posio kernel: Call Trace: [] [] [] [] [] [] [] Jun 7 17:24:27 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:27 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:27 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:27 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:28 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:28 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:28 posio kernel: [] Jun 7 17:24:28 posio kernel: sh D C1D73CE0 0 16290 16278 (NOTLB) Jun 7 17:24:28 posio kernel: Call Trace: [] [] [] [] [] [] [] Jun 7 17:24:29 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:29 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:29 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:29 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:29 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:29 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:29 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:30 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:30 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:30 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:30 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:30 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:30 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:30 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:30 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:30 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:31 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:31 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:31 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:31 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:33 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:34 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:34 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:35 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:35 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:35 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:35 posio kernel: [] ksymoops said: Trace; d9d9d800 Trace; d9d9d9d9 Trace; e8ba2e8c [cut 150 lines of similar garbage] Trace; ff008b50 Trace; f0658dd0 Trace; db310c55 Trace; c01a08a3 <__make_request+167/7bc> Trace; eb04708b Trace; c01102f2 Trace; c01cd841 <= recursion? Trace; c01d5832 <= Trace; c01d5260 <= Trace; c01ce9e0 <= Trace; c01ce968 <= Trace; c01ceca1 <= Trace; c01cf11b Trace; c01d539c Trace; c0107f3d Trace; c010a188 Trace; c01ae9a8 Trace; c026dfa3 Trace; c0268608 Trace; c0275bc3 Trace; c026da8f Trace; c0274a43 Trace; c0275b40 Trace; c0275b39 Trace; c026da8f Trace; c02754bd Trace; c0275b2c Trace; c028bc7d Trace; c028b83c Trace; c029127c Trace; c02912b6 Trace; c026218d Trace; c02a3498 Trace; c02a43f9 Trace; c02a3a02 Trace; c02a3a38 Trace; c0264de7 Trace; c0264f74 <__kfree_skb+140/148> Trace; c0268919 Trace; c016ab40 Trace; c016ab20 Trace; c02a42c9 Trace; c01cd841 <= Trace; c01d5832 <= Trace; c01d5260 <= Trace; c01ce9e0 <= Trace; c01ce968 <= Trace; c01ceca1 <= Trace; c0126e96 Trace; c0128764 <__alloc_pages_limit+6c/90> Trace; c012fa7e Trace; c01a08a3 <__make_request+167/7bc> Trace; c01a0fd1 Trace; c012e845 <__wait_on_buffer+55/9c> Trace; c014f261 Trace; c0140fea Trace; c014f4a0 Trace; c01374bb Trace; c013787c Trace; c013724c Trace; c01381e8 <__user_walk+3c/58> Trace; c0134fd6 Trace; c0106d03 Jun 7 17:24:57 posio kernel: SysRq: Show Regs Jun 7 17:24:57 posio kernel: Jun 7 17:24:57 posio kernel: EIP: 0010:[] CPU: 0 EFLAGS: 00000246 Jun 7 17:24:57 posio kernel: EAX: 00000001 EBX: c1130360 ECX: 0000000e EDX: c2213d98 Jun 7 17:24:57 posio kernel: ESI: 00000001 EDI: 0000000e EBP: c2213d98 DS: 0018 ES: 0018 Jun 7 17:24:57 posio kernel: CR0: 8005003b CR2: 400332c8 CR3: 03ef0000 CR4: 000006d0 Jun 7 17:24:57 posio kernel: Call Trace: [] [] [] [] [] [] [] Jun 7 17:24:57 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:57 posio kernel: [] [] [] [] [] [] [] [] Jun 7 17:24:57 posio kernel: [] [] [] [] [] >>EIP; c0107f30 <===== Trace; c01080a7 Trace; c0106dc0 Trace; c0230018 Trace; c0100018 Trace; c023723b Trace; c02372ac Trace; c02372cc Trace; c0118f84 Trace; c0116237 Trace; c0116178 Trace; c011607e Trace; c01080d8 Trace; c4899000 Trace; c0106dc0 Trace; c4899000 Trace; ffff0001 Trace; c0100018 Trace; c01292e7 <__swap_free+af/140> Trace; c4899000 Trace; c011eb28 Trace; c011ede2 Trace; c010f75b Trace; c010f5f4 Trace; ffff037f Trace; ffff0120 Trace; c0106171 Trace; c010623e Trace; c0106e44 Jun 7 17:24:57 posio kernel: SysRq: Show Regs Jun 7 17:24:57 posio kernel: Jun 7 17:24:58 posio kernel: EIP: 0010:[] CPU: 0 EFLAGS: 00000286 Jun 7 17:24:58 posio kernel: EAX: 00000003 EBX: c03b1f40 ECX: 00000001 EDX: c038914c Jun 7 17:24:58 posio kernel: ESI: c1164060 EDI: 00000286 EBP: c03b1f00 DS: 0018 ES: 0018 Jun 7 17:24:59 posio kernel: CR0: 8005003b CR2: 080601d4 CR3: 037ee000 CR4: 000006d0 Jun 7 17:24:59 posio kernel: Call Trace: [] [] [] [] >>EIP; c01cf136 <===== Trace; c01d5260 Trace; c0107f3d Trace; c01080a7 Trace; c0106dc0 Jun 7 17:24:59 posio kernel: SysRq: Show Regs Jun 7 17:24:59 posio kernel: Jun 7 17:24:59 posio kernel: EIP: 0010:[] CPU: 0 EFLAGS: 00000296 Jun 7 17:25:00 posio kernel: EAX: 00000f94 EBX: 00000f94 ECX: 01000002 EDX: 00fcbcad Jun 7 17:25:00 posio kernel: ESI: c0348000 EDI: c03b5d40 EBP: 0008e000 DS: 0018 ES: 0018 Jun 7 17:25:00 posio kernel: CR0: 8005003b CR2: 0804ee44 CR3: 03865000 CR4: 000006d0 Jun 7 17:25:01 posio kernel: Call Trace: [] [] [] [] >>EIP; c025ae0c <===== Trace; c025ac4c Trace; c01053da Trace; c0105000 Trace; c0100198 -- Frank