====================================== | [ 790.998363] nipa-hw-worker: drivers/net:napi_threaded.py: timed out | [ 791.001367] nipa-hw-worker: [5/46] Retrying drivers/net:napi_threaded.py | [ 791.156928] kselftest: Running tests in drivers/net [ 989.735222] INFO: task python3:5859 blocked for more than 122 seconds. [ 989.743439] Not tainted 7.1.0-rc4-qitp-g9f07b9c9cb8b #1 [ 989.751261] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 989.760853] task:python3 state:D stack:25672 pid:5859 tgid:5859 ppid:5858 task_flags:0x400100 flags:0x00080000 [ 989.774061] Call Trace: [ 989.777600] [ 989.780753] __schedule+0xcc3/0x19c0 [ 989.785571] ? io_schedule_timeout+0x140/0x140 [ 989.791357] ? find_held_lock+0x2b/0x80 [ 989.796464] ? __lock_release.isra.0+0x6b/0x1a0 [ 989.802350] schedule+0xd8/0x260 [ 989.806770] schedule_preempt_disabled+0x18/0x30 [ 989.812741] __mutex_lock+0x6af/0x2010 [ 989.817747] ? threaded_store+0x132/0x220 [ 989.823049] ? ww_mutex_lock+0x160/0x160 [ 989.828248] ? selinux_inode_free_security+0x330/0x330 [ 989.834817] ? cap_capable+0x1d6/0x3e0 [ 989.839827] ? threaded_store+0x132/0x220 [ 989.845125] threaded_store+0x132/0x220 [ 989.850230] ? xps_rxqs_store+0x2e0/0x2e0 [ 989.855527] ? sysfs_file_kobj+0xbd/0x1e0 [ 989.860828] ? sysfs_kf_write+0x65/0x160 [ 989.866031] ? sysfs_file_ops+0x140/0x140 [ 989.871326] kernfs_fop_write_iter+0x335/0x510 [ 989.877112] ? kernfs_ops+0xf0/0xf0 [ 989.881822] new_sync_write+0x333/0x750 [ 989.886925] ? __lock_acquire+0x508/0xc10 [ 989.892223] ? new_sync_read+0x740/0x740 [ 989.897424] ? lock_acquire.part.0+0xbc/0x260 [ 989.903110] ? ksys_write+0x116/0x250 [ 989.908030] vfs_write+0x657/0x9b0 [ 989.912645] ksys_write+0x116/0x250 [ 989.917356] ? __ia32_sys_read+0xc0/0xc0 [ 989.922558] ? kernfs_fop_llseek+0x160/0x1f0 [ 989.928148] ? rcu_is_watching+0x15/0xd0 [ 989.933351] do_syscall_64+0xf3/0x650 [ 989.938257] ? trace_hardirqs_off+0xd/0x30 [ 989.943643] ? exc_page_fault+0xda/0xf0 [ 989.948745] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 989.955213] RIP: 0033:0x7fe9ae4de22e [ 989.960027] RSP: 002b:00007ffc628de010 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 989.969324] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007fe9ae4de22e [ 989.978133] RDX: 0000000000000001 RSI: 000056042bf196b0 RDI: 0000000000000007 [ 989.986941] RBP: 00007ffc628de020 R08: 0000000000000000 R09: 0000000000000000 [ 989.995753] R10: 0000000000000000 R11: 0000000000000202 R12: 00007fe9ae356390 [ 990.004559] R13: 000056042bf196b0 R14: 0000000000000007 R15: 0000000000000003 | [ 990.427043] #2: ff1100012154eb50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 990.438200] #3: ff11000121619d68 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 990.448278] | [ 990.450744] =============================================\x0a | [ 1063.461375] kworker/u130:7 (848) used greatest stack depth: 21936 bytes left [ 1112.613680] INFO: task python3:5859 blocked for more than 245 seconds. [ 1112.621830] Not tainted 7.1.0-rc4-qitp-g9f07b9c9cb8b #1 [ 1112.629081] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1112.638667] task:python3 state:D stack:25672 pid:5859 tgid:5859 ppid:5858 task_flags:0x400100 flags:0x00080000 [ 1112.651871] Call Trace: [ 1112.655411] [ 1112.658563] __schedule+0xcc3/0x19c0 [ 1112.663379] ? io_schedule_timeout+0x140/0x140 [ 1112.669164] ? find_held_lock+0x2b/0x80 [ 1112.674267] ? __lock_release.isra.0+0x6b/0x1a0 [ 1112.680150] schedule+0xd8/0x260 [ 1112.684556] schedule_preempt_disabled+0x18/0x30 [ 1112.690532] __mutex_lock+0x6af/0x2010 [ 1112.695535] ? threaded_store+0x132/0x220 [ 1112.700834] ? ww_mutex_lock+0x160/0x160 [ 1112.706033] ? selinux_inode_free_security+0x330/0x330 [ 1112.712603] ? cap_capable+0x1d6/0x3e0 [ 1112.717607] ? threaded_store+0x132/0x220 [ 1112.722904] threaded_store+0x132/0x220 [ 1112.728006] ? xps_rxqs_store+0x2e0/0x2e0 [ 1112.733303] ? sysfs_file_kobj+0xbd/0x1e0 [ 1112.738603] ? sysfs_kf_write+0x65/0x160 [ 1112.743805] ? sysfs_file_ops+0x140/0x140 [ 1112.749101] kernfs_fop_write_iter+0x335/0x510 [ 1112.754883] ? kernfs_ops+0xf0/0xf0 [ 1112.759593] new_sync_write+0x333/0x750 [ 1112.764688] ? __lock_acquire+0x508/0xc10 [ 1112.769984] ? new_sync_read+0x740/0x740 [ 1112.775182] ? lock_acquire.part.0+0xbc/0x260 [ 1112.780868] ? ksys_write+0x116/0x250 [ 1112.785778] vfs_write+0x657/0x9b0 [ 1112.790390] ksys_write+0x116/0x250 [ 1112.795102] ? __ia32_sys_read+0xc0/0xc0 [ 1112.800291] ? kernfs_fop_llseek+0x160/0x1f0 [ 1112.805882] ? rcu_is_watching+0x15/0xd0 [ 1112.811082] do_syscall_64+0xf3/0x650 [ 1112.815986] ? trace_hardirqs_off+0xd/0x30 [ 1112.821381] ? exc_page_fault+0xda/0xf0 [ 1112.826484] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1112.832950] RIP: 0033:0x7fe9ae4de22e [ 1112.837749] RSP: 002b:00007ffc628de010 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1112.847047] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007fe9ae4de22e [ 1112.855854] RDX: 0000000000000001 RSI: 000056042bf196b0 RDI: 0000000000000007 [ 1112.864660] RBP: 00007ffc628de020 R08: 0000000000000000 R09: 0000000000000000 [ 1112.873467] R10: 0000000000000000 R11: 0000000000000202 R12: 00007fe9ae356390 [ 1112.882264] R13: 000056042bf196b0 R14: 0000000000000007 R15: 0000000000000003 | [ 1113.333281] #4: ff1100180dcb72e0 (&rq->__lock){-.-.}-{2:2}, at: ___task_rq_lock+0xf7/0x4b0 | [ 1113.343462] #5: ff110001ed338790 (key){....}-{2:2}, at: percpu_counter_add_batch+0x89/0x280 | [ 1113.353807] #6: ff1100012116c318 (_xmit_ETHER){+.-.}-{3:3}, at: sch_direct_xmit+0x2e5/0x1100 | [ 1113.364189] | [ 1113.366653] =============================================\x0a [ 1235.495861] INFO: task python3:5859 blocked for more than 368 seconds. [ 1235.504010] Not tainted 7.1.0-rc4-qitp-g9f07b9c9cb8b #1 [ 1235.511262] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1235.520851] task:python3 state:D stack:25672 pid:5859 tgid:5859 ppid:5858 task_flags:0x400100 flags:0x00080002 [ 1235.534047] Call Trace: [ 1235.537587] [ 1235.540741] __schedule+0xcc3/0x19c0 [ 1235.545560] ? io_schedule_timeout+0x140/0x140 [ 1235.551348] ? find_held_lock+0x2b/0x80 [ 1235.556455] ? __lock_release.isra.0+0x6b/0x1a0 [ 1235.562341] schedule+0xd8/0x260 [ 1235.566758] schedule_preempt_disabled+0x18/0x30 [ 1235.572740] __mutex_lock+0x6af/0x2010 [ 1235.577744] ? threaded_store+0x132/0x220 [ 1235.583044] ? ww_mutex_lock+0x160/0x160 [ 1235.588244] ? selinux_inode_free_security+0x330/0x330 [ 1235.594815] ? cap_capable+0x1d6/0x3e0 [ 1235.599823] ? threaded_store+0x132/0x220 [ 1235.605122] threaded_store+0x132/0x220 [ 1235.610223] ? xps_rxqs_store+0x2e0/0x2e0 [ 1235.615523] ? sysfs_file_kobj+0xbd/0x1e0 [ 1235.620822] ? sysfs_kf_write+0x65/0x160 [ 1235.626023] ? sysfs_file_ops+0x140/0x140 [ 1235.631321] kernfs_fop_write_iter+0x335/0x510 [ 1235.637104] ? kernfs_ops+0xf0/0xf0 [ 1235.641816] new_sync_write+0x333/0x750 [ 1235.646921] ? __lock_acquire+0x508/0xc10 [ 1235.652217] ? new_sync_read+0x740/0x740 [ 1235.657420] ? lock_acquire.part.0+0xbc/0x260 [ 1235.663107] ? ksys_write+0x116/0x250 [ 1235.668016] vfs_write+0x657/0x9b0 [ 1235.672634] ksys_write+0x116/0x250 [ 1235.677346] ? __ia32_sys_read+0xc0/0xc0 [ 1235.682535] ? kernfs_fop_llseek+0x160/0x1f0 [ 1235.688126] ? rcu_is_watching+0x15/0xd0 [ 1235.693328] do_syscall_64+0xf3/0x650 [ 1235.698237] ? trace_hardirqs_off+0xd/0x30 [ 1235.703633] ? exc_page_fault+0xda/0xf0 [ 1235.708736] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1235.715203] RIP: 0033:0x7fe9ae4de22e [ 1235.720013] RSP: 002b:00007ffc628de010 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1235.729312] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007fe9ae4de22e [ 1235.738122] RDX: 0000000000000001 RSI: 000056042bf196b0 RDI: 0000000000000007 [ 1235.746932] RBP: 00007ffc628de020 R08: 0000000000000000 R09: 0000000000000000 [ 1235.755742] R10: 0000000000000000 R11: 0000000000000202 R12: 00007fe9ae356390 [ 1235.764549] R13: 000056042bf196b0 R14: 0000000000000007 R15: 0000000000000003 | [ 1236.164484] #1: ff110001b7242c80 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 1236.175444] #2: ff1100012154eb50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1236.186599] #3: ff11000121619d68 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1236.196689] | [ 1236.199156] =============================================\x0a [ 1358.376515] INFO: task python3:5859 blocked for more than 491 seconds. [ 1358.384662] Not tainted 7.1.0-rc4-qitp-g9f07b9c9cb8b #1 [ 1358.391913] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1358.401501] task:python3 state:D stack:25672 pid:5859 tgid:5859 ppid:5858 task_flags:0x400100 flags:0x00080002 [ 1358.414707] Call Trace: [ 1358.418244] [ 1358.421396] __schedule+0xcc3/0x19c0 [ 1358.426213] ? io_schedule_timeout+0x140/0x140 [ 1358.431997] ? find_held_lock+0x2b/0x80 [ 1358.437103] ? __lock_release.isra.0+0x6b/0x1a0 [ 1358.443003] schedule+0xd8/0x260 [ 1358.447427] schedule_preempt_disabled+0x18/0x30 [ 1358.453406] __mutex_lock+0x6af/0x2010 [ 1358.458478] ? threaded_store+0x132/0x220 [ 1358.463778] ? ww_mutex_lock+0x160/0x160 [ 1358.468976] ? selinux_inode_free_security+0x330/0x330 [ 1358.475545] ? cap_capable+0x1d6/0x3e0 [ 1358.480551] ? threaded_store+0x132/0x220 [ 1358.485923] threaded_store+0x132/0x220 [ 1358.491024] ? xps_rxqs_store+0x2e0/0x2e0 [ 1358.496320] ? sysfs_file_kobj+0xbd/0x1e0 [ 1358.501607] ? sysfs_kf_write+0x65/0x160 [ 1358.506805] ? sysfs_file_ops+0x140/0x140 [ 1358.512100] kernfs_fop_write_iter+0x335/0x510 [ 1358.517882] ? kernfs_ops+0xf0/0xf0 [ 1358.522593] new_sync_write+0x333/0x750 [ 1358.527694] ? __lock_acquire+0x508/0xc10 [ 1358.532991] ? new_sync_read+0x740/0x740 [ 1358.538193] ? lock_acquire.part.0+0xbc/0x260 [ 1358.543878] ? ksys_write+0x116/0x250 [ 1358.548786] vfs_write+0x657/0x9b0 [ 1358.553399] ksys_write+0x116/0x250 [ 1358.558111] ? __ia32_sys_read+0xc0/0xc0 [ 1358.563302] ? kernfs_fop_llseek+0x160/0x1f0 [ 1358.568890] ? rcu_is_watching+0x15/0xd0 [ 1358.574092] do_syscall_64+0xf3/0x650 [ 1358.578998] ? trace_hardirqs_off+0xd/0x30 [ 1358.584383] ? exc_page_fault+0xda/0xf0 [ 1358.589487] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1358.595954] RIP: 0033:0x7fe9ae4de22e [ 1358.600762] RSP: 002b:00007ffc628de010 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1358.610059] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007fe9ae4de22e [ 1358.618857] RDX: 0000000000000001 RSI: 000056042bf196b0 RDI: 0000000000000007 [ 1358.627662] RBP: 00007ffc628de020 R08: 0000000000000000 R09: 0000000000000000 [ 1358.636472] R10: 0000000000000000 R11: 0000000000000202 R12: 00007fe9ae356390 [ 1358.645278] R13: 000056042bf196b0 R14: 0000000000000007 R15: 0000000000000003 Finger prints: __schedule:schedule:schedule_preempt_disabled:__mutex_lock:threaded_store