From owner-freebsd-bugs@FreeBSD.ORG Mon Sep 28 01:00:11 2009 Return-Path: Delivered-To: freebsd-bugs@hub.freebsd.org Received: from mx1.freebsd.org (mx1.freebsd.org [IPv6:2001:4f8:fff6::34]) by hub.freebsd.org (Postfix) with ESMTP id A504F106566B for ; Mon, 28 Sep 2009 01:00:11 +0000 (UTC) (envelope-from gnats@FreeBSD.org) Received: from freefall.freebsd.org (freefall.freebsd.org [IPv6:2001:4f8:fff6::28]) by mx1.freebsd.org (Postfix) with ESMTP id 5142A8FC18 for ; Mon, 28 Sep 2009 01:00:11 +0000 (UTC) Received: from freefall.freebsd.org (gnats@localhost [127.0.0.1]) by freefall.freebsd.org (8.14.3/8.14.3) with ESMTP id n8S10A0P096300 for ; Mon, 28 Sep 2009 01:00:10 GMT (envelope-from gnats@freefall.freebsd.org) Received: (from gnats@localhost) by freefall.freebsd.org (8.14.3/8.14.3/Submit) id n8S10A9X096299; Mon, 28 Sep 2009 01:00:10 GMT (envelope-from gnats) Resent-Date: Mon, 28 Sep 2009 01:00:10 GMT Resent-Message-Id: <200909280100.n8S10A9X096299@freefall.freebsd.org> Resent-From: FreeBSD-gnats-submit@FreeBSD.org (GNATS Filer) Resent-To: freebsd-bugs@FreeBSD.org Resent-Reply-To: FreeBSD-gnats-submit@FreeBSD.org, Larry Rosenman Received: from mx1.freebsd.org (mx1.freebsd.org [IPv6:2001:4f8:fff6::34]) by hub.freebsd.org (Postfix) with ESMTP id 854BD106566B for ; Mon, 28 Sep 2009 00:55:00 +0000 (UTC) (envelope-from ler@lerctr.org) Received: from thebighonker.lerctr.org (thebighonker.lerctr.org [192.147.25.65]) by mx1.freebsd.org (Postfix) with ESMTP id 55BF38FC15 for ; Mon, 28 Sep 2009 00:55:00 +0000 (UTC) Received: from 76-205-169-61.lightspeed.austtx.sbcglobal.net ([76.205.169.61]:58887 helo=borg.lerctr.org) by thebighonker.lerctr.org with esmtpsa (TLSv1:AES256-SHA:256) (Exim 4.69 (FreeBSD)) (envelope-from ) id 1Ms4W1-0002Mv-EP for FreeBSD-gnats-submit@freebsd.org; Sun, 27 Sep 2009 19:54:59 -0500 Received: from ler by borg.lerctr.org with local (Exim 4.69 (FreeBSD)) (envelope-from ) id 1Ms4Vq-0000NU-OQ for FreeBSD-gnats-submit@freebsd.org; Sun, 27 Sep 2009 19:54:46 -0500 Message-Id: Date: Sun, 27 Sep 2009 19:54:46 -0500 From: Larry Rosenman Sender: Larry Rosenman To: FreeBSD-gnats-submit@FreeBSD.org X-Send-Pr-Version: 3.113 Cc: Subject: kern/139198: Page Fault out of NLM X-BeenThere: freebsd-bugs@freebsd.org X-Mailman-Version: 2.1.5 Precedence: list Reply-To: Larry Rosenman List-Id: Bug reports List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , X-List-Received-Date: Mon, 28 Sep 2009 01:00:11 -0000 >Number: 139198 >Category: kern >Synopsis: Page Fault out of NLM >Confidential: no >Severity: serious >Priority: medium >Responsible: freebsd-bugs >State: open >Quarter: >Keywords: >Date-Required: >Class: sw-bug >Submitter-Id: current-users >Arrival-Date: Mon Sep 28 01:00:09 UTC 2009 >Closed-Date: >Last-Modified: >Originator: Larry Rosenman >Release: FreeBSD 8.0-RC1 amd64 >Organization: LERCTR Consulting >Environment: System: FreeBSD borg.lerctr.org 8.0-RC1 FreeBSD 8.0-RC1 #9: Sat Sep 26 03:33:42 CDT 2009 root@borg.lerctr.org:/usr/obj/usr/src/sys/BORG amd64 >Description: NFS mount to a couple of MAC's sharing ZFS filesystems garners: Copyright (c) 1992-2009 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 is a registered trademark of The FreeBSD Foundation. FreeBSD 8.0-RC1 #9: Sat Sep 26 03:33:42 CDT 2009 root@borg.lerctr.org:/usr/obj/usr/src/sys/BORG Timecounter "i8254" frequency 1193182 Hz quality 0 CPU: Intel(R) Xeon(R) CPU 5120 @ 1.86GHz (1866.68-MHz K8-class CPU) Origin = "GenuineIntel" Id = 0x6f6 Stepping = 6 Features=0xbfebfbff Features2=0x4e3bd AMD Features=0x20100800 AMD Features2=0x1 TSC: P-state invariant real memory = 10737418240 (10240 MB) avail memory = 10333769728 (9855 MB) ACPI APIC Table: FreeBSD/SMP: Multiprocessor System Detected: 4 CPUs FreeBSD/SMP: 2 package(s) x 2 core(s) cpu0 (BSP): APIC ID: 0 cpu1 (AP): APIC ID: 1 cpu2 (AP): APIC ID: 6 cpu3 (AP): APIC ID: 7 ioapic0 irqs 0-23 on motherboard ioapic1 irqs 24-47 on motherboard kbd1 at kbdmux0 smbios0: at iomem 0xf60c0-0xf60de on motherboard smbios0: Version: 2.5 cryptosoft0: on motherboard acpi0: on motherboard acpi0: [ITHREAD] acpi0: Power Button (fixed) Timecounter "ACPI-fast" frequency 3579545 Hz quality 1000 acpi_timer0: <24-bit timer at 3.579545MHz> port 0x1008-0x100b on acpi0 pcib0: port 0xcf8-0xcff on acpi0 pci0: on pcib0 pcib1: at device 2.0 on pci0 pci1: on pcib1 pcib2: irq 16 at device 0.0 on pci1 pci2: on pcib2 pcib3: irq 16 at device 0.0 on pci2 pci3: on pcib3 pcib4: at device 0.0 on pci3 pci4: on pcib4 pcib5: at device 0.2 on pci3 pci5: on pcib5 pcib6: irq 18 at device 2.0 on pci2 pci6: on pcib6 em0: port 0x2000-0x201f mem 0xd8000000-0xd801ffff irq 18 at device 0.0 on pci6 em0: Using MSI interrupt em0: [FILTER] em0: Ethernet address: 00:30:48:8e:9f:f3 em1: port 0x2020-0x203f mem 0xd8020000-0xd803ffff irq 19 at device 0.1 on pci6 em1: Using MSI interrupt em1: [FILTER] em1: Ethernet address: 00:30:48:8e:9f:f2 pcib7: at device 0.3 on pci1 pci7: on pcib7 pcib8: at device 4.0 on pci0 pci8: on pcib8 pcib9: at device 6.0 on pci0 pci9: on pcib9 pci0: at device 8.0 (no driver attached) pcib10: irq 17 at device 28.0 on pci0 pci10: on pcib10 uhci0: port 0x1800-0x181f irq 17 at device 29.0 on pci0 uhci0: [ITHREAD] uhci0: LegSup = 0x003b usbus0: on uhci0 uhci1: port 0x1820-0x183f irq 19 at device 29.1 on pci0 uhci1: [ITHREAD] uhci1: LegSup = 0x0010 usbus1: on uhci1 uhci2: port 0x1840-0x185f irq 18 at device 29.2 on pci0 uhci2: [ITHREAD] uhci2: LegSup = 0x0010 usbus2: on uhci2 ehci0: mem 0xd8500400-0xd85007ff irq 17 at device 29.7 on pci0 ehci0: [ITHREAD] usbus3: EHCI version 1.0 usbus3: on ehci0 pcib11: at device 30.0 on pci0 pci11: on pcib11 vgapci0: port 0x3000-0x30ff mem 0xd0000000-0xd7ffffff,0xd8200000-0xd820ffff irq 18 at device 1.0 on pci11 drm0: on vgapci0 vgapci0: child drm0 requested pci_enable_busmaster info: [drm] Initialized radeon 1.29.0 20080528 isab0: at device 31.0 on pci0 isa0: on isab0 atapci0: port 0x1f0-0x1f7,0x3f6,0x170-0x177,0x376,0x1860-0x186f at device 31.1 on pci0 ata0: on atapci0 ata0: [ITHREAD] ahci0: port 0x18a0-0x18a7,0x1874-0x1877,0x1878-0x187f,0x1870-0x1873,0x1880-0x189f mem 0xd8500800-0xd8500bff irq 19 at device 31.2 on pci0 ahci0: [ITHREAD] ahci0: AHCI v1.10 with 6 3Gbps ports, Port Multiplier supported ahcich0: at channel 0 on ahci0 ahcich0: [ITHREAD] ahcich1: at channel 1 on ahci0 ahcich1: [ITHREAD] ahcich2: at channel 2 on ahci0 ahcich2: [ITHREAD] ahcich3: at channel 3 on ahci0 ahcich3: [ITHREAD] ahcich4: at channel 4 on ahci0 ahcich4: [ITHREAD] ahcich5: at channel 5 on ahci0 ahcich5: [ITHREAD] ichsmb0: port 0x1100-0x111f irq 19 at device 31.3 on pci0 ichsmb0: [ITHREAD] smbus0: on ichsmb0 smb0: on smbus0 acpi_button0: on acpi0 atrtc0: port 0x70-0x71 irq 8 on acpi0 atkbdc0: port 0x60,0x64 irq 1 on acpi0 atkbd0: irq 1 on atkbdc0 kbd0 at atkbd0 atkbd0: [GIANT-LOCKED] atkbd0: [ITHREAD] psm0: irq 12 on atkbdc0 psm0: [GIANT-LOCKED] psm0: [ITHREAD] psm0: model IntelliMouse, device ID 3 uart0: <16550 or compatible> port 0x3f8-0x3ff irq 4 flags 0x10 on acpi0 uart0: [FILTER] uart1: <16550 or compatible> port 0x2f8-0x2ff irq 3 on acpi0 uart1: [FILTER] fdc0: port 0x3f0-0x3f5,0x3f7 irq 6 drq 2 on acpi0 fdc0: [FILTER] fd0: <1440-KB 3.5" drive> on fdc0 drive 0 ppc0: port 0x378-0x37f,0x778-0x77f irq 7 drq 3 on acpi0 ppc0: SMC-like chipset (ECP/EPP/PS2/NIBBLE) in COMPATIBLE mode ppc0: FIFO with 16/16/9 bytes threshold ppc0: [ITHREAD] ppbus0: on ppc0 lpt0: on ppbus0 lpt0: [ITHREAD] lpt0: Interrupt-driven port ppi0: on ppbus0 cpu0: on acpi0 coretemp0: on cpu0 est0: on cpu0 p4tcc0: on cpu0 cpu1: on acpi0 coretemp1: on cpu1 est1: on cpu1 p4tcc1: on cpu1 cpu2: on acpi0 coretemp2: on cpu2 est2: on cpu2 p4tcc2: on cpu2 cpu3: on acpi0 coretemp3: on cpu3 est3: on cpu3 p4tcc3: on cpu3 ipmi0: on isa0 ipmi0: KCS mode found at io 0xca2 alignment 0x1 on isa ipmi0: KCS Error Data 43 orm0: at iomem 0xc0000-0xcafff on isa0 sc0: at flags 0x100 on isa0 sc0: VGA <16 virtual consoles, flags=0x300> vga0: at port 0x3c0-0x3df iomem 0xa0000-0xbffff on isa0 ZFS filesystem version 13 ZFS storage pool version 13 Timecounters tick every 1.000 msec usbus0: 12Mbps Full Speed USB v1.0 usbus1: 12Mbps Full Speed USB v1.0 usbus2: 12Mbps Full Speed USB v1.0 usbus3: 480Mbps High Speed USB v2.0 ugen0.1: at usbus0 uhub0: on usbus0 ugen1.1: at usbus1 uhub1: on usbus1 ugen2.1: at usbus2 uhub2: on usbus2 ugen3.1: at usbus3 uhub3: on usbus3 acd0: DMA limited to UDMA33, controller found non-ATA66 cable acd0: DVDR at ata0-master UDMA33 Waiting 5 seconds for SCSI devices to settle ipmi0: IPMI device rev. 1, firmware rev. 1.2, version 2.0 ipmi0: Number of channels 8 ipmi0: Attached watchdog uhub0: 2 ports with 2 removable, self powered uhub1: 2 ports with 2 removable, self powered uhub2: 2 ports with 2 removable, self powered uhub3: 6 ports with 6 removable, self powered ugen3.2: at usbus3 ums0: on usbus3 ums0: 3 buttons and [XYZ] coordinates ID=0 ukbd0: on usbus3 kbd2 at ukbd0 acd0: FAILURE - INQUIRY ILLEGAL REQUEST asc=0x24 ascq=0x00 (probe0:ata0:0:0:0): TEST UNIT READY. CDB: 0 0 0 0 0 0 (probe0:ata0:0:0:0): CAM Status: SCSI Status Error (probe0:ata0:0:0:0): SCSI Status: Check Condition (probe0:ata0:0:0:0): NOT READY asc:3a,0 (probe0:ata0:0:0:0): Medium not present (probe0:ata0:0:0:0): Unretryable error acd0: FAILURE - INQUIRY ILLEGAL REQUEST asc=0x24 ascq=0x00 (aprobe0:ahcich0:0:15:0): SIGNATURE: 0000 (aprobe0:ahcich0:0:0:0): SIGNATURE: 0000 (aprobe1:ahcich1:0:15:0): SIGNATURE: 0000 (aprobe0:ahcich1:0:0:0): SIGNATURE: 0000 (aprobe2:ahcich2:0:15:0): SIGNATURE: 0000 (aprobe0:ahcich2:0:0:0): SIGNATURE: 0000 (aprobe3:ahcich3:0:15:0): SIGNATURE: 0000 (aprobe0:ahcich3:0:0:0): SIGNATURE: 0000 (aprobe4:ahcich4:0:15:0): SIGNATURE: 0000 (aprobe0:ahcich4:0:0:0): SIGNATURE: 0000 (aprobe5:ahcich5:0:15:0): SIGNATURE: 0000 (aprobe0:ahcich5:0:0:0): SIGNATURE: 0000 ada0 at ahcich0 bus 0 target 0 lun 0 ada0: ATA/ATAPI-7 SATA 2.x device ada0: 300.000MB/s transfers ada0: 381554MB (781422768 512 byte sectors: 16H 63S/T 16383C) ada0: Native Command Queueing enabled ada1 at ahcich1 bus 0 target 0 lun 0 ada1: ATA/ATAPI-7 SATA 2.x device ada1: 300.000MB/s transfers ada1: 381554MB (781422768 512 byte sectors: 16H 63S/T 16383C) ada1: Native Command Queueing enabled ada2 at ahcich2 bus 0 target 0 lun 0 ada2: ATA/ATAPI-7 SATA 2.x device ada2: 300.000MB/s transfers ada2: 476940MB (976773168 512 byte sectors: 16H 63S/T 16383C) ada2: Native Command Queueing enabled ada3 at ahcich3 bus 0 target 0 lun 0 ada3: ATA/ATAPI-7 SATA 2.x device ada3: 300.000MB/s transfers ada3: 381554MB (781422768 512 byte sectors: 16H 63S/T 16383C) ada3: Native Command Queueing enabled ada4 at ahcich4 bus 0 target 0 lun 0 ada4: ATA/ATAPI-7 SATA 2.x device ada4: 300.000MB/s transfers ada4: 381554MB (781422768 512 byte sectors: 16H 63S/T 16383C) ada4: Native Command Queueing enabled ada5 at ahcich5 bus 0 target 0 lun 0 ada5: ATA/ATAPI-7 SATA 2.x device ada5: 300.000MB/s transfers ada5: 381554MB (781422768 512 byte sectors: 16H 63S/T 16383C) ada5: Native Command Queueing enabled cd0 at ata0 bus 0 target 0 lun 0 cd0: Removable CD-ROM SCSI-0 device cd0: 33.000MB/s transfers cd0: Attempt to query device size failed: NOT READY, Medium not present SMP: AP CPU #1 Launched! SMP: AP CPU #2 Launched! SMP: AP CPU #3 Launched! Trying to mount root from ufs:/dev/ada0s1a <118>Entropy harvesting: <118> interrupts <118> ethernet <118> point_to_point <118> kickstart <118>. <118>/dev/ada0s1a: FILE SYSTEM CLEAN; SKIPPING CHECKS <118>/dev/ada0s1a: clean, 20823 free (1279 frags, 2443 blocks, 0.5% fragmentation) <118>Starting Network: lo0 em0. <118>add net default: gateway 192.168.200.10 <5>em0: link state changed to UP <118>Additional ABI support: <118> linux <118>. <118>/etc/rc.d/mountd: WARNING: /etc/exports is not readable. <118>Starting mountd. <118>Posting monthly OS statistics to rpt.bsdstats.org <118>Configuring syscons: <118> blanktime <118>. <118>Local package initialization: <118> p4d <118>Perforce Server starting... <118> <118> p4web <118>. <118> <118>Sun Sep 27 10:31:15 CDT 2009 <118>Sep 27 10:35:13 borg exim[1232]: gethostby*.gethostanswer: asked for "borg-ipmi.lerctr.org IN AAAA", got type "A" <118>Sep 27 10:36:36 borg bconsole: gethostby*.gethostanswer: asked for "borg.lerctr.org IN AAAA", got type "A" <118>Sep 27 10:42:46 borg bconsole: gethostby*.gethostanswer: asked for "borg.lerctr.org IN AAAA", got type "A" <118>Sep 27 10:43:22 borg bacula-dir: gethostby*.gethostanswer: asked for "borg.lerctr.org IN AAAA", got type "A" <118>Sep 27 10:43:23 borg bacula-fd: gethostby*.gethostanswer: asked for "borg.lerctr.org IN AAAA", got type "A" <118>Sep 27 10:45:17 borg bconsole: gethostby*.gethostanswer: asked for "borg.lerctr.org IN AAAA", got type "A" <118>Sep 27 10:46:09 borg bacula-dir: gethostby*.gethostanswer: asked for "borg.lerctr.org IN AAAA", got type "A" <118>Sep 27 11:05:49 borg bconsole: gethostby*.gethostanswer: asked for "borg.lerctr.org IN AAAA", got type "A" WARNING: ada0s1a expected rawoffset 0, found 63 WARNING: ada0s1 expected rawoffset 0, found 63 <118>Sep 27 12:23:10 borg postgres[11330]: [2-1] FATAL: database "exim" does not exist <118>Sep 27 17:39:54 borg bconsole: gethostby*.gethostanswer: asked for "borg.lerctr.org IN AAAA", got type "A" <118>Sep 27 18:13:05 borg rpc.statd: gethostby*.getanswer: asked for "lermac IN AAAA", got type "A" <118>Sep 27 18:13:05 borg rpc.statd: gethostby*.getanswer: asked for "lermac IN AAAA", got type "A" <118>Sep 27 18:59:09 borg rpc.statd: gethostby*.getanswer: asked for "lermac IN AAAA", got type "A" <118>Sep 27 19:05:27 borg rpc.statd: gethostby*.getanswer: asked for "lrosenman-macbook IN AAAA", got type "A" <118>Sep 27 19:05:27 borg rpc.statd: gethostby*.getanswer: asked for "lrosenman-macbook IN AAAA", got type "A" <118>Sep 27 19:05:27 borg rpc.statd: gethostby*.getanswer: asked for "lermac IN AAAA", got type "A" <118>Sep 27 19:05:40 borg rpc.statd: gethostby*.getanswer: asked for "lermac IN AAAA", got type "A" <118>Sep 27 19:06:29 borg last message repeated 2 times <118>Sep 27 19:18:00 borg rpc.statd: gethostby*.getanswer: asked for "lrosenman-macbook IN AAAA", got type "A" <118>Sep 27 19:18:00 borg rpc.statd: gethostby*.getanswer: asked for "lermac IN AAAA", got type "A" <118>Sep 27 19:18:00 borg rpc.statd: gethostby*.getanswer: asked for "lermac IN AAAA", got type "A" Fatal trap 12: page fault while in kernel mode cpuid = 0; apic id = 00 fault virtual address = 0xf8 fault code = supervisor read data, page not present instruction pointer = 0x20:0xffffffff8030ba4f stack pointer = 0x28:0xffffff812584b450 frame pointer = 0x28:0xffffff812584b470 code segment = base 0x0, limit 0xfffff, type 0x1b = DPL 0, pres 1, long 1, def32 0, gran 1 processor eflags = interrupt enabled, resume, IOPL = 0 current process = 779 (NLM: master) trap number = 12 panic: page fault cpuid = 0 KDB: stack backtrace: db_trace_self_wrapper() at db_trace_self_wrapper+0x2a panic() at panic+0x182 trap_fatal() at trap_fatal+0x2ad trap_pfault() at trap_pfault+0x294 trap() at trap+0x3cf calltrap() at calltrap+0x8 --- trap 0xc, rip = 0xffffffff8030ba4f, rsp = 0xffffff812584b450, rbp = 0xffffff812584b470 --- prison_priv_check() at prison_priv_check+0xff priv_check_cred() at priv_check_cred+0x1b secpolicy_vnode_access() at secpolicy_vnode_access+0x28 zfs_zaccess() at zfs_zaccess+0x141 zfs_freebsd_access() at zfs_freebsd_access+0xd2 nlm_get_vfs_state() at nlm_get_vfs_state+0x1af nlm_do_unlock() at nlm_do_unlock+0x186 nlm4_unlock_msg_4_svc() at nlm4_unlock_msg_4_svc+0x19 nlm_prog_4() at nlm_prog_4+0x300 svc_run_internal() at svc_run_internal+0x6d2 svc_run() at svc_run+0x94 nlm_syscall() at nlm_syscall+0x8e1 syscall() at syscall+0x246 Xfast_syscall() at Xfast_syscall+0xe1 --- syscall (154, FreeBSD ELF64, nlm_syscall), rip = 0x8008c235c, rsp = 0x7fffffffec38, rbp = 0x7fffffffed60 --- Uptime: 8h48m20s >How-To-Repeat: share NFS with a MAC (not sure exactly what's provoking this). The MAC's are 10.5 and 10.6 >Fix: >Release-Note: >Audit-Trail: >Unformatted: