====================================== | [ 781.412514] nipa-hw-worker: drivers/net:napi_threaded.py: timed out | [ 781.415007] nipa-hw-worker: [5/46] Retrying drivers/net:napi_threaded.py | [ 781.566001] kselftest: Running tests in drivers/net [ 989.682441] INFO: task python3:5694 blocked for more than 122 seconds. [ 989.690675] Not tainted 7.1.0-rc2-lpew-g9b05cb55ebd9 #1 [ 989.698624] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 989.708218] task:python3 state:D stack:25672 pid:5694 tgid:5694 ppid:5693 task_flags:0x400100 flags:0x00080000 [ 989.721426] Call Trace: [ 989.724965] [ 989.728117] __schedule+0xcba/0x1940 [ 989.732937] ? io_schedule_timeout+0x140/0x140 [ 989.738723] ? find_held_lock+0x2b/0x80 [ 989.743830] ? __lock_release.isra.0+0x6b/0x1a0 [ 989.749715] schedule+0xd4/0x260 [ 989.754134] schedule_preempt_disabled+0x18/0x30 [ 989.760116] __mutex_lock+0x6a3/0x2000 [ 989.765123] ? threaded_store+0x132/0x220 [ 989.770421] ? ww_mutex_lock+0x160/0x160 [ 989.775621] ? selinux_inode_free_security+0x330/0x330 [ 989.782191] ? cap_capable+0x1d6/0x3e0 [ 989.787198] ? threaded_store+0x132/0x220 [ 989.792497] threaded_store+0x132/0x220 [ 989.797600] ? xps_rxqs_store+0x2e0/0x2e0 [ 989.802889] ? sysfs_file_kobj+0xbd/0x1e0 [ 989.808189] ? sysfs_kf_write+0x65/0x160 [ 989.813391] ? sysfs_file_ops+0x140/0x140 [ 989.818686] kernfs_fop_write_iter+0x335/0x510 [ 989.824473] ? kernfs_ops+0xf0/0xf0 [ 989.829183] new_sync_write+0x333/0x750 [ 989.834286] ? __lock_acquire+0x508/0xc10 [ 989.839583] ? new_sync_read+0x740/0x740 [ 989.844783] ? lock_acquire.part.0+0xbc/0x260 [ 989.850471] ? ksys_write+0x116/0x250 [ 989.855372] vfs_write+0x657/0x9b0 [ 989.859988] ksys_write+0x116/0x250 [ 989.864701] ? __ia32_sys_read+0xc0/0xc0 [ 989.869899] ? kernfs_fop_llseek+0x160/0x1f0 [ 989.875490] ? rcu_is_watching+0x15/0xd0 [ 989.880694] do_syscall_64+0xf3/0x650 [ 989.885604] ? trace_hardirqs_off+0xd/0x30 [ 989.891001] ? exc_page_fault+0xda/0xf0 [ 989.896094] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 989.902563] RIP: 0033:0x7f7f83ef422e [ 989.907375] RSP: 002b:00007ffd050eff80 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 989.916672] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f7f83ef422e [ 989.925479] RDX: 0000000000000001 RSI: 00005625f7ab7fe0 RDI: 0000000000000007 [ 989.934290] RBP: 00007ffd050eff90 R08: 0000000000000000 R09: 0000000000000000 [ 989.943100] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f7f83d6c390 [ 989.951910] R13: 00005625f7ab7fe0 R14: 0000000000000007 R15: 0000000000000003 | [ 990.357480] #1: ff110001abc6b880 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 990.368443] #2: ff11000123b88b50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 990.379601] #3: ff11000123bd1d68 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 990.389690] | [ 990.392157] =============================================\x0a [ 1112.563668] INFO: task python3:5694 blocked for more than 245 seconds. [ 1112.571817] Not tainted 7.1.0-rc2-lpew-g9b05cb55ebd9 #1 [ 1112.579067] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1112.588659] task:python3 state:D stack:25672 pid:5694 tgid:5694 ppid:5693 task_flags:0x400100 flags:0x00080000 [ 1112.601866] Call Trace: [ 1112.605406] [ 1112.608558] __schedule+0xcba/0x1940 [ 1112.613377] ? io_schedule_timeout+0x140/0x140 [ 1112.619163] ? find_held_lock+0x2b/0x80 [ 1112.624271] ? __lock_release.isra.0+0x6b/0x1a0 [ 1112.630159] schedule+0xd4/0x260 [ 1112.634581] schedule_preempt_disabled+0x18/0x30 [ 1112.640561] __mutex_lock+0x6a3/0x2000 [ 1112.645568] ? threaded_store+0x132/0x220 [ 1112.650869] ? ww_mutex_lock+0x160/0x160 [ 1112.656070] ? selinux_inode_free_security+0x330/0x330 [ 1112.662643] ? cap_capable+0x1d6/0x3e0 [ 1112.667651] ? threaded_store+0x132/0x220 [ 1112.672947] threaded_store+0x132/0x220 [ 1112.678050] ? xps_rxqs_store+0x2e0/0x2e0 [ 1112.683347] ? sysfs_file_kobj+0xbd/0x1e0 [ 1112.688650] ? sysfs_kf_write+0x65/0x160 [ 1112.693853] ? sysfs_file_ops+0x140/0x140 [ 1112.699148] kernfs_fop_write_iter+0x335/0x510 [ 1112.704935] ? kernfs_ops+0xf0/0xf0 [ 1112.709645] new_sync_write+0x333/0x750 [ 1112.714749] ? __lock_acquire+0x508/0xc10 [ 1112.720049] ? new_sync_read+0x740/0x740 [ 1112.725249] ? lock_acquire.part.0+0xbc/0x260 [ 1112.730935] ? ksys_write+0x116/0x250 [ 1112.735848] vfs_write+0x657/0x9b0 [ 1112.740463] ksys_write+0x116/0x250 [ 1112.745176] ? __ia32_sys_read+0xc0/0xc0 [ 1112.750376] ? kernfs_fop_llseek+0x160/0x1f0 [ 1112.755965] ? rcu_is_watching+0x15/0xd0 [ 1112.761170] do_syscall_64+0xf3/0x650 [ 1112.766077] ? trace_hardirqs_off+0xd/0x30 [ 1112.771475] ? exc_page_fault+0xda/0xf0 [ 1112.776577] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1112.783046] RIP: 0033:0x7f7f83ef422e [ 1112.787854] RSP: 002b:00007ffd050eff80 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1112.797151] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f7f83ef422e [ 1112.805950] RDX: 0000000000000001 RSI: 00005625f7ab7fe0 RDI: 0000000000000007 [ 1112.814758] RBP: 00007ffd050eff90 R08: 0000000000000000 R09: 0000000000000000 [ 1112.823567] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f7f83d6c390 [ 1112.832376] R13: 00005625f7ab7fe0 R14: 0000000000000007 R15: 0000000000000003 | [ 1113.209240] #1: ff110001abc6b880 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 1113.220200] #2: ff11000123b88b50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1113.231357] #3: ff11000123bd1d68 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1113.241446] | [ 1113.243914] =============================================\x0a [ 1235.443874] INFO: task python3:5694 blocked for more than 368 seconds. [ 1235.452024] Not tainted 7.1.0-rc2-lpew-g9b05cb55ebd9 #1 [ 1235.459273] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1235.468864] task:python3 state:D stack:25672 pid:5694 tgid:5694 ppid:5693 task_flags:0x400100 flags:0x00080002 [ 1235.482070] Call Trace: [ 1235.485608] [ 1235.488758] __schedule+0xcba/0x1940 [ 1235.493575] ? io_schedule_timeout+0x140/0x140 [ 1235.499359] ? find_held_lock+0x2b/0x80 [ 1235.504456] ? __lock_release.isra.0+0x6b/0x1a0 [ 1235.510343] schedule+0xd4/0x260 [ 1235.514759] schedule_preempt_disabled+0x18/0x30 [ 1235.520739] __mutex_lock+0x6a3/0x2000 [ 1235.525741] ? threaded_store+0x132/0x220 [ 1235.531041] ? ww_mutex_lock+0x160/0x160 [ 1235.536239] ? selinux_inode_free_security+0x330/0x330 [ 1235.542807] ? cap_capable+0x1d6/0x3e0 [ 1235.547815] ? threaded_store+0x132/0x220 [ 1235.553113] threaded_store+0x132/0x220 [ 1235.558214] ? xps_rxqs_store+0x2e0/0x2e0 [ 1235.563508] ? sysfs_file_kobj+0xbd/0x1e0 [ 1235.568803] ? sysfs_kf_write+0x65/0x160 [ 1235.574003] ? sysfs_file_ops+0x140/0x140 [ 1235.579298] kernfs_fop_write_iter+0x335/0x510 [ 1235.585083] ? kernfs_ops+0xf0/0xf0 [ 1235.589784] new_sync_write+0x333/0x750 [ 1235.594887] ? __lock_acquire+0x508/0xc10 [ 1235.600182] ? new_sync_read+0x740/0x740 [ 1235.605384] ? lock_acquire.part.0+0xbc/0x260 [ 1235.611069] ? ksys_write+0x116/0x250 [ 1235.615977] vfs_write+0x657/0x9b0 [ 1235.620584] ksys_write+0x116/0x250 [ 1235.625295] ? __ia32_sys_read+0xc0/0xc0 [ 1235.630492] ? kernfs_fop_llseek+0x160/0x1f0 [ 1235.636081] ? rcu_is_watching+0x15/0xd0 [ 1235.641284] do_syscall_64+0xf3/0x650 [ 1235.646188] ? trace_hardirqs_off+0xd/0x30 [ 1235.651583] ? exc_page_fault+0xda/0xf0 [ 1235.656683] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1235.663152] RIP: 0033:0x7f7f83ef422e [ 1235.667961] RSP: 002b:00007ffd050eff80 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1235.677258] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f7f83ef422e [ 1235.686064] RDX: 0000000000000001 RSI: 00005625f7ab7fe0 RDI: 0000000000000007 [ 1235.694869] RBP: 00007ffd050eff90 R08: 0000000000000000 R09: 0000000000000000 [ 1235.703673] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f7f83d6c390 [ 1235.712480] R13: 00005625f7ab7fe0 R14: 0000000000000007 R15: 0000000000000003 | [ 1236.089261] #1: ff110001abc6b880 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 1236.100221] #2: ff11000123b88b50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1236.111380] #3: ff11000123bd1d68 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1236.121471] | [ 1236.123938] =============================================\x0a [ 1358.326091] INFO: task python3:5694 blocked for more than 491 seconds. [ 1358.334237] Not tainted 7.1.0-rc2-lpew-g9b05cb55ebd9 #1 [ 1358.341487] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1358.351078] task:python3 state:D stack:25672 pid:5694 tgid:5694 ppid:5693 task_flags:0x400100 flags:0x00080002 [ 1358.364287] Call Trace: [ 1358.367827] [ 1358.370976] __schedule+0xcba/0x1940 [ 1358.375793] ? io_schedule_timeout+0x140/0x140 [ 1358.381580] ? find_held_lock+0x2b/0x80 [ 1358.386690] ? __lock_release.isra.0+0x6b/0x1a0 [ 1358.392574] schedule+0xd4/0x260 [ 1358.396991] schedule_preempt_disabled+0x18/0x30 [ 1358.402972] __mutex_lock+0x6a3/0x2000 [ 1358.407980] ? threaded_store+0x132/0x220 [ 1358.413279] ? ww_mutex_lock+0x160/0x160 [ 1358.418469] ? selinux_inode_free_security+0x330/0x330 [ 1358.425030] ? cap_capable+0x1d6/0x3e0 [ 1358.430039] ? threaded_store+0x132/0x220 [ 1358.435337] threaded_store+0x132/0x220 [ 1358.440441] ? xps_rxqs_store+0x2e0/0x2e0 [ 1358.445739] ? sysfs_file_kobj+0xbd/0x1e0 [ 1358.451044] ? sysfs_kf_write+0x65/0x160 [ 1358.456245] ? sysfs_file_ops+0x140/0x140 [ 1358.461540] kernfs_fop_write_iter+0x335/0x510 [ 1358.467327] ? kernfs_ops+0xf0/0xf0 [ 1358.472041] new_sync_write+0x333/0x750 [ 1358.477145] ? __lock_acquire+0x508/0xc10 [ 1358.482445] ? new_sync_read+0x740/0x740 [ 1358.487646] ? lock_acquire.part.0+0xbc/0x260 [ 1358.493331] ? ksys_write+0x116/0x250 [ 1358.498241] vfs_write+0x657/0x9b0 [ 1358.502856] ksys_write+0x116/0x250 [ 1358.507569] ? __ia32_sys_read+0xc0/0xc0 [ 1358.512762] ? kernfs_fop_llseek+0x160/0x1f0 [ 1358.518354] ? rcu_is_watching+0x15/0xd0 [ 1358.523558] do_syscall_64+0xf3/0x650 [ 1358.528465] ? trace_hardirqs_off+0xd/0x30 [ 1358.533851] ? exc_page_fault+0xda/0xf0 [ 1358.538953] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1358.545424] RIP: 0033:0x7f7f83ef422e [ 1358.550235] RSP: 002b:00007ffd050eff80 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1358.559534] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f7f83ef422e [ 1358.568344] RDX: 0000000000000001 RSI: 00005625f7ab7fe0 RDI: 0000000000000007 [ 1358.577154] RBP: 00007ffd050eff90 R08: 0000000000000000 R09: 0000000000000000 [ 1358.585963] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f7f83d6c390 [ 1358.594773] R13: 00005625f7ab7fe0 R14: 0000000000000007 R15: 0000000000000003 Finger prints: __schedule:schedule:schedule_preempt_disabled:__mutex_lock:threaded_store