====================================== | [ 768.621699] nipa-hw-worker: drivers/net:napi_threaded.py: timed out | [ 768.627188] nipa-hw-worker: [5/14] Retrying drivers/net:napi_threaded.py | [ 768.755448] kselftest: Running tests in drivers/net [ 989.765588] INFO: task python3:5512 blocked for more than 122 seconds. [ 989.773791] Not tainted 7.1.0-rc7-clnv-g75e7aaa3b088 #1 [ 989.781361] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 989.790953] task:python3 state:D stack:25648 pid:5512 tgid:5512 ppid:5511 task_flags:0x400100 flags:0x00080000 [ 989.804162] Call Trace: [ 989.807703] [ 989.810854] __schedule+0xcc3/0x19c0 [ 989.815671] ? io_schedule_timeout+0x140/0x140 [ 989.821459] ? find_held_lock+0x2b/0x80 [ 989.826562] ? __lock_release.isra.0+0x6b/0x1a0 [ 989.832441] schedule+0xd8/0x260 [ 989.836860] schedule_preempt_disabled+0x18/0x30 [ 989.842839] __mutex_lock+0x6af/0x2010 [ 989.847843] ? threaded_store+0x132/0x220 [ 989.853142] ? ww_mutex_lock+0x160/0x160 [ 989.858343] ? selinux_inode_free_security+0x330/0x330 [ 989.864913] ? cap_capable+0x1d6/0x3e0 [ 989.869923] ? threaded_store+0x132/0x220 [ 989.875218] threaded_store+0x132/0x220 [ 989.880319] ? xps_rxqs_store+0x2e0/0x2e0 [ 989.885621] ? sysfs_file_kobj+0xbd/0x1e0 [ 989.890922] ? sysfs_kf_write+0x65/0x160 [ 989.896124] ? sysfs_file_ops+0x140/0x140 [ 989.901427] kernfs_fop_write_iter+0x335/0x510 [ 989.907211] ? kernfs_ops+0xf0/0xf0 [ 989.911923] new_sync_write+0x333/0x750 [ 989.917026] ? __lock_acquire+0x508/0xc10 [ 989.922322] ? new_sync_read+0x740/0x740 [ 989.927527] ? lock_acquire.part.0+0xbc/0x260 [ 989.933215] ? ksys_write+0x116/0x250 [ 989.938124] vfs_write+0x657/0x9b0 [ 989.942742] ksys_write+0x116/0x250 [ 989.947456] ? __ia32_sys_read+0xc0/0xc0 [ 989.952654] ? kernfs_fop_llseek+0x160/0x1f0 [ 989.958242] ? rcu_is_watching+0x15/0xd0 [ 989.963436] do_syscall_64+0xf3/0x650 [ 989.968342] ? irq_exit_rcu+0x1a/0x30 [ 989.973252] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 989.979722] RIP: 0033:0x7f639109164e [ 989.984530] RSP: 002b:00007ffc08edc0d0 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 989.993830] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f639109164e [ 990.002638] RDX: 0000000000000001 RSI: 000055798794abf0 RDI: 0000000000000007 [ 990.011445] RBP: 00007ffc08edc0e0 R08: 0000000000000000 R09: 0000000000000000 [ 990.020254] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f6390f0a390 [ 990.029062] R13: 000055798794abf0 R14: 0000000000000007 R15: 00007f6390e9d120 | [ 990.429066] #1: ff110001ab2e1480 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 990.440030] #2: ff110001121deb50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 990.451188] #3: ff110001220e9e00 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 990.461275] | [ 990.463740] =============================================\x0a [ 1112.646897] INFO: task python3:5512 blocked for more than 245 seconds. [ 1112.655049] Not tainted 7.1.0-rc7-clnv-g75e7aaa3b088 #1 [ 1112.662300] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1112.671893] task:python3 state:D stack:25648 pid:5512 tgid:5512 ppid:5511 task_flags:0x400100 flags:0x00080000 [ 1112.685103] Call Trace: [ 1112.688643] [ 1112.691797] __schedule+0xcc3/0x19c0 [ 1112.696612] ? io_schedule_timeout+0x140/0x140 [ 1112.702398] ? find_held_lock+0x2b/0x80 [ 1112.707505] ? __lock_release.isra.0+0x6b/0x1a0 [ 1112.713389] schedule+0xd8/0x260 [ 1112.717810] schedule_preempt_disabled+0x18/0x30 [ 1112.723788] __mutex_lock+0x6af/0x2010 [ 1112.728793] ? threaded_store+0x132/0x220 [ 1112.734093] ? ww_mutex_lock+0x160/0x160 [ 1112.739285] ? selinux_inode_free_security+0x330/0x330 [ 1112.745854] ? cap_capable+0x1d6/0x3e0 [ 1112.750864] ? threaded_store+0x132/0x220 [ 1112.756163] threaded_store+0x132/0x220 [ 1112.761267] ? xps_rxqs_store+0x2e0/0x2e0 [ 1112.766566] ? sysfs_file_kobj+0xbd/0x1e0 [ 1112.771867] ? sysfs_kf_write+0x65/0x160 [ 1112.777071] ? sysfs_file_ops+0x140/0x140 [ 1112.782365] kernfs_fop_write_iter+0x335/0x510 [ 1112.788152] ? kernfs_ops+0xf0/0xf0 [ 1112.792865] new_sync_write+0x333/0x750 [ 1112.797971] ? __lock_acquire+0x508/0xc10 [ 1112.803267] ? new_sync_read+0x740/0x740 [ 1112.808470] ? lock_acquire.part.0+0xbc/0x260 [ 1112.814156] ? ksys_write+0x116/0x250 [ 1112.819069] vfs_write+0x657/0x9b0 [ 1112.823682] ksys_write+0x116/0x250 [ 1112.828395] ? __ia32_sys_read+0xc0/0xc0 [ 1112.833596] ? kernfs_fop_llseek+0x160/0x1f0 [ 1112.839188] ? rcu_is_watching+0x15/0xd0 [ 1112.844389] do_syscall_64+0xf3/0x650 [ 1112.849294] ? irq_exit_rcu+0x1a/0x30 [ 1112.854202] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1112.860672] RIP: 0033:0x7f639109164e [ 1112.865482] RSP: 002b:00007ffc08edc0d0 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1112.874781] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f639109164e [ 1112.883591] RDX: 0000000000000001 RSI: 000055798794abf0 RDI: 0000000000000007 [ 1112.892400] RBP: 00007ffc08edc0e0 R08: 0000000000000000 R09: 0000000000000000 [ 1112.901209] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f6390f0a390 [ 1112.910019] R13: 000055798794abf0 R14: 0000000000000007 R15: 00007f6390e9d120 | [ 1113.292268] #2: ff110001121deb50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1113.303425] #3: ff110001220e9e00 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1113.313517] | [ 1113.315984] =============================================\x0a | [ 1210.951929] kworker/u130:2 (347) used greatest stack depth: 21456 bytes left [ 1235.528184] INFO: task python3:5512 blocked for more than 368 seconds. [ 1235.536330] Not tainted 7.1.0-rc7-clnv-g75e7aaa3b088 #1 [ 1235.543580] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1235.553167] task:python3 state:D stack:25648 pid:5512 tgid:5512 ppid:5511 task_flags:0x400100 flags:0x00080002 [ 1235.566372] Call Trace: [ 1235.569913] [ 1235.573065] __schedule+0xcc3/0x19c0 [ 1235.577879] ? io_schedule_timeout+0x140/0x140 [ 1235.583664] ? find_held_lock+0x2b/0x80 [ 1235.588765] ? __lock_release.isra.0+0x6b/0x1a0 [ 1235.594649] schedule+0xd8/0x260 [ 1235.599065] schedule_preempt_disabled+0x18/0x30 [ 1235.605043] __mutex_lock+0x6af/0x2010 [ 1235.610046] ? threaded_store+0x132/0x220 [ 1235.615345] ? ww_mutex_lock+0x160/0x160 [ 1235.620545] ? selinux_inode_free_security+0x330/0x330 [ 1235.627114] ? cap_capable+0x1d6/0x3e0 [ 1235.632123] ? threaded_store+0x132/0x220 [ 1235.637417] threaded_store+0x132/0x220 [ 1235.642516] ? xps_rxqs_store+0x2e0/0x2e0 [ 1235.647811] ? sysfs_file_kobj+0xbd/0x1e0 [ 1235.653111] ? sysfs_kf_write+0x65/0x160 [ 1235.658305] ? sysfs_file_ops+0x140/0x140 [ 1235.663602] kernfs_fop_write_iter+0x335/0x510 [ 1235.669386] ? kernfs_ops+0xf0/0xf0 [ 1235.674099] new_sync_write+0x333/0x750 [ 1235.679200] ? __lock_acquire+0x508/0xc10 [ 1235.684496] ? new_sync_read+0x740/0x740 [ 1235.689694] ? lock_acquire.part.0+0xbc/0x260 [ 1235.695381] ? ksys_write+0x116/0x250 [ 1235.700291] vfs_write+0x657/0x9b0 [ 1235.704903] ksys_write+0x116/0x250 [ 1235.709611] ? __ia32_sys_read+0xc0/0xc0 [ 1235.714809] ? kernfs_fop_llseek+0x160/0x1f0 [ 1235.720395] ? rcu_is_watching+0x15/0xd0 [ 1235.725597] do_syscall_64+0xf3/0x650 [ 1235.730503] ? irq_exit_rcu+0x1a/0x30 [ 1235.735412] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1235.741870] RIP: 0033:0x7f639109164e [ 1235.746671] RSP: 002b:00007ffc08edc0d0 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1235.755961] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f639109164e [ 1235.764767] RDX: 0000000000000001 RSI: 000055798794abf0 RDI: 0000000000000007 [ 1235.773574] RBP: 00007ffc08edc0e0 R08: 0000000000000000 R09: 0000000000000000 [ 1235.782379] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f6390f0a390 [ 1235.791186] R13: 000055798794abf0 R14: 0000000000000007 R15: 00007f6390e9d120 | [ 1236.162377] #1: ff110001ab2e1480 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 1236.173335] #2: ff110001121deb50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1236.184492] #3: ff110001220e9e00 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1236.194586] | [ 1236.197051] =============================================\x0a [ 1358.409438] INFO: task python3:5512 blocked for more than 491 seconds. [ 1358.417585] Not tainted 7.1.0-rc7-clnv-g75e7aaa3b088 #1 [ 1358.424837] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1358.434426] task:python3 state:D stack:25648 pid:5512 tgid:5512 ppid:5511 task_flags:0x400100 flags:0x00080002 [ 1358.447624] Call Trace: [ 1358.451165] [ 1358.454316] __schedule+0xcc3/0x19c0 [ 1358.459132] ? io_schedule_timeout+0x140/0x140 [ 1358.464918] ? find_held_lock+0x2b/0x80 [ 1358.470021] ? __lock_release.isra.0+0x6b/0x1a0 [ 1358.475907] schedule+0xd8/0x260 [ 1358.480325] schedule_preempt_disabled+0x18/0x30 [ 1358.486303] __mutex_lock+0x6af/0x2010 [ 1358.491308] ? threaded_store+0x132/0x220 [ 1358.496607] ? ww_mutex_lock+0x160/0x160 [ 1358.501807] ? selinux_inode_free_security+0x330/0x330 [ 1358.508378] ? cap_capable+0x1d6/0x3e0 [ 1358.513384] ? threaded_store+0x132/0x220 [ 1358.518679] threaded_store+0x132/0x220 [ 1358.523782] ? xps_rxqs_store+0x2e0/0x2e0 [ 1358.529082] ? sysfs_file_kobj+0xbd/0x1e0 [ 1358.534383] ? sysfs_kf_write+0x65/0x160 [ 1358.539584] ? sysfs_file_ops+0x140/0x140 [ 1358.544878] kernfs_fop_write_iter+0x335/0x510 [ 1358.550662] ? kernfs_ops+0xf0/0xf0 [ 1358.555375] new_sync_write+0x333/0x750 [ 1358.560478] ? __lock_acquire+0x508/0xc10 [ 1358.565776] ? new_sync_read+0x740/0x740 [ 1358.570980] ? lock_acquire.part.0+0xbc/0x260 [ 1358.576667] ? ksys_write+0x116/0x250 [ 1358.581577] vfs_write+0x657/0x9b0 [ 1358.586190] ksys_write+0x116/0x250 [ 1358.590904] ? __ia32_sys_read+0xc0/0xc0 [ 1358.596102] ? kernfs_fop_llseek+0x160/0x1f0 [ 1358.601694] ? rcu_is_watching+0x15/0xd0 [ 1358.606898] do_syscall_64+0xf3/0x650 [ 1358.611803] ? irq_exit_rcu+0x1a/0x30 [ 1358.616712] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1358.623179] RIP: 0033:0x7f639109164e [ 1358.627990] RSP: 002b:00007ffc08edc0d0 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1358.637294] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f639109164e [ 1358.646105] RDX: 0000000000000001 RSI: 000055798794abf0 RDI: 0000000000000007 [ 1358.654914] RBP: 00007ffc08edc0e0 R08: 0000000000000000 R09: 0000000000000000 [ 1358.663724] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f6390f0a390 [ 1358.672530] R13: 000055798794abf0 R14: 0000000000000007 R15: 00007f6390e9d120 Finger prints: __schedule:schedule:schedule_preempt_disabled:__mutex_lock:threaded_store