From mboxrd@z Thu Jan 1 00:00:00 1970 Return-Path: Received: from ns.mahan.org (unknown [67.116.10.138]) by dpdk.org (Postfix) with ESMTP id 2BE6C5320 for ; Fri, 14 Jun 2013 07:14:26 +0200 (CEST) Received: from gypsy.mahan.org (localhost [127.0.0.1]) by ns.mahan.org (8.14.5/8.14.5) with ESMTP id r5E5EZo5027023 for ; Thu, 13 Jun 2013 22:14:35 -0700 (PDT) (envelope-from mahan@mahan.org) Message-ID: <51BAA6BB.20107@mahan.org> Date: Thu, 13 Jun 2013 22:14:35 -0700 From: Patrick Mahan User-Agent: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.6; rv:17.0) Gecko/20130509 Thunderbird/17.0.6 MIME-Version: 1.0 To: dev@dpdk.org Content-Type: text/plain; charset=ISO-8859-1 Content-Transfer-Encoding: 7bit Subject: [dpdk-dev] link_status_interrupt fails X-BeenThere: dev@dpdk.org X-Mailman-Version: 2.1.15 Precedence: list List-Id: patches and discussions about DPDK List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , X-List-Received-Date: Fri, 14 Jun 2013 05:14:28 -0000 All, I am seeing the following issue with the link_status example. This requires a reboot on my testbox. Ideas or suggestions? Thanks, Patrick >>From /var/log/messages: Jun 12 11:08:22 dpdk-eval kernel: ixgbe 0000:03:00.0: removed PHC on p2p1 Jun 12 11:08:22 dpdk-eval kernel: ixgbe 0000:03:00.0: complete Jun 12 11:08:22 dpdk-eval kernel: ixgbe 0000:03:00.0: PCI INT A disabled Jun 12 11:08:22 dpdk-eval kernel: igb_uio 0000:03:00.0: PCI INT A -> GSI 48 (level, low) -> IRQ 48 Jun 12 11:08:22 dpdk-eval kernel: BUG: sleeping function called from invalid context at drivers/pci/access.c:402 Jun 12 11:08:22 dpdk-eval kernel: in_atomic(): 0, irqs_disabled(): 1, pid: 3403, name: work_for_cpu Jun 12 11:08:22 dpdk-eval kernel: 1 lock held by work_for_cpu/3403: Jun 12 11:08:22 dpdk-eval kernel: #0: (&udev->lock){......}, at: [] igbuio_pci_irqcontrol+0x3d/0x80 [igb_uio] Jun 12 11:08:22 dpdk-eval kernel: irq event stamp: 176 Jun 12 11:08:22 dpdk-eval kernel: hardirqs last enabled at (175): [] _spin_unlock_irqrestore+0x40/0x80 Jun 12 11:08:22 dpdk-eval kernel: hardirqs last disabled at (176): [] _spin_lock_irqsave+0x32/0xa0 Jun 12 11:08:22 dpdk-eval kernel: softirqs last enabled at (0): [] copy_process+0x625/0x1660 Jun 12 11:08:22 dpdk-eval kernel: softirqs last disabled at (0): [<(null)>] (null) Jun 12 11:08:22 dpdk-eval kernel: Pid: 3403, comm: work_for_cpu Tainted: G W --------------- 2.6.32-358.6.2.el6.x86_64.debug #1 Jun 12 11:08:22 dpdk-eval kernel: Call Trace: Jun 12 11:08:22 dpdk-eval kernel: [] ? print_irqtrace_events+0xd0/0xe0 Jun 12 11:08:22 dpdk-eval kernel: [] ? __might_sleep+0xf7/0x130 Jun 12 11:08:22 dpdk-eval kernel: [] ? pci_cfg_access_lock+0x24/0x70 Jun 12 11:08:22 dpdk-eval kernel: [] ? pci_block_user_cfg_access+0xe/0x10 Jun 12 11:08:22 dpdk-eval kernel: [] ? igbuio_pci_irqcontrol+0x48/0x80 [igb_uio] Jun 12 11:08:22 dpdk-eval kernel: [] ? igbuio_pci_probe+0x217/0x29e [igb_uio] Jun 12 11:08:22 dpdk-eval kernel: [] ? do_work_for_cpu+0x0/0x30 Jun 12 11:08:22 dpdk-eval kernel: [] ? local_pci_probe+0x17/0x20 Jun 12 11:08:22 dpdk-eval kernel: [] ? do_work_for_cpu+0x18/0x30 Jun 12 11:08:22 dpdk-eval kernel: [] ? kthread+0x96/0xa0 Jun 12 11:08:22 dpdk-eval kernel: [] ? child_rip+0xa/0x20 Jun 12 11:08:22 dpdk-eval kernel: [] ? restore_args+0x0/0x30 Jun 12 11:08:22 dpdk-eval kernel: [] ? kthread+0x0/0xa0 Jun 12 11:08:22 dpdk-eval kernel: [] ? child_rip+0x0/0x20 Jun 12 11:08:22 dpdk-eval kernel: uio device registered with irq 4f Jun 12 11:08:22 dpdk-eval rte[3386]: EAL: Mapping resources for '/dev/uio0' starting at 0x00000000 for 524288 bytes Jun 12 11:08:22 dpdk-eval kernel: ixgbe 0000:03:00.1: removed PHC on p2p2 Jun 12 11:08:23 dpdk-eval kernel: ixgbe 0000:03:00.1: complete Jun 12 11:08:23 dpdk-eval kernel: ixgbe 0000:03:00.1: PCI INT B disabled Jun 12 11:08:23 dpdk-eval kernel: igb_uio 0000:03:00.1: PCI INT B -> GSI 52 (level, low) -> IRQ 52 Jun 12 11:08:23 dpdk-eval kernel: uio device registered with irq 50 Jun 12 11:08:23 dpdk-eval rte[3386]: EAL: Mapping resources for '/dev/uio1' starting at 0x00000000 for 524288 bytes Jun 12 11:08:23 dpdk-eval kernel: igb 0000:06:00.0: removed PHC on em1 Jun 12 11:08:23 dpdk-eval dhclient[2227]: receive_packet failed on em1: Network is down Jun 12 11:08:24 dpdk-eval kernel: igb 0000:06:00.0: PCI INT A disabled Jun 12 11:08:24 dpdk-eval kernel: igb_uio 0000:06:00.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 Jun 12 11:08:24 dpdk-eval kernel: BUG: sleeping function called from invalid context at drivers/pci/access.c:402 Jun 12 11:08:24 dpdk-eval kernel: in_atomic(): 0, irqs_disabled(): 1, pid: 3509, name: work_for_cpu Jun 12 11:08:24 dpdk-eval kernel: 1 lock held by work_for_cpu/3509: Jun 12 11:08:24 dpdk-eval kernel: #0: (&udev->lock){+.+...}, at: [] igbuio_pci_irqcontrol+0x3d/0x80 [igb_uio] Jun 12 11:08:24 dpdk-eval kernel: irq event stamp: 234 Jun 12 11:08:24 dpdk-eval kernel: hardirqs last enabled at (233): [] _spin_unlock_irqrestore+0x40/0x80 Jun 12 11:08:24 dpdk-eval kernel: hardirqs last disabled at (234): [] _spin_lock_irqsave+0x32/0xa0 Jun 12 11:08:24 dpdk-eval kernel: softirqs last enabled at (64): [] __do_softirq+0x151/0x210 Jun 12 11:08:24 dpdk-eval kernel: softirqs last disabled at (49): [] call_softirq+0x1c/0x30 Jun 12 11:08:24 dpdk-eval kernel: Pid: 3509, comm: work_for_cpu Tainted: G W --------------- 2.6.32-358.6.2.el6.x86_64.debug #1 Jun 12 11:08:24 dpdk-eval kernel: Call Trace: Jun 12 11:08:24 dpdk-eval kernel: [] ? print_irqtrace_events+0xd0/0xe0 Jun 12 11:08:24 dpdk-eval kernel: [] ? __might_sleep+0xf7/0x130 Jun 12 11:08:24 dpdk-eval kernel: [] ? pci_cfg_access_lock+0x24/0x70 Jun 12 11:08:24 dpdk-eval kernel: [] ? pci_block_user_cfg_access+0xe/0x10 Jun 12 11:08:24 dpdk-eval kernel: [] ? igbuio_pci_irqcontrol+0x48/0x80 [igb_uio] Jun 12 11:08:24 dpdk-eval kernel: [] ? igbuio_pci_probe+0x217/0x29e [igb_uio] Jun 12 11:08:24 dpdk-eval kernel: [] ? do_work_for_cpu+0x0/0x30 Jun 12 11:08:24 dpdk-eval kernel: [] ? local_pci_probe+0x17/0x20 Jun 12 11:08:24 dpdk-eval kernel: [] ? do_work_for_cpu+0x18/0x30 Jun 12 11:08:24 dpdk-eval kernel: [] ? kthread+0x96/0xa0 Jun 12 11:08:24 dpdk-eval kernel: [] ? child_rip+0xa/0x20 Jun 12 11:08:24 dpdk-eval kernel: [] ? restore_args+0x0/0x30 Jun 12 11:08:24 dpdk-eval kernel: [] ? kthread+0x0/0xa0 Jun 12 11:08:24 dpdk-eval kernel: [] ? child_rip+0x0/0x20 Jun 12 11:08:24 dpdk-eval kernel: uio device registered with irq 51 Jun 12 11:08:24 dpdk-eval rte[3386]: EAL: Mapping resources for '/dev/uio2' starting at 0x00000000 for 1048576 bytes Jun 12 11:08:24 dpdk-eval kernel: igb 0000:06:00.1: removed PHC on em2 Jun 12 11:08:24 dpdk-eval kernel: igb 0000:06:00.1: PCI INT B disabled Jun 12 11:08:24 dpdk-eval kernel: igb_uio 0000:06:00.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17 Jun 12 11:08:24 dpdk-eval kernel: uio device registered with irq 52 Jun 12 11:08:24 dpdk-eval rte[3386]: EAL: Mapping resources for '/dev/uio3' starting at 0x00000000 for 1048576 bytes Jun 12 11:08:24 dpdk-eval kernel: Jun 12 11:08:24 dpdk-eval kernel: ================================= Jun 12 11:08:24 dpdk-eval kernel: [ INFO: inconsistent lock state ] Jun 12 11:08:24 dpdk-eval kernel: 2.6.32-358.6.2.el6.x86_64.debug #1 Jun 12 11:08:24 dpdk-eval kernel: --------------------------------- Jun 12 11:08:24 dpdk-eval kernel: inconsistent {HARDIRQ-ON-W} -> {IN-HARDIRQ-W} usage. Jun 12 11:08:24 dpdk-eval kernel: swapper/0 [HC1[1]:SC0[0]:HE0:SE1] takes: Jun 12 11:08:24 dpdk-eval kernel: (&udev->lock){?.+...}, at: [] igbuio_pci_irqhandler+0x3d/0xe8 [igb_uio] Jun 12 11:08:24 dpdk-eval kernel: {HARDIRQ-ON-W} state was registered at: Jun 12 11:08:24 dpdk-eval kernel: [] mark_held_locks+0x73/0xa0 Jun 12 11:08:24 dpdk-eval kernel: [] trace_hardirqs_on_caller+0x67/0x190 Jun 12 11:08:24 dpdk-eval kernel: [] trace_hardirqs_on+0xd/0x10 Jun 12 11:08:24 dpdk-eval kernel: [] _spin_unlock_irq+0x30/0x40 Jun 12 11:08:24 dpdk-eval kernel: [] pci_cfg_access_lock+0x64/0x70 Jun 12 11:08:24 dpdk-eval kernel: [] pci_block_user_cfg_access+0xe/0x10 Jun 12 11:08:24 dpdk-eval kernel: [] igbuio_pci_irqcontrol+0x48/0x80 [igb_uio] Jun 12 11:08:24 dpdk-eval kernel: [] igbuio_pci_probe+0x217/0x29e [igb_uio] Jun 12 11:08:24 dpdk-eval kernel: [] local_pci_probe+0x17/0x20 Jun 12 11:08:24 dpdk-eval kernel: [] do_work_for_cpu+0x18/0x30 Jun 12 11:08:24 dpdk-eval kernel: [] kthread+0x96/0xa0 Jun 12 11:08:24 dpdk-eval kernel: [] child_rip+0xa/0x20 Jun 12 11:08:24 dpdk-eval kernel: irq event stamp: 285500 Jun 12 11:08:24 dpdk-eval kernel: hardirqs last enabled at (285497): [] intel_idle+0xe1/0x170 Jun 12 11:08:24 dpdk-eval kernel: hardirqs last disabled at (285498): [] save_args+0x6a/0x70 Jun 12 11:08:24 dpdk-eval kernel: softirqs last enabled at (285500): [] _local_bh_enable+0x13/0x20 Jun 12 11:08:24 dpdk-eval kernel: softirqs last disabled at (285499): [] irq_enter+0x75/0x90 Jun 12 11:08:24 dpdk-eval kernel: Jun 12 11:08:24 dpdk-eval kernel: other info that might help us debug this: Jun 12 11:08:24 dpdk-eval kernel: no locks held by swapper/0. Jun 12 11:08:24 dpdk-eval kernel: Jun 12 11:08:24 dpdk-eval kernel: stack backtrace: Jun 12 11:08:24 dpdk-eval kernel: Pid: 0, comm: swapper Tainted: G W --------------- 2.6.32-358.6.2.el6.x86_64.debug #1 Jun 12 11:08:24 dpdk-eval kernel: Call Trace: Jun 12 11:08:24 dpdk-eval kernel: [] ? print_usage_bug+0x177/0x180 Jun 12 11:08:24 dpdk-eval kernel: [] ? mark_lock+0x35d/0x430 Jun 12 11:08:24 dpdk-eval kernel: [] ? __lock_acquire+0x77a/0x1570 Jun 12 11:08:24 dpdk-eval kernel: [] ? native_sched_clock+0x13/0x80 Jun 12 11:08:24 dpdk-eval kernel: [] ? sched_clock+0x9/0x10 Jun 12 11:08:24 dpdk-eval kernel: [] ? native_sched_clock+0x13/0x80 Jun 12 11:08:24 dpdk-eval kernel: [] ? sched_clock+0x9/0x10 Jun 12 11:08:24 dpdk-eval kernel: [] ? sched_clock_cpu+0xcd/0x110 Jun 12 11:08:24 dpdk-eval kernel: [] ? lock_acquire+0xa4/0x120 Jun 12 11:08:24 dpdk-eval kernel: [] ? igbuio_pci_irqhandler+0x3d/0xe8 [igb_uio] Jun 12 11:08:24 dpdk-eval kernel: [] ? _spin_lock_irqsave+0x55/0xa0 Jun 12 11:08:24 dpdk-eval kernel: [] ? igbuio_pci_irqhandler+0x3d/0xe8 [igb_uio] Jun 12 11:08:24 dpdk-eval kernel: [] ? igbuio_pci_irqhandler+0x3d/0xe8 [igb_uio] Jun 12 11:08:24 dpdk-eval kernel: [] ? sched_clock_cpu+0xcd/0x110 Jun 12 11:08:24 dpdk-eval kernel: [] ? uio_interrupt+0x1e/0x48 [uio] Jun 12 11:08:24 dpdk-eval kernel: [] ? handle_IRQ_event+0x50/0x160 Jun 12 11:08:24 dpdk-eval kernel: [] ? handle_edge_irq+0xe0/0x170 Jun 12 11:08:24 dpdk-eval kernel: [] ? handle_irq+0x49/0xa0 Jun 12 11:08:24 dpdk-eval kernel: [] ? do_IRQ+0x6c/0xf0 Jun 12 11:08:24 dpdk-eval kernel: [] ? ret_from_intr+0x0/0x16 Jun 12 11:08:24 dpdk-eval kernel: [] ? intel_idle+0xe8/0x170 Jun 12 11:08:24 dpdk-eval kernel: [] ? intel_idle+0xe1/0x170 Jun 12 11:08:24 dpdk-eval kernel: [] ? cpuidle_idle_call+0xa7/0x150 Jun 12 11:08:24 dpdk-eval kernel: [] ? cpu_idle+0xbb/0x110 Jun 12 11:08:24 dpdk-eval kernel: [] ? start_secondary+0x2bb/0x2fe Jun 12 11:08:24 dpdk-eval kernel: irq 0x4f handled Jun 12 11:08:25 dpdk-eval kernel: BUG: sleeping function called from invalid context at drivers/pci/access.c:402 Jun 12 11:08:25 dpdk-eval kernel: in_atomic(): 0, irqs_disabled(): 1, pid: 3386, name: link_status_int Jun 12 11:08:25 dpdk-eval kernel: INFO: lockdep is turned off. Jun 12 11:08:25 dpdk-eval kernel: irq event stamp: 240175 Jun 12 11:08:25 dpdk-eval kernel: hardirqs last enabled at (240175): [] sysret_check+0x2e/0x69 Jun 12 11:08:25 dpdk-eval kernel: hardirqs last disabled at (240174): [] sysret_check+0x22/0x69 Jun 12 11:08:25 dpdk-eval kernel: softirqs last enabled at (240164): [] __do_softirq+0x151/0x210 Jun 12 11:08:25 dpdk-eval kernel: softirqs last disabled at (240151): [] call_softirq+0x1c/0x30 Jun 12 11:08:25 dpdk-eval kernel: Pid: 3386, comm: link_status_int Tainted: G W --------------- 2.6.32-358.6.2.el6.x86_64.debug #1 Jun 12 11:08:25 dpdk-eval kernel: Call Trace: Jun 12 11:08:25 dpdk-eval kernel: [] ? print_irqtrace_events+0xd0/0xe0 Jun 12 11:08:25 dpdk-eval kernel: [] ? __might_sleep+0xf7/0x130 Jun 12 11:08:25 dpdk-eval kernel: [] ? pci_cfg_access_lock+0x24/0x70 Jun 12 11:08:25 dpdk-eval kernel: [] ? pci_block_user_cfg_access+0xe/0x10 Jun 12 11:08:25 dpdk-eval kernel: [] ? igbuio_pci_irqcontrol+0x48/0x80 [igb_uio] Jun 12 11:08:25 dpdk-eval kernel: [] ? uio_write+0x90/0xa0 [uio] Jun 12 11:08:25 dpdk-eval kernel: [] ? vfs_write+0xb8/0x1a0 Jun 12 11:08:25 dpdk-eval kernel: [] ? fget_light+0x66/0x100 Jun 12 11:08:25 dpdk-eval kernel: [] ? lockdep_sys_exit_thunk+0x35/0x67 Jun 12 11:08:25 dpdk-eval kernel: [] ? sys_write+0x51/0x90 Jun 12 11:08:25 dpdk-eval kernel: [] ? system_call_fastpath+0x16/0x1b Jun 12 11:08:25 dpdk-eval kernel: irq 0x50 handled Jun 12 11:08:25 dpdk-eval rte[3386]: LSI: lcore 1 has nothing to do Jun 12 11:08:25 dpdk-eval rte[3386]: LSI: lcore 2 has nothing to do Jun 12 11:08:25 dpdk-eval rte[3386]: LSI: entering main loop on lcore 0 Jun 12 11:08:25 dpdk-eval rte[3386]: LSI: -- lcoreid=0 portid=0 Jun 12 11:08:25 dpdk-eval rte[3386]: LSI: -- lcoreid=0 portid=1 Jun 12 11:08:25 dpdk-eval rte[3386]: LSI: lcore 3 has nothing to do Jun 12 11:08:26 dpdk-eval kernel: BUG: sleeping function called from invalid context at drivers/pci/access.c:402 Jun 12 11:08:26 dpdk-eval kernel: in_atomic(): 0, irqs_disabled(): 1, pid: 3387, name: link_status_int Jun 12 11:08:26 dpdk-eval kernel: INFO: lockdep is turned off. Jun 12 11:08:26 dpdk-eval kernel: irq event stamp: 806 Jun 12 11:08:26 dpdk-eval kernel: hardirqs last enabled at (805): [] _spin_unlock_irqrestore+0x40/0x80 Jun 12 11:08:26 dpdk-eval kernel: hardirqs last disabled at (806): [] _spin_lock_irq+0x1f/0x80 Jun 12 11:08:26 dpdk-eval kernel: softirqs last enabled at (0): [] copy_process+0x625/0x1660 Jun 12 11:08:26 dpdk-eval kernel: softirqs last disabled at (0): [<(null)>] (null) Jun 12 11:08:26 dpdk-eval kernel: Pid: 3387, comm: link_status_int Tainted: G W --------------- 2.6.32-358.6.2.el6.x86_64.debug #1 Jun 12 11:08:26 dpdk-eval kernel: Call Trace: Jun 12 11:08:26 dpdk-eval kernel: [] ? print_irqtrace_events+0xd0/0xe0 Jun 12 11:08:26 dpdk-eval kernel: [] ? __might_sleep+0xf7/0x130 Jun 12 11:08:26 dpdk-eval kernel: [] ? pci_cfg_access_lock+0x24/0x70 Jun 12 11:08:26 dpdk-eval kernel: [] ? pci_block_user_cfg_access+0xe/0x10 Jun 12 11:08:26 dpdk-eval kernel: [] ? igbuio_pci_irqcontrol+0x48/0x80 [igb_uio] Jun 12 11:08:26 dpdk-eval kernel: [] ? uio_write+0x90/0xa0 [uio] Jun 12 11:08:26 dpdk-eval kernel: [] ? vfs_write+0xb8/0x1a0 Jun 12 11:08:26 dpdk-eval kernel: [] ? fget_light+0x66/0x100 Jun 12 11:08:26 dpdk-eval kernel: [] ? sys_write+0x51/0x90 Jun 12 11:08:26 dpdk-eval kernel: [] ? system_call_fastpath+0x16/0x1b Jun 12 11:08:40 dpdk-eval kernel: BUG: sleeping function called from invalid context at drivers/pci/access.c:402 Jun 12 11:08:40 dpdk-eval kernel: in_atomic(): 1, irqs_disabled(): 1, pid: 0, name: swapper Jun 12 11:08:40 dpdk-eval kernel: INFO: lockdep is turned off. Jun 12 11:08:40 dpdk-eval kernel: irq event stamp: 285500 Jun 12 11:08:40 dpdk-eval kernel: hardirqs last enabled at (285497): [] intel_idle+0xe1/0x170 Jun 12 11:08:40 dpdk-eval kernel: hardirqs last disabled at (285498): [] save_args+0x6a/0x70 Jun 12 11:08:40 dpdk-eval kernel: softirqs last enabled at (285500): [] _local_bh_enable+0x13/0x20 Jun 12 11:08:40 dpdk-eval kernel: softirqs last disabled at (285499): [] irq_enter+0x75/0x90 Jun 12 11:08:40 dpdk-eval kernel: Pid: 0, comm: swapper Tainted: G W --------------- 2.6.32-358.6.2.el6.x86_64.debug #1 Jun 12 11:08:40 dpdk-eval kernel: Call Trace: Jun 12 11:08:40 dpdk-eval kernel: [] ? print_irqtrace_events+0xd0/0xe0 Jun 12 11:08:40 dpdk-eval kernel: [] ? __might_sleep+0xf7/0x130 Jun 12 11:08:40 dpdk-eval kernel: [] ? pci_cfg_access_lock+0x24/0x70 Jun 12 11:08:40 dpdk-eval kernel: [] ? trace_hardirqs_on+0xd/0x10 Jun 12 11:08:40 dpdk-eval kernel: [] ? pci_block_user_cfg_access+0xe/0x10 Jun 12 11:08:40 dpdk-eval kernel: [] ? igbuio_pci_irqhandler+0x48/0xe8 [igb_uio] Jun 12 11:08:40 dpdk-eval kernel: [] ? uio_interrupt+0x1e/0x48 [uio] Jun 12 11:08:40 dpdk-eval kernel: [] ? handle_edge_irq+0x22/0x170 Jun 12 11:08:40 dpdk-eval kernel: [] ? handle_IRQ_event+0x50/0x160 Jun 12 11:08:40 dpdk-eval kernel: [] ? handle_edge_irq+0xe0/0x170 Jun 12 11:08:40 dpdk-eval kernel: [] ? handle_irq+0x49/0xa0 Jun 12 11:08:40 dpdk-eval kernel: [] ? do_IRQ+0x6c/0xf0 Jun 12 11:08:40 dpdk-eval kernel: [] ? ret_from_intr+0x0/0x16 Jun 12 11:08:40 dpdk-eval kernel: [] ? intel_idle+0xe1/0x170 Jun 12 11:08:40 dpdk-eval kernel: [] ? intel_idle+0xe8/0x170 Jun 12 11:08:40 dpdk-eval kernel: [] ? intel_idle+0xe1/0x170 Jun 12 11:08:40 dpdk-eval kernel: [] ? cpuidle_idle_call+0xa7/0x150 Jun 12 11:08:40 dpdk-eval kernel: [] ? cpu_idle+0xbb/0x110 Jun 12 11:08:40 dpdk-eval kernel: [] ? start_secondary+0x2bb/0x2fe Jun 12 11:08:40 dpdk-eval kernel: irq 0x4f handled Jun 12 11:08:44 dpdk-eval kernel: BUG: sleeping function called from invalid context at drivers/pci/access.c:402 Jun 12 11:08:44 dpdk-eval kernel: in_atomic(): 0, irqs_disabled(): 1, pid: 3387, name: link_status_int Jun 12 11:08:44 dpdk-eval kernel: INFO: lockdep is turned off. Jun 12 11:08:44 dpdk-eval kernel: irq event stamp: 806 Jun 12 11:08:44 dpdk-eval kernel: hardirqs last enabled at (805): [] _spin_unlock_irqrestore+0x40/0x80 Jun 12 11:08:44 dpdk-eval kernel: hardirqs last disabled at (806): [] _spin_lock_irq+0x1f/0x80 Jun 12 11:08:44 dpdk-eval kernel: softirqs last enabled at (0): [] copy_process+0x625/0x1660 Jun 12 11:08:44 dpdk-eval kernel: softirqs last disabled at (0): [<(null)>] (null) Jun 12 11:08:44 dpdk-eval kernel: Pid: 3387, comm: link_status_int Tainted: G W --------------- 2.6.32-358.6.2.el6.x86_64.debug #1 Jun 12 11:08:44 dpdk-eval kernel: Call Trace: Jun 12 11:08:44 dpdk-eval kernel: [] ? print_irqtrace_events+0xd0/0xe0 Jun 12 11:08:44 dpdk-eval kernel: [] ? __might_sleep+0xf7/0x130 Jun 12 11:08:44 dpdk-eval kernel: [] ? pci_cfg_access_lock+0x24/0x70 Jun 12 11:08:44 dpdk-eval kernel: [] ? pci_block_user_cfg_access+0xe/0x10 Jun 12 11:08:44 dpdk-eval kernel: [] ? igbuio_pci_irqcontrol+0x48/0x80 [igb_uio] Jun 12 11:08:44 dpdk-eval kernel: [] ? uio_write+0x90/0xa0 [uio] Jun 12 11:08:44 dpdk-eval kernel: [] ? vfs_write+0xb8/0x1a0 Jun 12 11:08:44 dpdk-eval kernel: [] ? fget_light+0x66/0x100 Jun 12 11:08:44 dpdk-eval kernel: [] ? sys_write+0x51/0x90 Jun 12 11:08:44 dpdk-eval kernel: [] ? system_call_fastpath+0x16/0x1b Jun 12 11:08:46 dpdk-eval kernel: BUG: sleeping function called from invalid context at drivers/pci/access.c:402 Jun 12 11:08:46 dpdk-eval kernel: in_atomic(): 1, irqs_disabled(): 1, pid: 0, name: swapper Jun 12 11:08:46 dpdk-eval kernel: INFO: lockdep is turned off. Jun 12 11:08:46 dpdk-eval kernel: irq event stamp: 285500 Jun 12 11:08:46 dpdk-eval kernel: hardirqs last enabled at (285497): [] intel_idle+0xe1/0x170 Jun 12 11:08:46 dpdk-eval kernel: hardirqs last disabled at (285498): [] save_args+0x6a/0x70 Jun 12 11:08:46 dpdk-eval kernel: softirqs last enabled at (285500): [] _local_bh_enable+0x13/0x20 Jun 12 11:08:46 dpdk-eval kernel: softirqs last disabled at (285499): [] irq_enter+0x75/0x90 Jun 12 11:08:46 dpdk-eval kernel: Pid: 0, comm: swapper Tainted: G W --------------- 2.6.32-358.6.2.el6.x86_64.debug #1 Jun 12 11:08:46 dpdk-eval kernel: Call Trace: Jun 12 11:08:46 dpdk-eval kernel: [] ? print_irqtrace_events+0xd0/0xe0 Jun 12 11:08:46 dpdk-eval kernel: [] ? __might_sleep+0xf7/0x130 Jun 12 11:08:46 dpdk-eval kernel: [] ? pci_cfg_access_lock+0x24/0x70 Jun 12 11:08:46 dpdk-eval kernel: [] ? pci_block_user_cfg_access+0xe/0x10 Jun 12 11:08:46 dpdk-eval kernel: [] ? igbuio_pci_irqhandler+0x48/0xe8 [igb_uio] Jun 12 11:08:46 dpdk-eval kernel: [] ? uio_interrupt+0x1e/0x48 [uio] Jun 12 11:08:46 dpdk-eval kernel: [] ? handle_edge_irq+0x22/0x170 Jun 12 11:08:46 dpdk-eval kernel: [] ? handle_IRQ_event+0x50/0x160 Jun 12 11:08:46 dpdk-eval kernel: [] ? handle_edge_irq+0xe0/0x170 Jun 12 11:08:46 dpdk-eval kernel: [] ? handle_irq+0x49/0xa0 Jun 12 11:08:46 dpdk-eval kernel: [] ? do_IRQ+0x6c/0xf0 Jun 12 11:08:46 dpdk-eval kernel: [] ? ret_from_intr+0x0/0x16 Jun 12 11:08:46 dpdk-eval kernel: [] ? intel_idle+0xe1/0x170 Jun 12 11:08:46 dpdk-eval kernel: [] ? intel_idle+0xe8/0x170 Jun 12 11:08:46 dpdk-eval kernel: [] ? intel_idle+0xe1/0x170 Jun 12 11:08:46 dpdk-eval kernel: [] ? cpuidle_idle_call+0xa7/0x150 Jun 12 11:08:46 dpdk-eval kernel: [] ? cpu_idle+0xbb/0x110 Jun 12 11:08:46 dpdk-eval kernel: [] ? start_secondary+0x2bb/0x2fe Jun 12 11:08:46 dpdk-eval kernel: irq 0x4f handled Jun 12 11:08:47 dpdk-eval kernel: BUG: sleeping function called from invalid context at drivers/pci/access.c:402 Jun 12 11:08:47 dpdk-eval kernel: in_atomic(): 0, irqs_disabled(): 1, pid: 3387, name: link_status_int Jun 12 11:08:47 dpdk-eval kernel: INFO: lockdep is turned off. Jun 12 11:08:47 dpdk-eval kernel: irq event stamp: 806 Jun 12 11:08:47 dpdk-eval kernel: hardirqs last enabled at (805): [] _spin_unlock_irqrestore+0x40/0x80 Jun 12 11:08:47 dpdk-eval kernel: hardirqs last disabled at (806): [] _spin_lock_irq+0x1f/0x80 Jun 12 11:08:47 dpdk-eval kernel: softirqs last enabled at (0): [] copy_process+0x625/0x1660 Jun 12 11:08:47 dpdk-eval kernel: softirqs last disabled at (0): [<(null)>] (null) Jun 12 11:08:47 dpdk-eval kernel: Pid: 3387, comm: link_status_int Tainted: G W --------------- 2.6.32-358.6.2.el6.x86_64.debug #1 Jun 12 11:08:47 dpdk-eval kernel: Call Trace: Jun 12 11:08:47 dpdk-eval kernel: [] ? print_irqtrace_events+0xd0/0xe0 Jun 12 11:08:47 dpdk-eval kernel: [] ? __might_sleep+0xf7/0x130 Jun 12 11:08:47 dpdk-eval kernel: [] ? pci_cfg_access_lock+0x24/0x70 Jun 12 11:08:47 dpdk-eval kernel: [] ? pci_block_user_cfg_access+0xe/0x10 Jun 12 11:08:47 dpdk-eval kernel: [] ? igbuio_pci_irqcontrol+0x48/0x80 [igb_uio] Jun 12 11:08:47 dpdk-eval kernel: [] ? uio_write+0x90/0xa0 [uio] Jun 12 11:08:47 dpdk-eval kernel: [] ? vfs_write+0xb8/0x1a0 Jun 12 11:08:47 dpdk-eval kernel: [] ? fget_light+0x66/0x100 Jun 12 11:08:47 dpdk-eval kernel: [] ? sys_write+0x51/0x90 Jun 12 11:08:47 dpdk-eval kernel: [] ? system_call_fastpath+0x16/0x1b Jun 12 11:08:52 dpdk-eval kernel: BUG: sleeping function called from invalid context at drivers/pci/access.c:402 Jun 12 11:08:52 dpdk-eval kernel: in_atomic(): 1, irqs_disabled(): 1, pid: 0, name: swapper Jun 12 11:08:52 dpdk-eval kernel: INFO: lockdep is turned off. Jun 12 11:08:52 dpdk-eval kernel: irq event stamp: 285500 Jun 12 11:08:52 dpdk-eval kernel: hardirqs last enabled at (285497): [] intel_idle+0xe1/0x170 Jun 12 11:08:52 dpdk-eval kernel: hardirqs last disabled at (285498): [] save_args+0x6a/0x70 Jun 12 11:08:52 dpdk-eval kernel: softirqs last enabled at (285500): [] _local_bh_enable+0x13/0x20 Jun 12 11:08:52 dpdk-eval kernel: softirqs last disabled at (285499): [] irq_enter+0x75/0x90 Jun 12 11:08:52 dpdk-eval kernel: Pid: 0, comm: swapper Tainted: G W --------------- 2.6.32-358.6.2.el6.x86_64.debug #1 Jun 12 11:08:52 dpdk-eval kernel: Call Trace: Jun 12 11:08:52 dpdk-eval kernel: [] ? print_irqtrace_events+0xd0/0xe0 Jun 12 11:08:52 dpdk-eval kernel: [] ? __might_sleep+0xf7/0x130 Jun 12 11:08:52 dpdk-eval kernel: [] ? pci_cfg_access_lock+0x24/0x70 Jun 12 11:08:52 dpdk-eval kernel: [] ? pci_block_user_cfg_access+0xe/0x10 Jun 12 11:08:52 dpdk-eval kernel: [] ? igbuio_pci_irqhandler+0x48/0xe8 [igb_uio] Jun 12 11:08:52 dpdk-eval kernel: [] ? uio_interrupt+0x1e/0x48 [uio] Jun 12 11:08:52 dpdk-eval kernel: [] ? handle_edge_irq+0x22/0x170 Jun 12 11:08:52 dpdk-eval kernel: [] ? handle_IRQ_event+0x50/0x160 Jun 12 11:08:52 dpdk-eval kernel: [] ? handle_edge_irq+0xe0/0x170 Jun 12 11:08:52 dpdk-eval kernel: [] ? handle_irq+0x49/0xa0 Jun 12 11:08:52 dpdk-eval kernel: [] ? do_IRQ+0x6c/0xf0 Jun 12 11:08:52 dpdk-eval kernel: [] ? ret_from_intr+0x0/0x16 Jun 12 11:08:52 dpdk-eval kernel: [] ? intel_idle+0xe1/0x170 Jun 12 11:08:52 dpdk-eval kernel: [] ? intel_idle+0xe8/0x170 Jun 12 11:08:52 dpdk-eval kernel: [] ? intel_idle+0xe1/0x170 Jun 12 11:08:52 dpdk-eval kernel: [] ? cpuidle_idle_call+0xa7/0x150 Jun 12 11:08:52 dpdk-eval kernel: [] ? cpu_idle+0xbb/0x110 Jun 12 11:08:52 dpdk-eval kernel: [] ? start_secondary+0x2bb/0x2fe Jun 12 11:08:52 dpdk-eval kernel: irq 0x4f handled Jun 12 11:08:53 dpdk-eval kernel: BUG: sleeping function called from invalid context at drivers/pci/access.c:402 Jun 12 11:08:53 dpdk-eval kernel: in_atomic(): 0, irqs_disabled(): 1, pid: 3387, name: link_status_int Jun 12 11:08:53 dpdk-eval kernel: INFO: lockdep is turned off. Jun 12 11:08:53 dpdk-eval kernel: irq event stamp: 806 Jun 12 11:08:53 dpdk-eval kernel: hardirqs last enabled at (805): [] _spin_unlock_irqrestore+0x40/0x80 Jun 12 11:08:53 dpdk-eval kernel: hardirqs last disabled at (806): [] _spin_lock_irq+0x1f/0x80 Jun 12 11:08:53 dpdk-eval kernel: softirqs last enabled at (0): [] copy_process+0x625/0x1660 Jun 12 11:08:53 dpdk-eval kernel: softirqs last disabled at (0): [<(null)>] (null) Jun 12 11:08:53 dpdk-eval kernel: Pid: 3387, comm: link_status_int Tainted: G W --------------- 2.6.32-358.6.2.el6.x86_64.debug #1 Jun 12 11:08:53 dpdk-eval kernel: Call Trace: Jun 12 11:08:53 dpdk-eval kernel: [] ? print_irqtrace_events+0xd0/0xe0 Jun 12 11:08:53 dpdk-eval kernel: [] ? __might_sleep+0xf7/0x130 Jun 12 11:08:53 dpdk-eval kernel: [] ? pci_cfg_access_lock+0x24/0x70 Jun 12 11:08:53 dpdk-eval kernel: [] ? pci_block_user_cfg_access+0xe/0x10 Jun 12 11:08:53 dpdk-eval kernel: [] ? igbuio_pci_irqcontrol+0x48/0x80 [igb_uio] Jun 12 11:08:53 dpdk-eval kernel: [] ? uio_write+0x90/0xa0 [uio] Jun 12 11:08:53 dpdk-eval kernel: [] ? vfs_write+0xb8/0x1a0 Jun 12 11:08:53 dpdk-eval kernel: [] ? fget_light+0x66/0x100 Jun 12 11:08:53 dpdk-eval kernel: [] ? sys_write+0x51/0x90 Jun 12 11:08:53 dpdk-eval kernel: [] ? system_call_fastpath+0x16/0x1b Jun 12 11:09:12 dpdk-eval kernel: link_status_int used greatest stack depth: 2968 bytes left