====================================== | [ 763.167988] nipa-hw-worker: drivers/net:napi_threaded.py: timed out | [ 763.170661] nipa-hw-worker: [5/47] Retrying drivers/net:napi_threaded.py | [ 763.299218] kselftest: Running tests in drivers/net | [ 797.157001] kworker/u129:11 (306) used greatest stack depth: 23024 bytes left [ 989.671012] INFO: task python3:5342 blocked for more than 122 seconds. [ 989.679236] Not tainted 7.1.0-rc7-tvub-g38826fe51e02 #1 [ 989.687078] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 989.696667] task:python3 state:D stack:24960 pid:5342 tgid:5342 ppid:5341 task_flags:0x400100 flags:0x00080000 [ 989.709874] Call Trace: [ 989.713413] [ 989.716563] __schedule+0xcc3/0x19c0 [ 989.721380] ? io_schedule_timeout+0x140/0x140 [ 989.727165] ? find_held_lock+0x2b/0x80 [ 989.732266] ? __lock_release.isra.0+0x6b/0x1a0 [ 989.738151] schedule+0xd8/0x260 [ 989.742571] schedule_preempt_disabled+0x18/0x30 [ 989.748541] __mutex_lock+0x6af/0x2010 [ 989.753547] ? threaded_store+0x132/0x220 [ 989.758854] ? ww_mutex_lock+0x160/0x160 [ 989.764053] ? selinux_inode_free_security+0x330/0x330 [ 989.770625] ? cap_capable+0x1d6/0x3e0 [ 989.775634] ? threaded_store+0x132/0x220 [ 989.780933] threaded_store+0x132/0x220 [ 989.786037] ? xps_rxqs_store+0x2e0/0x2e0 [ 989.791338] ? sysfs_file_kobj+0xbd/0x1e0 [ 989.796637] ? sysfs_kf_write+0x65/0x160 [ 989.801831] ? sysfs_file_ops+0x140/0x140 [ 989.807129] kernfs_fop_write_iter+0x335/0x510 [ 989.812922] ? kernfs_ops+0xf0/0xf0 [ 989.817624] new_sync_write+0x333/0x750 [ 989.822727] ? __lock_acquire+0x508/0xc10 [ 989.828024] ? new_sync_read+0x740/0x740 [ 989.833225] ? lock_acquire.part.0+0xbc/0x260 [ 989.838911] ? ksys_write+0x116/0x250 [ 989.843823] vfs_write+0x657/0x9b0 [ 989.848438] ksys_write+0x116/0x250 [ 989.853148] ? __ia32_sys_read+0xc0/0xc0 [ 989.858346] ? kernfs_fop_llseek+0x160/0x1f0 [ 989.863936] ? rcu_is_watching+0x15/0xd0 [ 989.869138] do_syscall_64+0xf3/0x650 [ 989.874114] ? trace_hardirqs_off+0xd/0x30 [ 989.879512] ? exc_page_fault+0xda/0xf0 [ 989.884613] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 989.891095] RIP: 0033:0x7f4bc78de64e [ 989.895908] RSP: 002b:00007ffe97c7d910 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 989.905207] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f4bc78de64e [ 989.914016] RDX: 0000000000000001 RSI: 000056290c369bf0 RDI: 0000000000000007 [ 989.922822] RBP: 00007ffe97c7d920 R08: 0000000000000000 R09: 0000000000000000 [ 989.931634] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f4bc7757390 [ 989.940440] R13: 000056290c369bf0 R14: 0000000000000007 R15: 00007f4bc76ed120 | [ 990.351855] #2: ff11000112baab50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 990.363012] #3: ff11000112b99e00 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 990.373101] 2 locks held by systemd/6070: | [ 990.378406] | [ 990.380879] =============================================\x0a [ 1112.552286] INFO: task python3:5342 blocked for more than 245 seconds. [ 1112.560436] Not tainted 7.1.0-rc7-tvub-g38826fe51e02 #1 [ 1112.567687] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1112.577277] task:python3 state:D stack:24960 pid:5342 tgid:5342 ppid:5341 task_flags:0x400100 flags:0x00080000 [ 1112.590483] Call Trace: [ 1112.594021] [ 1112.597169] __schedule+0xcc3/0x19c0 [ 1112.601986] ? io_schedule_timeout+0x140/0x140 [ 1112.607769] ? find_held_lock+0x2b/0x80 [ 1112.612873] ? __lock_release.isra.0+0x6b/0x1a0 [ 1112.618748] schedule+0xd8/0x260 [ 1112.623164] schedule_preempt_disabled+0x18/0x30 [ 1112.629143] __mutex_lock+0x6af/0x2010 [ 1112.634146] ? threaded_store+0x132/0x220 [ 1112.639442] ? ww_mutex_lock+0x160/0x160 [ 1112.644639] ? selinux_inode_free_security+0x330/0x330 [ 1112.651207] ? cap_capable+0x1d6/0x3e0 [ 1112.656212] ? threaded_store+0x132/0x220 [ 1112.661506] threaded_store+0x132/0x220 [ 1112.666604] ? xps_rxqs_store+0x2e0/0x2e0 [ 1112.671898] ? sysfs_file_kobj+0xbd/0x1e0 [ 1112.677199] ? sysfs_kf_write+0x65/0x160 [ 1112.682398] ? sysfs_file_ops+0x140/0x140 [ 1112.687695] kernfs_fop_write_iter+0x335/0x510 [ 1112.693479] ? kernfs_ops+0xf0/0xf0 [ 1112.698190] new_sync_write+0x333/0x750 [ 1112.703286] ? __lock_acquire+0x508/0xc10 [ 1112.708574] ? new_sync_read+0x740/0x740 [ 1112.713772] ? lock_acquire.part.0+0xbc/0x260 [ 1112.719457] ? ksys_write+0x116/0x250 [ 1112.724367] vfs_write+0x657/0x9b0 [ 1112.728982] ksys_write+0x116/0x250 [ 1112.733693] ? __ia32_sys_read+0xc0/0xc0 [ 1112.738891] ? kernfs_fop_llseek+0x160/0x1f0 [ 1112.744477] ? rcu_is_watching+0x15/0xd0 [ 1112.749680] do_syscall_64+0xf3/0x650 [ 1112.754584] ? trace_hardirqs_off+0xd/0x30 [ 1112.759979] ? exc_page_fault+0xda/0xf0 [ 1112.765079] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1112.771547] RIP: 0033:0x7f4bc78de64e [ 1112.776357] RSP: 002b:00007ffe97c7d910 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1112.785646] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f4bc78de64e [ 1112.794455] RDX: 0000000000000001 RSI: 000056290c369bf0 RDI: 0000000000000007 [ 1112.803264] RBP: 00007ffe97c7d920 R08: 0000000000000000 R09: 0000000000000000 [ 1112.812072] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f4bc7757390 [ 1112.820872] R13: 000056290c369bf0 R14: 0000000000000007 R15: 00007f4bc76ed120 | [ 1113.220720] #1: ff110001a5a16e80 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 1113.231682] #2: ff11000112baab50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1113.242836] #3: ff11000112b99e00 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1113.252924] | [ 1113.255388] =============================================\x0a [ 1235.433568] INFO: task python3:5342 blocked for more than 368 seconds. [ 1235.441714] Not tainted 7.1.0-rc7-tvub-g38826fe51e02 #1 [ 1235.448966] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1235.458553] task:python3 state:D stack:24960 pid:5342 tgid:5342 ppid:5341 task_flags:0x400100 flags:0x00080002 [ 1235.471757] Call Trace: [ 1235.475295] [ 1235.478443] __schedule+0xcc3/0x19c0 [ 1235.483261] ? io_schedule_timeout+0x140/0x140 [ 1235.489045] ? find_held_lock+0x2b/0x80 [ 1235.494143] ? __lock_release.isra.0+0x6b/0x1a0 [ 1235.500027] schedule+0xd8/0x260 [ 1235.504443] schedule_preempt_disabled+0x18/0x30 [ 1235.510422] __mutex_lock+0x6af/0x2010 [ 1235.515425] ? threaded_store+0x132/0x220 [ 1235.520722] ? ww_mutex_lock+0x160/0x160 [ 1235.525920] ? selinux_inode_free_security+0x330/0x330 [ 1235.532480] ? cap_capable+0x1d6/0x3e0 [ 1235.537487] ? threaded_store+0x132/0x220 [ 1235.542782] threaded_store+0x132/0x220 [ 1235.547886] ? xps_rxqs_store+0x2e0/0x2e0 [ 1235.553182] ? sysfs_file_kobj+0xbd/0x1e0 [ 1235.558481] ? sysfs_kf_write+0x65/0x160 [ 1235.563681] ? sysfs_file_ops+0x140/0x140 [ 1235.568978] kernfs_fop_write_iter+0x335/0x510 [ 1235.574765] ? kernfs_ops+0xf0/0xf0 [ 1235.579474] new_sync_write+0x333/0x750 [ 1235.584578] ? __lock_acquire+0x508/0xc10 [ 1235.589866] ? new_sync_read+0x740/0x740 [ 1235.595066] ? lock_acquire.part.0+0xbc/0x260 [ 1235.600746] ? ksys_write+0x116/0x250 [ 1235.605660] vfs_write+0x657/0x9b0 [ 1235.610275] ksys_write+0x116/0x250 [ 1235.614989] ? __ia32_sys_read+0xc0/0xc0 [ 1235.620188] ? kernfs_fop_llseek+0x160/0x1f0 [ 1235.625769] ? rcu_is_watching+0x15/0xd0 [ 1235.630970] do_syscall_64+0xf3/0x650 [ 1235.635867] ? trace_hardirqs_off+0xd/0x30 [ 1235.641264] ? exc_page_fault+0xda/0xf0 [ 1235.646367] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1235.652836] RIP: 0033:0x7f4bc78de64e [ 1235.657648] RSP: 002b:00007ffe97c7d910 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1235.666947] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f4bc78de64e [ 1235.675757] RDX: 0000000000000001 RSI: 000056290c369bf0 RDI: 0000000000000007 [ 1235.684566] RBP: 00007ffe97c7d920 R08: 0000000000000000 R09: 0000000000000000 [ 1235.693374] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f4bc7757390 [ 1235.702183] R13: 000056290c369bf0 R14: 0000000000000007 R15: 00007f4bc76ed120 | [ 1236.073508] #1: ff110001a5a16e80 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 1236.084469] #2: ff11000112baab50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1236.095629] #3: ff11000112b99e00 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1236.105723] | [ 1236.108191] =============================================\x0a [ 1358.314846] INFO: task python3:5342 blocked for more than 491 seconds. [ 1358.322990] Not tainted 7.1.0-rc7-tvub-g38826fe51e02 #1 [ 1358.330239] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1358.339827] task:python3 state:D stack:24960 pid:5342 tgid:5342 ppid:5341 task_flags:0x400100 flags:0x00080002 [ 1358.353025] Call Trace: [ 1358.356562] [ 1358.359709] __schedule+0xcc3/0x19c0 [ 1358.364524] ? io_schedule_timeout+0x140/0x140 [ 1358.370308] ? find_held_lock+0x2b/0x80 [ 1358.375409] ? __lock_release.isra.0+0x6b/0x1a0 [ 1358.381296] schedule+0xd8/0x260 [ 1358.385711] schedule_preempt_disabled+0x18/0x30 [ 1358.391690] __mutex_lock+0x6af/0x2010 [ 1358.396704] ? threaded_store+0x132/0x220 [ 1358.402001] ? ww_mutex_lock+0x160/0x160 [ 1358.407201] ? selinux_inode_free_security+0x330/0x330 [ 1358.413771] ? cap_capable+0x1d6/0x3e0 [ 1358.418778] ? threaded_store+0x132/0x220 [ 1358.424063] threaded_store+0x132/0x220 [ 1358.429165] ? xps_rxqs_store+0x2e0/0x2e0 [ 1358.434454] ? sysfs_file_kobj+0xbd/0x1e0 [ 1358.439752] ? sysfs_kf_write+0x65/0x160 [ 1358.444952] ? sysfs_file_ops+0x140/0x140 [ 1358.450247] kernfs_fop_write_iter+0x335/0x510 [ 1358.456032] ? kernfs_ops+0xf0/0xf0 [ 1358.460740] new_sync_write+0x333/0x750 [ 1358.465844] ? __lock_acquire+0x508/0xc10 [ 1358.471137] ? new_sync_read+0x740/0x740 [ 1358.476338] ? lock_acquire.part.0+0xbc/0x260 [ 1358.482022] ? ksys_write+0x116/0x250 [ 1358.486930] vfs_write+0x657/0x9b0 [ 1358.491544] ksys_write+0x116/0x250 [ 1358.496257] ? __ia32_sys_read+0xc0/0xc0 [ 1358.501453] ? kernfs_fop_llseek+0x160/0x1f0 [ 1358.507042] ? rcu_is_watching+0x15/0xd0 [ 1358.512234] do_syscall_64+0xf3/0x650 [ 1358.517141] ? trace_hardirqs_off+0xd/0x30 [ 1358.522534] ? exc_page_fault+0xda/0xf0 [ 1358.527635] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1358.534101] RIP: 0033:0x7f4bc78de64e [ 1358.538909] RSP: 002b:00007ffe97c7d910 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1358.548206] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f4bc78de64e [ 1358.557014] RDX: 0000000000000001 RSI: 000056290c369bf0 RDI: 0000000000000007 [ 1358.565819] RBP: 00007ffe97c7d920 R08: 0000000000000000 R09: 0000000000000000 [ 1358.574623] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f4bc7757390 [ 1358.583432] R13: 000056290c369bf0 R14: 0000000000000007 R15: 00007f4bc76ed120 Finger prints: __schedule:schedule:schedule_preempt_disabled:__mutex_lock:threaded_store