From owner-freebsd-current@FreeBSD.ORG Sat Apr 10 14:46:28 2010 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 AC96B1065672 for ; Sat, 10 Apr 2010 14:46:28 +0000 (UTC) (envelope-from mike@sentex.net) Received: from lava.sentex.ca (pyroxene.sentex.ca [199.212.134.18]) by mx1.freebsd.org (Postfix) with ESMTP id DF6528FC0A for ; Sat, 10 Apr 2010 14:46:27 +0000 (UTC) Received: from mdt-xp.sentex.net (simeon.sentex.ca [192.168.43.27]) by lava.sentex.ca (8.14.3/8.14.3) with ESMTP id o3AEkQ0O058513; Sat, 10 Apr 2010 10:46:26 -0400 (EDT) (envelope-from mike@sentex.net) Message-Id: <201004101446.o3AEkQ0O058513@lava.sentex.ca> X-Mailer: QUALCOMM Windows Eudora Version 7.1.0.9 Date: Sat, 10 Apr 2010 10:46:21 -0400 To: Jack Vogel From: Mike Tancsa In-Reply-To: References: <201004081831.o38IVR3s043434@lava.sentex.ca> <20100408230750.GR5734@michelle.cdnetworks.com> <201004091317.o39DHFEl049965@lava.sentex.ca> <20100409164115.GB1085@michelle.cdnetworks.com> <201004091900.o39J0b0k051687@lava.sentex.ca> <20100409201307.GF1085@michelle.cdnetworks.com> <201004092105.o39L5SSh052323@lava.sentex.ca> Mime-Version: 1.0 Content-Type: text/plain; charset="us-ascii"; format=flowed Cc: pyunyh@gmail.com, freebsd-current@freebsd.org Subject: Re: LOR on em in HEAD ( was Re: em driver regression 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, 10 Apr 2010 14:46:28 -0000 At 05:11 PM 4/9/2010, Jack Vogel wrote: >Don't know, but I would just ignore it, I think its a false warning anyway. OK. I updated to HEAD as of this AM, but now I get a panic at bootup odule pci/rl failed to register: 17 Timecounter "i8254" frequency 1193182 Hz quality 0 CPU: Intel(R) Core(TM) i5 CPU 750 @ 2.67GHz (2666.65-MHz 686-class CPU) Origin = "GenuineIntel" Id = 0x106e5 Family = 6 Model = 1e Stepping = 5 Features=0xbfebfbff Features2=0x98e3fd AMD Features=0x28100000 AMD Features2=0x1 TSC: P-state invariant real memory = 4294967296 (4096 MB) avail memory = 2577711104 (2458 MB) ACPI APIC Table: FreeBSD/SMP: Multiprocessor System Detected: 4 CPUs FreeBSD/SMP: 1 package(s) x 4 core(s) cpu0 (BSP): APIC ID: 0 cpu1 (AP): APIC ID: 2 cpu2 (AP): APIC ID: 4 cpu3 (AP): APIC ID: 6 ioapic0 irqs 0-23 on motherboard lapic0: Forcing LINT1 to edge trigger 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 cpu0: on acpi0 cpu1: on acpi0 cpu2: on acpi0 cpu3: on acpi0 acpi_hpet0: iomem 0xfed00000-0xfed003ff on acpi0 Timecounter "HPET" frequency 14318180 Hz quality 900 pcib0: port 0xcf8-0xcff on acpi0 pci0: on pcib0 pcib1: irq 16 at device 5.0 on pci0 pci1: on pcib1 vgapci0: port 0x2000-0x20ff mem 0xb0000000-0xbfffffff,0xc1a00000-0xc1a0ffff irq 16 at device 0.0 on pci1 pci1: at device 0.1 (no driver attached) pci0: at device 8.0 (no driver attached) pci0: at device 8.1 (no driver attached) pci0: at device 8.2 (no driver attached) pci0: at device 8.3 (no driver attached) pci0: at device 16.0 (no driver attached) pci0: at device 16.1 (no driver attached) em0: port 0x3040-0x305f mem 0xc1b00000-0xc1b1ffff,0xc1b25000-0xc1b25fff irq 20 at device 25.0 on pci0 em0: Using MSI interrupt em0: [FILTER] em0: Ethernet address: 00:15:17:c8:4b:99 ehci0: mem 0xc1b22000-0xc1b223ff irq 21 at device 26.0 on pci0 ehci0: [ITHREAD] usbus0: EHCI version 1.0 usbus0: on ehci0 pcib2: irq 16 at device 28.0 on pci0 pci2: on pcib2 pcib3: irq 16 at device 28.4 on pci0 pci3: on pcib3 em1: port 0x1000-0x101f mem 0xc1900000-0xc191ffff,0xc1920000-0xc1923fff irq 16 at device 0.0 on pci3 em1: Using MSIX interrupts with 5 vectors em1: [ITHREAD] em1: [ITHREAD] em1: [ITHREAD] em1: [ITHREAD] em1: [ITHREAD] em1: Ethernet address: 00:15:17:c8:4b:98 pcib4: irq 18 at device 28.6 on pci0 pci4: on pcib4 pcib5: irq 19 at device 28.7 on pci0 pci5: on pcib5 ehci1: mem 0xc1b21000-0xc1b213ff irq 23 at device 29.0 on pci0 ehci1: [ITHREAD] usbus1: EHCI version 1.0 usbus1: on ehci1 pcib6: at device 30.0 on pci0 pci6: on pcib6 isab0: at device 31.0 on pci0 isa0: on isab0 ahci0: port 0x3068-0x306f,0x3074-0x3077,0x3060-0x3067,0x3070-0x3073,0x3020-0x303f mem 0xc1b20000-0xc1b207ff irq 18 at device 31.2 on pci0 ahci0: [ITHREAD] ahci0: AHCI v1.30 with 6 3Gbps ports, Port Multiplier not 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] pci0: at device 31.3 (no driver attached) acpi_button0: on acpi0 acpi_button0: enable wake failed atrtc0: port 0x70-0x71,0x74-0x77 irq 8 on acpi0 uart0: <16550 or compatible> port 0x3f8-0x3ff irq 4 flags 0x10 on acpi0 uart0: [FILTER] uart0: console (115200,n,8,1) uart1: <16550 or compatible> port 0x2f8-0x2ff irq 3 on acpi0 uart1: [FILTER] pmtimer0 on isa0 orm0: at iomem 0xd3000-0xd3fff,0xd4000-0xd4fff pnpid ORM0000 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 ata0 at port 0x1f0-0x1f7,0x3f6 irq 14 on isa0 ata0: [ITHREAD] ata1 at port 0x170-0x177,0x376 irq 15 on isa0 ata1: [ITHREAD] ppc0: parallel port not found. est0: on cpu0 p4tcc0: on cpu0 est1: on cpu1 p4tcc1: on cpu1 est2: on cpu2 p4tcc2: on cpu2 est3: on cpu3 p4tcc3: on cpu3 Timecounters tick every 1.000 msec usbus0: 480Mbps High Speed USB v2.0 usbus1: 480Mbps High Speed USB v2.0 ugen0.1: at usbus0 uhub0: on usbus0 ugen1.1: at usbus1 uhub1: on usbus1 ada0 at ahcich0 bus 0 scbus0 target 0 lun 0 ada0: ATA-8 SATA 2.x device ada0: 300.000MB/s transfers (SATA 2.x, UDMA6, PIO 8192bytes) ada0: Command Queueing enabled ada0: 238475MB (488397168 512 byte sectors: 16H 63S/T 16383C) ada1 at ahcich3 bus 0 scbus3 target 0 lun 0 ada1: ATA-8 SATA 2.x device ada1: 300.000MB/s transfers (SATA 2.x, UDMA6, PIO 8192bytes) ada1: Command Queueing enabled ada1: 238475MB (488397168 512 byte sectors: 16H 63S/T 16383C) lapic4: Forcing LINT1 to edge trigger SMP: AP CPU #2 Launched! lapic2: Forcing LINT1 to edge trigger SMP: AP CPU #1 Launched! lapic6: Forcing LINT1 to edge trigger SMP: AP CPU #3 Launched! WARNING: WITNESS option enabled, expect reduced performance. Root mount waiting for: usbus1 usbus0 uhub0: 2 ports with 2 removable, self powered uhub1: 2 ports with 2 removable, self powered ugen0.2: at usbus0 ugen1.2: at usbus1 uhub2: on usbus0 uhub3: on usbus1 Root mount waiting for: usbus1 usbus0 uhub2: 6 ports with 6 removable, self powered uhub3: 6 ports with 6 removable, self powered ugen1.3: at usbus1 ukbd0: on usbus1 kbd0 at ukbd0 ums0: on usbus1 ums0: 3 buttons and [Z] coordinates ID=0 Root mount waiting for: usbus1 ugen1.4: at usbus1 ukbd1: on usbus1 kbd2 at ukbd1 ums1: on usbus1 ums1: 5 buttons and [XYZ] coordinates ID=1 Trying to mount root from nfs: NFS ROOT: 10.255.255.1:/usr/home/pxe9/ panic: mutex em0:rx(0) not owned at /usr/HEAD/src/sys/modules/em/../../dev/e1000/if_em.c:4093 cpuid = 3 KDB: enter: panic [ thread pid 0 tid 100032 ] Stopped at kdb_enter+0x3a: movl $0,kdb_why db> bt Tracing pid 0 tid 100032 td 0xc5f5bb40 kdb_enter(c0cb0e9d,c0cb0e9d,c0caf56e,c5b2ac28,3,...) at kdb_enter+0x3a panic(c0caf56e,c6002024,c11a0357,ffd,c5b2ac7c,...) at panic+0x136 _mtx_assert(c6002010,4,c11a0357,ffd,64,...) at _mtx_assert+0x87 em_rxeof(246,c5ff7d98,c5b2aca8,c088e194,c5ff7d98,...) at em_rxeof+0x3b em_handle_que(c6006000,1,c0cb5c9c,4f,c5ff7d98,...) at em_handle_que+0x38 taskqueue_run(c5ff7d80,c5ff7d98,c0ca6410,0,c0caf5f6,...) at taskqueue_run+0x103 taskqueue_thread_loop(c600a520,c5b2ad38,c0cac192,343,c0e0ce20,...) at taskqueue_thread_loop+0x68 fork_exit(c08dcde0,c600a520,c5b2ad38) at fork_exit+0xb8 fork_trampoline() at fork_trampoline+0x8 --- trap 0, eip = 0, esp = 0xc5b2ad70, ebp = 0 --- db> >Jack > > >On Fri, Apr 9, 2010 at 2:05 PM, Mike Tancsa ><mike@sentex.net> wrote: >At 04:13 PM 4/9/2010, Pyun YongHyeon wrote: >On Fri, Apr 09, 2010 at 12:09:24PM -0700, Jack Vogel wrote: > > Someone else also pointed this out. I'm dubious about its claim. > >I can't reproduce the LOR with latest em(4)(r206429). > > > >I still get it for some reason > > 1st 0xc5dc7610 em1:rx(1) (em1:rx(1)) @ > /usr/HEAD/src/sys/modules/em/../../dev/e1000/if_em.c:4087 > 2nd 0xc0f7df0c udp (udp) @ /usr/HEAD/src/sys/netinet/udp_usrreq.c:454 >KDB: stack backtrace: >db_trace_self_wrapper(c0cb4313,c5b72a64,c08e4305,c08d467b,c0cb7338,...) >at db_trace_self_wrapper+0x26 >kdb_backtrace(c08d467b,c0cb7338,c5d31a98,c5d2cb60,c5b72ac0,...) at >kdb_backtrace+0x29 >_witness_debugger(c0cb7338,c0f7df0c,c0c9c4eb,c5d2cb60,c0ccfaf7,...) >at _witness_debugger+0x25 >witness_checkorder(c0f7df0c,1,c0ccfaf7,1c6,0,...) at witness_checkorder+0x839 >_rw_rlock(c0f7df0c,c0ccfaf7,1c6,c5d33088,c5e8be24,...) at _rw_rlock+0x9c >udp_input(c6905900,14,0,c5e8bd80,c0df9ee0,...) at udp_input+0x246 >ip_input(c6905900,c5f39240,c5b72bdc,c0745956,c0df9ee0,...) at ip_input+0x606 >netisr_dispatch_src(1,0,c6905900,c5b72c14,c0954161,...) at >netisr_dispatch_src+0xcd >netisr_dispatch(1,c6905900,c6018c00,c6018c00,c6925800,...) at >netisr_dispatch+0x20 >ether_demux(c6018c00,c6905900,3,0,3,...) at ether_demux+0x1a1 >ether_input(c6018c00,c6905900,c119f2d7,ff7,63,...) at ether_input+0x365 >em_rxeof(c5e8bd80,109,c6016180,0,c5b72cc8,...) at em_rxeof+0x133 >em_msix_rx(c5dc7600,c5b72cc8,c088e0b4,c0e13740,c60342b8,...) at >em_msix_rx+0x25 >intr_event_execute_handlers(c5d807f8,c6034280,c0cac35e,533,c60342f0,...) >at intr_event_execute_handlers+0x125 >ithread_loop(c603b4a0,c5b72d38,c0cac0cd,343,c5d807f8,...) at ithread_loop+0x9f >fork_exit(c0876da0,c603b4a0,c5b72d38) at fork_exit+0xb8 > >fork_trampoline() at fork_trampoline+0x8 >--- trap 0, eip = 0, esp = 0xc5b72d70, ebp = 0 --- > >Perhaps because multi-queue is still enabled ? > > >0(i5b)% vmstat -i >interrupt total rate >irq4: uart0 6637 28 >irq21: ehci0 382 1 >irq23: ehci1 615 2 >cpu0: timer 462573 1993 >irq256: em0 7640 32 >irq257: em1 5 0 >irq258: em1 6 0 > >irq261: em1 2 0 >irq262: ahci0 69 0 >cpu3: timer 461507 1989 >cpu2: timer 460985 1987 >cpu1: timer 461545 1989 >Total 1861966 8025 >0(i5b)% > > >em0: port 0x3040-0x305f >mem 0xc1b00000-0xc1b1ffff,0xc1b25000-0xc1b25fff irq 20 at device 25.0 on pci0 >em0: Using MSI interrupt >em0: [FILTER] >em0: Ethernet address: 00:15:17:c8:4b:99 >ehci0: mem >0xc1b22000-0xc1b223ff irq 21 at device 26.0 on pci0 >ehci0: [ITHREAD] >usbus0: EHCI version 1.0 >usbus0: on ehci0 >pcib2: irq 16 at device 28.0 on pci0 >pci2: on pcib2 >pcib3: irq 16 at device 28.4 on pci0 >pci3: on pcib3 >em1: port 0x1000-0x101f >mem 0xc1900000-0xc191ffff,0xc1920000-0xc1923fff irq 16 at device 0.0 on pci3 >em1: Using MSIX interrupts with 5 vectors >em1: [ITHREAD] >em1: [ITHREAD] >em1: [ITHREAD] >em1: [ITHREAD] >em1: [ITHREAD] >em1: Ethernet address: 00:15:17:c8:4b:98 > > > >-------------------------------------------------------------------- >Mike Tancsa, tel +1 519 651 3400 >Sentex >Communications, >mike@sentex.net >Providing Internet since >1994 www.sentex.net >Cambridge, Ontario >Canada www.sentex.net/mike > -------------------------------------------------------------------- Mike Tancsa, tel +1 519 651 3400 Sentex Communications, mike@sentex.net Providing Internet since 1994 www.sentex.net Cambridge, Ontario Canada www.sentex.net/mike