From: Patrick Mahan <mahan-5dHXHCkEAVbYtjvyW6yDsg@public.gmane.org>
To: dev-VfR2kkLFssw@public.gmane.org
Subject: link_status_interrupt fails
Date: Thu, 13 Jun 2013 22:14:35 -0700 [thread overview]
Message-ID: <51BAA6BB.20107@mahan.org> (raw)
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:
[<ffffffffa00331cd>] 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):
[<ffffffff81542270>] _spin_unlock_irqrestore+0x40/0x80
Jun 12 11:08:22 dpdk-eval kernel: hardirqs last disabled at (176):
[<ffffffff81542602>] _spin_lock_irqsave+0x32/0xa0
Jun 12 11:08:22 dpdk-eval kernel: softirqs last enabled at (0):
[<ffffffff8106f785>] 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: [<ffffffff810b0fd0>] ?
print_irqtrace_events+0xd0/0xe0
Jun 12 11:08:22 dpdk-eval kernel: [<ffffffff8105af17>] ? __might_sleep+0xf7/0x130
Jun 12 11:08:22 dpdk-eval kernel: [<ffffffff812b84c4>] ?
pci_cfg_access_lock+0x24/0x70
Jun 12 11:08:22 dpdk-eval kernel: [<ffffffff812b851e>] ?
pci_block_user_cfg_access+0xe/0x10
Jun 12 11:08:22 dpdk-eval kernel: [<ffffffffa00331d8>] ?
igbuio_pci_irqcontrol+0x48/0x80 [igb_uio]
Jun 12 11:08:22 dpdk-eval kernel: [<ffffffffa0033526>] ?
igbuio_pci_probe+0x217/0x29e [igb_uio]
Jun 12 11:08:22 dpdk-eval kernel: [<ffffffff81095a30>] ? do_work_for_cpu+0x0/0x30
Jun 12 11:08:22 dpdk-eval kernel: [<ffffffff812c07d7>] ? local_pci_probe+0x17/0x20
Jun 12 11:08:22 dpdk-eval kernel: [<ffffffff81095a48>] ? do_work_for_cpu+0x18/0x30
Jun 12 11:08:22 dpdk-eval kernel: [<ffffffff8109b826>] ? kthread+0x96/0xa0
Jun 12 11:08:22 dpdk-eval kernel: [<ffffffff8100c1ca>] ? child_rip+0xa/0x20
Jun 12 11:08:22 dpdk-eval kernel: [<ffffffff8100bb10>] ? restore_args+0x0/0x30
Jun 12 11:08:22 dpdk-eval kernel: [<ffffffff8109b790>] ? kthread+0x0/0xa0
Jun 12 11:08:22 dpdk-eval kernel: [<ffffffff8100c1c0>] ? 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:
[<ffffffffa00331cd>] 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):
[<ffffffff81542270>] _spin_unlock_irqrestore+0x40/0x80
Jun 12 11:08:24 dpdk-eval kernel: hardirqs last disabled at (234):
[<ffffffff81542602>] _spin_lock_irqsave+0x32/0xa0
Jun 12 11:08:24 dpdk-eval kernel: softirqs last enabled at (64):
[<ffffffff8107a6a1>] __do_softirq+0x151/0x210
Jun 12 11:08:24 dpdk-eval kernel: softirqs last disabled at (49):
[<ffffffff8100c2cc>] 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: [<ffffffff810b0fd0>] ?
print_irqtrace_events+0xd0/0xe0
Jun 12 11:08:24 dpdk-eval kernel: [<ffffffff8105af17>] ? __might_sleep+0xf7/0x130
Jun 12 11:08:24 dpdk-eval kernel: [<ffffffff812b84c4>] ?
pci_cfg_access_lock+0x24/0x70
Jun 12 11:08:24 dpdk-eval kernel: [<ffffffff812b851e>] ?
pci_block_user_cfg_access+0xe/0x10
Jun 12 11:08:24 dpdk-eval kernel: [<ffffffffa00331d8>] ?
igbuio_pci_irqcontrol+0x48/0x80 [igb_uio]
Jun 12 11:08:24 dpdk-eval kernel: [<ffffffffa0033526>] ?
igbuio_pci_probe+0x217/0x29e [igb_uio]
Jun 12 11:08:24 dpdk-eval kernel: [<ffffffff81095a30>] ? do_work_for_cpu+0x0/0x30
Jun 12 11:08:24 dpdk-eval kernel: [<ffffffff812c07d7>] ? local_pci_probe+0x17/0x20
Jun 12 11:08:24 dpdk-eval kernel: [<ffffffff81095a48>] ? do_work_for_cpu+0x18/0x30
Jun 12 11:08:24 dpdk-eval kernel: [<ffffffff8109b826>] ? kthread+0x96/0xa0
Jun 12 11:08:24 dpdk-eval kernel: [<ffffffff8100c1ca>] ? child_rip+0xa/0x20
Jun 12 11:08:24 dpdk-eval kernel: [<ffffffff8100bb10>] ? restore_args+0x0/0x30
Jun 12 11:08:24 dpdk-eval kernel: [<ffffffff8109b790>] ? kthread+0x0/0xa0
Jun 12 11:08:24 dpdk-eval kernel: [<ffffffff8100c1c0>] ? 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: [<ffffffffa003324d>]
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: [<ffffffff810b4d13>] mark_held_locks+0x73/0xa0
Jun 12 11:08:24 dpdk-eval kernel: [<ffffffff810b4f37>]
trace_hardirqs_on_caller+0x67/0x190
Jun 12 11:08:24 dpdk-eval kernel: [<ffffffff810b506d>] trace_hardirqs_on+0xd/0x10
Jun 12 11:08:24 dpdk-eval kernel: [<ffffffff81542220>] _spin_unlock_irq+0x30/0x40
Jun 12 11:08:24 dpdk-eval kernel: [<ffffffff812b8504>] pci_cfg_access_lock+0x64/0x70
Jun 12 11:08:24 dpdk-eval kernel: [<ffffffff812b851e>]
pci_block_user_cfg_access+0xe/0x10
Jun 12 11:08:24 dpdk-eval kernel: [<ffffffffa00331d8>]
igbuio_pci_irqcontrol+0x48/0x80 [igb_uio]
Jun 12 11:08:24 dpdk-eval kernel: [<ffffffffa0033526>]
igbuio_pci_probe+0x217/0x29e [igb_uio]
Jun 12 11:08:24 dpdk-eval kernel: [<ffffffff812c07d7>] local_pci_probe+0x17/0x20
Jun 12 11:08:24 dpdk-eval kernel: [<ffffffff81095a48>] do_work_for_cpu+0x18/0x30
Jun 12 11:08:24 dpdk-eval kernel: [<ffffffff8109b826>] kthread+0x96/0xa0
Jun 12 11:08:24 dpdk-eval kernel: [<ffffffff8100c1ca>] 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):
[<ffffffff812fbc11>] intel_idle+0xe1/0x170
Jun 12 11:08:24 dpdk-eval kernel: hardirqs last disabled at (285498):
[<ffffffff8100aeea>] save_args+0x6a/0x70
Jun 12 11:08:24 dpdk-eval kernel: softirqs last enabled at (285500):
[<ffffffff8107a773>] _local_bh_enable+0x13/0x20
Jun 12 11:08:24 dpdk-eval kernel: softirqs last disabled at (285499):
[<ffffffff8107a7f5>] 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: <IRQ> [<ffffffff810b3c27>] ?
print_usage_bug+0x177/0x180
Jun 12 11:08:24 dpdk-eval kernel: [<ffffffff810b4bcd>] ? mark_lock+0x35d/0x430
Jun 12 11:08:24 dpdk-eval kernel: [<ffffffff810b5eaa>] ? __lock_acquire+0x77a/0x1570
Jun 12 11:08:24 dpdk-eval kernel: [<ffffffff81013af3>] ? native_sched_clock+0x13/0x80
Jun 12 11:08:24 dpdk-eval kernel: [<ffffffff81012dc9>] ? sched_clock+0x9/0x10
Jun 12 11:08:24 dpdk-eval kernel: [<ffffffff81013af3>] ? native_sched_clock+0x13/0x80
Jun 12 11:08:24 dpdk-eval kernel: [<ffffffff81012dc9>] ? sched_clock+0x9/0x10
Jun 12 11:08:24 dpdk-eval kernel: [<ffffffff810a32dd>] ? sched_clock_cpu+0xcd/0x110
Jun 12 11:08:24 dpdk-eval kernel: [<ffffffff810b6d44>] ? lock_acquire+0xa4/0x120
Jun 12 11:08:24 dpdk-eval kernel: [<ffffffffa003324d>] ?
igbuio_pci_irqhandler+0x3d/0xe8 [igb_uio]
Jun 12 11:08:24 dpdk-eval kernel: [<ffffffff81542625>] ? _spin_lock_irqsave+0x55/0xa0
Jun 12 11:08:24 dpdk-eval kernel: [<ffffffffa003324d>] ?
igbuio_pci_irqhandler+0x3d/0xe8 [igb_uio]
Jun 12 11:08:24 dpdk-eval kernel: [<ffffffffa003324d>] ?
igbuio_pci_irqhandler+0x3d/0xe8 [igb_uio]
Jun 12 11:08:24 dpdk-eval kernel: [<ffffffff810a32dd>] ? sched_clock_cpu+0xcd/0x110
Jun 12 11:08:24 dpdk-eval kernel: [<ffffffffa02822ee>] ? uio_interrupt+0x1e/0x48
[uio]
Jun 12 11:08:24 dpdk-eval kernel: [<ffffffff810f3050>] ? handle_IRQ_event+0x50/0x160
Jun 12 11:08:24 dpdk-eval kernel: [<ffffffff810f5820>] ? handle_edge_irq+0xe0/0x170
Jun 12 11:08:24 dpdk-eval kernel: [<ffffffff8100e019>] ? handle_irq+0x49/0xa0
Jun 12 11:08:24 dpdk-eval kernel: [<ffffffff81549a0c>] ? do_IRQ+0x6c/0xf0
Jun 12 11:08:24 dpdk-eval kernel: [<ffffffff8100ba53>] ? ret_from_intr+0x0/0x16
Jun 12 11:08:24 dpdk-eval kernel: <EOI> [<ffffffff812fbc18>] ? intel_idle+0xe8/0x170
Jun 12 11:08:24 dpdk-eval kernel: [<ffffffff812fbc11>] ? intel_idle+0xe1/0x170
Jun 12 11:08:24 dpdk-eval kernel: [<ffffffff81440d07>] ? cpuidle_idle_call+0xa7/0x150
Jun 12 11:08:24 dpdk-eval kernel: [<ffffffff81009fcb>] ? cpu_idle+0xbb/0x110
Jun 12 11:08:24 dpdk-eval kernel: [<ffffffff81537f37>] ? 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):
[<ffffffff8100b0aa>] sysret_check+0x2e/0x69
Jun 12 11:08:25 dpdk-eval kernel: hardirqs last disabled at (240174):
[<ffffffff8100b09e>] sysret_check+0x22/0x69
Jun 12 11:08:25 dpdk-eval kernel: softirqs last enabled at (240164):
[<ffffffff8107a6a1>] __do_softirq+0x151/0x210
Jun 12 11:08:25 dpdk-eval kernel: softirqs last disabled at (240151):
[<ffffffff8100c2cc>] 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: [<ffffffff810b0fd0>] ?
print_irqtrace_events+0xd0/0xe0
Jun 12 11:08:25 dpdk-eval kernel: [<ffffffff8105af17>] ? __might_sleep+0xf7/0x130
Jun 12 11:08:25 dpdk-eval kernel: [<ffffffff812b84c4>] ?
pci_cfg_access_lock+0x24/0x70
Jun 12 11:08:25 dpdk-eval kernel: [<ffffffff812b851e>] ?
pci_block_user_cfg_access+0xe/0x10
Jun 12 11:08:25 dpdk-eval kernel: [<ffffffffa00331d8>] ?
igbuio_pci_irqcontrol+0x48/0x80 [igb_uio]
Jun 12 11:08:25 dpdk-eval kernel: [<ffffffffa0281ae0>] ? uio_write+0x90/0xa0 [uio]
Jun 12 11:08:25 dpdk-eval kernel: [<ffffffff8119d448>] ? vfs_write+0xb8/0x1a0
Jun 12 11:08:25 dpdk-eval kernel: [<ffffffff8119e636>] ? fget_light+0x66/0x100
Jun 12 11:08:25 dpdk-eval kernel: [<ffffffff81541da8>] ?
lockdep_sys_exit_thunk+0x35/0x67
Jun 12 11:08:25 dpdk-eval kernel: [<ffffffff8119dd41>] ? sys_write+0x51/0x90
Jun 12 11:08:25 dpdk-eval kernel: [<ffffffff8100b072>] ?
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):
[<ffffffff81542270>] _spin_unlock_irqrestore+0x40/0x80
Jun 12 11:08:26 dpdk-eval kernel: hardirqs last disabled at (806):
[<ffffffff8154256f>] _spin_lock_irq+0x1f/0x80
Jun 12 11:08:26 dpdk-eval kernel: softirqs last enabled at (0):
[<ffffffff8106f785>] 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: [<ffffffff810b0fd0>] ?
print_irqtrace_events+0xd0/0xe0
Jun 12 11:08:26 dpdk-eval kernel: [<ffffffff8105af17>] ? __might_sleep+0xf7/0x130
Jun 12 11:08:26 dpdk-eval kernel: [<ffffffff812b84c4>] ?
pci_cfg_access_lock+0x24/0x70
Jun 12 11:08:26 dpdk-eval kernel: [<ffffffff812b851e>] ?
pci_block_user_cfg_access+0xe/0x10
Jun 12 11:08:26 dpdk-eval kernel: [<ffffffffa00331d8>] ?
igbuio_pci_irqcontrol+0x48/0x80 [igb_uio]
Jun 12 11:08:26 dpdk-eval kernel: [<ffffffffa0281ae0>] ? uio_write+0x90/0xa0 [uio]
Jun 12 11:08:26 dpdk-eval kernel: [<ffffffff8119d448>] ? vfs_write+0xb8/0x1a0
Jun 12 11:08:26 dpdk-eval kernel: [<ffffffff8119e636>] ? fget_light+0x66/0x100
Jun 12 11:08:26 dpdk-eval kernel: [<ffffffff8119dd41>] ? sys_write+0x51/0x90
Jun 12 11:08:26 dpdk-eval kernel: [<ffffffff8100b072>] ?
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):
[<ffffffff812fbc11>] intel_idle+0xe1/0x170
Jun 12 11:08:40 dpdk-eval kernel: hardirqs last disabled at (285498):
[<ffffffff8100aeea>] save_args+0x6a/0x70
Jun 12 11:08:40 dpdk-eval kernel: softirqs last enabled at (285500):
[<ffffffff8107a773>] _local_bh_enable+0x13/0x20
Jun 12 11:08:40 dpdk-eval kernel: softirqs last disabled at (285499):
[<ffffffff8107a7f5>] 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: <IRQ> [<ffffffff810b0fd0>] ?
print_irqtrace_events+0xd0/0xe0
Jun 12 11:08:40 dpdk-eval kernel: [<ffffffff8105af17>] ? __might_sleep+0xf7/0x130
Jun 12 11:08:40 dpdk-eval kernel: [<ffffffff812b84c4>] ?
pci_cfg_access_lock+0x24/0x70
Jun 12 11:08:40 dpdk-eval kernel: [<ffffffff810b506d>] ? trace_hardirqs_on+0xd/0x10
Jun 12 11:08:40 dpdk-eval kernel: [<ffffffff812b851e>] ?
pci_block_user_cfg_access+0xe/0x10
Jun 12 11:08:40 dpdk-eval kernel: [<ffffffffa0033258>] ?
igbuio_pci_irqhandler+0x48/0xe8 [igb_uio]
Jun 12 11:08:40 dpdk-eval kernel: [<ffffffffa02822ee>] ? uio_interrupt+0x1e/0x48
[uio]
Jun 12 11:08:40 dpdk-eval kernel: [<ffffffff810f5762>] ? handle_edge_irq+0x22/0x170
Jun 12 11:08:40 dpdk-eval kernel: [<ffffffff810f3050>] ? handle_IRQ_event+0x50/0x160
Jun 12 11:08:40 dpdk-eval kernel: [<ffffffff810f5820>] ? handle_edge_irq+0xe0/0x170
Jun 12 11:08:40 dpdk-eval kernel: [<ffffffff8100e019>] ? handle_irq+0x49/0xa0
Jun 12 11:08:40 dpdk-eval kernel: [<ffffffff81549a0c>] ? do_IRQ+0x6c/0xf0
Jun 12 11:08:40 dpdk-eval kernel: [<ffffffff8100ba53>] ? ret_from_intr+0x0/0x16
Jun 12 11:08:40 dpdk-eval kernel: <EOI> [<ffffffff812fbc11>] ? intel_idle+0xe1/0x170
Jun 12 11:08:40 dpdk-eval kernel: [<ffffffff812fbc18>] ? intel_idle+0xe8/0x170
Jun 12 11:08:40 dpdk-eval kernel: [<ffffffff812fbc11>] ? intel_idle+0xe1/0x170
Jun 12 11:08:40 dpdk-eval kernel: [<ffffffff81440d07>] ? cpuidle_idle_call+0xa7/0x150
Jun 12 11:08:40 dpdk-eval kernel: [<ffffffff81009fcb>] ? cpu_idle+0xbb/0x110
Jun 12 11:08:40 dpdk-eval kernel: [<ffffffff81537f37>] ? 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):
[<ffffffff81542270>] _spin_unlock_irqrestore+0x40/0x80
Jun 12 11:08:44 dpdk-eval kernel: hardirqs last disabled at (806):
[<ffffffff8154256f>] _spin_lock_irq+0x1f/0x80
Jun 12 11:08:44 dpdk-eval kernel: softirqs last enabled at (0):
[<ffffffff8106f785>] 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: [<ffffffff810b0fd0>] ?
print_irqtrace_events+0xd0/0xe0
Jun 12 11:08:44 dpdk-eval kernel: [<ffffffff8105af17>] ? __might_sleep+0xf7/0x130
Jun 12 11:08:44 dpdk-eval kernel: [<ffffffff812b84c4>] ?
pci_cfg_access_lock+0x24/0x70
Jun 12 11:08:44 dpdk-eval kernel: [<ffffffff812b851e>] ?
pci_block_user_cfg_access+0xe/0x10
Jun 12 11:08:44 dpdk-eval kernel: [<ffffffffa00331d8>] ?
igbuio_pci_irqcontrol+0x48/0x80 [igb_uio]
Jun 12 11:08:44 dpdk-eval kernel: [<ffffffffa0281ae0>] ? uio_write+0x90/0xa0 [uio]
Jun 12 11:08:44 dpdk-eval kernel: [<ffffffff8119d448>] ? vfs_write+0xb8/0x1a0
Jun 12 11:08:44 dpdk-eval kernel: [<ffffffff8119e636>] ? fget_light+0x66/0x100
Jun 12 11:08:44 dpdk-eval kernel: [<ffffffff8119dd41>] ? sys_write+0x51/0x90
Jun 12 11:08:44 dpdk-eval kernel: [<ffffffff8100b072>] ?
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):
[<ffffffff812fbc11>] intel_idle+0xe1/0x170
Jun 12 11:08:46 dpdk-eval kernel: hardirqs last disabled at (285498):
[<ffffffff8100aeea>] save_args+0x6a/0x70
Jun 12 11:08:46 dpdk-eval kernel: softirqs last enabled at (285500):
[<ffffffff8107a773>] _local_bh_enable+0x13/0x20
Jun 12 11:08:46 dpdk-eval kernel: softirqs last disabled at (285499):
[<ffffffff8107a7f5>] 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: <IRQ> [<ffffffff810b0fd0>] ?
print_irqtrace_events+0xd0/0xe0
Jun 12 11:08:46 dpdk-eval kernel: [<ffffffff8105af17>] ? __might_sleep+0xf7/0x130
Jun 12 11:08:46 dpdk-eval kernel: [<ffffffff812b84c4>] ?
pci_cfg_access_lock+0x24/0x70
Jun 12 11:08:46 dpdk-eval kernel: [<ffffffff812b851e>] ?
pci_block_user_cfg_access+0xe/0x10
Jun 12 11:08:46 dpdk-eval kernel: [<ffffffffa0033258>] ?
igbuio_pci_irqhandler+0x48/0xe8 [igb_uio]
Jun 12 11:08:46 dpdk-eval kernel: [<ffffffffa02822ee>] ? uio_interrupt+0x1e/0x48
[uio]
Jun 12 11:08:46 dpdk-eval kernel: [<ffffffff810f5762>] ? handle_edge_irq+0x22/0x170
Jun 12 11:08:46 dpdk-eval kernel: [<ffffffff810f3050>] ? handle_IRQ_event+0x50/0x160
Jun 12 11:08:46 dpdk-eval kernel: [<ffffffff810f5820>] ? handle_edge_irq+0xe0/0x170
Jun 12 11:08:46 dpdk-eval kernel: [<ffffffff8100e019>] ? handle_irq+0x49/0xa0
Jun 12 11:08:46 dpdk-eval kernel: [<ffffffff81549a0c>] ? do_IRQ+0x6c/0xf0
Jun 12 11:08:46 dpdk-eval kernel: [<ffffffff8100ba53>] ? ret_from_intr+0x0/0x16
Jun 12 11:08:46 dpdk-eval kernel: <EOI> [<ffffffff812fbc11>] ? intel_idle+0xe1/0x170
Jun 12 11:08:46 dpdk-eval kernel: [<ffffffff812fbc18>] ? intel_idle+0xe8/0x170
Jun 12 11:08:46 dpdk-eval kernel: [<ffffffff812fbc11>] ? intel_idle+0xe1/0x170
Jun 12 11:08:46 dpdk-eval kernel: [<ffffffff81440d07>] ? cpuidle_idle_call+0xa7/0x150
Jun 12 11:08:46 dpdk-eval kernel: [<ffffffff81009fcb>] ? cpu_idle+0xbb/0x110
Jun 12 11:08:46 dpdk-eval kernel: [<ffffffff81537f37>] ? 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):
[<ffffffff81542270>] _spin_unlock_irqrestore+0x40/0x80
Jun 12 11:08:47 dpdk-eval kernel: hardirqs last disabled at (806):
[<ffffffff8154256f>] _spin_lock_irq+0x1f/0x80
Jun 12 11:08:47 dpdk-eval kernel: softirqs last enabled at (0):
[<ffffffff8106f785>] 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: [<ffffffff810b0fd0>] ?
print_irqtrace_events+0xd0/0xe0
Jun 12 11:08:47 dpdk-eval kernel: [<ffffffff8105af17>] ? __might_sleep+0xf7/0x130
Jun 12 11:08:47 dpdk-eval kernel: [<ffffffff812b84c4>] ?
pci_cfg_access_lock+0x24/0x70
Jun 12 11:08:47 dpdk-eval kernel: [<ffffffff812b851e>] ?
pci_block_user_cfg_access+0xe/0x10
Jun 12 11:08:47 dpdk-eval kernel: [<ffffffffa00331d8>] ?
igbuio_pci_irqcontrol+0x48/0x80 [igb_uio]
Jun 12 11:08:47 dpdk-eval kernel: [<ffffffffa0281ae0>] ? uio_write+0x90/0xa0 [uio]
Jun 12 11:08:47 dpdk-eval kernel: [<ffffffff8119d448>] ? vfs_write+0xb8/0x1a0
Jun 12 11:08:47 dpdk-eval kernel: [<ffffffff8119e636>] ? fget_light+0x66/0x100
Jun 12 11:08:47 dpdk-eval kernel: [<ffffffff8119dd41>] ? sys_write+0x51/0x90
Jun 12 11:08:47 dpdk-eval kernel: [<ffffffff8100b072>] ?
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):
[<ffffffff812fbc11>] intel_idle+0xe1/0x170
Jun 12 11:08:52 dpdk-eval kernel: hardirqs last disabled at (285498):
[<ffffffff8100aeea>] save_args+0x6a/0x70
Jun 12 11:08:52 dpdk-eval kernel: softirqs last enabled at (285500):
[<ffffffff8107a773>] _local_bh_enable+0x13/0x20
Jun 12 11:08:52 dpdk-eval kernel: softirqs last disabled at (285499):
[<ffffffff8107a7f5>] 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: <IRQ> [<ffffffff810b0fd0>] ?
print_irqtrace_events+0xd0/0xe0
Jun 12 11:08:52 dpdk-eval kernel: [<ffffffff8105af17>] ? __might_sleep+0xf7/0x130
Jun 12 11:08:52 dpdk-eval kernel: [<ffffffff812b84c4>] ?
pci_cfg_access_lock+0x24/0x70
Jun 12 11:08:52 dpdk-eval kernel: [<ffffffff812b851e>] ?
pci_block_user_cfg_access+0xe/0x10
Jun 12 11:08:52 dpdk-eval kernel: [<ffffffffa0033258>] ?
igbuio_pci_irqhandler+0x48/0xe8 [igb_uio]
Jun 12 11:08:52 dpdk-eval kernel: [<ffffffffa02822ee>] ? uio_interrupt+0x1e/0x48
[uio]
Jun 12 11:08:52 dpdk-eval kernel: [<ffffffff810f5762>] ? handle_edge_irq+0x22/0x170
Jun 12 11:08:52 dpdk-eval kernel: [<ffffffff810f3050>] ? handle_IRQ_event+0x50/0x160
Jun 12 11:08:52 dpdk-eval kernel: [<ffffffff810f5820>] ? handle_edge_irq+0xe0/0x170
Jun 12 11:08:52 dpdk-eval kernel: [<ffffffff8100e019>] ? handle_irq+0x49/0xa0
Jun 12 11:08:52 dpdk-eval kernel: [<ffffffff81549a0c>] ? do_IRQ+0x6c/0xf0
Jun 12 11:08:52 dpdk-eval kernel: [<ffffffff8100ba53>] ? ret_from_intr+0x0/0x16
Jun 12 11:08:52 dpdk-eval kernel: <EOI> [<ffffffff812fbc11>] ? intel_idle+0xe1/0x170
Jun 12 11:08:52 dpdk-eval kernel: [<ffffffff812fbc18>] ? intel_idle+0xe8/0x170
Jun 12 11:08:52 dpdk-eval kernel: [<ffffffff812fbc11>] ? intel_idle+0xe1/0x170
Jun 12 11:08:52 dpdk-eval kernel: [<ffffffff81440d07>] ? cpuidle_idle_call+0xa7/0x150
Jun 12 11:08:52 dpdk-eval kernel: [<ffffffff81009fcb>] ? cpu_idle+0xbb/0x110
Jun 12 11:08:52 dpdk-eval kernel: [<ffffffff81537f37>] ? 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):
[<ffffffff81542270>] _spin_unlock_irqrestore+0x40/0x80
Jun 12 11:08:53 dpdk-eval kernel: hardirqs last disabled at (806):
[<ffffffff8154256f>] _spin_lock_irq+0x1f/0x80
Jun 12 11:08:53 dpdk-eval kernel: softirqs last enabled at (0):
[<ffffffff8106f785>] 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: [<ffffffff810b0fd0>] ?
print_irqtrace_events+0xd0/0xe0
Jun 12 11:08:53 dpdk-eval kernel: [<ffffffff8105af17>] ? __might_sleep+0xf7/0x130
Jun 12 11:08:53 dpdk-eval kernel: [<ffffffff812b84c4>] ?
pci_cfg_access_lock+0x24/0x70
Jun 12 11:08:53 dpdk-eval kernel: [<ffffffff812b851e>] ?
pci_block_user_cfg_access+0xe/0x10
Jun 12 11:08:53 dpdk-eval kernel: [<ffffffffa00331d8>] ?
igbuio_pci_irqcontrol+0x48/0x80 [igb_uio]
Jun 12 11:08:53 dpdk-eval kernel: [<ffffffffa0281ae0>] ? uio_write+0x90/0xa0 [uio]
Jun 12 11:08:53 dpdk-eval kernel: [<ffffffff8119d448>] ? vfs_write+0xb8/0x1a0
Jun 12 11:08:53 dpdk-eval kernel: [<ffffffff8119e636>] ? fget_light+0x66/0x100
Jun 12 11:08:53 dpdk-eval kernel: [<ffffffff8119dd41>] ? sys_write+0x51/0x90
Jun 12 11:08:53 dpdk-eval kernel: [<ffffffff8100b072>] ?
system_call_fastpath+0x16/0x1b
Jun 12 11:09:12 dpdk-eval kernel: link_status_int used greatest stack depth: 2968
bytes left
next reply other threads:[~2013-06-14 5:14 UTC|newest]
Thread overview: 4+ messages / expand[flat|nested] mbox.gz Atom feed top
2013-06-14 5:14 Patrick Mahan [this message]
[not found] ` <51BAA6BB.20107-5dHXHCkEAVbYtjvyW6yDsg@public.gmane.org>
2013-06-14 14:57 ` link_status_interrupt fails Stephen Hemminger
[not found] ` <20130614075736.5f0e4752-We1ePj4FEcvRI77zikRAJc56i+j3xesD0e7PPNI6Mm0@public.gmane.org>
2013-06-17 9:33 ` Thomas Monjalon
[not found] ` <201306171133.41185.thomas.monjalon-pdR9zngts4EAvxtiuMwx3w@public.gmane.org>
2013-06-17 12:02 ` Patrick Mahan
Reply instructions:
You may reply publicly to this message via plain-text email
using any one of the following methods:
* Save the following mbox file, import it into your mail client,
and reply-to-all from there: mbox
Avoid top-posting and favor interleaved quoting:
https://en.wikipedia.org/wiki/Posting_style#Interleaved_style
* Reply using the --to, --cc, and --in-reply-to
switches of git-send-email(1):
git send-email \
--in-reply-to=51BAA6BB.20107@mahan.org \
--to=mahan-5dhxhckeavbytjvyw6ydsg@public.gmane.org \
--cc=dev-VfR2kkLFssw@public.gmane.org \
/path/to/YOUR_REPLY
https://kernel.org/pub/software/scm/git/docs/git-send-email.html
* If your mail client supports setting the In-Reply-To header
via mailto: links, try the mailto: link
Be sure your reply has a Subject: header at the top and a blank line
before the message body.
This is an external index of several public inboxes,
see mirroring instructions on how to clone and mirror
all data and code used by this external index.