====================================== | [ 758.281606] nipa-hw-worker: drivers/net:napi_threaded.py: timed out | [ 758.285449] nipa-hw-worker: [4/38] Retrying drivers/net:napi_threaded.py | [ 758.446418] kselftest: Running tests in drivers/net | [ 920.115868] kworker/u129:2 (187) used greatest stack depth: 21192 bytes left [ 989.749559] INFO: task python3:4978 blocked for more than 122 seconds. [ 989.757787] Not tainted 7.0.0-rc5-rnof-gd6ab535c2bff #1 [ 989.765035] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 989.774624] task:python3 state:D stack:25312 pid:4978 tgid:4978 ppid:4977 task_flags:0x400100 flags:0x00080000 [ 989.787819] Call Trace: [ 989.791357] [ 989.794507] __schedule+0xad9/0x1490 [ 989.799323] ? io_schedule_timeout+0x130/0x130 [ 989.805108] ? find_held_lock+0x2b/0x80 [ 989.810213] ? __lock_release.isra.0+0x59/0x170 [ 989.816096] schedule+0xd4/0x260 [ 989.820506] schedule_preempt_disabled+0x18/0x30 [ 989.826485] __mutex_lock+0xd76/0x1f20 [ 989.831491] ? threaded_store+0x132/0x220 [ 989.836791] ? ww_mutex_lock+0x160/0x160 [ 989.841978] ? selinux_inode_free_security+0x310/0x310 [ 989.848546] ? cap_capable+0x1ed/0x3f0 [ 989.853551] ? sysfs_file_ops+0x140/0x140 [ 989.858849] ? threaded_store+0x132/0x220 [ 989.864143] threaded_store+0x132/0x220 [ 989.869245] ? tx_maxrate_store+0x4e0/0x4e0 [ 989.874731] ? sysfs_file_ops+0x140/0x140 [ 989.880028] ? sysfs_file_kobj+0xbd/0x1e0 [ 989.885322] ? sysfs_kf_write+0x65/0x160 [ 989.890521] kernfs_fop_write_iter+0x335/0x510 [ 989.896305] ? kernfs_ops+0xf0/0xf0 [ 989.901017] new_sync_write+0x308/0x6f0 [ 989.906109] ? new_sync_read+0x6f0/0x6f0 [ 989.911309] ? lock_acquire.part.0+0xbc/0x260 [ 989.916992] ? ksys_write+0xff/0x200 [ 989.921804] vfs_write+0x62a/0x980 [ 989.926417] ksys_write+0xff/0x200 [ 989.931029] ? __ia32_sys_read+0xc0/0xc0 [ 989.936228] ? kernfs_fop_llseek+0x160/0x1f0 [ 989.941815] ? rcu_is_watching+0x15/0xd0 [ 989.947015] do_syscall_64+0xf3/0x5e0 [ 989.951914] ? exc_page_fault+0x9e/0xb0 [ 989.957014] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 989.963479] RIP: 0033:0x7f7d8be03c5e [ 989.968288] RSP: 002b:00007ffc9b459500 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 989.977591] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f7d8be03c5e [ 989.986397] RDX: 0000000000000001 RSI: 0000555c9f1b1110 RDI: 0000000000000007 [ 989.995205] RBP: 00007ffc9b459510 R08: 0000000000000000 R09: 0000000000000000 [ 990.004013] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f7d8bc9ee80 [ 990.012822] R13: 0000555c9f1b1110 R14: 0000000000000007 R15: 00007f7d8c504b98 | [ 990.421348] #1: ff1100013b430288 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x247/0x510 | [ 990.432308] #2: ff110001207c50f0 (kn->active#127){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 990.443462] #3: ff11000120829d00 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 990.453548] | [ 990.456011] =============================================\x0a [ 1112.630773] INFO: task python3:4978 blocked for more than 245 seconds. [ 1112.638918] Not tainted 7.0.0-rc5-rnof-gd6ab535c2bff #1 [ 1112.646168] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1112.655755] task:python3 state:D stack:25312 pid:4978 tgid:4978 ppid:4977 task_flags:0x400100 flags:0x00080000 [ 1112.669029] Call Trace: [ 1112.672567] [ 1112.675719] __schedule+0xad9/0x1490 [ 1112.680535] ? io_schedule_timeout+0x130/0x130 [ 1112.686320] ? find_held_lock+0x2b/0x80 [ 1112.691426] ? __lock_release.isra.0+0x59/0x170 [ 1112.697312] schedule+0xd4/0x260 [ 1112.701729] schedule_preempt_disabled+0x18/0x30 [ 1112.707710] __mutex_lock+0xd76/0x1f20 [ 1112.712715] ? threaded_store+0x132/0x220 [ 1112.718016] ? ww_mutex_lock+0x160/0x160 [ 1112.723213] ? selinux_inode_free_security+0x310/0x310 [ 1112.729782] ? cap_capable+0x1ed/0x3f0 [ 1112.734790] ? sysfs_file_ops+0x140/0x140 [ 1112.740089] ? threaded_store+0x132/0x220 [ 1112.745377] threaded_store+0x132/0x220 [ 1112.750480] ? tx_maxrate_store+0x4e0/0x4e0 [ 1112.755970] ? sysfs_file_ops+0x140/0x140 [ 1112.761266] ? sysfs_file_kobj+0xbd/0x1e0 [ 1112.766561] ? sysfs_kf_write+0x65/0x160 [ 1112.771760] kernfs_fop_write_iter+0x335/0x510 [ 1112.777542] ? kernfs_ops+0xf0/0xf0 [ 1112.782255] new_sync_write+0x308/0x6f0 [ 1112.787360] ? new_sync_read+0x6f0/0x6f0 [ 1112.792562] ? lock_acquire.part.0+0xbc/0x260 [ 1112.798247] ? ksys_write+0xff/0x200 [ 1112.803058] vfs_write+0x62a/0x980 [ 1112.807670] ksys_write+0xff/0x200 [ 1112.812281] ? __ia32_sys_read+0xc0/0xc0 [ 1112.817480] ? kernfs_fop_llseek+0x160/0x1f0 [ 1112.823070] ? rcu_is_watching+0x15/0xd0 [ 1112.828262] do_syscall_64+0xf3/0x5e0 [ 1112.833160] ? exc_page_fault+0x9e/0xb0 [ 1112.838330] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1112.844882] RIP: 0033:0x7f7d8be03c5e [ 1112.849766] RSP: 002b:00007ffc9b459500 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1112.859064] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f7d8be03c5e [ 1112.867869] RDX: 0000000000000001 RSI: 0000555c9f1b1110 RDI: 0000000000000007 [ 1112.876674] RBP: 00007ffc9b459510 R08: 0000000000000000 R09: 0000000000000000 [ 1112.885482] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f7d8bc9ee80 [ 1112.894360] R13: 0000555c9f1b1110 R14: 0000000000000007 R15: 00007f7d8c504b98 | [ 1113.316362] #2: ff110001207c50f0 (kn->active#127){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1113.327519] #3: ff11000120829d00 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1113.337614] 4 locks held by (exec-inner)/6059: | [ 1113.343399] | [ 1113.345877] =============================================\x0a [ 1235.511002] INFO: task python3:4978 blocked for more than 368 seconds. [ 1235.519152] Not tainted 7.0.0-rc5-rnof-gd6ab535c2bff #1 [ 1235.526403] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1235.535994] task:python3 state:D stack:25312 pid:4978 tgid:4978 ppid:4977 task_flags:0x400100 flags:0x00080002 [ 1235.549202] Call Trace: [ 1235.552743] [ 1235.555893] __schedule+0xad9/0x1490 [ 1235.560709] ? io_schedule_timeout+0x130/0x130 [ 1235.566497] ? find_held_lock+0x2b/0x80 [ 1235.571602] ? __lock_release.isra.0+0x59/0x170 [ 1235.577488] schedule+0xd4/0x260 [ 1235.581907] schedule_preempt_disabled+0x18/0x30 [ 1235.587884] __mutex_lock+0xd76/0x1f20 [ 1235.592890] ? threaded_store+0x132/0x220 [ 1235.598190] ? ww_mutex_lock+0x160/0x160 [ 1235.603381] ? selinux_inode_free_security+0x310/0x310 [ 1235.609951] ? cap_capable+0x1ed/0x3f0 [ 1235.614964] ? sysfs_file_ops+0x140/0x140 [ 1235.620267] ? threaded_store+0x132/0x220 [ 1235.625561] threaded_store+0x132/0x220 [ 1235.630664] ? tx_maxrate_store+0x4e0/0x4e0 [ 1235.636156] ? sysfs_file_ops+0x140/0x140 [ 1235.641455] ? sysfs_file_kobj+0xbd/0x1e0 [ 1235.646751] ? sysfs_kf_write+0x65/0x160 [ 1235.651954] kernfs_fop_write_iter+0x335/0x510 [ 1235.657741] ? kernfs_ops+0xf0/0xf0 [ 1235.662455] new_sync_write+0x308/0x6f0 [ 1235.667558] ? new_sync_read+0x6f0/0x6f0 [ 1235.672759] ? lock_acquire.part.0+0xbc/0x260 [ 1235.678445] ? ksys_write+0xff/0x200 [ 1235.683260] vfs_write+0x62a/0x980 [ 1235.687875] ksys_write+0xff/0x200 [ 1235.692478] ? __ia32_sys_read+0xc0/0xc0 [ 1235.697675] ? kernfs_fop_llseek+0x160/0x1f0 [ 1235.703266] ? rcu_is_watching+0x15/0xd0 [ 1235.708468] do_syscall_64+0xf3/0x5e0 [ 1235.713376] ? exc_page_fault+0x9e/0xb0 [ 1235.718471] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1235.724942] RIP: 0033:0x7f7d8be03c5e [ 1235.729753] RSP: 002b:00007ffc9b459500 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1235.739052] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f7d8be03c5e [ 1235.747863] RDX: 0000000000000001 RSI: 0000555c9f1b1110 RDI: 0000000000000007 [ 1235.756669] RBP: 00007ffc9b459510 R08: 0000000000000000 R09: 0000000000000000 [ 1235.765478] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f7d8bc9ee80 [ 1235.774288] R13: 0000555c9f1b1110 R14: 0000000000000007 R15: 00007f7d8c504b98 | [ 1236.144801] #1: ff1100013b430288 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x247/0x510 | [ 1236.155764] #2: ff110001207c50f0 (kn->active#127){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1236.166925] #3: ff11000120829d00 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1236.177019] | [ 1236.179485] =============================================\x0a [ 1358.392216] INFO: task python3:4978 blocked for more than 491 seconds. [ 1358.400364] Not tainted 7.0.0-rc5-rnof-gd6ab535c2bff #1 [ 1358.405622] nipa-hw-worker: drivers/net:napi_threaded.py: timed out [ 1358.407607] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. Finger prints: __schedule:schedule:schedule_preempt_disabled:__mutex_lock:threaded_store