====================================== | [ 783.474909] nipa-hw-worker: drivers/net:napi_threaded.py: timed out | [ 783.477288] nipa-hw-worker: [5/46] Retrying drivers/net:napi_threaded.py | [ 783.628090] kselftest: Running tests in drivers/net [ 989.663892] INFO: task python3:5696 blocked for more than 122 seconds. [ 989.672126] Not tainted 7.1.0-rc2-ympb-g53cf78e77f48 #1 [ 989.680052] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 989.689645] task:python3 state:D stack:25672 pid:5696 tgid:5696 ppid:5695 task_flags:0x400100 flags:0x00080000 [ 989.702852] Call Trace: [ 989.706394] [ 989.709545] __schedule+0xcba/0x1940 [ 989.714366] ? io_schedule_timeout+0x140/0x140 [ 989.720150] ? find_held_lock+0x2b/0x80 [ 989.725254] ? __lock_release.isra.0+0x6b/0x1a0 [ 989.731133] schedule+0xd4/0x260 [ 989.735553] schedule_preempt_disabled+0x18/0x30 [ 989.741531] __mutex_lock+0x6a3/0x2000 [ 989.746538] ? threaded_store+0x132/0x220 [ 989.751841] ? ww_mutex_lock+0x160/0x160 [ 989.757039] ? selinux_inode_free_security+0x330/0x330 [ 989.763612] ? cap_capable+0x1d6/0x3e0 [ 989.768620] ? threaded_store+0x132/0x220 [ 989.773918] threaded_store+0x132/0x220 [ 989.779014] ? xps_rxqs_store+0x2e0/0x2e0 [ 989.784315] ? sysfs_file_kobj+0xbd/0x1e0 [ 989.789613] ? sysfs_kf_write+0x65/0x160 [ 989.794817] ? sysfs_file_ops+0x140/0x140 [ 989.800111] kernfs_fop_write_iter+0x335/0x510 [ 989.805895] ? kernfs_ops+0xf0/0xf0 [ 989.810606] new_sync_write+0x333/0x750 [ 989.815702] ? __lock_acquire+0x508/0xc10 [ 989.821001] ? new_sync_read+0x740/0x740 [ 989.826193] ? lock_acquire.part.0+0xbc/0x260 [ 989.831880] ? ksys_write+0x116/0x250 [ 989.836790] vfs_write+0x657/0x9b0 [ 989.841403] ksys_write+0x116/0x250 [ 989.846117] ? __ia32_sys_read+0xc0/0xc0 [ 989.851316] ? kernfs_fop_llseek+0x160/0x1f0 [ 989.856908] ? rcu_is_watching+0x15/0xd0 [ 989.862111] do_syscall_64+0xf3/0x650 [ 989.867017] ? trace_hardirqs_off+0xd/0x30 [ 989.872415] ? exc_page_fault+0xda/0xf0 [ 989.877517] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 989.883978] RIP: 0033:0x7fe3a7a3d22e [ 989.888790] RSP: 002b:00007ffd7161c760 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 989.898088] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007fe3a7a3d22e [ 989.906896] RDX: 0000000000000001 RSI: 000055acad644fe0 RDI: 0000000000000007 [ 989.915694] RBP: 00007ffd7161c770 R08: 0000000000000000 R09: 0000000000000000 [ 989.924504] R10: 0000000000000000 R11: 0000000000000202 R12: 00007fe3a78b5390 [ 989.933313] R13: 000055acad644fe0 R14: 0000000000000007 R15: 0000000000000003 | [ 990.315566] #2: ff11000122374b50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 990.326732] #3: ff110001223e1d68 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 990.336820] | [ 990.339288] =============================================\x0a | [ 1027.017208] kworker/u130:1 (238) used greatest stack depth: 21456 bytes left [ 1112.546125] INFO: task python3:5696 blocked for more than 245 seconds. [ 1112.554272] Not tainted 7.1.0-rc2-ympb-g53cf78e77f48 #1 [ 1112.561522] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1112.571112] task:python3 state:D stack:25672 pid:5696 tgid:5696 ppid:5695 task_flags:0x400100 flags:0x00080000 [ 1112.584307] Call Trace: [ 1112.587847] [ 1112.591000] __schedule+0xcba/0x1940 [ 1112.595821] ? io_schedule_timeout+0x140/0x140 [ 1112.601605] ? find_held_lock+0x2b/0x80 [ 1112.606714] ? __lock_release.isra.0+0x6b/0x1a0 [ 1112.612602] schedule+0xd4/0x260 [ 1112.617021] schedule_preempt_disabled+0x18/0x30 [ 1112.622999] __mutex_lock+0x6a3/0x2000 [ 1112.628003] ? threaded_store+0x132/0x220 [ 1112.633300] ? ww_mutex_lock+0x160/0x160 [ 1112.638499] ? selinux_inode_free_security+0x330/0x330 [ 1112.645067] ? cap_capable+0x1d6/0x3e0 [ 1112.650082] ? threaded_store+0x132/0x220 [ 1112.655510] threaded_store+0x132/0x220 [ 1112.660619] ? xps_rxqs_store+0x2e0/0x2e0 [ 1112.665914] ? sysfs_file_kobj+0xbd/0x1e0 [ 1112.671214] ? sysfs_kf_write+0x65/0x160 [ 1112.676415] ? sysfs_file_ops+0x140/0x140 [ 1112.681709] kernfs_fop_write_iter+0x335/0x510 [ 1112.687493] ? kernfs_ops+0xf0/0xf0 [ 1112.692205] new_sync_write+0x333/0x750 [ 1112.697306] ? __lock_acquire+0x508/0xc10 [ 1112.702603] ? new_sync_read+0x740/0x740 [ 1112.707806] ? lock_acquire.part.0+0xbc/0x260 [ 1112.713495] ? ksys_write+0x116/0x250 [ 1112.718406] vfs_write+0x657/0x9b0 [ 1112.723021] ksys_write+0x116/0x250 [ 1112.727733] ? __ia32_sys_read+0xc0/0xc0 [ 1112.732932] ? kernfs_fop_llseek+0x160/0x1f0 [ 1112.738522] ? rcu_is_watching+0x15/0xd0 [ 1112.743724] do_syscall_64+0xf3/0x650 [ 1112.748630] ? trace_hardirqs_off+0xd/0x30 [ 1112.754028] ? exc_page_fault+0xda/0xf0 [ 1112.759133] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1112.765600] RIP: 0033:0x7fe3a7a3d22e [ 1112.770409] RSP: 002b:00007ffd7161c760 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1112.779707] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007fe3a7a3d22e [ 1112.788507] RDX: 0000000000000001 RSI: 000055acad644fe0 RDI: 0000000000000007 [ 1112.797316] RBP: 00007ffd7161c770 R08: 0000000000000000 R09: 0000000000000000 [ 1112.806124] R10: 0000000000000000 R11: 0000000000000202 R12: 00007fe3a78b5390 [ 1112.814926] R13: 000055acad644fe0 R14: 0000000000000007 R15: 0000000000000003 | [ 1113.186168] #1: ff110001ea4c2c80 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 1113.197128] #2: ff11000122374b50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1113.208287] #3: ff110001223e1d68 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1113.218378] | [ 1113.220843] =============================================\x0a [ 1235.426387] INFO: task python3:5696 blocked for more than 368 seconds. [ 1235.434537] Not tainted 7.1.0-rc2-ympb-g53cf78e77f48 #1 [ 1235.441788] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1235.451380] task:python3 state:D stack:25672 pid:5696 tgid:5696 ppid:5695 task_flags:0x400100 flags:0x00080002 [ 1235.464585] Call Trace: [ 1235.468127] [ 1235.471277] __schedule+0xcba/0x1940 [ 1235.476094] ? io_schedule_timeout+0x140/0x140 [ 1235.481880] ? find_held_lock+0x2b/0x80 [ 1235.486985] ? __lock_release.isra.0+0x6b/0x1a0 [ 1235.492869] schedule+0xd4/0x260 [ 1235.497287] schedule_preempt_disabled+0x18/0x30 [ 1235.503266] __mutex_lock+0x6a3/0x2000 [ 1235.508270] ? threaded_store+0x132/0x220 [ 1235.513569] ? ww_mutex_lock+0x160/0x160 [ 1235.518759] ? selinux_inode_free_security+0x330/0x330 [ 1235.525328] ? cap_capable+0x1d6/0x3e0 [ 1235.530354] ? threaded_store+0x132/0x220 [ 1235.535656] threaded_store+0x132/0x220 [ 1235.540757] ? xps_rxqs_store+0x2e0/0x2e0 [ 1235.546052] ? sysfs_file_kobj+0xbd/0x1e0 [ 1235.551341] ? sysfs_kf_write+0x65/0x160 [ 1235.556540] ? sysfs_file_ops+0x140/0x140 [ 1235.561838] kernfs_fop_write_iter+0x335/0x510 [ 1235.567625] ? kernfs_ops+0xf0/0xf0 [ 1235.572337] new_sync_write+0x333/0x750 [ 1235.577441] ? __lock_acquire+0x508/0xc10 [ 1235.582740] ? new_sync_read+0x740/0x740 [ 1235.587941] ? lock_acquire.part.0+0xbc/0x260 [ 1235.593630] ? ksys_write+0x116/0x250 [ 1235.598540] vfs_write+0x657/0x9b0 [ 1235.603157] ksys_write+0x116/0x250 [ 1235.607870] ? __ia32_sys_read+0xc0/0xc0 [ 1235.613071] ? kernfs_fop_llseek+0x160/0x1f0 [ 1235.618658] ? rcu_is_watching+0x15/0xd0 [ 1235.623863] do_syscall_64+0xf3/0x650 [ 1235.628769] ? trace_hardirqs_off+0xd/0x30 [ 1235.634166] ? exc_page_fault+0xda/0xf0 [ 1235.639260] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1235.645729] RIP: 0033:0x7fe3a7a3d22e [ 1235.650541] RSP: 002b:00007ffd7161c760 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1235.659842] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007fe3a7a3d22e [ 1235.668651] RDX: 0000000000000001 RSI: 000055acad644fe0 RDI: 0000000000000007 [ 1235.677461] RBP: 00007ffd7161c770 R08: 0000000000000000 R09: 0000000000000000 [ 1235.686269] R10: 0000000000000000 R11: 0000000000000202 R12: 00007fe3a78b5390 [ 1235.695078] R13: 000055acad644fe0 R14: 0000000000000007 R15: 0000000000000003 | [ 1236.106610] #1: ff110001ea4c2c80 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 1236.117571] #2: ff11000122374b50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1236.128729] #3: ff110001223e1d68 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1236.138819] | [ 1236.141285] =============================================\x0a [ 1358.308617] INFO: task python3:5696 blocked for more than 491 seconds. [ 1358.316765] Not tainted 7.1.0-rc2-ympb-g53cf78e77f48 #1 [ 1358.324017] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1358.333609] task:python3 state:D stack:25672 pid:5696 tgid:5696 ppid:5695 task_flags:0x400100 flags:0x00080002 [ 1358.346816] Call Trace: [ 1358.350356] [ 1358.353508] __schedule+0xcba/0x1940 [ 1358.358325] ? io_schedule_timeout+0x140/0x140 [ 1358.364112] ? find_held_lock+0x2b/0x80 [ 1358.369216] ? __lock_release.isra.0+0x6b/0x1a0 [ 1358.375094] schedule+0xd4/0x260 [ 1358.379511] schedule_preempt_disabled+0x18/0x30 [ 1358.385492] __mutex_lock+0x6a3/0x2000 [ 1358.390496] ? threaded_store+0x132/0x220 [ 1358.395785] ? ww_mutex_lock+0x160/0x160 [ 1358.400985] ? selinux_inode_free_security+0x330/0x330 [ 1358.407555] ? cap_capable+0x1d6/0x3e0 [ 1358.412564] ? threaded_store+0x132/0x220 [ 1358.417861] threaded_store+0x132/0x220 [ 1358.422961] ? xps_rxqs_store+0x2e0/0x2e0 [ 1358.428259] ? sysfs_file_kobj+0xbd/0x1e0 [ 1358.433558] ? sysfs_kf_write+0x65/0x160 [ 1358.438753] ? sysfs_file_ops+0x140/0x140 [ 1358.444050] kernfs_fop_write_iter+0x335/0x510 [ 1358.449834] ? kernfs_ops+0xf0/0xf0 [ 1358.454548] new_sync_write+0x333/0x750 [ 1358.459653] ? __lock_acquire+0x508/0xc10 [ 1358.464950] ? new_sync_read+0x740/0x740 [ 1358.470144] ? lock_acquire.part.0+0xbc/0x260 [ 1358.475831] ? ksys_write+0x116/0x250 [ 1358.480741] vfs_write+0x657/0x9b0 [ 1358.485355] ksys_write+0x116/0x250 [ 1358.490069] ? __ia32_sys_read+0xc0/0xc0 [ 1358.495266] ? kernfs_fop_llseek+0x160/0x1f0 [ 1358.500854] ? rcu_is_watching+0x15/0xd0 [ 1358.506057] do_syscall_64+0xf3/0x650 [ 1358.510963] ? trace_hardirqs_off+0xd/0x30 [ 1358.516357] ? exc_page_fault+0xda/0xf0 [ 1358.521464] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1358.527932] RIP: 0033:0x7fe3a7a3d22e [ 1358.532744] RSP: 002b:00007ffd7161c760 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1358.542045] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007fe3a7a3d22e [ 1358.550856] RDX: 0000000000000001 RSI: 000055acad644fe0 RDI: 0000000000000007 [ 1358.559664] RBP: 00007ffd7161c770 R08: 0000000000000000 R09: 0000000000000000 [ 1358.568472] R10: 0000000000000000 R11: 0000000000000202 R12: 00007fe3a78b5390 [ 1358.577282] R13: 000055acad644fe0 R14: 0000000000000007 R15: 0000000000000003 Finger prints: __schedule:schedule:schedule_preempt_disabled:__mutex_lock:threaded_store