Bug#822936: Occurs on Stretch
[814247.295392] INFO: rcu_sched self-detected stall on CPU [814247.295421] INFO: rcu_sched detected stalls on CPUs/tasks: [814247.295444] 0-...: (1 ticks this GP) idle=d81/2/0 softirq=3762400/3762400 fqs=1 [814247.295448] [814247.295456] (detected by 1, t=27866 jiffies, g=2973706, c=2973705, q=0) [814247.295461] Task dump for CPU 0: [814247.295466] swapper/0 R [814247.295469] running task [814247.295476] 0 0 0 0x0008 [814247.295483] da7c55f0 [814247.295487] da761fbc [814247.295491] [814247.295494] da76007b [814247.295497] c58b007b [814247.295501] 000200d8 [814247.295504] da7c00e0 [814247.295507] ff5e [814247.295512] da480944 [814247.295516] 0060 [814247.295519] 00200246 [814247.295522] a575 [814247.295526] c58a7700 [814247.295529] 0002e473 [814247.295532] [814247.295535] da7c54a0 [814247.295540] 0004 [814247.295543] ff9d7fe0 [814247.295547] c58bc430 [814247.295550] 0002e473 [814247.295553] [814247.295556] ff9d7fe0 [814247.295559] da7c54a0 [814247.295563] da761fe4 [814247.295568] Call Trace: [814247.295605] [] ? cpuidle_enter_state+0x134/0x330 [814247.295623] [] ? cpu_startup_entry+0x135/0x220 [814247.295640] [] ? start_kernel+0x39d/0x3b4 [814247.295656] rcu_sched kthread starved for 27864 jiffies! g2973706 c2973705 f0x0 RCU_GP_WAIT_FQS(3) ->state=0x1 [814247.295661] rcu_sched S [814247.295668] 0 7 2 0x [814247.295673] [814247.295677] f79f2ac0 [814247.295680] f65c8c00 [814247.295684] f751bed4 [814247.295687] da5a9e7e [814247.295690] f751bebc [814247.295694] da0d4cd7 [814247.295697] da8b8280 [814247.295702] 0051bec4 [814247.295706] f7518db8 [814247.295709] f7520a00 [814247.295712] f7520a00 [814247.295715] f79f2ac0 [814247.295719] f75189c0 [814247.295722] f751bee0 [814247.295726] f75189c0 [814247.295731] f79ec900 [814247.295734] f751bf00 [814247.295737] f751bee0 [814247.295741] da5aa3ae [814247.295744] f79ec900 [814247.295747] f751bf28 [814247.295750] da5ad08f [814247.295763] da782140 [814247.295767] Call Trace: [814247.295788] [] ? __schedule+0x25e/0x760 [814247.295805] [] ? lock_timer_base+0x67/0x80 [814247.295818] [] ? schedule+0x2e/0x80 [814247.295832] [] ? schedule_timeout+0x12f/0x300 [814247.295846] [] ? del_timer_sync+0x50/0x50 [814247.295858] [] ? rcu_gp_kthread+0x4a1/0x7c0 [814247.295873] [] ? kthread+0xb4/0xd0 [814247.295884] [] ? rcu_note_context_switch+0xf0/0xf0 [814247.295896] [] ? kthread_park+0x50/0x50 [814247.295908] [] ? ret_from_fork+0x1b/0x28 [814247.297335] 0-...: (1 ticks this GP) idle=d81/2/0 softirq=3762400/3762400 fqs=1 [814247.297772] (t=27867 jiffies g=2973706 c=2973705 q=2) [814247.298125] rcu_sched kthread starved for 27865 jiffies! g2973706 c2973705 f0x2 RCU_GP_WAIT_FQS(3) ->state=0x0 [814247.298718] rcu_sched R running task0 7 2 0x [814247.298741] f79f2ac0 f65c8c00 f751bed4 da5a9e7e f751bebc da0d4cd7 da8b8280 [814247.298774] 0051bec4 f7518db8 f7520a00 f7520a00 f79f2ac0 f75189c0 f751bee0 f75189c0 [814247.298805] f79ec900 f751bf00 f751bee0 da5aa3ae f79ec900 f751bf28 da5ad08f da782140 [814247.298835] Call Trace: [814247.298871] [] ? __schedule+0x25e/0x760 [814247.298893] [] ? lock_timer_base+0x67/0x80 [814247.298909] [] ? schedule+0x2e/0x80 [814247.298924] [] ? schedule_timeout+0x12f/0x300 [814247.298941] [] ? del_timer_sync+0x50/0x50 [814247.298957] [] ? rcu_gp_kthread+0x4a1/0x7c0 [814247.298976] [] ? kthread+0xb4/0xd0 [814247.298990] [] ? rcu_note_context_switch+0xf0/0xf0 [814247.299005] [] ? kthread_park+0x50/0x50 [814247.299019] [] ? ret_from_fork+0x1b/0x28 [814247.299030] Task dump for CPU 0: [814247.299037] swapper/0 R running task0 0 0 0x0008 [814247.299054] f742fe54 da091941 da6b35df 0008 da782140 [814247.299082] da782140 f742fe6c da166884 00200087 f79df300 da782140 da782140 [814247.299112] f742feb8 da0d20e1 da6a9ed0 6cdb 002d600a 002d6009 0002 f79deac0 [814247.299142] Call Trace: [814247.299150] [814247.299172] [] ? sched_show_task+0xf1/0x160 [814247.299186] [] ? rcu_dump_cpu_stacks+0x79/0x95 [814247.299186] [] ? rcu_check_callbacks+0x631/0x780 [814247.299186] [] ? account_process_tick+0x5a/0x150 [814247.299186] [] ? update_process_times+0x28/0x50 [814247.299186] [] ? tick_sched_handle.isra.11+0x26/0x60 [814247.299186] [] ? tick_sched_timer+0x3a/0x80 [814247.299186] [] ? __remove_hrtimer+0x3f/0x80 [814247.299186] [] ? __hrtimer_run_queues+0xc0/0x260 [814247.299186] [] ? tick_sched_handle.isra.11+0x60/0x60 [814247.299186] [] ? hrtimer_interrupt+0x93/0x1a0 [814247.299186] [] ? timer_interrupt+0x12/0x20 [814247.299186] [] ? __handle_irq_event_percpu+0x78/0x190 [814247.299186] [] ? handle_irq_event_percpu+0x2b/0x70 [814247.299186] [] ? handle_irq_event+0x2f/0x50 [814247.299186] [] ? handle_edge_irq+0x6d/0x120 [814247.299186] [] ? handle_level_irq+0xe0/0x
Bug#822936: Occurs on Stretch
# uname -v #1 SMP Debian 4.9.51-1 (2017-09-28) [256204.725417] INFO: rcu_sched self-detected stall on CPU [256204.725450] INFO: rcu_sched detected stalls on CPUs/tasks: [256204.725473] 0-...: (2 GPs behind) idle=52b/2/0 softirq=1717403/1717404 fqs=0 [256204.725477] [256204.725485] (detected by 1, t=18640 jiffies, g=600410, c=600409, q=1) [256204.725490] Task dump for CPU 0: [256204.725495] swapper/0 R [256204.725498] running task [256204.725505] 0 0 0 0x0008 [256204.725512] d07c55f0 [256204.725517] d0761fbc [256204.725520] [256204.725523] d076007b [256204.725527] f3ec007b [256204.725530] 00d8 [256204.725533] d07c00e0 [256204.725537] ff5e [256204.725542] d0480944 [256204.725545] 0060 [256204.725548] 00200246 [256204.725552] 000ad6bb [256204.72] f3ea7231 [256204.725558] e8f2 [256204.725561] [256204.725565] d07c54a0 [256204.725570] 0004 [256204.725573] ff9d7fe0 [256204.725576] f3ec141f [256204.725580] e8f2 [256204.725583] [256204.725586] ff9d7fe0 [256204.725589] d07c54a0 [256204.725593] d0761fe4 [256204.725598] Call Trace: [256204.725653] [] ? cpuidle_enter_state+0x134/0x330 [256204.725673] [] ? cpu_startup_entry+0x135/0x220 [256204.725685] [] ? start_kernel+0x39d/0x3b4 [256204.725696] rcu_sched kthread starved for 18640 jiffies! g600410 c600409 f0x0 RCU_GP_WAIT_FQS(3) ->state=0x1 [256204.725701] rcu_sched S [256204.725705] 0 7 2 0x [256204.725709] [256204.725711] f79f2ac0 [256204.725713] f6c9be00 [256204.725715] f751bed4 [256204.725717] d05a9e7e [256204.725719] f751bebc [256204.725727] d00d4cd7 [256204.725729] d08b8280 [256204.725732] 0051bec4 [256204.725734] f7518db8 [256204.725736] f7520a00 [256204.725737] f7520a00 [256204.725739] f79f2ac0 [256204.725741] f75189c0 [256204.725744] f751bee0 [256204.725746] f75189c0 [256204.725749] f79ec900 [256204.725750] f751bf00 [256204.725752] f751bee0 [256204.725754] d05aa3ae [256204.725756] f79ec900 [256204.725758] f751bf28 [256204.725760] d05ad08f [256204.725762] 0002 [256204.725765] Call Trace: [256204.725779] [] ? __schedule+0x25e/0x760 [256204.725790] [] ? lock_timer_base+0x67/0x80 [256204.725798] [] ? schedule+0x2e/0x80 [256204.725807] [] ? schedule_timeout+0x12f/0x300 [256204.725815] [] ? del_timer_sync+0x50/0x50 [256204.725823] [] ? rcu_gp_kthread+0x4a1/0x7c0 [256204.725833] [] ? kthread+0xb4/0xd0 [256204.725839] [] ? rcu_note_context_switch+0xf0/0xf0 [256204.725847] [] ? kthread_park+0x50/0x50 [256204.725854] [] ? ret_from_fork+0x1b/0x28 [256204.726675] 0-...: (2 GPs behind) idle=52b/2/0 softirq=1717403/1717404 fqs=0 [256204.726951] (t=18640 jiffies g=600410 c=600409 q=1) [256204.727207] rcu_sched kthread starved for 18640 jiffies! g600410 c600409 f0x2 RCU_GP_WAIT_FQS(3) ->state=0x0 [256204.727557] rcu_sched R running task0 7 2 0x [256204.727579] f79f2ac0 f6c9be00 f751bed4 d05a9e7e f751bebc d00d4cd7 d08b8280 [256204.727599] 0051bec4 f7518db8 f7520a00 f7520a00 f79f2ac0 f75189c0 f751bee0 f75189c0 [256204.727618] f79ec900 f751bf00 f751bee0 d05aa3ae f79ec900 f751bf28 d05ad08f 0002 [256204.727638] Call Trace: [256204.727667] [] ? __schedule+0x25e/0x760 [256204.727681] [] ? lock_timer_base+0x67/0x80 [256204.727693] [] ? schedule+0x2e/0x80 [256204.727704] [] ? schedule_timeout+0x12f/0x300 [256204.727714] [] ? del_timer_sync+0x50/0x50 [256204.727722] [] ? rcu_gp_kthread+0x4a1/0x7c0 [256204.727733] [] ? kthread+0xb4/0xd0 [256204.727741] [] ? rcu_note_context_switch+0xf0/0xf0 [256204.727750] [] ? kthread_park+0x50/0x50 [256204.727758] [] ? ret_from_fork+0x1b/0x28 [256204.727769] Task dump for CPU 0: [256204.727774] swapper/0 R running task0 0 0 0x0008 [256204.727784] f742fe54 d0091941 d06b35df 0008 d0782140 [256204.727802] d0782140 f742fe6c d0166884 00200087 f79df300 d0782140 d0782140 [256204.727820] f742feb8 d00d20e1 d06a9ed0 48d0 0009295a 00092959 0001 f79deac0 [256204.727839] Call Trace: [256204.727848] [256204.727872] [] ? sched_show_task+0xf1/0x160 [256204.727892] [] ? rcu_dump_cpu_stacks+0x79/0x95 [256204.727908] [] ? rcu_check_callbacks+0x631/0x780 [256204.727922] [] ? account_process_tick+0x5a/0x150 [256204.727937] [] ? update_process_times+0x28/0x50 [256204.727949] [] ? tick_sched_handle.isra.11+0x26/0x60 [256204.727956] [] ? tick_sched_timer+0x3a/0x80 [256204.727965] [] ? __remove_hrtimer+0x3f/0x80 [256204.727976] [] ? __hrtimer_run_queues+0xc0/0x260 [256204.727987] [] ? tick_sched_handle.isra.11+0x60/0x60 [256204.727999] [] ? hrtimer_interrupt+0x93/0x1a0 [256204.728012] [] ? timer_interrupt+0x12/0x20 [256204.728024] [] ? __handle_irq_event_percpu+0x78/0x190 [256204.728034] [] ? handle_irq_event_percpu+0x2b/0x70 [256204.728041] [] ? handle_irq_event+0x2f/0x50 [256204.728049] [] ? handle_edge_irq+0x6d/0x120 [256204.7280
Bug#822936: Occurs on Stretch
linux-image-4.9.0-4-686-pae 4.9.51-1 Oct 27 08:15:19 puar kernel: [26080.447922] INFO: rcu_sched self-detected stall on CPU Oct 27 08:15:19 puar kernel: [26080.447946] INFO: rcu_sched self-detected stall on CPU Oct 27 08:15:19 puar kernel: [26080.447964] 1-...: (1 GPs behind) idle=c41/1/0 softirq=1143278/1143279 fqs=0 Oct 27 08:15:19 puar kernel: [26080.447968] Oct 27 08:15:19 puar kernel: [26080.447976] (t=9226 jiffies g=487334 c=487333 q=1) Oct 27 08:15:19 puar kernel: [26080.447988] rcu_sched kthread starved for 9226 jiffies! g487334 c487333 f0x0 RCU_GP_WAIT_FQS(3) ->state=0x1 Oct 27 08:15:19 puar kernel: [26080.447993] rcu_sched S Oct 27 08:15:19 puar kernel: [26080.448001] 0 7 2 0x Oct 27 08:15:19 puar kernel: Oct 27 08:15:19 puar kernel: [26080.448012] f300a100 f68b8800 f751bed4 c75a9e7e f751bebc c70d4cd7 c78b8280 0051bec4 Oct 27 08:15:19 puar kernel: [26080.448041] f7518db8 c7767680 f300a100 f79deac0 f75189c0 f751bee0 f75189c0 f79d8900 Oct 27 08:15:19 puar kernel: [26080.448069] f751bf00 f751bee0 c75aa3ae f79d8900 f751bf28 c75ad08f 0002Call Trace: Oct 27 08:15:19 puar kernel: [26080.448126] [] ? __schedule+0x25e/0x760 Oct 27 08:15:19 puar kernel: [26080.448144] [] ? lock_timer_base+0x67/0x80 Oct 27 08:15:19 puar kernel: [26080.448158] [] ? schedule+0x2e/0x80 Oct 27 08:15:19 puar kernel: [26080.448172] [] ? schedule_timeout+0x12f/0x300 Oct 27 08:15:19 puar kernel: [26080.448186] [] ? del_timer_sync+0x50/0x50 Oct 27 08:15:19 puar kernel: [26080.448199] [] ? rcu_gp_kthread+0x4a1/0x7c0 Oct 27 08:15:19 puar kernel: [26080.448214] [] ? kthread+0xb4/0xd0 Oct 27 08:15:19 puar kernel: [26080.448225] [] ? rcu_note_context_switch+0xf0/0xf0 Oct 27 08:15:19 puar kernel: [26080.448237] [] ? kthread_park+0x50/0x50 Oct 27 08:15:19 puar kernel: [26080.448248] [] ? ret_from_fork+0x1b/0x28 Oct 27 08:15:19 puar kernel: [26080.448299] Task dump for CPU 0: Oct 27 08:15:19 puar kernel: [26080.448307] swapper/0 R Oct 27 08:15:19 puar kernel: [26080.448310] running task0 0 0 0x Oct 27 08:15:19 puar kernel: c77c55f0 Oct 27 08:15:19 puar kernel: [26080.448327] c7761fbc c776007b bc31007b 00d8 c77c00e0 ff5e c7480944 Oct 27 08:15:19 puar kernel: [26080.448355] 0060 0246 1667 bc2ca3cb 17af c77c54a0 0004 Oct 27 08:15:19 puar kernel: [26080.448382] ff9d7fe0 bc314afc 17af ff9d7fe0 c77c54a0 c7761fe4Call Trace: Oct 27 08:15:19 puar kernel: [26080.448435] [] ? cpuidle_enter_state+0x134/0x330 Oct 27 08:15:19 puar kernel: [26080.448451] [] ? cpu_startup_entry+0x135/0x220 Oct 27 08:15:19 puar kernel: [26080.448467] [] ? start_kernel+0x39d/0x3b4 Oct 27 08:15:19 puar kernel: [26080.448473] Task dump for CPU 1: Oct 27 08:15:19 puar kernel: [26080.448478] swapper/1 R Oct 27 08:15:19 puar kernel: [26080.448481] running task0 0 1 0x0008 Oct 27 08:15:19 puar kernel: f7527dcc Oct 27 08:15:19 puar kernel: [26080.448495] c7091941 c76b35df 0001 0008 c7782140 c7782140 Oct 27 08:15:19 puar kernel: [26080.448522] 0001 f7527de4 c7166884 0087 f79f3300 c7782140 c7782140 f7527e30 Oct 27 08:15:19 puar kernel: [26080.448550] c70d20e1 c76a9ed0 240a 00076fa6 00076fa5 0001 603ea661Call Trace: Oct 27 08:15:19 puar kernel: [26080.448593] [] ? sched_show_task+0xf1/0x160 Oct 27 08:15:19 puar kernel: [26080.448612] [] ? rcu_dump_cpu_stacks+0x79/0x95 Oct 27 08:15:19 puar kernel: [26080.448633] [] ? rcu_check_callbacks+0x631/0x780 Oct 27 08:15:19 puar kernel: [26080.448652] [] ? update_process_times+0x28/0x50 Oct 27 08:15:19 puar kernel: [26080.448665] [] ? tick_sched_handle.isra.11+0x26/0x60 Oct 27 08:15:19 puar kernel: [26080.448675] [] ? tick_sched_timer+0x3a/0x80 Oct 27 08:15:19 puar kernel: [26080.448688] [] ? __remove_hrtimer+0x3f/0x80 Oct 27 08:15:19 puar kernel: [26080.448700] [] ? __hrtimer_run_queues+0xc0/0x260 Oct 27 08:15:19 puar kernel: [26080.448712] [] ? tick_sched_handle.isra.11+0x60/0x60 Oct 27 08:15:19 puar kernel: [26080.448725] [] ? hrtimer_interrupt+0x93/0x1a0 Oct 27 08:15:19 puar kernel: [26080.448742] [] ? smp_apic_timer_interrupt+0x33/0x50 Oct 27 08:15:19 puar kernel: [26080.448753] [] ? apic_timer_interrupt+0x34/0x3c Oct 27 08:15:19 puar kernel: [26080.448772] [] ? cpuidle_enter_state+0x134/0x330 Oct 27 08:15:19 puar kernel: [26080.448787] [] ? cpu_startup_entry+0x135/0x220 Oct 27 08:15:19 puar kernel: [26080.448801] [] ? start_secondary+0x155/0x1b0 Oct 27 08:15:19 puar kernel: [26080.449772] 0-...: (1 ticks this GP) idle=3e1/2/0 softirq=1245237/1245237 fqs=0 Oct 27 08:15:19 puar kernel: [26080.450190] (t=9227 jiffies g=487334 c=487333 q=3) Oct 27 08:15:19 puar kernel: [26080.450514] rcu_sched kthread starved for 9227 jiffies! g487334 c487333 f0x0 RCU_GP_WAIT_FQS(3) ->state=0x0 Oct 27 08:15:19 puar kernel: [26080.451087] rcu_sched R running task0 7 2 0x Oct 27 08
