====================================== | [ 789.633892] nipa-hw-worker: drivers/net:napi_threaded.py: timed out | [ 789.636343] nipa-hw-worker: [5/46] Retrying drivers/net:napi_threaded.py | [ 789.786974] kselftest: Running tests in drivers/net [ 989.691876] INFO: task python3:5811 blocked for more than 122 seconds. [ 989.700114] Not tainted 7.1.0-rc5-tlvk-gc1c90e81b188 #1 [ 989.708049] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 989.717640] task:python3 state:D stack:25672 pid:5811 tgid:5811 ppid:5810 task_flags:0x400100 flags:0x00080000 [ 989.730846] Call Trace: [ 989.734385] [ 989.737535] __schedule+0xcc3/0x19c0 [ 989.742352] ? io_schedule_timeout+0x140/0x140 [ 989.748135] ? find_held_lock+0x2b/0x80 [ 989.753232] ? __lock_release.isra.0+0x6b/0x1a0 [ 989.759119] schedule+0xd8/0x260 [ 989.763537] schedule_preempt_disabled+0x18/0x30 [ 989.769515] __mutex_lock+0x6af/0x2010 [ 989.774520] ? threaded_store+0x132/0x220 [ 989.779821] ? ww_mutex_lock+0x160/0x160 [ 989.785023] ? selinux_inode_free_security+0x330/0x330 [ 989.791593] ? cap_capable+0x1d6/0x3e0 [ 989.796603] ? threaded_store+0x132/0x220 [ 989.801902] threaded_store+0x132/0x220 [ 989.807004] ? xps_rxqs_store+0x2e0/0x2e0 [ 989.812294] ? sysfs_file_kobj+0xbd/0x1e0 [ 989.817597] ? sysfs_kf_write+0x65/0x160 [ 989.822800] ? sysfs_file_ops+0x140/0x140 [ 989.828095] kernfs_fop_write_iter+0x335/0x510 [ 989.833879] ? kernfs_ops+0xf0/0xf0 [ 989.838592] new_sync_write+0x333/0x750 [ 989.843697] ? __lock_acquire+0x508/0xc10 [ 989.848995] ? new_sync_read+0x740/0x740 [ 989.854189] ? lock_acquire.part.0+0xbc/0x260 [ 989.859878] ? ksys_write+0x116/0x250 [ 989.864789] vfs_write+0x657/0x9b0 [ 989.869402] ksys_write+0x116/0x250 [ 989.874116] ? __ia32_sys_read+0xc0/0xc0 [ 989.879316] ? kernfs_fop_llseek+0x160/0x1f0 [ 989.884905] ? rcu_is_watching+0x15/0xd0 [ 989.890098] do_syscall_64+0xf3/0x650 [ 989.895004] ? trace_hardirqs_off+0xd/0x30 [ 989.900398] ? exc_page_fault+0xda/0xf0 [ 989.905502] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 989.911969] RIP: 0033:0x7fee5ddf522e [ 989.916772] RSP: 002b:00007ffd0d8a6a10 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 989.926073] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007fee5ddf522e [ 989.934883] RDX: 0000000000000001 RSI: 00005567020736b0 RDI: 0000000000000007 [ 989.943689] RBP: 00007ffd0d8a6a20 R08: 0000000000000000 R09: 0000000000000000 [ 989.952498] R10: 0000000000000000 R11: 0000000000000202 R12: 00007fee5dc6d390 [ 989.961308] R13: 00005567020736b0 R14: 0000000000000007 R15: 0000000000000003 | [ 990.379059] #1: ff110001e42cac80 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 990.390021] #2: ff1100011172cb50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 990.401178] #3: ff110001116b1d68 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 990.411269] | [ 990.413737] =============================================\x0a [ 1112.572237] INFO: task python3:5811 blocked for more than 245 seconds. [ 1112.580382] Not tainted 7.1.0-rc5-tlvk-gc1c90e81b188 #1 [ 1112.587633] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1112.597223] task:python3 state:D stack:25672 pid:5811 tgid:5811 ppid:5810 task_flags:0x400100 flags:0x00080000 [ 1112.610428] Call Trace: [ 1112.613970] [ 1112.617116] __schedule+0xcc3/0x19c0 [ 1112.621935] ? io_schedule_timeout+0x140/0x140 [ 1112.627723] ? find_held_lock+0x2b/0x80 [ 1112.632831] ? __lock_release.isra.0+0x6b/0x1a0 [ 1112.638717] schedule+0xd8/0x260 [ 1112.643134] schedule_preempt_disabled+0x18/0x30 [ 1112.649113] __mutex_lock+0x6af/0x2010 [ 1112.654117] ? threaded_store+0x132/0x220 [ 1112.659417] ? ww_mutex_lock+0x160/0x160 [ 1112.664617] ? selinux_inode_free_security+0x330/0x330 [ 1112.671187] ? cap_capable+0x1d6/0x3e0 [ 1112.676197] ? threaded_store+0x132/0x220 [ 1112.681496] threaded_store+0x132/0x220 [ 1112.686597] ? xps_rxqs_store+0x2e0/0x2e0 [ 1112.691896] ? sysfs_file_kobj+0xbd/0x1e0 [ 1112.697197] ? sysfs_kf_write+0x65/0x160 [ 1112.702402] ? sysfs_file_ops+0x140/0x140 [ 1112.707699] kernfs_fop_write_iter+0x335/0x510 [ 1112.713474] ? kernfs_ops+0xf0/0xf0 [ 1112.718186] new_sync_write+0x333/0x750 [ 1112.723293] ? __lock_acquire+0x508/0xc10 [ 1112.728589] ? new_sync_read+0x740/0x740 [ 1112.733791] ? lock_acquire.part.0+0xbc/0x260 [ 1112.739479] ? ksys_write+0x116/0x250 [ 1112.744390] vfs_write+0x657/0x9b0 [ 1112.749009] ksys_write+0x116/0x250 [ 1112.753723] ? __ia32_sys_read+0xc0/0xc0 [ 1112.758922] ? kernfs_fop_llseek+0x160/0x1f0 [ 1112.764513] ? rcu_is_watching+0x15/0xd0 [ 1112.769716] do_syscall_64+0xf3/0x650 [ 1112.774621] ? trace_hardirqs_off+0xd/0x30 [ 1112.780017] ? exc_page_fault+0xda/0xf0 [ 1112.785111] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1112.791578] RIP: 0033:0x7fee5ddf522e [ 1112.796389] RSP: 002b:00007ffd0d8a6a10 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1112.805689] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007fee5ddf522e [ 1112.814499] RDX: 0000000000000001 RSI: 00005567020736b0 RDI: 0000000000000007 [ 1112.823306] RBP: 00007ffd0d8a6a20 R08: 0000000000000000 R09: 0000000000000000 [ 1112.832114] R10: 0000000000000000 R11: 0000000000000202 R12: 00007fee5dc6d390 [ 1112.840926] R13: 00005567020736b0 R14: 0000000000000007 R15: 0000000000000003 | [ 1113.247095] #1: ff110001e42cac80 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 1113.258059] #2: ff1100011172cb50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1113.269223] #3: ff110001116b1d68 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1113.279321] | [ 1113.281795] =============================================\x0a [ 1235.454563] INFO: task python3:5811 blocked for more than 368 seconds. [ 1235.462709] Not tainted 7.1.0-rc5-tlvk-gc1c90e81b188 #1 [ 1235.469960] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1235.479547] task:python3 state:D stack:25672 pid:5811 tgid:5811 ppid:5810 task_flags:0x400100 flags:0x00080002 [ 1235.492750] Call Trace: [ 1235.496289] [ 1235.499437] __schedule+0xcc3/0x19c0 [ 1235.504250] ? io_schedule_timeout+0x140/0x140 [ 1235.510035] ? find_held_lock+0x2b/0x80 [ 1235.515137] ? __lock_release.isra.0+0x6b/0x1a0 [ 1235.521022] schedule+0xd8/0x260 [ 1235.525439] schedule_preempt_disabled+0x18/0x30 [ 1235.531418] __mutex_lock+0x6af/0x2010 [ 1235.536421] ? threaded_store+0x132/0x220 [ 1235.541710] ? ww_mutex_lock+0x160/0x160 [ 1235.546906] ? selinux_inode_free_security+0x330/0x330 [ 1235.553476] ? cap_capable+0x1d6/0x3e0 [ 1235.558486] ? threaded_store+0x132/0x220 [ 1235.563780] threaded_store+0x132/0x220 [ 1235.568881] ? xps_rxqs_store+0x2e0/0x2e0 [ 1235.574175] ? sysfs_file_kobj+0xbd/0x1e0 [ 1235.579474] ? sysfs_kf_write+0x65/0x160 [ 1235.584673] ? sysfs_file_ops+0x140/0x140 [ 1235.589967] kernfs_fop_write_iter+0x335/0x510 [ 1235.595750] ? kernfs_ops+0xf0/0xf0 [ 1235.600458] new_sync_write+0x333/0x750 [ 1235.605560] ? __lock_acquire+0x508/0xc10 [ 1235.610856] ? new_sync_read+0x740/0x740 [ 1235.616053] ? lock_acquire.part.0+0xbc/0x260 [ 1235.621737] ? ksys_write+0x116/0x250 [ 1235.626647] vfs_write+0x657/0x9b0 [ 1235.631261] ksys_write+0x116/0x250 [ 1235.635971] ? __ia32_sys_read+0xc0/0xc0 [ 1235.641170] ? kernfs_fop_llseek+0x160/0x1f0 [ 1235.646757] ? rcu_is_watching+0x15/0xd0 [ 1235.651959] do_syscall_64+0xf3/0x650 [ 1235.656865] ? trace_hardirqs_off+0xd/0x30 [ 1235.662257] ? exc_page_fault+0xda/0xf0 [ 1235.667361] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1235.673827] RIP: 0033:0x7fee5ddf522e [ 1235.678626] RSP: 002b:00007ffd0d8a6a10 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1235.687922] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007fee5ddf522e [ 1235.696728] RDX: 0000000000000001 RSI: 00005567020736b0 RDI: 0000000000000007 [ 1235.705533] RBP: 00007ffd0d8a6a20 R08: 0000000000000000 R09: 0000000000000000 [ 1235.714340] R10: 0000000000000000 R11: 0000000000000202 R12: 00007fee5dc6d390 [ 1235.723146] R13: 00005567020736b0 R14: 0000000000000007 R15: 0000000000000003 | [ 1236.129192] #1: ff110001e42cac80 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 1236.140150] #2: ff1100011172cb50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1236.151303] #3: ff110001116b1d68 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1236.161391] | [ 1236.163855] =============================================\x0a [ 1358.334927] INFO: task python3:5811 blocked for more than 491 seconds. [ 1358.343266] Not tainted 7.1.0-rc5-tlvk-gc1c90e81b188 #1 [ 1358.350521] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1358.360113] task:python3 state:D stack:25672 pid:5811 tgid:5811 ppid:5810 task_flags:0x400100 flags:0x00080002 [ 1358.373321] Call Trace: [ 1358.376863] [ 1358.380012] __schedule+0xcc3/0x19c0 [ 1358.384832] ? io_schedule_timeout+0x140/0x140 [ 1358.390614] ? find_held_lock+0x2b/0x80 [ 1358.395721] ? __lock_release.isra.0+0x6b/0x1a0 [ 1358.401606] schedule+0xd8/0x260 [ 1358.406023] schedule_preempt_disabled+0x18/0x30 [ 1358.412000] __mutex_lock+0x6af/0x2010 [ 1358.417004] ? threaded_store+0x132/0x220 [ 1358.422302] ? ww_mutex_lock+0x160/0x160 [ 1358.427503] ? selinux_inode_free_security+0x330/0x330 [ 1358.434075] ? cap_capable+0x1d6/0x3e0 [ 1358.439085] ? threaded_store+0x132/0x220 [ 1358.444384] threaded_store+0x132/0x220 [ 1358.449486] ? xps_rxqs_store+0x2e0/0x2e0 [ 1358.454784] ? sysfs_file_kobj+0xbd/0x1e0 [ 1358.460083] ? sysfs_kf_write+0x65/0x160 [ 1358.465283] ? sysfs_file_ops+0x140/0x140 [ 1358.470571] kernfs_fop_write_iter+0x335/0x510 [ 1358.476358] ? kernfs_ops+0xf0/0xf0 [ 1358.481071] new_sync_write+0x333/0x750 [ 1358.486176] ? __lock_acquire+0x508/0xc10 [ 1358.491474] ? new_sync_read+0x740/0x740 [ 1358.496667] ? lock_acquire.part.0+0xbc/0x260 [ 1358.502354] ? ksys_write+0x116/0x250 [ 1358.507265] vfs_write+0x657/0x9b0 [ 1358.511883] ksys_write+0x116/0x250 [ 1358.516597] ? __ia32_sys_read+0xc0/0xc0 [ 1358.521798] ? kernfs_fop_llseek+0x160/0x1f0 [ 1358.527389] ? rcu_is_watching+0x15/0xd0 [ 1358.532590] do_syscall_64+0xf3/0x650 [ 1358.537489] ? trace_hardirqs_off+0xd/0x30 [ 1358.542886] ? exc_page_fault+0xda/0xf0 [ 1358.547990] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1358.554457] RIP: 0033:0x7fee5ddf522e [ 1358.559257] RSP: 002b:00007ffd0d8a6a10 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1358.568548] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007fee5ddf522e [ 1358.577358] RDX: 0000000000000001 RSI: 00005567020736b0 RDI: 0000000000000007 [ 1358.586168] RBP: 00007ffd0d8a6a20 R08: 0000000000000000 R09: 0000000000000000 [ 1358.594978] R10: 0000000000000000 R11: 0000000000000202 R12: 00007fee5dc6d390 [ 1358.603788] R13: 00005567020736b0 R14: 0000000000000007 R15: 0000000000000003 Finger prints: __schedule:schedule:schedule_preempt_disabled:__mutex_lock:threaded_store