====================================== | [ 784.313495] nipa-hw-worker: drivers/net:napi_threaded.py: timed out | [ 784.315914] nipa-hw-worker: [5/47] Retrying drivers/net:napi_threaded.py | [ 784.472093] kselftest: Running tests in drivers/net [ 989.635087] INFO: task python3:5817 blocked for more than 122 seconds. [ 989.643291] Not tainted 7.1.0-rc3-gwzx-gf524823000d3 #1 [ 989.651014] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 989.660605] task:python3 state:D stack:25672 pid:5817 tgid:5817 ppid:5816 task_flags:0x400100 flags:0x00080000 [ 989.673811] Call Trace: [ 989.677349] [ 989.680500] __schedule+0xcc3/0x19c0 [ 989.685319] ? io_schedule_timeout+0x140/0x140 [ 989.691100] ? find_held_lock+0x2b/0x80 [ 989.696203] ? __lock_release.isra.0+0x6b/0x1a0 [ 989.702087] schedule+0xd4/0x260 [ 989.706503] schedule_preempt_disabled+0x18/0x30 [ 989.712478] __mutex_lock+0x6a3/0x2000 [ 989.717481] ? threaded_store+0x132/0x220 [ 989.722778] ? ww_mutex_lock+0x160/0x160 [ 989.727976] ? selinux_inode_free_security+0x330/0x330 [ 989.734546] ? cap_capable+0x1d6/0x3e0 [ 989.739555] ? threaded_store+0x132/0x220 [ 989.744850] threaded_store+0x132/0x220 [ 989.749951] ? xps_rxqs_store+0x2e0/0x2e0 [ 989.755248] ? sysfs_file_kobj+0xbd/0x1e0 [ 989.760548] ? sysfs_kf_write+0x65/0x160 [ 989.765748] ? sysfs_file_ops+0x140/0x140 [ 989.771044] kernfs_fop_write_iter+0x335/0x510 [ 989.776826] ? kernfs_ops+0xf0/0xf0 [ 989.781537] new_sync_write+0x333/0x750 [ 989.786641] ? __lock_acquire+0x508/0xc10 [ 989.791938] ? new_sync_read+0x740/0x740 [ 989.797135] ? lock_acquire.part.0+0xbc/0x260 [ 989.802821] ? ksys_write+0x116/0x250 [ 989.807730] vfs_write+0x657/0x9b0 [ 989.812343] ksys_write+0x116/0x250 [ 989.817055] ? __ia32_sys_read+0xc0/0xc0 [ 989.822245] ? kernfs_fop_llseek+0x160/0x1f0 [ 989.827832] ? rcu_is_watching+0x15/0xd0 [ 989.833031] do_syscall_64+0xf3/0x650 [ 989.837936] ? trace_hardirqs_off+0xd/0x30 [ 989.843330] ? exc_page_fault+0xda/0xf0 [ 989.848433] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 989.854900] RIP: 0033:0x7f37f656222e [ 989.859702] RSP: 002b:00007ffd2aa9d0f0 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 989.869000] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f37f656222e [ 989.877808] RDX: 0000000000000001 RSI: 00005614c63f8cd0 RDI: 0000000000000007 [ 989.886613] RBP: 00007ffd2aa9d100 R08: 0000000000000000 R09: 0000000000000000 [ 989.895420] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f37f63da390 [ 989.904225] R13: 00005614c63f8cd0 R14: 0000000000000007 R15: 0000000000000003 | [ 990.321262] #1: ff110001f2f26880 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 990.332220] #2: ff1100011cd78b50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 990.343377] #3: ff1100011cdf1d68 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 990.353457] | [ 990.355920] =============================================\x0a [ 1112.516174] INFO: task python3:5817 blocked for more than 245 seconds. [ 1112.524323] Not tainted 7.1.0-rc3-gwzx-gf524823000d3 #1 [ 1112.531574] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1112.541165] task:python3 state:D stack:25672 pid:5817 tgid:5817 ppid:5816 task_flags:0x400100 flags:0x00080000 [ 1112.554373] Call Trace: [ 1112.557916] [ 1112.561069] __schedule+0xcc3/0x19c0 [ 1112.565886] ? io_schedule_timeout+0x140/0x140 [ 1112.571671] ? find_held_lock+0x2b/0x80 [ 1112.576777] ? __lock_release.isra.0+0x6b/0x1a0 [ 1112.582665] schedule+0xd4/0x260 [ 1112.587083] schedule_preempt_disabled+0x18/0x30 [ 1112.593063] __mutex_lock+0x6a3/0x2000 [ 1112.598058] ? threaded_store+0x132/0x220 [ 1112.603361] ? ww_mutex_lock+0x160/0x160 [ 1112.608560] ? selinux_inode_free_security+0x330/0x330 [ 1112.615130] ? cap_capable+0x1d6/0x3e0 [ 1112.620137] ? threaded_store+0x132/0x220 [ 1112.625434] threaded_store+0x132/0x220 [ 1112.630537] ? xps_rxqs_store+0x2e0/0x2e0 [ 1112.635836] ? sysfs_file_kobj+0xbd/0x1e0 [ 1112.641139] ? sysfs_kf_write+0x65/0x160 [ 1112.646342] ? sysfs_file_ops+0x140/0x140 [ 1112.651640] kernfs_fop_write_iter+0x335/0x510 [ 1112.657417] ? kernfs_ops+0xf0/0xf0 [ 1112.662131] new_sync_write+0x333/0x750 [ 1112.667235] ? __lock_acquire+0x508/0xc10 [ 1112.672533] ? new_sync_read+0x740/0x740 [ 1112.677735] ? lock_acquire.part.0+0xbc/0x260 [ 1112.683420] ? ksys_write+0x116/0x250 [ 1112.688332] vfs_write+0x657/0x9b0 [ 1112.692949] ksys_write+0x116/0x250 [ 1112.697660] ? __ia32_sys_read+0xc0/0xc0 [ 1112.702862] ? kernfs_fop_llseek+0x160/0x1f0 [ 1112.708452] ? rcu_is_watching+0x15/0xd0 [ 1112.713657] do_syscall_64+0xf3/0x650 [ 1112.718565] ? trace_hardirqs_off+0xd/0x30 [ 1112.723960] ? exc_page_fault+0xda/0xf0 [ 1112.729063] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1112.735532] RIP: 0033:0x7f37f656222e [ 1112.740342] RSP: 002b:00007ffd2aa9d0f0 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1112.749632] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f37f656222e [ 1112.758441] RDX: 0000000000000001 RSI: 00005614c63f8cd0 RDI: 0000000000000007 [ 1112.767251] RBP: 00007ffd2aa9d100 R08: 0000000000000000 R09: 0000000000000000 [ 1112.776059] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f37f63da390 [ 1112.784870] R13: 00005614c63f8cd0 R14: 0000000000000007 R15: 0000000000000003 | [ 1113.161731] #1: ff110001f2f26880 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 1113.172693] #2: ff1100011cd78b50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1113.183852] #3: ff1100011cdf1d68 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1113.193943] | [ 1113.196402] =============================================\x0a [ 1235.397277] INFO: task python3:5817 blocked for more than 368 seconds. [ 1235.405426] Not tainted 7.1.0-rc3-gwzx-gf524823000d3 #1 [ 1235.412676] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1235.422267] task:python3 state:D stack:25672 pid:5817 tgid:5817 ppid:5816 task_flags:0x400100 flags:0x00080002 [ 1235.435474] Call Trace: [ 1235.439013] [ 1235.442164] __schedule+0xcc3/0x19c0 [ 1235.446982] ? io_schedule_timeout+0x140/0x140 [ 1235.452769] ? find_held_lock+0x2b/0x80 [ 1235.457876] ? __lock_release.isra.0+0x6b/0x1a0 [ 1235.463760] schedule+0xd4/0x260 [ 1235.468180] schedule_preempt_disabled+0x18/0x30 [ 1235.474158] __mutex_lock+0x6a3/0x2000 [ 1235.479162] ? threaded_store+0x132/0x220 [ 1235.484461] ? ww_mutex_lock+0x160/0x160 [ 1235.489661] ? selinux_inode_free_security+0x330/0x330 [ 1235.496230] ? cap_capable+0x1d6/0x3e0 [ 1235.501238] ? threaded_store+0x132/0x220 [ 1235.506539] threaded_store+0x132/0x220 [ 1235.511645] ? xps_rxqs_store+0x2e0/0x2e0 [ 1235.516944] ? sysfs_file_kobj+0xbd/0x1e0 [ 1235.522246] ? sysfs_kf_write+0x65/0x160 [ 1235.527447] ? sysfs_file_ops+0x140/0x140 [ 1235.532734] kernfs_fop_write_iter+0x335/0x510 [ 1235.538521] ? kernfs_ops+0xf0/0xf0 [ 1235.543231] new_sync_write+0x333/0x750 [ 1235.548328] ? __lock_acquire+0x508/0xc10 [ 1235.553627] ? new_sync_read+0x740/0x740 [ 1235.558830] ? lock_acquire.part.0+0xbc/0x260 [ 1235.564516] ? ksys_write+0x116/0x250 [ 1235.569429] vfs_write+0x657/0x9b0 [ 1235.574045] ksys_write+0x116/0x250 [ 1235.578757] ? __ia32_sys_read+0xc0/0xc0 [ 1235.583949] ? kernfs_fop_llseek+0x160/0x1f0 [ 1235.589532] ? rcu_is_watching+0x15/0xd0 [ 1235.594735] do_syscall_64+0xf3/0x650 [ 1235.599643] ? trace_hardirqs_off+0xd/0x30 [ 1235.605039] ? exc_page_fault+0xda/0xf0 [ 1235.610140] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1235.616611] RIP: 0033:0x7f37f656222e [ 1235.621420] RSP: 002b:00007ffd2aa9d0f0 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1235.630720] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f37f656222e [ 1235.639527] RDX: 0000000000000001 RSI: 00005614c63f8cd0 RDI: 0000000000000007 [ 1235.648335] RBP: 00007ffd2aa9d100 R08: 0000000000000000 R09: 0000000000000000 [ 1235.657145] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f37f63da390 [ 1235.665954] R13: 00005614c63f8cd0 R14: 0000000000000007 R15: 0000000000000003 | [ 1236.042799] #1: ff110001f2f26880 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 1236.053761] #2: ff1100011cd78b50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1236.064918] #3: ff1100011cdf1d68 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1236.075010] | [ 1236.077478] =============================================\x0a [ 1358.278434] INFO: task python3:5817 blocked for more than 491 seconds. [ 1358.286583] Not tainted 7.1.0-rc3-gwzx-gf524823000d3 #1 [ 1358.293826] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1358.303415] task:python3 state:D stack:25672 pid:5817 tgid:5817 ppid:5816 task_flags:0x400100 flags:0x00080002 [ 1358.316621] Call Trace: [ 1358.320161] [ 1358.323310] __schedule+0xcc3/0x19c0 [ 1358.328127] ? io_schedule_timeout+0x140/0x140 [ 1358.333911] ? find_held_lock+0x2b/0x80 [ 1358.339016] ? __lock_release.isra.0+0x6b/0x1a0 [ 1358.344890] schedule+0xd4/0x260 [ 1358.349297] schedule_preempt_disabled+0x18/0x30 [ 1358.355276] __mutex_lock+0x6a3/0x2000 [ 1358.360281] ? threaded_store+0x132/0x220 [ 1358.365579] ? ww_mutex_lock+0x160/0x160 [ 1358.370776] ? selinux_inode_free_security+0x330/0x330 [ 1358.377345] ? cap_capable+0x1d6/0x3e0 [ 1358.382353] ? threaded_store+0x132/0x220 [ 1358.387653] threaded_store+0x132/0x220 [ 1358.392756] ? xps_rxqs_store+0x2e0/0x2e0 [ 1358.398054] ? sysfs_file_kobj+0xbd/0x1e0 [ 1358.403351] ? sysfs_kf_write+0x65/0x160 [ 1358.408550] ? sysfs_file_ops+0x140/0x140 [ 1358.413847] kernfs_fop_write_iter+0x335/0x510 [ 1358.419629] ? kernfs_ops+0xf0/0xf0 [ 1358.424338] new_sync_write+0x333/0x750 [ 1358.429441] ? __lock_acquire+0x508/0xc10 [ 1358.434735] ? new_sync_read+0x740/0x740 [ 1358.439936] ? lock_acquire.part.0+0xbc/0x260 [ 1358.445620] ? ksys_write+0x116/0x250 [ 1358.450529] vfs_write+0x657/0x9b0 [ 1358.455144] ksys_write+0x116/0x250 [ 1358.459856] ? __ia32_sys_read+0xc0/0xc0 [ 1358.465052] ? kernfs_fop_llseek+0x160/0x1f0 [ 1358.470638] ? rcu_is_watching+0x15/0xd0 [ 1358.475839] do_syscall_64+0xf3/0x650 [ 1358.480742] ? trace_hardirqs_off+0xd/0x30 [ 1358.486138] ? exc_page_fault+0xda/0xf0 [ 1358.491232] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1358.497688] RIP: 0033:0x7f37f656222e [ 1358.502497] RSP: 002b:00007ffd2aa9d0f0 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1358.511796] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f37f656222e [ 1358.520601] RDX: 0000000000000001 RSI: 00005614c63f8cd0 RDI: 0000000000000007 [ 1358.529406] RBP: 00007ffd2aa9d100 R08: 0000000000000000 R09: 0000000000000000 [ 1358.538211] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f37f63da390 [ 1358.547017] R13: 00005614c63f8cd0 R14: 0000000000000007 R15: 0000000000000003 Finger prints: __schedule:schedule:schedule_preempt_disabled:__mutex_lock:threaded_store