====================================== | [ 786.296737] nipa-hw-worker: drivers/net:napi_threaded.py: timed out | [ 786.299270] nipa-hw-worker: [5/47] Retrying drivers/net:napi_threaded.py | [ 786.490226] kselftest: Running tests in drivers/net [ 989.710221] INFO: task python3:5839 blocked for more than 122 seconds. [ 989.718455] Not tainted 7.1.0-rc3-ehnc-g5b2f5ff27f1c #1 [ 989.725710] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 989.735920] task:python3 state:D stack:25672 pid:5839 tgid:5839 ppid:5838 task_flags:0x400100 flags:0x00080000 [ 989.749126] Call Trace: [ 989.752662] [ 989.755811] __schedule+0xcc3/0x19c0 [ 989.760625] ? io_schedule_timeout+0x140/0x140 [ 989.766401] ? find_held_lock+0x2b/0x80 [ 989.771505] ? __lock_release.isra.0+0x6b/0x1a0 [ 989.777392] schedule+0xd8/0x260 [ 989.781809] schedule_preempt_disabled+0x18/0x30 [ 989.787785] __mutex_lock+0x6af/0x2010 [ 989.792787] ? threaded_store+0x132/0x220 [ 989.798077] ? ww_mutex_lock+0x160/0x160 [ 989.803274] ? selinux_inode_free_security+0x330/0x330 [ 989.809840] ? cap_capable+0x1d6/0x3e0 [ 989.814847] ? threaded_store+0x132/0x220 [ 989.820145] threaded_store+0x132/0x220 [ 989.825247] ? xps_rxqs_store+0x2e0/0x2e0 [ 989.830533] ? sysfs_file_kobj+0xbd/0x1e0 [ 989.835831] ? sysfs_kf_write+0x65/0x160 [ 989.841028] ? sysfs_file_ops+0x140/0x140 [ 989.846321] kernfs_fop_write_iter+0x335/0x510 [ 989.852105] ? kernfs_ops+0xf0/0xf0 [ 989.856815] new_sync_write+0x333/0x750 [ 989.861916] ? __lock_acquire+0x508/0xc10 [ 989.867212] ? new_sync_read+0x740/0x740 [ 989.872411] ? lock_acquire.part.0+0xbc/0x260 [ 989.878093] ? ksys_write+0x116/0x250 [ 989.883000] vfs_write+0x657/0x9b0 [ 989.887615] ksys_write+0x116/0x250 [ 989.892325] ? __ia32_sys_read+0xc0/0xc0 [ 989.897525] ? kernfs_fop_llseek+0x160/0x1f0 [ 989.903114] ? rcu_is_watching+0x15/0xd0 [ 989.908316] do_syscall_64+0xf3/0x650 [ 989.913222] ? trace_hardirqs_off+0xd/0x30 [ 989.918616] ? exc_page_fault+0xda/0xf0 [ 989.923715] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 989.930181] RIP: 0033:0x7f0d303ca22e [ 989.934981] RSP: 002b:00007ffeed69f5f0 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 989.944279] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f0d303ca22e [ 989.953087] RDX: 0000000000000001 RSI: 0000565261879cd0 RDI: 0000000000000007 [ 989.961895] RBP: 00007ffeed69f600 R08: 0000000000000000 R09: 0000000000000000 [ 989.970701] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f0d30242390 [ 989.979508] R13: 0000565261879cd0 R14: 0000000000000007 R15: 0000000000000003 | [ 990.356257] #1: ff11000127947480 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 990.367219] #2: ff11000123608b50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 990.378366] #3: ff11000123669d68 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 990.388443] | [ 990.390907] =============================================\x0a [ 1112.591252] INFO: task python3:5839 blocked for more than 245 seconds. [ 1112.599403] Not tainted 7.1.0-rc3-ehnc-g5b2f5ff27f1c #1 [ 1112.606653] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1112.616245] task:python3 state:D stack:25672 pid:5839 tgid:5839 ppid:5838 task_flags:0x400100 flags:0x00080000 [ 1112.629452] Call Trace: [ 1112.632992] [ 1112.636145] __schedule+0xcc3/0x19c0 [ 1112.640963] ? io_schedule_timeout+0x140/0x140 [ 1112.646752] ? find_held_lock+0x2b/0x80 [ 1112.651859] ? __lock_release.isra.0+0x6b/0x1a0 [ 1112.657744] schedule+0xd8/0x260 [ 1112.662165] schedule_preempt_disabled+0x18/0x30 [ 1112.668147] __mutex_lock+0x6af/0x2010 [ 1112.673153] ? threaded_store+0x132/0x220 [ 1112.678452] ? ww_mutex_lock+0x160/0x160 [ 1112.683651] ? selinux_inode_free_security+0x330/0x330 [ 1112.690220] ? cap_capable+0x1d6/0x3e0 [ 1112.695231] ? threaded_store+0x132/0x220 [ 1112.700530] threaded_store+0x132/0x220 [ 1112.705631] ? xps_rxqs_store+0x2e0/0x2e0 [ 1112.710928] ? sysfs_file_kobj+0xbd/0x1e0 [ 1112.716219] ? sysfs_kf_write+0x65/0x160 [ 1112.721435] ? sysfs_file_ops+0x140/0x140 [ 1112.726731] kernfs_fop_write_iter+0x335/0x510 [ 1112.732516] ? kernfs_ops+0xf0/0xf0 [ 1112.737218] new_sync_write+0x333/0x750 [ 1112.742322] ? __lock_acquire+0x508/0xc10 [ 1112.747613] ? new_sync_read+0x740/0x740 [ 1112.752815] ? lock_acquire.part.0+0xbc/0x260 [ 1112.758501] ? ksys_write+0x116/0x250 [ 1112.763414] vfs_write+0x657/0x9b0 [ 1112.768032] ksys_write+0x116/0x250 [ 1112.772744] ? __ia32_sys_read+0xc0/0xc0 [ 1112.777946] ? kernfs_fop_llseek+0x160/0x1f0 [ 1112.783536] ? rcu_is_watching+0x15/0xd0 [ 1112.788731] do_syscall_64+0xf3/0x650 [ 1112.793640] ? trace_hardirqs_off+0xd/0x30 [ 1112.799035] ? exc_page_fault+0xda/0xf0 [ 1112.804138] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1112.810608] RIP: 0033:0x7f0d303ca22e [ 1112.815418] RSP: 002b:00007ffeed69f5f0 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1112.824716] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f0d303ca22e [ 1112.833525] RDX: 0000000000000001 RSI: 0000565261879cd0 RDI: 0000000000000007 [ 1112.842333] RBP: 00007ffeed69f600 R08: 0000000000000000 R09: 0000000000000000 [ 1112.851142] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f0d30242390 [ 1112.859951] R13: 0000565261879cd0 R14: 0000000000000007 R15: 0000000000000003 | [ 1113.236805] #1: ff11000127947480 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 1113.247769] #2: ff11000123608b50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1113.258929] #3: ff11000123669d68 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1113.269020] | [ 1113.271488] =============================================\x0a [ 1235.472284] INFO: task python3:5839 blocked for more than 368 seconds. [ 1235.480447] Not tainted 7.1.0-rc3-ehnc-g5b2f5ff27f1c #1 [ 1235.487721] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1235.497336] task:python3 state:D stack:25672 pid:5839 tgid:5839 ppid:5838 task_flags:0x400100 flags:0x00080002 [ 1235.510570] Call Trace: [ 1235.514118] [ 1235.517276] __schedule+0xcc3/0x19c0 [ 1235.522110] ? io_schedule_timeout+0x140/0x140 [ 1235.527909] ? find_held_lock+0x2b/0x80 [ 1235.533029] ? __lock_release.isra.0+0x6b/0x1a0 [ 1235.538930] schedule+0xd8/0x260 [ 1235.543352] schedule_preempt_disabled+0x18/0x30 [ 1235.549332] __mutex_lock+0x6af/0x2010 [ 1235.554335] ? threaded_store+0x132/0x220 [ 1235.554343] ? ww_mutex_lock+0x160/0x160 [ 1235.554346] ? selinux_inode_free_security+0x330/0x330 [ 1235.554355] ? cap_capable+0x1d6/0x3e0 [ 1235.576476] ? threaded_store+0x132/0x220 [ 1235.581773] threaded_store+0x132/0x220 [ 1235.586878] ? xps_rxqs_store+0x2e0/0x2e0 [ 1235.592173] ? sysfs_file_kobj+0xbd/0x1e0 [ 1235.597469] ? sysfs_kf_write+0x65/0x160 [ 1235.602669] ? sysfs_file_ops+0x140/0x140 [ 1235.607964] kernfs_fop_write_iter+0x335/0x510 [ 1235.613748] ? kernfs_ops+0xf0/0xf0 [ 1235.618457] new_sync_write+0x333/0x750 [ 1235.623559] ? __lock_acquire+0x508/0xc10 [ 1235.628852] ? new_sync_read+0x740/0x740 [ 1235.634051] ? lock_acquire.part.0+0xbc/0x260 [ 1235.639735] ? ksys_write+0x116/0x250 [ 1235.644647] vfs_write+0x657/0x9b0 [ 1235.649262] ksys_write+0x116/0x250 [ 1235.653966] ? __ia32_sys_read+0xc0/0xc0 [ 1235.659156] ? kernfs_fop_llseek+0x160/0x1f0 [ 1235.664746] ? rcu_is_watching+0x15/0xd0 [ 1235.669946] do_syscall_64+0xf3/0x650 [ 1235.674849] ? trace_hardirqs_off+0xd/0x30 [ 1235.680242] ? exc_page_fault+0xda/0xf0 [ 1235.685344] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1235.691812] RIP: 0033:0x7f0d303ca22e [ 1235.696620] RSP: 002b:00007ffeed69f5f0 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1235.705909] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f0d303ca22e [ 1235.714709] RDX: 0000000000000001 RSI: 0000565261879cd0 RDI: 0000000000000007 [ 1235.723516] RBP: 00007ffeed69f600 R08: 0000000000000000 R09: 0000000000000000 [ 1235.732316] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f0d30242390 [ 1235.741123] R13: 0000565261879cd0 R14: 0000000000000007 R15: 0000000000000003 | [ 1236.177172] #2: ffffffffb8f69ce0 (cgroup_mutex){+.+.}-{4:4}, at: cgroup_kn_lock_live+0xe2/0x3e0 | [ 1236.187839] #3: ffffffffb8d68870 (cpu_hotplug_lock){++++}-{0:0}, at: cgroup_procs_write_start+0x3e5/0x650 | [ 1236.199484] #4: ffffffffb8f69a50 (cgroup_threadgroup_rwsem){++++}-{0:0}, at: cgroup_procs_write_start+0x3f1/0x650 | [ 1236.211910] | [ 1236.214386] =============================================\x0a [ 1358.352346] INFO: task python3:5839 blocked for more than 491 seconds. [ 1358.360492] Not tainted 7.1.0-rc3-ehnc-g5b2f5ff27f1c #1 [ 1358.367742] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1358.377334] task:python3 state:D stack:25672 pid:5839 tgid:5839 ppid:5838 task_flags:0x400100 flags:0x00080002 [ 1358.390542] Call Trace: [ 1358.394083] [ 1358.397232] __schedule+0xcc3/0x19c0 [ 1358.402051] ? io_schedule_timeout+0x140/0x140 [ 1358.407837] ? find_held_lock+0x2b/0x80 [ 1358.412944] ? __lock_release.isra.0+0x6b/0x1a0 [ 1358.418827] schedule+0xd8/0x260 [ 1358.423249] schedule_preempt_disabled+0x18/0x30 [ 1358.429230] __mutex_lock+0x6af/0x2010 [ 1358.434233] ? threaded_store+0x132/0x220 [ 1358.439532] ? ww_mutex_lock+0x160/0x160 [ 1358.444730] ? selinux_inode_free_security+0x330/0x330 [ 1358.451300] ? cap_capable+0x1d6/0x3e0 [ 1358.456309] ? threaded_store+0x132/0x220 [ 1358.461607] threaded_store+0x132/0x220 [ 1358.466709] ? xps_rxqs_store+0x2e0/0x2e0 [ 1358.472009] ? sysfs_file_kobj+0xbd/0x1e0 [ 1358.477310] ? sysfs_kf_write+0x65/0x160 [ 1358.482510] ? sysfs_file_ops+0x140/0x140 [ 1358.487798] kernfs_fop_write_iter+0x335/0x510 [ 1358.493585] ? kernfs_ops+0xf0/0xf0 [ 1358.498296] new_sync_write+0x333/0x750 [ 1358.503401] ? __lock_acquire+0x508/0xc10 [ 1358.508698] ? new_sync_read+0x740/0x740 [ 1358.513901] ? lock_acquire.part.0+0xbc/0x260 [ 1358.519588] ? ksys_write+0x116/0x250 [ 1358.524497] vfs_write+0x657/0x9b0 [ 1358.529114] ksys_write+0x116/0x250 [ 1358.533827] ? __ia32_sys_read+0xc0/0xc0 [ 1358.539029] ? kernfs_fop_llseek+0x160/0x1f0 [ 1358.544620] ? rcu_is_watching+0x15/0xd0 [ 1358.549820] do_syscall_64+0xf3/0x650 [ 1358.554721] ? trace_hardirqs_off+0xd/0x30 [ 1358.560117] ? exc_page_fault+0xda/0xf0 [ 1358.565219] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1358.571686] RIP: 0033:0x7f0d303ca22e [ 1358.576487] RSP: 002b:00007ffeed69f5f0 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1358.585776] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f0d303ca22e [ 1358.594586] RDX: 0000000000000001 RSI: 0000565261879cd0 RDI: 0000000000000007 [ 1358.603395] RBP: 00007ffeed69f600 R08: 0000000000000000 R09: 0000000000000000 [ 1358.612202] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f0d30242390 [ 1358.621012] R13: 0000565261879cd0 R14: 0000000000000007 R15: 0000000000000003 Finger prints: __schedule:schedule:schedule_preempt_disabled:__mutex_lock:threaded_store