====================================== | [ 785.228103] nipa-hw-worker: drivers/net:napi_threaded.py: timed out | [ 785.230578] nipa-hw-worker: [5/44] Retrying drivers/net:napi_threaded.py | [ 785.359179] kselftest: Running tests in drivers/net [ 989.712504] INFO: task python3:5837 blocked for more than 122 seconds. [ 989.720708] Not tainted 7.1.0-rc1-fvmh-g646fd76e2503 #1 [ 989.728353] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 989.737946] task:python3 state:D stack:25672 pid:5837 tgid:5837 ppid:5836 task_flags:0x400100 flags:0x00080000 [ 989.751155] Call Trace: [ 989.754695] [ 989.757848] __schedule+0xcba/0x1940 [ 989.762668] ? io_schedule_timeout+0x140/0x140 [ 989.768453] ? find_held_lock+0x2b/0x80 [ 989.773558] ? __lock_release.isra.0+0x6b/0x1a0 [ 989.779445] schedule+0xd4/0x260 [ 989.783862] schedule_preempt_disabled+0x18/0x30 [ 989.789841] __mutex_lock+0x6a3/0x2000 [ 989.794845] ? threaded_store+0x132/0x220 [ 989.800137] ? ww_mutex_lock+0x160/0x160 [ 989.805336] ? selinux_inode_free_security+0x330/0x330 [ 989.811911] ? cap_capable+0x1d6/0x3e0 [ 989.816919] ? threaded_store+0x132/0x220 [ 989.822215] threaded_store+0x132/0x220 [ 989.827319] ? xps_rxqs_store+0x2e0/0x2e0 [ 989.832621] ? sysfs_file_kobj+0xbd/0x1e0 [ 989.837922] ? sysfs_kf_write+0x65/0x160 [ 989.843123] ? sysfs_file_ops+0x140/0x140 [ 989.848418] kernfs_fop_write_iter+0x335/0x510 [ 989.854204] ? kernfs_ops+0xf0/0xf0 [ 989.858915] new_sync_write+0x333/0x750 [ 989.864013] ? __lock_acquire+0x508/0xc10 [ 989.869310] ? new_sync_read+0x740/0x740 [ 989.874512] ? lock_acquire.part.0+0xbc/0x260 [ 989.880200] ? ksys_write+0x116/0x250 [ 989.885113] vfs_write+0x657/0x9b0 [ 989.889729] ksys_write+0x116/0x250 [ 989.894443] ? __ia32_sys_read+0xc0/0xc0 [ 989.899644] ? kernfs_fop_llseek+0x160/0x1f0 [ 989.905234] ? rcu_is_watching+0x15/0xd0 [ 989.910435] do_syscall_64+0xf3/0x650 [ 989.915349] ? trace_hardirqs_off+0xd/0x30 [ 989.920745] ? exc_page_fault+0xda/0xf0 [ 989.925848] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 989.932307] RIP: 0033:0x7f88a7eb522e [ 989.937119] RSP: 002b:00007ffd56461b10 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 989.946419] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f88a7eb522e [ 989.955229] RDX: 0000000000000001 RSI: 0000558316542410 RDI: 0000000000000007 [ 989.964037] RBP: 00007ffd56461b20 R08: 0000000000000000 R09: 0000000000000000 [ 989.972845] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f88a7d2d390 [ 989.981654] R13: 0000558316542410 R14: 0000000000000007 R15: 0000000000000003 | [ 990.352958] #1: ff110001ea290280 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 990.363911] #2: ff11000124650b50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 990.375068] #3: ff11000124631d60 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 990.385161] | [ 990.387627] =============================================\x0a [ 1112.594125] INFO: task python3:5837 blocked for more than 245 seconds. [ 1112.602274] Not tainted 7.1.0-rc1-fvmh-g646fd76e2503 #1 [ 1112.609524] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1112.619113] task:python3 state:D stack:25672 pid:5837 tgid:5837 ppid:5836 task_flags:0x400100 flags:0x00080000 [ 1112.632312] Call Trace: [ 1112.635851] [ 1112.639001] __schedule+0xcba/0x1940 [ 1112.643817] ? io_schedule_timeout+0x140/0x140 [ 1112.649601] ? find_held_lock+0x2b/0x80 [ 1112.654705] ? __lock_release.isra.0+0x6b/0x1a0 [ 1112.660583] schedule+0xd4/0x260 [ 1112.665002] schedule_preempt_disabled+0x18/0x30 [ 1112.670982] __mutex_lock+0x6a3/0x2000 [ 1112.675979] ? threaded_store+0x132/0x220 [ 1112.681275] ? ww_mutex_lock+0x160/0x160 [ 1112.686473] ? selinux_inode_free_security+0x330/0x330 [ 1112.693032] ? cap_capable+0x1d6/0x3e0 [ 1112.698040] ? threaded_store+0x132/0x220 [ 1112.703335] threaded_store+0x132/0x220 [ 1112.708435] ? xps_rxqs_store+0x2e0/0x2e0 [ 1112.713732] ? sysfs_file_kobj+0xbd/0x1e0 [ 1112.719032] ? sysfs_kf_write+0x65/0x160 [ 1112.724231] ? sysfs_file_ops+0x140/0x140 [ 1112.729528] kernfs_fop_write_iter+0x335/0x510 [ 1112.735310] ? kernfs_ops+0xf0/0xf0 [ 1112.740021] new_sync_write+0x333/0x750 [ 1112.745124] ? __lock_acquire+0x508/0xc10 [ 1112.750420] ? new_sync_read+0x740/0x740 [ 1112.755622] ? lock_acquire.part.0+0xbc/0x260 [ 1112.761305] ? ksys_write+0x116/0x250 [ 1112.766214] vfs_write+0x657/0x9b0 [ 1112.770831] ksys_write+0x116/0x250 [ 1112.775544] ? __ia32_sys_read+0xc0/0xc0 [ 1112.780735] ? kernfs_fop_llseek+0x160/0x1f0 [ 1112.786322] ? rcu_is_watching+0x15/0xd0 [ 1112.791524] do_syscall_64+0xf3/0x650 [ 1112.796430] ? trace_hardirqs_off+0xd/0x30 [ 1112.801826] ? exc_page_fault+0xda/0xf0 [ 1112.806928] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1112.813384] RIP: 0033:0x7f88a7eb522e [ 1112.818195] RSP: 002b:00007ffd56461b10 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1112.827490] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f88a7eb522e [ 1112.836298] RDX: 0000000000000001 RSI: 0000558316542410 RDI: 0000000000000007 [ 1112.845106] RBP: 00007ffd56461b20 R08: 0000000000000000 R09: 0000000000000000 [ 1112.853915] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f88a7d2d390 [ 1112.862723] R13: 0000558316542410 R14: 0000000000000007 R15: 0000000000000003 | [ 1113.262384] #1: ff110001ea290280 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 1113.273343] #2: ff11000124650b50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1113.284498] #3: ff11000124631d60 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1113.294589] | [ 1113.297052] =============================================\x0a [ 1235.475722] INFO: task python3:5837 blocked for more than 368 seconds. [ 1235.483869] Not tainted 7.1.0-rc1-fvmh-g646fd76e2503 #1 [ 1235.491110] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1235.500699] task:python3 state:D stack:25672 pid:5837 tgid:5837 ppid:5836 task_flags:0x400100 flags:0x00080002 [ 1235.513903] Call Trace: [ 1235.517443] [ 1235.520591] __schedule+0xcba/0x1940 [ 1235.525406] ? io_schedule_timeout+0x140/0x140 [ 1235.531179] ? find_held_lock+0x2b/0x80 [ 1235.536286] ? __lock_release.isra.0+0x6b/0x1a0 [ 1235.542170] schedule+0xd4/0x260 [ 1235.546586] schedule_preempt_disabled+0x18/0x30 [ 1235.552571] __mutex_lock+0x6a3/0x2000 [ 1235.557574] ? threaded_store+0x132/0x220 [ 1235.562873] ? ww_mutex_lock+0x160/0x160 [ 1235.568070] ? selinux_inode_free_security+0x330/0x330 [ 1235.574641] ? cap_capable+0x1d6/0x3e0 [ 1235.579645] ? threaded_store+0x132/0x220 [ 1235.584942] threaded_store+0x132/0x220 [ 1235.590044] ? xps_rxqs_store+0x2e0/0x2e0 [ 1235.595343] ? sysfs_file_kobj+0xbd/0x1e0 [ 1235.600643] ? sysfs_kf_write+0x65/0x160 [ 1235.605840] ? sysfs_file_ops+0x140/0x140 [ 1235.611136] kernfs_fop_write_iter+0x335/0x510 [ 1235.616920] ? kernfs_ops+0xf0/0xf0 [ 1235.621630] new_sync_write+0x333/0x750 [ 1235.626734] ? __lock_acquire+0x508/0xc10 [ 1235.632028] ? new_sync_read+0x740/0x740 [ 1235.637229] ? lock_acquire.part.0+0xbc/0x260 [ 1235.642914] ? ksys_write+0x116/0x250 [ 1235.647822] vfs_write+0x657/0x9b0 [ 1235.652436] ksys_write+0x116/0x250 [ 1235.657145] ? __ia32_sys_read+0xc0/0xc0 [ 1235.662344] ? kernfs_fop_llseek+0x160/0x1f0 [ 1235.667935] ? rcu_is_watching+0x15/0xd0 [ 1235.673133] do_syscall_64+0xf3/0x650 [ 1235.678041] ? trace_hardirqs_off+0xd/0x30 [ 1235.683424] ? exc_page_fault+0xda/0xf0 [ 1235.688526] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1235.694991] RIP: 0033:0x7f88a7eb522e [ 1235.699798] RSP: 002b:00007ffd56461b10 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1235.709095] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f88a7eb522e [ 1235.717892] RDX: 0000000000000001 RSI: 0000558316542410 RDI: 0000000000000007 [ 1235.726701] RBP: 00007ffd56461b20 R08: 0000000000000000 R09: 0000000000000000 [ 1235.735510] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f88a7d2d390 [ 1235.744317] R13: 0000558316542410 R14: 0000000000000007 R15: 0000000000000003 | [ 1236.115498] #1: ff110001ea290280 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 1236.126457] #2: ff11000124650b50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1236.137614] #3: ff11000124631d60 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1236.147704] | [ 1236.150171] =============================================\x0a [ 1358.357320] INFO: task python3:5837 blocked for more than 491 seconds. [ 1358.365467] Not tainted 7.1.0-rc1-fvmh-g646fd76e2503 #1 [ 1358.372716] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1358.382308] task:python3 state:D stack:25672 pid:5837 tgid:5837 ppid:5836 task_flags:0x400100 flags:0x00080002 [ 1358.395515] Call Trace: [ 1358.399055] [ 1358.402205] __schedule+0xcba/0x1940 [ 1358.407023] ? io_schedule_timeout+0x140/0x140 [ 1358.412811] ? find_held_lock+0x2b/0x80 [ 1358.417918] ? __lock_release.isra.0+0x6b/0x1a0 [ 1358.423803] schedule+0xd4/0x260 [ 1358.428221] schedule_preempt_disabled+0x18/0x30 [ 1358.434202] __mutex_lock+0x6a3/0x2000 [ 1358.439207] ? threaded_store+0x132/0x220 [ 1358.444507] ? ww_mutex_lock+0x160/0x160 [ 1358.449699] ? selinux_inode_free_security+0x330/0x330 [ 1358.456270] ? cap_capable+0x1d6/0x3e0 [ 1358.461278] ? threaded_store+0x132/0x220 [ 1358.466576] threaded_store+0x132/0x220 [ 1358.471679] ? xps_rxqs_store+0x2e0/0x2e0 [ 1358.476971] ? sysfs_file_kobj+0xbd/0x1e0 [ 1358.482272] ? sysfs_kf_write+0x65/0x160 [ 1358.487471] ? sysfs_file_ops+0x140/0x140 [ 1358.492768] kernfs_fop_write_iter+0x335/0x510 [ 1358.498552] ? kernfs_ops+0xf0/0xf0 [ 1358.503265] new_sync_write+0x333/0x750 [ 1358.508370] ? __lock_acquire+0x508/0xc10 [ 1358.513666] ? new_sync_read+0x740/0x740 [ 1358.518868] ? lock_acquire.part.0+0xbc/0x260 [ 1358.524555] ? ksys_write+0x116/0x250 [ 1358.529456] vfs_write+0x657/0x9b0 [ 1358.534070] ksys_write+0x116/0x250 [ 1358.538784] ? __ia32_sys_read+0xc0/0xc0 [ 1358.543982] ? kernfs_fop_llseek+0x160/0x1f0 [ 1358.549571] ? rcu_is_watching+0x15/0xd0 [ 1358.554764] do_syscall_64+0xf3/0x650 [ 1358.559670] ? trace_hardirqs_off+0xd/0x30 [ 1358.565067] ? exc_page_fault+0xda/0xf0 [ 1358.570173] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1358.576643] RIP: 0033:0x7f88a7eb522e [ 1358.581454] RSP: 002b:00007ffd56461b10 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1358.590742] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f88a7eb522e [ 1358.599550] RDX: 0000000000000001 RSI: 0000558316542410 RDI: 0000000000000007 [ 1358.608350] RBP: 00007ffd56461b20 R08: 0000000000000000 R09: 0000000000000000 [ 1358.617163] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f88a7d2d390 [ 1358.625975] R13: 0000558316542410 R14: 0000000000000007 R15: 0000000000000003 Finger prints: __schedule:schedule:schedule_preempt_disabled:__mutex_lock:threaded_store