CURRENT crash (NFS-related on a system not using NFS???)

From: Daniel Eriksson <daniel_k_eriksson_at_telia.com>
Date: Wed, 22 Sep 2004 20:46:15 +0200
This is the second time I've seen this crash on CURRENT in the last 3-4
days. After it happened the first time I disabled ACPI, but that does not
seem to make any difference. This one happened on a system compiled from
sources dated 2004.09.20.20.00.00. I'm not a kernel hacker, but the
references to NFS seems strange given that I'm not using NFS on this
machine. I do have a core dump saved in case someone wants further
information.

NFS is compiled into the kernel (just like in GENERIC), but it is never
started from rc.conf.

Some interesting things from the kernel config:
options         SCHED_4BSD
options         DEVICE_POLLING
options         HZ=2000
options         ZERO_COPY_SOCKETS
options         AHC_ALLOW_MEMIO
options         GEOM_BDE
device          apic

"options PREEMPTION" is NOT used.

PS! It's a Tyan Tiger MP motherboard (AMD 760MP based), but only one CPU is
installed (so far) and the kernel is a UP kernel.


Fatal trap 12: page fault while in kernel mode
fault virtual address   = 0x756363ab
fault code              = supervisor read, page not present
instruction pointer     = 0x8:0xc0659970
stack pointer           = 0x10:0xe73f7c74
frame pointer           = 0x10:0xe73f7c94
code segment            = base 0x0, limit 0xfffff, type 0x1b
                        = DPL 0, pres 1, def32 1, gran 1
processor eflags        = interrupt enabled, resume, IOPL = 0
current process         = 34 (swi5: clock sio)
[thread 100030]
Stopped at      nfsrv_timer+0x40:       movl    0x3c(%ebx),%eax
db> tr
nfsrv_timer(0,c07b6214,c22fd000,9f17,6836c94) at nfsrv_timer+0x40
softclock(0,0,0,0,0) at softclock+0x1f5
ithread_loop(c2258180,e73f7d48,0,0,0) at ithread_loop+0xb9
fork_exit(c0546a80,c2258180,e73f7d48) at fork_exit+0x62
fork_trampoline() at fork_trampoline+0x8
--- trap 0x1, eip = 0, esp = 0xe73f7d7c, ebp = 0 ---
db> show pcpu 
cpuid        = 0
curthread    = 0xc22fd000: pid 34 "swi5: clock sio"
curpcb       = 0xe73f7da0
fpcurthread  = none
idlethread   = 0xc2268320: pid 10 "idle"
APIC ID      = 0
currentldt   = 0x28
db> show map 
Task map 0xc0659970: pmap=0xc07a1180, nentries=608487168, version=608487168
db> show irqs
irq0: clk (pid 33)
irq1: atkbd0 (pid 11)
irq3: (pid 12)
irq4: sio0 (pid 13)
irq5: (pid 14)
irq6: fdc0 (pid 15) {ENTROPY}
irq7: (pid 16)
irq8: rtc (pid 17)
irq9: (pid 18)
irq10: (pid 19)
irq11: (pid 20)
irq12: psm0 (pid 21)
irq13: (pid 22)
irq14: ata0 (pid 23) {ENTROPY}
irq15: ata1 (pid 24) {ENTROPY}
irq16: ahc0 atapci3 (pid 25) {ENTROPY}
irq17: em0 atapci4+ (pid 26) {ENTROPY}
irq18: em1 atapci1+ (pid 27) {ENTROPY}
irq19: (pid 28)
irq20: (pid 29)
irq21: (pid 30)
irq22: (pid 31)
irq23: (pid 32)
db> show idt 
  0     Xdiv
  1     Xdbg
  2     Xnmi
  3     Xbpt
  4     Xofl
  5     Xbnd
  6     Xill
  7     Xdna
  8     0
  9     Xfpusegm
 10     Xtss
 11     Xmissing
 12     Xstk
 13     Xprot
 14     Xpage
 16     Xfpu
 17     Xalign
 18     Xmchk
 19     Xxmm
 32     Xatpic_intr0
 33     Xatpic_intr1
 35     Xatpic_intr3
 36     Xatpic_intr4
 37     Xatpic_intr5
 38     Xatpic_intr6
 39     Xatpic_intr7
 40     Xatpic_intr8
 41     Xatpic_intr9
 42     Xatpic_intr10
 43     Xatpic_intr11
 44     Xatpic_intr12
 45     Xatpic_intr13
 46     Xatpic_intr14
 47     Xatpic_intr15
 49     Xapic_isr1
 52     Xapic_isr1
 54     Xapic_isr1
 56     Xapic_isr1
 60     Xapic_isr1
 61     Xapic_isr1
 62     Xapic_isr1
 63     Xapic_isr1
 64     Xapic_isr2
 65     Xapic_isr2
 66     Xapic_isr2
128     Xint0x80_syscall
255     Xspuriousint


(kgdb) bt
#0  doadump () at pcpu.h:159
#1  0xc044d1cc in db_fncall (dummy1=-415270252, dummy2=0, dummy3=16,
dummy4=0xe73f7a8c "\020\037nĀm\232sĀÔŠtĀ")
    at /usr/src/sys/ddb/db_command.c:531
#2  0xc044d670 in db_command_loop () at /usr/src/sys/ddb/db_command.c:349
#3  0xc044f464 in db_trap (type=12, code=0) at
/usr/src/sys/ddb/db_main.c:221
#4  0xc057ba32 in kdb_trap (type=0, code=0, tf=0xe73f7c34) at
/usr/src/sys/kern/subr_kdb.c:418
#5  0xc06f572e in trap_fatal (frame=0xe73f7c34, eva=1969447851) at
/usr/src/sys/i386/i386/trap.c:804
#6  0xc06f5a1b in trap_pfault (frame=0xe73f7c34, usermode=0, eva=1969447851)
at /usr/src/sys/i386/i386/trap.c:727
#7  0xc06f5e75 in trap (frame=
      {tf_fs = 24, tf_es = 16, tf_ds = 16, tf_edi = 255153, tf_esi =
586014197, tf_ebp = -415269740, tf_isp = -415269792, tf_ebx = 1969447791,
tf_edx = 255153, tf_ecx = 490485, tf_eax = 586014197, tf_trapno = 12, tf_err
= 0, tf_eip = -1067083408, tf_cs = 8, tf_eflags = 66054, tf_esp = 582, tf_ss
= 2147483647}) at /usr/src/sys/i386/i386/trap.c:417
#8  0xc06e3e6a in calltrap () at /usr/src/sys/i386/i386/exception.s:140
#9  0x00000018 in ?? ()
#10 0x00000010 in ?? ()
#11 0x00000010 in ?? ()
#12 0x0003e4b1 in ?? ()
#13 0x22edddf5 in ?? ()
#14 0xe73f7c94 in ?? ()
#15 0xe73f7c60 in ?? ()
#16 0x7563636f in ?? ()
#17 0x0003e4b1 in ?? ()
#18 0x00077bf5 in ?? ()
#19 0x22edddf5 in ?? ()
#20 0x0000000c in ?? ()
#21 0x00000000 in ?? ()
#22 0xc0659970 in nfsrv_timer (arg=0x0) at
/usr/src/sys/nfsserver/nfs_srvsock.c:817
#23 0xc056df75 in softclock (dummy=0x0) at
/usr/src/sys/kern/kern_timeout.c:259
#24 0xc0546b39 in ithread_loop (arg=0xc2258180) at
/usr/src/sys/kern/kern_intr.c:547
#25 0xc05458c2 in fork_exit (callout=0xc0546a80 <ithread_loop>, arg=0x0,
frame=0x0) at /usr/src/sys/kern/kern_fork.c:807
#26 0xc06e3ecc in fork_trampoline () at
/usr/src/sys/i386/i386/exception.s:209


dmesg:
------
KDB: debugger backends: ddb
KDB: current backend: ddb
Copyright (c) 1992-2004 The FreeBSD Project.
Copyright (c) 1979, 1980, 1983, 1986, 1988, 1989, 1991, 1992, 1993, 1994
        The Regents of the University of California. All rights reserved.
FreeBSD 6.0-CURRENT #0: Mon Sep 20 23:59:20 CEST 2004
    daniel_at_xxx.xxx.xxx:/usr/obj/usr/src/sys/FORTIFY
Timecounter "i8254" frequency 1193182 Hz quality 0
CPU: AMD Athlon(tm) Processor (1992.62-MHz 686-class CPU)
  Origin = "AuthenticAMD"  Id = 0x6a0  Stepping = 0
 
Features=0x383fbff<FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,
CMOV,PAT,PSE36,MMX,FXSR,SSE>
  AMD Features=0xc0480000<MP,AMIE,DSP,3DNow!>
real memory  = 1073741824 (1024 MB)
avail memory = 1041244160 (993 MB)
MPTable: <TYAN     GUINNESS    >
ioapic0: Assuming intbase of 0
ioapic0 <Version 1.1> irqs 0-23 on motherboard
npx0: [FAST]
npx0: <math processor> on motherboard
npx0: INT 16 interface
pcib0: <MPTable Host-PCI bridge> pcibus 0 on motherboard
pci0: <PCI bus> on pcib0
agp0: <AMD 762 host to AGP bridge> port 0x2860-0x2863 mem
0xea000000-0xea000fff,0xec000000-0xefffffff at device 0.0 on pci0
pcib1: <MPTable PCI-PCI bridge> at device 1.0 on pci0
pci1: <PCI bus> on pcib1
pci1: <display, VGA> at device 5.0 (no driver attached)
isab0: <PCI-ISA bridge> at device 7.0 on pci0
isa0: <ISA bus> on isab0
atapci0: <AMD 766 UDMA100 controller> port
0xf000-0xf00f,0x376,0x170-0x177,0x3f6,0x1f0-0x1f7 at device 7.1 on pci0
ata0: channel #0 on atapci0
ata1: channel #1 on atapci0
pci0: <bridge, PCI-unknown> at device 7.3 (no driver attached)
ahc0: <Adaptec 29160 Ultra160 SCSI adapter> port 0x1000-0x10ff mem
0xe80e0000-0xe80e0fff irq 16 at device 8.0 on pci0
ahc0: [GIANT-LOCKED]
aic7892: Ultra160 Wide Channel A, SCSI Id=7, 32/253 SCBs
em0: <Intel(R) PRO/1000 Network Connection, Version - 1.7.35> port
0x2480-0x24bf mem 0xe8000000-0xe803ffff,0xe8080000-0xe809ffff irq 17 at
device 9.0 on pci0
em0: Ethernet address: 00:04:23:ac:20:8a
em0:  Speed:N/A  Duplex:N/A
em1: <Intel(R) PRO/1000 Network Connection, Version - 1.7.35> port
0x24c0-0x24ff mem 0xe8040000-0xe807ffff,0xe80a0000-0xe80bffff irq 18 at
device 9.1 on pci0
em1: Ethernet address: 00:04:23:ac:20:8b
em1:  Speed:N/A  Duplex:N/A
atapci1: <HighPoint HPT374 (channel 0+1) UDMA133 controller> port
0x1400-0x14ff,0x2864-0x2867,0x2868-0x286f,0x2870-0x2873,0x2878-0x287f irq 18
at device 10.0 on pci0
ata2: channel #0 on atapci1
ata3: channel #1 on atapci1
atapci2: <HighPoint HPT374 (channel 2+3) UDMA133 controller> port
0x1800-0x18ff,0x2874-0x2877,0x2880-0x2887,0x2888-0x288b,0x2890-0x2897 irq 18
at device 10.1 on pci0
ata4: channel #0 on atapci2
ata5: channel #1 on atapci2
atapci3: <Promise PDC20318 SATA150 controller> port
0x2400-0x247f,0x2850-0x285f,0x2800-0x283f mem
0xe80c0000-0xe80dffff,0xe80e1000-0xe80e1fff irq 16 at device 12.0 on pci0
atapci3: failed: rid 0x20 is memory, requested 4
ata6: channel #0 on atapci3
ata7: channel #1 on atapci3
ata8: channel #2 on atapci3
ata9: channel #3 on atapci3
atapci4: <HighPoint HPT374 (channel 0+1) UDMA133 controller> port
0x1c00-0x1cff,0x288c-0x288f,0x2898-0x289f,0x28a0-0x28a3,0x28a8-0x28af irq 17
at device 13.0 on pci0
ata10: channel #0 on atapci4
ata11: channel #1 on atapci4
atapci5: <HighPoint HPT374 (channel 2+3) UDMA133 controller> port
0x2000-0x20ff,0x28a4-0x28a7,0x28b0-0x28b7,0x28b8-0x28bb,0x28c0-0x28c7 irq 17
at device 13.1 on pci0
ata12: channel #0 on atapci5
ata13: channel #1 on atapci5
cpu0 on motherboard
orm0: <ISA Option ROM> at iomem 0xc0000-0xcafff on isa0
pmtimer0 on isa0
atkbdc0: <Keyboard controller (i8042)> at port 0x64,0x60 on isa0
atkbd0: <AT Keyboard> irq 1 on atkbdc0
kbd0 at atkbd0
atkbd0: [GIANT-LOCKED]
psm0: <PS/2 Mouse> irq 12 on atkbdc0
psm0: [GIANT-LOCKED]
psm0: model Generic PS/2 mouse, device ID 0
fdc0: <Enhanced floppy controller> at port 0x3f0-0x3f5 irq 6 drq 2 on isa0
fdc0: [FAST]
fd0: <1440-KB 3.5" drive> on fdc0 drive 0
sc0: <System console> at flags 0x100 on isa0
sc0: VGA <16 virtual consoles, flags=0x100>
sio0 at port 0x3f8-0x3ff irq 4 flags 0x10 on isa0
sio0: type 16550A, console
sio1: configured irq 3 not in bitmap of probed irqs 0
sio1: port may not be enabled
vga0: <Generic ISA VGA> at port 0x3c0-0x3df iomem 0xa0000-0xbffff on isa0
ahc1: No resources allocated.
unknown: <PNP0303> can't assign resources (port)
speaker0: <PC speaker> at port 0x61 on isa0
ahc1: No resources allocated.
ahc1: No resources allocated.
ahc1: No resources allocated.
unknown: <PNP0f13> can't assign resources (irq)
unknown: <PNP0501> can't assign resources (port)
unknown: <PNP0700> can't assign resources (port)
Timecounter "TSC" frequency 2256618076 Hz quality 800
Timecounters tick every 1.000 msec
ipfw2 initialized, divert enabled, rule-based forwarding disabled, default
to accept, logging unlimited
ad0: 114473MB <ST3120022A/3.06> [232581/16/63] at ata0-master UDMA100
ad1: 114473MB <ST3120022A/3.06> [232581/16/63] at ata0-slave UDMA100
ad2: 117800MB <IC35L120AVV207-0/V24OA66A> [239340/16/63] at ata1-master
UDMA100
ad3: 117800MB <IC35L120AVV207-0/V24OA66A> [239340/16/63] at ata1-slave
UDMA100
ad4: 238475MB <WDC WD2500JB-00FUA0/15.05R15> [484521/16/63] at ata2-master
UDMA100
ad5: 238475MB <WDC WD2500JB-00FUA0/15.05R15> [484521/16/63] at ata2-slave
UDMA100
ad6: 239372MB <Maxtor 4A250J0/RAMB1TU0> [486344/16/63] at ata3-master
UDMA133
ad7: 239372MB <Maxtor 5A250J0/RAMB1TV0> [486344/16/63] at ata3-slave UDMA133
ad8: 194481MB <Maxtor 6Y200P0/YAR41BW0> [395136/16/63] at ata4-master
UDMA133
ad9: 194481MB <Maxtor 6Y200P0/YAR41VW0> [395136/16/63] at ata4-slave UDMA133
ad10: 194481MB <Maxtor 6Y200P0/YAR41BW0> [395136/16/63] at ata5-master
UDMA133
ad11: 239372MB <Maxtor 7Y250P0/YAR41BW0> [486344/16/63] at ata5-slave
UDMA133
ad12: 239372MB <Maxtor 7Y250M0/YAR51EW0> [486344/16/63] at ata6-master
SATA150
ad14: 238475MB <WDC WD2500JD-00FYB0/02.05D02> [484521/16/63] at ata7-master
SATA150
em0: Link is up 100 Mbps Full Duplex
ad20: 117800MB <IC35L120AVV207-0/V24OA66A> [239340/16/63] at ata10-master
UDMA100
ad21: 117800MB <HDS722512VLAT20/V33OA60A> [239340/16/63] at ata10-slave
UDMA100
ad22: 117246MB <Maxtor 6Y120L0/YAR41BW0> [238216/16/63] at ata11-master
UDMA133
ad23: 117246MB <Maxtor 6Y120L0/YAR41BW0> [238216/16/63] at ata11-slave
UDMA133
ad24: 114473MB <WDC WD1200JB-00DUA3/75.13B75> [232581/16/63] at ata12-master
UDMA100
ad25: 117800MB <IC35L120AVV207-1/V24OA66A> [239340/16/63] at ata12-slave
UDMA100
ad26: 26059MB <Maxtor 92732U8/RA530JN0> [52946/16/63] at ata13-master UDMA66
ar0: 476950MB <ATA RAID0 array> [60802/255/63] status: READY subdisks:
 disk0 READY on ad4 at ata2-master
 disk1 READY on ad5 at ata2-slave
ar1: 478744MB <ATA RAID0 array> [61031/255/63] status: READY subdisks:
 disk0 READY on ad6 at ata3-master
 disk1 READY on ad7 at ata3-slave
ar2: 388962MB <ATA RAID0 array> [49585/255/63] status: READY subdisks:
 disk0 READY on ad9 at ata4-slave
 disk1 READY on ad8 at ata4-master
ar3: 228946MB <ATA RAID0 array> [29186/255/63] status: READY subdisks:
 disk0 READY on ad23 at ata11-slave
 disk1 READY on ad24 at ata12-master
Waiting 5 seconds for SCSI devices to settle
em1: Link is up 100 Mbps Full Duplex
GEOM_VINUM: subdisk 480GB.p0.s3 is up
GEOM_VINUM: subdisk 480GB.p0.s0 is up
GEOM_VINUM: subdisk 480GB.p0.s2 is up
GEOM_VINUM: subdisk 480GB.p0.s1 is up
GEOM_VINUM: subdisk tmp.p0.s0 is up
GEOM_VINUM: subdisk var.p0.s0 is up
GEOM_VINUM: subdisk usr.p0.s0 is up
GEOM_VINUM: subdisk 190GB.p0.s0 is up
GEOM_VINUM: subdisk tmp.p1.s0 is up
GEOM_VINUM: subdisk var.p1.s0 is up
GEOM_VINUM: subdisk usr.p1.s0 is up
GEOM_VINUM: subdisk 190GB.p0.s1 is up
sa0 at ahc0 bus 0 target 5 lun 0
sa0: <Seagate STT20000N 6A51> Removable Sequential Access SCSI-2 device 
sa0: 10.000MB/s transfers (10.000MHz, offset 15)
da0 at ahc0 bus 0 target 0 lun 0
da0: <QUANTUM ATLAS 10K 9WLS UCH0> Fixed Direct Access SCSI-3 device 
da0: 160.000MB/s transfers (80.000MHz, offset 31, 16bit), Tagged Queueing
Enabled
da0: 8759MB (17938986 512 byte sectors: 255H 63S/T 1116C)
da1 at ahc0 bus 0 target 1 lun 0
da1: <QUANTUM ATLAS 10K 9WLS UCH0> Fixed Direct Access SCSI-3 device 
da1: 160.000MB/s transfers (80.000MHz, offset 31, 16bit), Tagged Queueing
Enabled
da1: 8759MB (17938986 512 byte sectors: 255H 63S/T 1116C)
da2 at ahc0 bus 0 target 10 lun 0
da2: <IBM DDYS-T36950N S93E> Fixed Direct Access SCSI-3 device 
da2: 160.000MB/s transfers (80.000MHz, offset 63, 16bit), Tagged Queueing
Enabled
da2: 35003MB (71687340 512 byte sectors: 255H 63S/T 4462C)
da3 at ahc0 bus 0 target 11 lun 0
da3: <IBM IC35L036UWD210-0 S5CQ> Fixed Direct Access SCSI-3 device 
da3: 160.000MB/s transfers (80.000MHz, offset 63, 16bit), Tagged Queueing
Enabled
da3: 35003MB (71687340 512 byte sectors: 255H 63S/T 4462C)
da4 at ahc0 bus 0 target 12 lun 0
da4: <IBM DDYS-T36950N S93E> Fixed Direct Access SCSI-3 device 
da4: 160.000MB/s transfers (80.000MHz, offset 63, 16bit), Tagged Queueing
Enabled
da4: 35003MB (71687340 512 byte sectors: 255H 63S/T 4462C)
da5 at ahc0 bus 0 target 13 lun 0
da5: <IBM DDYS-T36950N S93E> Fixed Direct Access SCSI-3 device 
da5: 160.000MB/s transfers (80.000MHz, offset 63, 16bit), Tagged Queueing
Enabled
da5: 35003MB (71687340 512 byte sectors: 255H 63S/T 4462C)
GEOM_VINUM: subdisk raid0.p0.s0 is up
GEOM_VINUM: subdisk raid0.p0.s1 is up
GEOM_VINUM: subdisk raid0.p0.s2 is up
GEOM_VINUM: subdisk raid0.p0.s3 is up
Mounting root from ufs:/dev/da0s1a
Pre-seeding PRNG: kickstart.
Loading configuration files.
Entropy harvesting: point_to_point kickstart.
kernel dumps on /dev/da0s1b
swapon: adding /dev/da0s1b as swap device
swapon: adding /dev/da1s1b as swap device
Starting file system checks:
/dev/da0s1a: FILE SYSTEM CLEAN; SKIPPING CHECKS
/dev/da0s1a: clean, 192561 free (905 frags, 23957 blocks, 0.4%
fragmentation)
/dev/da1s1e: FILE SYSTEM CLEAN; SKIPPING CHECKS
/dev/da1s1e: clean, 913946 free (34 frags, 114239 blocks, 0.0%
fragmentation)
/dev/da0s1e: FILE SYSTEM CLEAN; SKIPPING CHECKS
/dev/da0s1e: clean, 253810 free (26 frags, 31723 blocks, 0.0% fragmentation)
/dev/da1s1d: FILE SYSTEM CLEAN; SKIPPING CHECKS
/dev/da1s1d: clean, 205044 free (1796 frags, 25406 blocks, 0.4%
fragmentation)
/dev/da0s1f: FILE SYSTEM CLEAN; SKIPPING CHECKS
/dev/da0s1f: clean, 999737 free (26529 frags, 121651 blocks, 1.0%
fragmentation)
/dev/da0s1d: FILE SYSTEM CLEAN; SKIPPING CHECKS
/dev/da0s1d: clean, 189312 free (376 frags, 23617 blocks, 0.1%
fragmentation)
net.inet.tcp.sendspace: 32768 -> 262144
net.inet.tcp.recvspace: 65536 -> 262144
kern.ipc.maxsockbuf: 262144 -> 2097152
kern.maxfiles: 12328 -> 32768
kern.maxfilesperproc: 11095 -> 16384
kern.ipc.somaxconn: 128 -> 1024
net.inet.ip.intr_queue_maxlen: 50 -> 500
kern.polling.enable: 0 -> 1
kern.polling.burst_max: 150 -> 300
kern.polling.each_burst: 5 -> 50
kern.polling.poll_in_trap: 0 -> 1
kern.polling.user_frac: 50 -> 40


/Daniel Eriksson
Received on Wed Sep 22 2004 - 16:46:24 UTC

This archive was generated by hypermail 2.4.0 : Wed May 19 2021 - 11:38:13 UTC