2009-09-16 22:44:28

by Knut Petersen

[permalink] [raw]
Subject: [BUG] 2.6.31-rt10

Sep 16 22:17:08 linux pppd[2362]: Script /etc/ppp/ip-down finished (pid 10148), status = 0x0
Sep 16 22:21:06 linux kernel: [13539.454613] BUG: sleeping function called from invalid context at kernel/rtmutex.c:684
Sep 16 22:21:06 linux kernel: [13539.454663] in_atomic(): 1, irqs_disabled(): 1, pid: 2397, name: Xorg
Sep 16 22:21:06 linux kernel: [13539.454694] Pid: 2397, comm: Xorg Not tainted 2.6.31-rt10-kape #11
Sep 16 22:21:06 linux kernel: [13539.454721] Call Trace:
Sep 16 22:21:06 linux kernel: [13539.454749] [<c011add8>] __might_sleep+0xb1/0xb6
Sep 16 22:21:06 linux kernel: [13539.454778] [<c03cc059>] rt_spin_lock+0x23/0x56
Sep 16 22:21:06 linux kernel: [13539.454805] [<c03cbd1a>] __account_scheduler_latency+0x66/0x19b
Sep 16 22:21:06 linux kernel: [13539.454838] [<c018d8f7>] ? poll_schedule_timeout+0x27/0x3e
Sep 16 22:21:06 linux kernel: [13539.454866] [<c018dcb7>] ? do_sys_poll+0x33f/0x3be
Sep 16 22:21:06 linux kernel: [13539.454892] [<c018de6e>] ? sys_poll+0x42/0x8b
Sep 16 22:21:06 linux kernel: [13539.454917] [<c01027f0>] ? sysenter_do_call+0x12/0x22
Sep 16 22:21:06 linux kernel: [13539.454948] [<c011d3ce>] enqueue_entity+0x15f/0x2df
Sep 16 22:21:06 linux kernel: [13539.454976] [<c011d56d>] enqueue_task_fair+0x1f/0x59
Sep 16 22:21:06 linux kernel: [13539.455003] [<c0119bf3>] enqueue_task+0x4f/0x5b
Sep 16 22:21:06 linux kernel: [13539.455029] [<c0119d26>] activate_task+0x1f/0x24
Sep 16 22:21:06 linux kernel: [13539.455056] [<c011de98>] try_to_wake_up+0xb9/0x1b3
Sep 16 22:21:06 linux kernel: [13539.455084] [<c011df9f>] default_wake_function+0xd/0xf
Sep 16 22:21:06 linux kernel: [13539.455111] [<c018e940>] pollwake+0x57/0x63
Sep 16 22:21:06 linux kernel: [13539.455137] [<c011df92>] ? default_wake_function+0x0/0xf
Sep 16 22:21:06 linux kernel: [13539.455165] [<c011a198>] __wake_up_common+0x34/0x5c
Sep 16 22:21:06 linux kernel: [13539.455193] [<c011b3bf>] __wake_up_sync_key+0x30/0x3e
Sep 16 22:21:06 linux kernel: [13539.455221] [<c011b3d7>] __wake_up_sync+0xa/0xd
Sep 16 22:21:06 linux kernel: [13539.455248] [<c03be619>] unix_write_space+0x43/0x6d
Sep 16 22:21:06 linux kernel: [13539.455277] [<c03636d1>] sock_wfree+0x2b/0x3e
Sep 16 22:21:06 linux kernel: [13539.455302] [<c036662b>] skb_release_head_state+0x39/0x84
Sep 16 22:21:06 linux kernel: [13539.455330] [<c0366457>] __kfree_skb+0xb/0x6d
Sep 16 22:21:06 linux kernel: [13539.455354] [<c0366503>] kfree_skb+0x24/0x26
Sep 16 22:21:06 linux kernel: [13539.455379] [<c03be0f7>] unix_stream_recvmsg+0x339/0x447
Sep 16 22:21:06 linux kernel: [13539.455410] [<c0360901>] sock_aio_read+0x10c/0x116
Sep 16 22:21:06 linux kernel: [13539.455442] [<c0181c23>] do_sync_read+0xa8/0xe3
Sep 16 22:21:06 linux kernel: [13539.455471] [<c0133045>] ? autoremove_wake_function+0x0/0x2f
Sep 16 22:21:06 linux kernel: [13539.455502] [<c0126546>] ? do_setitimer+0x16e/0x2b9
Sep 16 22:21:06 linux kernel: [13539.455532] [<c01fb9e4>] ? security_file_permission+0xf/0x11
Sep 16 22:21:06 linux kernel: [13539.455562] [<c0181cf6>] ? rw_verify_area+0x98/0xbb
Sep 16 22:21:06 linux kernel: [13539.455590] [<c01826f6>] vfs_read+0x98/0x139
Sep 16 22:21:06 linux kernel: [13539.455616] [<c018282e>] sys_read+0x3b/0x60
Sep 16 22:21:06 linux kernel: [13539.455641] [<c01027f0>] sysenter_do_call+0x12/0x22
Sep 16 22:21:09 linux kernel: [13540.458987] BUG: sleeping function called from invalid context at kernel/rtmutex.c:684
Sep 16 22:21:09 linux kernel: [13540.459036] in_atomic(): 1, irqs_disabled(): 1, pid: 2397, name: Xorg
Sep 16 22:21:09 linux kernel: [13540.459068] Pid: 2397, comm: Xorg Not tainted 2.6.31-rt10-kape #11
Sep 16 22:21:09 linux kernel: [13540.459095] Call Trace:
Sep 16 22:21:09 linux kernel: [13540.459122] [<c011add8>] __might_sleep+0xb1/0xb6
Sep 16 22:21:09 linux kernel: [13540.459152] [<c03cc059>] rt_spin_lock+0x23/0x56
Sep 16 22:21:09 linux kernel: [13540.459179] [<c03cbd1a>] __account_scheduler_latency+0x66/0x19b
Sep 16 22:21:09 linux kernel: [13540.459212] [<c018d8f7>] ? poll_schedule_timeout+0x27/0x3e
Sep 16 22:21:09 linux kernel: [13540.459240] [<c018dcb7>] ? do_sys_poll+0x33f/0x3be
Sep 16 22:21:09 linux kernel: [13540.459266] [<c018de6e>] ? sys_poll+0x42/0x8b
Sep 16 22:21:09 linux kernel: [13540.459291] [<c01027f0>] ? sysenter_do_call+0x12/0x22
Sep 16 22:21:09 linux kernel: [13540.459322] [<c011d3ce>] enqueue_entity+0x15f/0x2df
Sep 16 22:21:09 linux kernel: [13540.459350] [<c011d56d>] enqueue_task_fair+0x1f/0x59
Sep 16 22:21:09 linux kernel: [13540.459378] [<c0119bf3>] enqueue_task+0x4f/0x5b
Sep 16 22:21:09 linux kernel: [13540.459403] [<c0119d26>] activate_task+0x1f/0x24
Sep 16 22:21:09 linux kernel: [13540.459430] [<c011de98>] try_to_wake_up+0xb9/0x1b3
Sep 16 22:21:09 linux kernel: [13540.459456] [<c018e8e9>] ? pollwake+0x0/0x63
Sep 16 22:21:09 linux kernel: [13540.459483] [<c011df9f>] default_wake_function+0xd/0xf
Sep 16 22:21:09 linux kernel: [13540.459509] [<c018e940>] pollwake+0x57/0x63
Sep 16 22:21:09 linux kernel: [13540.459535] [<c011df92>] ? default_wake_function+0x0/0xf
Sep 16 22:21:09 linux kernel: [13540.459564] [<c011a198>] __wake_up_common+0x34/0x5c
Sep 16 22:21:09 linux kernel: [13540.459592] [<c011b3bf>] __wake_up_sync_key+0x30/0x3e
Sep 16 22:21:09 linux kernel: [13540.459620] [<c011b3d7>] __wake_up_sync+0xa/0xd
Sep 16 22:21:09 linux kernel: [13540.459646] [<c03be619>] unix_write_space+0x43/0x6d
Sep 16 22:21:09 linux kernel: [13540.459676] [<c03636d1>] sock_wfree+0x2b/0x3e
Sep 16 22:21:09 linux kernel: [13540.459701] [<c036662b>] skb_release_head_state+0x39/0x84
Sep 16 22:21:09 linux kernel: [13540.459729] [<c0366457>] __kfree_skb+0xb/0x6d
Sep 16 22:21:09 linux kernel: [13540.459753] [<c0366503>] kfree_skb+0x24/0x26
Sep 16 22:21:09 linux kernel: [13540.459778] [<c03be0f7>] unix_stream_recvmsg+0x339/0x447
Sep 16 22:21:09 linux kernel: [13540.459809] [<c0360901>] sock_aio_read+0x10c/0x116
Sep 16 22:21:09 linux kernel: [13540.459841] [<c0181c23>] do_sync_read+0xa8/0xe3
Sep 16 22:21:09 linux kernel: [13540.459869] [<c0133045>] ? autoremove_wake_function+0x0/0x2f
Sep 16 22:21:09 linux kernel: [13540.459900] [<c0126546>] ? do_setitimer+0x16e/0x2b9
Sep 16 22:21:09 linux kernel: [13540.459930] [<c01fb9e4>] ? security_file_permission+0xf/0x11
Sep 16 22:21:09 linux kernel: [13540.459960] [<c0181cf6>] ? rw_verify_area+0x98/0xbb
Sep 16 22:21:09 linux kernel: [13540.459988] [<c01826f6>] vfs_read+0x98/0x139
Sep 16 22:21:09 linux kernel: [13540.460014] [<c018282e>] sys_read+0x3b/0x60
Sep 16 22:21:09 linux kernel: [13540.460039] [<c01027f0>] sysenter_do_call+0x12/0x22
Sep 16 22:21:09 linux kernel: [13541.457425] BUG: sleeping function called from invalid context at kernel/rtmutex.c:684
Sep 16 22:21:09 linux kernel: [13541.457458] in_atomic(): 1, irqs_disabled(): 1, pid: 40, name: irq/16-i915
Sep 16 22:21:09 linux kernel: [13541.457472] Pid: 40, comm: irq/16-i915 Not tainted 2.6.31-rt10-kape #11
Sep 16 22:21:09 linux kernel: [13541.457484] Call Trace:
Sep 16 22:21:09 linux kernel: [13541.457501] [<c011add8>] __might_sleep+0xb1/0xb6
Sep 16 22:21:09 linux kernel: [13541.457515] [<c03cc059>] rt_spin_lock+0x23/0x56
Sep 16 22:21:09 linux kernel: [13541.457527] [<c03cbd1a>] __account_scheduler_latency+0x66/0x19b
Sep 16 22:21:09 linux kernel: [13541.457542] [<c02aa6fd>] ? i915_wait_request+0x12e/0x1ae
Sep 16 22:21:09 linux kernel: [13541.457554] [<c02aa806>] ? i915_gem_object_wait_rendering+0x28/0x2a
Sep 16 22:21:09 linux kernel: [13541.457568] [<c02aa830>] ? i915_gem_object_set_to_gtt_domain+0x28/0x71
Sep 16 22:21:09 linux kernel: [13541.457581] [<c02aad66>] ? i915_gem_set_domain_ioctl+0x99/0x113
Sep 16 22:21:09 linux kernel: [13541.457597] [<c0298008>] ? drm_ioctl+0x1f5/0x282
Sep 16 22:21:09 linux kernel: [13541.457610] [<c018c7c1>] ? vfs_ioctl+0x57/0x71
Sep 16 22:21:09 linux kernel: [13541.457621] [<c018cd80>] ? do_vfs_ioctl+0x4ea/0x528
Sep 16 22:21:09 linux kernel: [13541.457633] [<c018cdff>] ? sys_ioctl+0x41/0x61
Sep 16 22:21:09 linux kernel: [13541.457645] [<c01027f0>] ? sysenter_do_call+0x12/0x22
Sep 16 22:21:09 linux kernel: [13541.457658] [<c011d3ce>] enqueue_entity+0x15f/0x2df
Sep 16 22:21:09 linux kernel: [13541.457670] [<c011d56d>] enqueue_task_fair+0x1f/0x59
Sep 16 22:21:09 linux kernel: [13541.457682] [<c0119bf3>] enqueue_task+0x4f/0x5b
Sep 16 22:21:09 linux kernel: [13541.457693] [<c0119d26>] activate_task+0x1f/0x24
Sep 16 22:21:09 linux kernel: [13541.457704] [<c011de98>] try_to_wake_up+0xb9/0x1b3
Sep 16 22:21:09 linux kernel: [13541.457717] [<c011df9f>] default_wake_function+0xd/0xf
Sep 16 22:21:09 linux kernel: [13541.457730] [<c0133054>] autoremove_wake_function+0xf/0x2f
Sep 16 22:21:09 linux kernel: [13541.457743] [<c011a198>] __wake_up_common+0x34/0x5c
Sep 16 22:21:09 linux kernel: [13541.457754] [<c011b400>] __wake_up+0x26/0x34
Sep 16 22:21:09 linux kernel: [13541.457765] [<c02a77e5>] i915_driver_irq_handler+0x8e8/0x93b
Sep 16 22:21:09 linux kernel: [13541.457778] [<c03cab0d>] ? __schedule+0x417/0x467
Sep 16 22:21:09 linux kernel: [13541.457791] [<c0153e92>] irq_thread+0xa7/0x151
Sep 16 22:21:09 linux kernel: [13541.457802] [<c0153deb>] ? irq_thread+0x0/0x151
Sep 16 22:21:09 linux kernel: [13541.457813] [<c0132e61>] kthread+0x68/0x6d
Sep 16 22:21:09 linux kernel: [13541.457823] [<c0132df9>] ? kthread+0x0/0x6d
Sep 16 22:21:09 linux kernel: [13541.457834] [<c01030e3>] kernel_thread_helper+0x7/0x10


[Restarted PC]


Sep 16 23:26:51 linux kernel: [ 177.901615] BUG: sleeping function called from invalid context at kernel/rtmutex.c:684
Sep 16 23:26:51 linux kernel: [ 177.901653] in_atomic(): 1, irqs_disabled(): 1, pid: 7, name: sirq-block/0
Sep 16 23:26:51 linux kernel: [ 177.901681] Pid: 7, comm: sirq-block/0 Not tainted 2.6.31-rt10-kape #13
Sep 16 23:26:51 linux kernel: [ 177.901705] Call Trace:
Sep 16 23:26:51 linux kernel: [ 177.901728] [<c011add8>] __might_sleep+0xb1/0xb6
Sep 16 23:26:51 linux kernel: [ 177.901755] [<c0399189>] rt_spin_lock+0x23/0x56
Sep 16 23:26:51 linux kernel: [ 177.901779] [<c0398e4a>] __account_scheduler_latency+0x66/0x19b
Sep 16 23:26:51 linux kernel: [ 177.901809] [<c019d739>] ? sync_buffer+0x2e/0x32
Sep 16 23:26:51 linux kernel: [ 177.901831] [<c019d6a8>] ? __wait_on_buffer+0x19/0x1c
Sep 16 23:26:51 linux kernel: [ 177.901858] [<c01d78d4>] ? search_by_key+0x142/0xee4
Sep 16 23:26:51 linux kernel: [ 177.901882] [<c01c9b1e>] ? reiserfs_read_locked_inode+0x5a/0x554
Sep 16 23:26:51 linux kernel: [ 177.901909] [<c01ca062>] ? reiserfs_iget+0x4a/0x7d
Sep 16 23:26:51 linux kernel: [ 177.901931] [<c01c6dd1>] ? reiserfs_lookup+0x81/0xd1
Sep 16 23:26:51 linux kernel: [ 177.901956] [<c0188636>] ? do_lookup+0xa7/0x151
Sep 16 23:26:51 linux kernel: [ 177.901979] [<c018a59b>] ? __link_path_walk+0x78f/0xb21
Sep 16 23:26:51 linux kernel: [ 177.902003] [<c018aa76>] ? path_walk+0x38/0x71
Sep 16 23:26:51 linux kernel: [ 177.902026] [<c018ab63>] ? do_path_lookup+0x21/0x6e
Sep 16 23:26:51 linux kernel: [ 177.902050] [<c018b38a>] ? user_path_at+0x39/0x5f
Sep 16 23:26:51 linux kernel: [ 177.902072] [<c0184e8f>] ? vfs_fstatat+0x2d/0x54
Sep 16 23:26:51 linux kernel: [ 177.902097] [<c011d4cf>] enqueue_entity+0x260/0x2df
Sep 16 23:26:51 linux kernel: [ 177.902121] [<c011d546>] ? enqueue_entity+0x2d7/0x2df
Sep 16 23:26:51 linux kernel: [ 177.902147] [<c011d56d>] enqueue_task_fair+0x1f/0x59
Sep 16 23:26:51 linux kernel: [ 177.902171] [<c0119bf3>] enqueue_task+0x4f/0x5b
Sep 16 23:26:51 linux kernel: [ 177.902194] [<c0119d26>] activate_task+0x1f/0x24
Sep 16 23:26:51 linux kernel: [ 177.902217] [<c011de98>] try_to_wake_up+0xb9/0x1b3
Sep 16 23:26:51 linux kernel: [ 177.902240] [<c0119d26>] ? activate_task+0x1f/0x24
Sep 16 23:26:51 linux kernel: [ 177.902264] [<c011df76>] ? try_to_wake_up+0x197/0x1b3
Sep 16 23:26:51 linux kernel: [ 177.902289] [<c011df9f>] default_wake_function+0xd/0xf
Sep 16 23:26:51 linux kernel: [ 177.902315] [<c0133054>] autoremove_wake_function+0xf/0x2f
Sep 16 23:26:51 linux kernel: [ 177.902341] [<c013309e>] wake_bit_function+0x2a/0x37
Sep 16 23:26:51 linux kernel: [ 177.902365] [<c011a198>] __wake_up_common+0x34/0x5c
Sep 16 23:26:51 linux kernel: [ 177.902389] [<c011b400>] __wake_up+0x26/0x34
Sep 16 23:26:51 linux kernel: [ 177.902411] [<c0132fe7>] __wake_up_bit+0x27/0x2a
Sep 16 23:26:51 linux kernel: [ 177.902434] [<c0133041>] wake_up_bit+0x57/0x5b
Sep 16 23:26:51 linux kernel: [ 177.902456] [<c019d74d>] unlock_buffer+0x10/0x12
Sep 16 23:26:51 linux kernel: [ 177.902479] [<c019d7e2>] end_buffer_read_sync+0x19/0x1f
Sep 16 23:26:51 linux kernel: [ 177.902504] [<c019c71e>] end_bio_bh_io_sync+0x2b/0x35
Sep 16 23:26:51 linux kernel: [ 177.902529] [<c019f668>] bio_endio+0x22/0x24
Sep 16 23:26:51 linux kernel: [ 177.902553] [<c02178ef>] req_bio_endio+0xa5/0xc7
Sep 16 23:26:51 linux kernel: [ 177.902576] [<c0217a0c>] blk_update_request+0xfb/0x28e
Sep 16 23:26:51 linux kernel: [ 177.902602] [<c0217bad>] blk_update_bidi_request+0xe/0x3c
Sep 16 23:26:51 linux kernel: [ 177.902628] [<c021849e>] blk_end_bidi_request+0x15/0x47
Sep 16 23:26:51 linux kernel: [ 177.902654] [<c0218504>] blk_end_request+0xa/0xc
Sep 16 23:26:51 linux kernel: [ 177.902677] [<c02b3b10>] scsi_io_completion+0x191/0x3bf
Sep 16 23:26:51 linux kernel: [ 177.902702] [<c02b38ae>] ? scsi_device_unbusy+0x7e/0x83
Sep 16 23:26:51 linux kernel: [ 177.902729] [<c02ae351>] scsi_finish_command+0xce/0xd6
Sep 16 23:26:51 linux kernel: [ 177.902753] [<c02b3e33>] scsi_softirq_done+0xe6/0xee
Sep 16 23:26:51 linux kernel: [ 177.902778] [<c021b861>] blk_done_softirq+0x55/0x62
Sep 16 23:26:51 linux kernel: [ 177.902803] [<c0127457>] ksoftirqd+0xed/0x1ac
Sep 16 23:26:51 linux kernel: [ 177.902826] [<c012736a>] ? ksoftirqd+0x0/0x1ac
Sep 16 23:26:51 linux kernel: [ 177.902847] [<c0132e61>] kthread+0x68/0x6d
Sep 16 23:26:51 linux kernel: [ 177.902869] [<c0132df9>] ? kthread+0x0/0x6d
Sep 16 23:26:51 linux kernel: [ 177.902891] [<c01030e3>] kernel_thread_helper+0x7/0x10
Sep 16 23:26:52 linux kernel: [ 179.152586] BUG: sleeping function called from invalid context at kernel/rtmutex.c:684
Sep 16 23:26:52 linux kernel: [ 179.152623] in_atomic(): 1, irqs_disabled(): 1, pid: 2721, name: smpppd
Sep 16 23:26:52 linux kernel: [ 179.152652] Pid: 2721, comm: smpppd Not tainted 2.6.31-rt10-kape #13
Sep 16 23:26:52 linux kernel: [ 179.152675] Call Trace:
Sep 16 23:26:52 linux kernel: [ 179.152698] [<c011add8>] __might_sleep+0xb1/0xb6
Sep 16 23:26:52 linux kernel: [ 179.152724] [<c0399189>] rt_spin_lock+0x23/0x56
Sep 16 23:26:52 linux kernel: [ 179.152748] [<c0398e4a>] __account_scheduler_latency+0x66/0x19b
Sep 16 23:26:52 linux kernel: [ 179.152778] [<c018d8f7>] ? poll_schedule_timeout+0x27/0x3e
Sep 16 23:26:52 linux kernel: [ 179.152802] [<c018e3ab>] ? do_select+0x4b5/0x4f0
Sep 16 23:26:52 linux kernel: [ 179.152823] [<c018e5ab>] ? core_sys_select+0x1c5/0x299
Sep 16 23:26:52 linux kernel: [ 179.152846] [<c018e829>] ? sys_select+0x6a/0x86
Sep 16 23:26:52 linux kernel: [ 179.152868] [<c01027f0>] ? sysenter_do_call+0x12/0x22
Sep 16 23:26:52 linux kernel: [ 179.152894] [<c011d3ce>] enqueue_entity+0x15f/0x2df
Sep 16 23:26:52 linux kernel: [ 179.152919] [<c011d56d>] enqueue_task_fair+0x1f/0x59
Sep 16 23:26:52 linux kernel: [ 179.152943] [<c0119bf3>] enqueue_task+0x4f/0x5b
Sep 16 23:26:52 linux kernel: [ 179.152965] [<c0119d26>] activate_task+0x1f/0x24
Sep 16 23:26:52 linux kernel: [ 179.152988] [<c011de98>] try_to_wake_up+0xb9/0x1b3
Sep 16 23:26:52 linux kernel: [ 179.153012] [<c011df9f>] default_wake_function+0xd/0xf
Sep 16 23:26:52 linux kernel: [ 179.153035] [<c018e940>] pollwake+0x57/0x63
Sep 16 23:26:52 linux kernel: [ 179.153057] [<c011df92>] ? default_wake_function+0x0/0xf
Sep 16 23:26:52 linux kernel: [ 179.153082] [<c011a198>] __wake_up_common+0x34/0x5c
Sep 16 23:26:52 linux kernel: [ 179.153106] [<c011b3bf>] __wake_up_sync_key+0x30/0x3e
Sep 16 23:26:52 linux kernel: [ 179.153130] [<c011b3d7>] __wake_up_sync+0xa/0xd
Sep 16 23:26:52 linux kernel: [ 179.153155] [<c038b989>] unix_write_space+0x43/0x6d
Sep 16 23:26:52 linux kernel: [ 179.153178] [<c0330a41>] sock_wfree+0x2b/0x3e
Sep 16 23:26:52 linux kernel: [ 179.153201] [<c033399b>] skb_release_head_state+0x39/0x84
Sep 16 23:26:52 linux kernel: [ 179.153225] [<c03337c7>] __kfree_skb+0xb/0x6d
Sep 16 23:26:52 linux kernel: [ 179.153246] [<c0333873>] kfree_skb+0x24/0x26
Sep 16 23:26:52 linux kernel: [ 179.153268] [<c038b467>] unix_stream_recvmsg+0x339/0x447
Sep 16 23:26:52 linux kernel: [ 179.153298] [<c032dc71>] sock_aio_read+0x10c/0x116
Sep 16 23:26:52 linux kernel: [ 179.153325] [<c0181c23>] do_sync_read+0xa8/0xe3
Sep 16 23:26:52 linux kernel: [ 179.153350] [<c0133045>] ? autoremove_wake_function+0x0/0x2f
Sep 16 23:26:52 linux kernel: [ 179.153376] [<c013a164>] ? ktime_get_ts+0xc0/0xca
Sep 16 23:26:52 linux kernel: [ 179.153400] [<c022943c>] ? copy_to_user+0x38/0x10e
Sep 16 23:26:52 linux kernel: [ 179.153426] [<c01fb9e4>] ? security_file_permission+0xf/0x11
Sep 16 23:26:52 linux kernel: [ 179.153451] [<c0181cf6>] ? rw_verify_area+0x98/0xbb
Sep 16 23:26:52 linux kernel: [ 179.153475] [<c01826f6>] vfs_read+0x98/0x139
Sep 16 23:26:52 linux kernel: [ 179.153498] [<c018282e>] sys_read+0x3b/0x60
Sep 16 23:26:52 linux kernel: [ 179.153519] [<c01027f0>] sysenter_do_call+0x12/0x22
Sep 16 23:26:53 linux kernel: [ 180.176745] BUG: sleeping function called from invalid context at kernel/rtmutex.c:684
Sep 16 23:26:53 linux kernel: [ 180.176780] in_atomic(): 1, irqs_disabled(): 1, pid: 2721, name: smpppd
Sep 16 23:26:53 linux kernel: [ 180.176808] Pid: 2721, comm: smpppd Not tainted 2.6.31-rt10-kape #13
Sep 16 23:26:53 linux kernel: [ 180.176832] Call Trace:
Sep 16 23:26:53 linux kernel: [ 180.176854] [<c011add8>] __might_sleep+0xb1/0xb6
Sep 16 23:26:53 linux kernel: [ 180.176879] [<c0399189>] rt_spin_lock+0x23/0x56
Sep 16 23:26:53 linux kernel: [ 180.176903] [<c0398e4a>] __account_scheduler_latency+0x66/0x19b
Sep 16 23:26:53 linux kernel: [ 180.176932] [<c018d8f7>] ? poll_schedule_timeout+0x27/0x3e
Sep 16 23:26:53 linux kernel: [ 180.176956] [<c018e3ab>] ? do_select+0x4b5/0x4f0
Sep 16 23:26:53 linux kernel: [ 180.176977] [<c018e5ab>] ? core_sys_select+0x1c5/0x299
Sep 16 23:26:53 linux kernel: [ 180.177000] [<c018e829>] ? sys_select+0x6a/0x86
Sep 16 23:26:53 linux kernel: [ 180.177022] [<c01027f0>] ? sysenter_do_call+0x12/0x22
Sep 16 23:26:53 linux kernel: [ 180.177048] [<c011d3ce>] enqueue_entity+0x15f/0x2df
Sep 16 23:26:53 linux kernel: [ 180.177073] [<c011d56d>] enqueue_task_fair+0x1f/0x59
Sep 16 23:26:53 linux kernel: [ 180.177096] [<c0119bf3>] enqueue_task+0x4f/0x5b
Sep 16 23:26:53 linux kernel: [ 180.177118] [<c0119d26>] activate_task+0x1f/0x24
Sep 16 23:26:53 linux kernel: [ 180.177141] [<c011de98>] try_to_wake_up+0xb9/0x1b3
Sep 16 23:26:53 linux kernel: [ 180.177166] [<c011df9f>] default_wake_function+0xd/0xf
Sep 16 23:26:53 linux kernel: [ 180.177189] [<c018e940>] pollwake+0x57/0x63
Sep 16 23:26:53 linux kernel: [ 180.177211] [<c011df92>] ? default_wake_function+0x0/0xf
Sep 16 23:26:53 linux kernel: [ 180.177236] [<c011a198>] __wake_up_common+0x34/0x5c
Sep 16 23:26:53 linux kernel: [ 180.177260] [<c011b3bf>] __wake_up_sync_key+0x30/0x3e
Sep 16 23:26:53 linux kernel: [ 180.177284] [<c011b3d7>] __wake_up_sync+0xa/0xd
Sep 16 23:26:53 linux kernel: [ 180.177308] [<c038b989>] unix_write_space+0x43/0x6d
Sep 16 23:26:53 linux kernel: [ 180.177332] [<c0330a41>] sock_wfree+0x2b/0x3e
Sep 16 23:26:53 linux kernel: [ 180.177355] [<c033399b>] skb_release_head_state+0x39/0x84
Sep 16 23:26:53 linux kernel: [ 180.177379] [<c03337c7>] __kfree_skb+0xb/0x6d
Sep 16 23:26:53 linux kernel: [ 180.177400] [<c0333873>] kfree_skb+0x24/0x26
Sep 16 23:26:53 linux kernel: [ 180.177422] [<c038b467>] unix_stream_recvmsg+0x339/0x447
Sep 16 23:26:53 linux kernel: [ 180.177451] [<c032dc71>] sock_aio_read+0x10c/0x116
Sep 16 23:26:53 linux kernel: [ 180.177478] [<c0181c23>] do_sync_read+0xa8/0xe3
Sep 16 23:26:53 linux kernel: [ 180.177503] [<c0133045>] ? autoremove_wake_function+0x0/0x2f
Sep 16 23:26:53 linux kernel: [ 180.177529] [<c013a164>] ? ktime_get_ts+0xc0/0xca
Sep 16 23:26:53 linux kernel: [ 180.177552] [<c022943c>] ? copy_to_user+0x38/0x10e
Sep 16 23:26:53 linux kernel: [ 180.177577] [<c01fb9e4>] ? security_file_permission+0xf/0x11
Sep 16 23:26:53 linux kernel: [ 180.177603] [<c0181cf6>] ? rw_verify_area+0x98/0xbb
Sep 16 23:26:53 linux kernel: [ 180.177627] [<c01826f6>] vfs_read+0x98/0x139
Sep 16 23:26:53 linux kernel: [ 180.177649] [<c018282e>] sys_read+0x3b/0x60
Sep 16 23:26:53 linux kernel: [ 180.177670] [<c01027f0>] sysenter_do_call+0x12/0x22


Attachments:
messages (20.30 kB)
.config (62.00 kB)
Download all attachments

2009-09-17 07:29:57

by Thomas Gleixner

[permalink] [raw]
Subject: Re: [BUG] 2.6.31-rt10

Knut,

On Thu, 17 Sep 2009, Knut Petersen wrote:
> Hi Thomas!
>
> Well, I thought it was a good idea to test the rt patch.
> Never did that before. Cyclictest output looked great, but then
> i tried to do some audio recording. I used "chrt 80 arecord ..."
> to record at 96kHz/16bit stereo from an RME Digi96/PAD.
> That seemed to work fine on an idle machine. So I put
> some extra load on the machine. A "make -j 16" kernel
> compilation locks keyboard and screen within minutes,
> no matter if X is running or not. The attached part of
> /var/log/messages shows two of those events - I deleted all
> but the very first log entries.

Duh, never used latencytop on RT. Patch below should fix that.

Thanks,

tglx
---
diff --git a/kernel/latencytop.c b/kernel/latencytop.c
index ca07c5c..34311e1 100644
--- a/kernel/latencytop.c
+++ b/kernel/latencytop.c
@@ -59,7 +59,7 @@
#include <linux/slab.h>
#include <linux/stacktrace.h>

-static DEFINE_SPINLOCK(latency_lock);
+static DEFINE_ATOMIC_SPINLOCK(latency_lock);

#define MAXLR 128
static struct latency_record latency_record[MAXLR];
@@ -73,19 +73,19 @@ void clear_all_latency_tracing(struct task_struct *p)
if (!latencytop_enabled)
return;

- spin_lock_irqsave(&latency_lock, flags);
+ atomic_spin_lock_irqsave(&latency_lock, flags);
memset(&p->latency_record, 0, sizeof(p->latency_record));
p->latency_record_count = 0;
- spin_unlock_irqrestore(&latency_lock, flags);
+ atomic_spin_unlock_irqrestore(&latency_lock, flags);
}

static void clear_global_latency_tracing(void)
{
unsigned long flags;

- spin_lock_irqsave(&latency_lock, flags);
+ atomic_spin_lock_irqsave(&latency_lock, flags);
memset(&latency_record, 0, sizeof(latency_record));
- spin_unlock_irqrestore(&latency_lock, flags);
+ atomic_spin_unlock_irqrestore(&latency_lock, flags);
}

static void __sched
@@ -191,7 +191,7 @@ __account_scheduler_latency(struct task_struct *tsk, int usecs, int inter)
lat.max = usecs;
store_stacktrace(tsk, &lat);

- spin_lock_irqsave(&latency_lock, flags);
+ atomic_spin_lock_irqsave(&latency_lock, flags);

account_global_scheduler_latency(tsk, &lat);

@@ -233,7 +233,7 @@ __account_scheduler_latency(struct task_struct *tsk, int usecs, int inter)
memcpy(&tsk->latency_record[i], &lat, sizeof(struct latency_record));

out_unlock:
- spin_unlock_irqrestore(&latency_lock, flags);
+ atomic_spin_unlock_irqrestore(&latency_lock, flags);
}

static int lstats_show(struct seq_file *m, void *v)