From owner-freebsd-isdn Sun Sep 24 10:29: 3 2000 Delivered-To: freebsd-isdn@freebsd.org Received: from mercury.nildram.co.uk (mercury.nildram.co.uk [195.112.4.37]) by hub.freebsd.org (Postfix) with ESMTP id A776C37B43C for ; Sun, 24 Sep 2000 10:28:42 -0700 (PDT) Received: from nermal.unix-consult.com (tgeusch.dial.nildram.co.uk [195.149.29.39]) by mercury.nildram.co.uk (8.10.0/8.10.0.Beta10) with ESMTP id e8OGjJY05633 for ; Sun, 24 Sep 2000 17:45:19 +0100 Received: (qmail 369 invoked from network); 24 Sep 2000 17:27:06 -0000 Received: from odie.unix-consult.com (192.168.0.2) by nermal.unix-consult.com with SMTP; 24 Sep 2000 17:27:06 -0000 Received: (qmail 301 invoked by uid 1002); 24 Sep 2000 17:27:03 -0000 Date: Sun, 24 Sep 2000 18:27:03 +0100 From: Timo Geusch To: freebsd-isdn@freebsd.org Subject: Re: Are there any known issues with SMP, 4.1-stable and i4b? Message-ID: <20000924182703.B260@odie.unix-consult.com> References: <20000924154123.A266@odie.unix-consult.com> Mime-Version: 1.0 Content-Type: text/plain; charset=us-ascii Content-Disposition: inline User-Agent: Mutt/1.2.5i In-Reply-To: <20000924154123.A266@odie.unix-consult.com>; from freebsd@unix-consult.com on Sun, Sep 24, 2000 at 03:41:23PM +0100 Sender: owner-freebsd-isdn@FreeBSD.ORG Precedence: bulk X-Loop: FreeBSD.org Great, I *love* responding to my own emails. Anyway, the non-SMP kernel went bang the same fashion the SMP one did, it only took longer. So I went back to the SMP kernel with debug info. Which means that I now have a stack trace (see below). What happened is fairly obvious - the ISDN card's interrupt handler got a bad sc pointer, tried to dereference it and the whole system didn't like it. Of course the interesting bit is to find out why... I'll keep the dump around for some time in case someone wants a specific view of it but I'd rather not upload it to a publicly accessible place. It's 256MB in size... TIA, Timo Anyway, here's the panic message: SMP 2 cpus IdlePTD 3407872 initial pcb at 2bcaa0 panicstr: page fault panic messages: --- Fatal trap 12: page fault while in kernel mode mp_lock = 00000002; cpuid = 0; lapic.id = 00000000 fault virtual address = 0xd5d5d936 fault code = supervisor write, page not present instruction pointer = 0x8:0xc02559a1 stack pointer = 0x10:0xff806f18 frame pointer = 0x10:0xff806f68 code segment = base 0x0, limit 0xfffff, type 0x1b = DPL 0, pres 1, def32 1, gran 1 processor eflags = interrupt enabled, resume, IOPL = 0 current process = Idle interrupt mask = net <- SMP: XXX trap number = 12 panic: page fault mp_lock = 00000002; cpuid = 0; lapic.id = 00000000 boot() called on cpu#0 syncing disks... Fatal trap 12: page fault while in kernel mode mp_lock = 00000003; cpuid = 0; lapic.id = 00000000 fault virtual address = 0x30 fault code = supervisor read, page not present instruction pointer = 0x8:0xc01f93a5 stack pointer = 0x10:0xff806d30 frame pointer = 0x10:0xff806d34 code segment = base 0x0, limit 0xfffff, type 0x1b = DPL 0, pres 1, def32 1, gran 1 processor eflags = interrupt enabled, resume, IOPL = 0 current process = Idle interrupt mask = net bio <- SMP: XXX trap number = 12 panic: page fault mp_lock = 00000003; cpuid = 0; lapic.id = 00000000 boot() called on cpu#0 Uptime: 18m52s dumping to dev #da/0x20001, offset 524288 dump 256 255 254 253 252 251 250 249 248 247 246 245 244 243 242 241 240 239 238 237 236 235 234 233 232 231 230 229 228 227 226 225 224 223 222 221 220 219 218 2 17 216 215 214 213 212 211 210 209 208 207 206 205 204 203 202 201 200 199 198 19 7 196 195 194 193 192 191 190 189 188 187 186 185 184 183 182 181 180 179 178 177 176 175 174 173 172 171 170 169 168 167 166 165 164 163 162 161 160 159 158 157 156 155 154 153 152 151 150 149 148 147 146 145 144 143 142 141 140 139 138 137 1 36 135 134 133 132 131 130 129 128 127 126 125 124 123 122 121 120 119 118 117 11 6 115 114 113 112 111 110 109 108 107 106 105 104 103 102 101 100 99 98 97 96 95 94 93 92 91 90 89 88 87 86 85 84 83 82 81 80 79 78 77 76 75 74 73 72 71 70 69 68 67 66 65 64 63 62 61 60 59 58 57 56 55 54 53 52 51 50 49 48 47 46 45 44 43 42 41 40 39 38 37 36 35 34 33 32 31 30 29 28 27 26 25 24 23 22 21 20 19 18 17 16 15 14 13 12 11 10 9 8 7 6 5 4 3 2 1 --- #0 boot (howto=260) at ../../kern/kern_shutdown.c:302 302 dumppcb.pcb_cr3 = rcr3(); ... and the stack trace ... (kgdb) where #0 boot (howto=260) at ../../kern/kern_shutdown.c:302 #1 0xc01648c4 in poweroff_wait (junk=0xc028e28f, howto=0) at ../../kern/kern_shutdown.c:552 #2 0xc024a697 in trap_fatal (frame=0xff806cf0, eva=48) at ../../i386/i386/trap.c:951 #3 0xc024a32d in trap_pfault (frame=0xff806cf0, usermode=0, eva=48) at ../../i386/i386/trap.c:844 #4 0xc0249e9b in trap (frame={tf_fs = -1070858216, tf_es = -1070858224, tf_ds = -8388592, tf_edi = -880377088, tf_esi = 0, tf_ebp = -8360652, tf_isp = -8360676, tf_ebx = -1070933124, tf_edx = 1744863296, tf_ecx = 2, tf_eax = 0, tf_trapno = 12, tf_err = 0, tf_eip = -1071672411, tf_cs = 8, tf_eflags = 66050, tf_esp = -1058341376, tf_ss = -8360620}) at ../../i386/i386/trap.c:443 #5 0xc01f93a5 in acquire_lock (lk=0xc02adb7c) at machine/globals.h:114 #6 0xc01fd57a in softdep_fsync_mountdev (vp=0xcb868300) at ../../ufs/ffs/ffs_softdep.c:3788 #7 0xc02016de in ffs_fsync (ap=0xff806db4) at ../../ufs/ffs/ffs_vnops.c:134 #8 0xc020040a in ffs_sync (mp=0xc0eafe00, waitfor=2, cred=0xc0a2a880, p=0xc02d7b20) at vnode_if.h:537 #9 0xc0191a2b in sync (p=0xc02d7b20, uap=0x0) at ../../kern/vfs_syscalls.c:544 #10 0xc01642df in boot (howto=256) at ../../kern/kern_shutdown.c:224 #11 0xc01648c4 in poweroff_wait (junk=0xc028e28f, howto=0) at ../../kern/kern_shutdown.c:552 #12 0xc024a697 in trap_fatal (frame=0xff806ed8, eva=3587561782) at ../../i386/i386/trap.c:951 #13 0xc024a32d in trap_pfault (frame=0xff806ed8, usermode=0, eva=3587561782) at ../../i386/i386/trap.c:844 #14 0xc0249e9b in trap (frame={tf_fs = -1070792680, tf_es = -8388592, tf_ds = -8388592, tf_edi = 63, tf_esi = -1070743556, tf_ebp = -8360088, tf_isp = -8360188, tf_ebx = 2, tf_edx = 37904, tf_ecx = -707406379, tf_eax = -707406379, tf_trapno = 12, tf_err = 2, tf_eip = -1071294047, tf_cs = 8, tf_eflags = 66118, tf_esp = -1070744192, tf_ss = -1070744192}) at ../../i386/i386/trap.c:443 #15 0xc02559a1 in avma1pp_hscx_intr (h_chan=-707406379, stat=3587560917, sc=0xd5d5d5d5) at ../../i4b/layer1/i4b_avm_fritz_pci.c:912 #16 0xd5d5d5d5 in ?? () Cannot access memory at address 0xd5d5d5d5. ... and the dmesg: Copyright (c) 1992-2000 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 4.1-STABLE #0: Sun Sep 24 17:09:37 BST 2000 root@nermal.unix-consult.com:/usr/src/sys/compile/NERMAL.SMP Timecounter "i8254" frequency 1193182 Hz CPU: Pentium Pro (199.43-MHz 686-class CPU) Origin = "GenuineIntel" Id = 0x619 Stepping = 9 Features=0xfbff real memory = 268435456 (262144K bytes) avail memory = 258162688 (252112K bytes) Programming 24 pins in IOAPIC #0 IOAPIC #0 intpin 2 -> irq 0 IOAPIC #0 intpin 16 -> irq 11 IOAPIC #0 intpin 18 -> irq 10 IOAPIC #0 intpin 19 -> irq 12 FreeBSD/SMP: Multiprocessor motherboard cpu0 (BSP): apic id: 0, version: 0x00040011, at 0xfee00000 cpu1 (AP): apic id: 1, version: 0x00040011, at 0xfee00000 io0 (APIC): apic id: 2, version: 0x00170011, at 0xfec00000 Preloaded elf kernel "kernel" at 0xc032e000. Pentium Pro MTRR support enabled md0: Malloc disk npx0: on motherboard npx0: INT 16 interface pcib0: on motherboard pci0: on pcib0 isab0: at device 7.0 on pci0 isa0: on isab0 atapci0: port 0xf000-0xf00f at device 7.1 on pci0 ata1: at 0x170 irq 15 on atapci0 isic0: port 0x9400-0x941f mem 0xe2103000-0xe210301f irq 11 at device 10.0 on pci0 isic0: ISAC 2085 Version A1/A2 or 2086/2186 Version 1.1 (IOM-2) pci0: at 11.0 irq 12 sym0: <875> port 0x9800-0x98ff mem 0xe2101000-0xe2101fff,0xe2100000-0xe21000ff irq 10 at device 12.0 on pci0 sym0: Symbios NVRAM, ID 7, Fast-20, SE, parity checking sym0: open drain IRQ line driver, using on-chip SRAM sym0: using LOAD/STORE-based firmware. fxp0: port 0x9c00-0x9c1f mem 0xe2000000-0xe20fffff,0xe2102000-0xe2102fff irq 11 at device 14.0 on pci0 fxp0: Ethernet address 00:08:c7:8c:40:03 fdc0: at port 0x3f0-0x3f5,0x3f7 irq 6 drq 2 on isa0 fdc0: FIFO enabled, 8 bytes threshold fd0: <1440-KB 3.5" drive> on fdc0 drive 0 atkbdc0: at port 0x60,0x64 on isa0 atkbd0: flags 0x1 irq 1 on atkbdc0 kbd0 at atkbd0 vga0: at port 0x3c0-0x3df iomem 0xa0000-0xbffff on isa0 sc0: at flags 0x100 on isa0 sc0: VGA <16 virtual consoles, flags=0x300> sio0 at port 0x3f8-0x3ff irq 4 flags 0x10 on isa0 sio0: type 16550A sio1 at port 0x2f8-0x2ff irq 3 on isa0 sio1: type 16550A ppc0: at port 0x378-0x37f irq 7 on isa0 ppc0: Generic chipset (NIBBLE-only) in COMPATIBLE mode ppi0: on ppbus0 lpt0: on ppbus0 lpt0: Interrupt-driven port i4b-L1-isic_isac_exir_hdlr: EXIRQ Rx Frame Overflow APIC_IO: Testing 8254 interrupt delivery APIC_IO: routing 8254 via IOAPIC #0 intpin 2 i4bisppp: 4 ISDN SyncPPP device(s) attached i4b: ISDN call control device attached i4btel: 2 ISDN telephony interface device(s) attached i4brbch: 4 raw B channel access device(s) attached i4bipr: 4 IP over raw HDLC ISDN device(s) attached (VJ header compression) i4bctl: ISDN system control port attached i4btrc: 4 ISDN trace device(s) attached SMP: AP CPU #1 Launched! acd0: CDROM at ata1-master using PIO4 Waiting 5 seconds for SCSI devices to settle (noperiph:sym0:0:-1:-1): SCSI BUS reset delivered. da1 at sym0 bus 0 target 1 lun 0 da1: Fixed Direct Access SCSI-2 device da1: 20.000MB/s transfers (20.000MHz, offset 15), Tagged Queueing Enabled da1: 4340MB (8888924 512 byte sectors: 255H 63S/T 553C) da3 at sym0 bus 0 target 5 lun 0 da3: Fixed Direct Access SCSI-2 device da3: 40.000MB/s transfers (20.000MHz, offset 16, 16bit), Tagged Queueing Enabled da3: 4343MB (8895370 512 byte sectors: 255H 63S/T 553C) Mounting root from ufs:/dev/da0s1a da0 at sym0 bus 0 target 0 lun 0 da0: Fixed Direct Access SCSI-2 device da0: 20.000MB/s transfers (20.000MHz, offset 15) da0: 2063MB (4226725 512 byte sectors: 255H 63S/T 263C) WARNING: / was not properly dismounted da2 at sym0 bus 0 target 4 lun 0 da2: Fixed Direct Access SCSI-2 device da2: 40.000MB/s transfers (20.000MHz, offset 15, 16bit), Tagged Queueing Enabled da2: 4357MB (8925000 512 byte sectors: 255H 63S/T 555C) vinum: loaded vinum: reading configuration from /dev/da3s1e vinum: updating configuration from /dev/da2s1e vinum: updating configuration from /dev/da1s1e i4b: unit 0, assigned TEI = 116 = 0x74 To Unsubscribe: send mail to majordomo@FreeBSD.org with "unsubscribe freebsd-isdn" in the body of the message