====================================== | [ 781.208961] nipa-hw-worker: drivers/net:napi_threaded.py: timed out | [ 781.211922] nipa-hw-worker: [5/46] Retrying drivers/net:napi_threaded.py | [ 781.339189] kselftest: Running tests in drivers/net [ 989.681739] INFO: task python3:5691 blocked for more than 122 seconds. [ 989.689971] Not tainted 7.1.0-rc2-izpd-g50ebd7b3c8a3 #1 [ 989.697945] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 989.707536] task:python3 state:D stack:25672 pid:5691 tgid:5691 ppid:5690 task_flags:0x400100 flags:0x00080000 [ 989.720745] Call Trace: [ 989.724285] [ 989.727439] __schedule+0xcba/0x1940 [ 989.732257] ? io_schedule_timeout+0x140/0x140 [ 989.738042] ? find_held_lock+0x2b/0x80 [ 989.743149] ? __lock_release.isra.0+0x6b/0x1a0 [ 989.749032] schedule+0xd4/0x260 [ 989.753444] schedule_preempt_disabled+0x18/0x30 [ 989.759424] __mutex_lock+0x6a3/0x2000 [ 989.764430] ? threaded_store+0x132/0x220 [ 989.769731] ? ww_mutex_lock+0x160/0x160 [ 989.774931] ? selinux_inode_free_security+0x330/0x330 [ 989.781500] ? cap_capable+0x1d6/0x3e0 [ 989.786507] ? threaded_store+0x132/0x220 [ 989.791804] threaded_store+0x132/0x220 [ 989.796904] ? xps_rxqs_store+0x2e0/0x2e0 [ 989.802204] ? sysfs_file_kobj+0xbd/0x1e0 [ 989.807507] ? sysfs_kf_write+0x65/0x160 [ 989.812707] ? sysfs_file_ops+0x140/0x140 [ 989.818003] kernfs_fop_write_iter+0x335/0x510 [ 989.823790] ? kernfs_ops+0xf0/0xf0 [ 989.828501] new_sync_write+0x333/0x750 [ 989.833605] ? __lock_acquire+0x508/0xc10 [ 989.838892] ? new_sync_read+0x740/0x740 [ 989.844094] ? lock_acquire.part.0+0xbc/0x260 [ 989.849781] ? ksys_write+0x116/0x250 [ 989.854690] vfs_write+0x657/0x9b0 [ 989.859306] ksys_write+0x116/0x250 [ 989.864019] ? __ia32_sys_read+0xc0/0xc0 [ 989.869210] ? kernfs_fop_llseek+0x160/0x1f0 [ 989.874792] ? rcu_is_watching+0x15/0xd0 [ 989.879992] do_syscall_64+0xf3/0x650 [ 989.884892] ? trace_hardirqs_off+0xd/0x30 [ 989.890287] ? exc_page_fault+0xda/0xf0 [ 989.895389] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 989.901859] RIP: 0033:0x7f15829cf22e [ 989.906670] RSP: 002b:00007ffda5b15300 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 989.915967] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f15829cf22e [ 989.924777] RDX: 0000000000000001 RSI: 0000555f8d89ffe0 RDI: 0000000000000007 [ 989.933587] RBP: 00007ffda5b15310 R08: 0000000000000000 R09: 0000000000000000 [ 989.942397] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f1582847390 [ 989.951204] R13: 0000555f8d89ffe0 R14: 0000000000000007 R15: 0000000000000003 | [ 990.356773] #1: ff110001b6b83880 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 990.367737] #2: ff110017d90a4b50 (kn->active#159){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 990.378894] #3: ff110017d9109d68 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 990.388980] | [ 990.391445] =============================================\x0a [ 1112.562949] INFO: task python3:5691 blocked for more than 245 seconds. [ 1112.571094] Not tainted 7.1.0-rc2-izpd-g50ebd7b3c8a3 #1 [ 1112.578347] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1112.587956] task:python3 state:D stack:25672 pid:5691 tgid:5691 ppid:5690 task_flags:0x400100 flags:0x00080000 [ 1112.601184] Call Trace: [ 1112.604734] [ 1112.607892] __schedule+0xcba/0x1940 [ 1112.612713] ? io_schedule_timeout+0x140/0x140 [ 1112.618502] ? find_held_lock+0x2b/0x80 [ 1112.623610] ? __lock_release.isra.0+0x6b/0x1a0 [ 1112.629505] schedule+0xd4/0x260 [ 1112.633928] schedule_preempt_disabled+0x18/0x30 [ 1112.639912] __mutex_lock+0x6a3/0x2000 [ 1112.644919] ? threaded_store+0x132/0x220 [ 1112.650225] ? ww_mutex_lock+0x160/0x160 [ 1112.655429] ? selinux_inode_free_security+0x330/0x330 [ 1112.662009] ? cap_capable+0x1d6/0x3e0 [ 1112.667021] ? threaded_store+0x132/0x220 [ 1112.672320] threaded_store+0x132/0x220 [ 1112.677423] ? xps_rxqs_store+0x2e0/0x2e0 [ 1112.682720] ? sysfs_file_kobj+0xbd/0x1e0 [ 1112.688025] ? sysfs_kf_write+0x65/0x160 [ 1112.693233] ? sysfs_file_ops+0x140/0x140 [ 1112.698525] kernfs_fop_write_iter+0x335/0x510 [ 1112.704314] ? kernfs_ops+0xf0/0xf0 [ 1112.709029] new_sync_write+0x333/0x750 [ 1112.714135] ? __lock_acquire+0x508/0xc10 [ 1112.719434] ? new_sync_read+0x740/0x740 [ 1112.724644] ? lock_acquire.part.0+0xbc/0x260 [ 1112.730335] ? ksys_write+0x116/0x250 [ 1112.735252] vfs_write+0x657/0x9b0 [ 1112.739873] ksys_write+0x116/0x250 [ 1112.744591] ? __ia32_sys_read+0xc0/0xc0 [ 1112.749796] ? kernfs_fop_llseek+0x160/0x1f0 [ 1112.755392] ? rcu_is_watching+0x15/0xd0 [ 1112.760587] do_syscall_64+0xf3/0x650 [ 1112.765496] ? trace_hardirqs_off+0xd/0x30 [ 1112.770893] ? exc_page_fault+0xda/0xf0 [ 1112.776002] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1112.782473] RIP: 0033:0x7f15829cf22e [ 1112.787287] RSP: 002b:00007ffda5b15300 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1112.796588] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f15829cf22e [ 1112.805403] RDX: 0000000000000001 RSI: 0000555f8d89ffe0 RDI: 0000000000000007 [ 1112.814214] RBP: 00007ffda5b15310 R08: 0000000000000000 R09: 0000000000000000 [ 1112.823029] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f1582847390 [ 1112.831831] R13: 0000555f8d89ffe0 R14: 0000000000000007 R15: 0000000000000003 | [ 1113.209038] #1: ff110001b6b83880 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 1113.220007] #2: ff110017d90a4b50 (kn->active#159){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1113.231171] #3: ff110017d9109d68 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1113.241269] | [ 1113.243739] =============================================\x0a [ 1235.443121] INFO: task python3:5691 blocked for more than 368 seconds. [ 1235.451269] Not tainted 7.1.0-rc2-izpd-g50ebd7b3c8a3 #1 [ 1235.458509] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1235.468100] task:python3 state:D stack:25672 pid:5691 tgid:5691 ppid:5690 task_flags:0x400100 flags:0x00080002 [ 1235.481307] Call Trace: [ 1235.484848] [ 1235.487997] __schedule+0xcba/0x1940 [ 1235.492816] ? io_schedule_timeout+0x140/0x140 [ 1235.498603] ? find_held_lock+0x2b/0x80 [ 1235.503710] ? __lock_release.isra.0+0x6b/0x1a0 [ 1235.509595] schedule+0xd4/0x260 [ 1235.514016] schedule_preempt_disabled+0x18/0x30 [ 1235.519997] __mutex_lock+0x6a3/0x2000 [ 1235.525001] ? threaded_store+0x132/0x220 [ 1235.530298] ? ww_mutex_lock+0x160/0x160 [ 1235.535498] ? selinux_inode_free_security+0x330/0x330 [ 1235.542068] ? cap_capable+0x1d6/0x3e0 [ 1235.547078] ? threaded_store+0x132/0x220 [ 1235.552377] threaded_store+0x132/0x220 [ 1235.557476] ? xps_rxqs_store+0x2e0/0x2e0 [ 1235.562774] ? sysfs_file_kobj+0xbd/0x1e0 [ 1235.568071] ? sysfs_kf_write+0x65/0x160 [ 1235.573272] ? sysfs_file_ops+0x140/0x140 [ 1235.578568] kernfs_fop_write_iter+0x335/0x510 [ 1235.584355] ? kernfs_ops+0xf0/0xf0 [ 1235.589066] new_sync_write+0x333/0x750 [ 1235.594171] ? __lock_acquire+0x508/0xc10 [ 1235.599466] ? new_sync_read+0x740/0x740 [ 1235.604668] ? lock_acquire.part.0+0xbc/0x260 [ 1235.610356] ? ksys_write+0x116/0x250 [ 1235.615260] vfs_write+0x657/0x9b0 [ 1235.619877] ksys_write+0x116/0x250 [ 1235.624590] ? __ia32_sys_read+0xc0/0xc0 [ 1235.629787] ? kernfs_fop_llseek+0x160/0x1f0 [ 1235.635378] ? rcu_is_watching+0x15/0xd0 [ 1235.640579] do_syscall_64+0xf3/0x650 [ 1235.645483] ? trace_hardirqs_off+0xd/0x30 [ 1235.650879] ? exc_page_fault+0xda/0xf0 [ 1235.655981] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1235.662451] RIP: 0033:0x7f15829cf22e [ 1235.667262] RSP: 002b:00007ffda5b15300 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1235.676550] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f15829cf22e [ 1235.685352] RDX: 0000000000000001 RSI: 0000555f8d89ffe0 RDI: 0000000000000007 [ 1235.694160] RBP: 00007ffda5b15310 R08: 0000000000000000 R09: 0000000000000000 [ 1235.702970] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f1582847390 [ 1235.711780] R13: 0000555f8d89ffe0 R14: 0000000000000007 R15: 0000000000000003 | [ 1236.088633] #1: ff110001b6b83880 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 1236.099593] #2: ff110017d90a4b50 (kn->active#159){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1236.110750] #3: ff110017d9109d68 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1236.120841] | [ 1236.123308] =============================================\x0a [ 1358.324256] INFO: task python3:5691 blocked for more than 491 seconds. [ 1358.332403] Not tainted 7.1.0-rc2-izpd-g50ebd7b3c8a3 #1 [ 1358.339643] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1358.349231] task:python3 state:D stack:25672 pid:5691 tgid:5691 ppid:5690 task_flags:0x400100 flags:0x00080002 [ 1358.362440] Call Trace: [ 1358.365980] [ 1358.369134] __schedule+0xcba/0x1940 [ 1358.373949] ? io_schedule_timeout+0x140/0x140 [ 1358.379735] ? find_held_lock+0x2b/0x80 [ 1358.384842] ? __lock_release.isra.0+0x6b/0x1a0 [ 1358.390727] schedule+0xd4/0x260 [ 1358.395145] schedule_preempt_disabled+0x18/0x30 [ 1358.401126] __mutex_lock+0x6a3/0x2000 [ 1358.406129] ? threaded_store+0x132/0x220 [ 1358.411428] ? ww_mutex_lock+0x160/0x160 [ 1358.416618] ? selinux_inode_free_security+0x330/0x330 [ 1358.423189] ? cap_capable+0x1d6/0x3e0 [ 1358.428198] ? threaded_store+0x132/0x220 [ 1358.433495] threaded_store+0x132/0x220 [ 1358.438591] ? xps_rxqs_store+0x2e0/0x2e0 [ 1358.443891] ? sysfs_file_kobj+0xbd/0x1e0 [ 1358.449190] ? sysfs_kf_write+0x65/0x160 [ 1358.454384] ? sysfs_file_ops+0x140/0x140 [ 1358.459682] kernfs_fop_write_iter+0x335/0x510 [ 1358.465469] ? kernfs_ops+0xf0/0xf0 [ 1358.470181] new_sync_write+0x333/0x750 [ 1358.475282] ? __lock_acquire+0x508/0xc10 [ 1358.480579] ? new_sync_read+0x740/0x740 [ 1358.485783] ? lock_acquire.part.0+0xbc/0x260 [ 1358.491470] ? ksys_write+0x116/0x250 [ 1358.496379] vfs_write+0x657/0x9b0 [ 1358.500997] ksys_write+0x116/0x250 [ 1358.505709] ? __ia32_sys_read+0xc0/0xc0 [ 1358.510898] ? kernfs_fop_llseek+0x160/0x1f0 [ 1358.516489] ? rcu_is_watching+0x15/0xd0 [ 1358.521690] do_syscall_64+0xf3/0x650 [ 1358.526596] ? trace_hardirqs_off+0xd/0x30 [ 1358.531992] ? exc_page_fault+0xda/0xf0 [ 1358.537099] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1358.543569] RIP: 0033:0x7f15829cf22e [ 1358.548381] RSP: 002b:00007ffda5b15300 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1358.557680] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f15829cf22e [ 1358.566489] RDX: 0000000000000001 RSI: 0000555f8d89ffe0 RDI: 0000000000000007 [ 1358.575298] RBP: 00007ffda5b15310 R08: 0000000000000000 R09: 0000000000000000 [ 1358.584108] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f1582847390 [ 1358.592918] R13: 0000555f8d89ffe0 R14: 0000000000000007 R15: 0000000000000003 Finger prints: __schedule:schedule:schedule_preempt_disabled:__mutex_lock:threaded_store