====================================== | [ 790.905807] nipa-hw-worker: drivers/net:napi_threaded.py: timed out | [ 790.908215] nipa-hw-worker: [5/44] Retrying drivers/net:napi_threaded.py | [ 791.059094] kselftest: Running tests in drivers/net [ 989.719551] INFO: task python3:5918 blocked for more than 122 seconds. [ 989.727777] Not tainted 7.1.0-rc1-rwpk-gdc8800b5d2c4 #1 [ 989.735680] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 989.745269] task:python3 state:D stack:25672 pid:5918 tgid:5918 ppid:5917 task_flags:0x400100 flags:0x00080000 [ 989.758464] Call Trace: [ 989.762001] [ 989.765152] __schedule+0xcba/0x1940 [ 989.769967] ? io_schedule_timeout+0x140/0x140 [ 989.775750] ? find_held_lock+0x2b/0x80 [ 989.780855] ? __lock_release.isra.0+0x6b/0x1a0 [ 989.786740] schedule+0xd4/0x260 [ 989.791159] schedule_preempt_disabled+0x18/0x30 [ 989.797136] __mutex_lock+0x6a3/0x2000 [ 989.802139] ? threaded_store+0x132/0x220 [ 989.807429] ? ww_mutex_lock+0x160/0x160 [ 989.812625] ? selinux_inode_free_security+0x330/0x330 [ 989.819193] ? cap_capable+0x1d6/0x3e0 [ 989.824197] ? threaded_store+0x132/0x220 [ 989.829491] threaded_store+0x132/0x220 [ 989.834593] ? xps_rxqs_store+0x2e0/0x2e0 [ 989.839889] ? sysfs_file_kobj+0xbd/0x1e0 [ 989.845191] ? sysfs_kf_write+0x65/0x160 [ 989.850398] ? sysfs_file_ops+0x140/0x140 [ 989.855693] kernfs_fop_write_iter+0x335/0x510 [ 989.861478] ? kernfs_ops+0xf0/0xf0 [ 989.866187] new_sync_write+0x333/0x750 [ 989.871288] ? __lock_acquire+0x508/0xc10 [ 989.876585] ? new_sync_read+0x740/0x740 [ 989.881774] ? lock_acquire.part.0+0xbc/0x260 [ 989.887458] ? ksys_write+0x116/0x250 [ 989.892365] vfs_write+0x657/0x9b0 [ 989.896979] ksys_write+0x116/0x250 [ 989.901690] ? __ia32_sys_read+0xc0/0xc0 [ 989.906889] ? kernfs_fop_llseek+0x160/0x1f0 [ 989.912479] ? rcu_is_watching+0x15/0xd0 [ 989.917677] do_syscall_64+0xf3/0x650 [ 989.922581] ? trace_hardirqs_off+0xd/0x30 [ 989.927977] ? exc_page_fault+0xda/0xf0 [ 989.933078] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 989.939544] RIP: 0033:0x7f90e5a0522e [ 989.944353] RSP: 002b:00007ffe623ac190 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 989.953651] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f90e5a0522e [ 989.962460] RDX: 0000000000000001 RSI: 0000555ddff3ba10 RDI: 0000000000000007 [ 989.971267] RBP: 00007ffe623ac1a0 R08: 0000000000000000 R09: 0000000000000000 [ 989.980075] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f90e587d390 [ 989.988881] R13: 0000555ddff3ba10 R14: 0000000000000007 R15: 0000000000000003 | [ 990.401111] #1: ff1100020846d680 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 990.412071] #2: ff1100012337ab50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 990.423226] #3: ff11000123459d60 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 990.433310] | [ 990.435777] =============================================\x0a [ 1112.599677] INFO: task python3:5918 blocked for more than 245 seconds. [ 1112.607825] Not tainted 7.1.0-rc1-rwpk-gdc8800b5d2c4 #1 [ 1112.615076] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1112.624664] task:python3 state:D stack:25672 pid:5918 tgid:5918 ppid:5917 task_flags:0x400100 flags:0x00080000 [ 1112.637870] Call Trace: [ 1112.641411] [ 1112.644560] __schedule+0xcba/0x1940 [ 1112.649375] ? io_schedule_timeout+0x140/0x140 [ 1112.655162] ? find_held_lock+0x2b/0x80 [ 1112.660269] ? __lock_release.isra.0+0x6b/0x1a0 [ 1112.666156] schedule+0xd4/0x260 [ 1112.670574] schedule_preempt_disabled+0x18/0x30 [ 1112.676552] __mutex_lock+0x6a3/0x2000 [ 1112.681555] ? threaded_store+0x132/0x220 [ 1112.686846] ? ww_mutex_lock+0x160/0x160 [ 1112.692046] ? selinux_inode_free_security+0x330/0x330 [ 1112.698617] ? cap_capable+0x1d6/0x3e0 [ 1112.703626] ? threaded_store+0x132/0x220 [ 1112.708925] threaded_store+0x132/0x220 [ 1112.714027] ? xps_rxqs_store+0x2e0/0x2e0 [ 1112.719329] ? sysfs_file_kobj+0xbd/0x1e0 [ 1112.724628] ? sysfs_kf_write+0x65/0x160 [ 1112.729845] ? sysfs_file_ops+0x140/0x140 [ 1112.735134] kernfs_fop_write_iter+0x335/0x510 [ 1112.740917] ? kernfs_ops+0xf0/0xf0 [ 1112.745629] new_sync_write+0x333/0x750 [ 1112.750735] ? __lock_acquire+0x508/0xc10 [ 1112.756030] ? new_sync_read+0x740/0x740 [ 1112.761233] ? lock_acquire.part.0+0xbc/0x260 [ 1112.766920] ? ksys_write+0x116/0x250 [ 1112.771829] vfs_write+0x657/0x9b0 [ 1112.776443] ksys_write+0x116/0x250 [ 1112.781155] ? __ia32_sys_read+0xc0/0xc0 [ 1112.786348] ? kernfs_fop_llseek+0x160/0x1f0 [ 1112.791939] ? rcu_is_watching+0x15/0xd0 [ 1112.797142] do_syscall_64+0xf3/0x650 [ 1112.802047] ? trace_hardirqs_off+0xd/0x30 [ 1112.807442] ? exc_page_fault+0xda/0xf0 [ 1112.812546] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1112.819016] RIP: 0033:0x7f90e5a0522e [ 1112.823827] RSP: 002b:00007ffe623ac190 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1112.833125] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f90e5a0522e [ 1112.841934] RDX: 0000000000000001 RSI: 0000555ddff3ba10 RDI: 0000000000000007 [ 1112.850744] RBP: 00007ffe623ac1a0 R08: 0000000000000000 R09: 0000000000000000 [ 1112.859555] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f90e587d390 [ 1112.868362] R13: 0000555ddff3ba10 R14: 0000000000000007 R15: 0000000000000003 | [ 1113.268336] #1: ff1100020846d680 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 1113.279297] #2: ff1100012337ab50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1113.290453] #3: ff11000123459d60 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1113.300543] | [ 1113.303009] =============================================\x0a [ 1235.481792] INFO: task python3:5918 blocked for more than 368 seconds. [ 1235.489938] Not tainted 7.1.0-rc1-rwpk-gdc8800b5d2c4 #1 [ 1235.497188] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1235.506774] task:python3 state:D stack:25672 pid:5918 tgid:5918 ppid:5917 task_flags:0x400100 flags:0x00080002 [ 1235.519982] Call Trace: [ 1235.523521] [ 1235.526668] __schedule+0xcba/0x1940 [ 1235.531482] ? io_schedule_timeout+0x140/0x140 [ 1235.537267] ? find_held_lock+0x2b/0x80 [ 1235.542369] ? __lock_release.isra.0+0x6b/0x1a0 [ 1235.548254] schedule+0xd4/0x260 [ 1235.552670] schedule_preempt_disabled+0x18/0x30 [ 1235.558646] __mutex_lock+0x6a3/0x2000 [ 1235.563649] ? threaded_store+0x132/0x220 [ 1235.568947] ? ww_mutex_lock+0x160/0x160 [ 1235.574146] ? selinux_inode_free_security+0x330/0x330 [ 1235.580715] ? cap_capable+0x1d6/0x3e0 [ 1235.585719] ? threaded_store+0x132/0x220 [ 1235.591016] threaded_store+0x132/0x220 [ 1235.596107] ? xps_rxqs_store+0x2e0/0x2e0 [ 1235.601405] ? sysfs_file_kobj+0xbd/0x1e0 [ 1235.606704] ? sysfs_kf_write+0x65/0x160 [ 1235.611898] ? sysfs_file_ops+0x140/0x140 [ 1235.617195] kernfs_fop_write_iter+0x335/0x510 [ 1235.622977] ? kernfs_ops+0xf0/0xf0 [ 1235.627688] new_sync_write+0x333/0x750 [ 1235.632789] ? __lock_acquire+0x508/0xc10 [ 1235.638085] ? new_sync_read+0x740/0x740 [ 1235.643283] ? lock_acquire.part.0+0xbc/0x260 [ 1235.648969] ? ksys_write+0x116/0x250 [ 1235.653878] vfs_write+0x657/0x9b0 [ 1235.658491] ksys_write+0x116/0x250 [ 1235.663204] ? __ia32_sys_read+0xc0/0xc0 [ 1235.668392] ? kernfs_fop_llseek+0x160/0x1f0 [ 1235.673978] ? rcu_is_watching+0x15/0xd0 [ 1235.679180] do_syscall_64+0xf3/0x650 [ 1235.684086] ? trace_hardirqs_off+0xd/0x30 [ 1235.689478] ? exc_page_fault+0xda/0xf0 [ 1235.694581] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1235.701047] RIP: 0033:0x7f90e5a0522e [ 1235.705853] RSP: 002b:00007ffe623ac190 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1235.715152] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f90e5a0522e [ 1235.723959] RDX: 0000000000000001 RSI: 0000555ddff3ba10 RDI: 0000000000000007 [ 1235.732763] RBP: 00007ffe623ac1a0 R08: 0000000000000000 R09: 0000000000000000 [ 1235.741570] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f90e587d390 [ 1235.750377] R13: 0000555ddff3ba10 R14: 0000000000000007 R15: 0000000000000003 | [ 1236.150214] #1: ff1100020846d680 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 1236.161172] #2: ff1100012337ab50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1236.172328] #3: ff11000123459d60 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1236.182416] | [ 1236.184879] =============================================\x0a [ 1358.362926] INFO: task python3:5918 blocked for more than 491 seconds. [ 1358.371073] Not tainted 7.1.0-rc1-rwpk-gdc8800b5d2c4 #1 [ 1358.378316] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1358.387906] task:python3 state:D stack:25672 pid:5918 tgid:5918 ppid:5917 task_flags:0x400100 flags:0x00080002 [ 1358.401113] Call Trace: [ 1358.404653] [ 1358.407801] __schedule+0xcba/0x1940 [ 1358.412614] ? io_schedule_timeout+0x140/0x140 [ 1358.418401] ? find_held_lock+0x2b/0x80 [ 1358.423507] ? __lock_release.isra.0+0x6b/0x1a0 [ 1358.429392] schedule+0xd4/0x260 [ 1358.433809] schedule_preempt_disabled+0x18/0x30 [ 1358.439786] __mutex_lock+0x6a3/0x2000 [ 1358.444789] ? threaded_store+0x132/0x220 [ 1358.450088] ? ww_mutex_lock+0x160/0x160 [ 1358.455287] ? selinux_inode_free_security+0x330/0x330 [ 1358.461856] ? cap_capable+0x1d6/0x3e0 [ 1358.466860] ? threaded_store+0x132/0x220 [ 1358.472158] threaded_store+0x132/0x220 [ 1358.477250] ? xps_rxqs_store+0x2e0/0x2e0 [ 1358.482549] ? sysfs_file_kobj+0xbd/0x1e0 [ 1358.487847] ? sysfs_kf_write+0x65/0x160 [ 1358.493038] ? sysfs_file_ops+0x140/0x140 [ 1358.498331] kernfs_fop_write_iter+0x335/0x510 [ 1358.504115] ? kernfs_ops+0xf0/0xf0 [ 1358.508826] new_sync_write+0x333/0x750 [ 1358.513931] ? __lock_acquire+0x508/0xc10 [ 1358.519227] ? new_sync_read+0x740/0x740 [ 1358.524429] ? lock_acquire.part.0+0xbc/0x260 [ 1358.530115] ? ksys_write+0x116/0x250 [ 1358.535023] vfs_write+0x657/0x9b0 [ 1358.539627] ksys_write+0x116/0x250 [ 1358.544336] ? __ia32_sys_read+0xc0/0xc0 [ 1358.549534] ? kernfs_fop_llseek+0x160/0x1f0 [ 1358.555121] ? rcu_is_watching+0x15/0xd0 [ 1358.560322] do_syscall_64+0xf3/0x650 [ 1358.565228] ? trace_hardirqs_off+0xd/0x30 [ 1358.570624] ? exc_page_fault+0xda/0xf0 [ 1358.575728] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1358.582194] RIP: 0033:0x7f90e5a0522e [ 1358.587002] RSP: 002b:00007ffe623ac190 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1358.596297] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f90e5a0522e [ 1358.605104] RDX: 0000000000000001 RSI: 0000555ddff3ba10 RDI: 0000000000000007 [ 1358.613910] RBP: 00007ffe623ac1a0 R08: 0000000000000000 R09: 0000000000000000 [ 1358.622715] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f90e587d390 [ 1358.631522] R13: 0000555ddff3ba10 R14: 0000000000000007 R15: 0000000000000003 Finger prints: __schedule:schedule:schedule_preempt_disabled:__mutex_lock:threaded_store