From owner-freebsd-stable  Tue Jan 11  7:14:27 2000
Delivered-To: freebsd-stable@freebsd.org
Received: from dorifer.heim3.tu-clausthal.de (dorifer.heim3.tu-clausthal.de [139.174.243.252])
	by hub.freebsd.org (Postfix) with ESMTP id C3DDA15493
	for <freebsd-stable@FreeBSD.ORG>; Tue, 11 Jan 2000 07:14:19 -0800 (PST)
	(envelope-from olli@dorifer.heim3.tu-clausthal.de)
Received: (from olli@localhost)
	by dorifer.heim3.tu-clausthal.de (8.9.3/8.9.3) id QAA38786;
	Tue, 11 Jan 2000 16:14:16 +0100 (CET)
	(envelope-from olli)
Date: Tue, 11 Jan 2000 16:14:16 +0100 (CET)
Message-Id: <200001111514.QAA38786@dorifer.heim3.tu-clausthal.de>
From: Oliver Fromme <olli@dorifer.heim3.tu-clausthal.de>
To: freebsd-stable@FreeBSD.ORG
Reply-To: freebsd-stable@FreeBSD.ORG
Subject: Re: load spike strangeness
X-Newsgroups: list.freebsd-stable
In-Reply-To: <858qfm$17gj$1@atlantis.rz.tu-clausthal.de>
User-Agent: tin/1.4.1-19991201 ("Polish") (UNIX) (FreeBSD/3.4-19991219-STABLE (i386))
MIME-Version: 1.0
Content-Type: text/plain; charset=ISO-8859-1
Content-Transfer-Encoding: 8bit
Sender: owner-freebsd-stable@FreeBSD.ORG
Precedence: bulk
X-Loop: FreeBSD.ORG

Hi,

It happened again, and I noticed it in time to record a few
stats...  But I fear that this is not enough to actually
debug the problem.  What else could I do if this happenes
again?  In this particular case, the load dropped back to
0.0 after a few minutes, for no apparent reason.

BTW, this is some 3-stable (not the newest, but I watched
the same behaviour on all kinds of different versions).


Script started on Tue Jan 11 16:02:40 2000
lysithea# top -S -b 999999
last pid:   665;  load averages:  1.07,  1.03,  0.98  up 0+11:03:46    16:02:46
38 processes:  1 running, 37 sleeping

Mem: 29M Active, 6368K Inact, 8152K Wired, 2064K Cache, 3861K Buf, 16M Free
Swap: 128M Total, 128M Free


  PID USERNAME PRI NICE  SIZE    RES STATE    TIME   WCPU    CPU COMMAND
  569 root       2   0 21856K 20180K select   0:52  0.00%  0.00% XF86_SVGA
  195 root       2   0   784K   472K select   0:05  0.00%  0.00% moused
  222 ekg       10   0   764K   468K nanslp   0:05  0.00%  0.00% heartbeat
  640 netscape   2   0 15192K 12700K select   0:03  0.00%  0.00% navigator-4.08
  602 root       2   0  2036K  1368K select   0:02  0.00%  0.00% bigredknob
   93 root       2 -12  1040K   668K select   0:02  0.00%  0.00% xntpd
  232 root      10   0   764K   464K nanslp   0:01  0.00%  0.00% idlewatch
  255 root       2   0  1184K   704K select   0:01  0.00%  0.00% sshd1
  596 netscape   2   0  1684K  1176K select   0:01  0.00%  0.00% fvwm
    4 root      18   0     0K     0K syncer   0:00  0.00%  0.00% syncer
  115 daemon     2   0   784K   480K sbwait   0:00  0.00%  0.00% rwhod
   17 root      10   0 32532K  2268K mfsidl   0:00  0.00%  0.00% mount_mfs
  645 root       2   0  1228K   780K select   0:00  0.00%  0.00% sshd1
   85 root       2   0   812K   468K select   0:00  0.00%  0.00% syslogd
  262 root      10   0   504K   188K wait     0:00  0.00%  0.00% sh
  650 root      18   0  1180K   848K pause    0:00  0.00%  0.00% zsh
    2 root     -18   0     0K     0K psleep   0:00  0.00%  0.00% pagedaemon
  264 root      10   0   504K   168K wait     0:00  0.00%  0.00% sh
  641 netscape   2   0 10048K  7492K select   0:00  0.00%  0.00% navigator-4.08
  647 root      18   0   456K   284K pause    0:00  0.00%  0.00% csh
  665 root      28   0  1500K   756K RUN      0:00  0.00%  0.00% top
  109 root      10   0   208K    32K nfsidl   0:00  0.00%  0.00% nfsiod
  599 netscape   2   0   792K   484K select   0:00  0.00%  0.00% FvwmClean
  263 root      10   0   496K   164K wait     0:00  0.00%  0.00% sh
  639 netscape  18   0   448K   272K pause    0:00  0.00%  0.00% csh
  568 root      10   0  1860K  1012K wait     0:00  0.00%  0.00% xinit
  583 netscape  18   0   448K   240K pause    0:00  0.00%  0.00% csh
    0 root     -18   0     0K     0K sched    0:00  0.00%  0.00% swapper
  663 root      18   0   452K   280K pause    0:00  0.00%  0.00% csh
  662 root       2   0   808K   584K select   0:00  0.00%  0.00% script
  584 netscape  10   0   504K   168K wait     0:00  0.00%  0.00% sh
    1 root      10   0   484K   168K wait     0:00  0.00%  0.00% init
  110 root      10   0   208K    32K nfsidl   0:00  0.00%  0.00% nfsiod
  229 root      10   0   492K   188K wait     0:00  0.00%  0.00% sh
   32 root      18   0   204K    60K pause    0:00  0.00%  0.00% adjkerntz
  111 root      10   0   208K    32K nfsidl   0:00  0.00%  0.00% nfsiod
  112 root      10   0   208K    32K nfsidl   0:00  0.00%  0.00% nfsiod
    3 root      18   0     0K     0K psleep   0:00  0.00%  0.00% vmdaemon

lysithea# vmstat 5
 procs      memory     page                   disk faults      cpu
 r b w     avm   fre  flt  re  pi  po  fr  sr fd0   in   sy  cs us sy id
 1 0 0    6592 18860    3   0   0   0   3   0   0  235  184  21  0  0 99
 0 0 0    6916 18860    1   0   0   0   0   0   0  231   30  12  0  0 100
 0 0 0    6916 18860    1   0   0   0   0   0   0  231   29  13  0  0 100
 0 0 0    5904 18860    1   0   0   0   0   0   0  231   29  13  0  0 100
^C
lysithea# ps -auxww
USER       PID %CPU %MEM   VSZ  RSS  TT  STAT STARTED      TIME COMMAND
root       667  0.0  0.4   404  240  p1  R+    4:03PM   0:00.00 ps -auxww
root         1  0.0  0.3   484  168  ??  Is    5:59AM   0:00.01 /sbin/init --
root         2  0.0  0.0     0    0  ??  DL    5:59AM   0:00.06  (pagedaemon)
root         3  0.0  0.0     0    0  ??  DL    5:59AM   0:00.00  (vmdaemon)
root         4  0.0  0.0     0    0  ??  DL    5:59AM   0:00.30  (syncer)
root        17  0.0  3.6 32532 2268  ??  SLs   5:59AM   0:00.20 mfs -o nosuid -s 64000 -m 0 -T minimum /dev/zero /tmp (mount_mfs)
root        32  0.0  0.1   204   60  ??  Is    5:59AM   0:00.00 adjkerntz -i
root        85  0.0  0.8   812  468  ??  Is    4:59AM   0:00.12 syslogd -s
root        93  0.0  1.1  1040  668  ??  S<s   4:59AM   0:01.60 xntpd -c /etc/ntp.conf -p /var/run/xntpd.pid
root       109  0.0  0.1   208   32  ??  I     4:59AM   0:00.02 nfsiod -n 4
root       110  0.0  0.1   208   32  ??  I     4:59AM   0:00.00 nfsiod -n 4
root       111  0.0  0.1   208   32  ??  I     4:59AM   0:00.00 nfsiod -n 4
root       112  0.0  0.1   208   32  ??  I     4:59AM   0:00.00 nfsiod -n 4
daemon     115  0.0  0.8   784  480  ??  Is    4:59AM   0:00.28 rwhod
root       195  0.0  0.8   784  472  ??  Is    4:59AM   0:05.24 moused -p /dev/mouse -t auto
ekg        222  0.0  0.8   764  468 con- S+    4:59AM   0:05.07 /usr/local/bin/heartbeat 10.0.0.1
root       229  0.0  0.3   492  188 con- I+    4:59AM   0:00.00 /bin/sh - /usr/local/sbin/idlewatch.loop
root       232  0.0  0.7   764  464 con- S+    4:59AM   0:00.89 /usr/local/sbin/idlewatch
root       255  0.0  1.1  1184  704  ??  Is    4:59AM   0:00.54 /usr/local/sbin/sshd (sshd1)
root       262  0.0  0.3   504  188 con- I+    4:59AM   0:00.08 /bin/sh - /usr/local/sbin/x11runner.netscape
root       263  0.0  0.3   496  164 con- I+    4:59AM   0:00.02 /bin/sh - /usr/local/sbin/x11runner.X
root       264  0.0  0.3   504  168 con- I+    4:59AM   0:00.06 /bin/sh - /usr/local/sbin/x11runner.bigredknob
root       568  0.0  1.6  1860 1012  ??  I    12:17PM   0:00.02 xinit /usr/bin/su -l netscape -c /usr/local/sbin/xinitrc -- /usr/X11R6/bin/X vt1
root       569  0.0 32.0 21856 20180  ??  I    12:17PM   0:52.02 /usr/X11R6/bin/XF86_SVGA -xf86config /etc/XF86Configs/lysithea :0 vt1
netscape   583  0.0  0.4   448  240  ??  I    12:17PM   0:00.01 -su -c /usr/local/sbin/xinitrc (csh)
netscape   584  0.0  0.3   504  168  ??  I    12:17PM   0:00.01 /bin/sh - /usr/local/sbin/xinitrc
netscape   596  0.0  1.9  1684 1176  ??  I    12:17PM   0:00.50 fvwm
netscape   599  0.0  0.8   792  484  ??  I    12:17PM   0:00.02 /usr/X11R6/lib/X11/fvwm/FvwmClean 8 5 /tmp/fvwmrcuf596 0 0
root       602  0.0  2.2  2036 1368  ??  S    12:17PM   0:02.11 /usr/local/bin/bigredknob
netscape   639  0.0  0.4   448  272  ??  I     2:58PM   0:00.02 -su (csh)
netscape   640  0.0 20.1 15192 12700  ??  I     2:58PM   0:02.58 /usr/local/lib/netscape/navigator-4.08.bin http://aphrodite.infoterm.tuc/
netscape   641  0.0 11.9 10048 7492  ??  I     2:58PM   0:00.06 (dns helper) (navigator-4.08.b)
root       645  0.0  1.2  1228  780  ??  S     4:00PM   0:00.16 /usr/local/sbin/sshd (sshd1)
root       647  0.0  0.5   456  284  p0  Is    4:00PM   0:00.02 -csh (csh)
root       650  0.0  1.4  1180  848  p0  I     4:00PM   0:00.07 zsh
root       662  0.0  0.9   808  584  p0  S+    4:02PM   0:00.01 script
root       663  0.0  0.5   452  288  p1  Ss    4:02PM   0:00.01 -h -i (csh)
root         0  0.0  0.0     0    0  ??  DLs   5:59AM   0:00.01  (swapper)
lysithea# uptime
 4:03PM  up 11:04, 1 user, load averages: 1.04, 1.03, 0.98
lysithea#
lysithea# uptime
 4:04PM  up 11:05, 1 user, load averages: 1.02, 1.02, 0.98
lysithea# top -S -b 999999
last pid:   670;  load averages:  1.02,  1.02,  0.98  up 0+11:05:07    16:04:07
38 processes:  1 running, 37 sleeping

Mem: 29M Active, 6384K Inact, 8172K Wired, 2064K Cache, 3884K Buf, 16M Free
Swap: 128M Total, 128M Free


  PID USERNAME PRI NICE  SIZE    RES STATE    TIME   WCPU    CPU COMMAND
  569 root       2   0 21856K 20180K select   0:52  0.00%  0.00% XF86_SVGA
  195 root       2   0   784K   472K select   0:05  0.00%  0.00% moused
  222 ekg       10   0   764K   468K nanslp   0:05  0.00%  0.00% heartbeat
  640 netscape   2   0 15192K 12700K select   0:03  0.00%  0.00% navigator-4.08
  602 root       2   0  2036K  1368K select   0:02  0.00%  0.00% bigredknob
   93 root       2 -12  1040K   668K select   0:02  0.00%  0.00% xntpd
  232 root      10   0   764K   464K nanslp   0:01  0.00%  0.00% idlewatch
  255 root       2   0  1184K   704K select   0:01  0.00%  0.00% sshd1
  596 netscape   2   0  1684K  1176K select   0:01  0.00%  0.00% fvwm
    4 root      18   0     0K     0K syncer   0:00  0.00%  0.00% syncer
  115 daemon     2   0   784K   480K sbwait   0:00  0.00%  0.00% rwhod
   17 root      10   0 32532K  2268K mfsidl   0:00  0.00%  0.00% mount_mfs
  645 root       2   0  1228K   780K select   0:00  0.00%  0.00% sshd1
   85 root       2   0   812K   468K select   0:00  0.00%  0.00% syslogd
  262 root      10   0   504K   188K wait     0:00  0.00%  0.00% sh
  650 root      18   0  1180K   848K pause    0:00  0.00%  0.00% zsh
    2 root     -18   0     0K     0K psleep   0:00  0.00%  0.00% pagedaemon
  264 root      10   0   504K   168K wait     0:00  0.00%  0.00% sh
  641 netscape   2   0 10048K  7496K select   0:00  0.00%  0.00% navigator-4.08
  647 root      18   0   456K   284K pause    0:00  0.00%  0.00% csh
  109 root      10   0   208K    32K nfsidl   0:00  0.00%  0.00% nfsiod
  670 root      29   0  1500K   756K RUN      0:00  0.00%  0.00% top
  599 netscape   2   0   792K   484K select   0:00  0.00%  0.00% FvwmClean
  263 root      10   0   496K   164K wait     0:00  0.00%  0.00% sh
  639 netscape  18   0   448K   272K pause    0:00  0.00%  0.00% csh
  568 root      10   0  1860K  1012K wait     0:00  0.00%  0.00% xinit
  662 root       2   0   808K   584K select   0:00  0.00%  0.00% script
  663 root      18   0   456K   292K pause    0:00  0.00%  0.00% csh
  583 netscape  18   0   448K   240K pause    0:00  0.00%  0.00% csh
    0 root     -18   0     0K     0K sched    0:00  0.00%  0.00% swapper
  584 netscape  10   0   504K   168K wait     0:00  0.00%  0.00% sh
    1 root      10   0   484K   168K wait     0:00  0.00%  0.00% init
  110 root      10   0   208K    32K nfsidl   0:00  0.00%  0.00% nfsiod
  229 root      10   0   492K   188K wait     0:00  0.00%  0.00% sh
   32 root      18   0   204K    60K pause    0:00  0.00%  0.00% adjkerntz
  112 root      10   0   208K    32K nfsidl   0:00  0.00%  0.00% nfsiod
  111 root      10   0   208K    32K nfsidl   0:00  0.00%  0.00% nfsiod
    3 root      18   0     0K     0K psleep   0:00  0.00%  0.00% vmdaemon

lysithea# vmstat 5
 procs      memory     page                   disk faults      cpu
 r b w     avm   fre  flt  re  pi  po  fr  sr fd0   in   sy  cs us sy id
 1 0 0    5996 18824    3   0   0   0   3   0   0  235  184  21  0  0 99
 0 0 0    5996 18824    1   0   0   0   0   0   0  231   29  12  0  0 100
 0 0 0    5696 18824    1   0   0   0   0   0   0  231   29  13  0  0 100
^C
lysithea# ls -al
total 44
drwxrwxrwt   9 root      wheel       512 Jan 11 16:02 .
drwxr-xr-x  15 root      wheel       512 Dec 23 13:59 ..
-r--r--r--   1 root      wheel        11 Jan 11 12:17 .X0-lock
drwxrwxrwt   2 root      wheel       512 Jan 11 12:17 .X11-unix
drwxr-xr-x   2 root      wheel       512 Jan 11 04:59 dev
-rw-r--r--   1 netscape  wheel     19570 Jan 11 12:17 fvwmrcuf596
drwxr-xr-x   4 netscape  netscape    512 Jan 11 13:15 netscape
drwxr-xr-x   4 netscape  netscape    512 Jan 11 14:58 netscape.new
drwxr-xr-x   2 root      wheel       512 Jan 11 15:59 run
drwxrwxr-x   2 root      daemon      512 Jan 11 05:02 rwho
drwx------   2 root      wheel       512 Jan 11 16:00 ssh-root
-rw-r--r--   1 netscape  wheel         0 Jan 11 12:17 start.ok
-rw-r--r--   1 root      wheel      2893 Jan 11 16:02 top_-S_-b_999999
-rw-r--r--   1 root      wheel     10524 Jan 11 16:04 typescript
lysithea# 

Script done on Tue Jan 11 16:04:45 2000

Regards
   Oliver

-- 
Oliver Fromme, Leibnizstr. 18/61, 38678 Clausthal, Germany
(Info: finger userinfo:olli@dorifer.heim3.tu-clausthal.de)

"In jedem Stück Kohle wartet ein Diamant auf seine Geburt"
                                         (Terry Pratchett)


To Unsubscribe: send mail to majordomo@FreeBSD.org
with "unsubscribe freebsd-stable" in the body of the message