====================================== | [ 789.142846] nipa-hw-worker: drivers/net:napi_threaded.py: timed out | [ 789.145260] nipa-hw-worker: [5/44] Retrying drivers/net:napi_threaded.py | [ 789.275756] kselftest: Running tests in drivers/net [ 989.665650] INFO: task python3:5877 blocked for more than 122 seconds. [ 989.673881] Not tainted 7.1.0-rc1-unbo-g45ed429acae8 #1 [ 989.681715] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 989.691306] task:python3 state:D stack:25672 pid:5877 tgid:5877 ppid:5876 task_flags:0x400100 flags:0x00080000 [ 989.704512] Call Trace: [ 989.708052] [ 989.711205] __schedule+0xcba/0x1940 [ 989.716024] ? io_schedule_timeout+0x140/0x140 [ 989.721808] ? find_held_lock+0x2b/0x80 [ 989.726916] ? __lock_release.isra.0+0x6b/0x1a0 [ 989.732802] schedule+0xd4/0x260 [ 989.737222] schedule_preempt_disabled+0x18/0x30 [ 989.743192] __mutex_lock+0x6a3/0x2000 [ 989.748196] ? threaded_store+0x132/0x220 [ 989.753497] ? ww_mutex_lock+0x160/0x160 [ 989.758695] ? selinux_inode_free_security+0x330/0x330 [ 989.765258] ? cap_capable+0x1d6/0x3e0 [ 989.770264] ? threaded_store+0x132/0x220 [ 989.775560] threaded_store+0x132/0x220 [ 989.780663] ? xps_rxqs_store+0x2e0/0x2e0 [ 989.785954] ? sysfs_file_kobj+0xbd/0x1e0 [ 989.791256] ? sysfs_kf_write+0x65/0x160 [ 989.796454] ? sysfs_file_ops+0x140/0x140 [ 989.801750] kernfs_fop_write_iter+0x335/0x510 [ 989.807537] ? kernfs_ops+0xf0/0xf0 [ 989.812247] new_sync_write+0x333/0x750 [ 989.817349] ? __lock_acquire+0x508/0xc10 [ 989.822647] ? new_sync_read+0x740/0x740 [ 989.827849] ? lock_acquire.part.0+0xbc/0x260 [ 989.833534] ? ksys_write+0x116/0x250 [ 989.838447] vfs_write+0x657/0x9b0 [ 989.843062] ksys_write+0x116/0x250 [ 989.847774] ? __ia32_sys_read+0xc0/0xc0 [ 989.852964] ? kernfs_fop_llseek+0x160/0x1f0 [ 989.858556] ? rcu_is_watching+0x15/0xd0 [ 989.863759] do_syscall_64+0xf3/0x650 [ 989.868667] ? trace_hardirqs_off+0xd/0x30 [ 989.874062] ? exc_page_fault+0xda/0xf0 [ 989.879164] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 989.885633] RIP: 0033:0x7f9d89b5622e [ 989.890446] RSP: 002b:00007fff54019820 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 989.899744] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f9d89b5622e [ 989.908552] RDX: 0000000000000001 RSI: 000055ec3f569a10 RDI: 0000000000000007 [ 989.917360] RBP: 00007fff54019830 R08: 0000000000000000 R09: 0000000000000000 [ 989.926170] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f9d899ce390 [ 989.934979] R13: 000055ec3f569a10 R14: 0000000000000007 R15: 0000000000000003 | [ 990.340586] #1: ff110001bfd05680 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 990.351548] #2: ff11000124760b50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 990.362703] #3: ff11000112001d60 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 990.372794] | [ 990.375261] =============================================\x0a [ 1112.547992] INFO: task python3:5877 blocked for more than 245 seconds. [ 1112.556141] Not tainted 7.1.0-rc1-unbo-g45ed429acae8 #1 [ 1112.563391] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1112.572982] task:python3 state:D stack:25672 pid:5877 tgid:5877 ppid:5876 task_flags:0x400100 flags:0x00080000 [ 1112.586190] Call Trace: [ 1112.589734] [ 1112.592885] __schedule+0xcba/0x1940 [ 1112.597703] ? io_schedule_timeout+0x140/0x140 [ 1112.603490] ? find_held_lock+0x2b/0x80 [ 1112.608598] ? __lock_release.isra.0+0x6b/0x1a0 [ 1112.614483] schedule+0xd4/0x260 [ 1112.618903] schedule_preempt_disabled+0x18/0x30 [ 1112.624884] __mutex_lock+0x6a3/0x2000 [ 1112.629892] ? threaded_store+0x132/0x220 [ 1112.635191] ? ww_mutex_lock+0x160/0x160 [ 1112.640389] ? selinux_inode_free_security+0x330/0x330 [ 1112.646959] ? cap_capable+0x1d6/0x3e0 [ 1112.651969] ? threaded_store+0x132/0x220 [ 1112.657269] threaded_store+0x132/0x220 [ 1112.662373] ? xps_rxqs_store+0x2e0/0x2e0 [ 1112.667671] ? sysfs_file_kobj+0xbd/0x1e0 [ 1112.672968] ? sysfs_kf_write+0x65/0x160 [ 1112.678169] ? sysfs_file_ops+0x140/0x140 [ 1112.683466] kernfs_fop_write_iter+0x335/0x510 [ 1112.689244] ? kernfs_ops+0xf0/0xf0 [ 1112.693956] new_sync_write+0x333/0x750 [ 1112.699059] ? __lock_acquire+0x508/0xc10 [ 1112.704357] ? new_sync_read+0x740/0x740 [ 1112.709557] ? lock_acquire.part.0+0xbc/0x260 [ 1112.715234] ? ksys_write+0x116/0x250 [ 1112.720146] vfs_write+0x657/0x9b0 [ 1112.724763] ksys_write+0x116/0x250 [ 1112.729475] ? __ia32_sys_read+0xc0/0xc0 [ 1112.734676] ? kernfs_fop_llseek+0x160/0x1f0 [ 1112.740267] ? rcu_is_watching+0x15/0xd0 [ 1112.745471] do_syscall_64+0xf3/0x650 [ 1112.750381] ? trace_hardirqs_off+0xd/0x30 [ 1112.755777] ? exc_page_fault+0xda/0xf0 [ 1112.760869] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1112.767340] RIP: 0033:0x7f9d89b5622e [ 1112.772148] RSP: 002b:00007fff54019820 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1112.781448] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f9d89b5622e [ 1112.790258] RDX: 0000000000000001 RSI: 000055ec3f569a10 RDI: 0000000000000007 [ 1112.799068] RBP: 00007fff54019830 R08: 0000000000000000 R09: 0000000000000000 [ 1112.807876] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f9d899ce390 [ 1112.816684] R13: 000055ec3f569a10 R14: 0000000000000007 R15: 0000000000000003 | [ 1113.193588] #1: ff110001bfd05680 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 1113.204541] #2: ff11000124760b50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1113.215692] #3: ff11000112001d60 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1113.225784] | [ 1113.228251] =============================================\x0a [ 1235.428358] INFO: task python3:5877 blocked for more than 368 seconds. [ 1235.436508] Not tainted 7.1.0-rc1-unbo-g45ed429acae8 #1 [ 1235.443759] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1235.453350] task:python3 state:D stack:25672 pid:5877 tgid:5877 ppid:5876 task_flags:0x400100 flags:0x00080002 [ 1235.466562] Call Trace: [ 1235.470104] [ 1235.473254] __schedule+0xcba/0x1940 [ 1235.478071] ? io_schedule_timeout+0x140/0x140 [ 1235.483856] ? find_held_lock+0x2b/0x80 [ 1235.488965] ? __lock_release.isra.0+0x6b/0x1a0 [ 1235.494849] schedule+0xd4/0x260 [ 1235.499270] schedule_preempt_disabled+0x18/0x30 [ 1235.505250] __mutex_lock+0x6a3/0x2000 [ 1235.510257] ? threaded_store+0x132/0x220 [ 1235.515559] ? ww_mutex_lock+0x160/0x160 [ 1235.520760] ? selinux_inode_free_security+0x330/0x330 [ 1235.527329] ? cap_capable+0x1d6/0x3e0 [ 1235.532337] ? threaded_store+0x132/0x220 [ 1235.537636] threaded_store+0x132/0x220 [ 1235.542741] ? xps_rxqs_store+0x2e0/0x2e0 [ 1235.548039] ? sysfs_file_kobj+0xbd/0x1e0 [ 1235.553341] ? sysfs_kf_write+0x65/0x160 [ 1235.558542] ? sysfs_file_ops+0x140/0x140 [ 1235.563838] kernfs_fop_write_iter+0x335/0x510 [ 1235.569624] ? kernfs_ops+0xf0/0xf0 [ 1235.574336] new_sync_write+0x333/0x750 [ 1235.579440] ? __lock_acquire+0x508/0xc10 [ 1235.584739] ? new_sync_read+0x740/0x740 [ 1235.589940] ? lock_acquire.part.0+0xbc/0x260 [ 1235.595616] ? ksys_write+0x116/0x250 [ 1235.600526] vfs_write+0x657/0x9b0 [ 1235.605142] ksys_write+0x116/0x250 [ 1235.609853] ? __ia32_sys_read+0xc0/0xc0 [ 1235.615053] ? kernfs_fop_llseek+0x160/0x1f0 [ 1235.620645] ? rcu_is_watching+0x15/0xd0 [ 1235.625848] do_syscall_64+0xf3/0x650 [ 1235.630757] ? trace_hardirqs_off+0xd/0x30 [ 1235.636152] ? exc_page_fault+0xda/0xf0 [ 1235.641254] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1235.647724] RIP: 0033:0x7f9d89b5622e [ 1235.652534] RSP: 002b:00007fff54019820 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1235.661825] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f9d89b5622e [ 1235.670634] RDX: 0000000000000001 RSI: 000055ec3f569a10 RDI: 0000000000000007 [ 1235.679444] RBP: 00007fff54019830 R08: 0000000000000000 R09: 0000000000000000 [ 1235.688255] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f9d899ce390 [ 1235.697063] R13: 000055ec3f569a10 R14: 0000000000000007 R15: 0000000000000003 | [ 1236.073912] #1: ff110001bfd05680 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 1236.084873] #2: ff11000124760b50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1236.096031] #3: ff11000112001d60 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1236.106127] | [ 1236.108595] =============================================\x0a [ 1358.310690] INFO: task python3:5877 blocked for more than 491 seconds. [ 1358.318836] Not tainted 7.1.0-rc1-unbo-g45ed429acae8 #1 [ 1358.326086] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1358.335677] task:python3 state:D stack:25672 pid:5877 tgid:5877 ppid:5876 task_flags:0x400100 flags:0x00080002 [ 1358.348883] Call Trace: [ 1358.352421] [ 1358.355572] __schedule+0xcba/0x1940 [ 1358.360390] ? io_schedule_timeout+0x140/0x140 [ 1358.366175] ? find_held_lock+0x2b/0x80 [ 1358.371281] ? __lock_release.isra.0+0x6b/0x1a0 [ 1358.377166] schedule+0xd4/0x260 [ 1358.381582] schedule_preempt_disabled+0x18/0x30 [ 1358.387562] __mutex_lock+0x6a3/0x2000 [ 1358.392564] ? threaded_store+0x132/0x220 [ 1358.397862] ? ww_mutex_lock+0x160/0x160 [ 1358.403060] ? selinux_inode_free_security+0x330/0x330 [ 1358.409628] ? cap_capable+0x1d6/0x3e0 [ 1358.414635] ? threaded_store+0x132/0x220 [ 1358.419932] threaded_store+0x132/0x220 [ 1358.425035] ? xps_rxqs_store+0x2e0/0x2e0 [ 1358.430333] ? sysfs_file_kobj+0xbd/0x1e0 [ 1358.435631] ? sysfs_kf_write+0x65/0x160 [ 1358.440834] ? sysfs_file_ops+0x140/0x140 [ 1358.446128] kernfs_fop_write_iter+0x335/0x510 [ 1358.451910] ? kernfs_ops+0xf0/0xf0 [ 1358.456619] new_sync_write+0x333/0x750 [ 1358.461723] ? __lock_acquire+0x508/0xc10 [ 1358.467021] ? new_sync_read+0x740/0x740 [ 1358.472222] ? lock_acquire.part.0+0xbc/0x260 [ 1358.477907] ? ksys_write+0x116/0x250 [ 1358.482818] vfs_write+0x657/0x9b0 [ 1358.487433] ksys_write+0x116/0x250 [ 1358.492134] ? __ia32_sys_read+0xc0/0xc0 [ 1358.497331] ? kernfs_fop_llseek+0x160/0x1f0 [ 1358.502917] ? rcu_is_watching+0x15/0xd0 [ 1358.508118] do_syscall_64+0xf3/0x650 [ 1358.513026] ? trace_hardirqs_off+0xd/0x30 [ 1358.518420] ? exc_page_fault+0xda/0xf0 [ 1358.523521] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1358.529991] RIP: 0033:0x7f9d89b5622e [ 1358.534801] RSP: 002b:00007fff54019820 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1358.544090] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f9d89b5622e [ 1358.552886] RDX: 0000000000000001 RSI: 000055ec3f569a10 RDI: 0000000000000007 [ 1358.561692] RBP: 00007fff54019830 R08: 0000000000000000 R09: 0000000000000000 [ 1358.570498] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f9d899ce390 [ 1358.579305] R13: 000055ec3f569a10 R14: 0000000000000007 R15: 0000000000000003 Finger prints: __schedule:schedule:schedule_preempt_disabled:__mutex_lock:threaded_store