====================================== | [ 784.485855] nipa-hw-worker: drivers/net:napi_threaded.py: timed out | [ 784.489434] nipa-hw-worker: [5/46] Retrying drivers/net:napi_threaded.py | [ 784.638722] kselftest: Running tests in drivers/net [ 989.669458] INFO: task python3:5718 blocked for more than 122 seconds. [ 989.677689] Not tainted 7.1.0-rc4-ehha-g02f1b1291a39 #1 [ 989.684937] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 989.695116] task:python3 state:D stack:25568 pid:5718 tgid:5718 ppid:5717 task_flags:0x400100 flags:0x00080000 [ 989.708320] Call Trace: [ 989.711857] [ 989.715007] __schedule+0xcc3/0x19c0 [ 989.719822] ? io_schedule_timeout+0x140/0x140 [ 989.725605] ? find_held_lock+0x2b/0x80 [ 989.730711] ? __lock_release.isra.0+0x6b/0x1a0 [ 989.736594] schedule+0xd8/0x260 [ 989.741009] schedule_preempt_disabled+0x18/0x30 [ 989.746988] __mutex_lock+0x6af/0x2010 [ 989.751992] ? threaded_store+0x132/0x220 [ 989.757295] ? ww_mutex_lock+0x160/0x160 [ 989.762492] ? selinux_inode_free_security+0x330/0x330 [ 989.769060] ? cap_capable+0x1d6/0x3e0 [ 989.774065] ? threaded_store+0x132/0x220 [ 989.779362] threaded_store+0x132/0x220 [ 989.784465] ? xps_rxqs_store+0x2e0/0x2e0 [ 989.789761] ? sysfs_file_kobj+0xbd/0x1e0 [ 989.795060] ? sysfs_kf_write+0x65/0x160 [ 989.800258] ? sysfs_file_ops+0x140/0x140 [ 989.805553] kernfs_fop_write_iter+0x335/0x510 [ 989.811335] ? kernfs_ops+0xf0/0xf0 [ 989.816047] new_sync_write+0x333/0x750 [ 989.821149] ? __lock_acquire+0x508/0xc10 [ 989.826442] ? new_sync_read+0x740/0x740 [ 989.831642] ? lock_acquire.part.0+0xbc/0x260 [ 989.837325] ? ksys_write+0x116/0x250 [ 989.842232] vfs_write+0x657/0x9b0 [ 989.846848] ksys_write+0x116/0x250 [ 989.851559] ? __ia32_sys_read+0xc0/0xc0 [ 989.856758] ? kernfs_fop_llseek+0x160/0x1f0 [ 989.862347] ? rcu_is_watching+0x15/0xd0 [ 989.867549] do_syscall_64+0xf3/0x650 [ 989.872453] ? irq_exit_rcu+0x1a/0x30 [ 989.877359] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 989.883826] RIP: 0033:0x7fdabb37d22e [ 989.888634] RSP: 002b:00007fff97e0a7b0 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 989.897937] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007fdabb37d22e [ 989.906742] RDX: 0000000000000001 RSI: 0000556c8ee266b0 RDI: 0000000000000007 [ 989.915549] RBP: 00007fff97e0a7c0 R08: 0000000000000000 R09: 0000000000000000 [ 989.924354] R10: 0000000000000000 R11: 0000000000000202 R12: 00007fdabb1f5390 [ 989.933165] R13: 0000556c8ee266b0 R14: 0000000000000007 R15: 0000000000000003 | [ 990.338616] #1: ff110001e4148e80 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 990.349576] #2: ff110001227c4b50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 990.360729] #3: ff11000122801d68 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 990.370817] | [ 990.373283] =============================================\x0a [ 1112.549607] INFO: task python3:5718 blocked for more than 245 seconds. [ 1112.557753] Not tainted 7.1.0-rc4-ehha-g02f1b1291a39 #1 [ 1112.565003] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1112.574594] task:python3 state:D stack:25568 pid:5718 tgid:5718 ppid:5717 task_flags:0x400100 flags:0x00080000 [ 1112.587799] Call Trace: [ 1112.591340] [ 1112.594493] __schedule+0xcc3/0x19c0 [ 1112.599308] ? io_schedule_timeout+0x140/0x140 [ 1112.605092] ? find_held_lock+0x2b/0x80 [ 1112.610199] ? __lock_release.isra.0+0x6b/0x1a0 [ 1112.616082] schedule+0xd8/0x260 [ 1112.620503] schedule_preempt_disabled+0x18/0x30 [ 1112.626482] __mutex_lock+0x6af/0x2010 [ 1112.631489] ? threaded_store+0x132/0x220 [ 1112.636787] ? ww_mutex_lock+0x160/0x160 [ 1112.641987] ? selinux_inode_free_security+0x330/0x330 [ 1112.648558] ? cap_capable+0x1d6/0x3e0 [ 1112.653568] ? threaded_store+0x132/0x220 [ 1112.658866] threaded_store+0x132/0x220 [ 1112.663972] ? xps_rxqs_store+0x2e0/0x2e0 [ 1112.669268] ? sysfs_file_kobj+0xbd/0x1e0 [ 1112.674567] ? sysfs_kf_write+0x65/0x160 [ 1112.679766] ? sysfs_file_ops+0x140/0x140 [ 1112.685063] kernfs_fop_write_iter+0x335/0x510 [ 1112.690837] ? kernfs_ops+0xf0/0xf0 [ 1112.695548] new_sync_write+0x333/0x750 [ 1112.700654] ? __lock_acquire+0x508/0xc10 [ 1112.705941] ? new_sync_read+0x740/0x740 [ 1112.711143] ? lock_acquire.part.0+0xbc/0x260 [ 1112.716831] ? ksys_write+0x116/0x250 [ 1112.721740] vfs_write+0x657/0x9b0 [ 1112.726358] ksys_write+0x116/0x250 [ 1112.731070] ? __ia32_sys_read+0xc0/0xc0 [ 1112.736260] ? kernfs_fop_llseek+0x160/0x1f0 [ 1112.741849] ? rcu_is_watching+0x15/0xd0 [ 1112.747049] do_syscall_64+0xf3/0x650 [ 1112.751958] ? irq_exit_rcu+0x1a/0x30 [ 1112.756867] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1112.763333] RIP: 0033:0x7fdabb37d22e [ 1112.768145] RSP: 002b:00007fff97e0a7b0 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1112.777448] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007fdabb37d22e [ 1112.786256] RDX: 0000000000000001 RSI: 0000556c8ee266b0 RDI: 0000000000000007 [ 1112.795063] RBP: 00007fff97e0a7c0 R08: 0000000000000000 R09: 0000000000000000 [ 1112.803869] R10: 0000000000000000 R11: 0000000000000202 R12: 00007fdabb1f5390 [ 1112.812676] R13: 0000556c8ee266b0 R14: 0000000000000007 R15: 0000000000000003 | [ 1113.253863] #2: ffffffff91369b60 (cgroup_mutex){+.+.}-{4:4}, at: cgroup_kn_lock_live+0xe2/0x3e0 | [ 1113.264533] #3: ffffffff911686f0 (cpu_hotplug_lock){++++}-{0:0}, at: cgroup_procs_write_start+0x3e5/0x650 | [ 1113.276180] #4: ffffffff913698d0 (cgroup_threadgroup_rwsem){++++}-{0:0}, at: cgroup_procs_write_start+0x3f1/0x650 | [ 1113.288607] | [ 1113.291073] =============================================\x0a [ 1235.431743] INFO: task python3:5718 blocked for more than 368 seconds. [ 1235.439890] Not tainted 7.1.0-rc4-ehha-g02f1b1291a39 #1 [ 1235.447134] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1235.456729] task:python3 state:D stack:25568 pid:5718 tgid:5718 ppid:5717 task_flags:0x400100 flags:0x00080002 [ 1235.469945] Call Trace: [ 1235.473487] [ 1235.476643] __schedule+0xcc3/0x19c0 [ 1235.481463] ? io_schedule_timeout+0x140/0x140 [ 1235.487253] ? find_held_lock+0x2b/0x80 [ 1235.492363] ? __lock_release.isra.0+0x6b/0x1a0 [ 1235.498252] schedule+0xd8/0x260 [ 1235.502674] schedule_preempt_disabled+0x18/0x30 [ 1235.508659] __mutex_lock+0x6af/0x2010 [ 1235.513664] ? threaded_store+0x132/0x220 [ 1235.518972] ? ww_mutex_lock+0x160/0x160 [ 1235.524172] ? selinux_inode_free_security+0x330/0x330 [ 1235.530752] ? cap_capable+0x1d6/0x3e0 [ 1235.535762] ? threaded_store+0x132/0x220 [ 1235.541062] threaded_store+0x132/0x220 [ 1235.546167] ? xps_rxqs_store+0x2e0/0x2e0 [ 1235.551471] ? sysfs_file_kobj+0xbd/0x1e0 [ 1235.556774] ? sysfs_kf_write+0x65/0x160 [ 1235.561977] ? sysfs_file_ops+0x140/0x140 [ 1235.567277] kernfs_fop_write_iter+0x335/0x510 [ 1235.573066] ? kernfs_ops+0xf0/0xf0 [ 1235.577778] new_sync_write+0x333/0x750 [ 1235.582887] ? __lock_acquire+0x508/0xc10 [ 1235.588186] ? new_sync_read+0x740/0x740 [ 1235.593387] ? lock_acquire.part.0+0xbc/0x260 [ 1235.599075] ? ksys_write+0x116/0x250 [ 1235.603986] vfs_write+0x657/0x9b0 [ 1235.608605] ksys_write+0x116/0x250 [ 1235.613322] ? __ia32_sys_read+0xc0/0xc0 [ 1235.618524] ? kernfs_fop_llseek+0x160/0x1f0 [ 1235.624118] ? rcu_is_watching+0x15/0xd0 [ 1235.629325] do_syscall_64+0xf3/0x650 [ 1235.634235] ? irq_exit_rcu+0x1a/0x30 [ 1235.639144] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1235.645612] RIP: 0033:0x7fdabb37d22e [ 1235.650424] RSP: 002b:00007fff97e0a7b0 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1235.659727] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007fdabb37d22e [ 1235.668539] RDX: 0000000000000001 RSI: 0000556c8ee266b0 RDI: 0000000000000007 [ 1235.677352] RBP: 00007fff97e0a7c0 R08: 0000000000000000 R09: 0000000000000000 [ 1235.686162] R10: 0000000000000000 R11: 0000000000000202 R12: 00007fdabb1f5390 [ 1235.694975] R13: 0000556c8ee266b0 R14: 0000000000000007 R15: 0000000000000003 | [ 1236.100248] #1: ff110001e4148e80 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 1236.111219] #2: ff110001227c4b50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1236.122381] #3: ff11000122801d68 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1236.132479] | [ 1236.134950] =============================================\x0a [ 1358.312929] INFO: task python3:5718 blocked for more than 491 seconds. [ 1358.321087] Not tainted 7.1.0-rc4-ehha-g02f1b1291a39 #1 [ 1358.328339] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1358.337931] task:python3 state:D stack:25568 pid:5718 tgid:5718 ppid:5717 task_flags:0x400100 flags:0x00080002 [ 1358.351137] Call Trace: [ 1358.354677] [ 1358.357829] __schedule+0xcc3/0x19c0 [ 1358.362648] ? io_schedule_timeout+0x140/0x140 [ 1358.368431] ? find_held_lock+0x2b/0x80 [ 1358.373541] ? __lock_release.isra.0+0x6b/0x1a0 [ 1358.379438] schedule+0xd8/0x260 [ 1358.383862] schedule_preempt_disabled+0x18/0x30 [ 1358.389926] __mutex_lock+0x6af/0x2010 [ 1358.394931] ? threaded_store+0x132/0x220 [ 1358.400232] ? ww_mutex_lock+0x160/0x160 [ 1358.405432] ? selinux_inode_free_security+0x330/0x330 [ 1358.412005] ? cap_capable+0x1d6/0x3e0 [ 1358.417014] ? threaded_store+0x132/0x220 [ 1358.422313] threaded_store+0x132/0x220 [ 1358.427482] ? xps_rxqs_store+0x2e0/0x2e0 [ 1358.432780] ? sysfs_file_kobj+0xbd/0x1e0 [ 1358.438082] ? sysfs_kf_write+0x65/0x160 [ 1358.443283] ? sysfs_file_ops+0x140/0x140 [ 1358.448578] kernfs_fop_write_iter+0x335/0x510 [ 1358.454363] ? kernfs_ops+0xf0/0xf0 [ 1358.459074] new_sync_write+0x333/0x750 [ 1358.464169] ? __lock_acquire+0x508/0xc10 [ 1358.469467] ? new_sync_read+0x740/0x740 [ 1358.474735] ? lock_acquire.part.0+0xbc/0x260 [ 1358.480420] ? ksys_write+0x116/0x250 [ 1358.485332] vfs_write+0x657/0x9b0 [ 1358.489949] ksys_write+0x116/0x250 [ 1358.494662] ? __ia32_sys_read+0xc0/0xc0 [ 1358.499861] ? kernfs_fop_llseek+0x160/0x1f0 [ 1358.505455] ? rcu_is_watching+0x15/0xd0 [ 1358.510659] do_syscall_64+0xf3/0x650 [ 1358.515567] ? irq_exit_rcu+0x1a/0x30 [ 1358.520477] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1358.526943] RIP: 0033:0x7fdabb37d22e [ 1358.531756] RSP: 002b:00007fff97e0a7b0 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1358.541054] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007fdabb37d22e [ 1358.549861] RDX: 0000000000000001 RSI: 0000556c8ee266b0 RDI: 0000000000000007 [ 1358.558673] RBP: 00007fff97e0a7c0 R08: 0000000000000000 R09: 0000000000000000 [ 1358.567483] R10: 0000000000000000 R11: 0000000000000202 R12: 00007fdabb1f5390 [ 1358.576294] R13: 0000556c8ee266b0 R14: 0000000000000007 R15: 0000000000000003 Finger prints: __schedule:schedule:schedule_preempt_disabled:__mutex_lock:threaded_store