====================================== | [ 790.905124] nipa-hw-worker: drivers/net:napi_threaded.py: timed out | [ 790.916560] nipa-hw-worker: [5/46] Retrying drivers/net:napi_threaded.py | [ 791.053801] kselftest: Running tests in drivers/net [ 989.700477] INFO: task python3:5896 blocked for more than 122 seconds. [ 989.708675] Not tainted 7.1.0-rc4-czsj-g3c4ae627e8ff #1 [ 989.716148] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 989.725741] task:python3 state:D stack:25672 pid:5896 tgid:5896 ppid:5895 task_flags:0x400100 flags:0x00080000 [ 989.738948] Call Trace: [ 989.742488] [ 989.745643] __schedule+0xcc3/0x19c0 [ 989.750462] ? io_schedule_timeout+0x140/0x140 [ 989.756245] ? find_held_lock+0x2b/0x80 [ 989.761340] ? __lock_release.isra.0+0x6b/0x1a0 [ 989.767229] schedule+0xd8/0x260 [ 989.771647] schedule_preempt_disabled+0x18/0x30 [ 989.777626] __mutex_lock+0x6af/0x2010 [ 989.782633] ? threaded_store+0x132/0x220 [ 989.787933] ? ww_mutex_lock+0x160/0x160 [ 989.793131] ? selinux_inode_free_security+0x330/0x330 [ 989.799693] ? cap_capable+0x1d6/0x3e0 [ 989.804699] ? threaded_store+0x132/0x220 [ 989.809995] threaded_store+0x132/0x220 [ 989.815099] ? xps_rxqs_store+0x2e0/0x2e0 [ 989.820399] ? sysfs_file_kobj+0xbd/0x1e0 [ 989.825701] ? sysfs_kf_write+0x65/0x160 [ 989.830902] ? sysfs_file_ops+0x140/0x140 [ 989.836198] kernfs_fop_write_iter+0x335/0x510 [ 989.841983] ? kernfs_ops+0xf0/0xf0 [ 989.846687] new_sync_write+0x333/0x750 [ 989.851790] ? __lock_acquire+0x508/0xc10 [ 989.857088] ? new_sync_read+0x740/0x740 [ 989.862287] ? lock_acquire.part.0+0xbc/0x260 [ 989.867968] ? ksys_write+0x116/0x250 [ 989.872880] vfs_write+0x657/0x9b0 [ 989.877496] ksys_write+0x116/0x250 [ 989.882207] ? __ia32_sys_read+0xc0/0xc0 [ 989.887407] ? kernfs_fop_llseek+0x160/0x1f0 [ 989.892987] ? rcu_is_watching+0x15/0xd0 [ 989.898183] do_syscall_64+0xf3/0x650 [ 989.903088] ? irq_exit_rcu+0x1a/0x30 [ 989.907996] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 989.914455] RIP: 0033:0x7f41beb5522e [ 989.919266] RSP: 002b:00007ffdfb3b42c0 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 989.928565] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f41beb5522e [ 989.937374] RDX: 0000000000000001 RSI: 0000557c4b5856b0 RDI: 0000000000000007 [ 989.946184] RBP: 00007ffdfb3b42d0 R08: 0000000000000000 R09: 0000000000000000 [ 989.954991] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f41be9cd390 [ 989.963792] R13: 0000557c4b5856b0 R14: 0000000000000007 R15: 0000000000000003 | [ 990.399124] #1: ff110001284a6280 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 990.410082] #2: ff1100011c066b50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 990.421235] #3: ff11000123ef9d68 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 990.431326] | [ 990.433792] =============================================\x0a [ 1112.581594] INFO: task python3:5896 blocked for more than 245 seconds. [ 1112.589740] Not tainted 7.1.0-rc4-czsj-g3c4ae627e8ff #1 [ 1112.596991] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1112.606583] task:python3 state:D stack:25672 pid:5896 tgid:5896 ppid:5895 task_flags:0x400100 flags:0x00080000 [ 1112.619791] Call Trace: [ 1112.623332] [ 1112.626482] __schedule+0xcc3/0x19c0 [ 1112.631300] ? io_schedule_timeout+0x140/0x140 [ 1112.637086] ? find_held_lock+0x2b/0x80 [ 1112.642193] ? __lock_release.isra.0+0x6b/0x1a0 [ 1112.648079] schedule+0xd8/0x260 [ 1112.652499] schedule_preempt_disabled+0x18/0x30 [ 1112.658480] __mutex_lock+0x6af/0x2010 [ 1112.663487] ? threaded_store+0x132/0x220 [ 1112.668786] ? ww_mutex_lock+0x160/0x160 [ 1112.673985] ? selinux_inode_free_security+0x330/0x330 [ 1112.680555] ? cap_capable+0x1d6/0x3e0 [ 1112.685564] ? threaded_store+0x132/0x220 [ 1112.690861] threaded_store+0x132/0x220 [ 1112.695965] ? xps_rxqs_store+0x2e0/0x2e0 [ 1112.701264] ? sysfs_file_kobj+0xbd/0x1e0 [ 1112.706566] ? sysfs_kf_write+0x65/0x160 [ 1112.711768] ? sysfs_file_ops+0x140/0x140 [ 1112.717063] kernfs_fop_write_iter+0x335/0x510 [ 1112.722849] ? kernfs_ops+0xf0/0xf0 [ 1112.727560] new_sync_write+0x333/0x750 [ 1112.732663] ? __lock_acquire+0x508/0xc10 [ 1112.737962] ? new_sync_read+0x740/0x740 [ 1112.743164] ? lock_acquire.part.0+0xbc/0x260 [ 1112.748849] ? ksys_write+0x116/0x250 [ 1112.753761] vfs_write+0x657/0x9b0 [ 1112.758377] ksys_write+0x116/0x250 [ 1112.763089] ? __ia32_sys_read+0xc0/0xc0 [ 1112.768282] ? kernfs_fop_llseek+0x160/0x1f0 [ 1112.773871] ? rcu_is_watching+0x15/0xd0 [ 1112.779068] do_syscall_64+0xf3/0x650 [ 1112.783976] ? irq_exit_rcu+0x1a/0x30 [ 1112.788884] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1112.795344] RIP: 0033:0x7f41beb5522e [ 1112.800152] RSP: 002b:00007ffdfb3b42c0 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1112.809450] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f41beb5522e [ 1112.818257] RDX: 0000000000000001 RSI: 0000557c4b5856b0 RDI: 0000000000000007 [ 1112.827066] RBP: 00007ffdfb3b42d0 R08: 0000000000000000 R09: 0000000000000000 [ 1112.835876] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f41be9cd390 [ 1112.844686] R13: 0000557c4b5856b0 R14: 0000000000000007 R15: 0000000000000003 | [ 1113.216037] #1: ff110001284a6280 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 1113.227000] #2: ff1100011c066b50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1113.238161] #3: ff11000123ef9d68 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1113.248253] | [ 1113.250719] =============================================\x0a [ 1235.462712] INFO: task python3:5896 blocked for more than 368 seconds. [ 1235.470860] Not tainted 7.1.0-rc4-czsj-g3c4ae627e8ff #1 [ 1235.478109] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1235.487688] task:python3 state:D stack:25672 pid:5896 tgid:5896 ppid:5895 task_flags:0x400100 flags:0x00080002 [ 1235.500895] Call Trace: [ 1235.504436] [ 1235.507587] __schedule+0xcc3/0x19c0 [ 1235.512403] ? io_schedule_timeout+0x140/0x140 [ 1235.518186] ? find_held_lock+0x2b/0x80 [ 1235.523290] ? __lock_release.isra.0+0x6b/0x1a0 [ 1235.529165] schedule+0xd8/0x260 [ 1235.533584] schedule_preempt_disabled+0x18/0x30 [ 1235.539561] __mutex_lock+0x6af/0x2010 [ 1235.544564] ? threaded_store+0x132/0x220 [ 1235.549861] ? ww_mutex_lock+0x160/0x160 [ 1235.555061] ? selinux_inode_free_security+0x330/0x330 [ 1235.561630] ? cap_capable+0x1d6/0x3e0 [ 1235.566634] ? threaded_store+0x132/0x220 [ 1235.571930] threaded_store+0x132/0x220 [ 1235.577028] ? xps_rxqs_store+0x2e0/0x2e0 [ 1235.582329] ? sysfs_file_kobj+0xbd/0x1e0 [ 1235.587630] ? sysfs_kf_write+0x65/0x160 [ 1235.592831] ? sysfs_file_ops+0x140/0x140 [ 1235.598127] kernfs_fop_write_iter+0x335/0x510 [ 1235.603913] ? kernfs_ops+0xf0/0xf0 [ 1235.608616] new_sync_write+0x333/0x750 [ 1235.613720] ? __lock_acquire+0x508/0xc10 [ 1235.619015] ? new_sync_read+0x740/0x740 [ 1235.624213] ? lock_acquire.part.0+0xbc/0x260 [ 1235.629899] ? ksys_write+0x116/0x250 [ 1235.634807] vfs_write+0x657/0x9b0 [ 1235.639422] ksys_write+0x116/0x250 [ 1235.644133] ? __ia32_sys_read+0xc0/0xc0 [ 1235.649330] ? kernfs_fop_llseek+0x160/0x1f0 [ 1235.654919] ? rcu_is_watching+0x15/0xd0 [ 1235.660120] do_syscall_64+0xf3/0x650 [ 1235.665015] ? irq_exit_rcu+0x1a/0x30 [ 1235.669923] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1235.676382] RIP: 0033:0x7f41beb5522e [ 1235.681192] RSP: 002b:00007ffdfb3b42c0 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1235.690487] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f41beb5522e [ 1235.699294] RDX: 0000000000000001 RSI: 0000557c4b5856b0 RDI: 0000000000000007 [ 1235.708099] RBP: 00007ffdfb3b42d0 R08: 0000000000000000 R09: 0000000000000000 [ 1235.716907] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f41be9cd390 [ 1235.725705] R13: 0000557c4b5856b0 R14: 0000000000000007 R15: 0000000000000003 | [ 1236.096893] #1: ff110001284a6280 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 1236.107853] #2: ff1100011c066b50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1236.119011] #3: ff11000123ef9d68 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1236.129103] | [ 1236.131570] =============================================\x0a [ 1358.344864] INFO: task python3:5896 blocked for more than 491 seconds. [ 1358.353022] Not tainted 7.1.0-rc4-czsj-g3c4ae627e8ff #1 [ 1358.360275] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1358.369864] task:python3 state:D stack:25672 pid:5896 tgid:5896 ppid:5895 task_flags:0x400100 flags:0x00080002 [ 1358.383070] Call Trace: [ 1358.386611] [ 1358.389761] __schedule+0xcc3/0x19c0 [ 1358.394579] ? io_schedule_timeout+0x140/0x140 [ 1358.400365] ? find_held_lock+0x2b/0x80 [ 1358.405470] ? __lock_release.isra.0+0x6b/0x1a0 [ 1358.411350] schedule+0xd8/0x260 [ 1358.415771] schedule_preempt_disabled+0x18/0x30 [ 1358.421750] __mutex_lock+0x6af/0x2010 [ 1358.426757] ? threaded_store+0x132/0x220 [ 1358.432059] ? ww_mutex_lock+0x160/0x160 [ 1358.437257] ? selinux_inode_free_security+0x330/0x330 [ 1358.443817] ? cap_capable+0x1d6/0x3e0 [ 1358.448824] ? threaded_store+0x132/0x220 [ 1358.454121] threaded_store+0x132/0x220 [ 1358.459225] ? xps_rxqs_store+0x2e0/0x2e0 [ 1358.464522] ? sysfs_file_kobj+0xbd/0x1e0 [ 1358.469812] ? sysfs_kf_write+0x65/0x160 [ 1358.475003] ? sysfs_file_ops+0x140/0x140 [ 1358.480292] kernfs_fop_write_iter+0x335/0x510 [ 1358.486079] ? kernfs_ops+0xf0/0xf0 [ 1358.490789] new_sync_write+0x333/0x750 [ 1358.495894] ? __lock_acquire+0x508/0xc10 [ 1358.501193] ? new_sync_read+0x740/0x740 [ 1358.506396] ? lock_acquire.part.0+0xbc/0x260 [ 1358.512082] ? ksys_write+0x116/0x250 [ 1358.516991] vfs_write+0x657/0x9b0 [ 1358.521609] ksys_write+0x116/0x250 [ 1358.526322] ? __ia32_sys_read+0xc0/0xc0 [ 1358.531523] ? kernfs_fop_llseek+0x160/0x1f0 [ 1358.537105] ? rcu_is_watching+0x15/0xd0 [ 1358.542308] do_syscall_64+0xf3/0x650 [ 1358.547216] ? irq_exit_rcu+0x1a/0x30 [ 1358.552123] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1358.558594] RIP: 0033:0x7f41beb5522e [ 1358.563404] RSP: 002b:00007ffdfb3b42c0 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1358.572692] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f41beb5522e [ 1358.581493] RDX: 0000000000000001 RSI: 0000557c4b5856b0 RDI: 0000000000000007 [ 1358.590299] RBP: 00007ffdfb3b42d0 R08: 0000000000000000 R09: 0000000000000000 [ 1358.599108] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f41be9cd390 [ 1358.607915] R13: 0000557c4b5856b0 R14: 0000000000000007 R15: 0000000000000003 Finger prints: __schedule:schedule:schedule_preempt_disabled:__mutex_lock:threaded_store