ppp triggers GPF panic

From: Stefan Bethke <stb_at_lassitu.de>
Date: Sat, 11 Jul 2009 14:35:53 +0200
Yesterday's -current, amd64, C2D, 4 GB RAM. Full dmesg below.


Fatal trap 9: general protection fault while in kernel mode
cpuid = 0; apic id = 00
instruction pointer	= 0x20:0xffffffff802fc2ce
stack pointer	        = 0x28:0xffffff8000037b10
frame pointer	        = 0x28:0xffffff8000037b30
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		= 12 (swi1: netisr 0)
[thread pid 12 tid 100007 ]
Stopped at      _mtx_lock_sleep+0x4e:   movl    0x288(%rcx),%esi

Didn't capture anything else there.  This happened when my ADSL link  
was forced down (24h connection reset).

After fixing the file system (UFS2 + softupdates on /), I got another  
"panic: spin lock held too long" on rebooting.

Then, the GPF panic happened again as ppp was trying to establish the  
connection:

Fatal trap 9: general protection fault while in kernel mode
cpuid = 1; apic id = 01
instruction pointer	= 0x20:0xffffffff802fc2f5
stack pointer	        = 0x28:0xffffff80750a0540
frame pointer	        = 0x28:0xffffff80750a0560
code segment		= base 0x0, limit 0xfffff, type 0x1b
			= DPL 0, pres 1, long 1, def32 0, gran 1
processor eflags	= resume, IOPL = 0
current process		= 726 (ppp)
[thread pid 726 tid 100098 ]
Stopped at      _mtx_lock_sleep+0x75:   movl    0x288(%r9),%r8d
db> bt
Tracing pid 726 tid 100098 td 0xffffff0002194ab0
_mtx_lock_sleep() at _mtx_lock_sleep+0x75
_mtx_lock_flags() at _mtx_lock_flags+0x43
netisr_queue_internal() at netisr_queue_internal+0x4f
netisr_queue_src() at netisr_queue_src+0x3c
rt_newaddrmsg() at rt_newaddrmsg+0x1d1
rtinit() at rtinit+0x3c0
in_ifinit() at in_ifinit+0x2f0
in_control() at in_control+0xf12
ifioctl() at ifioctl+0xfc1
kern_ioctl() at kern_ioctl+0xf6
ioctl() at ioctl+0xfd
syscall() at syscall+0x19e
Xfast_syscall() at Xfast_syscall+0xe1
--- syscall (54, FreeBSD ELF64, ioctl), rip = 0x80128ef5c, rsp =  
0x7fffffffd928, rbp = 0x7fffffffdcb0 ---
db> ps
   pid  ppid  pgrp   uid   state   wmesg         wchan        cmd
  1685  1684  1685  1000  Ss+     ttyin    0xffffff00023e04a8 bash
  1684  1682  1682  1000  S       select   0xffffff0002f18540 sshd
  1682  1596  1682     0  Ss      sbwait   0xffffff000218f144 sshd
  1681  1578  1578    80  S       accept   0xffffff0002aaddae httpd
  1680  1578  1578    80  S       accept   0xffffff0002aaddae httpd
  1679  1578  1578    80  S       accept   0xffffff0002aaddae httpd
  1678  1578  1578    80  S       accept   0xffffff0002aaddae httpd
  1677  1578  1578    80  S       accept   0xffffff0002aaddae httpd
  1676     1  1676     0  Ss+     ttyin    0xffffff0001f998a8 getty
  1675     1  1675     0  Ss+     ttyin    0xffffff0001fa1ca8 getty
  1674     1  1674     0  Ss+     ttyin    0xffffff0001fa88a8 getty
  1673     1  1673     0  Ss+     ttyin    0xffffff0001fa18a8 getty
  1672     1  1672     0  Ss+     ttyin    0xffffff0001fa70a8 getty
  1671     1  1671     0  Ss+     ttyin    0xffffff0001fa7ca8 getty
  1670     1  1670     0  Ss+     ttyin    0xffffff0001fa80a8 getty
  1669     1  1669     0  Ss+     ttyin    0xffffff0001f9fca8 getty
  1668     1  1668     0  Ss+     ttyin    0xffffff0001fa74a8 getty
  1666  1664    27     0  S+      nanslp   0xffffffff807b5328 sleep
  1665     1    27     0  S+      piperd   0xffffff0002ba7b60 logger
  1664     1    27     0  S+      wait     0xffffff000278c460 sh
  1643     1  1643     0  Ss      select   0xffffff00027df7c0 inetd
  1610     1  1610     0  Ss      nanslp   0xffffffff807b5328 cron
  1598  1121  1598     0  Ss      (threaded)                  sshguard
100142                   S       nanslp   0xffffffff807b5328 sshguard
100056                   S       piperd   0xffffff0002762888 initial  
thread
  1596     1  1596     0  Ss      select   0xffffff00026ef840 sshd
  1578     1  1578     0  Ss      select   0xffffff0002702040 httpd
  1539     1  1539     0  Ss      select   0xffffff0002027540 avahi- 
dnsconfd
  1534     1  1534   558  Ss      select   0xffffff0002900840 avahi- 
daemon
  1529     1  1529   556  Ss      select   0xffffff0002c47440 dbus- 
daemon
  1517     1  1517     0  Ss      select   0xffffff00027001c0 iscsi- 
target
  1512     1  1512     0  S+      select   0xffffff00020a8940 afpd
  1496     1  1496     0  Ss      nanslp   0xffffffff807b5328 openvpn
  1483     1  1483     0  Ss      nanslp   0xffffffff807b5328 openvpn
  1470     1  1470   136  Ss      select   0xffffff00028e7240 dhcpd
  1344     1  1344     0  Ss      rpcsvc   0xffffff00020a90a0 NLM:  
master
  1331     1  1331     0  Ss      select   0xffffff00027df940 rpc.statd
  1318  1317  1317     0  S       (threaded)                  nfsd
100126                   S       rpcsvc   0xffffff0002901720 nfsd:  
service
100125                   S       rpcsvc   0xffffff00020329a0 nfsd:  
service
100124                   S       rpcsvc   0xffffff0002032ca0 nfsd:  
service
100101                   S       rpcsvc   0xffffff0002901b20 nfsd:  
master
  1317     1  1317     0  Ss      select   0xffffff00029012c0 nfsd
  1302     1  1302     0  Ss      select   0xffffff0002901cc0 mountd
  1298     1  1298     0  Ss      select   0xffffff0002901940 rpcbind
  1233     0     0     0  SL      mdwait   0xffffff0002760000 [md0]
  1193     1  1193    53  Ss      (threaded)                  named
100110                   S       kqread   0xffffff000288c400 named
100109                   S       ucond    0xffffff00027ac300 named
100108                   S       ucond    0xffffff00027ac280 named
100107                   S       ucond    0xffffff00027ac200 named
100106                   S       sigwait  0xffffff80750c8a68 named
  1121     1  1121     0  Ss      bo_wwait 0xffffff00029d68d8 syslogd
   908     1   908     0  Ss      select   0xffffff00024f8c40 devd
   740     0     0     0  SL      (threaded)                  ng_queue
100105                   D       sleep    0xffffffff80c39f30 [ng_queue1]
100092                   D       sleep    0xffffffff80c39f30 [ng_queue0]
   726     1   726     0  Rs      CPU 1                       ppp
   151     0     0     0  SL      tx->tx_s 0xffffff0002397238  
[txg_thread_enter]
   150     0     0     0  SL      tx->tx_q 0xffffff0002397258  
[txg_thread_enter]
   149     0     0     0  SL      vgeom:io 0xffffff00020b1a90  
[vdev:worker label/d]
   148     0     0     0  SL      vgeom:io 0xffffff00020b2710  
[vdev:worker label/d]
   147     0     0     0  SL      vgeom:io 0xffffff00020a9410  
[vdev:worker label/d]
   146     0     0     0  SL      tq->tq_d 0xffffff000155f720 [spa_zio]
   145     0     0     0  SL      tq->tq_d 0xffffff000155f840 [spa_zio]
   144     0     0     0  SL      tq->tq_d 0xffffff000155f960 [spa_zio]
   143     0     0     0  SL      tq->tq_d 0xffffff000155fa80 [spa_zio]
   142     0     0     0  SL      tq->tq_d 0xffffff000155fba0 [spa_zio]
   141     0     0     0  SL      tq->tq_d 0xffffff000155fcc0 [spa_zio]
   140     0     0     0  SL      tq->tq_d 0xffffff000155fde0 [spa_zio]
   139     0     0     0  SL      tq->tq_d 0xffffff0001560600 [spa_zio]
   138     0     0     0  SL      tq->tq_d 0xffffff0001560600 [spa_zio]
   137     0     0     0  SL      tq->tq_d 0xffffff0001560600 [spa_zio]
   136     0     0     0  SL      tq->tq_d 0xffffff0001560600 [spa_zio]
   135     0     0     0  SL      tq->tq_d 0xffffff0001560600 [spa_zio]
   134     0     0     0  SL      tq->tq_d 0xffffff0001560600 [spa_zio]
   133     0     0     0  SL      tq->tq_d 0xffffff0001560600 [spa_zio]
   132     0     0     0  SL      tq->tq_d 0xffffff0001560600 [spa_zio]
   131     0     0     0  SL      tq->tq_d 0xffffff00015604e0 [spa_zio]
   130     0     0     0  SL      tq->tq_d 0xffffff00015604e0 [spa_zio]
   129     0     0     0  SL      tq->tq_d 0xffffff00015604e0 [spa_zio]
   128     0     0     0  SL      tq->tq_d 0xffffff00015604e0 [spa_zio]
   127     0     0     0  SL      tq->tq_d 0xffffff00015604e0 [spa_zio]
   126     0     0     0  SL      tq->tq_d 0xffffff00015604e0 [spa_zio]
   125     0     0     0  SL      tq->tq_d 0xffffff00015604e0 [spa_zio]
   124     0     0     0  SL      tq->tq_d 0xffffff00015604e0 [spa_zio]
   123     0     0     0  SL      tq->tq_d 0xffffff0001560180 [spa_zio]
   122     0     0     0  SL      tq->tq_d 0xffffff00015602a0 [spa_zio]
   121     0     0     0  SL      tq->tq_d 0xffffff00015603c0 [spa_zio]
    26     0     0     0  SL      m:w1     0xffffff0002074a00  
[g_mirror diesel_swa]
    25     0     0     0  SL      gwrite   0xffffff0002023000  
[g_mirror diesel_roo]
    24     0     0     0  SL      flowclea 0xffffffff807b5004  
[flowcleaner]
    23     0     0     0  SL      sdflush  0xffffffff807e88d8  
[softdepflush]
    22     0     0     0  SL      vlruwt   0xffffff0002034000 [vnlru]
    21     0     0     0  SL      vacv     0xffffffff80a48d40 [vaclean]
    20     0     0     0  SL      syncer   0xffffffff807d98a0 [syncer]
    19     0     0     0  SL      psleep   0xffffffff807d93c8  
[bufdaemon]
    18     0     0     0  SL      pgzero   0xffffffff807ea2ec [pagezero]
    17     0     0     0  SL      psleep   0xffffffff807e9688 [vmdaemon]
    16     0     0     0  SL      psleep   0xffffffff807e964c  
[pagedaemon]
    15     0     0     0  SL      l2arc_fe 0xffffffff80a52620  
[l2arc_feed_thread]
    14     0     0     0  SL      arc_recl 0xffffffff80a4a040  
[arc_reclaim_thread]
     9     0     0     0  SL      pftm     0xffffffff80b25d20 [pfpurge]
     8     0     0     0  SL      waiting_ 0xffffffff807dcd60  
[sctp_iterator]
     7     0     0     0  SL      ccb_scan 0xffffffff80799be0 [xpt_thrd]
    13     0     0     0  SL      -        0xffffffff807b5004 [yarrow]
     6     0     0     0  SL      tq->tq_d 0xffffff0001560060  
[system_taskq]
     5     0     0     0  SL      tq->tq_d 0xffffff0001560060  
[system_taskq]
     4     0     0     0  SL      -        0xffffffff807b17e8 [g_down]
     3     0     0     0  SL      -        0xffffffff807b17e0 [g_up]
     2     0     0     0  SL      -        0xffffffff807b17d0 [g_event]
    12     0     0     0  WL      (threaded)                  intr
100030                   I                                   [irq1:  
atkbd0]
100029                   I                                   [swi0:  
uart]
100028                   I                                   [irq19:  
atapci0+]
100026                   I                                   [irq9:  
acpi0]
100025                   I                                   [swi6:  
task queue]
100024                   I                                   [swi6:  
Giant taskq]
100022                   I                                   [swi5: +]
100021                   I                                   [swi2:  
cambio]
100008                   I                                   [swi3: vm]
100007                   I                                   [swi1:  
netisr 0]
100006                   I                                   [swi4:  
clock]
100005                   I                                   [swi4:  
clock]
    11     0     0     0  RL      (threaded)                  idle
100004                   Run     CPU 0                       [idle:  
cpu0]
100003                   CanRun                              [idle:  
cpu1]
     1     0     1     0  SLs     wait     0xffffff00014ce8c0 [init]
    10     0     0     0  SL      audit_wo 0xffffffff807e7c30 [audit]
     0     0     0     0  SLs     (threaded)                  kernel
100027                   D       -        0xffffff0001620400 [em0 taskq]
100023                   D       -        0xffffff00015afa80 [thread  
taskq]
100019                   D       -        0xffffff0001540780 [kqueue  
taskq]
100018                   D       -        0xffffff0001540800  
[acpi_task_2]
100017                   D       -        0xffffff0001540800  
[acpi_task_1]
100016                   D       -        0xffffff0001540800  
[acpi_task_0]
100012                   D       -        0xffffff00014ca200 [firmware  
taskq]
100000                   D       sched    0xffffffff807b18e0 [swapper]
db>


Full dmesg:
GDB: no debug ports present
KDB: debugger backends: ddb
KDB: current backend: ddb
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-BETA1 #5 r195556: Fri Jul 10 19:12:08 CEST 2009
     root_at_diesel.lassitu.de:/usr/obj/usr/src/sys/DIESEL
module_register: module probe already exists!
Module probe failed to register: 17
Timecounter "i8254" frequency 1193182 Hz quality 0
CPU: Intel(R) Core(TM)2 Duo CPU     E7300  _at_ 2.66GHz (2666.78-MHz K8- 
class CPU)
   Origin = "GenuineIntel"  Id = 0x10676  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 
=0x8e39d<SSE3,DTES64,MON,DS_CPL,EST,TM2,SSSE3,CX16,xTPR,PDCM,SSE4.1>
   AMD Features=0x20100800<SYSCALL,NX,LM>
   AMD Features2=0x1<LAHF>
   TSC: P-state invariant
real memory  = 4294967296 (4096 MB)
avail memory = 4008845312 (3823 MB)
ACPI APIC Table: <INTEL  DG45FC  >
FreeBSD/SMP: Multiprocessor System Detected: 2 CPUs
FreeBSD/SMP: 1 package(s) x 2 core(s)
  cpu0 (BSP): APIC ID:  0
  cpu1 (AP): APIC ID:  1
This module (opensolaris) contains code covered by the
Common Development and Distribution License (CDDL)
see http://opensolaris.org/os/licensing/opensolaris_license/
ACPI Warning: 32/64X FACS address mismatch in FADT - BDB69F40/        
0BDB6FE40, using 32 20090521 tbfadt-586
ioapic0 <Version 2.0> irqs 0-23 on motherboard
kbd1 at kbdmux0
acpi0: <INTEL DG45FC> 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 0x408-0x40b on acpi0
pcib0: <ACPI Host-PCI bridge> port 0xcf8-0xcff on acpi0
pci0: <ACPI PCI bus> on pcib0
vgapci0: <VGA-compatible display> port 0xf1c0-0xf1c7 mem  
0xd0000000-0xd03fffff,0xc0000000-0xcfffffff irq 16 at device 2.0 on pci0
agp0: <Intel G45 SVGA controller>f on vgapci0
agp0: detected 32764k stolen memory
agp0: apertre size is 256M
vgapci1: <VGA-compatible display> mem 0xd0400000-0xd04fffff at device  
2.1 on pci0
pci0: <simple comms> at device 3.0 (no driver attached)
em0: <Intel(R) PRO/1000 Network Connection 6.9.14> port 0xf100-0xf11f  
mem 0xd0500000-0xd051ffff,0xd0524000-0xd0524fff irq 20 at device 25.0  
on pci0
em0: Using MSI interrupt
em0: [FILTER]
em0: Ethernet address: 00:1c:c0:7d:8c:50
pci0: <serial bus, USB> at device 26.0 (no driver attached)
pci0: <serial bus, USB> at device 26.1 (no driver attached)
pci0: <serial bus, USB> at device 26.2 (no driver attached)
pci0: <serial bus, USB> at device 26.7 (no driver attached)
pci0: <multimedia, HDA> at device 27.0 (no driver attached)
pci0: <serial bus, USB> at device 29.0 (no driver attached)
pci0: <serial bus, USB> at device 29.1 (no driver attached)
pci0: <serial bus, USB> at device 29.2 (no driver attached)
pci0: <serial bus, USB> at device 29.7 (no driver attached)
pcib1: <ACPI PCI-PCI bridge> at device 30.0 on pci0
pci1: <ACPI PCI bus> on pcib1
isab0: <PCI-ISA bridge> at device 31.0 on pci0
isa0: <ISA bus> on isab0
atapci0: <Intel ICH10 SATA300 controller> port  
0xf1b0-0xf1b7,0xf1a0-0xf1a3,0xf190-0xf197,0xf180-0xf183,0xf020-0xf03f  
mem 0xd0525000-0xd05257ff irq 19 at device 31.2 on pci0
atapci0: [ITHREAD]
atapci0: AHCI called from vendor specific driver
atapci0: AHCI v1.20 controller with 6 3Gbps ports, PM not supported
ata2: <ATA channel 0> on atapci0
ata2: [ITHREAD]
ata3: <ATA channel 1> on atapci0
ata3: [ITHREAD]
ata4: <ATA channel 2> on atapci0
ata4: [ITHREAD]
ata5: <ATA channel 3> on atapci0
ata5: [ITHREAD]
ata6: <ATA channel 4> on atapci0
ata6: [ITHREAD]
ata7: <ATA channel 5> on atapci0
ata7: [ITHREAD]
pci0: <serial bus, SMBus> at device 31.3 (no driver attached)
atapci1: <Intel ICH10 SATA300 controller> port  
0xf170-0xf177,0xf160-0xf163,0xf150-0xf157,0xf140-0xf143,0xf130-0xf13f, 
0xf120-0xf12f irq 19 at device 31.5 on pci0
atapci1: [ITHREAD]
ata8: <ATA channel 0> on atapci1
ata8: [ITHREAD]
ata9: <ATA channel 1> on atapci1
ata9: [ITHREAD]
acpi_button0: <Power Button> on acpi0
uart0: <16550 or compatible> port 0x3f8-0x3ff irq 4 flags 0x10 on acpi0
uart0: [FILTER]
uart0: console (38400,n,8,1)
atrtc0: <AT realtime clock> port 0x70-0x71 irq 8 on acpi0
cpu0: <ACPI CPU> on acpi0
est0: <Enhanced SpeedStep Frequency Control> on cpu0
est: CPU supports Enhanced Speedstep, but is not recognized.
est: cpu_vendor GenuineIntel, msr 61a0a2006000a20
device_attach: est0 attach returned 6
p4tcc0: <CPU Frequency Thermal Control> on cpu0
cpu1: <ACPI CcPU> on acpi0
est1: <Enhanced SpeedStep Frequency Control> on pu1
est: CPU supports Enhanced Speedstep, but is not recognized.
est: cpu_vendor GenuineIntel, msr 61a0a2006000a20
device_attach: est1 attach returned 6
p4tcc1: <CPU Frequency Thermal Control> on cpu1
orm0: <ISA Option ROM> at iomem 0xcc800-0xcd7ff 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
atkbdc0: <Keyboard controller (i8042)> at port 0x60,0x64 on isa0
atkbd0: <AT Keyboard> irq 1 on atkbdc0
kbd0 at atkbd0
atkbd0: [GIANT-LOCKED]
atkbd0: [ITHREAD]
WARNING: ZFS is considered to be an experimental feature in FreeBSD.
Timecounters tick every 1.000 msec
ZFS filesystem version 13
ZFS storage pool version 13
ad4: 953869MB <SAMSUNG HD103UJ 1AA01113> at ata2-master SATA300
ad6: 953869MB <SAMSUNG HD103UJ 1AA01113> at ata3-master SATA300
ad8: 953869MB <SAMSUNG HD103UJ 1AA01113> at ata4-master SATA300
SMP: AP CPU #1 Launched!
GEOM_MIRROR: Device mirror/diesel_root launched (3/3).
GEOM_MIRROR: Device mirror/diesel_swap launched (3/3).
Trying to mount root from ufs:/dev/mirror/diesel_root



-- 
Stefan Bethke <stb_at_lassitu.de>   Fon +49 151 14070811
Received on Sat Jul 11 2009 - 10:49:50 UTC

This archive was generated by hypermail 2.4.0 : Wed May 19 2021 - 11:39:51 UTC