====================================== | [ 789.792975] nipa-hw-worker: drivers/net:napi_threaded.py: timed out | [ 789.795599] nipa-hw-worker: [5/44] Retrying drivers/net:napi_threaded.py | [ 789.926248] kselftest: Running tests in drivers/net [ 989.685905] INFO: task python3:5912 blocked for more than 122 seconds. [ 989.694138] Not tainted 7.1.0-rc1-kvpk-g8d59040b039c #1 [ 989.702073] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 989.711659] task:python3 state:D stack:25672 pid:5912 tgid:5912 ppid:5911 task_flags:0x400100 flags:0x00080000 [ 989.724864] Call Trace: [ 989.728404] [ 989.731552] __schedule+0xcba/0x1940 [ 989.736369] ? io_schedule_timeout+0x140/0x140 [ 989.742153] ? find_held_lock+0x2b/0x80 [ 989.747247] ? __lock_release.isra.0+0x6b/0x1a0 [ 989.753132] schedule+0xd4/0x260 [ 989.757551] schedule_preempt_disabled+0x18/0x30 [ 989.763531] __mutex_lock+0x6a3/0x2000 [ 989.768536] ? threaded_store+0x132/0x220 [ 989.773833] ? ww_mutex_lock+0x160/0x160 [ 989.779033] ? selinux_inode_free_security+0x330/0x330 [ 989.785600] ? cap_capable+0x1d6/0x3e0 [ 989.790608] ? threaded_store+0x132/0x220 [ 989.795904] threaded_store+0x132/0x220 [ 989.801003] ? xps_rxqs_store+0x2e0/0x2e0 [ 989.806305] ? sysfs_file_kobj+0xbd/0x1e0 [ 989.811601] ? sysfs_kf_write+0x65/0x160 [ 989.816802] ? sysfs_file_ops+0x140/0x140 [ 989.822098] kernfs_fop_write_iter+0x335/0x510 [ 989.827881] ? kernfs_ops+0xf0/0xf0 [ 989.832591] new_sync_write+0x333/0x750 [ 989.837694] ? __lock_acquire+0x508/0xc10 [ 989.842990] ? new_sync_read+0x740/0x740 [ 989.848188] ? lock_acquire.part.0+0xbc/0x260 [ 989.853874] ? ksys_write+0x116/0x250 [ 989.858783] vfs_write+0x657/0x9b0 [ 989.863396] ksys_write+0x116/0x250 [ 989.868104] ? __ia32_sys_read+0xc0/0xc0 [ 989.873300] ? kernfs_fop_llseek+0x160/0x1f0 [ 989.878886] ? rcu_is_watching+0x15/0xd0 [ 989.884088] do_syscall_64+0xf3/0x650 [ 989.888993] ? trace_hardirqs_off+0xd/0x30 [ 989.894388] ? exc_page_fault+0xda/0xf0 [ 989.899487] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 989.905951] RIP: 0033:0x7f2fb23be22e [ 989.910758] RSP: 002b:00007ffc633ae2e0 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 989.920057] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f2fb23be22e [ 989.928864] RDX: 0000000000000001 RSI: 0000559bcde0fa10 RDI: 0000000000000007 [ 989.937669] RBP: 00007ffc633ae2f0 R08: 0000000000000000 R09: 0000000000000000 [ 989.946475] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f2fb2236390 [ 989.955283] R13: 0000559bcde0fa10 R14: 0000000000000007 R15: 0000000000000003 | [ 990.396188] #1: ff110017d9753e80 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 990.407148] #2: ff11000124562b50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 990.418306] #3: ff11000124599d60 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 990.428396] | [ 990.430865] =============================================\x0a [ 1112.567262] INFO: task python3:5912 blocked for more than 245 seconds. [ 1112.575408] Not tainted 7.1.0-rc1-kvpk-g8d59040b039c #1 [ 1112.582658] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1112.592249] task:python3 state:D stack:25672 pid:5912 tgid:5912 ppid:5911 task_flags:0x400100 flags:0x00080000 [ 1112.605458] Call Trace: [ 1112.609000] [ 1112.612151] __schedule+0xcba/0x1940 [ 1112.616969] ? io_schedule_timeout+0x140/0x140 [ 1112.622755] ? find_held_lock+0x2b/0x80 [ 1112.627861] ? __lock_release.isra.0+0x6b/0x1a0 [ 1112.633749] schedule+0xd4/0x260 [ 1112.638171] schedule_preempt_disabled+0x18/0x30 [ 1112.644153] __mutex_lock+0x6a3/0x2000 [ 1112.649160] ? threaded_store+0x132/0x220 [ 1112.654459] ? ww_mutex_lock+0x160/0x160 [ 1112.659659] ? selinux_inode_free_security+0x330/0x330 [ 1112.666227] ? cap_capable+0x1d6/0x3e0 [ 1112.671240] ? threaded_store+0x132/0x220 [ 1112.676548] threaded_store+0x132/0x220 [ 1112.681656] ? xps_rxqs_store+0x2e0/0x2e0 [ 1112.686956] ? sysfs_file_kobj+0xbd/0x1e0 [ 1112.692255] ? sysfs_kf_write+0x65/0x160 [ 1112.697454] ? sysfs_file_ops+0x140/0x140 [ 1112.702751] kernfs_fop_write_iter+0x335/0x510 [ 1112.708528] ? kernfs_ops+0xf0/0xf0 [ 1112.713238] new_sync_write+0x333/0x750 [ 1112.718334] ? __lock_acquire+0x508/0xc10 [ 1112.723633] ? new_sync_read+0x740/0x740 [ 1112.728834] ? lock_acquire.part.0+0xbc/0x260 [ 1112.734519] ? ksys_write+0x116/0x250 [ 1112.739430] vfs_write+0x657/0x9b0 [ 1112.744048] ksys_write+0x116/0x250 [ 1112.748761] ? __ia32_sys_read+0xc0/0xc0 [ 1112.753953] ? kernfs_fop_llseek+0x160/0x1f0 [ 1112.759533] ? rcu_is_watching+0x15/0xd0 [ 1112.764729] do_syscall_64+0xf3/0x650 [ 1112.769636] ? trace_hardirqs_off+0xd/0x30 [ 1112.775033] ? exc_page_fault+0xda/0xf0 [ 1112.780135] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1112.786605] RIP: 0033:0x7f2fb23be22e [ 1112.791415] RSP: 002b:00007ffc633ae2e0 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1112.800714] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f2fb23be22e [ 1112.809524] RDX: 0000000000000001 RSI: 0000559bcde0fa10 RDI: 0000000000000007 [ 1112.818333] RBP: 00007ffc633ae2f0 R08: 0000000000000000 R09: 0000000000000000 [ 1112.827144] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f2fb2236390 [ 1112.835954] R13: 0000559bcde0fa10 R14: 0000000000000007 R15: 0000000000000003 | [ 1113.253074] #1: ff110017d9753e80 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 1113.264037] #2: ff11000124562b50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1113.275196] #3: ff11000124599d60 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1113.285284] | [ 1113.287752] =============================================\x0a [ 1235.448644] INFO: task python3:5912 blocked for more than 368 seconds. [ 1235.456790] Not tainted 7.1.0-rc1-kvpk-g8d59040b039c #1 [ 1235.464040] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1235.473629] task:python3 state:D stack:25672 pid:5912 tgid:5912 ppid:5911 task_flags:0x400100 flags:0x00080002 [ 1235.486828] Call Trace: [ 1235.490370] [ 1235.493521] __schedule+0xcba/0x1940 [ 1235.498339] ? io_schedule_timeout+0x140/0x140 [ 1235.504126] ? find_held_lock+0x2b/0x80 [ 1235.509233] ? __lock_release.isra.0+0x6b/0x1a0 [ 1235.515118] schedule+0xd4/0x260 [ 1235.519539] schedule_preempt_disabled+0x18/0x30 [ 1235.525520] __mutex_lock+0x6a3/0x2000 [ 1235.530524] ? threaded_store+0x132/0x220 [ 1235.535826] ? ww_mutex_lock+0x160/0x160 [ 1235.541026] ? selinux_inode_free_security+0x330/0x330 [ 1235.547596] ? cap_capable+0x1d6/0x3e0 [ 1235.552605] ? threaded_store+0x132/0x220 [ 1235.557906] threaded_store+0x132/0x220 [ 1235.563011] ? xps_rxqs_store+0x2e0/0x2e0 [ 1235.568311] ? sysfs_file_kobj+0xbd/0x1e0 [ 1235.573614] ? sysfs_kf_write+0x65/0x160 [ 1235.578816] ? sysfs_file_ops+0x140/0x140 [ 1235.584112] kernfs_fop_write_iter+0x335/0x510 [ 1235.589899] ? kernfs_ops+0xf0/0xf0 [ 1235.594610] new_sync_write+0x333/0x750 [ 1235.599704] ? __lock_acquire+0x508/0xc10 [ 1235.605003] ? new_sync_read+0x740/0x740 [ 1235.610205] ? lock_acquire.part.0+0xbc/0x260 [ 1235.615891] ? ksys_write+0x116/0x250 [ 1235.620791] vfs_write+0x657/0x9b0 [ 1235.625407] ksys_write+0x116/0x250 [ 1235.630109] ? __ia32_sys_read+0xc0/0xc0 [ 1235.635307] ? kernfs_fop_llseek+0x160/0x1f0 [ 1235.640896] ? rcu_is_watching+0x15/0xd0 [ 1235.646096] do_syscall_64+0xf3/0x650 [ 1235.651001] ? trace_hardirqs_off+0xd/0x30 [ 1235.656394] ? exc_page_fault+0xda/0xf0 [ 1235.661493] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1235.667963] RIP: 0033:0x7f2fb23be22e [ 1235.672773] RSP: 002b:00007ffc633ae2e0 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1235.682073] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f2fb23be22e [ 1235.690883] RDX: 0000000000000001 RSI: 0000559bcde0fa10 RDI: 0000000000000007 [ 1235.699692] RBP: 00007ffc633ae2f0 R08: 0000000000000000 R09: 0000000000000000 [ 1235.708502] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f2fb2236390 [ 1235.717313] R13: 0000559bcde0fa10 R14: 0000000000000007 R15: 0000000000000003 | [ 1236.094200] #1: ff110017d9753e80 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 1236.105162] #2: ff11000124562b50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1236.116323] #3: ff11000124599d60 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1236.126417] | [ 1236.128886] =============================================\x0a [ 1358.330052] INFO: task python3:5912 blocked for more than 491 seconds. [ 1358.338202] Not tainted 7.1.0-rc1-kvpk-g8d59040b039c #1 [ 1358.345457] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1358.355067] task:python3 state:D stack:25672 pid:5912 tgid:5912 ppid:5911 task_flags:0x400100 flags:0x00080002 [ 1358.368290] Call Trace: [ 1358.371840] [ 1358.374999] __schedule+0xcba/0x1940 [ 1358.379823] ? io_schedule_timeout+0x140/0x140 [ 1358.385613] ? find_held_lock+0x2b/0x80 [ 1358.390724] ? __lock_release.isra.0+0x6b/0x1a0 [ 1358.396614] schedule+0xd4/0x260 [ 1358.401037] schedule_preempt_disabled+0x18/0x30 [ 1358.407019] __mutex_lock+0x6a3/0x2000 [ 1358.412020] ? threaded_store+0x132/0x220 [ 1358.417325] ? ww_mutex_lock+0x160/0x160 [ 1358.422530] ? selinux_inode_free_security+0x330/0x330 [ 1358.429106] ? cap_capable+0x1d6/0x3e0 [ 1358.434121] ? threaded_store+0x132/0x220 [ 1358.439422] threaded_store+0x132/0x220 [ 1358.444527] ? xps_rxqs_store+0x2e0/0x2e0 [ 1358.449828] ? sysfs_file_kobj+0xbd/0x1e0 [ 1358.455128] ? sysfs_kf_write+0x65/0x160 [ 1358.460333] ? sysfs_file_ops+0x140/0x140 [ 1358.465631] kernfs_fop_write_iter+0x335/0x510 [ 1358.471421] ? kernfs_ops+0xf0/0xf0 [ 1358.476138] new_sync_write+0x333/0x750 [ 1358.481248] ? __lock_acquire+0x508/0xc10 [ 1358.486549] ? new_sync_read+0x740/0x740 [ 1358.491755] ? lock_acquire.part.0+0xbc/0x260 [ 1358.497445] ? ksys_write+0x116/0x250 [ 1358.502360] vfs_write+0x657/0x9b0 [ 1358.506979] ksys_write+0x116/0x250 [ 1358.511695] ? __ia32_sys_read+0xc0/0xc0 [ 1358.516902] ? kernfs_fop_llseek+0x160/0x1f0 [ 1358.522496] ? rcu_is_watching+0x15/0xd0 [ 1358.527707] do_syscall_64+0xf3/0x650 [ 1358.532618] ? trace_hardirqs_off+0xd/0x30 [ 1358.538017] ? exc_page_fault+0xda/0xf0 [ 1358.543123] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1358.549598] RIP: 0033:0x7f2fb23be22e [ 1358.554410] RSP: 002b:00007ffc633ae2e0 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1358.563715] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f2fb23be22e [ 1358.572519] RDX: 0000000000000001 RSI: 0000559bcde0fa10 RDI: 0000000000000007 [ 1358.581331] RBP: 00007ffc633ae2f0 R08: 0000000000000000 R09: 0000000000000000 [ 1358.590141] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f2fb2236390 [ 1358.598957] R13: 0000559bcde0fa10 R14: 0000000000000007 R15: 0000000000000003 Finger prints: __schedule:schedule:schedule_preempt_disabled:__mutex_lock:threaded_store