====================================== | [ 789.564516] nipa-hw-worker: drivers/net:napi_threaded.py: timed out | [ 789.566978] nipa-hw-worker: [5/46] Retrying drivers/net:napi_threaded.py | [ 789.695084] kselftest: Running tests in drivers/net [ 989.703243] INFO: task python3:5811 blocked for more than 122 seconds. [ 989.711472] Not tainted 7.1.0-rc5-bfky-g4adaa508e1d3 #1 [ 989.719418] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 989.729006] task:python3 state:D stack:25672 pid:5811 tgid:5811 ppid:5810 task_flags:0x400100 flags:0x00080000 [ 989.742211] Call Trace: [ 989.745750] [ 989.748903] __schedule+0xcc3/0x19c0 [ 989.753723] ? io_schedule_timeout+0x140/0x140 [ 989.759510] ? find_held_lock+0x2b/0x80 [ 989.764619] ? __lock_release.isra.0+0x6b/0x1a0 [ 989.770504] schedule+0xd8/0x260 [ 989.774925] schedule_preempt_disabled+0x18/0x30 [ 989.780906] __mutex_lock+0x6af/0x2010 [ 989.785911] ? threaded_store+0x132/0x220 [ 989.791214] ? ww_mutex_lock+0x160/0x160 [ 989.796413] ? selinux_inode_free_security+0x330/0x330 [ 989.802984] ? cap_capable+0x1d6/0x3e0 [ 989.807995] ? threaded_store+0x132/0x220 [ 989.813293] threaded_store+0x132/0x220 [ 989.818394] ? xps_rxqs_store+0x2e0/0x2e0 [ 989.823692] ? sysfs_file_kobj+0xbd/0x1e0 [ 989.828990] ? sysfs_kf_write+0x65/0x160 [ 989.834190] ? sysfs_file_ops+0x140/0x140 [ 989.839477] kernfs_fop_write_iter+0x335/0x510 [ 989.845261] ? kernfs_ops+0xf0/0xf0 [ 989.849973] new_sync_write+0x333/0x750 [ 989.855078] ? __lock_acquire+0x508/0xc10 [ 989.860381] ? new_sync_read+0x740/0x740 [ 989.865584] ? lock_acquire.part.0+0xbc/0x260 [ 989.871270] ? ksys_write+0x116/0x250 [ 989.876175] vfs_write+0x657/0x9b0 [ 989.880792] ksys_write+0x116/0x250 [ 989.885506] ? __ia32_sys_read+0xc0/0xc0 [ 989.890708] ? kernfs_fop_llseek+0x160/0x1f0 [ 989.896300] ? rcu_is_watching+0x15/0xd0 [ 989.901504] do_syscall_64+0xf3/0x650 [ 989.906409] ? trace_hardirqs_off+0xd/0x30 [ 989.911806] ? exc_page_fault+0xda/0xf0 [ 989.916909] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 989.923379] RIP: 0033:0x7f0b5c55e22e [ 989.928189] RSP: 002b:00007fff642fef90 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 989.937489] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f0b5c55e22e [ 989.946300] RDX: 0000000000000001 RSI: 000055ed165616b0 RDI: 0000000000000007 [ 989.955107] RBP: 00007fff642fefa0 R08: 0000000000000000 R09: 0000000000000000 [ 989.963908] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f0b5c3d6390 [ 989.972718] R13: 000055ed165616b0 R14: 0000000000000007 R15: 0000000000000003 | [ 990.349849] #1: ff110001eede7480 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 990.360811] #2: ff11000122f6eb50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 990.371972] #3: ff11000123039d68 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 990.382062] | [ 990.384529] =============================================\x0a [ 1112.584436] INFO: task python3:5811 blocked for more than 245 seconds. [ 1112.592584] Not tainted 7.1.0-rc5-bfky-g4adaa508e1d3 #1 [ 1112.599835] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1112.609425] task:python3 state:D stack:25672 pid:5811 tgid:5811 ppid:5810 task_flags:0x400100 flags:0x00080000 [ 1112.622633] Call Trace: [ 1112.626173] [ 1112.629324] __schedule+0xcc3/0x19c0 [ 1112.634142] ? io_schedule_timeout+0x140/0x140 [ 1112.639928] ? find_held_lock+0x2b/0x80 [ 1112.645035] ? __lock_release.isra.0+0x6b/0x1a0 [ 1112.650919] schedule+0xd8/0x260 [ 1112.655340] schedule_preempt_disabled+0x18/0x30 [ 1112.661322] __mutex_lock+0x6af/0x2010 [ 1112.666330] ? threaded_store+0x132/0x220 [ 1112.671629] ? ww_mutex_lock+0x160/0x160 [ 1112.676823] ? selinux_inode_free_security+0x330/0x330 [ 1112.683392] ? cap_capable+0x1d6/0x3e0 [ 1112.688402] ? threaded_store+0x132/0x220 [ 1112.693700] threaded_store+0x132/0x220 [ 1112.698804] ? xps_rxqs_store+0x2e0/0x2e0 [ 1112.704099] ? sysfs_file_kobj+0xbd/0x1e0 [ 1112.709390] ? sysfs_kf_write+0x65/0x160 [ 1112.714584] ? sysfs_file_ops+0x140/0x140 [ 1112.719881] kernfs_fop_write_iter+0x335/0x510 [ 1112.725659] ? kernfs_ops+0xf0/0xf0 [ 1112.730369] new_sync_write+0x333/0x750 [ 1112.735464] ? __lock_acquire+0x508/0xc10 [ 1112.740760] ? new_sync_read+0x740/0x740 [ 1112.745961] ? lock_acquire.part.0+0xbc/0x260 [ 1112.751649] ? ksys_write+0x116/0x250 [ 1112.756562] vfs_write+0x657/0x9b0 [ 1112.761178] ksys_write+0x116/0x250 [ 1112.765891] ? __ia32_sys_read+0xc0/0xc0 [ 1112.771091] ? kernfs_fop_llseek+0x160/0x1f0 [ 1112.776682] ? rcu_is_watching+0x15/0xd0 [ 1112.781887] do_syscall_64+0xf3/0x650 [ 1112.786795] ? trace_hardirqs_off+0xd/0x30 [ 1112.792190] ? exc_page_fault+0xda/0xf0 [ 1112.797293] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1112.803763] RIP: 0033:0x7f0b5c55e22e [ 1112.808573] RSP: 002b:00007fff642fef90 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1112.817865] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f0b5c55e22e [ 1112.826675] RDX: 0000000000000001 RSI: 000055ed165616b0 RDI: 0000000000000007 [ 1112.835482] RBP: 00007fff642fefa0 R08: 0000000000000000 R09: 0000000000000000 [ 1112.844289] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f0b5c3d6390 [ 1112.853099] R13: 000055ed165616b0 R14: 0000000000000007 R15: 0000000000000003 | [ 1113.229989] #1: ff110001eede7480 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 1113.240953] #2: ff11000122f6eb50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1113.252110] #3: ff11000123039d68 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1113.262192] | [ 1113.264658] =============================================\x0a [ 1235.465650] INFO: task python3:5811 blocked for more than 368 seconds. [ 1235.473799] Not tainted 7.1.0-rc5-bfky-g4adaa508e1d3 #1 [ 1235.481050] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1235.490639] task:python3 state:D stack:25672 pid:5811 tgid:5811 ppid:5810 task_flags:0x400100 flags:0x00080002 [ 1235.503846] Call Trace: [ 1235.507382] [ 1235.510534] __schedule+0xcc3/0x19c0 [ 1235.515350] ? io_schedule_timeout+0x140/0x140 [ 1235.521133] ? find_held_lock+0x2b/0x80 [ 1235.526228] ? __lock_release.isra.0+0x6b/0x1a0 [ 1235.532111] schedule+0xd8/0x260 [ 1235.536526] schedule_preempt_disabled+0x18/0x30 [ 1235.542506] __mutex_lock+0x6af/0x2010 [ 1235.547509] ? threaded_store+0x132/0x220 [ 1235.552807] ? ww_mutex_lock+0x160/0x160 [ 1235.558005] ? selinux_inode_free_security+0x330/0x330 [ 1235.564575] ? cap_capable+0x1d6/0x3e0 [ 1235.569583] ? threaded_store+0x132/0x220 [ 1235.574878] threaded_store+0x132/0x220 [ 1235.579977] ? xps_rxqs_store+0x2e0/0x2e0 [ 1235.585276] ? sysfs_file_kobj+0xbd/0x1e0 [ 1235.590574] ? sysfs_kf_write+0x65/0x160 [ 1235.595775] ? sysfs_file_ops+0x140/0x140 [ 1235.601070] kernfs_fop_write_iter+0x335/0x510 [ 1235.606851] ? kernfs_ops+0xf0/0xf0 [ 1235.611562] new_sync_write+0x333/0x750 [ 1235.616666] ? __lock_acquire+0x508/0xc10 [ 1235.621961] ? new_sync_read+0x740/0x740 [ 1235.627163] ? lock_acquire.part.0+0xbc/0x260 [ 1235.632847] ? ksys_write+0x116/0x250 [ 1235.637755] vfs_write+0x657/0x9b0 [ 1235.642362] ksys_write+0x116/0x250 [ 1235.647072] ? __ia32_sys_read+0xc0/0xc0 [ 1235.652268] ? kernfs_fop_llseek+0x160/0x1f0 [ 1235.657853] ? rcu_is_watching+0x15/0xd0 [ 1235.663054] do_syscall_64+0xf3/0x650 [ 1235.667956] ? trace_hardirqs_off+0xd/0x30 [ 1235.673348] ? exc_page_fault+0xda/0xf0 [ 1235.678449] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1235.684915] RIP: 0033:0x7f0b5c55e22e [ 1235.689722] RSP: 002b:00007fff642fef90 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1235.699018] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f0b5c55e22e [ 1235.707825] RDX: 0000000000000001 RSI: 000055ed165616b0 RDI: 0000000000000007 [ 1235.716632] RBP: 00007fff642fefa0 R08: 0000000000000000 R09: 0000000000000000 [ 1235.725440] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f0b5c3d6390 [ 1235.734246] R13: 000055ed165616b0 R14: 0000000000000007 R15: 0000000000000003 | [ 1236.162218] #2: ff11000122f6eb50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1236.173374] #3: ff11000123039d68 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1236.183465] | [ 1236.185920] =============================================\x0a | [ 1247.753748] kworker/u130:3 (526) used greatest stack depth: 21936 bytes left [ 1358.345859] INFO: task python3:5811 blocked for more than 491 seconds. [ 1358.354006] Not tainted 7.1.0-rc5-bfky-g4adaa508e1d3 #1 [ 1358.361256] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1358.370847] task:python3 state:D stack:25672 pid:5811 tgid:5811 ppid:5810 task_flags:0x400100 flags:0x00080002 [ 1358.384050] Call Trace: [ 1358.387588] [ 1358.390736] __schedule+0xcc3/0x19c0 [ 1358.395553] ? io_schedule_timeout+0x140/0x140 [ 1358.401339] ? find_held_lock+0x2b/0x80 [ 1358.406444] ? __lock_release.isra.0+0x6b/0x1a0 [ 1358.412328] schedule+0xd8/0x260 [ 1358.416746] schedule_preempt_disabled+0x18/0x30 [ 1358.422721] __mutex_lock+0x6af/0x2010 [ 1358.427723] ? threaded_store+0x132/0x220 [ 1358.433019] ? ww_mutex_lock+0x160/0x160 [ 1358.438217] ? selinux_inode_free_security+0x330/0x330 [ 1358.444784] ? cap_capable+0x1d6/0x3e0 [ 1358.449793] ? threaded_store+0x132/0x220 [ 1358.455089] threaded_store+0x132/0x220 [ 1358.460189] ? xps_rxqs_store+0x2e0/0x2e0 [ 1358.465485] ? sysfs_file_kobj+0xbd/0x1e0 [ 1358.470784] ? sysfs_kf_write+0x65/0x160 [ 1358.475988] ? sysfs_file_ops+0x140/0x140 [ 1358.481284] kernfs_fop_write_iter+0x335/0x510 [ 1358.487070] ? kernfs_ops+0xf0/0xf0 [ 1358.491770] new_sync_write+0x333/0x750 [ 1358.496873] ? __lock_acquire+0x508/0xc10 [ 1358.502168] ? new_sync_read+0x740/0x740 [ 1358.507369] ? lock_acquire.part.0+0xbc/0x260 [ 1358.513053] ? ksys_write+0x116/0x250 [ 1358.517961] vfs_write+0x657/0x9b0 [ 1358.522577] ksys_write+0x116/0x250 [ 1358.527288] ? __ia32_sys_read+0xc0/0xc0 [ 1358.532485] ? kernfs_fop_llseek+0x160/0x1f0 [ 1358.538075] ? rcu_is_watching+0x15/0xd0 [ 1358.543278] do_syscall_64+0xf3/0x650 [ 1358.548182] ? trace_hardirqs_off+0xd/0x30 [ 1358.553576] ? exc_page_fault+0xda/0xf0 [ 1358.558678] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1358.565139] RIP: 0033:0x7f0b5c55e22e [ 1358.569949] RSP: 002b:00007fff642fef90 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1358.579247] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f0b5c55e22e [ 1358.588056] RDX: 0000000000000001 RSI: 000055ed165616b0 RDI: 0000000000000007 [ 1358.596861] RBP: 00007fff642fefa0 R08: 0000000000000000 R09: 0000000000000000 [ 1358.605666] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f0b5c3d6390 [ 1358.614474] R13: 000055ed165616b0 R14: 0000000000000007 R15: 0000000000000003 Finger prints: __schedule:schedule:schedule_preempt_disabled:__mutex_lock:threaded_store