====================================== | [ 779.691831] nipa-hw-worker: drivers/net:napi_threaded.py: timed out | [ 779.694178] nipa-hw-worker: [4/40] Retrying drivers/net:napi_threaded.py | [ 779.847452] kselftest: Running tests in drivers/net [ 989.664781] INFO: task python3:5575 blocked for more than 122 seconds. [ 989.673002] Not tainted 7.0.0-rc6-yvgy-gf3ebf9fb7be9 #1 [ 989.680256] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 989.689846] task:python3 state:D stack:25336 pid:5575 tgid:5575 ppid:5574 task_flags:0x400100 flags:0x00080000 [ 989.703053] Call Trace: [ 989.706593] [ 989.709742] __schedule+0xae3/0x14d0 [ 989.714559] ? io_schedule_timeout+0x140/0x140 [ 989.720346] ? find_held_lock+0x2b/0x80 [ 989.725453] ? __lock_release.isra.0+0x6b/0x1a0 [ 989.731339] schedule+0xd4/0x260 [ 989.735759] schedule_preempt_disabled+0x18/0x30 [ 989.741737] __mutex_lock+0x6b2/0x1ee0 [ 989.746744] ? rcu_is_watching+0x15/0xd0 [ 989.751944] ? threaded_store+0x132/0x220 [ 989.757247] ? ww_mutex_lock+0x160/0x160 [ 989.762447] ? selinux_inode_free_security+0x330/0x330 [ 989.769022] ? cap_capable+0x1ed/0x3f0 [ 989.774026] ? threaded_store+0x132/0x220 [ 989.779321] threaded_store+0x132/0x220 [ 989.784423] ? xps_rxqs_store+0x2e0/0x2e0 [ 989.789720] ? sysfs_file_kobj+0xbd/0x1e0 [ 989.795020] ? sysfs_kf_write+0x65/0x160 [ 989.800221] ? sysfs_file_ops+0x140/0x140 [ 989.805518] kernfs_fop_write_iter+0x335/0x510 [ 989.811304] ? kernfs_ops+0xf0/0xf0 [ 989.816007] new_sync_write+0x333/0x750 [ 989.821113] ? __lock_acquire+0x508/0xc10 [ 989.826412] ? new_sync_read+0x740/0x740 [ 989.831619] ? lock_acquire.part.0+0xbc/0x260 [ 989.837307] ? ksys_write+0x116/0x250 [ 989.842219] vfs_write+0x657/0x9b0 [ 989.846833] ksys_write+0x116/0x250 [ 989.851543] ? __ia32_sys_read+0xc0/0xc0 [ 989.856744] ? kernfs_fop_llseek+0x160/0x1f0 [ 989.862332] ? rcu_is_watching+0x15/0xd0 [ 989.867531] do_syscall_64+0xf3/0x5e0 [ 989.872436] ? trace_hardirqs_off+0xd/0x30 [ 989.877830] ? exc_page_fault+0xda/0xf0 [ 989.882933] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 989.889402] RIP: 0033:0x7f25e9c3322e [ 989.894213] RSP: 002b:00007ffcbad75df0 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 989.903510] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f25e9c3322e [ 989.912310] RDX: 0000000000000001 RSI: 000055ae480a8f40 RDI: 0000000000000007 [ 989.921120] RBP: 00007ffcbad75e00 R08: 0000000000000000 R09: 0000000000000000 [ 989.929928] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f25e9aab390 [ 989.938740] R13: 000055ae480a8f40 R14: 0000000000000007 R15: 0000000000000003 | [ 990.309998] #1: ff110001a074f488 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 990.320962] #2: ff110001216890f0 (kn->active#127){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 990.332122] #3: ff110001216e1d00 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 990.342214] | [ 990.344681] =============================================\x0a [ 1112.546483] INFO: task python3:5575 blocked for more than 245 seconds. [ 1112.554628] Not tainted 7.0.0-rc6-yvgy-gf3ebf9fb7be9 #1 [ 1112.561902] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1112.571492] task:python3 state:D stack:25336 pid:5575 tgid:5575 ppid:5574 task_flags:0x400100 flags:0x00080000 [ 1112.584696] Call Trace: [ 1112.588233] [ 1112.591384] __schedule+0xae3/0x14d0 [ 1112.596201] ? io_schedule_timeout+0x140/0x140 [ 1112.601984] ? find_held_lock+0x2b/0x80 [ 1112.607088] ? __lock_release.isra.0+0x6b/0x1a0 [ 1112.612965] schedule+0xd4/0x260 [ 1112.617384] schedule_preempt_disabled+0x18/0x30 [ 1112.623364] __mutex_lock+0x6b2/0x1ee0 [ 1112.628370] ? rcu_is_watching+0x15/0xd0 [ 1112.633570] ? threaded_store+0x132/0x220 [ 1112.638869] ? ww_mutex_lock+0x160/0x160 [ 1112.644059] ? selinux_inode_free_security+0x330/0x330 [ 1112.650629] ? cap_capable+0x1ed/0x3f0 [ 1112.655636] ? threaded_store+0x132/0x220 [ 1112.660935] threaded_store+0x132/0x220 [ 1112.666040] ? xps_rxqs_store+0x2e0/0x2e0 [ 1112.671337] ? sysfs_file_kobj+0xbd/0x1e0 [ 1112.676637] ? sysfs_kf_write+0x65/0x160 [ 1112.681826] ? sysfs_file_ops+0x140/0x140 [ 1112.687121] kernfs_fop_write_iter+0x335/0x510 [ 1112.692907] ? kernfs_ops+0xf0/0xf0 [ 1112.697619] new_sync_write+0x333/0x750 [ 1112.702713] ? __lock_acquire+0x508/0xc10 [ 1112.708008] ? new_sync_read+0x740/0x740 [ 1112.713208] ? lock_acquire.part.0+0xbc/0x260 [ 1112.718893] ? ksys_write+0x116/0x250 [ 1112.723802] vfs_write+0x657/0x9b0 [ 1112.728417] ksys_write+0x116/0x250 [ 1112.733121] ? __ia32_sys_read+0xc0/0xc0 [ 1112.738318] ? kernfs_fop_llseek+0x160/0x1f0 [ 1112.743908] ? rcu_is_watching+0x15/0xd0 [ 1112.749106] do_syscall_64+0xf3/0x5e0 [ 1112.754009] ? trace_hardirqs_off+0xd/0x30 [ 1112.759403] ? exc_page_fault+0xda/0xf0 [ 1112.764504] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1112.770972] RIP: 0033:0x7f25e9c3322e [ 1112.775782] RSP: 002b:00007ffcbad75df0 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1112.785078] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f25e9c3322e [ 1112.793884] RDX: 0000000000000001 RSI: 000055ae480a8f40 RDI: 0000000000000007 [ 1112.802692] RBP: 00007ffcbad75e00 R08: 0000000000000000 R09: 0000000000000000 [ 1112.811489] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f25e9aab390 [ 1112.820294] R13: 000055ae480a8f40 R14: 0000000000000007 R15: 0000000000000003 | [ 1113.191521] #1: ff110001a074f488 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 1113.202482] #2: ff110001216890f0 (kn->active#127){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1113.213637] #3: ff110001216e1d00 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1113.223726] | [ 1113.226190] =============================================\x0a [ 1235.427380] INFO: task python3:5575 blocked for more than 368 seconds. [ 1235.435577] Not tainted 7.0.0-rc6-yvgy-gf3ebf9fb7be9 #1 [ 1235.442826] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1235.452405] task:python3 state:D stack:25336 pid:5575 tgid:5575 ppid:5574 task_flags:0x400100 flags:0x00080002 [ 1235.465609] Call Trace: [ 1235.469147] [ 1235.472300] __schedule+0xae3/0x14d0 [ 1235.477116] ? io_schedule_timeout+0x140/0x140 [ 1235.482900] ? find_held_lock+0x2b/0x80 [ 1235.488006] ? __lock_release.isra.0+0x6b/0x1a0 [ 1235.493888] schedule+0xd4/0x260 [ 1235.498306] schedule_preempt_disabled+0x18/0x30 [ 1235.504284] __mutex_lock+0x6b2/0x1ee0 [ 1235.509289] ? rcu_is_watching+0x15/0xd0 [ 1235.514487] ? threaded_store+0x132/0x220 [ 1235.519786] ? ww_mutex_lock+0x160/0x160 [ 1235.524976] ? selinux_inode_free_security+0x330/0x330 [ 1235.531537] ? cap_capable+0x1ed/0x3f0 [ 1235.536546] ? threaded_store+0x132/0x220 [ 1235.541839] threaded_store+0x132/0x220 [ 1235.546940] ? xps_rxqs_store+0x2e0/0x2e0 [ 1235.552238] ? sysfs_file_kobj+0xbd/0x1e0 [ 1235.557533] ? sysfs_kf_write+0x65/0x160 [ 1235.562733] ? sysfs_file_ops+0x140/0x140 [ 1235.568027] kernfs_fop_write_iter+0x335/0x510 [ 1235.573809] ? kernfs_ops+0xf0/0xf0 [ 1235.578519] new_sync_write+0x333/0x750 [ 1235.583618] ? __lock_acquire+0x508/0xc10 [ 1235.588911] ? new_sync_read+0x740/0x740 [ 1235.594111] ? lock_acquire.part.0+0xbc/0x260 [ 1235.599788] ? ksys_write+0x116/0x250 [ 1235.604695] vfs_write+0x657/0x9b0 [ 1235.609302] ksys_write+0x116/0x250 [ 1235.614005] ? __ia32_sys_read+0xc0/0xc0 [ 1235.619203] ? kernfs_fop_llseek+0x160/0x1f0 [ 1235.624790] ? rcu_is_watching+0x15/0xd0 [ 1235.629988] do_syscall_64+0xf3/0x5e0 [ 1235.634893] ? trace_hardirqs_off+0xd/0x30 [ 1235.640288] ? exc_page_fault+0xda/0xf0 [ 1235.645387] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1235.651854] RIP: 0033:0x7f25e9c3322e [ 1235.656664] RSP: 002b:00007ffcbad75df0 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1235.665960] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f25e9c3322e [ 1235.674768] RDX: 0000000000000001 RSI: 000055ae480a8f40 RDI: 0000000000000007 [ 1235.683569] RBP: 00007ffcbad75e00 R08: 0000000000000000 R09: 0000000000000000 [ 1235.692376] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f25e9aab390 [ 1235.701184] R13: 000055ae480a8f40 R14: 0000000000000007 R15: 0000000000000003 | [ 1236.088289] #1: ff110001a074f488 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 1236.099253] #2: ff110001216890f0 (kn->active#127){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1236.110413] #3: ff110001216e1d00 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1236.120504] | [ 1236.122972] =============================================\x0a [ 1358.308677] INFO: task python3:5575 blocked for more than 491 seconds. [ 1358.316837] Not tainted 7.0.0-rc6-yvgy-gf3ebf9fb7be9 #1 [ 1358.324091] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1358.333679] task:python3 state:D stack:25336 pid:5575 tgid:5575 ppid:5574 task_flags:0x400100 flags:0x00080002 [ 1358.346884] Call Trace: [ 1358.350423] [ 1358.353570] __schedule+0xae3/0x14d0 [ 1358.358383] ? io_schedule_timeout+0x140/0x140 [ 1358.364167] ? find_held_lock+0x2b/0x80 [ 1358.369264] ? __lock_release.isra.0+0x6b/0x1a0 [ 1358.375149] schedule+0xd4/0x260 [ 1358.379569] schedule_preempt_disabled+0x18/0x30 [ 1358.385548] __mutex_lock+0x6b2/0x1ee0 [ 1358.390554] ? rcu_is_watching+0x15/0xd0 [ 1358.395754] ? threaded_store+0x132/0x220 [ 1358.401054] ? ww_mutex_lock+0x160/0x160 [ 1358.406252] ? selinux_inode_free_security+0x330/0x330 [ 1358.412812] ? cap_capable+0x1ed/0x3f0 [ 1358.417818] ? threaded_store+0x132/0x220 [ 1358.423114] threaded_store+0x132/0x220 [ 1358.428214] ? xps_rxqs_store+0x2e0/0x2e0 [ 1358.433509] ? sysfs_file_kobj+0xbd/0x1e0 [ 1358.438804] ? sysfs_kf_write+0x65/0x160 [ 1358.444004] ? sysfs_file_ops+0x140/0x140 [ 1358.449288] kernfs_fop_write_iter+0x335/0x510 [ 1358.455070] ? kernfs_ops+0xf0/0xf0 [ 1358.459781] new_sync_write+0x333/0x750 [ 1358.464876] ? __lock_acquire+0x508/0xc10 [ 1358.470170] ? new_sync_read+0x740/0x740 [ 1358.475371] ? lock_acquire.part.0+0xbc/0x260 [ 1358.481059] ? ksys_write+0x116/0x250 [ 1358.485967] vfs_write+0x657/0x9b0 [ 1358.490570] ksys_write+0x116/0x250 [ 1358.495279] ? __ia32_sys_read+0xc0/0xc0 [ 1358.500476] ? kernfs_fop_llseek+0x160/0x1f0 [ 1358.506064] ? rcu_is_watching+0x15/0xd0 [ 1358.511263] do_syscall_64+0xf3/0x5e0 [ 1358.516158] ? trace_hardirqs_off+0xd/0x30 [ 1358.521553] ? exc_page_fault+0xda/0xf0 [ 1358.526655] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1358.533121] RIP: 0033:0x7f25e9c3322e [ 1358.537929] RSP: 002b:00007ffcbad75df0 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1358.547227] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f25e9c3322e [ 1358.556034] RDX: 0000000000000001 RSI: 000055ae480a8f40 RDI: 0000000000000007 [ 1358.564843] RBP: 00007ffcbad75e00 R08: 0000000000000000 R09: 0000000000000000 [ 1358.573650] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f25e9aab390 [ 1358.582455] R13: 000055ae480a8f40 R14: 0000000000000007 R15: 0000000000000003 Finger prints: __schedule:schedule:schedule_preempt_disabled:__mutex_lock:threaded_store