====================================== | [ 793.759269] nipa-hw-worker: drivers/net:napi_threaded.py: timed out | [ 793.761660] nipa-hw-worker: [5/47] Retrying drivers/net:napi_threaded.py | [ 793.889752] kselftest: Running tests in drivers/net [ 989.658062] INFO: task python3:5857 blocked for more than 122 seconds. [ 989.666281] Not tainted 7.1.0-rc3-onyy-g4194a54e9394 #1 [ 989.674142] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 989.683731] task:python3 state:D stack:25672 pid:5857 tgid:5857 ppid:5856 task_flags:0x400100 flags:0x00080000 [ 989.696936] Call Trace: [ 989.700475] [ 989.703627] __schedule+0xcc3/0x19c0 [ 989.708444] ? io_schedule_timeout+0x140/0x140 [ 989.714228] ? find_held_lock+0x2b/0x80 [ 989.719332] ? __lock_release.isra.0+0x6b/0x1a0 [ 989.725220] schedule+0xd4/0x260 [ 989.729639] schedule_preempt_disabled+0x18/0x30 [ 989.735618] __mutex_lock+0x6a3/0x2000 [ 989.740622] ? threaded_store+0x132/0x220 [ 989.745922] ? ww_mutex_lock+0x160/0x160 [ 989.751119] ? selinux_inode_free_security+0x330/0x330 [ 989.757689] ? cap_capable+0x1d6/0x3e0 [ 989.762697] ? threaded_store+0x132/0x220 [ 989.767995] threaded_store+0x132/0x220 [ 989.773099] ? xps_rxqs_store+0x2e0/0x2e0 [ 989.778398] ? sysfs_file_kobj+0xbd/0x1e0 [ 989.783697] ? sysfs_kf_write+0x65/0x160 [ 989.788896] ? sysfs_file_ops+0x140/0x140 [ 989.794191] kernfs_fop_write_iter+0x335/0x510 [ 989.799976] ? kernfs_ops+0xf0/0xf0 [ 989.804680] new_sync_write+0x333/0x750 [ 989.809787] ? __lock_acquire+0x508/0xc10 [ 989.815084] ? new_sync_read+0x740/0x740 [ 989.820285] ? lock_acquire.part.0+0xbc/0x260 [ 989.825974] ? ksys_write+0x116/0x250 [ 989.830885] vfs_write+0x657/0x9b0 [ 989.835501] ksys_write+0x116/0x250 [ 989.840212] ? __ia32_sys_read+0xc0/0xc0 [ 989.845412] ? kernfs_fop_llseek+0x160/0x1f0 [ 989.850994] ? rcu_is_watching+0x15/0xd0 [ 989.856197] do_syscall_64+0xf3/0x650 [ 989.861105] ? trace_hardirqs_off+0xd/0x30 [ 989.866500] ? exc_page_fault+0xda/0xf0 [ 989.871604] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 989.878071] RIP: 0033:0x7f2e1ea4322e [ 989.882880] RSP: 002b:00007ffe1830ca30 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 989.892180] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f2e1ea4322e [ 989.900988] RDX: 0000000000000001 RSI: 000055b7572ebcd0 RDI: 0000000000000007 [ 989.909786] RBP: 00007ffe1830ca40 R08: 0000000000000000 R09: 0000000000000000 [ 989.918595] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f2e1e8bb390 [ 989.927404] R13: 000055b7572ebcd0 R14: 0000000000000007 R15: 0000000000000003 | [ 990.298628] #1: ff110001f25ad680 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 990.309591] #2: ff11000122402b50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 990.320751] #3: ff110001223e9d68 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 990.330839] | [ 990.333305] =============================================\x0a [ 1112.539327] INFO: task python3:5857 blocked for more than 245 seconds. [ 1112.547474] Not tainted 7.1.0-rc3-onyy-g4194a54e9394 #1 [ 1112.554724] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1112.564314] task:python3 state:D stack:25672 pid:5857 tgid:5857 ppid:5856 task_flags:0x400100 flags:0x00080000 [ 1112.577518] Call Trace: [ 1112.581058] [ 1112.584211] __schedule+0xcc3/0x19c0 [ 1112.589030] ? io_schedule_timeout+0x140/0x140 [ 1112.594817] ? find_held_lock+0x2b/0x80 [ 1112.599924] ? __lock_release.isra.0+0x6b/0x1a0 [ 1112.605809] schedule+0xd4/0x260 [ 1112.610230] schedule_preempt_disabled+0x18/0x30 [ 1112.616210] __mutex_lock+0x6a3/0x2000 [ 1112.621208] ? threaded_store+0x132/0x220 [ 1112.626510] ? ww_mutex_lock+0x160/0x160 [ 1112.631709] ? selinux_inode_free_security+0x330/0x330 [ 1112.638277] ? cap_capable+0x1d6/0x3e0 [ 1112.643286] ? threaded_store+0x132/0x220 [ 1112.648585] threaded_store+0x132/0x220 [ 1112.653690] ? xps_rxqs_store+0x2e0/0x2e0 [ 1112.658988] ? sysfs_file_kobj+0xbd/0x1e0 [ 1112.664287] ? sysfs_kf_write+0x65/0x160 [ 1112.669490] ? sysfs_file_ops+0x140/0x140 [ 1112.674787] kernfs_fop_write_iter+0x335/0x510 [ 1112.680573] ? kernfs_ops+0xf0/0xf0 [ 1112.685284] new_sync_write+0x333/0x750 [ 1112.690387] ? __lock_acquire+0x508/0xc10 [ 1112.695683] ? new_sync_read+0x740/0x740 [ 1112.700884] ? lock_acquire.part.0+0xbc/0x260 [ 1112.706570] ? ksys_write+0x116/0x250 [ 1112.711483] vfs_write+0x657/0x9b0 [ 1112.716099] ksys_write+0x116/0x250 [ 1112.720811] ? __ia32_sys_read+0xc0/0xc0 [ 1112.726012] ? kernfs_fop_llseek+0x160/0x1f0 [ 1112.731603] ? rcu_is_watching+0x15/0xd0 [ 1112.736807] do_syscall_64+0xf3/0x650 [ 1112.741716] ? trace_hardirqs_off+0xd/0x30 [ 1112.747111] ? exc_page_fault+0xda/0xf0 [ 1112.752215] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1112.758684] RIP: 0033:0x7f2e1ea4322e [ 1112.763493] RSP: 002b:00007ffe1830ca30 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1112.772790] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f2e1ea4322e [ 1112.781590] RDX: 0000000000000001 RSI: 000055b7572ebcd0 RDI: 0000000000000007 [ 1112.790398] RBP: 00007ffe1830ca40 R08: 0000000000000000 R09: 0000000000000000 [ 1112.799207] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f2e1e8bb390 [ 1112.808016] R13: 000055b7572ebcd0 R14: 0000000000000007 R15: 0000000000000003 | [ 1113.207931] #1: ff110001f25ad680 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 1113.218893] #2: ff11000122402b50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1113.230050] #3: ff110001223e9d68 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1113.240137] | [ 1113.242603] =============================================\x0a [ 1235.421626] INFO: task python3:5857 blocked for more than 368 seconds. [ 1235.429770] Not tainted 7.1.0-rc3-onyy-g4194a54e9394 #1 [ 1235.437020] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1235.446610] task:python3 state:D stack:25672 pid:5857 tgid:5857 ppid:5856 task_flags:0x400100 flags:0x00080002 [ 1235.459818] Call Trace: [ 1235.463358] [ 1235.466512] __schedule+0xcc3/0x19c0 [ 1235.471331] ? io_schedule_timeout+0x140/0x140 [ 1235.477117] ? find_held_lock+0x2b/0x80 [ 1235.482224] ? __lock_release.isra.0+0x6b/0x1a0 [ 1235.488112] schedule+0xd4/0x260 [ 1235.492532] schedule_preempt_disabled+0x18/0x30 [ 1235.498503] __mutex_lock+0x6a3/0x2000 [ 1235.503502] ? threaded_store+0x132/0x220 [ 1235.508804] ? ww_mutex_lock+0x160/0x160 [ 1235.514003] ? selinux_inode_free_security+0x330/0x330 [ 1235.520571] ? cap_capable+0x1d6/0x3e0 [ 1235.525594] ? threaded_store+0x132/0x220 [ 1235.530886] threaded_store+0x132/0x220 [ 1235.535987] ? xps_rxqs_store+0x2e0/0x2e0 [ 1235.541285] ? sysfs_file_kobj+0xbd/0x1e0 [ 1235.546586] ? sysfs_kf_write+0x65/0x160 [ 1235.551787] ? sysfs_file_ops+0x140/0x140 [ 1235.557084] kernfs_fop_write_iter+0x335/0x510 [ 1235.562869] ? kernfs_ops+0xf0/0xf0 [ 1235.567579] new_sync_write+0x333/0x750 [ 1235.572683] ? __lock_acquire+0x508/0xc10 [ 1235.577978] ? new_sync_read+0x740/0x740 [ 1235.583179] ? lock_acquire.part.0+0xbc/0x260 [ 1235.588864] ? ksys_write+0x116/0x250 [ 1235.593773] vfs_write+0x657/0x9b0 [ 1235.598389] ksys_write+0x116/0x250 [ 1235.603101] ? __ia32_sys_read+0xc0/0xc0 [ 1235.608302] ? kernfs_fop_llseek+0x160/0x1f0 [ 1235.613892] ? rcu_is_watching+0x15/0xd0 [ 1235.619097] do_syscall_64+0xf3/0x650 [ 1235.624005] ? trace_hardirqs_off+0xd/0x30 [ 1235.629399] ? exc_page_fault+0xda/0xf0 [ 1235.634502] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1235.640959] RIP: 0033:0x7f2e1ea4322e [ 1235.645760] RSP: 002b:00007ffe1830ca30 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1235.655057] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f2e1ea4322e [ 1235.663857] RDX: 0000000000000001 RSI: 000055b7572ebcd0 RDI: 0000000000000007 [ 1235.672666] RBP: 00007ffe1830ca40 R08: 0000000000000000 R09: 0000000000000000 [ 1235.681477] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f2e1e8bb390 [ 1235.690286] R13: 000055b7572ebcd0 R14: 0000000000000007 R15: 0000000000000003 | [ 1236.101779] #1: ff110001f25ad680 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 1236.112738] #2: ff11000122402b50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1236.123898] #3: ff110001223e9d68 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1236.133986] | [ 1236.136452] =============================================\x0a [ 1358.301842] INFO: task python3:5857 blocked for more than 491 seconds. [ 1358.309990] Not tainted 7.1.0-rc3-onyy-g4194a54e9394 #1 [ 1358.317241] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1358.326833] task:python3 state:D stack:25672 pid:5857 tgid:5857 ppid:5856 task_flags:0x400100 flags:0x00080002 [ 1358.340040] Call Trace: [ 1358.343581] [ 1358.346731] __schedule+0xcc3/0x19c0 [ 1358.351549] ? io_schedule_timeout+0x140/0x140 [ 1358.357336] ? find_held_lock+0x2b/0x80 [ 1358.362444] ? __lock_release.isra.0+0x6b/0x1a0 [ 1358.368332] schedule+0xd4/0x260 [ 1358.372753] schedule_preempt_disabled+0x18/0x30 [ 1358.378735] __mutex_lock+0x6a3/0x2000 [ 1358.383742] ? threaded_store+0x132/0x220 [ 1358.389044] ? ww_mutex_lock+0x160/0x160 [ 1358.394242] ? selinux_inode_free_security+0x330/0x330 [ 1358.400811] ? cap_capable+0x1d6/0x3e0 [ 1358.405818] ? threaded_store+0x132/0x220 [ 1358.411116] threaded_store+0x132/0x220 [ 1358.416221] ? xps_rxqs_store+0x2e0/0x2e0 [ 1358.421522] ? sysfs_file_kobj+0xbd/0x1e0 [ 1358.426825] ? sysfs_kf_write+0x65/0x160 [ 1358.432024] ? sysfs_file_ops+0x140/0x140 [ 1358.437321] kernfs_fop_write_iter+0x335/0x510 [ 1358.443106] ? kernfs_ops+0xf0/0xf0 [ 1358.447807] new_sync_write+0x333/0x750 [ 1358.452911] ? __lock_acquire+0x508/0xc10 [ 1358.458205] ? new_sync_read+0x740/0x740 [ 1358.463407] ? lock_acquire.part.0+0xbc/0x260 [ 1358.469093] ? ksys_write+0x116/0x250 [ 1358.474002] vfs_write+0x657/0x9b0 [ 1358.478619] ksys_write+0x116/0x250 [ 1358.483332] ? __ia32_sys_read+0xc0/0xc0 [ 1358.488524] ? kernfs_fop_llseek+0x160/0x1f0 [ 1358.494115] ? rcu_is_watching+0x15/0xd0 [ 1358.499319] do_syscall_64+0xf3/0x650 [ 1358.504227] ? trace_hardirqs_off+0xd/0x30 [ 1358.509621] ? exc_page_fault+0xda/0xf0 [ 1358.514724] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1358.521184] RIP: 0033:0x7f2e1ea4322e [ 1358.525994] RSP: 002b:00007ffe1830ca30 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1358.535291] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f2e1ea4322e [ 1358.544099] RDX: 0000000000000001 RSI: 000055b7572ebcd0 RDI: 0000000000000007 [ 1358.552907] RBP: 00007ffe1830ca40 R08: 0000000000000000 R09: 0000000000000000 [ 1358.561716] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f2e1e8bb390 [ 1358.570525] R13: 000055b7572ebcd0 R14: 0000000000000007 R15: 0000000000000003 Finger prints: __schedule:schedule:schedule_preempt_disabled:__mutex_lock:threaded_store