====================================== | [ 782.404825] nipa-hw-worker: drivers/net:napi_threaded.py: timed out | [ 782.407763] nipa-hw-worker: [5/43] Retrying drivers/net:napi_threaded.py | [ 782.558444] kselftest: Running tests in drivers/net [ 989.678714] INFO: task python3:5731 blocked for more than 122 seconds. [ 989.686936] Not tainted 7.1.0-rc1-yuzy-gce627b16a197 #1 [ 989.694805] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 989.704396] task:python3 state:D stack:25672 pid:5731 tgid:5731 ppid:5730 task_flags:0x400100 flags:0x00080000 [ 989.717599] Call Trace: [ 989.721139] [ 989.724287] __schedule+0xcba/0x1940 [ 989.729103] ? io_schedule_timeout+0x140/0x140 [ 989.734886] ? find_held_lock+0x2b/0x80 [ 989.739989] ? __lock_release.isra.0+0x6b/0x1a0 [ 989.745875] schedule+0xd4/0x260 [ 989.750294] schedule_preempt_disabled+0x18/0x30 [ 989.756272] __mutex_lock+0x6a3/0x2000 [ 989.761274] ? threaded_store+0x132/0x220 [ 989.766572] ? ww_mutex_lock+0x160/0x160 [ 989.771772] ? selinux_inode_free_security+0x330/0x330 [ 989.778341] ? cap_capable+0x1d6/0x3e0 [ 989.783351] ? threaded_store+0x132/0x220 [ 989.788649] threaded_store+0x132/0x220 [ 989.793752] ? xps_rxqs_store+0x2e0/0x2e0 [ 989.799047] ? sysfs_file_kobj+0xbd/0x1e0 [ 989.804344] ? sysfs_kf_write+0x65/0x160 [ 989.809549] ? sysfs_file_ops+0x140/0x140 [ 989.814842] kernfs_fop_write_iter+0x335/0x510 [ 989.820626] ? kernfs_ops+0xf0/0xf0 [ 989.825338] new_sync_write+0x333/0x750 [ 989.830442] ? __lock_acquire+0x508/0xc10 [ 989.835738] ? new_sync_read+0x740/0x740 [ 989.840935] ? lock_acquire.part.0+0xbc/0x260 [ 989.846623] ? ksys_write+0x116/0x250 [ 989.851534] vfs_write+0x657/0x9b0 [ 989.856148] ksys_write+0x116/0x250 [ 989.860858] ? __ia32_sys_read+0xc0/0xc0 [ 989.866056] ? kernfs_fop_llseek+0x160/0x1f0 [ 989.871645] ? rcu_is_watching+0x15/0xd0 [ 989.876839] do_syscall_64+0xf3/0x650 [ 989.881743] ? trace_hardirqs_off+0xd/0x30 [ 989.887137] ? exc_page_fault+0xda/0xf0 [ 989.892237] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 989.898704] RIP: 0033:0x7f2d25cd622e [ 989.903512] RSP: 002b:00007ffee6734670 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 989.912810] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f2d25cd622e [ 989.921618] RDX: 0000000000000001 RSI: 0000559712f57a10 RDI: 0000000000000007 [ 989.930416] RBP: 00007ffee6734680 R08: 0000000000000000 R09: 0000000000000000 [ 989.939223] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f2d25b4e390 [ 989.948029] R13: 0000559712f57a10 R14: 0000000000000007 R15: 0000000000000003 | [ 990.319243] #1: ff110001c6ba4a80 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 990.330203] #2: ff110001219acb50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 990.341358] #3: ff110001219f9d60 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 990.351447] | [ 990.353913] =============================================\x0a [ 1112.559914] INFO: task python3:5731 blocked for more than 245 seconds. [ 1112.568061] Not tainted 7.1.0-rc1-yuzy-gce627b16a197 #1 [ 1112.575311] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1112.584899] task:python3 state:D stack:25672 pid:5731 tgid:5731 ppid:5730 task_flags:0x400100 flags:0x00080000 [ 1112.598103] Call Trace: [ 1112.601642] [ 1112.604796] __schedule+0xcba/0x1940 [ 1112.609614] ? io_schedule_timeout+0x140/0x140 [ 1112.615401] ? find_held_lock+0x2b/0x80 [ 1112.620509] ? __lock_release.isra.0+0x6b/0x1a0 [ 1112.626393] schedule+0xd4/0x260 [ 1112.630814] schedule_preempt_disabled+0x18/0x30 [ 1112.636792] __mutex_lock+0x6a3/0x2000 [ 1112.641796] ? threaded_store+0x132/0x220 [ 1112.647098] ? ww_mutex_lock+0x160/0x160 [ 1112.652298] ? selinux_inode_free_security+0x330/0x330 [ 1112.658867] ? cap_capable+0x1d6/0x3e0 [ 1112.663878] ? threaded_store+0x132/0x220 [ 1112.669175] threaded_store+0x132/0x220 [ 1112.674276] ? xps_rxqs_store+0x2e0/0x2e0 [ 1112.679576] ? sysfs_file_kobj+0xbd/0x1e0 [ 1112.684876] ? sysfs_kf_write+0x65/0x160 [ 1112.690077] ? sysfs_file_ops+0x140/0x140 [ 1112.695372] kernfs_fop_write_iter+0x335/0x510 [ 1112.701158] ? kernfs_ops+0xf0/0xf0 [ 1112.705870] new_sync_write+0x333/0x750 [ 1112.710974] ? __lock_acquire+0x508/0xc10 [ 1112.716270] ? new_sync_read+0x740/0x740 [ 1112.721472] ? lock_acquire.part.0+0xbc/0x260 [ 1112.727158] ? ksys_write+0x116/0x250 [ 1112.732067] vfs_write+0x657/0x9b0 [ 1112.736684] ksys_write+0x116/0x250 [ 1112.741398] ? __ia32_sys_read+0xc0/0xc0 [ 1112.746598] ? kernfs_fop_llseek+0x160/0x1f0 [ 1112.752179] ? rcu_is_watching+0x15/0xd0 [ 1112.757382] do_syscall_64+0xf3/0x650 [ 1112.762289] ? trace_hardirqs_off+0xd/0x30 [ 1112.767684] ? exc_page_fault+0xda/0xf0 [ 1112.772786] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1112.779256] RIP: 0033:0x7f2d25cd622e [ 1112.784065] RSP: 002b:00007ffee6734670 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1112.793364] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f2d25cd622e [ 1112.802175] RDX: 0000000000000001 RSI: 0000559712f57a10 RDI: 0000000000000007 [ 1112.810985] RBP: 00007ffee6734680 R08: 0000000000000000 R09: 0000000000000000 [ 1112.819795] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f2d25b4e390 [ 1112.828604] R13: 0000559712f57a10 R14: 0000000000000007 R15: 0000000000000003 | [ 1113.199944] #1: ff110001c6ba4a80 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 1113.210906] #2: ff110001219acb50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1113.222064] #3: ff110001219f9d60 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1113.232155] | [ 1113.234622] =============================================\x0a [ 1235.441128] INFO: task python3:5731 blocked for more than 368 seconds. [ 1235.449277] Not tainted 7.1.0-rc1-yuzy-gce627b16a197 #1 [ 1235.456528] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1235.466115] task:python3 state:D stack:25672 pid:5731 tgid:5731 ppid:5730 task_flags:0x400100 flags:0x00080002 [ 1235.479319] Call Trace: [ 1235.482859] [ 1235.486011] __schedule+0xcba/0x1940 [ 1235.490824] ? io_schedule_timeout+0x140/0x140 [ 1235.496611] ? find_held_lock+0x2b/0x80 [ 1235.501716] ? __lock_release.isra.0+0x6b/0x1a0 [ 1235.507600] schedule+0xd4/0x260 [ 1235.512016] schedule_preempt_disabled+0x18/0x30 [ 1235.517993] __mutex_lock+0x6a3/0x2000 [ 1235.522995] ? threaded_store+0x132/0x220 [ 1235.528291] ? ww_mutex_lock+0x160/0x160 [ 1235.533489] ? selinux_inode_free_security+0x330/0x330 [ 1235.540058] ? cap_capable+0x1d6/0x3e0 [ 1235.545065] ? threaded_store+0x132/0x220 [ 1235.550361] threaded_store+0x132/0x220 [ 1235.555460] ? xps_rxqs_store+0x2e0/0x2e0 [ 1235.560757] ? sysfs_file_kobj+0xbd/0x1e0 [ 1235.566055] ? sysfs_kf_write+0x65/0x160 [ 1235.571256] ? sysfs_file_ops+0x140/0x140 [ 1235.576553] kernfs_fop_write_iter+0x335/0x510 [ 1235.582339] ? kernfs_ops+0xf0/0xf0 [ 1235.587041] new_sync_write+0x333/0x750 [ 1235.592136] ? __lock_acquire+0x508/0xc10 [ 1235.597432] ? new_sync_read+0x740/0x740 [ 1235.602633] ? lock_acquire.part.0+0xbc/0x260 [ 1235.608320] ? ksys_write+0x116/0x250 [ 1235.613230] vfs_write+0x657/0x9b0 [ 1235.617842] ksys_write+0x116/0x250 [ 1235.622554] ? __ia32_sys_read+0xc0/0xc0 [ 1235.627750] ? kernfs_fop_llseek+0x160/0x1f0 [ 1235.633336] ? rcu_is_watching+0x15/0xd0 [ 1235.638539] do_syscall_64+0xf3/0x650 [ 1235.643442] ? trace_hardirqs_off+0xd/0x30 [ 1235.648837] ? exc_page_fault+0xda/0xf0 [ 1235.653939] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1235.660403] RIP: 0033:0x7f2d25cd622e [ 1235.665212] RSP: 002b:00007ffee6734670 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1235.674508] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f2d25cd622e [ 1235.683317] RDX: 0000000000000001 RSI: 0000559712f57a10 RDI: 0000000000000007 [ 1235.692123] RBP: 00007ffee6734680 R08: 0000000000000000 R09: 0000000000000000 [ 1235.700929] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f2d25b4e390 [ 1235.709737] R13: 0000559712f57a10 R14: 0000000000000007 R15: 0000000000000003 | [ 1236.121177] #1: ff110001c6ba4a80 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 1236.132135] #2: ff110001219acb50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1236.143291] #3: ff110001219f9d60 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1236.153379] | [ 1236.155844] =============================================\x0a [ 1358.322383] INFO: task python3:5731 blocked for more than 491 seconds. [ 1358.330535] Not tainted 7.1.0-rc1-yuzy-gce627b16a197 #1 [ 1358.337784] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1358.347372] task:python3 state:D stack:25672 pid:5731 tgid:5731 ppid:5730 task_flags:0x400100 flags:0x00080002 [ 1358.360577] Call Trace: [ 1358.364120] [ 1358.367264] __schedule+0xcba/0x1940 [ 1358.372082] ? io_schedule_timeout+0x140/0x140 [ 1358.377869] ? find_held_lock+0x2b/0x80 [ 1358.382976] ? __lock_release.isra.0+0x6b/0x1a0 [ 1358.388861] schedule+0xd4/0x260 [ 1358.393282] schedule_preempt_disabled+0x18/0x30 [ 1358.399264] __mutex_lock+0x6a3/0x2000 [ 1358.404272] ? threaded_store+0x132/0x220 [ 1358.409571] ? ww_mutex_lock+0x160/0x160 [ 1358.414771] ? selinux_inode_free_security+0x330/0x330 [ 1358.421339] ? cap_capable+0x1d6/0x3e0 [ 1358.426353] ? threaded_store+0x132/0x220 [ 1358.431652] threaded_store+0x132/0x220 [ 1358.436755] ? xps_rxqs_store+0x2e0/0x2e0 [ 1358.442051] ? sysfs_file_kobj+0xbd/0x1e0 [ 1358.447342] ? sysfs_kf_write+0x65/0x160 [ 1358.452544] ? sysfs_file_ops+0x140/0x140 [ 1358.457841] kernfs_fop_write_iter+0x335/0x510 [ 1358.463628] ? kernfs_ops+0xf0/0xf0 [ 1358.468339] new_sync_write+0x333/0x750 [ 1358.473443] ? __lock_acquire+0x508/0xc10 [ 1358.478731] ? new_sync_read+0x740/0x740 [ 1358.483932] ? lock_acquire.part.0+0xbc/0x260 [ 1358.489619] ? ksys_write+0x116/0x250 [ 1358.494529] vfs_write+0x657/0x9b0 [ 1358.499145] ksys_write+0x116/0x250 [ 1358.503858] ? __ia32_sys_read+0xc0/0xc0 [ 1358.509059] ? kernfs_fop_llseek+0x160/0x1f0 [ 1358.514649] ? rcu_is_watching+0x15/0xd0 [ 1358.519850] do_syscall_64+0xf3/0x650 [ 1358.524759] ? trace_hardirqs_off+0xd/0x30 [ 1358.530154] ? exc_page_fault+0xda/0xf0 [ 1358.535256] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1358.541726] RIP: 0033:0x7f2d25cd622e [ 1358.546539] RSP: 002b:00007ffee6734670 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1358.555837] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f2d25cd622e [ 1358.564647] RDX: 0000000000000001 RSI: 0000559712f57a10 RDI: 0000000000000007 [ 1358.573457] RBP: 00007ffee6734680 R08: 0000000000000000 R09: 0000000000000000 [ 1358.582267] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f2d25b4e390 [ 1358.591076] R13: 0000559712f57a10 R14: 0000000000000007 R15: 0000000000000003 Finger prints: __schedule:schedule:schedule_preempt_disabled:__mutex_lock:threaded_store