====================================== | [ 762.216354] nipa-hw-worker: drivers/net:napi_threaded.py: timed out | [ 762.218910] nipa-hw-worker: [4/38] Retrying drivers/net:napi_threaded.py | [ 762.383225] kselftest: Running tests in drivers/net [ 989.749613] INFO: task python3:5058 blocked for more than 122 seconds. [ 989.757847] Not tainted 7.0.0-rc4-npew-ge47e86122399 #1 [ 989.765098] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 989.774688] task:python3 state:D stack:25312 pid:5058 tgid:5058 ppid:5057 task_flags:0x400100 flags:0x00080000 [ 989.787894] Call Trace: [ 989.791437] [ 989.794591] __schedule+0xad9/0x1490 [ 989.799407] ? io_schedule_timeout+0x130/0x130 [ 989.805193] ? find_held_lock+0x2b/0x80 [ 989.810297] ? __lock_release.isra.0+0x59/0x170 [ 989.816176] schedule+0xd4/0x260 [ 989.820595] schedule_preempt_disabled+0x18/0x30 [ 989.826575] __mutex_lock+0xd76/0x1f20 [ 989.831582] ? threaded_store+0x132/0x220 [ 989.836885] ? ww_mutex_lock+0x160/0x160 [ 989.842083] ? selinux_inode_free_security+0x310/0x310 [ 989.848655] ? cap_capable+0x1ed/0x3f0 [ 989.853663] ? sysfs_file_ops+0x140/0x140 [ 989.858963] ? threaded_store+0x132/0x220 [ 989.864251] threaded_store+0x132/0x220 [ 989.869343] ? tx_maxrate_store+0x4e0/0x4e0 [ 989.874837] ? sysfs_file_ops+0x140/0x140 [ 989.880134] ? sysfs_file_kobj+0xbd/0x1e0 [ 989.885432] ? sysfs_kf_write+0x65/0x160 [ 989.890638] kernfs_fop_write_iter+0x335/0x510 [ 989.896422] ? kernfs_ops+0xf0/0xf0 [ 989.901136] new_sync_write+0x308/0x6f0 [ 989.906241] ? new_sync_read+0x6f0/0x6f0 [ 989.911445] ? lock_acquire.part.0+0xbc/0x260 [ 989.917131] ? ksys_write+0xff/0x200 [ 989.921944] vfs_write+0x62a/0x980 [ 989.926561] ksys_write+0xff/0x200 [ 989.931177] ? __ia32_sys_read+0xc0/0xc0 [ 989.936375] ? kernfs_fop_llseek+0x160/0x1f0 [ 989.941965] ? rcu_is_watching+0x15/0xd0 [ 989.947169] do_syscall_64+0xf3/0x5e0 [ 989.952076] ? exc_page_fault+0x9e/0xb0 [ 989.957177] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 989.963645] RIP: 0033:0x7f833d9ffc5e [ 989.968454] RSP: 002b:00007ffe539bae30 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 989.977751] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f833d9ffc5e [ 989.986551] RDX: 0000000000000001 RSI: 0000560e10cd9110 RDI: 0000000000000007 [ 989.995359] RBP: 00007ffe539bae40 R08: 0000000000000000 R09: 0000000000000000 [ 990.004165] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f833d89ae80 [ 990.012973] R13: 0000560e10cd9110 R14: 0000000000000007 R15: 00007f833e100b98 | [ 990.412291] #1: ff110001a046e888 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x247/0x510 | [ 990.423254] #2: ff110001207230f0 (kn->active#127){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 990.434412] #3: ff110001206f1cb8 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 990.444501] | [ 990.446966] =============================================\x0a [ 1112.632006] INFO: task python3:5058 blocked for more than 245 seconds. [ 1112.640151] Not tainted 7.0.0-rc4-npew-ge47e86122399 #1 [ 1112.647403] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1112.656993] task:python3 state:D stack:25312 pid:5058 tgid:5058 ppid:5057 task_flags:0x400100 flags:0x00080000 [ 1112.670196] Call Trace: [ 1112.673736] [ 1112.676887] __schedule+0xad9/0x1490 [ 1112.681700] ? io_schedule_timeout+0x130/0x130 [ 1112.687485] ? find_held_lock+0x2b/0x80 [ 1112.692593] ? __lock_release.isra.0+0x59/0x170 [ 1112.698476] schedule+0xd4/0x260 [ 1112.702892] schedule_preempt_disabled+0x18/0x30 [ 1112.708867] __mutex_lock+0xd76/0x1f20 [ 1112.713870] ? threaded_store+0x132/0x220 [ 1112.719159] ? ww_mutex_lock+0x160/0x160 [ 1112.724358] ? selinux_inode_free_security+0x310/0x310 [ 1112.730927] ? cap_capable+0x1ed/0x3f0 [ 1112.735935] ? sysfs_file_ops+0x140/0x140 [ 1112.741234] ? threaded_store+0x132/0x220 [ 1112.746528] threaded_store+0x132/0x220 [ 1112.751632] ? tx_maxrate_store+0x4e0/0x4e0 [ 1112.757124] ? sysfs_file_ops+0x140/0x140 [ 1112.762409] ? sysfs_file_kobj+0xbd/0x1e0 [ 1112.767704] ? sysfs_kf_write+0x65/0x160 [ 1112.772904] kernfs_fop_write_iter+0x335/0x510 [ 1112.778687] ? kernfs_ops+0xf0/0xf0 [ 1112.783396] new_sync_write+0x308/0x6f0 [ 1112.788499] ? new_sync_read+0x6f0/0x6f0 [ 1112.793697] ? lock_acquire.part.0+0xbc/0x260 [ 1112.799382] ? ksys_write+0xff/0x200 [ 1112.804194] vfs_write+0x62a/0x980 [ 1112.808808] ksys_write+0xff/0x200 [ 1112.813422] ? __ia32_sys_read+0xc0/0xc0 [ 1112.818619] ? kernfs_fop_llseek+0x160/0x1f0 [ 1112.824207] ? rcu_is_watching+0x15/0xd0 [ 1112.829407] do_syscall_64+0xf3/0x5e0 [ 1112.834312] ? exc_page_fault+0x9e/0xb0 [ 1112.839414] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1112.845881] RIP: 0033:0x7f833d9ffc5e [ 1112.850683] RSP: 002b:00007ffe539bae30 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1112.859981] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f833d9ffc5e [ 1112.868788] RDX: 0000000000000001 RSI: 0000560e10cd9110 RDI: 0000000000000007 [ 1112.877596] RBP: 00007ffe539bae40 R08: 0000000000000000 R09: 0000000000000000 [ 1112.886394] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f833d89ae80 [ 1112.895195] R13: 0000560e10cd9110 R14: 0000000000000007 R15: 00007f833e100b98 | [ 1113.265836] #1: ff110001a046e888 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x247/0x510 | [ 1113.276800] #2: ff110001207230f0 (kn->active#127){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1113.287955] #3: ff110001206f1cb8 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1113.298045] | [ 1113.300510] =============================================\x0a [ 1235.513402] INFO: task python3:5058 blocked for more than 368 seconds. [ 1235.521547] Not tainted 7.0.0-rc4-npew-ge47e86122399 #1 [ 1235.528797] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1235.538384] task:python3 state:D stack:25312 pid:5058 tgid:5058 ppid:5057 task_flags:0x400100 flags:0x00080002 [ 1235.551588] Call Trace: [ 1235.555128] [ 1235.558276] __schedule+0xad9/0x1490 [ 1235.563090] ? io_schedule_timeout+0x130/0x130 [ 1235.568875] ? find_held_lock+0x2b/0x80 [ 1235.573977] ? __lock_release.isra.0+0x59/0x170 [ 1235.579862] schedule+0xd4/0x260 [ 1235.584278] schedule_preempt_disabled+0x18/0x30 [ 1235.590254] __mutex_lock+0xd76/0x1f20 [ 1235.595259] ? threaded_store+0x132/0x220 [ 1235.600557] ? ww_mutex_lock+0x160/0x160 [ 1235.605757] ? selinux_inode_free_security+0x310/0x310 [ 1235.612325] ? cap_capable+0x1ed/0x3f0 [ 1235.617337] ? sysfs_file_ops+0x140/0x140 [ 1235.622634] ? threaded_store+0x132/0x220 [ 1235.627930] threaded_store+0x132/0x220 [ 1235.633031] ? tx_maxrate_store+0x4e0/0x4e0 [ 1235.638521] ? sysfs_file_ops+0x140/0x140 [ 1235.643816] ? sysfs_file_kobj+0xbd/0x1e0 [ 1235.649112] ? sysfs_kf_write+0x65/0x160 [ 1235.654312] kernfs_fop_write_iter+0x335/0x510 [ 1235.660086] ? kernfs_ops+0xf0/0xf0 [ 1235.664795] new_sync_write+0x308/0x6f0 [ 1235.669898] ? new_sync_read+0x6f0/0x6f0 [ 1235.675095] ? lock_acquire.part.0+0xbc/0x260 [ 1235.680781] ? ksys_write+0xff/0x200 [ 1235.685592] vfs_write+0x62a/0x980 [ 1235.690207] ksys_write+0xff/0x200 [ 1235.694821] ? __ia32_sys_read+0xc0/0xc0 [ 1235.700017] ? kernfs_fop_llseek+0x160/0x1f0 [ 1235.705605] ? rcu_is_watching+0x15/0xd0 [ 1235.710806] do_syscall_64+0xf3/0x5e0 [ 1235.715711] ? exc_page_fault+0x9e/0xb0 [ 1235.720814] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1235.727280] RIP: 0033:0x7f833d9ffc5e [ 1235.732087] RSP: 002b:00007ffe539bae30 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1235.741385] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f833d9ffc5e [ 1235.750192] RDX: 0000000000000001 RSI: 0000560e10cd9110 RDI: 0000000000000007 [ 1235.758996] RBP: 00007ffe539bae40 R08: 0000000000000000 R09: 0000000000000000 [ 1235.767801] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f833d89ae80 [ 1235.776607] R13: 0000560e10cd9110 R14: 0000000000000007 R15: 00007f833e100b98 | [ 1236.164159] #2: ff110001207230f0 (kn->active#127){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1236.175317] #3: ff110001206f1cb8 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1236.185407] | [ 1236.187873] =============================================\x0a | [ 1268.281747] kworker/u130:3 (535) used greatest stack depth: 21192 bytes left [ 1358.394799] INFO: task python3:5058 blocked for more than 491 seconds. [ 1358.402946] Not tainted 7.0.0-rc4-npew-ge47e86122399 #1 [ 1358.410186] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1358.419776] task:python3 state:D stack:25312 pid:5058 tgid:5058 ppid:5057 task_flags:0x400100 flags:0x00080002 [ 1358.432980] Call Trace: [ 1358.436521] [ 1358.439669] __schedule+0xad9/0x1490 [ 1358.444486] ? io_schedule_timeout+0x130/0x130 [ 1358.450272] ? find_held_lock+0x2b/0x80 [ 1358.455374] ? __lock_release.isra.0+0x59/0x170 [ 1358.461261] schedule+0xd4/0x260 [ 1358.465678] schedule_preempt_disabled+0x18/0x30 [ 1358.471655] __mutex_lock+0xd76/0x1f20 [ 1358.476659] ? threaded_store+0x132/0x220 [ 1358.481959] ? ww_mutex_lock+0x160/0x160 [ 1358.487157] ? selinux_inode_free_security+0x310/0x310 [ 1358.493727] ? cap_capable+0x1ed/0x3f0 [ 1358.498731] ? sysfs_file_ops+0x140/0x140 [ 1358.504031] ? threaded_store+0x132/0x220 [ 1358.509318] threaded_store+0x132/0x220 [ 1358.514423] ? tx_maxrate_store+0x4e0/0x4e0 [ 1358.519917] ? sysfs_file_ops+0x140/0x140 [ 1358.525211] ? sysfs_file_kobj+0xbd/0x1e0 [ 1358.530506] ? sysfs_kf_write+0x65/0x160 [ 1358.535706] kernfs_fop_write_iter+0x335/0x510 [ 1358.541479] ? kernfs_ops+0xf0/0xf0 [ 1358.546188] new_sync_write+0x308/0x6f0 [ 1358.551291] ? new_sync_read+0x6f0/0x6f0 [ 1358.556489] ? lock_acquire.part.0+0xbc/0x260 [ 1358.562176] ? ksys_write+0xff/0x200 [ 1358.566978] vfs_write+0x62a/0x980 [ 1358.571594] ksys_write+0xff/0x200 [ 1358.576198] ? __ia32_sys_read+0xc0/0xc0 [ 1358.581395] ? kernfs_fop_llseek+0x160/0x1f0 [ 1358.586983] ? rcu_is_watching+0x15/0xd0 [ 1358.592184] do_syscall_64+0xf3/0x5e0 [ 1358.597081] ? exc_page_fault+0x9e/0xb0 [ 1358.602180] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1358.608648] RIP: 0033:0x7f833d9ffc5e [ 1358.613456] RSP: 002b:00007ffe539bae30 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1358.622752] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f833d9ffc5e [ 1358.631559] RDX: 0000000000000001 RSI: 0000560e10cd9110 RDI: 0000000000000007 [ 1358.640364] RBP: 00007ffe539bae40 R08: 0000000000000000 R09: 0000000000000000 [ 1358.649169] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f833d89ae80 [ 1358.657975] R13: 0000560e10cd9110 R14: 0000000000000007 R15: 00007f833e100b98 Finger prints: __schedule:schedule:schedule_preempt_disabled:__mutex_lock:threaded_store