====================================== | [ 778.643272] nipa-hw-worker: drivers/net:napi_threaded.py: timed out | [ 778.645820] nipa-hw-worker: [5/45] Retrying drivers/net:napi_threaded.py | [ 778.798725] kselftest: Running tests in drivers/net [ 989.715460] INFO: task python3:5676 blocked for more than 122 seconds. [ 989.723693] Not tainted 7.1.0-rc1-fhcd-gccc70ebf7dd8 #1 [ 989.731576] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 989.741165] task:python3 state:D stack:25672 pid:5676 tgid:5676 ppid:5675 task_flags:0x400100 flags:0x00080000 [ 989.754370] Call Trace: [ 989.757911] [ 989.761064] __schedule+0xcba/0x1940 [ 989.765885] ? io_schedule_timeout+0x140/0x140 [ 989.771672] ? find_held_lock+0x2b/0x80 [ 989.776779] ? __lock_release.isra.0+0x6b/0x1a0 [ 989.782665] schedule+0xd4/0x260 [ 989.787084] schedule_preempt_disabled+0x18/0x30 [ 989.793062] __mutex_lock+0x6a3/0x2000 [ 989.798069] ? threaded_store+0x132/0x220 [ 989.803368] ? ww_mutex_lock+0x160/0x160 [ 989.808565] ? selinux_inode_free_security+0x330/0x330 [ 989.815137] ? cap_capable+0x1d6/0x3e0 [ 989.820147] ? threaded_store+0x132/0x220 [ 989.825446] threaded_store+0x132/0x220 [ 989.830547] ? xps_rxqs_store+0x2e0/0x2e0 [ 989.835843] ? sysfs_file_kobj+0xbd/0x1e0 [ 989.841144] ? sysfs_kf_write+0x65/0x160 [ 989.846347] ? sysfs_file_ops+0x140/0x140 [ 989.851645] kernfs_fop_write_iter+0x335/0x510 [ 989.857429] ? kernfs_ops+0xf0/0xf0 [ 989.862142] new_sync_write+0x333/0x750 [ 989.867246] ? __lock_acquire+0x508/0xc10 [ 989.872542] ? new_sync_read+0x740/0x740 [ 989.877741] ? lock_acquire.part.0+0xbc/0x260 [ 989.883430] ? ksys_write+0x116/0x250 [ 989.888340] vfs_write+0x657/0x9b0 [ 989.892957] ksys_write+0x116/0x250 [ 989.897668] ? __ia32_sys_read+0xc0/0xc0 [ 989.902867] ? kernfs_fop_llseek+0x160/0x1f0 [ 989.908460] ? rcu_is_watching+0x15/0xd0 [ 989.913661] do_syscall_64+0xf3/0x650 [ 989.918558] ? trace_hardirqs_off+0xd/0x30 [ 989.923953] ? exc_page_fault+0xda/0xf0 [ 989.929057] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 989.935527] RIP: 0033:0x7fc7bf1ad22e [ 989.940338] RSP: 002b:00007fff65f574c0 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 989.949635] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007fc7bf1ad22e [ 989.958444] RDX: 0000000000000001 RSI: 000055e6f27d6e10 RDI: 0000000000000007 [ 989.967250] RBP: 00007fff65f574d0 R08: 0000000000000000 R09: 0000000000000000 [ 989.976059] R10: 0000000000000000 R11: 0000000000000202 R12: 00007fc7bf025390 [ 989.984869] R13: 000055e6f27d6e10 R14: 0000000000000007 R15: 0000000000000003 | [ 990.407422] #2: ff11000120be8b50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 990.418577] #3: ff11000120bc9d60 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 990.428666] | [ 990.431134] =============================================\x0a | [ 1067.541297] kworker/u129:15 (310) used greatest stack depth: 21936 bytes left [ 1112.597790] INFO: task python3:5676 blocked for more than 245 seconds. [ 1112.605934] Not tainted 7.1.0-rc1-fhcd-gccc70ebf7dd8 #1 [ 1112.613184] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1112.622776] task:python3 state:D stack:25672 pid:5676 tgid:5676 ppid:5675 task_flags:0x400100 flags:0x00080000 [ 1112.635983] Call Trace: [ 1112.639523] [ 1112.642674] __schedule+0xcba/0x1940 [ 1112.647491] ? io_schedule_timeout+0x140/0x140 [ 1112.653277] ? find_held_lock+0x2b/0x80 [ 1112.658383] ? __lock_release.isra.0+0x6b/0x1a0 [ 1112.664267] schedule+0xd4/0x260 [ 1112.668686] schedule_preempt_disabled+0x18/0x30 [ 1112.674657] __mutex_lock+0x6a3/0x2000 [ 1112.679664] ? threaded_store+0x132/0x220 [ 1112.684966] ? ww_mutex_lock+0x160/0x160 [ 1112.690167] ? selinux_inode_free_security+0x330/0x330 [ 1112.696735] ? cap_capable+0x1d6/0x3e0 [ 1112.701743] ? threaded_store+0x132/0x220 [ 1112.707041] threaded_store+0x132/0x220 [ 1112.712136] ? xps_rxqs_store+0x2e0/0x2e0 [ 1112.717435] ? sysfs_file_kobj+0xbd/0x1e0 [ 1112.722738] ? sysfs_kf_write+0x65/0x160 [ 1112.727939] ? sysfs_file_ops+0x140/0x140 [ 1112.733235] kernfs_fop_write_iter+0x335/0x510 [ 1112.739021] ? kernfs_ops+0xf0/0xf0 [ 1112.743731] new_sync_write+0x333/0x750 [ 1112.748836] ? __lock_acquire+0x508/0xc10 [ 1112.754122] ? new_sync_read+0x740/0x740 [ 1112.759325] ? lock_acquire.part.0+0xbc/0x260 [ 1112.765010] ? ksys_write+0x116/0x250 [ 1112.769922] vfs_write+0x657/0x9b0 [ 1112.774538] ksys_write+0x116/0x250 [ 1112.779249] ? __ia32_sys_read+0xc0/0xc0 [ 1112.784450] ? kernfs_fop_llseek+0x160/0x1f0 [ 1112.790039] ? rcu_is_watching+0x15/0xd0 [ 1112.795239] do_syscall_64+0xf3/0x650 [ 1112.800147] ? trace_hardirqs_off+0xd/0x30 [ 1112.805542] ? exc_page_fault+0xda/0xf0 [ 1112.810645] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1112.817115] RIP: 0033:0x7fc7bf1ad22e [ 1112.821925] RSP: 002b:00007fff65f574c0 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1112.831223] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007fc7bf1ad22e [ 1112.840033] RDX: 0000000000000001 RSI: 000055e6f27d6e10 RDI: 0000000000000007 [ 1112.849433] RBP: 00007fff65f574d0 R08: 0000000000000000 R09: 0000000000000000 [ 1112.858245] R10: 0000000000000000 R11: 0000000000000202 R12: 00007fc7bf025390 [ 1112.867054] R13: 000055e6f27d6e10 R14: 0000000000000007 R15: 0000000000000003 | [ 1113.238478] #1: ff11000189783e80 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 1113.249440] #2: ff11000120be8b50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1113.260599] #3: ff11000120bc9d60 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1113.270689] | [ 1113.273154] =============================================\x0a [ 1235.478150] INFO: task python3:5676 blocked for more than 368 seconds. [ 1235.486297] Not tainted 7.1.0-rc1-fhcd-gccc70ebf7dd8 #1 [ 1235.493546] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1235.503133] task:python3 state:D stack:25672 pid:5676 tgid:5676 ppid:5675 task_flags:0x400100 flags:0x00080002 [ 1235.516336] Call Trace: [ 1235.519876] [ 1235.523024] __schedule+0xcba/0x1940 [ 1235.527839] ? io_schedule_timeout+0x140/0x140 [ 1235.533623] ? find_held_lock+0x2b/0x80 [ 1235.538727] ? __lock_release.isra.0+0x6b/0x1a0 [ 1235.544609] schedule+0xd4/0x260 [ 1235.549024] schedule_preempt_disabled+0x18/0x30 [ 1235.555005] __mutex_lock+0x6a3/0x2000 [ 1235.560009] ? threaded_store+0x132/0x220 [ 1235.565309] ? ww_mutex_lock+0x160/0x160 [ 1235.570506] ? selinux_inode_free_security+0x330/0x330 [ 1235.577073] ? cap_capable+0x1d6/0x3e0 [ 1235.582080] ? threaded_store+0x132/0x220 [ 1235.587377] threaded_store+0x132/0x220 [ 1235.592481] ? xps_rxqs_store+0x2e0/0x2e0 [ 1235.597776] ? sysfs_file_kobj+0xbd/0x1e0 [ 1235.603064] ? sysfs_kf_write+0x65/0x160 [ 1235.608264] ? sysfs_file_ops+0x140/0x140 [ 1235.613560] kernfs_fop_write_iter+0x335/0x510 [ 1235.619345] ? kernfs_ops+0xf0/0xf0 [ 1235.624046] new_sync_write+0x333/0x750 [ 1235.629149] ? __lock_acquire+0x508/0xc10 [ 1235.634442] ? new_sync_read+0x740/0x740 [ 1235.639642] ? lock_acquire.part.0+0xbc/0x260 [ 1235.645327] ? ksys_write+0x116/0x250 [ 1235.650236] vfs_write+0x657/0x9b0 [ 1235.654851] ksys_write+0x116/0x250 [ 1235.659564] ? __ia32_sys_read+0xc0/0xc0 [ 1235.664763] ? kernfs_fop_llseek+0x160/0x1f0 [ 1235.670351] ? rcu_is_watching+0x15/0xd0 [ 1235.675550] do_syscall_64+0xf3/0x650 [ 1235.680456] ? trace_hardirqs_off+0xd/0x30 [ 1235.685841] ? exc_page_fault+0xda/0xf0 [ 1235.690940] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1235.697408] RIP: 0033:0x7fc7bf1ad22e [ 1235.702216] RSP: 002b:00007fff65f574c0 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1235.711515] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007fc7bf1ad22e [ 1235.720320] RDX: 0000000000000001 RSI: 000055e6f27d6e10 RDI: 0000000000000007 [ 1235.729124] RBP: 00007fff65f574d0 R08: 0000000000000000 R09: 0000000000000000 [ 1235.737931] R10: 0000000000000000 R11: 0000000000000202 R12: 00007fc7bf025390 [ 1235.746735] R13: 000055e6f27d6e10 R14: 0000000000000007 R15: 0000000000000003 | [ 1236.117925] #1: ff11000189783e80 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 1236.128888] #2: ff11000120be8b50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1236.140042] #3: ff11000120bc9d60 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1236.150132] | [ 1236.152590] =============================================\x0a [ 1358.360490] INFO: task python3:5676 blocked for more than 491 seconds. [ 1358.368635] Not tainted 7.1.0-rc1-fhcd-gccc70ebf7dd8 #1 [ 1358.375887] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1358.385478] task:python3 state:D stack:25672 pid:5676 tgid:5676 ppid:5675 task_flags:0x400100 flags:0x00080002 [ 1358.398683] Call Trace: [ 1358.402225] [ 1358.405374] __schedule+0xcba/0x1940 [ 1358.410192] ? io_schedule_timeout+0x140/0x140 [ 1358.415979] ? find_held_lock+0x2b/0x80 [ 1358.421084] ? __lock_release.isra.0+0x6b/0x1a0 [ 1358.426967] schedule+0xd4/0x260 [ 1358.431388] schedule_preempt_disabled+0x18/0x30 [ 1358.437369] __mutex_lock+0x6a3/0x2000 [ 1358.442375] ? threaded_store+0x132/0x220 [ 1358.447674] ? ww_mutex_lock+0x160/0x160 [ 1358.452872] ? selinux_inode_free_security+0x330/0x330 [ 1358.459445] ? cap_capable+0x1d6/0x3e0 [ 1358.464455] ? threaded_store+0x132/0x220 [ 1358.469753] threaded_store+0x132/0x220 [ 1358.474857] ? xps_rxqs_store+0x2e0/0x2e0 [ 1358.480153] ? sysfs_file_kobj+0xbd/0x1e0 [ 1358.485447] ? sysfs_kf_write+0x65/0x160 [ 1358.490649] ? sysfs_file_ops+0x140/0x140 [ 1358.495944] kernfs_fop_write_iter+0x335/0x510 [ 1358.501732] ? kernfs_ops+0xf0/0xf0 [ 1358.506445] new_sync_write+0x333/0x750 [ 1358.511550] ? __lock_acquire+0x508/0xc10 [ 1358.516840] ? new_sync_read+0x740/0x740 [ 1358.522042] ? lock_acquire.part.0+0xbc/0x260 [ 1358.527727] ? ksys_write+0x116/0x250 [ 1358.532640] vfs_write+0x657/0x9b0 [ 1358.537255] ksys_write+0x116/0x250 [ 1358.541958] ? __ia32_sys_read+0xc0/0xc0 [ 1358.547159] ? kernfs_fop_llseek+0x160/0x1f0 [ 1358.552749] ? rcu_is_watching+0x15/0xd0 [ 1358.557949] do_syscall_64+0xf3/0x650 [ 1358.562857] ? trace_hardirqs_off+0xd/0x30 [ 1358.568253] ? exc_page_fault+0xda/0xf0 [ 1358.573346] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1358.579815] RIP: 0033:0x7fc7bf1ad22e [ 1358.584625] RSP: 002b:00007fff65f574c0 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1358.593924] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007fc7bf1ad22e [ 1358.602732] RDX: 0000000000000001 RSI: 000055e6f27d6e10 RDI: 0000000000000007 [ 1358.611544] RBP: 00007fff65f574d0 R08: 0000000000000000 R09: 0000000000000000 [ 1358.620353] R10: 0000000000000000 R11: 0000000000000202 R12: 00007fc7bf025390 [ 1358.629163] R13: 000055e6f27d6e10 R14: 0000000000000007 R15: 0000000000000003 Finger prints: __schedule:schedule:schedule_preempt_disabled:__mutex_lock:threaded_store