====================================== | [ 787.557556] nipa-hw-worker: drivers/net:napi_threaded.py: timed out | [ 787.559967] nipa-hw-worker: [5/46] Retrying drivers/net:napi_threaded.py | [ 787.687363] kselftest: Running tests in drivers/net [ 989.661035] INFO: task python3:5769 blocked for more than 122 seconds. [ 989.669232] Not tainted 7.1.0-rc4-dvkf-g90e41bdee216 #1 [ 989.676821] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 989.686411] task:python3 state:D stack:24984 pid:5769 tgid:5769 ppid:5768 task_flags:0x400100 flags:0x00080000 [ 989.699616] Call Trace: [ 989.703155] [ 989.706306] __schedule+0xcc3/0x19c0 [ 989.711122] ? io_schedule_timeout+0x140/0x140 [ 989.716908] ? find_held_lock+0x2b/0x80 [ 989.722013] ? __lock_release.isra.0+0x6b/0x1a0 [ 989.727887] schedule+0xd8/0x260 [ 989.732294] schedule_preempt_disabled+0x18/0x30 [ 989.738274] __mutex_lock+0x6af/0x2010 [ 989.743279] ? threaded_store+0x132/0x220 [ 989.748577] ? ww_mutex_lock+0x160/0x160 [ 989.753776] ? selinux_inode_free_security+0x330/0x330 [ 989.760343] ? cap_capable+0x1d6/0x3e0 [ 989.765350] ? threaded_store+0x132/0x220 [ 989.770649] threaded_store+0x132/0x220 [ 989.775753] ? xps_rxqs_store+0x2e0/0x2e0 [ 989.781050] ? sysfs_file_kobj+0xbd/0x1e0 [ 989.786348] ? sysfs_kf_write+0x65/0x160 [ 989.791547] ? sysfs_file_ops+0x140/0x140 [ 989.796843] kernfs_fop_write_iter+0x335/0x510 [ 989.802624] ? kernfs_ops+0xf0/0xf0 [ 989.807333] new_sync_write+0x333/0x750 [ 989.812436] ? __lock_acquire+0x508/0xc10 [ 989.817730] ? new_sync_read+0x740/0x740 [ 989.822931] ? lock_acquire.part.0+0xbc/0x260 [ 989.828618] ? ksys_write+0x116/0x250 [ 989.833517] vfs_write+0x657/0x9b0 [ 989.838133] ksys_write+0x116/0x250 [ 989.842844] ? __ia32_sys_read+0xc0/0xc0 [ 989.848040] ? kernfs_fop_llseek+0x160/0x1f0 [ 989.853627] ? rcu_is_watching+0x15/0xd0 [ 989.858825] do_syscall_64+0xf3/0x650 [ 989.863730] ? trace_hardirqs_off+0xd/0x30 [ 989.869126] ? exc_page_fault+0xda/0xf0 [ 989.874225] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 989.880690] RIP: 0033:0x7f36e499222e [ 989.885501] RSP: 002b:00007ffede494f70 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 989.894799] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f36e499222e [ 989.903608] RDX: 0000000000000001 RSI: 0000556fd5bcb6b0 RDI: 0000000000000007 [ 989.912412] RBP: 00007ffede494f80 R08: 0000000000000000 R09: 0000000000000000 [ 989.921222] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f36e480a390 [ 989.930028] R13: 0000556fd5bcb6b0 R14: 0000000000000007 R15: 0000000000000003 | [ 990.329955] #1: ff110001c2eea080 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 990.340913] #2: ff11000123eccb50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 990.352067] #3: ff11000123e79d68 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 990.362153] | [ 990.364618] =============================================\x0a [ 1112.542172] INFO: task python3:5769 blocked for more than 245 seconds. [ 1112.550320] Not tainted 7.1.0-rc4-dvkf-g90e41bdee216 #1 [ 1112.557571] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1112.567159] task:python3 state:D stack:24984 pid:5769 tgid:5769 ppid:5768 task_flags:0x400100 flags:0x00080000 [ 1112.580367] Call Trace: [ 1112.583908] [ 1112.587059] __schedule+0xcc3/0x19c0 [ 1112.591876] ? io_schedule_timeout+0x140/0x140 [ 1112.597662] ? find_held_lock+0x2b/0x80 [ 1112.602768] ? __lock_release.isra.0+0x6b/0x1a0 [ 1112.608656] schedule+0xd8/0x260 [ 1112.613074] schedule_preempt_disabled+0x18/0x30 [ 1112.619055] __mutex_lock+0x6af/0x2010 [ 1112.624059] ? threaded_store+0x132/0x220 [ 1112.629358] ? ww_mutex_lock+0x160/0x160 [ 1112.634548] ? selinux_inode_free_security+0x330/0x330 [ 1112.641118] ? cap_capable+0x1d6/0x3e0 [ 1112.646128] ? threaded_store+0x132/0x220 [ 1112.651429] threaded_store+0x132/0x220 [ 1112.656533] ? xps_rxqs_store+0x2e0/0x2e0 [ 1112.661831] ? sysfs_file_kobj+0xbd/0x1e0 [ 1112.667132] ? sysfs_kf_write+0x65/0x160 [ 1112.672333] ? sysfs_file_ops+0x140/0x140 [ 1112.677629] kernfs_fop_write_iter+0x335/0x510 [ 1112.683415] ? kernfs_ops+0xf0/0xf0 [ 1112.688126] new_sync_write+0x333/0x750 [ 1112.693222] ? __lock_acquire+0x508/0xc10 [ 1112.698519] ? new_sync_read+0x740/0x740 [ 1112.703720] ? lock_acquire.part.0+0xbc/0x260 [ 1112.709407] ? ksys_write+0x116/0x250 [ 1112.714312] vfs_write+0x657/0x9b0 [ 1112.718929] ksys_write+0x116/0x250 [ 1112.723641] ? __ia32_sys_read+0xc0/0xc0 [ 1112.728830] ? kernfs_fop_llseek+0x160/0x1f0 [ 1112.734421] ? rcu_is_watching+0x15/0xd0 [ 1112.739622] do_syscall_64+0xf3/0x650 [ 1112.744530] ? trace_hardirqs_off+0xd/0x30 [ 1112.749927] ? exc_page_fault+0xda/0xf0 [ 1112.755030] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1112.761496] RIP: 0033:0x7f36e499222e [ 1112.766299] RSP: 002b:00007ffede494f70 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1112.775596] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f36e499222e [ 1112.784404] RDX: 0000000000000001 RSI: 0000556fd5bcb6b0 RDI: 0000000000000007 [ 1112.793211] RBP: 00007ffede494f80 R08: 0000000000000000 R09: 0000000000000000 [ 1112.802021] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f36e480a390 [ 1112.810833] R13: 0000556fd5bcb6b0 R14: 0000000000000007 R15: 0000000000000003 | [ 1113.210841] #1: ff110001c2eea080 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 1113.221806] #2: ff11000123eccb50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1113.232963] #3: ff11000123e79d68 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1113.243051] | [ 1113.245519] =============================================\x0a [ 1235.422295] INFO: task python3:5769 blocked for more than 368 seconds. [ 1235.430441] Not tainted 7.1.0-rc4-dvkf-g90e41bdee216 #1 [ 1235.437691] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1235.447279] task:python3 state:D stack:24984 pid:5769 tgid:5769 ppid:5768 task_flags:0x400100 flags:0x00080002 [ 1235.460483] Call Trace: [ 1235.464022] [ 1235.467172] __schedule+0xcc3/0x19c0 [ 1235.471989] ? io_schedule_timeout+0x140/0x140 [ 1235.477774] ? find_held_lock+0x2b/0x80 [ 1235.482881] ? __lock_release.isra.0+0x6b/0x1a0 [ 1235.488764] schedule+0xd8/0x260 [ 1235.493181] schedule_preempt_disabled+0x18/0x30 [ 1235.499157] __mutex_lock+0x6af/0x2010 [ 1235.504163] ? threaded_store+0x132/0x220 [ 1235.509461] ? ww_mutex_lock+0x160/0x160 [ 1235.514659] ? selinux_inode_free_security+0x330/0x330 [ 1235.521229] ? cap_capable+0x1d6/0x3e0 [ 1235.526236] ? threaded_store+0x132/0x220 [ 1235.531533] threaded_store+0x132/0x220 [ 1235.536634] ? xps_rxqs_store+0x2e0/0x2e0 [ 1235.541933] ? sysfs_file_kobj+0xbd/0x1e0 [ 1235.547232] ? sysfs_kf_write+0x65/0x160 [ 1235.552431] ? sysfs_file_ops+0x140/0x140 [ 1235.557725] kernfs_fop_write_iter+0x335/0x510 [ 1235.563507] ? kernfs_ops+0xf0/0xf0 [ 1235.568218] new_sync_write+0x333/0x750 [ 1235.573314] ? __lock_acquire+0x508/0xc10 [ 1235.578607] ? new_sync_read+0x740/0x740 [ 1235.583809] ? lock_acquire.part.0+0xbc/0x260 [ 1235.589495] ? ksys_write+0x116/0x250 [ 1235.594404] vfs_write+0x657/0x9b0 [ 1235.599016] ksys_write+0x116/0x250 [ 1235.603727] ? __ia32_sys_read+0xc0/0xc0 [ 1235.608923] ? kernfs_fop_llseek+0x160/0x1f0 [ 1235.614510] ? rcu_is_watching+0x15/0xd0 [ 1235.619710] do_syscall_64+0xf3/0x650 [ 1235.624617] ? trace_hardirqs_off+0xd/0x30 [ 1235.630013] ? exc_page_fault+0xda/0xf0 [ 1235.635115] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1235.641575] RIP: 0033:0x7f36e499222e [ 1235.646384] RSP: 002b:00007ffede494f70 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1235.655679] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f36e499222e [ 1235.664487] RDX: 0000000000000001 RSI: 0000556fd5bcb6b0 RDI: 0000000000000007 [ 1235.673292] RBP: 00007ffede494f80 R08: 0000000000000000 R09: 0000000000000000 [ 1235.682099] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f36e480a390 [ 1235.690905] R13: 0000556fd5bcb6b0 R14: 0000000000000007 R15: 0000000000000003 | [ 1236.062137] #1: ff110001c2eea080 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 1236.073097] #2: ff11000123eccb50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1236.084253] #3: ff11000123e79d68 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1236.094346] | [ 1236.096813] =============================================\x0a [ 1358.304392] INFO: task python3:5769 blocked for more than 491 seconds. [ 1358.312542] Not tainted 7.1.0-rc4-dvkf-g90e41bdee216 #1 [ 1358.319792] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1358.329385] task:python3 state:D stack:24984 pid:5769 tgid:5769 ppid:5768 task_flags:0x400100 flags:0x00080002 [ 1358.342593] Call Trace: [ 1358.346132] [ 1358.349285] __schedule+0xcc3/0x19c0 [ 1358.354103] ? io_schedule_timeout+0x140/0x140 [ 1358.359890] ? find_held_lock+0x2b/0x80 [ 1358.364997] ? __lock_release.isra.0+0x6b/0x1a0 [ 1358.370883] schedule+0xd8/0x260 [ 1358.375303] schedule_preempt_disabled+0x18/0x30 [ 1358.381275] __mutex_lock+0x6af/0x2010 [ 1358.386279] ? threaded_store+0x132/0x220 [ 1358.391579] ? ww_mutex_lock+0x160/0x160 [ 1358.396770] ? selinux_inode_free_security+0x330/0x330 [ 1358.403341] ? cap_capable+0x1d6/0x3e0 [ 1358.408351] ? threaded_store+0x132/0x220 [ 1358.413650] threaded_store+0x132/0x220 [ 1358.418752] ? xps_rxqs_store+0x2e0/0x2e0 [ 1358.424041] ? sysfs_file_kobj+0xbd/0x1e0 [ 1358.429340] ? sysfs_kf_write+0x65/0x160 [ 1358.434536] ? sysfs_file_ops+0x140/0x140 [ 1358.439832] kernfs_fop_write_iter+0x335/0x510 [ 1358.445615] ? kernfs_ops+0xf0/0xf0 [ 1358.450328] new_sync_write+0x333/0x750 [ 1358.455434] ? __lock_acquire+0x508/0xc10 [ 1358.460731] ? new_sync_read+0x740/0x740 [ 1358.465934] ? lock_acquire.part.0+0xbc/0x260 [ 1358.471621] ? ksys_write+0x116/0x250 [ 1358.476534] vfs_write+0x657/0x9b0 [ 1358.481149] ksys_write+0x116/0x250 [ 1358.485861] ? __ia32_sys_read+0xc0/0xc0 [ 1358.491062] ? kernfs_fop_llseek+0x160/0x1f0 [ 1358.496652] ? rcu_is_watching+0x15/0xd0 [ 1358.501853] do_syscall_64+0xf3/0x650 [ 1358.506762] ? trace_hardirqs_off+0xd/0x30 [ 1358.512158] ? exc_page_fault+0xda/0xf0 [ 1358.517253] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1358.523720] RIP: 0033:0x7f36e499222e [ 1358.528523] RSP: 002b:00007ffede494f70 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1358.537823] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f36e499222e [ 1358.546633] RDX: 0000000000000001 RSI: 0000556fd5bcb6b0 RDI: 0000000000000007 [ 1358.555443] RBP: 00007ffede494f80 R08: 0000000000000000 R09: 0000000000000000 [ 1358.564250] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f36e480a390 [ 1358.573060] R13: 0000556fd5bcb6b0 R14: 0000000000000007 R15: 0000000000000003 Finger prints: __schedule:schedule:schedule_preempt_disabled:__mutex_lock:threaded_store