Date: Sun, 27 Sep 2009 19:54:46 -0500 From: Larry Rosenman <ler@lerctr.org> To: FreeBSD-gnats-submit@FreeBSD.org Subject: kern/139198: Page Fault out of NLM Message-ID: <E1Ms4Vq-0000NU-OQ@borg.lerctr.org> Resent-Message-ID: <200909280100.n8S10A9X096299@freefall.freebsd.org>
next in thread | raw e-mail | index | archive | help
>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<FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,CLFLUSH,DTS,ACPI,MMX,FXSR,SSE,SSE2,SS,HTT,TM,PBE>
Features2=0x4e3bd<SSE3,DTES64,MON,DS_CPL,VMX,EST,TM2,SSSE3,CX16,xTPR,PDCM,DCA>
AMD Features=0x20100800<SYSCALL,NX,LM>
AMD Features2=0x1<LAHF>
TSC: P-state invariant
real memory = 10737418240 (10240 MB)
avail memory = 10333769728 (9855 MB)
ACPI APIC Table: <PTLTD APIC >
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 <Version 2.0> irqs 0-23 on motherboard
ioapic1 <Version 2.0> irqs 24-47 on motherboard
kbd1 at kbdmux0
smbios0: <System Management BIOS> at iomem 0xf60c0-0xf60de on motherboard
smbios0: Version: 2.5
cryptosoft0: <software crypto> on motherboard
acpi0: <SMCI SMCISLP2> 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: <ACPI Host-PCI bridge> port 0xcf8-0xcff on acpi0
pci0: <ACPI PCI bus> on pcib0
pcib1: <ACPI PCI-PCI bridge> at device 2.0 on pci0
pci1: <ACPI PCI bus> on pcib1
pcib2: <ACPI PCI-PCI bridge> irq 16 at device 0.0 on pci1
pci2: <ACPI PCI bus> on pcib2
pcib3: <ACPI PCI-PCI bridge> irq 16 at device 0.0 on pci2
pci3: <ACPI PCI bus> on pcib3
pcib4: <ACPI PCI-PCI bridge> at device 0.0 on pci3
pci4: <ACPI PCI bus> on pcib4
pcib5: <ACPI PCI-PCI bridge> at device 0.2 on pci3
pci5: <ACPI PCI bus> on pcib5
pcib6: <ACPI PCI-PCI bridge> irq 18 at device 2.0 on pci2
pci6: <ACPI PCI bus> on pcib6
em0: <Intel(R) PRO/1000 Network Connection 6.9.14> 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: <Intel(R) PRO/1000 Network Connection 6.9.14> 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: <ACPI PCI-PCI bridge> at device 0.3 on pci1
pci7: <ACPI PCI bus> on pcib7
pcib8: <ACPI PCI-PCI bridge> at device 4.0 on pci0
pci8: <ACPI PCI bus> on pcib8
pcib9: <ACPI PCI-PCI bridge> at device 6.0 on pci0
pci9: <ACPI PCI bus> on pcib9
pci0: <base peripheral> at device 8.0 (no driver attached)
pcib10: <ACPI PCI-PCI bridge> irq 17 at device 28.0 on pci0
pci10: <ACPI PCI bus> on pcib10
uhci0: <Intel 631XESB/632XESB/3100 USB controller USB-1> port 0x1800-0x181f irq 17 at device 29.0 on pci0
uhci0: [ITHREAD]
uhci0: LegSup = 0x003b
usbus0: <Intel 631XESB/632XESB/3100 USB controller USB-1> on uhci0
uhci1: <Intel 631XESB/632XESB/3100 USB controller USB-2> port 0x1820-0x183f irq 19 at device 29.1 on pci0
uhci1: [ITHREAD]
uhci1: LegSup = 0x0010
usbus1: <Intel 631XESB/632XESB/3100 USB controller USB-2> on uhci1
uhci2: <Intel 631XESB/632XESB/3100 USB controller USB-3> port 0x1840-0x185f irq 18 at device 29.2 on pci0
uhci2: [ITHREAD]
uhci2: LegSup = 0x0010
usbus2: <Intel 631XESB/632XESB/3100 USB controller USB-3> on uhci2
ehci0: <Intel 63XXESB USB 2.0 controller> mem 0xd8500400-0xd85007ff irq 17 at device 29.7 on pci0
ehci0: [ITHREAD]
usbus3: EHCI version 1.0
usbus3: <Intel 63XXESB USB 2.0 controller> on ehci0
pcib11: <ACPI PCI-PCI bridge> at device 30.0 on pci0
pci11: <ACPI PCI bus> on pcib11
vgapci0: <VGA-compatible display> port 0x3000-0x30ff mem 0xd0000000-0xd7ffffff,0xd8200000-0xd820ffff irq 18 at device 1.0 on pci11
drm0: <ATI ES1000 RN50> on vgapci0
vgapci0: child drm0 requested pci_enable_busmaster
info: [drm] Initialized radeon 1.29.0 20080528
isab0: <PCI-ISA bridge> at device 31.0 on pci0
isa0: <ISA bus> on isab0
atapci0: <Intel 63XXESB2 UDMA100 controller> port 0x1f0-0x1f7,0x3f6,0x170-0x177,0x376,0x1860-0x186f at device 31.1 on pci0
ata0: <ATA channel 0> on atapci0
ata0: [ITHREAD]
ahci0: <AHCI controller> 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: <AHCI channel> at channel 0 on ahci0
ahcich0: [ITHREAD]
ahcich1: <AHCI channel> at channel 1 on ahci0
ahcich1: [ITHREAD]
ahcich2: <AHCI channel> at channel 2 on ahci0
ahcich2: [ITHREAD]
ahcich3: <AHCI channel> at channel 3 on ahci0
ahcich3: [ITHREAD]
ahcich4: <AHCI channel> at channel 4 on ahci0
ahcich4: [ITHREAD]
ahcich5: <AHCI channel> at channel 5 on ahci0
ahcich5: [ITHREAD]
ichsmb0: <Intel 631xESB/6321ESB (ESB2) SMBus controller> port 0x1100-0x111f irq 19 at device 31.3 on pci0
ichsmb0: [ITHREAD]
smbus0: <System Management Bus> on ichsmb0
smb0: <SMBus generic I/O> on smbus0
acpi_button0: <Power Button> on acpi0
atrtc0: <AT realtime clock> port 0x70-0x71 irq 8 on acpi0
atkbdc0: <Keyboard controller (i8042)> port 0x60,0x64 irq 1 on acpi0
atkbd0: <AT Keyboard> irq 1 on atkbdc0
kbd0 at atkbd0
atkbd0: [GIANT-LOCKED]
atkbd0: [ITHREAD]
psm0: <PS/2 Mouse> 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: <floppy drive controller> port 0x3f0-0x3f5,0x3f7 irq 6 drq 2 on acpi0
fdc0: [FILTER]
fd0: <1440-KB 3.5" drive> on fdc0 drive 0
ppc0: <Parallel port> 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: <Parallel port bus> on ppc0
lpt0: <Printer> on ppbus0
lpt0: [ITHREAD]
lpt0: Interrupt-driven port
ppi0: <Parallel I/O> on ppbus0
cpu0: <ACPI CPU> on acpi0
coretemp0: <CPU On-Die Thermal Sensors> on cpu0
est0: <Enhanced SpeedStep Frequency Control> on cpu0
p4tcc0: <CPU Frequency Thermal Control> on cpu0
cpu1: <ACPI CPU> on acpi0
coretemp1: <CPU On-Die Thermal Sensors> on cpu1
est1: <Enhanced SpeedStep Frequency Control> on cpu1
p4tcc1: <CPU Frequency Thermal Control> on cpu1
cpu2: <ACPI CPU> on acpi0
coretemp2: <CPU On-Die Thermal Sensors> on cpu2
est2: <Enhanced SpeedStep Frequency Control> on cpu2
p4tcc2: <CPU Frequency Thermal Control> on cpu2
cpu3: <ACPI CPU> on acpi0
coretemp3: <CPU On-Die Thermal Sensors> on cpu3
est3: <Enhanced SpeedStep Frequency Control> on cpu3
p4tcc3: <CPU Frequency Thermal Control> on cpu3
ipmi0: <IPMI System Interface> on isa0
ipmi0: KCS mode found at io 0xca2 alignment 0x1 on isa
ipmi0: KCS Error Data 43
orm0: <ISA Option ROM> at iomem 0xc0000-0xcafff on isa0
sc0: <System console> at flags 0x100 on isa0
sc0: VGA <16 virtual consoles, flags=0x300>
vga0: <Generic ISA VGA> 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: <Intel> at usbus0
uhub0: <Intel UHCI root HUB, class 9/0, rev 1.00/1.00, addr 1> on usbus0
ugen1.1: <Intel> at usbus1
uhub1: <Intel UHCI root HUB, class 9/0, rev 1.00/1.00, addr 1> on usbus1
ugen2.1: <Intel> at usbus2
uhub2: <Intel UHCI root HUB, class 9/0, rev 1.00/1.00, addr 1> on usbus2
ugen3.1: <Intel> at usbus3
uhub3: <Intel EHCI root HUB, class 9/0, rev 2.00/1.00, addr 1> on usbus3
acd0: DMA limited to UDMA33, controller found non-ATA66 cable
acd0: DVDR <Memorex DVD+-RAM 510L v1/MWS7> 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: <Peppercon AG> at usbus3
ums0: <Peppercon AG Multidevice, class 0/0, rev 2.00/0.01, addr 2> on usbus3
ums0: 3 buttons and [XYZ] coordinates ID=0
ukbd0: <Peppercon AG Multidevice, class 0/0, rev 2.00/0.01, addr 2> 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: <ST3400620AS 3.AAJ> 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: <ST3400620AS 3.AAJ> 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: <ST3500630AS 3.AAE> 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: <ST3400620AS 3.AAJ> 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: <ST3400620AS 3.AAJ> 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: <ST3400620AS 3.AAJ> 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: <Memorex DVD+-RAM 510L v1 MWS7> 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:
Want to link to this message? Use this URL: <https://mail-archive.FreeBSD.org/cgi/mid.cgi?E1Ms4Vq-0000NU-OQ>