Subject: Re: Problems with NetBSD 1.4.1+ on Sparc 5
To: Charles M. Hannum <root@ihack.net>
From: Brian Buhrow <buhrow@lothlorien.nfbcal.org>
List: port-sparc
Date: 12/27/1999 17:03:44
	To follow up on my own post of earlier today, I tried the 1.4.2_ALPHA kernel
from ftp.netbsd.org and find that it panics regularly every 30 minutes,
just when innd gets ready to start receiving articles in mass.  Again, I've
notated a backtrace and find that ffs_nodealloccg causes the machine to
throw a memfault_sun4m exception.  I think there's potentially a real
problem here, anyone able to shed light on what it is?
-Brian

Script started on Mon Dec 27 15:47:05 1999
%ftp  nf  news.zodal^C
%

NetBSD/sparc (news) (console)

login: data fault: pc=0xf01c463c addr=0xef4f25d9 sfsr=226<PERR=0,LVL=2,AT=1,FT=1,FAV,OW>
panic: kernel fault
syncing disks... panic: lockmgr: locking against myself
Frame pointer is at 0xf8a7c440
Call traceback:
  pc = 0xf01988bc [_cpu_reboot] args = (0x40010e6, 0x4001fe6, 0x0, 0x0, 0xf8a7c558, 0x49010e6, 0xf8a7c4a8) fp = 0xf8a7c4a8
  pc = 0xf004ec88 [_panic] args = (0x104, 0x0, 0x0, 0xf8a7c4ec, 0xf0797f00, 0x16b6b74, 0xf8a7c510) fp = 0xf8a7c510
  pc = 0xf00418c4 [trapbase_sun4] args = (0xf00414b8, 0x104, 0xf0041880, 0x2, 0x0, 0x1000, 0xf8a7c578) fp = 0xf8a7c578
  pc = 0xf01380bc [_ufs_lock] args = (0xf8c09704, 0x10, 0xf8c08510, 0x0, 0xf8f59c84, 0xf0002000, 0xf8a7c5e0) fp = 0xf8a7c5e0
  pc = 0xf00746d0 [_vn_lock] args = (0xf8a7c6a8, 0xf01380a8, 0x78, 0xf082de00, 0xf014b410, 0x0, 0xf8a7c648) fp = 0xf8a7c648
  pc = 0xf006d17c [_vget] args = (0xf8c08480, 0x10012, 0xf0041a70, 0x6, 0x31, 0x0, 0xf8a7c6c0) fp = 0xf8a7c6c0
  pc = 0xf01261e8 [_ffs_sync] args = (0xf8c08480, 0x10012, 0x0, 0x31, 0x2, 0x1, 0xf8a7c728) fp = 0xf8a7c728
  pc = 0xf006fcc0 [_sys_sync] args = (0x0, 0x2, 0xf081d700, 0xf0203848, 0xf0126144, 0xf020c8d0, 0xf8a7c7a8) fp = 0xf8a7c7a8
  pc = 0xf006ed24 [_vfs_shutdown] args = (0xf0203848, 0x0, 0x0, 0xf876a8c0, 0xf020c000, 0x31, 0xf8a7c810) fp = 0xf8a7c810
  pc = 0xf0198888 [_cpu_reboot] args = (0xf0203800, 0xf0002000, 0xf01e3800, 0x0, 0xf8a7c928, 0x49010e7, 0xf8a7c878) fp = 0xf8a7c878
  pc = 0xf004ec88 [_panic] args = (0x100, 0x0, 0x0, 0x0, 0xf8a7c99c, 0x49010e0, 0xf8a7c8e0) fp = 0xf8a7c8e0
  pc = 0xf01a8f00 [_mem_access_fault4m] args = (0xf01a8600, 0x100, 0xef4f25d9, 0xf8a7c9a8, 0x1e, 0x1, 0xf8a7c948) fp = 0xf8a7c948
  pc = 0xf0008518 [_memfault_sun4m] args = (0x0, 0x226, 0xef4f25d9, 0xf8a7ca50, 0xf8a7b564, 0x2710, 0xf8a7c9f0) fp = 0xf8a7c9f0
  pc = 0xf0121a08 [_ffs_nodealloccg] args = (0xff, 0x1f1ed20, 0xef4f25d9, 0x90255, 0xffffffff, 0xf80, 0xf8a7caa0) fp = 0xf8a7caa0
  pc = 0xf012048c [_ffs_hashalloc] args = (0x1f1ed20, 0xc7, 0x2, 0x81b4, 0x2, 0xf0866600, 0xf8a7cb10) fp = 0xf8a7cb10
  pc = 0xf0120068 [_ffs_valloc] args = (0xf8c096d0, 0xc7, 0xc0c82, 0x81b4, 0xf012183c, 0xf80, 0xf8a7cb78) fp = 0xf8a7cb78
  pc = 0xf0138978 [_ufs_makeinode] args = (0xf8a7cc58, 0xf011ffdc, 0x98, 0xf8a7cc54, 0xf082de00, 0xf083e400, 0xf8a7cbf0) fp = 0xf8a7cbf0
  pc = 0xf0135864 [_ufs_create] args = (0x81b4, 0xf8c08480, 0xf8a7ce68, 0xf8a7ce7c, 0x8000, 0xf8a7cd58, 0xf8a7cc78) fp = 0xf8a7cc78
  pc = 0xf00738b0 [_vn_open] args = (0xf8a7cd40, 0x10, 0xf0135838, 0xf082de00, 0xf01d1400, 0xf8c08480, 0xf8a7cce0) fp = 0xf8a7cce0
  pc = 0xf00704b4 [_sys_open] args = (0x0, 0x602, 0x1b4, 0x1b4, 0x208, 0x0, 0xf8a7cdc8) fp = 0xf8a7cdc8
  pc = 0xf01a92a0 [_syscall] args = (0x0, 0xf877ad70, 0xf8a7cf20, 0xf0070438, 0x68, 0x26848, 0xf8a7cec0) fp = 0xf8a7cec0
  pc = 0xf00087b0 [syscall] args = (0x5, 0xf8a7cfb0, 0x10098898, 0xa, 0x0, 0x12d1, 0xf8a7cf50) fp = 0xf8a7cf50
  pc = 0x36d0  args = (0xeffff3a0, 0x601, 0x1b4, 0xeffff3c4, 0x6, 0xffffffff, 0xefffea50) fp = 0xefffea50

dump to dev 7,1 not possible
sd4: WARNING: cache synchronization failed
sd3: WARNING: cache synchronization failed
rebooting

Resetting ... 
SPARCstation 5, No Keyboard
ROM Rev. 2.15, 256 MB memory installed, Serial #7788135.
Ethernet address 8:0:20:76:d6:67, Host ID: 8076d667.



Initializing Memory |/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\                                                                      Rebooting with command: 
Boot device: /iommu/sbus/espdma@5,8400000/esp@5,8800000/sd@3,0  File and args: 
>> NetBSD/sparc Secondary Boot, Revision 1.9
>> (pk@flambard, Tue Oct 19 11:10:24 MEST 1999)
Booting netbsd
entry: 0x4000, bootinfo: 0x245b20
bootinfo[0]=0x245f20; bootinfo[1]=0x245b28
nsym=0x1881c, ssym=0x2103c8, esym=0x245b1c
OBP version 3, revision 2.15 (plugin rev 2)
console is ttya
Copyright (c) 1996, 1997, 1998, 1999
    The NetBSD Foundation, Inc.  All rights reserved.
Copyright (c) 1982, 1986, 1989, 1991, 1993
    The Regents of the University of California.  All rights reserved.

NetBSD 1.4.2_ALPHA (GENERIC_SCSI3) #2: Tue Dec 21 16:15:10 MET 1999
    he@ken.runit.sintef.no:/usr/src/sys/arch/sparc/compile/GENERIC_SCSI3
real mem = 268029952
avail mem = 252063744
using 1792 buffers containing 7340032 bytes of memory
bootpath: /iommu@0,10000000/sbus@0,10001000/espdma@5,8400000/esp@5,8800000/sd@3,0
mainbus0 (root): SUNW,SPARCstation-5
cpu0 at mainbus0: MB86904 @ 85 MHz, on-chip FPU
cpu0: 16K instruction (32 b/l), 8K data (16 b/l): cache enabled
obio0 at mainbus0
clock0 at obio0 slot 0 offset 0x200000: mk48t08 (eeprom)
timer0 at obio0 slot 0 offset 0xd00000 delay constant 40
zs0 at obio0 slot 0 offset 0x100000 level 12 softpri 6
zstty0 at zs0 channel 0 (console)
zstty1 at zs0 channel 1
zs1 at obio0 slot 0 offset 0x0 level 12 softpri 6
kbd0 at zs1 channel 0
ms0 at zs1 channel 1
slavioconfig at obio0 slot 0 offset 0x800000 not configured
auxreg0 at obio0 slot 0 offset 0x900000
power0 at obio0 slot 0 offset 0x910000 level 2
fdc0 at obio0 slot 0 offset 0x400000 level 11 softpri 4: chip 82077
iommu0 at mainbus0 addr 0x10000000: version 0x4/0x0, page-size 4096, range 64MB
sbus0 at iommu0: clock = 21.250 MHz
dma0 at sbus0 slot 5 offset 0x8400000: rev 2
esp0 at dma0 slot 5 offset 0x8800000 level 4: ESP200, 40MHz, SCSI ID 7
scsibus0 at esp0: 8 targets, 8 luns per target
probe(esp0:1:0): max sync rate 10.00Mb/s
sd1 at scsibus0 targ 1 lun 0: <SEAGATE, ST39102LCSUN9.0G, 0828> SCSI2 0/direct fixed
sd1: 8637MB, 4926 cyl, 27 head, 133 sec, 512 bytes/sect x 17689267 sectors
probe(esp0:2:0): max sync rate 10.00Mb/s
sd2 at scsibus0 targ 2 lun 0: <QUANTUM, FIREBALL_TM2110S, 300X> SCSI2 0/direct fixed
sd2: 2014MB, 6810 cyl, 4 head, 151 sec, 512 bytes/sect x 4124736 sectors
probe(esp0:3:0): max sync rate 10.00Mb/s
sd0 at scsibus0 targ 3 lun 0: <SEAGATE, ST5660N  SUN0535, 0522> SCSI2 0/direct fixed
sd0: 520MB, 3002 cyl, 4 head, 88 sec, 512 bytes/sect x 1065664 sectors
SUNW,bpp at sbus0 slot 5 offset 0xc800000 level 2 (ipl 3) not configured
ledma0 at sbus0 slot 5 offset 0x8400010: rev 2
le0 at ledma0 slot 5 offset 0x8c00000 level 6: address 08:00:20:76:d6:67
le0: 8 receive buffers, 2 transmit buffers
audiocs0 at sbus0 slot 4 offset 0xc000000 level 9: CS4231A
audio0 at audiocs0: full duplex
power-management at sbus0 slot 4 offset 0xa000000 not configured
dma1 at sbus0 slot 1 offset 0x100000: rev 1+
esp1 at sbus0 slot 1 offset 0x200000 level 5: ESP100A, 25MHz, SCSI ID 7
scsibus1 at esp1: 8 targets, 8 luns per target
probe(esp1:0:0): max sync rate 5.00Mb/s
sd3 at scsibus1 targ 0 lun 0: <SEAGATE, ST15150N, 0020> SCSI2 0/direct fixed
sd3: 4101MB, 3712 cyl, 21 head, 107 sec, 512 bytes/sect x 8399448 sectors
dma2 at sbus0 slot 2 offset 0x100000: rev 1+
esp2 at sbus0 slot 2 offset 0x200000 level 5: ESP100A, 25MHz, SCSI ID 7
scsibus2 at esp2: 8 targets, 8 luns per target
probe(esp2:3:0): max sync rate 5.00Mb/s
sd4 at scsibus2 targ 3 lun 0: <SEAGATE, ST19171N, 0024> SCSI2 0/direct fixed
sd4: 8683MB, 5268 cyl, 20 head, 168 sec, 512 bytes/sect x 17783112 sectors
root on sd0a dumps on sd0b
root file system type: ffs
WARNING: ccd0: end of partition `a' exceeds the size of ccd (35320320)
swapctl: adding /dev/sd0b as swap device at priority 0
swapctl: adding /dev/sd2b as swap device at priority 0
Automatic boot in progress: starting file system checks.
/dev/rsd0a: 2889 files, 99785 used, 152470 free (2710 frags, 18720 blocks, 1.1% fragmentation)
/dev/rsd0a: MARKING FILE SYSTEM CLEAN
WARNING: ccd0: end of partition `a' exceeds the size of ccd (35320320)
/dev/rsd2a: 37895 files, 669815 used, 879501 free (48949 frags, 103819 blocks, 3.2% fragmentation)
/dev/rsd2a: MARKING FILE SYSTEM CLEAN
/dev/rsd3c: FREE BLK COUNT(S) WRONG IN SUPERBLK (SALVAGED)
/dev/rsd3c: 46534 files, 427132 used, 3638006 free (94470 frags, 442942 blocks, 2.3% fragmentation)
/dev/rsd3c: MARKING FILE SYSTEM CLEAN
/dev/rccd0c: FREE BLK COUNT(S) WRONG IN SUPERBLK (SALVAGED)
/dev/rccd0c: BLK(S) MISSING IN BIT MAPS (SALVAGED)
/dev/rccd0c: SUMMARY INFORMATION BAD (SALVAGED)
/dev/rccd0c: 1003148 files, 10717203 used, 6390988 free (232956 frags, 769754 blocks, 1.4% fragmentation)
/dev/rccd0c: MARKING FILE SYSTEM CLEAN
setting tty flags
starting network
hostname: news
configuring network interfaces: le0.
adding interface aliases:
WARNING: ccd0: end of partition `a' exceeds the size of ccd (35320320)
starting system logger
checking for core dump...
savecore: no core dump
starting rpc daemons: portmap.
starting nfs daemons:.
creating runtime link editor directory cache.
checking quotas: done.
building databases...
clearing /tmp
updating motd.
turning on accounting
standard daemons: update cron.
starting network daemons: inetd.
starting local daemons:Setting autonicetime to 0
Setting kern.maxvnodes to 10832
Starting news services
INND:  PID file exists -- unclean shutdown!
send-mail: mailwrapper: can't open /etc/mailer.conf: No such file or directory
Starting innd.
.
Mon Dec 27 15:58:12 PST 1999
Dec 27 15:58:13 news init: kernel security level changed from 0 to 1



Script done on Mon Dec 27 16:07:35 1999