====================================== | [ 782.500031] nipa-hw-worker: drivers/net:napi_threaded.py: timed out | [ 782.502483] nipa-hw-worker: [5/40] Retrying drivers/net:napi_threaded.py | [ 782.636363] kselftest: Running tests in drivers/net [ 989.740848] INFO: task python3:5630 blocked for more than 122 seconds. [ 989.749065] Not tainted 7.0.0-rc5-epkv-gb850f67b3183 #1 [ 989.756315] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 989.765905] task:python3 state:D stack:25312 pid:5630 tgid:5630 ppid:5629 task_flags:0x400100 flags:0x00080000 [ 989.779115] Call Trace: [ 989.782655] [ 989.785806] __schedule+0xad9/0x1490 [ 989.790623] ? io_schedule_timeout+0x130/0x130 [ 989.796411] ? find_held_lock+0x2b/0x80 [ 989.801517] ? __lock_release.isra.0+0x59/0x170 [ 989.807405] schedule+0xd4/0x260 [ 989.811826] schedule_preempt_disabled+0x18/0x30 [ 989.817806] __mutex_lock+0xd76/0x1f20 [ 989.822805] ? threaded_store+0x132/0x220 [ 989.828106] ? ww_mutex_lock+0x160/0x160 [ 989.833308] ? selinux_inode_free_security+0x310/0x310 [ 989.839881] ? cap_capable+0x1ed/0x3f0 [ 989.844889] ? sysfs_file_ops+0x140/0x140 [ 989.850187] ? threaded_store+0x132/0x220 [ 989.855486] threaded_store+0x132/0x220 [ 989.860590] ? tx_maxrate_store+0x4e0/0x4e0 [ 989.866084] ? sysfs_file_ops+0x140/0x140 [ 989.871382] ? sysfs_file_kobj+0xbd/0x1e0 [ 989.876676] ? sysfs_kf_write+0x65/0x160 [ 989.881876] kernfs_fop_write_iter+0x335/0x510 [ 989.887652] ? kernfs_ops+0xf0/0xf0 [ 989.892365] new_sync_write+0x308/0x6f0 [ 989.897470] ? new_sync_read+0x6f0/0x6f0 [ 989.902677] ? lock_acquire.part.0+0xbc/0x260 [ 989.908366] ? ksys_write+0xff/0x200 [ 989.913180] vfs_write+0x62a/0x980 [ 989.917797] ksys_write+0xff/0x200 [ 989.922412] ? __ia32_sys_read+0xc0/0xc0 [ 989.927609] ? kernfs_fop_llseek+0x160/0x1f0 [ 989.933202] ? rcu_is_watching+0x15/0xd0 [ 989.938406] do_syscall_64+0xf3/0x5e0 [ 989.943311] ? irq_exit_rcu+0x39/0xf0 [ 989.948220] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 989.954687] RIP: 0033:0x7f17ff967c5e [ 989.959487] RSP: 002b:00007fffc3b0cb90 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 989.968785] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f17ff967c5e [ 989.977585] RDX: 0000000000000001 RSI: 00005557151bb760 RDI: 0000000000000007 [ 989.986392] RBP: 00007fffc3b0cba0 R08: 0000000000000000 R09: 0000000000000000 [ 989.995200] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f17ff802e80 [ 990.004009] R13: 00005557151bb760 R14: 0000000000000007 R15: 00007f1800068b98 | [ 990.374504] #1: ff110001c6fe1488 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x247/0x510 | [ 990.385466] #2: ff1100011118b0f0 (kn->active#127){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 990.396624] #3: ff11000122921d00 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 990.406715] | [ 990.409172] =============================================\x0a [ 1112.622030] INFO: task python3:5630 blocked for more than 245 seconds. [ 1112.630178] Not tainted 7.0.0-rc5-epkv-gb850f67b3183 #1 [ 1112.637427] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1112.647005] task:python3 state:D stack:25312 pid:5630 tgid:5630 ppid:5629 task_flags:0x400100 flags:0x00080000 [ 1112.660212] Call Trace: [ 1112.663752] [ 1112.666900] __schedule+0xad9/0x1490 [ 1112.671714] ? io_schedule_timeout+0x130/0x130 [ 1112.677502] ? find_held_lock+0x2b/0x80 [ 1112.682609] ? __lock_release.isra.0+0x59/0x170 [ 1112.688492] schedule+0xd4/0x260 [ 1112.692899] schedule_preempt_disabled+0x18/0x30 [ 1112.698879] __mutex_lock+0xd76/0x1f20 [ 1112.703874] ? threaded_store+0x132/0x220 [ 1112.709173] ? ww_mutex_lock+0x160/0x160 [ 1112.714372] ? selinux_inode_free_security+0x310/0x310 [ 1112.720940] ? cap_capable+0x1ed/0x3f0 [ 1112.725948] ? sysfs_file_ops+0x140/0x140 [ 1112.731250] ? threaded_store+0x132/0x220 [ 1112.736544] threaded_store+0x132/0x220 [ 1112.741646] ? tx_maxrate_store+0x4e0/0x4e0 [ 1112.747136] ? sysfs_file_ops+0x140/0x140 [ 1112.752507] ? sysfs_file_kobj+0xbd/0x1e0 [ 1112.757804] ? sysfs_kf_write+0x65/0x160 [ 1112.762994] kernfs_fop_write_iter+0x335/0x510 [ 1112.768779] ? kernfs_ops+0xf0/0xf0 [ 1112.773490] new_sync_write+0x308/0x6f0 [ 1112.778585] ? new_sync_read+0x6f0/0x6f0 [ 1112.783784] ? lock_acquire.part.0+0xbc/0x260 [ 1112.789469] ? ksys_write+0xff/0x200 [ 1112.794281] vfs_write+0x62a/0x980 [ 1112.798894] ksys_write+0xff/0x200 [ 1112.803508] ? __ia32_sys_read+0xc0/0xc0 [ 1112.808708] ? kernfs_fop_llseek+0x160/0x1f0 [ 1112.814290] ? rcu_is_watching+0x15/0xd0 [ 1112.819493] do_syscall_64+0xf3/0x5e0 [ 1112.824402] ? irq_exit_rcu+0x39/0xf0 [ 1112.829309] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1112.835777] RIP: 0033:0x7f17ff967c5e [ 1112.840585] RSP: 002b:00007fffc3b0cb90 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1112.849882] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f17ff967c5e [ 1112.858682] RDX: 0000000000000001 RSI: 00005557151bb760 RDI: 0000000000000007 [ 1112.867559] RBP: 00007fffc3b0cba0 R08: 0000000000000000 R09: 0000000000000000 [ 1112.876357] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f17ff802e80 [ 1112.885163] R13: 00005557151bb760 R14: 0000000000000007 R15: 00007f1800068b98 | [ 1113.266837] #2: ff1100011118b0f0 (kn->active#127){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1113.277996] #3: ff11000122921d00 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1113.288086] 1 lock held by systemd/6649: | [ 1113.293283] | [ 1113.295748] =============================================\x0a [ 1235.504250] INFO: task python3:5630 blocked for more than 368 seconds. [ 1235.512397] Not tainted 7.0.0-rc5-epkv-gb850f67b3183 #1 [ 1235.519640] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1235.529230] task:python3 state:D stack:25312 pid:5630 tgid:5630 ppid:5629 task_flags:0x400100 flags:0x00080002 [ 1235.542436] Call Trace: [ 1235.545978] [ 1235.549127] __schedule+0xad9/0x1490 [ 1235.553947] ? io_schedule_timeout+0x130/0x130 [ 1235.559734] ? find_held_lock+0x2b/0x80 [ 1235.564844] ? __lock_release.isra.0+0x59/0x170 [ 1235.570728] schedule+0xd4/0x260 [ 1235.575146] schedule_preempt_disabled+0x18/0x30 [ 1235.581126] __mutex_lock+0xd76/0x1f20 [ 1235.586131] ? threaded_store+0x132/0x220 [ 1235.591431] ? ww_mutex_lock+0x160/0x160 [ 1235.596631] ? selinux_inode_free_security+0x310/0x310 [ 1235.603202] ? cap_capable+0x1ed/0x3f0 [ 1235.608212] ? sysfs_file_ops+0x140/0x140 [ 1235.613509] ? threaded_store+0x132/0x220 [ 1235.618804] threaded_store+0x132/0x220 [ 1235.623910] ? tx_maxrate_store+0x4e0/0x4e0 [ 1235.629404] ? sysfs_file_ops+0x140/0x140 [ 1235.634703] ? sysfs_file_kobj+0xbd/0x1e0 [ 1235.639999] ? sysfs_kf_write+0x65/0x160 [ 1235.645200] kernfs_fop_write_iter+0x335/0x510 [ 1235.650989] ? kernfs_ops+0xf0/0xf0 [ 1235.655702] new_sync_write+0x308/0x6f0 [ 1235.660805] ? new_sync_read+0x6f0/0x6f0 [ 1235.666006] ? lock_acquire.part.0+0xbc/0x260 [ 1235.671693] ? ksys_write+0xff/0x200 [ 1235.676504] vfs_write+0x62a/0x980 [ 1235.681119] ksys_write+0xff/0x200 [ 1235.685734] ? __ia32_sys_read+0xc0/0xc0 [ 1235.690926] ? kernfs_fop_llseek+0x160/0x1f0 [ 1235.696519] ? rcu_is_watching+0x15/0xd0 [ 1235.701720] do_syscall_64+0xf3/0x5e0 [ 1235.706626] ? irq_exit_rcu+0x39/0xf0 [ 1235.711536] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1235.718005] RIP: 0033:0x7f17ff967c5e [ 1235.722816] RSP: 002b:00007fffc3b0cb90 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1235.732115] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f17ff967c5e [ 1235.740926] RDX: 0000000000000001 RSI: 00005557151bb760 RDI: 0000000000000007 [ 1235.749736] RBP: 00007fffc3b0cba0 R08: 0000000000000000 R09: 0000000000000000 [ 1235.758545] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f17ff802e80 [ 1235.767356] R13: 00005557151bb760 R14: 0000000000000007 R15: 00007f1800068b98 | [ 1236.137846] #1: ff110001c6fe1488 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x247/0x510 | [ 1236.148810] #2: ff1100011118b0f0 (kn->active#127){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1236.159970] #3: ff11000122921d00 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1236.170062] | [ 1236.172529] =============================================\x0a [ 1358.385489] INFO: task python3:5630 blocked for more than 491 seconds. [ 1358.393639] Not tainted 7.0.0-rc5-epkv-gb850f67b3183 #1 [ 1358.400889] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1358.410479] task:python3 state:D stack:25312 pid:5630 tgid:5630 ppid:5629 task_flags:0x400100 flags:0x00080002 [ 1358.423684] Call Trace: [ 1358.427223] [ 1358.430371] __schedule+0xad9/0x1490 [ 1358.435185] ? io_schedule_timeout+0x130/0x130 [ 1358.440970] ? find_held_lock+0x2b/0x80 [ 1358.446076] ? __lock_release.isra.0+0x59/0x170 [ 1358.451959] schedule+0xd4/0x260 [ 1358.456375] schedule_preempt_disabled+0x18/0x30 [ 1358.462351] __mutex_lock+0xd76/0x1f20 [ 1358.467353] ? threaded_store+0x132/0x220 [ 1358.472651] ? ww_mutex_lock+0x160/0x160 [ 1358.477851] ? selinux_inode_free_security+0x310/0x310 [ 1358.484419] ? cap_capable+0x1ed/0x3f0 [ 1358.489427] ? sysfs_file_ops+0x140/0x140 [ 1358.494721] ? threaded_store+0x132/0x220 [ 1358.500017] threaded_store+0x132/0x220 [ 1358.505122] ? tx_maxrate_store+0x4e0/0x4e0 [ 1358.510614] ? sysfs_file_ops+0x140/0x140 [ 1358.515908] ? sysfs_file_kobj+0xbd/0x1e0 [ 1358.521203] ? sysfs_kf_write+0x65/0x160 [ 1358.526402] kernfs_fop_write_iter+0x335/0x510 [ 1358.532185] ? kernfs_ops+0xf0/0xf0 [ 1358.536897] new_sync_write+0x308/0x6f0 [ 1358.541999] ? new_sync_read+0x6f0/0x6f0 [ 1358.547199] ? lock_acquire.part.0+0xbc/0x260 [ 1358.552876] ? ksys_write+0xff/0x200 [ 1358.557687] vfs_write+0x62a/0x980 [ 1358.562302] ksys_write+0xff/0x200 [ 1358.566915] ? __ia32_sys_read+0xc0/0xc0 [ 1358.572111] ? kernfs_fop_llseek+0x160/0x1f0 [ 1358.577702] ? rcu_is_watching+0x15/0xd0 [ 1358.582903] do_syscall_64+0xf3/0x5e0 [ 1358.587798] ? irq_exit_rcu+0x39/0xf0 [ 1358.592706] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1358.599162] RIP: 0033:0x7f17ff967c5e [ 1358.603973] RSP: 002b:00007fffc3b0cb90 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1358.613270] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f17ff967c5e [ 1358.622076] RDX: 0000000000000001 RSI: 00005557151bb760 RDI: 0000000000000007 [ 1358.630881] RBP: 00007fffc3b0cba0 R08: 0000000000000000 R09: 0000000000000000 [ 1358.639686] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f17ff802e80 [ 1358.648492] R13: 00005557151bb760 R14: 0000000000000007 R15: 00007f1800068b98 Finger prints: __schedule:schedule:schedule_preempt_disabled:__mutex_lock:threaded_store