From owner-freebsd-current@FreeBSD.ORG Wed Sep 22 18:46:24 2004 Return-Path: Delivered-To: freebsd-current@freebsd.org Received: from mx1.FreeBSD.org (mx1.freebsd.org [216.136.204.125]) by hub.freebsd.org (Postfix) with ESMTP id 79AAE16A4CE for ; Wed, 22 Sep 2004 18:46:24 +0000 (GMT) Received: from av3-2-sn4.m-sp.skanova.net (av3-2-sn4.m-sp.skanova.net [81.228.10.113]) by mx1.FreeBSD.org (Postfix) with ESMTP id 822BB43D3F for ; Wed, 22 Sep 2004 18:46:23 +0000 (GMT) (envelope-from daniel_k_eriksson@telia.com) Received: by av3-2-sn4.m-sp.skanova.net (Postfix, from userid 502) id B28E537E75; Wed, 22 Sep 2004 20:46:22 +0200 (CEST) Received: from smtp2-2-sn4.m-sp.skanova.net (smtp2-2-sn4.m-sp.skanova.net [81.228.10.182]) by av3-2-sn4.m-sp.skanova.net (Postfix) with ESMTP id 9BD9537E61 for ; Wed, 22 Sep 2004 20:46:22 +0200 (CEST) Received: from gadget (h130n1fls11o822.telia.com [213.64.66.130]) by smtp2-2-sn4.m-sp.skanova.net (Postfix) with ESMTP id 65B9637E4B for ; Wed, 22 Sep 2004 20:46:22 +0200 (CEST) From: "Daniel Eriksson" To: Date: Wed, 22 Sep 2004 20:46:15 +0200 Organization: Home Message-ID: MIME-Version: 1.0 Content-Type: text/plain; charset="iso-8859-1" Content-Transfer-Encoding: quoted-printable X-Mailer: Microsoft Office Outlook, Build 11.0.6353 X-MimeOLE: Produced By Microsoft MimeOLE V6.00.2900.2180 Thread-Index: AcSg1HAtHX2NHqP7QH20HaBBHqcwNw== Subject: CURRENT crash (NFS-related on a system not using NFS???) X-BeenThere: freebsd-current@freebsd.org X-Mailman-Version: 2.1.1 Precedence: list List-Id: Discussions about the use of FreeBSD-current List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , X-List-Received-Date: Wed, 22 Sep 2004 18:46:24 -0000 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=3D2000 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 =3D 0x756363ab fault code =3D supervisor read, page not present instruction pointer =3D 0x8:0xc0659970 stack pointer =3D 0x10:0xe73f7c74 frame pointer =3D 0x10:0xe73f7c94 code segment =3D base 0x0, limit 0xfffff, type 0x1b =3D DPL 0, pres 1, def32 1, gran 1 processor eflags =3D interrupt enabled, resume, IOPL =3D 0 current process =3D 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 =3D 0, esp =3D 0xe73f7d7c, ebp =3D 0 --- db> show pcpu=20 cpuid =3D 0 curthread =3D 0xc22fd000: pid 34 "swi5: clock sio" curpcb =3D 0xe73f7da0 fpcurthread =3D none idlethread =3D 0xc2268320: pid 10 "idle" APIC ID =3D 0 currentldt =3D 0x28 db> show map=20 Task map 0xc0659970: pmap=3D0xc07a1180, nentries=3D608487168, = version=3D608487168 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=20 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=3D-415270252, dummy2=3D0, = dummy3=3D16, dummy4=3D0xe73f7a8c "\020\037n=C0m\232s=C0=D4=A9t=C0") 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=3D12, code=3D0) at /usr/src/sys/ddb/db_main.c:221 #4 0xc057ba32 in kdb_trap (type=3D0, code=3D0, tf=3D0xe73f7c34) at /usr/src/sys/kern/subr_kdb.c:418 #5 0xc06f572e in trap_fatal (frame=3D0xe73f7c34, eva=3D1969447851) at /usr/src/sys/i386/i386/trap.c:804 #6 0xc06f5a1b in trap_pfault (frame=3D0xe73f7c34, usermode=3D0, = eva=3D1969447851) at /usr/src/sys/i386/i386/trap.c:727 #7 0xc06f5e75 in trap (frame=3D {tf_fs =3D 24, tf_es =3D 16, tf_ds =3D 16, tf_edi =3D 255153, = tf_esi =3D 586014197, tf_ebp =3D -415269740, tf_isp =3D -415269792, tf_ebx =3D = 1969447791, tf_edx =3D 255153, tf_ecx =3D 490485, tf_eax =3D 586014197, tf_trapno = =3D 12, tf_err =3D 0, tf_eip =3D -1067083408, tf_cs =3D 8, tf_eflags =3D 66054, tf_esp = =3D 582, tf_ss =3D 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=3D0x0) at /usr/src/sys/nfsserver/nfs_srvsock.c:817 #23 0xc056df75 in softclock (dummy=3D0x0) at /usr/src/sys/kern/kern_timeout.c:259 #24 0xc0546b39 in ithread_loop (arg=3D0xc2258180) at /usr/src/sys/kern/kern_intr.c:547 #25 0xc05458c2 in fork_exit (callout=3D0xc0546a80 , = arg=3D0x0, frame=3D0x0) 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@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 =3D "AuthenticAMD" Id =3D 0x6a0 Stepping =3D 0 =20 Features=3D0x383fbff AMD Features=3D0xc0480000 real memory =3D 1073741824 (1024 MB) avail memory =3D 1041244160 (993 MB) MPTable: ioapic0: Assuming intbase of 0 ioapic0 irqs 0-23 on motherboard npx0: [FAST] npx0: on motherboard npx0: INT 16 interface pcib0: pcibus 0 on motherboard pci0: on pcib0 agp0: port 0x2860-0x2863 mem 0xea000000-0xea000fff,0xec000000-0xefffffff at device 0.0 on pci0 pcib1: at device 1.0 on pci0 pci1: on pcib1 pci1: at device 5.0 (no driver attached) isab0: at device 7.0 on pci0 isa0: on isab0 atapci0: 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: at device 7.3 (no driver attached) ahc0: port 0x1000-0x10ff mem 0xe80e0000-0xe80e0fff irq 16 at device 8.0 on pci0 ahc0: [GIANT-LOCKED] aic7892: Ultra160 Wide Channel A, SCSI Id=3D7, 32/253 SCBs em0: 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: 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: 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: 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: 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: 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: 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: at iomem 0xc0000-0xcafff on isa0 pmtimer0 on isa0 atkbdc0: at port 0x64,0x60 on isa0 atkbd0: irq 1 on atkbdc0 kbd0 at atkbd0 atkbd0: [GIANT-LOCKED] psm0: irq 12 on atkbdc0 psm0: [GIANT-LOCKED] psm0: model Generic PS/2 mouse, device ID 0 fdc0: at port 0x3f0-0x3f5 irq 6 drq 2 on = isa0 fdc0: [FAST] fd0: <1440-KB 3.5" drive> on fdc0 drive 0 sc0: at flags 0x100 on isa0 sc0: VGA <16 virtual consoles, flags=3D0x100> 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: at port 0x3c0-0x3df iomem 0xa0000-0xbffff on = isa0 ahc1: No resources allocated. unknown: can't assign resources (port) speaker0: at port 0x61 on isa0 ahc1: No resources allocated. ahc1: No resources allocated. ahc1: No resources allocated. unknown: can't assign resources (irq) unknown: can't assign resources (port) unknown: 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 [232581/16/63] at ata0-master UDMA100 ad1: 114473MB [232581/16/63] at ata0-slave UDMA100 ad2: 117800MB [239340/16/63] at ata1-master UDMA100 ad3: 117800MB [239340/16/63] at ata1-slave UDMA100 ad4: 238475MB [484521/16/63] at = ata2-master UDMA100 ad5: 238475MB [484521/16/63] at = ata2-slave UDMA100 ad6: 239372MB [486344/16/63] at ata3-master UDMA133 ad7: 239372MB [486344/16/63] at ata3-slave = UDMA133 ad8: 194481MB [395136/16/63] at ata4-master UDMA133 ad9: 194481MB [395136/16/63] at ata4-slave = UDMA133 ad10: 194481MB [395136/16/63] at ata5-master UDMA133 ad11: 239372MB [486344/16/63] at ata5-slave UDMA133 ad12: 239372MB [486344/16/63] at ata6-master SATA150 ad14: 238475MB [484521/16/63] at = ata7-master SATA150 em0: Link is up 100 Mbps Full Duplex ad20: 117800MB [239340/16/63] at = ata10-master UDMA100 ad21: 117800MB [239340/16/63] at ata10-slave UDMA100 ad22: 117246MB [238216/16/63] at ata11-master UDMA133 ad23: 117246MB [238216/16/63] at ata11-slave UDMA133 ad24: 114473MB [232581/16/63] at = ata12-master UDMA100 ad25: 117800MB [239340/16/63] at ata12-slave UDMA100 ad26: 26059MB [52946/16/63] at ata13-master = UDMA66 ar0: 476950MB [60802/255/63] status: READY subdisks: disk0 READY on ad4 at ata2-master disk1 READY on ad5 at ata2-slave ar1: 478744MB [61031/255/63] status: READY subdisks: disk0 READY on ad6 at ata3-master disk1 READY on ad7 at ata3-slave ar2: 388962MB [49585/255/63] status: READY subdisks: disk0 READY on ad9 at ata4-slave disk1 READY on ad8 at ata4-master ar3: 228946MB [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: Removable Sequential Access SCSI-2 device=20 sa0: 10.000MB/s transfers (10.000MHz, offset 15) da0 at ahc0 bus 0 target 0 lun 0 da0: Fixed Direct Access SCSI-3 device=20 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: Fixed Direct Access SCSI-3 device=20 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: Fixed Direct Access SCSI-3 device=20 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: Fixed Direct Access SCSI-3 device=20 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: Fixed Direct Access SCSI-3 device=20 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: Fixed Direct Access SCSI-3 device=20 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