====================================== | [ 783.411872] nipa-hw-worker: drivers/net:napi_threaded.py: timed out | [ 783.414345] nipa-hw-worker: [5/45] Retrying drivers/net:napi_threaded.py | [ 783.541253] kselftest: Running tests in drivers/net [ 989.659537] INFO: task python3:5695 blocked for more than 122 seconds. [ 989.667784] Not tainted 7.1.0-rc2-lwdd-g7b5999081c7b #1 [ 989.675735] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 989.685317] task:python3 state:D stack:25672 pid:5695 tgid:5695 ppid:5694 task_flags:0x400100 flags:0x00080000 [ 989.698523] Call Trace: [ 989.702063] [ 989.705218] __schedule+0xcba/0x1940 [ 989.710038] ? io_schedule_timeout+0x140/0x140 [ 989.715823] ? find_held_lock+0x2b/0x80 [ 989.720930] ? __lock_release.isra.0+0x6b/0x1a0 [ 989.726818] schedule+0xd4/0x260 [ 989.731236] schedule_preempt_disabled+0x18/0x30 [ 989.737215] __mutex_lock+0x6a3/0x2000 [ 989.742221] ? threaded_store+0x132/0x220 [ 989.747522] ? ww_mutex_lock+0x160/0x160 [ 989.752721] ? selinux_inode_free_security+0x330/0x330 [ 989.759290] ? cap_capable+0x1d6/0x3e0 [ 989.764298] ? threaded_store+0x132/0x220 [ 989.769597] threaded_store+0x132/0x220 [ 989.774704] ? xps_rxqs_store+0x2e0/0x2e0 [ 989.780001] ? sysfs_file_kobj+0xbd/0x1e0 [ 989.785301] ? sysfs_kf_write+0x65/0x160 [ 989.790500] ? sysfs_file_ops+0x140/0x140 [ 989.795796] kernfs_fop_write_iter+0x335/0x510 [ 989.801584] ? kernfs_ops+0xf0/0xf0 [ 989.806294] new_sync_write+0x333/0x750 [ 989.811398] ? __lock_acquire+0x508/0xc10 [ 989.816685] ? new_sync_read+0x740/0x740 [ 989.821886] ? lock_acquire.part.0+0xbc/0x260 [ 989.827572] ? ksys_write+0x116/0x250 [ 989.832485] vfs_write+0x657/0x9b0 [ 989.837101] ksys_write+0x116/0x250 [ 989.841814] ? __ia32_sys_read+0xc0/0xc0 [ 989.847005] ? kernfs_fop_llseek+0x160/0x1f0 [ 989.852594] ? rcu_is_watching+0x15/0xd0 [ 989.857794] do_syscall_64+0xf3/0x650 [ 989.862703] ? trace_hardirqs_off+0xd/0x30 [ 989.868098] ? exc_page_fault+0xda/0xf0 [ 989.873201] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 989.879671] RIP: 0033:0x7f4f2c85e22e [ 989.884482] RSP: 002b:00007ffd5a6bfa30 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 989.893778] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f4f2c85e22e [ 989.902579] RDX: 0000000000000001 RSI: 0000561e31a343d0 RDI: 0000000000000007 [ 989.911387] RBP: 00007ffd5a6bfa40 R08: 0000000000000000 R09: 0000000000000000 [ 989.920197] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f4f2c6d6390 [ 989.929003] R13: 0000561e31a343d0 R14: 0000000000000007 R15: 0000000000000003 | [ 990.300312] #1: ff110001dcd7b880 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 990.311275] #2: ff1100012335ab50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 990.322436] #3: ff110001233c9d68 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 990.332525] | [ 990.334992] =============================================\x0a [ 1112.539736] INFO: task python3:5695 blocked for more than 245 seconds. [ 1112.547882] Not tainted 7.1.0-rc2-lwdd-g7b5999081c7b #1 [ 1112.555132] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1112.564723] task:python3 state:D stack:25672 pid:5695 tgid:5695 ppid:5694 task_flags:0x400100 flags:0x00080000 [ 1112.577930] Call Trace: [ 1112.581470] [ 1112.584621] __schedule+0xcba/0x1940 [ 1112.589439] ? io_schedule_timeout+0x140/0x140 [ 1112.595225] ? find_held_lock+0x2b/0x80 [ 1112.600332] ? __lock_release.isra.0+0x6b/0x1a0 [ 1112.606216] schedule+0xd4/0x260 [ 1112.610633] schedule_preempt_disabled+0x18/0x30 [ 1112.616615] __mutex_lock+0x6a3/0x2000 [ 1112.621618] ? threaded_store+0x132/0x220 [ 1112.626915] ? ww_mutex_lock+0x160/0x160 [ 1112.632114] ? selinux_inode_free_security+0x330/0x330 [ 1112.638683] ? cap_capable+0x1d6/0x3e0 [ 1112.643690] ? threaded_store+0x132/0x220 [ 1112.648987] threaded_store+0x132/0x220 [ 1112.654091] ? xps_rxqs_store+0x2e0/0x2e0 [ 1112.659388] ? sysfs_file_kobj+0xbd/0x1e0 [ 1112.664686] ? sysfs_kf_write+0x65/0x160 [ 1112.669889] ? sysfs_file_ops+0x140/0x140 [ 1112.675186] kernfs_fop_write_iter+0x335/0x510 [ 1112.680972] ? kernfs_ops+0xf0/0xf0 [ 1112.685682] new_sync_write+0x333/0x750 [ 1112.690787] ? __lock_acquire+0x508/0xc10 [ 1112.696073] ? new_sync_read+0x740/0x740 [ 1112.701275] ? lock_acquire.part.0+0xbc/0x260 [ 1112.706963] ? ksys_write+0x116/0x250 [ 1112.711863] vfs_write+0x657/0x9b0 [ 1112.716481] ksys_write+0x116/0x250 [ 1112.721194] ? __ia32_sys_read+0xc0/0xc0 [ 1112.726391] ? kernfs_fop_llseek+0x160/0x1f0 [ 1112.731981] ? rcu_is_watching+0x15/0xd0 [ 1112.737182] do_syscall_64+0xf3/0x650 [ 1112.742086] ? trace_hardirqs_off+0xd/0x30 [ 1112.747482] ? exc_page_fault+0xda/0xf0 [ 1112.752585] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1112.759056] RIP: 0033:0x7f4f2c85e22e [ 1112.763867] RSP: 002b:00007ffd5a6bfa30 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1112.773166] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f4f2c85e22e [ 1112.781976] RDX: 0000000000000001 RSI: 0000561e31a343d0 RDI: 0000000000000007 [ 1112.790786] RBP: 00007ffd5a6bfa40 R08: 0000000000000000 R09: 0000000000000000 [ 1112.799595] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f4f2c6d6390 [ 1112.808405] R13: 0000561e31a343d0 R14: 0000000000000007 R15: 0000000000000003 | [ 1113.179661] #1: ff110001dcd7b880 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 1113.190621] #2: ff1100012335ab50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1113.201777] #3: ff110001233c9d68 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1113.211867] | [ 1113.214331] =============================================\x0a [ 1235.420950] INFO: task python3:5695 blocked for more than 368 seconds. [ 1235.429098] Not tainted 7.1.0-rc2-lwdd-g7b5999081c7b #1 [ 1235.436347] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1235.445938] task:python3 state:D stack:25672 pid:5695 tgid:5695 ppid:5694 task_flags:0x400100 flags:0x00080002 [ 1235.459144] Call Trace: [ 1235.462686] [ 1235.465835] __schedule+0xcba/0x1940 [ 1235.470654] ? io_schedule_timeout+0x140/0x140 [ 1235.476439] ? find_held_lock+0x2b/0x80 [ 1235.481546] ? __lock_release.isra.0+0x6b/0x1a0 [ 1235.487431] schedule+0xd4/0x260 [ 1235.491849] schedule_preempt_disabled+0x18/0x30 [ 1235.497830] __mutex_lock+0x6a3/0x2000 [ 1235.502833] ? threaded_store+0x132/0x220 [ 1235.508136] ? ww_mutex_lock+0x160/0x160 [ 1235.513335] ? selinux_inode_free_security+0x330/0x330 [ 1235.519904] ? cap_capable+0x1d6/0x3e0 [ 1235.524912] ? threaded_store+0x132/0x220 [ 1235.530212] threaded_store+0x132/0x220 [ 1235.535313] ? xps_rxqs_store+0x2e0/0x2e0 [ 1235.540610] ? sysfs_file_kobj+0xbd/0x1e0 [ 1235.545906] ? sysfs_kf_write+0x65/0x160 [ 1235.551108] ? sysfs_file_ops+0x140/0x140 [ 1235.556405] kernfs_fop_write_iter+0x335/0x510 [ 1235.562191] ? kernfs_ops+0xf0/0xf0 [ 1235.566901] new_sync_write+0x333/0x750 [ 1235.571996] ? __lock_acquire+0x508/0xc10 [ 1235.577292] ? new_sync_read+0x740/0x740 [ 1235.582494] ? lock_acquire.part.0+0xbc/0x260 [ 1235.588181] ? ksys_write+0x116/0x250 [ 1235.593090] vfs_write+0x657/0x9b0 [ 1235.597707] ksys_write+0x116/0x250 [ 1235.602419] ? __ia32_sys_read+0xc0/0xc0 [ 1235.607616] ? kernfs_fop_llseek+0x160/0x1f0 [ 1235.613206] ? rcu_is_watching+0x15/0xd0 [ 1235.618411] do_syscall_64+0xf3/0x650 [ 1235.623320] ? trace_hardirqs_off+0xd/0x30 [ 1235.628716] ? exc_page_fault+0xda/0xf0 [ 1235.633817] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1235.640287] RIP: 0033:0x7f4f2c85e22e [ 1235.645096] RSP: 002b:00007ffd5a6bfa30 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1235.654397] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f4f2c85e22e [ 1235.663206] RDX: 0000000000000001 RSI: 0000561e31a343d0 RDI: 0000000000000007 [ 1235.672015] RBP: 00007ffd5a6bfa40 R08: 0000000000000000 R09: 0000000000000000 [ 1235.680823] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f4f2c6d6390 [ 1235.689632] R13: 0000561e31a343d0 R14: 0000000000000007 R15: 0000000000000003 | [ 1236.089586] #1: ff110001dcd7b880 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 1236.100548] #2: ff1100012335ab50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1236.111694] #3: ff110001233c9d68 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1236.121786] | [ 1236.124254] =============================================\x0a [ 1358.302173] INFO: task python3:5695 blocked for more than 491 seconds. [ 1358.310320] Not tainted 7.1.0-rc2-lwdd-g7b5999081c7b #1 [ 1358.317560] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1358.327141] task:python3 state:D stack:25672 pid:5695 tgid:5695 ppid:5694 task_flags:0x400100 flags:0x00080002 [ 1358.340346] Call Trace: [ 1358.343885] [ 1358.347032] __schedule+0xcba/0x1940 [ 1358.351849] ? io_schedule_timeout+0x140/0x140 [ 1358.357635] ? find_held_lock+0x2b/0x80 [ 1358.362736] ? __lock_release.isra.0+0x6b/0x1a0 [ 1358.368619] schedule+0xd4/0x260 [ 1358.373026] schedule_preempt_disabled+0x18/0x30 [ 1358.379006] __mutex_lock+0x6a3/0x2000 [ 1358.383999] ? threaded_store+0x132/0x220 [ 1358.389298] ? ww_mutex_lock+0x160/0x160 [ 1358.394487] ? selinux_inode_free_security+0x330/0x330 [ 1358.401057] ? cap_capable+0x1d6/0x3e0 [ 1358.406065] ? threaded_store+0x132/0x220 [ 1358.411361] threaded_store+0x132/0x220 [ 1358.416460] ? xps_rxqs_store+0x2e0/0x2e0 [ 1358.421759] ? sysfs_file_kobj+0xbd/0x1e0 [ 1358.427056] ? sysfs_kf_write+0x65/0x160 [ 1358.432257] ? sysfs_file_ops+0x140/0x140 [ 1358.437553] kernfs_fop_write_iter+0x335/0x510 [ 1358.443334] ? kernfs_ops+0xf0/0xf0 [ 1358.448045] new_sync_write+0x333/0x750 [ 1358.453141] ? __lock_acquire+0x508/0xc10 [ 1358.458427] ? new_sync_read+0x740/0x740 [ 1358.463625] ? lock_acquire.part.0+0xbc/0x260 [ 1358.469312] ? ksys_write+0x116/0x250 [ 1358.474221] vfs_write+0x657/0x9b0 [ 1358.478825] ksys_write+0x116/0x250 [ 1358.483533] ? __ia32_sys_read+0xc0/0xc0 [ 1358.488730] ? kernfs_fop_llseek+0x160/0x1f0 [ 1358.494317] ? rcu_is_watching+0x15/0xd0 [ 1358.499517] do_syscall_64+0xf3/0x650 [ 1358.504414] ? trace_hardirqs_off+0xd/0x30 [ 1358.509806] ? exc_page_fault+0xda/0xf0 [ 1358.514905] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1358.521370] RIP: 0033:0x7f4f2c85e22e [ 1358.526178] RSP: 002b:00007ffd5a6bfa30 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1358.535476] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f4f2c85e22e [ 1358.544282] RDX: 0000000000000001 RSI: 0000561e31a343d0 RDI: 0000000000000007 [ 1358.553088] RBP: 00007ffd5a6bfa40 R08: 0000000000000000 R09: 0000000000000000 [ 1358.561893] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f4f2c6d6390 [ 1358.570699] R13: 0000561e31a343d0 R14: 0000000000000007 R15: 0000000000000003 Finger prints: __schedule:schedule:schedule_preempt_disabled:__mutex_lock:threaded_store