====================================== | [ 762.872256] nipa-hw-worker: drivers/net:napi_threaded.py: timed out | [ 762.874747] nipa-hw-worker: [5/47] Retrying drivers/net:napi_threaded.py | [ 763.004875] kselftest: Running tests in drivers/net [ 989.699491] INFO: task python3:5426 blocked for more than 122 seconds. [ 989.707730] Not tainted 7.1.0-rc6-cbqh-g596bda603a38 #1 [ 989.715685] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 989.725275] task:python3 state:D stack:25672 pid:5426 tgid:5426 ppid:5425 task_flags:0x400100 flags:0x00080000 [ 989.738483] Call Trace: [ 989.742024] [ 989.745174] __schedule+0xcc3/0x19c0 [ 989.749992] ? io_schedule_timeout+0x140/0x140 [ 989.755780] ? find_held_lock+0x2b/0x80 [ 989.760886] ? __lock_release.isra.0+0x6b/0x1a0 [ 989.766771] schedule+0xd8/0x260 [ 989.771189] schedule_preempt_disabled+0x18/0x30 [ 989.777167] __mutex_lock+0x6af/0x2010 [ 989.782175] ? threaded_store+0x132/0x220 [ 989.787473] ? ww_mutex_lock+0x160/0x160 [ 989.792675] ? selinux_inode_free_security+0x330/0x330 [ 989.799245] ? cap_capable+0x1d6/0x3e0 [ 989.804255] ? threaded_store+0x132/0x220 [ 989.809552] threaded_store+0x132/0x220 [ 989.814655] ? xps_rxqs_store+0x2e0/0x2e0 [ 989.819945] ? sysfs_file_kobj+0xbd/0x1e0 [ 989.825245] ? sysfs_kf_write+0x65/0x160 [ 989.830448] ? sysfs_file_ops+0x140/0x140 [ 989.835745] kernfs_fop_write_iter+0x335/0x510 [ 989.841530] ? kernfs_ops+0xf0/0xf0 [ 989.846240] new_sync_write+0x333/0x750 [ 989.851345] ? __lock_acquire+0x508/0xc10 [ 989.856644] ? new_sync_read+0x740/0x740 [ 989.861847] ? lock_acquire.part.0+0xbc/0x260 [ 989.867535] ? ksys_write+0x116/0x250 [ 989.872447] vfs_write+0x657/0x9b0 [ 989.877061] ksys_write+0x116/0x250 [ 989.881776] ? __ia32_sys_read+0xc0/0xc0 [ 989.886975] ? kernfs_fop_llseek+0x160/0x1f0 [ 989.892568] ? rcu_is_watching+0x15/0xd0 [ 989.897770] do_syscall_64+0xf3/0x650 [ 989.902677] ? trace_hardirqs_off+0xd/0x30 [ 989.908074] ? exc_page_fault+0xda/0xf0 [ 989.913177] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 989.919643] RIP: 0033:0x7f365c56764e [ 989.924455] RSP: 002b:00007ffc2f200e80 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 989.933755] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f365c56764e [ 989.942562] RDX: 0000000000000001 RSI: 0000563218227420 RDI: 0000000000000007 [ 989.951371] RBP: 00007ffc2f200e90 R08: 0000000000000000 R09: 0000000000000000 [ 989.960181] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f365c3e0390 [ 989.968990] R13: 0000563218227420 R14: 0000000000000007 R15: 00007f365c375120 | [ 990.340266] #1: ff110001a9a72680 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 990.351220] #2: ff11000123638b50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 990.362379] #3: ff110001235c1d68 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 990.372474] | [ 990.374943] =============================================\x0a [ 1112.580666] INFO: task python3:5426 blocked for more than 245 seconds. [ 1112.588817] Not tainted 7.1.0-rc6-cbqh-g596bda603a38 #1 [ 1112.596069] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1112.605658] task:python3 state:D stack:25672 pid:5426 tgid:5426 ppid:5425 task_flags:0x400100 flags:0x00080000 [ 1112.618866] Call Trace: [ 1112.622407] [ 1112.625557] __schedule+0xcc3/0x19c0 [ 1112.630373] ? io_schedule_timeout+0x140/0x140 [ 1112.636160] ? find_held_lock+0x2b/0x80 [ 1112.641264] ? __lock_release.isra.0+0x6b/0x1a0 [ 1112.647150] schedule+0xd8/0x260 [ 1112.651568] schedule_preempt_disabled+0x18/0x30 [ 1112.657547] __mutex_lock+0x6af/0x2010 [ 1112.662554] ? threaded_store+0x132/0x220 [ 1112.667854] ? ww_mutex_lock+0x160/0x160 [ 1112.673054] ? selinux_inode_free_security+0x330/0x330 [ 1112.679625] ? cap_capable+0x1d6/0x3e0 [ 1112.684634] ? threaded_store+0x132/0x220 [ 1112.689935] threaded_store+0x132/0x220 [ 1112.695038] ? xps_rxqs_store+0x2e0/0x2e0 [ 1112.700329] ? sysfs_file_kobj+0xbd/0x1e0 [ 1112.705626] ? sysfs_kf_write+0x65/0x160 [ 1112.710825] ? sysfs_file_ops+0x140/0x140 [ 1112.716121] kernfs_fop_write_iter+0x335/0x510 [ 1112.721909] ? kernfs_ops+0xf0/0xf0 [ 1112.726621] new_sync_write+0x333/0x750 [ 1112.731727] ? __lock_acquire+0x508/0xc10 [ 1112.737023] ? new_sync_read+0x740/0x740 [ 1112.742227] ? lock_acquire.part.0+0xbc/0x260 [ 1112.747915] ? ksys_write+0x116/0x250 [ 1112.752825] vfs_write+0x657/0x9b0 [ 1112.757443] ksys_write+0x116/0x250 [ 1112.762157] ? __ia32_sys_read+0xc0/0xc0 [ 1112.767358] ? kernfs_fop_llseek+0x160/0x1f0 [ 1112.772949] ? rcu_is_watching+0x15/0xd0 [ 1112.778151] do_syscall_64+0xf3/0x650 [ 1112.783056] ? trace_hardirqs_off+0xd/0x30 [ 1112.788451] ? exc_page_fault+0xda/0xf0 [ 1112.793556] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1112.800026] RIP: 0033:0x7f365c56764e [ 1112.804836] RSP: 002b:00007ffc2f200e80 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1112.814136] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f365c56764e [ 1112.822943] RDX: 0000000000000001 RSI: 0000563218227420 RDI: 0000000000000007 [ 1112.831753] RBP: 00007ffc2f200e90 R08: 0000000000000000 R09: 0000000000000000 [ 1112.840563] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f365c3e0390 [ 1112.849369] R13: 0000563218227420 R14: 0000000000000007 R15: 00007f365c375120 | [ 1113.220692] #1: ff110001a9a72680 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 1113.231653] #2: ff11000123638b50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1113.242803] #3: ff110001235c1d68 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1113.252894] | [ 1113.255362] =============================================\x0a [ 1235.461794] INFO: task python3:5426 blocked for more than 368 seconds. [ 1235.469944] Not tainted 7.1.0-rc6-cbqh-g596bda603a38 #1 [ 1235.477197] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1235.486784] task:python3 state:D stack:25672 pid:5426 tgid:5426 ppid:5425 task_flags:0x400100 flags:0x00080002 [ 1235.499993] Call Trace: [ 1235.503534] [ 1235.503539] __schedule+0xcc3/0x19c0 [ 1235.503550] ? io_schedule_timeout+0x140/0x140 [ 1235.503554] ? find_held_lock+0x2b/0x80 [ 1235.522455] ? __lock_release.isra.0+0x6b/0x1a0 [ 1235.528337] schedule+0xd8/0x260 [ 1235.532753] schedule_preempt_disabled+0x18/0x30 [ 1235.538733] __mutex_lock+0x6af/0x2010 [ 1235.543735] ? threaded_store+0x132/0x220 [ 1235.549032] ? ww_mutex_lock+0x160/0x160 [ 1235.554229] ? selinux_inode_free_security+0x330/0x330 [ 1235.560798] ? cap_capable+0x1d6/0x3e0 [ 1235.565805] ? threaded_store+0x132/0x220 [ 1235.571103] threaded_store+0x132/0x220 [ 1235.576195] ? xps_rxqs_store+0x2e0/0x2e0 [ 1235.581493] ? sysfs_file_kobj+0xbd/0x1e0 [ 1235.586789] ? sysfs_kf_write+0x65/0x160 [ 1235.591989] ? sysfs_file_ops+0x140/0x140 [ 1235.597276] kernfs_fop_write_iter+0x335/0x510 [ 1235.603058] ? kernfs_ops+0xf0/0xf0 [ 1235.607768] new_sync_write+0x333/0x750 [ 1235.612872] ? __lock_acquire+0x508/0xc10 [ 1235.618166] ? new_sync_read+0x740/0x740 [ 1235.623368] ? lock_acquire.part.0+0xbc/0x260 [ 1235.629053] ? ksys_write+0x116/0x250 [ 1235.633961] vfs_write+0x657/0x9b0 [ 1235.638573] ksys_write+0x116/0x250 [ 1235.643285] ? __ia32_sys_read+0xc0/0xc0 [ 1235.648482] ? kernfs_fop_llseek+0x160/0x1f0 [ 1235.654072] ? rcu_is_watching+0x15/0xd0 [ 1235.659272] do_syscall_64+0xf3/0x650 [ 1235.664175] ? trace_hardirqs_off+0xd/0x30 [ 1235.669570] ? exc_page_fault+0xda/0xf0 [ 1235.674671] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1235.681136] RIP: 0033:0x7f365c56764e [ 1235.685945] RSP: 002b:00007ffc2f200e80 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1235.695245] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f365c56764e [ 1235.704050] RDX: 0000000000000001 RSI: 0000563218227420 RDI: 0000000000000007 [ 1235.712858] RBP: 00007ffc2f200e90 R08: 0000000000000000 R09: 0000000000000000 [ 1235.721658] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f365c3e0390 [ 1235.730467] R13: 0000563218227420 R14: 0000000000000007 R15: 00007f365c375120 | [ 1236.160883] #2: ffffffffb5369c20 (cgroup_mutex){+.+.}-{4:4}, at: cgroup_kn_lock_live+0xe2/0x3e0 | [ 1236.171549] #3: ffffffffb51687b0 (cpu_hotplug_lock){++++}-{0:0}, at: cgroup_procs_write_start+0x3e5/0x650 | [ 1236.183190] #4: ffffffffb5369990 (cgroup_threadgroup_rwsem){++++}-{0:0}, at: cgroup_procs_write_start+0x3f1/0x650 | [ 1236.195615] | [ 1236.198080] =============================================\x0a [ 1358.342936] INFO: task python3:5426 blocked for more than 491 seconds. [ 1358.351076] Not tainted 7.1.0-rc6-cbqh-g596bda603a38 #1 [ 1358.358324] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1358.367916] task:python3 state:D stack:25672 pid:5426 tgid:5426 ppid:5425 task_flags:0x400100 flags:0x00080002 [ 1358.381121] Call Trace: [ 1358.384660] [ 1358.387811] __schedule+0xcc3/0x19c0 [ 1358.392627] ? io_schedule_timeout+0x140/0x140 [ 1358.398411] ? find_held_lock+0x2b/0x80 [ 1358.403515] ? __lock_release.isra.0+0x6b/0x1a0 [ 1358.409401] schedule+0xd8/0x260 [ 1358.413821] schedule_preempt_disabled+0x18/0x30 [ 1358.419791] __mutex_lock+0x6af/0x2010 [ 1358.424796] ? threaded_store+0x132/0x220 [ 1358.430097] ? ww_mutex_lock+0x160/0x160 [ 1358.435297] ? selinux_inode_free_security+0x330/0x330 [ 1358.441869] ? cap_capable+0x1d6/0x3e0 [ 1358.446879] ? threaded_store+0x132/0x220 [ 1358.452175] threaded_store+0x132/0x220 [ 1358.457278] ? xps_rxqs_store+0x2e0/0x2e0 [ 1358.462576] ? sysfs_file_kobj+0xbd/0x1e0 [ 1358.467876] ? sysfs_kf_write+0x65/0x160 [ 1358.473075] ? sysfs_file_ops+0x140/0x140 [ 1358.478369] kernfs_fop_write_iter+0x335/0x510 [ 1358.484154] ? kernfs_ops+0xf0/0xf0 [ 1358.488866] new_sync_write+0x333/0x750 [ 1358.493972] ? __lock_acquire+0x508/0xc10 [ 1358.499270] ? new_sync_read+0x740/0x740 [ 1358.504470] ? lock_acquire.part.0+0xbc/0x260 [ 1358.510155] ? ksys_write+0x116/0x250 [ 1358.515065] vfs_write+0x657/0x9b0 [ 1358.519679] ksys_write+0x116/0x250 [ 1358.524390] ? __ia32_sys_read+0xc0/0xc0 [ 1358.529588] ? kernfs_fop_llseek+0x160/0x1f0 [ 1358.535180] ? rcu_is_watching+0x15/0xd0 [ 1358.540383] do_syscall_64+0xf3/0x650 [ 1358.545289] ? trace_hardirqs_off+0xd/0x30 [ 1358.550687] ? exc_page_fault+0xda/0xf0 [ 1358.555793] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1358.562260] RIP: 0033:0x7f365c56764e [ 1358.567068] RSP: 002b:00007ffc2f200e80 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1358.576365] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f365c56764e [ 1358.585164] RDX: 0000000000000001 RSI: 0000563218227420 RDI: 0000000000000007 [ 1358.593971] RBP: 00007ffc2f200e90 R08: 0000000000000000 R09: 0000000000000000 [ 1358.602786] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f365c3e0390 [ 1358.611594] R13: 0000563218227420 R14: 0000000000000007 R15: 00007f365c375120 Finger prints: __schedule:schedule:schedule_preempt_disabled:__mutex_lock:threaded_store