====================================== | [ 787.136560] nipa-hw-worker: drivers/net:napi_threaded.py: timed out | [ 787.138967] nipa-hw-worker: [5/43] Retrying drivers/net:napi_threaded.py | [ 787.291008] kselftest: Running tests in drivers/net [ 989.710127] INFO: task python3:5912 blocked for more than 122 seconds. [ 989.718353] Not tainted 7.1.0-rc1-jcpg-g7773fb5e3fe2 #1 [ 989.726188] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 989.735777] task:python3 state:D stack:25672 pid:5912 tgid:5912 ppid:5911 task_flags:0x400100 flags:0x00080000 [ 989.748986] Call Trace: [ 989.752527] [ 989.755677] __schedule+0xcba/0x1940 [ 989.760496] ? io_schedule_timeout+0x140/0x140 [ 989.766284] ? find_held_lock+0x2b/0x80 [ 989.771391] ? __lock_release.isra.0+0x6b/0x1a0 [ 989.777277] schedule+0xd4/0x260 [ 989.781698] schedule_preempt_disabled+0x18/0x30 [ 989.787678] __mutex_lock+0x6a3/0x2000 [ 989.792684] ? threaded_store+0x132/0x220 [ 989.797986] ? ww_mutex_lock+0x160/0x160 [ 989.803186] ? selinux_inode_free_security+0x330/0x330 [ 989.809756] ? cap_capable+0x1d6/0x3e0 [ 989.814763] ? threaded_store+0x132/0x220 [ 989.820062] threaded_store+0x132/0x220 [ 989.825165] ? xps_rxqs_store+0x2e0/0x2e0 [ 989.830455] ? sysfs_file_kobj+0xbd/0x1e0 [ 989.835751] ? sysfs_kf_write+0x65/0x160 [ 989.840952] ? sysfs_file_ops+0x140/0x140 [ 989.846254] kernfs_fop_write_iter+0x335/0x510 [ 989.852042] ? kernfs_ops+0xf0/0xf0 [ 989.856753] new_sync_write+0x333/0x750 [ 989.861847] ? __lock_acquire+0x508/0xc10 [ 989.867143] ? new_sync_read+0x740/0x740 [ 989.872345] ? lock_acquire.part.0+0xbc/0x260 [ 989.878033] ? ksys_write+0x116/0x250 [ 989.882933] vfs_write+0x657/0x9b0 [ 989.887550] ksys_write+0x116/0x250 [ 989.892263] ? __ia32_sys_read+0xc0/0xc0 [ 989.897465] ? kernfs_fop_llseek+0x160/0x1f0 [ 989.903056] ? rcu_is_watching+0x15/0xd0 [ 989.908257] do_syscall_64+0xf3/0x650 [ 989.913162] ? trace_hardirqs_off+0xd/0x30 [ 989.918559] ? exc_page_fault+0xda/0xf0 [ 989.923661] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 989.930131] RIP: 0033:0x7f2d450f822e [ 989.934941] RSP: 002b:00007ffd1207c660 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 989.944241] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f2d450f822e [ 989.953051] RDX: 0000000000000001 RSI: 000055ec61ca2a10 RDI: 0000000000000007 [ 989.961858] RBP: 00007ffd1207c670 R08: 0000000000000000 R09: 0000000000000000 [ 989.970666] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f2d44f70390 [ 989.979475] R13: 000055ec61ca2a10 R14: 0000000000000007 R15: 0000000000000003 | [ 990.379427] #1: ff11000133faf480 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 990.390391] #2: ff11000123538b50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 990.401550] #3: ff11000123519d60 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 990.411639] | [ 990.414103] =============================================\x0a [ 1112.591258] INFO: task python3:5912 blocked for more than 245 seconds. [ 1112.599406] Not tainted 7.1.0-rc1-jcpg-g7773fb5e3fe2 #1 [ 1112.606656] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1112.616243] task:python3 state:D stack:25672 pid:5912 tgid:5912 ppid:5911 task_flags:0x400100 flags:0x00080000 [ 1112.629448] Call Trace: [ 1112.632988] [ 1112.636136] __schedule+0xcba/0x1940 [ 1112.640949] ? io_schedule_timeout+0x140/0x140 [ 1112.646732] ? find_held_lock+0x2b/0x80 [ 1112.651834] ? __lock_release.isra.0+0x6b/0x1a0 [ 1112.657721] schedule+0xd4/0x260 [ 1112.662138] schedule_preempt_disabled+0x18/0x30 [ 1112.668114] __mutex_lock+0x6a3/0x2000 [ 1112.673117] ? threaded_store+0x132/0x220 [ 1112.678416] ? ww_mutex_lock+0x160/0x160 [ 1112.683615] ? selinux_inode_free_security+0x330/0x330 [ 1112.690184] ? cap_capable+0x1d6/0x3e0 [ 1112.695195] ? threaded_store+0x132/0x220 [ 1112.700490] threaded_store+0x132/0x220 [ 1112.705591] ? xps_rxqs_store+0x2e0/0x2e0 [ 1112.710888] ? sysfs_file_kobj+0xbd/0x1e0 [ 1112.716183] ? sysfs_kf_write+0x65/0x160 [ 1112.721385] ? sysfs_file_ops+0x140/0x140 [ 1112.726678] kernfs_fop_write_iter+0x335/0x510 [ 1112.732460] ? kernfs_ops+0xf0/0xf0 [ 1112.737171] new_sync_write+0x333/0x750 [ 1112.742271] ? __lock_acquire+0x508/0xc10 [ 1112.747567] ? new_sync_read+0x740/0x740 [ 1112.752769] ? lock_acquire.part.0+0xbc/0x260 [ 1112.758456] ? ksys_write+0x116/0x250 [ 1112.763364] vfs_write+0x657/0x9b0 [ 1112.767976] ksys_write+0x116/0x250 [ 1112.772685] ? __ia32_sys_read+0xc0/0xc0 [ 1112.777883] ? kernfs_fop_llseek+0x160/0x1f0 [ 1112.783468] ? rcu_is_watching+0x15/0xd0 [ 1112.788671] do_syscall_64+0xf3/0x650 [ 1112.793576] ? trace_hardirqs_off+0xd/0x30 [ 1112.798971] ? exc_page_fault+0xda/0xf0 [ 1112.804074] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1112.810543] RIP: 0033:0x7f2d450f822e [ 1112.815354] RSP: 002b:00007ffd1207c660 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1112.824649] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f2d450f822e [ 1112.833455] RDX: 0000000000000001 RSI: 000055ec61ca2a10 RDI: 0000000000000007 [ 1112.842260] RBP: 00007ffd1207c670 R08: 0000000000000000 R09: 0000000000000000 [ 1112.851066] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f2d44f70390 [ 1112.859874] R13: 000055ec61ca2a10 R14: 0000000000000007 R15: 0000000000000003 | [ 1113.231085] #1: ff11000133faf480 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 1113.242045] #2: ff11000123538b50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1113.253201] #3: ff11000123519d60 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1113.263294] | [ 1113.265759] =============================================\x0a [ 1235.473370] INFO: task python3:5912 blocked for more than 368 seconds. [ 1235.481518] Not tainted 7.1.0-rc1-jcpg-g7773fb5e3fe2 #1 [ 1235.488769] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1235.498357] task:python3 state:D stack:25672 pid:5912 tgid:5912 ppid:5911 task_flags:0x400100 flags:0x00080002 [ 1235.511562] Call Trace: [ 1235.515101] [ 1235.518249] __schedule+0xcba/0x1940 [ 1235.523063] ? io_schedule_timeout+0x140/0x140 [ 1235.528849] ? find_held_lock+0x2b/0x80 [ 1235.533955] ? __lock_release.isra.0+0x6b/0x1a0 [ 1235.539840] schedule+0xd4/0x260 [ 1235.544256] schedule_preempt_disabled+0x18/0x30 [ 1235.550233] __mutex_lock+0x6a3/0x2000 [ 1235.555237] ? threaded_store+0x132/0x220 [ 1235.560535] ? ww_mutex_lock+0x160/0x160 [ 1235.565734] ? selinux_inode_free_security+0x330/0x330 [ 1235.572303] ? cap_capable+0x1d6/0x3e0 [ 1235.577311] ? threaded_store+0x132/0x220 [ 1235.582605] threaded_store+0x132/0x220 [ 1235.587705] ? xps_rxqs_store+0x2e0/0x2e0 [ 1235.593003] ? sysfs_file_kobj+0xbd/0x1e0 [ 1235.598302] ? sysfs_kf_write+0x65/0x160 [ 1235.603501] ? sysfs_file_ops+0x140/0x140 [ 1235.608794] kernfs_fop_write_iter+0x335/0x510 [ 1235.614576] ? kernfs_ops+0xf0/0xf0 [ 1235.619287] new_sync_write+0x333/0x750 [ 1235.624388] ? __lock_acquire+0x508/0xc10 [ 1235.629684] ? new_sync_read+0x740/0x740 [ 1235.634883] ? lock_acquire.part.0+0xbc/0x260 [ 1235.640568] ? ksys_write+0x116/0x250 [ 1235.645477] vfs_write+0x657/0x9b0 [ 1235.650090] ksys_write+0x116/0x250 [ 1235.654799] ? __ia32_sys_read+0xc0/0xc0 [ 1235.659995] ? kernfs_fop_llseek+0x160/0x1f0 [ 1235.665582] ? rcu_is_watching+0x15/0xd0 [ 1235.670783] do_syscall_64+0xf3/0x650 [ 1235.675688] ? trace_hardirqs_off+0xd/0x30 [ 1235.681084] ? exc_page_fault+0xda/0xf0 [ 1235.686188] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1235.692654] RIP: 0033:0x7f2d450f822e [ 1235.697465] RSP: 002b:00007ffd1207c660 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1235.706763] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f2d450f822e [ 1235.715568] RDX: 0000000000000001 RSI: 000055ec61ca2a10 RDI: 0000000000000007 [ 1235.724374] RBP: 00007ffd1207c670 R08: 0000000000000000 R09: 0000000000000000 [ 1235.733179] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f2d44f70390 [ 1235.741988] R13: 000055ec61ca2a10 R14: 0000000000000007 R15: 0000000000000003 | [ 1236.141874] #1: ff11000133faf480 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 1236.152835] #2: ff11000123538b50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1236.163982] #3: ff11000123519d60 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1236.174070] | [ 1236.176533] =============================================\x0a [ 1358.353477] INFO: task python3:5912 blocked for more than 491 seconds. [ 1358.361624] Not tainted 7.1.0-rc1-jcpg-g7773fb5e3fe2 #1 [ 1358.368874] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1358.378461] task:python3 state:D stack:25672 pid:5912 tgid:5912 ppid:5911 task_flags:0x400100 flags:0x00080002 [ 1358.391665] Call Trace: [ 1358.395204] [ 1358.398352] __schedule+0xcba/0x1940 [ 1358.403166] ? io_schedule_timeout+0x140/0x140 [ 1358.408944] ? find_held_lock+0x2b/0x80 [ 1358.414046] ? __lock_release.isra.0+0x6b/0x1a0 [ 1358.419929] schedule+0xd4/0x260 [ 1358.424338] schedule_preempt_disabled+0x18/0x30 [ 1358.430320] __mutex_lock+0x6a3/0x2000 [ 1358.435324] ? threaded_store+0x132/0x220 [ 1358.440623] ? ww_mutex_lock+0x160/0x160 [ 1358.445818] ? selinux_inode_free_security+0x330/0x330 [ 1358.452385] ? cap_capable+0x1d6/0x3e0 [ 1358.457380] ? threaded_store+0x132/0x220 [ 1358.462673] threaded_store+0x132/0x220 [ 1358.467776] ? xps_rxqs_store+0x2e0/0x2e0 [ 1358.473065] ? sysfs_file_kobj+0xbd/0x1e0 [ 1358.478366] ? sysfs_kf_write+0x65/0x160 [ 1358.483565] ? sysfs_file_ops+0x140/0x140 [ 1358.488860] kernfs_fop_write_iter+0x335/0x510 [ 1358.494644] ? kernfs_ops+0xf0/0xf0 [ 1358.499352] new_sync_write+0x333/0x750 [ 1358.504454] ? __lock_acquire+0x508/0xc10 [ 1358.509751] ? new_sync_read+0x740/0x740 [ 1358.514941] ? lock_acquire.part.0+0xbc/0x260 [ 1358.520624] ? ksys_write+0x116/0x250 [ 1358.525534] vfs_write+0x657/0x9b0 [ 1358.530147] ksys_write+0x116/0x250 [ 1358.534858] ? __ia32_sys_read+0xc0/0xc0 [ 1358.540056] ? kernfs_fop_llseek+0x160/0x1f0 [ 1358.545644] ? rcu_is_watching+0x15/0xd0 [ 1358.550846] do_syscall_64+0xf3/0x650 [ 1358.555752] ? trace_hardirqs_off+0xd/0x30 [ 1358.561144] ? exc_page_fault+0xda/0xf0 [ 1358.566243] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1358.572710] RIP: 0033:0x7f2d450f822e [ 1358.577518] RSP: 002b:00007ffd1207c660 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1358.586814] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f2d450f822e [ 1358.595623] RDX: 0000000000000001 RSI: 000055ec61ca2a10 RDI: 0000000000000007 [ 1358.604421] RBP: 00007ffd1207c670 R08: 0000000000000000 R09: 0000000000000000 [ 1358.613229] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f2d44f70390 [ 1358.622036] R13: 000055ec61ca2a10 R14: 0000000000000007 R15: 0000000000000003 Finger prints: __schedule:schedule:schedule_preempt_disabled:__mutex_lock:threaded_store