[dpdk-dev] link_status_interrupt fails

Patrick Mahan mahan at mahan.org
Fri Jun 14 07:14:35 CEST 2013
Previous message: [dpdk-dev] l3fwd doesn't seem to work
Next message: [dpdk-dev] link_status_interrupt fails
Messages sorted by: [ date ] [ thread ] [ subject ] [ author ]
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
Previous message: [dpdk-dev] l3fwd doesn't seem to work
Next message: [dpdk-dev] link_status_interrupt fails
Messages sorted by: [ date ] [ thread ] [ subject ] [ author ]
More information about the dev mailing list