====================================== | [ 764.557855] nipa-hw-worker: drivers/net:napi_threaded.py: timed out | [ 764.560537] nipa-hw-worker: [5/47] Retrying drivers/net:napi_threaded.py | [ 764.710907] kselftest: Running tests in drivers/net [ 989.669329] INFO: task python3:5434 blocked for more than 122 seconds. [ 989.677562] Not tainted 7.1.0-rc7-kkyy-g733dab1ea569 #1 [ 989.685443] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 989.695032] task:python3 state:D stack:25672 pid:5434 tgid:5434 ppid:5433 task_flags:0x400100 flags:0x00080000 [ 989.708236] Call Trace: [ 989.711766] [ 989.714918] __schedule+0xcc3/0x19c0 [ 989.719733] ? io_schedule_timeout+0x140/0x140 [ 989.725516] ? find_held_lock+0x2b/0x80 [ 989.730621] ? __lock_release.isra.0+0x6b/0x1a0 [ 989.736505] schedule+0xd8/0x260 [ 989.740922] schedule_preempt_disabled+0x18/0x30 [ 989.746898] __mutex_lock+0x6af/0x2010 [ 989.751901] ? threaded_store+0x132/0x220 [ 989.757198] ? ww_mutex_lock+0x160/0x160 [ 989.762393] ? selinux_inode_free_security+0x330/0x330 [ 989.768960] ? cap_capable+0x1d6/0x3e0 [ 989.773968] ? threaded_store+0x132/0x220 [ 989.779262] threaded_store+0x132/0x220 [ 989.784366] ? xps_rxqs_store+0x2e0/0x2e0 [ 989.789660] ? sysfs_file_kobj+0xbd/0x1e0 [ 989.794960] ? sysfs_kf_write+0x65/0x160 [ 989.800150] ? sysfs_file_ops+0x140/0x140 [ 989.805443] kernfs_fop_write_iter+0x335/0x510 [ 989.811225] ? kernfs_ops+0xf0/0xf0 [ 989.815933] new_sync_write+0x333/0x750 [ 989.821034] ? __lock_acquire+0x508/0xc10 [ 989.826332] ? new_sync_read+0x740/0x740 [ 989.831522] ? lock_acquire.part.0+0xbc/0x260 [ 989.837207] ? ksys_write+0x116/0x250 [ 989.842116] vfs_write+0x657/0x9b0 [ 989.846728] ksys_write+0x116/0x250 [ 989.851438] ? __ia32_sys_read+0xc0/0xc0 [ 989.856637] ? kernfs_fop_llseek+0x160/0x1f0 [ 989.862225] ? rcu_is_watching+0x15/0xd0 [ 989.867424] do_syscall_64+0xf3/0x650 [ 989.872331] ? trace_hardirqs_off+0xd/0x30 [ 989.877726] ? exc_page_fault+0xda/0xf0 [ 989.882825] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 989.889292] RIP: 0033:0x7f083fa0464e [ 989.894100] RSP: 002b:00007ffd130c65b0 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 989.903397] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f083fa0464e [ 989.912203] RDX: 0000000000000001 RSI: 0000560ac7fe8bf0 RDI: 0000000000000007 [ 989.921011] RBP: 00007ffd130c65c0 R08: 0000000000000000 R09: 0000000000000000 [ 989.929820] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f083f87d390 [ 989.938626] R13: 0000560ac7fe8bf0 R14: 0000000000000007 R15: 00007f083f811120 | [ 990.341345] #1: ff1100017a618280 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 990.352309] #2: ff11000123b60b50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 990.363464] #3: ff11000123b41e00 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 990.373553] | [ 990.376020] =============================================\x0a [ 1112.550345] INFO: task python3:5434 blocked for more than 245 seconds. [ 1112.558495] Not tainted 7.1.0-rc7-kkyy-g733dab1ea569 #1 [ 1112.565746] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1112.575335] task:python3 state:D stack:25672 pid:5434 tgid:5434 ppid:5433 task_flags:0x400100 flags:0x00080000 [ 1112.588540] Call Trace: [ 1112.592082] [ 1112.595232] __schedule+0xcc3/0x19c0 [ 1112.600051] ? io_schedule_timeout+0x140/0x140 [ 1112.605839] ? find_held_lock+0x2b/0x80 [ 1112.610943] ? __lock_release.isra.0+0x6b/0x1a0 [ 1112.616829] schedule+0xd8/0x260 [ 1112.621248] schedule_preempt_disabled+0x18/0x30 [ 1112.627221] __mutex_lock+0x6af/0x2010 [ 1112.632225] ? threaded_store+0x132/0x220 [ 1112.637526] ? ww_mutex_lock+0x160/0x160 [ 1112.642725] ? selinux_inode_free_security+0x330/0x330 [ 1112.649296] ? cap_capable+0x1d6/0x3e0 [ 1112.654307] ? threaded_store+0x132/0x220 [ 1112.659606] threaded_store+0x132/0x220 [ 1112.664706] ? xps_rxqs_store+0x2e0/0x2e0 [ 1112.670002] ? sysfs_file_kobj+0xbd/0x1e0 [ 1112.675300] ? sysfs_kf_write+0x65/0x160 [ 1112.680501] ? sysfs_file_ops+0x140/0x140 [ 1112.685797] kernfs_fop_write_iter+0x335/0x510 [ 1112.691583] ? kernfs_ops+0xf0/0xf0 [ 1112.696296] new_sync_write+0x333/0x750 [ 1112.701400] ? __lock_acquire+0x508/0xc10 [ 1112.706687] ? new_sync_read+0x740/0x740 [ 1112.711890] ? lock_acquire.part.0+0xbc/0x260 [ 1112.717576] ? ksys_write+0x116/0x250 [ 1112.722488] vfs_write+0x657/0x9b0 [ 1112.727104] ksys_write+0x116/0x250 [ 1112.731816] ? __ia32_sys_read+0xc0/0xc0 [ 1112.737016] ? kernfs_fop_llseek+0x160/0x1f0 [ 1112.742608] ? rcu_is_watching+0x15/0xd0 [ 1112.747813] do_syscall_64+0xf3/0x650 [ 1112.752720] ? trace_hardirqs_off+0xd/0x30 [ 1112.758117] ? exc_page_fault+0xda/0xf0 [ 1112.763211] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1112.769677] RIP: 0033:0x7f083fa0464e [ 1112.774478] RSP: 002b:00007ffd130c65b0 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1112.783766] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f083fa0464e [ 1112.792576] RDX: 0000000000000001 RSI: 0000560ac7fe8bf0 RDI: 0000000000000007 [ 1112.801387] RBP: 00007ffd130c65c0 R08: 0000000000000000 R09: 0000000000000000 [ 1112.810197] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f083f87d390 [ 1112.819007] R13: 0000560ac7fe8bf0 R14: 0000000000000007 R15: 00007f083f811120 | [ 1113.195860] #1: ff1100017a618280 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 1113.206820] #2: ff11000123b60b50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1113.217980] #3: ff11000123b41e00 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1113.228067] | [ 1113.230533] =============================================\x0a [ 1235.431362] INFO: task python3:5434 blocked for more than 368 seconds. [ 1235.439511] Not tainted 7.1.0-rc7-kkyy-g733dab1ea569 #1 [ 1235.446761] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1235.456351] task:python3 state:D stack:25672 pid:5434 tgid:5434 ppid:5433 task_flags:0x400100 flags:0x00080002 [ 1235.469557] Call Trace: [ 1235.473105] [ 1235.476356] __schedule+0xcc3/0x19c0 [ 1235.481166] ? io_schedule_timeout+0x140/0x140 [ 1235.486954] ? find_held_lock+0x2b/0x80 [ 1235.492058] ? __lock_release.isra.0+0x6b/0x1a0 [ 1235.497946] schedule+0xd8/0x260 [ 1235.502365] schedule_preempt_disabled+0x18/0x30 [ 1235.508344] __mutex_lock+0x6af/0x2010 [ 1235.513351] ? threaded_store+0x132/0x220 [ 1235.518651] ? ww_mutex_lock+0x160/0x160 [ 1235.523850] ? selinux_inode_free_security+0x330/0x330 [ 1235.530422] ? cap_capable+0x1d6/0x3e0 [ 1235.535432] ? threaded_store+0x132/0x220 [ 1235.540728] threaded_store+0x132/0x220 [ 1235.545832] ? xps_rxqs_store+0x2e0/0x2e0 [ 1235.551128] ? sysfs_file_kobj+0xbd/0x1e0 [ 1235.556424] ? sysfs_kf_write+0x65/0x160 [ 1235.561625] ? sysfs_file_ops+0x140/0x140 [ 1235.566920] kernfs_fop_write_iter+0x335/0x510 [ 1235.572705] ? kernfs_ops+0xf0/0xf0 [ 1235.577418] new_sync_write+0x333/0x750 [ 1235.582522] ? __lock_acquire+0x508/0xc10 [ 1235.587819] ? new_sync_read+0x740/0x740 [ 1235.593011] ? lock_acquire.part.0+0xbc/0x260 [ 1235.598698] ? ksys_write+0x116/0x250 [ 1235.603599] vfs_write+0x657/0x9b0 [ 1235.608217] ksys_write+0x116/0x250 [ 1235.612928] ? __ia32_sys_read+0xc0/0xc0 [ 1235.618125] ? kernfs_fop_llseek+0x160/0x1f0 [ 1235.623715] ? rcu_is_watching+0x15/0xd0 [ 1235.628920] do_syscall_64+0xf3/0x650 [ 1235.633828] ? trace_hardirqs_off+0xd/0x30 [ 1235.639226] ? exc_page_fault+0xda/0xf0 [ 1235.644327] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1235.650796] RIP: 0033:0x7f083fa0464e [ 1235.655605] RSP: 002b:00007ffd130c65b0 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1235.664902] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f083fa0464e [ 1235.673701] RDX: 0000000000000001 RSI: 0000560ac7fe8bf0 RDI: 0000000000000007 [ 1235.682502] RBP: 00007ffd130c65c0 R08: 0000000000000000 R09: 0000000000000000 [ 1235.691313] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f083f87d390 [ 1235.700120] R13: 0000560ac7fe8bf0 R14: 0000000000000007 R15: 00007f083f811120 | [ 1236.076999] #1: ff1100017a618280 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 1236.087962] #2: ff11000123b60b50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1236.099120] #3: ff11000123b41e00 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1236.109219] | [ 1236.111676] =============================================\x0a [ 1358.312399] INFO: task python3:5434 blocked for more than 491 seconds. [ 1358.320548] Not tainted 7.1.0-rc7-kkyy-g733dab1ea569 #1 [ 1358.327798] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1358.337387] task:python3 state:D stack:25672 pid:5434 tgid:5434 ppid:5433 task_flags:0x400100 flags:0x00080002 [ 1358.350590] Call Trace: [ 1358.354121] [ 1358.357270] __schedule+0xcc3/0x19c0 [ 1358.362084] ? io_schedule_timeout+0x140/0x140 [ 1358.367870] ? find_held_lock+0x2b/0x80 [ 1358.372972] ? __lock_release.isra.0+0x6b/0x1a0 [ 1358.378857] schedule+0xd8/0x260 [ 1358.383276] schedule_preempt_disabled+0x18/0x30 [ 1358.389253] __mutex_lock+0x6af/0x2010 [ 1358.394256] ? threaded_store+0x132/0x220 [ 1358.399552] ? ww_mutex_lock+0x160/0x160 [ 1358.404751] ? selinux_inode_free_security+0x330/0x330 [ 1358.411320] ? cap_capable+0x1d6/0x3e0 [ 1358.416324] ? threaded_store+0x132/0x220 [ 1358.421623] threaded_store+0x132/0x220 [ 1358.426724] ? xps_rxqs_store+0x2e0/0x2e0 [ 1358.432020] ? sysfs_file_kobj+0xbd/0x1e0 [ 1358.437320] ? sysfs_kf_write+0x65/0x160 [ 1358.442520] ? sysfs_file_ops+0x140/0x140 [ 1358.447817] kernfs_fop_write_iter+0x335/0x510 [ 1358.453590] ? kernfs_ops+0xf0/0xf0 [ 1358.458302] new_sync_write+0x333/0x750 [ 1358.463407] ? __lock_acquire+0x508/0xc10 [ 1358.468701] ? new_sync_read+0x740/0x740 [ 1358.473900] ? lock_acquire.part.0+0xbc/0x260 [ 1358.479585] ? ksys_write+0x116/0x250 [ 1358.484492] vfs_write+0x657/0x9b0 [ 1358.489105] ksys_write+0x116/0x250 [ 1358.493815] ? __ia32_sys_read+0xc0/0xc0 [ 1358.499012] ? kernfs_fop_llseek+0x160/0x1f0 [ 1358.504600] ? rcu_is_watching+0x15/0xd0 [ 1358.509799] do_syscall_64+0xf3/0x650 [ 1358.514703] ? trace_hardirqs_off+0xd/0x30 [ 1358.520098] ? exc_page_fault+0xda/0xf0 [ 1358.525199] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1358.531665] RIP: 0033:0x7f083fa0464e [ 1358.536476] RSP: 002b:00007ffd130c65b0 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1358.545771] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f083fa0464e [ 1358.554576] RDX: 0000000000000001 RSI: 0000560ac7fe8bf0 RDI: 0000000000000007 [ 1358.563381] RBP: 00007ffd130c65c0 R08: 0000000000000000 R09: 0000000000000000 [ 1358.572188] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f083f87d390 [ 1358.580997] R13: 0000560ac7fe8bf0 R14: 0000000000000007 R15: 00007f083f811120 Finger prints: __schedule:schedule:schedule_preempt_disabled:__mutex_lock:threaded_store