====================================== | [ 788.930927] nipa-hw-worker: drivers/net:napi_threaded.py: timed out | [ 788.934568] nipa-hw-worker: [5/47] Retrying drivers/net:napi_threaded.py | [ 789.063215] kselftest: Running tests in drivers/net [ 989.669773] INFO: task python3:5826 blocked for more than 122 seconds. [ 989.678012] Not tainted 7.1.0-rc3-cysb-g97954c0a8d6f #1 [ 989.685941] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 989.695527] task:python3 state:D stack:25672 pid:5826 tgid:5826 ppid:5825 task_flags:0x400100 flags:0x00080000 [ 989.708734] Call Trace: [ 989.712274] [ 989.715421] __schedule+0xcc3/0x19c0 [ 989.720239] ? io_schedule_timeout+0x140/0x140 [ 989.726024] ? find_held_lock+0x2b/0x80 [ 989.731125] ? __lock_release.isra.0+0x6b/0x1a0 [ 989.737010] schedule+0xd4/0x260 [ 989.741426] schedule_preempt_disabled+0x18/0x30 [ 989.747406] __mutex_lock+0x6a3/0x2000 [ 989.752399] ? threaded_store+0x132/0x220 [ 989.757697] ? ww_mutex_lock+0x160/0x160 [ 989.762897] ? selinux_inode_free_security+0x330/0x330 [ 989.769465] ? cap_capable+0x1d6/0x3e0 [ 989.774473] ? threaded_store+0x132/0x220 [ 989.779772] threaded_store+0x132/0x220 [ 989.784874] ? xps_rxqs_store+0x2e0/0x2e0 [ 989.790169] ? sysfs_file_kobj+0xbd/0x1e0 [ 989.795465] ? sysfs_kf_write+0x65/0x160 [ 989.800666] ? sysfs_file_ops+0x140/0x140 [ 989.805958] kernfs_fop_write_iter+0x335/0x510 [ 989.811741] ? kernfs_ops+0xf0/0xf0 [ 989.816453] new_sync_write+0x333/0x750 [ 989.821557] ? __lock_acquire+0x508/0xc10 [ 989.826845] ? new_sync_read+0x740/0x740 [ 989.832044] ? lock_acquire.part.0+0xbc/0x260 [ 989.837727] ? ksys_write+0x116/0x250 [ 989.842637] vfs_write+0x657/0x9b0 [ 989.847250] ksys_write+0x116/0x250 [ 989.851959] ? __ia32_sys_read+0xc0/0xc0 [ 989.857157] ? kernfs_fop_llseek+0x160/0x1f0 [ 989.862744] ? rcu_is_watching+0x15/0xd0 [ 989.867946] do_syscall_64+0xf3/0x650 [ 989.872851] ? irq_exit_rcu+0x1a/0x30 [ 989.877756] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 989.884222] RIP: 0033:0x7f2e0ad0122e [ 989.889032] RSP: 002b:00007ffd99e76170 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 989.898331] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f2e0ad0122e [ 989.907138] RDX: 0000000000000001 RSI: 000055a2d2c60cd0 RDI: 0000000000000007 [ 989.915943] RBP: 00007ffd99e76180 R08: 0000000000000000 R09: 0000000000000000 [ 989.924748] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f2e0ab79390 [ 989.933554] R13: 000055a2d2c60cd0 R14: 0000000000000007 R15: 0000000000000003 | [ 990.333493] #1: ff110001ee710e80 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 990.344452] #2: ff11000123a8eb50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 990.355608] #3: ff11000123b39d68 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 990.365695] | [ 990.368158] =============================================\x0a [ 1112.550839] INFO: task python3:5826 blocked for more than 245 seconds. [ 1112.558988] Not tainted 7.1.0-rc3-cysb-g97954c0a8d6f #1 [ 1112.566239] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1112.575828] task:python3 state:D stack:25672 pid:5826 tgid:5826 ppid:5825 task_flags:0x400100 flags:0x00080000 [ 1112.589035] Call Trace: [ 1112.592577] [ 1112.595726] __schedule+0xcc3/0x19c0 [ 1112.600542] ? io_schedule_timeout+0x140/0x140 [ 1112.606326] ? find_held_lock+0x2b/0x80 [ 1112.611431] ? __lock_release.isra.0+0x6b/0x1a0 [ 1112.617307] schedule+0xd4/0x260 [ 1112.621726] schedule_preempt_disabled+0x18/0x30 [ 1112.627705] __mutex_lock+0x6a3/0x2000 [ 1112.632709] ? threaded_store+0x132/0x220 [ 1112.638009] ? ww_mutex_lock+0x160/0x160 [ 1112.643210] ? selinux_inode_free_security+0x330/0x330 [ 1112.649780] ? cap_capable+0x1d6/0x3e0 [ 1112.654790] ? threaded_store+0x132/0x220 [ 1112.660086] threaded_store+0x132/0x220 [ 1112.665188] ? xps_rxqs_store+0x2e0/0x2e0 [ 1112.670487] ? sysfs_file_kobj+0xbd/0x1e0 [ 1112.675790] ? sysfs_kf_write+0x65/0x160 [ 1112.680994] ? sysfs_file_ops+0x140/0x140 [ 1112.686292] kernfs_fop_write_iter+0x335/0x510 [ 1112.692076] ? kernfs_ops+0xf0/0xf0 [ 1112.696789] new_sync_write+0x333/0x750 [ 1112.701890] ? __lock_acquire+0x508/0xc10 [ 1112.707188] ? new_sync_read+0x740/0x740 [ 1112.712383] ? lock_acquire.part.0+0xbc/0x260 [ 1112.718071] ? ksys_write+0x116/0x250 [ 1112.722982] vfs_write+0x657/0x9b0 [ 1112.727597] ksys_write+0x116/0x250 [ 1112.732311] ? __ia32_sys_read+0xc0/0xc0 [ 1112.737509] ? kernfs_fop_llseek+0x160/0x1f0 [ 1112.743098] ? rcu_is_watching+0x15/0xd0 [ 1112.748298] do_syscall_64+0xf3/0x650 [ 1112.753208] ? irq_exit_rcu+0x1a/0x30 [ 1112.758117] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1112.764587] RIP: 0033:0x7f2e0ad0122e [ 1112.769397] RSP: 002b:00007ffd99e76170 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1112.778696] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f2e0ad0122e [ 1112.787503] RDX: 0000000000000001 RSI: 000055a2d2c60cd0 RDI: 0000000000000007 [ 1112.796310] RBP: 00007ffd99e76180 R08: 0000000000000000 R09: 0000000000000000 [ 1112.805119] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f2e0ab79390 [ 1112.813930] R13: 000055a2d2c60cd0 R14: 0000000000000007 R15: 0000000000000003 | [ 1113.196155] #2: ff11000123a8eb50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1113.207314] #3: ff11000123b39d68 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1113.217404] | [ 1113.219872] =============================================\x0a | [ 1116.646857] kworker/u129:13 (308) used greatest stack depth: 21936 bytes left [ 1235.431912] INFO: task python3:5826 blocked for more than 368 seconds. [ 1235.440059] Not tainted 7.1.0-rc3-cysb-g97954c0a8d6f #1 [ 1235.447300] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1235.456888] task:python3 state:D stack:25672 pid:5826 tgid:5826 ppid:5825 task_flags:0x400100 flags:0x00080002 [ 1235.470094] Call Trace: [ 1235.473634] [ 1235.476782] __schedule+0xcc3/0x19c0 [ 1235.481599] ? io_schedule_timeout+0x140/0x140 [ 1235.487380] ? find_held_lock+0x2b/0x80 [ 1235.492483] ? __lock_release.isra.0+0x6b/0x1a0 [ 1235.498370] schedule+0xd4/0x260 [ 1235.502790] schedule_preempt_disabled+0x18/0x30 [ 1235.508763] __mutex_lock+0x6a3/0x2000 [ 1235.513766] ? threaded_store+0x132/0x220 [ 1235.519066] ? ww_mutex_lock+0x160/0x160 [ 1235.524256] ? selinux_inode_free_security+0x330/0x330 [ 1235.530816] ? cap_capable+0x1d6/0x3e0 [ 1235.535821] ? threaded_store+0x132/0x220 [ 1235.541114] threaded_store+0x132/0x220 [ 1235.546217] ? xps_rxqs_store+0x2e0/0x2e0 [ 1235.551515] ? sysfs_file_kobj+0xbd/0x1e0 [ 1235.556814] ? sysfs_kf_write+0x65/0x160 [ 1235.562013] ? sysfs_file_ops+0x140/0x140 [ 1235.567307] kernfs_fop_write_iter+0x335/0x510 [ 1235.573090] ? kernfs_ops+0xf0/0xf0 [ 1235.577801] new_sync_write+0x333/0x750 [ 1235.582904] ? __lock_acquire+0x508/0xc10 [ 1235.588199] ? new_sync_read+0x740/0x740 [ 1235.593401] ? lock_acquire.part.0+0xbc/0x260 [ 1235.599088] ? ksys_write+0x116/0x250 [ 1235.603999] vfs_write+0x657/0x9b0 [ 1235.608612] ksys_write+0x116/0x250 [ 1235.613321] ? __ia32_sys_read+0xc0/0xc0 [ 1235.618519] ? kernfs_fop_llseek+0x160/0x1f0 [ 1235.624106] ? rcu_is_watching+0x15/0xd0 [ 1235.629307] do_syscall_64+0xf3/0x650 [ 1235.634213] ? irq_exit_rcu+0x1a/0x30 [ 1235.639120] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1235.645586] RIP: 0033:0x7f2e0ad0122e [ 1235.650393] RSP: 002b:00007ffd99e76170 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1235.659691] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f2e0ad0122e [ 1235.668499] RDX: 0000000000000001 RSI: 000055a2d2c60cd0 RDI: 0000000000000007 [ 1235.677295] RBP: 00007ffd99e76180 R08: 0000000000000000 R09: 0000000000000000 [ 1235.686103] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f2e0ab79390 [ 1235.694902] R13: 000055a2d2c60cd0 R14: 0000000000000007 R15: 0000000000000003 | [ 1236.066079] #1: ff110001ee710e80 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 1236.077030] #2: ff11000123a8eb50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1236.088187] #3: ff11000123b39d68 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1236.098281] | [ 1236.100748] =============================================\x0a [ 1358.313006] INFO: task python3:5826 blocked for more than 491 seconds. [ 1358.321153] Not tainted 7.1.0-rc3-cysb-g97954c0a8d6f #1 [ 1358.328406] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1358.337996] task:python3 state:D stack:25672 pid:5826 tgid:5826 ppid:5825 task_flags:0x400100 flags:0x00080002 [ 1358.351201] Call Trace: [ 1358.354743] [ 1358.357894] __schedule+0xcc3/0x19c0 [ 1358.362710] ? io_schedule_timeout+0x140/0x140 [ 1358.368494] ? find_held_lock+0x2b/0x80 [ 1358.373598] ? __lock_release.isra.0+0x6b/0x1a0 [ 1358.379485] schedule+0xd4/0x260 [ 1358.383903] schedule_preempt_disabled+0x18/0x30 [ 1358.389881] __mutex_lock+0x6a3/0x2000 [ 1358.394885] ? threaded_store+0x132/0x220 [ 1358.400185] ? ww_mutex_lock+0x160/0x160 [ 1358.405377] ? selinux_inode_free_security+0x330/0x330 [ 1358.411949] ? cap_capable+0x1d6/0x3e0 [ 1358.416959] ? threaded_store+0x132/0x220 [ 1358.422255] threaded_store+0x132/0x220 [ 1358.427358] ? xps_rxqs_store+0x2e0/0x2e0 [ 1358.432659] ? sysfs_file_kobj+0xbd/0x1e0 [ 1358.437960] ? sysfs_kf_write+0x65/0x160 [ 1358.443159] ? sysfs_file_ops+0x140/0x140 [ 1358.448457] kernfs_fop_write_iter+0x335/0x510 [ 1358.454241] ? kernfs_ops+0xf0/0xf0 [ 1358.458954] new_sync_write+0x333/0x750 [ 1358.464056] ? __lock_acquire+0x508/0xc10 [ 1358.469354] ? new_sync_read+0x740/0x740 [ 1358.474557] ? lock_acquire.part.0+0xbc/0x260 [ 1358.480244] ? ksys_write+0x116/0x250 [ 1358.485154] vfs_write+0x657/0x9b0 [ 1358.489769] ksys_write+0x116/0x250 [ 1358.494474] ? __ia32_sys_read+0xc0/0xc0 [ 1358.499674] ? kernfs_fop_llseek+0x160/0x1f0 [ 1358.505252] ? rcu_is_watching+0x15/0xd0 [ 1358.510455] do_syscall_64+0xf3/0x650 [ 1358.515361] ? irq_exit_rcu+0x1a/0x30 [ 1358.520271] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1358.526738] RIP: 0033:0x7f2e0ad0122e [ 1358.531540] RSP: 002b:00007ffd99e76170 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1358.540839] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f2e0ad0122e [ 1358.549648] RDX: 0000000000000001 RSI: 000055a2d2c60cd0 RDI: 0000000000000007 [ 1358.558445] RBP: 00007ffd99e76180 R08: 0000000000000000 R09: 0000000000000000 [ 1358.567255] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f2e0ab79390 [ 1358.576063] R13: 000055a2d2c60cd0 R14: 0000000000000007 R15: 0000000000000003 Finger prints: __schedule:schedule:schedule_preempt_disabled:__mutex_lock:threaded_store