====================================== | [ 783.227006] nipa-hw-worker: drivers/net:napi_threaded.py: timed out | [ 783.229442] nipa-hw-worker: [5/46] Retrying drivers/net:napi_threaded.py | [ 783.382973] kselftest: Running tests in drivers/net [ 989.709367] INFO: task python3:5740 blocked for more than 122 seconds. [ 989.717605] Not tainted 7.1.0-rc4-ytun-g8252f1aece1a #1 [ 989.725531] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 989.735122] task:python3 state:D stack:25672 pid:5740 tgid:5740 ppid:5739 task_flags:0x400100 flags:0x00080000 [ 989.748333] Call Trace: [ 989.751874] [ 989.755022] __schedule+0xcc3/0x19c0 [ 989.759837] ? io_schedule_timeout+0x140/0x140 [ 989.765622] ? find_held_lock+0x2b/0x80 [ 989.770728] ? __lock_release.isra.0+0x6b/0x1a0 [ 989.776612] schedule+0xd8/0x260 [ 989.781029] schedule_preempt_disabled+0x18/0x30 [ 989.787007] __mutex_lock+0x6af/0x2010 [ 989.792011] ? threaded_store+0x132/0x220 [ 989.797311] ? ww_mutex_lock+0x160/0x160 [ 989.802510] ? selinux_inode_free_security+0x330/0x330 [ 989.809078] ? cap_capable+0x1d6/0x3e0 [ 989.814089] ? threaded_store+0x132/0x220 [ 989.819388] threaded_store+0x132/0x220 [ 989.824488] ? xps_rxqs_store+0x2e0/0x2e0 [ 989.829785] ? sysfs_file_kobj+0xbd/0x1e0 [ 989.835081] ? sysfs_kf_write+0x65/0x160 [ 989.840281] ? sysfs_file_ops+0x140/0x140 [ 989.845566] kernfs_fop_write_iter+0x335/0x510 [ 989.851349] ? kernfs_ops+0xf0/0xf0 [ 989.856059] new_sync_write+0x333/0x750 [ 989.861161] ? __lock_acquire+0x508/0xc10 [ 989.866458] ? new_sync_read+0x740/0x740 [ 989.871655] ? lock_acquire.part.0+0xbc/0x260 [ 989.877341] ? ksys_write+0x116/0x250 [ 989.882250] vfs_write+0x657/0x9b0 [ 989.886865] ksys_write+0x116/0x250 [ 989.891575] ? __ia32_sys_read+0xc0/0xc0 [ 989.896773] ? kernfs_fop_llseek+0x160/0x1f0 [ 989.902359] ? rcu_is_watching+0x15/0xd0 [ 989.907560] do_syscall_64+0xf3/0x650 [ 989.912466] ? trace_hardirqs_off+0xd/0x30 [ 989.917862] ? exc_page_fault+0xda/0xf0 [ 989.922956] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 989.929421] RIP: 0033:0x7f337193822e [ 989.934232] RSP: 002b:00007fffcd73a940 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 989.943529] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f337193822e [ 989.952337] RDX: 0000000000000001 RSI: 00005644224086b0 RDI: 0000000000000007 [ 989.961142] RBP: 00007fffcd73a950 R08: 0000000000000000 R09: 0000000000000000 [ 989.969950] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f33717b0390 [ 989.978760] R13: 00005644224086b0 R14: 0000000000000007 R15: 0000000000000003 | [ 990.349972] #1: ff1100014fc12c80 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 990.360934] #2: ff11000120e02b50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 990.372090] #3: ff11000120e69d68 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 990.382178] | [ 990.384645] =============================================\x0a [ 1112.590386] INFO: task python3:5740 blocked for more than 245 seconds. [ 1112.598533] Not tainted 7.1.0-rc4-ytun-g8252f1aece1a #1 [ 1112.605781] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1112.615363] task:python3 state:D stack:25672 pid:5740 tgid:5740 ppid:5739 task_flags:0x400100 flags:0x00080000 [ 1112.628570] Call Trace: [ 1112.632112] [ 1112.635271] __schedule+0xcc3/0x19c0 [ 1112.640089] ? io_schedule_timeout+0x140/0x140 [ 1112.645874] ? find_held_lock+0x2b/0x80 [ 1112.650979] ? __lock_release.isra.0+0x6b/0x1a0 [ 1112.656854] schedule+0xd8/0x260 [ 1112.661271] schedule_preempt_disabled+0x18/0x30 [ 1112.667253] __mutex_lock+0x6af/0x2010 [ 1112.672257] ? threaded_store+0x132/0x220 [ 1112.677556] ? ww_mutex_lock+0x160/0x160 [ 1112.682749] ? selinux_inode_free_security+0x330/0x330 [ 1112.689321] ? cap_capable+0x1d6/0x3e0 [ 1112.694331] ? threaded_store+0x132/0x220 [ 1112.699626] threaded_store+0x132/0x220 [ 1112.704728] ? xps_rxqs_store+0x2e0/0x2e0 [ 1112.710026] ? sysfs_file_kobj+0xbd/0x1e0 [ 1112.715325] ? sysfs_kf_write+0x65/0x160 [ 1112.720519] ? sysfs_file_ops+0x140/0x140 [ 1112.725817] kernfs_fop_write_iter+0x335/0x510 [ 1112.731604] ? kernfs_ops+0xf0/0xf0 [ 1112.736317] new_sync_write+0x333/0x750 [ 1112.741421] ? __lock_acquire+0x508/0xc10 [ 1112.746718] ? new_sync_read+0x740/0x740 [ 1112.751919] ? lock_acquire.part.0+0xbc/0x260 [ 1112.757604] ? ksys_write+0x116/0x250 [ 1112.762514] vfs_write+0x657/0x9b0 [ 1112.767128] ksys_write+0x116/0x250 [ 1112.771839] ? __ia32_sys_read+0xc0/0xc0 [ 1112.777036] ? kernfs_fop_llseek+0x160/0x1f0 [ 1112.782625] ? rcu_is_watching+0x15/0xd0 [ 1112.787820] do_syscall_64+0xf3/0x650 [ 1112.792729] ? trace_hardirqs_off+0xd/0x30 [ 1112.798125] ? exc_page_fault+0xda/0xf0 [ 1112.803219] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1112.809689] RIP: 0033:0x7f337193822e [ 1112.814498] RSP: 002b:00007fffcd73a940 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1112.823796] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f337193822e [ 1112.832606] RDX: 0000000000000001 RSI: 00005644224086b0 RDI: 0000000000000007 [ 1112.841415] RBP: 00007fffcd73a950 R08: 0000000000000000 R09: 0000000000000000 [ 1112.850232] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f33717b0390 [ 1112.859033] R13: 00005644224086b0 R14: 0000000000000007 R15: 0000000000000003 | [ 1113.270550] #1: ff1100014fc12c80 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 1113.281502] #2: ff11000120e02b50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1113.292661] #3: ff11000120e69d68 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1113.302753] | [ 1113.305222] =============================================\x0a [ 1235.471455] INFO: task python3:5740 blocked for more than 368 seconds. [ 1235.479602] Not tainted 7.1.0-rc4-ytun-g8252f1aece1a #1 [ 1235.486856] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1235.496445] task:python3 state:D stack:25672 pid:5740 tgid:5740 ppid:5739 task_flags:0x400100 flags:0x00080002 [ 1235.509641] Call Trace: [ 1235.513179] [ 1235.516328] __schedule+0xcc3/0x19c0 [ 1235.521143] ? io_schedule_timeout+0x140/0x140 [ 1235.526927] ? find_held_lock+0x2b/0x80 [ 1235.532023] ? __lock_release.isra.0+0x6b/0x1a0 [ 1235.537910] schedule+0xd8/0x260 [ 1235.542326] schedule_preempt_disabled+0x18/0x30 [ 1235.548304] __mutex_lock+0x6af/0x2010 [ 1235.553308] ? threaded_store+0x132/0x220 [ 1235.558606] ? ww_mutex_lock+0x160/0x160 [ 1235.563802] ? selinux_inode_free_security+0x330/0x330 [ 1235.570370] ? cap_capable+0x1d6/0x3e0 [ 1235.575381] ? threaded_store+0x132/0x220 [ 1235.580677] threaded_store+0x132/0x220 [ 1235.585776] ? xps_rxqs_store+0x2e0/0x2e0 [ 1235.591071] ? sysfs_file_kobj+0xbd/0x1e0 [ 1235.596367] ? sysfs_kf_write+0x65/0x160 [ 1235.601565] ? sysfs_file_ops+0x140/0x140 [ 1235.606860] kernfs_fop_write_iter+0x335/0x510 [ 1235.612642] ? kernfs_ops+0xf0/0xf0 [ 1235.617351] new_sync_write+0x333/0x750 [ 1235.622453] ? __lock_acquire+0x508/0xc10 [ 1235.627749] ? new_sync_read+0x740/0x740 [ 1235.632948] ? lock_acquire.part.0+0xbc/0x260 [ 1235.638633] ? ksys_write+0x116/0x250 [ 1235.643542] vfs_write+0x657/0x9b0 [ 1235.648157] ksys_write+0x116/0x250 [ 1235.652861] ? __ia32_sys_read+0xc0/0xc0 [ 1235.658052] ? kernfs_fop_llseek+0x160/0x1f0 [ 1235.663641] ? rcu_is_watching+0x15/0xd0 [ 1235.668844] do_syscall_64+0xf3/0x650 [ 1235.673750] ? trace_hardirqs_off+0xd/0x30 [ 1235.679137] ? exc_page_fault+0xda/0xf0 [ 1235.684239] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1235.690700] RIP: 0033:0x7f337193822e [ 1235.695510] RSP: 002b:00007fffcd73a940 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1235.704807] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f337193822e [ 1235.713608] RDX: 0000000000000001 RSI: 00005644224086b0 RDI: 0000000000000007 [ 1235.722413] RBP: 00007fffcd73a950 R08: 0000000000000000 R09: 0000000000000000 [ 1235.731219] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f33717b0390 [ 1235.740026] R13: 00005644224086b0 R14: 0000000000000007 R15: 0000000000000003 | [ 1236.151432] #1: ff1100014fc12c80 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 1236.162383] #2: ff11000120e02b50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1236.173536] #3: ff11000120e69d68 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1236.183626] | [ 1236.186092] =============================================\x0a [ 1358.351591] INFO: task python3:5740 blocked for more than 491 seconds. [ 1358.359748] Not tainted 7.1.0-rc4-ytun-g8252f1aece1a #1 [ 1358.366998] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1358.376591] task:python3 state:D stack:25672 pid:5740 tgid:5740 ppid:5739 task_flags:0x400100 flags:0x00080002 [ 1358.389798] Call Trace: [ 1358.393340] [ 1358.396490] __schedule+0xcc3/0x19c0 [ 1358.401307] ? io_schedule_timeout+0x140/0x140 [ 1358.407091] ? find_held_lock+0x2b/0x80 [ 1358.412197] ? __lock_release.isra.0+0x6b/0x1a0 [ 1358.418086] schedule+0xd8/0x260 [ 1358.422579] schedule_preempt_disabled+0x18/0x30 [ 1358.428558] __mutex_lock+0x6af/0x2010 [ 1358.433564] ? threaded_store+0x132/0x220 [ 1358.438933] ? ww_mutex_lock+0x160/0x160 [ 1358.444126] ? selinux_inode_free_security+0x330/0x330 [ 1358.450698] ? cap_capable+0x1d6/0x3e0 [ 1358.455774] ? threaded_store+0x132/0x220 [ 1358.461075] threaded_store+0x132/0x220 [ 1358.466180] ? xps_rxqs_store+0x2e0/0x2e0 [ 1358.471478] ? sysfs_file_kobj+0xbd/0x1e0 [ 1358.476780] ? sysfs_kf_write+0x65/0x160 [ 1358.481982] ? sysfs_file_ops+0x140/0x140 [ 1358.481986] kernfs_fop_write_iter+0x335/0x510 [ 1358.481990] ? kernfs_ops+0xf0/0xf0 [ 1358.481993] new_sync_write+0x333/0x750 [ 1358.481999] ? __lock_acquire+0x508/0xc10 [ 1358.508208] ? new_sync_read+0x740/0x740 [ 1358.513409] ? lock_acquire.part.0+0xbc/0x260 [ 1358.519095] ? ksys_write+0x116/0x250 [ 1358.524006] vfs_write+0x657/0x9b0 [ 1358.528622] ksys_write+0x116/0x250 [ 1358.533334] ? __ia32_sys_read+0xc0/0xc0 [ 1358.538536] ? kernfs_fop_llseek+0x160/0x1f0 [ 1358.544126] ? rcu_is_watching+0x15/0xd0 [ 1358.549330] do_syscall_64+0xf3/0x650 [ 1358.554236] ? trace_hardirqs_off+0xd/0x30 [ 1358.559634] ? exc_page_fault+0xda/0xf0 [ 1358.564735] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1358.571203] RIP: 0033:0x7f337193822e [ 1358.576012] RSP: 002b:00007fffcd73a940 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1358.585313] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f337193822e [ 1358.594123] RDX: 0000000000000001 RSI: 00005644224086b0 RDI: 0000000000000007 [ 1358.602929] RBP: 00007fffcd73a950 R08: 0000000000000000 R09: 0000000000000000 [ 1358.611737] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f33717b0390 [ 1358.620547] R13: 00005644224086b0 R14: 0000000000000007 R15: 0000000000000003 Finger prints: __schedule:schedule:schedule_preempt_disabled:__mutex_lock:threaded_store