2009-09-22 03:47:57

by Justin P. Mattock

[permalink] [raw]
Subject: [ 14.123127] BUG: spinlock bad magic on CPU#0, swapper/0

I'm just trying to get the dam* bluetooth mighty mouse to
work. anyways the system barfed up this:
(FWIW: had bluez installed at-least an hour without this
showing up, as soon as I installed libusb
this showed up)

[ 13.607016] type=1400 audit(1253565686.600:3): avc: denied {
execmem } for pid=1759 comm="python"
scontext=system_u:system_r:kernel_t
tcontext=system_u:system_r:kernel_t tclass=process
[ 14.123127] BUG: spinlock bad magic on CPU#0, swapper/0
[ 14.123322] lock: ffff88002ad28430, .magic: 00000000, .owner:
<none>/-1, .owner_cpu: 0
[ 14.123598] Pid: 0, comm: swapper Not tainted 2.6.31-05767-gdf58bee #4
[ 14.123784] Call Trace:
[ 14.123965] <IRQ> [<ffffffff811cfd1e>] spin_bug+0x9c/0xa3
[ 14.124256] [<ffffffff811cfdd9>] _raw_spin_lock+0x29/0x13c
[ 14.124446] [<ffffffff813bad6c>] _spin_lock_irqsave+0x1f/0x26
[ 14.124637] [<ffffffff812f99f5>] skb_dequeue+0x1c/0x6b
[ 14.124839] [<ffffffffa0092c5e>] ? uhci_urb_enqueue+0x83a/0x853 [uhci_hcd]
[ 14.125029] [<ffffffff812fa571>] skb_queue_purge+0x1e/0x28
[ 14.125224] [<ffffffffa004d783>] l2cap_recv_frame+0x118d/0x20f4 [l2cap]
[ 14.125417] [<ffffffff810ddf9c>] ? __slab_free+0x2b3/0x2f9
[ 14.125617] [<ffffffffa003c8cd>] ? hci_event_packet+0x2984/0x299f
[bluetooth]
[ 14.125892] [<ffffffff812fa217>] ? __kfree_skb+0x78/0x7c
[ 14.126079] [<ffffffff812fa2aa>] ? kfree_skb+0x65/0x6d
[ 14.126276] [<ffffffffa003c8cd>] ? hci_event_packet+0x2984/0x299f
[bluetooth]
[ 14.126552] [<ffffffff81299598>] ? usb_submit_urb+0x217/0x22e
[ 14.126745] [<ffffffffa006063c>] ? btusb_bulk_complete+0x9f/0xd6 [btusb]
[ 14.126935] [<ffffffff813bad1d>] ? _spin_lock+0x9/0xb
[ 14.127126] [<ffffffffa009093d>] ? uhci_giveback_urb+0x124/0x1a5 [uhci_hcd]
[ 14.127322] [<ffffffffa004e7fe>] l2cap_recv_acldata+0xc5/0x233 [l2cap]
[ 14.127526] [<ffffffffa003809f>] hci_rx_task+0x155/0x253 [bluetooth]
[ 14.127718] [<ffffffff81064319>] tasklet_action+0x80/0xdf
[ 14.127906] [<ffffffff81065ba7>] __do_softirq+0xe0/0x1a1
[ 14.128099] [<ffffffff8102d9ac>] call_softirq+0x1c/0x28
[ 14.128288] [<ffffffff8102ee35>] do_softirq+0x41/0x81
[ 14.128480] [<ffffffff8106597a>] irq_exit+0x36/0x78
[ 14.128671] [<ffffffff8102e513>] do_IRQ+0xa0/0xb7
[ 14.128860] [<ffffffff8102d1d3>] ret_from_intr+0x0/0x11
[ 14.129043] <EOI> [<ffffffffa0018c88>] ?
acpi_idle_enter_bm+0x2d7/0x30b [processor]
[ 14.129432] [<ffffffffa0018c81>] ? acpi_idle_enter_bm+0x2d0/0x30b
[processor]
[ 14.129709] [<ffffffff812d65b9>] ? cpuidle_idle_call+0x88/0xbf
[ 14.129899] [<ffffffff8102bbf7>] ? cpu_idle+0x58/0x93
[ 14.130086] [<ffffffff813aa2ad>] ? rest_init+0x61/0x63
[ 14.130276] [<ffffffff8162514e>] ? start_kernel+0x361/0x36c
[ 14.130466] [<ffffffff81624785>] ? x86_64_start_reservations+0xac/0xb0
[ 14.130656] [<ffffffff81624881>] ? x86_64_start_kernel+0xf8/0x107
[ 14.642763] applesmc: light sensor data length set to 6

I can try and do a git bisect,
but might not be right now.

--
Justin P. Mattock


2009-09-24 22:02:40

by Justin P. Mattock

[permalink] [raw]
Subject: Re: [ 14.123127] BUG: spinlock bad magic on CPU#0, swapper/0

On Mon, Sep 21, 2009 at 8:47 PM, Justin Mattock <[email protected]> w=
rote:
> I'm just trying to get the dam* bluetooth mighty mouse to
> work. anyways the system barfed up this:
> (FWIW: =A0had bluez installed at-least an hour without this
> showing up, as soon as I installed libusb
> this showed up)
>
> [ =A0 13.607016] type=3D1400 audit(1253565686.600:3): avc: =A0denied =A0{
> execmem } for =A0pid=3D1759 comm=3D"python"
> scontext=3Dsystem_u:system_r:kernel_t
> tcontext=3Dsystem_u:system_r:kernel_t tclass=3Dprocess
> [ =A0 14.123127] BUG: spinlock bad magic on CPU#0, swapper/0
> [ =A0 14.123322] =A0lock: ffff88002ad28430, .magic: 00000000, .owner:
> <none>/-1, .owner_cpu: 0
> [ =A0 14.123598] Pid: 0, comm: swapper Not tainted 2.6.31-05767-gdf58bee =
#4
> [ =A0 14.123784] Call Trace:
> [ =A0 14.123965] =A0<IRQ> =A0[<ffffffff811cfd1e>] spin_bug+0x9c/0xa3
> [ =A0 14.124256] =A0[<ffffffff811cfdd9>] _raw_spin_lock+0x29/0x13c
> [ =A0 14.124446] =A0[<ffffffff813bad6c>] _spin_lock_irqsave+0x1f/0x26
> [ =A0 14.124637] =A0[<ffffffff812f99f5>] skb_dequeue+0x1c/0x6b
> [ =A0 14.124839] =A0[<ffffffffa0092c5e>] ? uhci_urb_enqueue+0x83a/0x853 [=
uhci_hcd]
> [ =A0 14.125029] =A0[<ffffffff812fa571>] skb_queue_purge+0x1e/0x28
> [ =A0 14.125224] =A0[<ffffffffa004d783>] l2cap_recv_frame+0x118d/0x20f4 [=
l2cap]
> [ =A0 14.125417] =A0[<ffffffff810ddf9c>] ? __slab_free+0x2b3/0x2f9
> [ =A0 14.125617] =A0[<ffffffffa003c8cd>] ? hci_event_packet+0x2984/0x299f
> [bluetooth]
> [ =A0 14.125892] =A0[<ffffffff812fa217>] ? __kfree_skb+0x78/0x7c
> [ =A0 14.126079] =A0[<ffffffff812fa2aa>] ? kfree_skb+0x65/0x6d
> [ =A0 14.126276] =A0[<ffffffffa003c8cd>] ? hci_event_packet+0x2984/0x299f
> [bluetooth]
> [ =A0 14.126552] =A0[<ffffffff81299598>] ? usb_submit_urb+0x217/0x22e
> [ =A0 14.126745] =A0[<ffffffffa006063c>] ? btusb_bulk_complete+0x9f/0xd6 =
[btusb]
> [ =A0 14.126935] =A0[<ffffffff813bad1d>] ? _spin_lock+0x9/0xb
> [ =A0 14.127126] =A0[<ffffffffa009093d>] ? uhci_giveback_urb+0x124/0x1a5 =
[uhci_hcd]
> [ =A0 14.127322] =A0[<ffffffffa004e7fe>] l2cap_recv_acldata+0xc5/0x233 [l=
2cap]
> [ =A0 14.127526] =A0[<ffffffffa003809f>] hci_rx_task+0x155/0x253 [bluetoo=
th]
> [ =A0 14.127718] =A0[<ffffffff81064319>] tasklet_action+0x80/0xdf
> [ =A0 14.127906] =A0[<ffffffff81065ba7>] __do_softirq+0xe0/0x1a1
> [ =A0 14.128099] =A0[<ffffffff8102d9ac>] call_softirq+0x1c/0x28
> [ =A0 14.128288] =A0[<ffffffff8102ee35>] do_softirq+0x41/0x81
> [ =A0 14.128480] =A0[<ffffffff8106597a>] irq_exit+0x36/0x78
> [ =A0 14.128671] =A0[<ffffffff8102e513>] do_IRQ+0xa0/0xb7
> [ =A0 14.128860] =A0[<ffffffff8102d1d3>] ret_from_intr+0x0/0x11
> [ =A0 14.129043] =A0<EOI> =A0[<ffffffffa0018c88>] ?
> acpi_idle_enter_bm+0x2d7/0x30b [processor]
> [ =A0 14.129432] =A0[<ffffffffa0018c81>] ? acpi_idle_enter_bm+0x2d0/0x30b
> [processor]
> [ =A0 14.129709] =A0[<ffffffff812d65b9>] ? cpuidle_idle_call+0x88/0xbf
> [ =A0 14.129899] =A0[<ffffffff8102bbf7>] ? cpu_idle+0x58/0x93
> [ =A0 14.130086] =A0[<ffffffff813aa2ad>] ? rest_init+0x61/0x63
> [ =A0 14.130276] =A0[<ffffffff8162514e>] ? start_kernel+0x361/0x36c
> [ =A0 14.130466] =A0[<ffffffff81624785>] ? x86_64_start_reservations+0xac=
/0xb0
> [ =A0 14.130656] =A0[<ffffffff81624881>] ? x86_64_start_kernel+0xf8/0x107
> [ =A0 14.642763] applesmc: light sensor data length set to 6
>
> I can try and do a git bisect,
> but might not be right now.
>
> --
> Justin P. Mattock
>

I understand if you have too much time
to deal with things like this.
Anyways posted a few days ago, had no response,
so I decided to post again, except add marcel to the
CC. Anyways I seem to be hitting this during
boot, and once the mouse falls alseep upon
wake up this triggers.(not all the time though)

here's a cutout of upon waking the mouse up
from sleeping(not s2ram)

[ 4400.410296] BUG: spinlock bad magic on CPU#0, swapper/0
[ 4400.410307] lock: ffff8800385a5700, .magic: 00000000, .owner:
<none>/-1, .owner_cpu: 0
[ 4400.410314] Pid: 0, comm: swapper Not tainted 2.6.31-05767-gdf58bee #5
[ 4400.410319] Call Trace:
[ 4400.410323] <IRQ> [<ffffffff811ae39e>] spin_bug+0x9c/0xa3
[ 4400.410343] [<ffffffff811ae459>] _raw_spin_lock+0x29/0x13c
[ 4400.410352] [<ffffffff8139a51c>] _spin_lock_irqsave+0x1f/0x26
[ 4400.410361] [<ffffffff812d91a5>] skb_dequeue+0x1c/0x6b
[ 4400.410382] [<ffffffffa0092c5e>] ? uhci_urb_enqueue+0x83a/0x853 [uhci_h=
cd]
[ 4400.410390] [<ffffffff812d9d21>] skb_queue_purge+0x1e/0x28
[ 4400.410404] [<ffffffffa004d783>] l2cap_recv_frame+0x118d/0x20f4 [l2cap]
[ 4400.410413] [<ffffffff810bc61c>] ? __slab_free+0x2b3/0x2f9
[ 4400.410432] [<ffffffffa003c8cd>] ? hci_event_packet+0x2984/0x299f
[bluetooth]
[ 4400.410439] [<ffffffff812d99c7>] ? __kfree_skb+0x78/0x7c
[ 4400.410446] [<ffffffff812d9a5a>] ? kfree_skb+0x65/0x6d
[ 4400.410463] [<ffffffffa003c8cd>] ? hci_event_packet+0x2984/0x299f
[bluetooth]
[ 4400.410471] [<ffffffff81277c18>] ? usb_submit_urb+0x217/0x22e
[ 4400.410483] [<ffffffffa006063c>] ? btusb_bulk_complete+0x9f/0xd6 [btusb=
]
[ 4400.410491] [<ffffffff8139a4cd>] ? _spin_lock+0x9/0xb
[ 4400.410501] [<ffffffffa009093d>] ? uhci_giveback_urb+0x124/0x1a5 [uhci_=
hcd]
[ 4400.410514] [<ffffffffa004e7fe>] l2cap_recv_acldata+0xc5/0x233 [l2cap]
[ 4400.410531] [<ffffffffa003809f>] hci_rx_task+0x155/0x253 [bluetooth]
[ 4400.410539] [<ffffffff81042999>] tasklet_action+0x80/0xdf
[ 4400.410547] [<ffffffff81044227>] __do_softirq+0xe0/0x1a1
[ 4400.410556] [<ffffffff8100c02c>] call_softirq+0x1c/0x28
[ 4400.410563] [<ffffffff8100d4b5>] do_softirq+0x41/0x81
[ 4400.410570] [<ffffffff81043ffa>] irq_exit+0x36/0x78
[ 4400.410576] [<ffffffff8100cb93>] do_IRQ+0xa0/0xb7
[ 4400.410585] [<ffffffff8100b853>] ret_from_intr+0x0/0x11
[ 4400.410589] <EOI> [<ffffffffa0018c88>] ?
acpi_idle_enter_bm+0x2d7/0x30b [processor]
[ 4400.410619] [<ffffffffa0018c81>] ? acpi_idle_enter_bm+0x2d0/0x30b
[processor]
[ 4400.410629] [<ffffffff812b4c39>] ? cpuidle_idle_call+0x88/0xbf
[ 4400.410637] [<ffffffff8100a277>] ? cpu_idle+0x58/0x93
[ 4400.410645] [<ffffffff81389a5d>] ? rest_init+0x61/0x63
[ 4400.410654] [<ffffffff815f710e>] ? start_kernel+0x361/0x36c
[ 4400.410662] [<ffffffff815f6745>] ? x86_64_start_reservations+0xac/0xb0
[ 4400.410670] [<ffffffff815f6841>] ? x86_64_start_kernel+0xf8/0x107
[ 5158.248934] input: justinmattock=E2s mouse as
/devices/pci0000:00/0000:00:1d.3/usb5/5-1/5-1:1.0/bluetooth/hci0/hci0:46/in=
put12
[ 5158.249279] generic-bluetooth 0005:0000:0000.0007: input: BLUETOOTH
HID v0.00 Mouse [justinmattock=E2s mouse] on 00:17:F2:B4:BC:F5

everything seems o.k. after this triggers
mouse reacts as is.
In any case at least you have this info.

--=20
Justin P. Mattock