====================================== | [ 784.209906] nipa-hw-worker: drivers/net:napi_threaded.py: timed out | [ 784.212978] nipa-hw-worker: [5/46] Retrying drivers/net:napi_threaded.py | [ 784.373108] kselftest: Running tests in drivers/net [ 989.706599] INFO: task python3:5700 blocked for more than 122 seconds. [ 989.714815] Not tainted 7.1.0-rc2-vpwd-gd245c487d19e #1 [ 989.722551] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 989.732140] task:python3 state:D stack:25672 pid:5700 tgid:5700 ppid:5699 task_flags:0x400100 flags:0x00080000 [ 989.745346] Call Trace: [ 989.748887] [ 989.752039] __schedule+0xcba/0x1940 [ 989.756857] ? io_schedule_timeout+0x140/0x140 [ 989.762642] ? find_held_lock+0x2b/0x80 [ 989.767748] ? __lock_release.isra.0+0x6b/0x1a0 [ 989.773635] schedule+0xd4/0x260 [ 989.778053] schedule_preempt_disabled+0x18/0x30 [ 989.784032] __mutex_lock+0x6a3/0x2000 [ 989.789037] ? threaded_store+0x132/0x220 [ 989.794337] ? ww_mutex_lock+0x160/0x160 [ 989.799538] ? selinux_inode_free_security+0x330/0x330 [ 989.806110] ? cap_capable+0x1d6/0x3e0 [ 989.811120] ? threaded_store+0x132/0x220 [ 989.816418] threaded_store+0x132/0x220 [ 989.821521] ? xps_rxqs_store+0x2e0/0x2e0 [ 989.826819] ? sysfs_file_kobj+0xbd/0x1e0 [ 989.832120] ? sysfs_kf_write+0x65/0x160 [ 989.837321] ? sysfs_file_ops+0x140/0x140 [ 989.842620] kernfs_fop_write_iter+0x335/0x510 [ 989.848404] ? kernfs_ops+0xf0/0xf0 [ 989.853116] new_sync_write+0x333/0x750 [ 989.858222] ? __lock_acquire+0x508/0xc10 [ 989.863519] ? new_sync_read+0x740/0x740 [ 989.868720] ? lock_acquire.part.0+0xbc/0x260 [ 989.874408] ? ksys_write+0x116/0x250 [ 989.879319] vfs_write+0x657/0x9b0 [ 989.883937] ksys_write+0x116/0x250 [ 989.888647] ? __ia32_sys_read+0xc0/0xc0 [ 989.893846] ? kernfs_fop_llseek+0x160/0x1f0 [ 989.899439] ? rcu_is_watching+0x15/0xd0 [ 989.904640] do_syscall_64+0xf3/0x650 [ 989.909547] ? trace_hardirqs_off+0xd/0x30 [ 989.914936] ? exc_page_fault+0xda/0xf0 [ 989.920040] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 989.926508] RIP: 0033:0x7fa9fef1922e [ 989.931311] RSP: 002b:00007ffd9ef6fdb0 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 989.940611] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007fa9fef1922e [ 989.949422] RDX: 0000000000000001 RSI: 0000565350b03fe0 RDI: 0000000000000007 [ 989.958230] RBP: 00007ffd9ef6fdc0 R08: 0000000000000000 R09: 0000000000000000 [ 989.967042] R10: 0000000000000000 R11: 0000000000000202 R12: 00007fa9fed91390 [ 989.975851] R13: 0000565350b03fe0 R14: 0000000000000007 R15: 0000000000000003 | [ 990.393020] #1: ff110001e2c10e80 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 990.403971] #2: ff11000122e20b50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 990.415127] #3: ff11000122e99d68 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 990.425217] | [ 990.427684] =============================================\x0a [ 1112.586780] INFO: task python3:5700 blocked for more than 245 seconds. [ 1112.594929] Not tainted 7.1.0-rc2-vpwd-gd245c487d19e #1 [ 1112.602179] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1112.611766] task:python3 state:D stack:25672 pid:5700 tgid:5700 ppid:5699 task_flags:0x400100 flags:0x00080000 [ 1112.624970] Call Trace: [ 1112.628511] [ 1112.631658] __schedule+0xcba/0x1940 [ 1112.636472] ? io_schedule_timeout+0x140/0x140 [ 1112.642254] ? find_held_lock+0x2b/0x80 [ 1112.647357] ? __lock_release.isra.0+0x6b/0x1a0 [ 1112.653241] schedule+0xd4/0x260 [ 1112.657658] schedule_preempt_disabled+0x18/0x30 [ 1112.663635] __mutex_lock+0x6a3/0x2000 [ 1112.668638] ? threaded_store+0x132/0x220 [ 1112.673936] ? ww_mutex_lock+0x160/0x160 [ 1112.679134] ? selinux_inode_free_security+0x330/0x330 [ 1112.685705] ? cap_capable+0x1d6/0x3e0 [ 1112.690714] ? threaded_store+0x132/0x220 [ 1112.696007] threaded_store+0x132/0x220 [ 1112.701108] ? xps_rxqs_store+0x2e0/0x2e0 [ 1112.706406] ? sysfs_file_kobj+0xbd/0x1e0 [ 1112.711706] ? sysfs_kf_write+0x65/0x160 [ 1112.716908] ? sysfs_file_ops+0x140/0x140 [ 1112.722200] kernfs_fop_write_iter+0x335/0x510 [ 1112.727983] ? kernfs_ops+0xf0/0xf0 [ 1112.732694] new_sync_write+0x333/0x750 [ 1112.737798] ? __lock_acquire+0x508/0xc10 [ 1112.743093] ? new_sync_read+0x740/0x740 [ 1112.748296] ? lock_acquire.part.0+0xbc/0x260 [ 1112.753983] ? ksys_write+0x116/0x250 [ 1112.758892] vfs_write+0x657/0x9b0 [ 1112.763503] ksys_write+0x116/0x250 [ 1112.768216] ? __ia32_sys_read+0xc0/0xc0 [ 1112.773403] ? kernfs_fop_llseek+0x160/0x1f0 [ 1112.778994] ? rcu_is_watching+0x15/0xd0 [ 1112.784194] do_syscall_64+0xf3/0x650 [ 1112.789098] ? trace_hardirqs_off+0xd/0x30 [ 1112.794494] ? exc_page_fault+0xda/0xf0 [ 1112.799596] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1112.806053] RIP: 0033:0x7fa9fef1922e [ 1112.810859] RSP: 002b:00007ffd9ef6fdb0 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1112.820155] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007fa9fef1922e [ 1112.828960] RDX: 0000000000000001 RSI: 0000565350b03fe0 RDI: 0000000000000007 [ 1112.837764] RBP: 00007ffd9ef6fdc0 R08: 0000000000000000 R09: 0000000000000000 [ 1112.846573] R10: 0000000000000000 R11: 0000000000000202 R12: 00007fa9fed91390 [ 1112.855369] R13: 0000565350b03fe0 R14: 0000000000000007 R15: 0000000000000003 | [ 1113.232154] #1: ff110001e2c10e80 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 1113.243104] #2: ff11000122e20b50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1113.254262] #3: ff11000122e99d68 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1113.264353] | [ 1113.266820] =============================================\x0a [ 1235.468943] INFO: task python3:5700 blocked for more than 368 seconds. [ 1235.477088] Not tainted 7.1.0-rc2-vpwd-gd245c487d19e #1 [ 1235.484339] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1235.493926] task:python3 state:D stack:25672 pid:5700 tgid:5700 ppid:5699 task_flags:0x400100 flags:0x00080002 [ 1235.507130] Call Trace: [ 1235.510669] [ 1235.513817] __schedule+0xcba/0x1940 [ 1235.518632] ? io_schedule_timeout+0x140/0x140 [ 1235.524418] ? find_held_lock+0x2b/0x80 [ 1235.529521] ? __lock_release.isra.0+0x6b/0x1a0 [ 1235.535403] schedule+0xd4/0x260 [ 1235.539819] schedule_preempt_disabled+0x18/0x30 [ 1235.545796] __mutex_lock+0x6a3/0x2000 [ 1235.550799] ? threaded_store+0x132/0x220 [ 1235.556097] ? ww_mutex_lock+0x160/0x160 [ 1235.561288] ? selinux_inode_free_security+0x330/0x330 [ 1235.567857] ? cap_capable+0x1d6/0x3e0 [ 1235.572866] ? threaded_store+0x132/0x220 [ 1235.578159] threaded_store+0x132/0x220 [ 1235.583260] ? xps_rxqs_store+0x2e0/0x2e0 [ 1235.588557] ? sysfs_file_kobj+0xbd/0x1e0 [ 1235.593858] ? sysfs_kf_write+0x65/0x160 [ 1235.599055] ? sysfs_file_ops+0x140/0x140 [ 1235.604347] kernfs_fop_write_iter+0x335/0x510 [ 1235.610129] ? kernfs_ops+0xf0/0xf0 [ 1235.614841] new_sync_write+0x333/0x750 [ 1235.619941] ? __lock_acquire+0x508/0xc10 [ 1235.625238] ? new_sync_read+0x740/0x740 [ 1235.630437] ? lock_acquire.part.0+0xbc/0x260 [ 1235.636124] ? ksys_write+0x116/0x250 [ 1235.641034] vfs_write+0x657/0x9b0 [ 1235.645647] ksys_write+0x116/0x250 [ 1235.650359] ? __ia32_sys_read+0xc0/0xc0 [ 1235.655557] ? kernfs_fop_llseek+0x160/0x1f0 [ 1235.661145] ? rcu_is_watching+0x15/0xd0 [ 1235.666345] do_syscall_64+0xf3/0x650 [ 1235.671251] ? trace_hardirqs_off+0xd/0x30 [ 1235.676642] ? exc_page_fault+0xda/0xf0 [ 1235.681745] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1235.688211] RIP: 0033:0x7fa9fef1922e [ 1235.693018] RSP: 002b:00007ffd9ef6fdb0 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1235.702317] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007fa9fef1922e [ 1235.711123] RDX: 0000000000000001 RSI: 0000565350b03fe0 RDI: 0000000000000007 [ 1235.719928] RBP: 00007ffd9ef6fdc0 R08: 0000000000000000 R09: 0000000000000000 [ 1235.728736] R10: 0000000000000000 R11: 0000000000000202 R12: 00007fa9fed91390 [ 1235.737541] R13: 0000565350b03fe0 R14: 0000000000000007 R15: 0000000000000003 | [ 1236.114331] #1: ff110001e2c10e80 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 1236.125289] #2: ff11000122e20b50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1236.136445] #3: ff11000122e99d68 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1236.146536] | [ 1236.149004] =============================================\x0a [ 1358.349117] INFO: task python3:5700 blocked for more than 491 seconds. [ 1358.357262] Not tainted 7.1.0-rc2-vpwd-gd245c487d19e #1 [ 1358.364510] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1358.374096] task:python3 state:D stack:25672 pid:5700 tgid:5700 ppid:5699 task_flags:0x400100 flags:0x00080002 [ 1358.387300] Call Trace: [ 1358.390840] [ 1358.393988] __schedule+0xcba/0x1940 [ 1358.398803] ? io_schedule_timeout+0x140/0x140 [ 1358.404581] ? find_held_lock+0x2b/0x80 [ 1358.409684] ? __lock_release.isra.0+0x6b/0x1a0 [ 1358.415569] schedule+0xd4/0x260 [ 1358.419986] schedule_preempt_disabled+0x18/0x30 [ 1358.425966] __mutex_lock+0x6a3/0x2000 [ 1358.430966] ? threaded_store+0x132/0x220 [ 1358.436263] ? ww_mutex_lock+0x160/0x160 [ 1358.441462] ? selinux_inode_free_security+0x330/0x330 [ 1358.448032] ? cap_capable+0x1d6/0x3e0 [ 1358.453036] ? threaded_store+0x132/0x220 [ 1358.458330] threaded_store+0x132/0x220 [ 1358.463430] ? xps_rxqs_store+0x2e0/0x2e0 [ 1358.468724] ? sysfs_file_kobj+0xbd/0x1e0 [ 1358.474024] ? sysfs_kf_write+0x65/0x160 [ 1358.479221] ? sysfs_file_ops+0x140/0x140 [ 1358.484514] kernfs_fop_write_iter+0x335/0x510 [ 1358.490295] ? kernfs_ops+0xf0/0xf0 [ 1358.495008] new_sync_write+0x333/0x750 [ 1358.500109] ? __lock_acquire+0x508/0xc10 [ 1358.505406] ? new_sync_read+0x740/0x740 [ 1358.510605] ? lock_acquire.part.0+0xbc/0x260 [ 1358.516284] ? ksys_write+0x116/0x250 [ 1358.521185] vfs_write+0x657/0x9b0 [ 1358.525799] ksys_write+0x116/0x250 [ 1358.530509] ? __ia32_sys_read+0xc0/0xc0 [ 1358.535707] ? kernfs_fop_llseek+0x160/0x1f0 [ 1358.541295] ? rcu_is_watching+0x15/0xd0 [ 1358.546497] do_syscall_64+0xf3/0x650 [ 1358.551403] ? trace_hardirqs_off+0xd/0x30 [ 1358.556795] ? exc_page_fault+0xda/0xf0 [ 1358.561894] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1358.568361] RIP: 0033:0x7fa9fef1922e [ 1358.573159] RSP: 002b:00007ffd9ef6fdb0 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1358.582455] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007fa9fef1922e [ 1358.591262] RDX: 0000000000000001 RSI: 0000565350b03fe0 RDI: 0000000000000007 [ 1358.600070] RBP: 00007ffd9ef6fdc0 R08: 0000000000000000 R09: 0000000000000000 [ 1358.608869] R10: 0000000000000000 R11: 0000000000000202 R12: 00007fa9fed91390 [ 1358.617667] R13: 0000565350b03fe0 R14: 0000000000000007 R15: 0000000000000003 Finger prints: __schedule:schedule:schedule_preempt_disabled:__mutex_lock:threaded_store