From owner-freebsd-current@FreeBSD.ORG Sat Jul 11 12:49:50 2009 Return-Path: Delivered-To: freebsd-current@freebsd.org Received: from mx1.freebsd.org (mx1.freebsd.org [IPv6:2001:4f8:fff6::34]) by hub.freebsd.org (Postfix) with ESMTP id 1285F1065673 for ; Sat, 11 Jul 2009 12:49:50 +0000 (UTC) (envelope-from stb@lassitu.de) Received: from koef.zs64.net (koef.zs64.net [212.12.50.230]) by mx1.freebsd.org (Postfix) with ESMTP id EF2758FC13 for ; Sat, 11 Jul 2009 12:49:48 +0000 (UTC) (envelope-from stb@lassitu.de) Received: from localhost by koef.zs64.net (8.14.3/8.14.3) with ESMTP id n6BCZrJS016286 (version=TLSv1/SSLv3 cipher=AES128-SHA bits=128 verify=NO) for ; Sat, 11 Jul 2009 14:35:54 +0200 (CEST) (envelope-from stb@lassitu.de) (authenticated as stb) Message-Id: <128E7C52-CCBD-4BAF-A4AE-1D914A3968CB@lassitu.de> From: Stefan Bethke To: FreeBSD Current Content-Type: text/plain; charset=US-ASCII; format=flowed; delsp=yes Content-Transfer-Encoding: 7bit Mime-Version: 1.0 (Apple Message framework v935.3) Date: Sat, 11 Jul 2009 14:35:53 +0200 X-Mailer: Apple Mail (2.935.3) Subject: ppp triggers GPF panic X-BeenThere: freebsd-current@freebsd.org X-Mailman-Version: 2.1.5 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: Sat, 11 Jul 2009 12:49:50 -0000 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@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 @ 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 AMD Features=0x20100800 AMD Features2=0x1 TSC: P-state invariant real memory = 4294967296 (4096 MB) avail memory = 4008845312 (3823 MB) ACPI APIC Table: 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 irqs 0-23 on motherboard kbd1 at kbdmux0 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 0x408-0x40b on acpi0 pcib0: port 0xcf8-0xcff on acpi0 pci0: on pcib0 vgapci0: port 0xf1c0-0xf1c7 mem 0xd0000000-0xd03fffff,0xc0000000-0xcfffffff irq 16 at device 2.0 on pci0 agp0: f on vgapci0 agp0: detected 32764k stolen memory agp0: apertre size is 256M vgapci1: mem 0xd0400000-0xd04fffff at device 2.1 on pci0 pci0: at device 3.0 (no driver attached) em0: 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: at device 26.0 (no driver attached) pci0: at device 26.1 (no driver attached) pci0: at device 26.2 (no driver attached) pci0: at device 26.7 (no driver attached) pci0: at device 27.0 (no driver attached) pci0: at device 29.0 (no driver attached) pci0: at device 29.1 (no driver attached) pci0: at device 29.2 (no driver attached) pci0: at device 29.7 (no driver attached) pcib1: at device 30.0 on pci0 pci1: on pcib1 isab0: at device 31.0 on pci0 isa0: on isab0 atapci0: 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: on atapci0 ata2: [ITHREAD] ata3: on atapci0 ata3: [ITHREAD] ata4: on atapci0 ata4: [ITHREAD] ata5: on atapci0 ata5: [ITHREAD] ata6: on atapci0 ata6: [ITHREAD] ata7: on atapci0 ata7: [ITHREAD] pci0: at device 31.3 (no driver attached) atapci1: port 0xf170-0xf177,0xf160-0xf163,0xf150-0xf157,0xf140-0xf143,0xf130-0xf13f, 0xf120-0xf12f irq 19 at device 31.5 on pci0 atapci1: [ITHREAD] ata8: on atapci1 ata8: [ITHREAD] ata9: on atapci1 ata9: [ITHREAD] acpi_button0: on acpi0 uart0: <16550 or compatible> port 0x3f8-0x3ff irq 4 flags 0x10 on acpi0 uart0: [FILTER] uart0: console (38400,n,8,1) atrtc0: port 0x70-0x71 irq 8 on acpi0 cpu0: on acpi0 est0: on cpu0 est: CPU supports Enhanced Speedstep, but is not recognized. est: cpu_vendor GenuineIntel, msr 61a0a2006000a20 device_attach: est0 attach returned 6 p4tcc0: on cpu0 cpu1: on acpi0 est1: on pu1 est: CPU supports Enhanced Speedstep, but is not recognized. est: cpu_vendor GenuineIntel, msr 61a0a2006000a20 device_attach: est1 attach returned 6 p4tcc1: on cpu1 orm0: at iomem 0xcc800-0xcd7ff 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 atkbdc0: at port 0x60,0x64 on isa0 atkbd0: 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 at ata2-master SATA300 ad6: 953869MB at ata3-master SATA300 ad8: 953869MB 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 Fon +49 151 14070811