====================================== | [ 787.330626] nipa-hw-worker: drivers/net:napi_threaded.py: timed out | [ 787.332988] nipa-hw-worker: [5/46] Retrying drivers/net:napi_threaded.py | [ 787.494879] kselftest: Running tests in drivers/net [ 989.686306] INFO: task python3:5815 blocked for more than 122 seconds. [ 989.694522] Not tainted 7.1.0-rc4-buzj-g554a61d6e749 #1 [ 989.701773] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 989.711364] task:python3 state:D stack:25520 pid:5815 tgid:5815 ppid:5814 task_flags:0x400100 flags:0x00080000 [ 989.725064] Call Trace: [ 989.728604] [ 989.731754] __schedule+0xcc3/0x19c0 [ 989.736570] ? io_schedule_timeout+0x140/0x140 [ 989.742355] ? find_held_lock+0x2b/0x80 [ 989.747457] ? __lock_release.isra.0+0x6b/0x1a0 [ 989.753345] schedule+0xd8/0x260 [ 989.757760] schedule_preempt_disabled+0x18/0x30 [ 989.763740] __mutex_lock+0x6af/0x2010 [ 989.768737] ? threaded_store+0x132/0x220 [ 989.774034] ? ww_mutex_lock+0x160/0x160 [ 989.779231] ? selinux_inode_free_security+0x330/0x330 [ 989.785792] ? cap_capable+0x1d6/0x3e0 [ 989.790799] ? threaded_store+0x132/0x220 [ 989.796096] threaded_store+0x132/0x220 [ 989.801197] ? xps_rxqs_store+0x2e0/0x2e0 [ 989.806494] ? sysfs_file_kobj+0xbd/0x1e0 [ 989.811792] ? sysfs_kf_write+0x65/0x160 [ 989.816992] ? sysfs_file_ops+0x140/0x140 [ 989.822288] kernfs_fop_write_iter+0x335/0x510 [ 989.828071] ? kernfs_ops+0xf0/0xf0 [ 989.832782] new_sync_write+0x333/0x750 [ 989.837874] ? __lock_acquire+0x508/0xc10 [ 989.843169] ? new_sync_read+0x740/0x740 [ 989.848366] ? lock_acquire.part.0+0xbc/0x260 [ 989.854049] ? ksys_write+0x116/0x250 [ 989.858958] vfs_write+0x657/0x9b0 [ 989.863574] ksys_write+0x116/0x250 [ 989.868283] ? __ia32_sys_read+0xc0/0xc0 [ 989.873480] ? kernfs_fop_llseek+0x160/0x1f0 [ 989.879065] ? rcu_is_watching+0x15/0xd0 [ 989.884267] do_syscall_64+0xf3/0x650 [ 989.889174] ? irq_exit_rcu+0x1a/0x30 [ 989.894071] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 989.900540] RIP: 0033:0x7fca1d07022e [ 989.905347] RSP: 002b:00007ffde3aa6a40 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 989.914645] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007fca1d07022e [ 989.923453] RDX: 0000000000000001 RSI: 0000557aab4546b0 RDI: 0000000000000007 [ 989.932250] RBP: 00007ffde3aa6a50 R08: 0000000000000000 R09: 0000000000000000 [ 989.941056] R10: 0000000000000000 R11: 0000000000000202 R12: 00007fca1cee8390 [ 989.949862] R13: 0000557aab4546b0 R14: 0000000000000007 R15: 0000000000000003 | [ 990.359213] #1: ff110001736b2c80 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 990.370172] #2: ff11000123e44b50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 990.381327] #3: ff11000123e89d68 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 990.391412] | [ 990.393876] =============================================\x0a [ 1112.567515] INFO: task python3:5815 blocked for more than 245 seconds. [ 1112.575670] Not tainted 7.1.0-rc4-buzj-g554a61d6e749 #1 [ 1112.582919] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1112.592511] task:python3 state:D stack:25520 pid:5815 tgid:5815 ppid:5814 task_flags:0x400100 flags:0x00080000 [ 1112.605719] Call Trace: [ 1112.609261] [ 1112.612414] __schedule+0xcc3/0x19c0 [ 1112.617232] ? io_schedule_timeout+0x140/0x140 [ 1112.623018] ? find_held_lock+0x2b/0x80 [ 1112.628125] ? __lock_release.isra.0+0x6b/0x1a0 [ 1112.634014] schedule+0xd8/0x260 [ 1112.638435] schedule_preempt_disabled+0x18/0x30 [ 1112.644415] __mutex_lock+0x6af/0x2010 [ 1112.649423] ? threaded_store+0x132/0x220 [ 1112.654724] ? ww_mutex_lock+0x160/0x160 [ 1112.659926] ? selinux_inode_free_security+0x330/0x330 [ 1112.666499] ? cap_capable+0x1d6/0x3e0 [ 1112.671506] ? threaded_store+0x132/0x220 [ 1112.676804] threaded_store+0x132/0x220 [ 1112.681985] ? xps_rxqs_store+0x2e0/0x2e0 [ 1112.687286] ? sysfs_file_kobj+0xbd/0x1e0 [ 1112.692585] ? sysfs_kf_write+0x65/0x160 [ 1112.697778] ? sysfs_file_ops+0x140/0x140 [ 1112.703074] kernfs_fop_write_iter+0x335/0x510 [ 1112.708861] ? kernfs_ops+0xf0/0xf0 [ 1112.713577] new_sync_write+0x333/0x750 [ 1112.718681] ? __lock_acquire+0x508/0xc10 [ 1112.723972] ? new_sync_read+0x740/0x740 [ 1112.729178] ? lock_acquire.part.0+0xbc/0x260 [ 1112.734869] ? ksys_write+0x116/0x250 [ 1112.739781] vfs_write+0x657/0x9b0 [ 1112.744399] ksys_write+0x116/0x250 [ 1112.749113] ? __ia32_sys_read+0xc0/0xc0 [ 1112.754303] ? kernfs_fop_llseek+0x160/0x1f0 [ 1112.759892] ? rcu_is_watching+0x15/0xd0 [ 1112.765087] do_syscall_64+0xf3/0x650 [ 1112.769994] ? irq_exit_rcu+0x1a/0x30 [ 1112.774896] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1112.781366] RIP: 0033:0x7fca1d07022e [ 1112.786179] RSP: 002b:00007ffde3aa6a40 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1112.795476] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007fca1d07022e [ 1112.804284] RDX: 0000000000000001 RSI: 0000557aab4546b0 RDI: 0000000000000007 [ 1112.813163] RBP: 00007ffde3aa6a50 R08: 0000000000000000 R09: 0000000000000000 [ 1112.821971] R10: 0000000000000000 R11: 0000000000000202 R12: 00007fca1cee8390 [ 1112.830783] R13: 0000557aab4546b0 R14: 0000000000000007 R15: 0000000000000003 | [ 1113.251966] #2: ff11000123e44b50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1113.263123] #3: ff11000123e89d68 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1113.273214] 2 locks held by systemd/6863: | [ 1113.278526] | [ 1113.280996] =============================================\x0a [ 1235.447705] INFO: task python3:5815 blocked for more than 368 seconds. [ 1235.455853] Not tainted 7.1.0-rc4-buzj-g554a61d6e749 #1 [ 1235.463103] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1235.472690] task:python3 state:D stack:25520 pid:5815 tgid:5815 ppid:5814 task_flags:0x400100 flags:0x00080002 [ 1235.485894] Call Trace: [ 1235.489434] [ 1235.492583] __schedule+0xcc3/0x19c0 [ 1235.497398] ? io_schedule_timeout+0x140/0x140 [ 1235.503175] ? find_held_lock+0x2b/0x80 [ 1235.508277] ? __lock_release.isra.0+0x6b/0x1a0 [ 1235.514161] schedule+0xd8/0x260 [ 1235.518577] schedule_preempt_disabled+0x18/0x30 [ 1235.524556] __mutex_lock+0x6af/0x2010 [ 1235.529559] ? threaded_store+0x132/0x220 [ 1235.534849] ? ww_mutex_lock+0x160/0x160 [ 1235.540049] ? selinux_inode_free_security+0x330/0x330 [ 1235.546619] ? cap_capable+0x1d6/0x3e0 [ 1235.551627] ? threaded_store+0x132/0x220 [ 1235.556922] threaded_store+0x132/0x220 [ 1235.562025] ? xps_rxqs_store+0x2e0/0x2e0 [ 1235.567310] ? sysfs_file_kobj+0xbd/0x1e0 [ 1235.572610] ? sysfs_kf_write+0x65/0x160 [ 1235.577809] ? sysfs_file_ops+0x140/0x140 [ 1235.583103] kernfs_fop_write_iter+0x335/0x510 [ 1235.588888] ? kernfs_ops+0xf0/0xf0 [ 1235.593600] new_sync_write+0x333/0x750 [ 1235.598701] ? __lock_acquire+0x508/0xc10 [ 1235.603998] ? new_sync_read+0x740/0x740 [ 1235.609197] ? lock_acquire.part.0+0xbc/0x260 [ 1235.614880] ? ksys_write+0x116/0x250 [ 1235.619789] vfs_write+0x657/0x9b0 [ 1235.624403] ksys_write+0x116/0x250 [ 1235.629112] ? __ia32_sys_read+0xc0/0xc0 [ 1235.634310] ? kernfs_fop_llseek+0x160/0x1f0 [ 1235.639897] ? rcu_is_watching+0x15/0xd0 [ 1235.645098] do_syscall_64+0xf3/0x650 [ 1235.649994] ? irq_exit_rcu+0x1a/0x30 [ 1235.654899] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1235.661366] RIP: 0033:0x7fca1d07022e [ 1235.666174] RSP: 002b:00007ffde3aa6a40 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1235.675472] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007fca1d07022e [ 1235.684278] RDX: 0000000000000001 RSI: 0000557aab4546b0 RDI: 0000000000000007 [ 1235.693085] RBP: 00007ffde3aa6a50 R08: 0000000000000000 R09: 0000000000000000 [ 1235.701890] R10: 0000000000000000 R11: 0000000000000202 R12: 00007fca1cee8390 [ 1235.710698] R13: 0000557aab4546b0 R14: 0000000000000007 R15: 0000000000000003 | [ 1236.081900] #1: ff110001736b2c80 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 1236.092860] #2: ff11000123e44b50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1236.104019] #3: ff11000123e89d68 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1236.114110] | [ 1236.116566] =============================================\x0a [ 1358.328930] INFO: task python3:5815 blocked for more than 491 seconds. [ 1358.337082] Not tainted 7.1.0-rc4-buzj-g554a61d6e749 #1 [ 1358.344334] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1358.353926] task:python3 state:D stack:25520 pid:5815 tgid:5815 ppid:5814 task_flags:0x400100 flags:0x00080002 [ 1358.367131] Call Trace: [ 1358.370670] [ 1358.373817] __schedule+0xcc3/0x19c0 [ 1358.378631] ? io_schedule_timeout+0x140/0x140 [ 1358.384417] ? find_held_lock+0x2b/0x80 [ 1358.389520] ? __lock_release.isra.0+0x6b/0x1a0 [ 1358.395404] schedule+0xd8/0x260 [ 1358.399820] schedule_preempt_disabled+0x18/0x30 [ 1358.405797] __mutex_lock+0x6af/0x2010 [ 1358.410799] ? threaded_store+0x132/0x220 [ 1358.416099] ? ww_mutex_lock+0x160/0x160 [ 1358.421359] ? selinux_inode_free_security+0x330/0x330 [ 1358.427930] ? cap_capable+0x1d6/0x3e0 [ 1358.432935] ? threaded_store+0x132/0x220 [ 1358.438231] threaded_store+0x132/0x220 [ 1358.443330] ? xps_rxqs_store+0x2e0/0x2e0 [ 1358.448627] ? sysfs_file_kobj+0xbd/0x1e0 [ 1358.453927] ? sysfs_kf_write+0x65/0x160 [ 1358.459126] ? sysfs_file_ops+0x140/0x140 [ 1358.464422] kernfs_fop_write_iter+0x335/0x510 [ 1358.470204] ? kernfs_ops+0xf0/0xf0 [ 1358.474914] new_sync_write+0x333/0x750 [ 1358.480010] ? __lock_acquire+0x508/0xc10 [ 1358.485303] ? new_sync_read+0x740/0x740 [ 1358.490504] ? lock_acquire.part.0+0xbc/0x260 [ 1358.496180] ? ksys_write+0x116/0x250 [ 1358.501088] vfs_write+0x657/0x9b0 [ 1358.505695] ksys_write+0x116/0x250 [ 1358.510407] ? __ia32_sys_read+0xc0/0xc0 [ 1358.515605] ? kernfs_fop_llseek+0x160/0x1f0 [ 1358.521195] ? rcu_is_watching+0x15/0xd0 [ 1358.526398] do_syscall_64+0xf3/0x650 [ 1358.531303] ? irq_exit_rcu+0x1a/0x30 [ 1358.536209] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1358.542678] RIP: 0033:0x7fca1d07022e [ 1358.547486] RSP: 002b:00007ffde3aa6a40 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1358.556784] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007fca1d07022e [ 1358.565583] RDX: 0000000000000001 RSI: 0000557aab4546b0 RDI: 0000000000000007 [ 1358.574391] RBP: 00007ffde3aa6a50 R08: 0000000000000000 R09: 0000000000000000 [ 1358.583199] R10: 0000000000000000 R11: 0000000000000202 R12: 00007fca1cee8390 [ 1358.592005] R13: 0000557aab4546b0 R14: 0000000000000007 R15: 0000000000000003 Finger prints: __schedule:schedule:schedule_preempt_disabled:__mutex_lock:threaded_store