====================================== | [ 763.247629] nipa-hw-worker: drivers/net:napi_threaded.py: timed out | [ 763.250122] nipa-hw-worker: [4/38] Retrying drivers/net:napi_threaded.py | [ 763.411302] kselftest: Running tests in drivers/net | [ 805.428578] kworker/u130:2 (406) used greatest stack depth: 21192 bytes left [ 989.750260] INFO: task python3:5067 blocked for more than 122 seconds. [ 989.758483] Not tainted 7.0.0-rc4-babn-g3ea2a328f638 #1 [ 989.765736] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 989.775326] task:python3 state:D stack:25312 pid:5067 tgid:5067 ppid:5066 task_flags:0x400100 flags:0x00080000 [ 989.788533] Call Trace: [ 989.792073] [ 989.795226] __schedule+0xad9/0x1490 [ 989.800043] ? io_schedule_timeout+0x130/0x130 [ 989.805827] ? find_held_lock+0x2b/0x80 [ 989.810933] ? __lock_release.isra.0+0x59/0x170 [ 989.816812] schedule+0xd4/0x260 [ 989.821231] schedule_preempt_disabled+0x18/0x30 [ 989.827210] __mutex_lock+0xd76/0x1f20 [ 989.832216] ? threaded_store+0x132/0x220 [ 989.837518] ? ww_mutex_lock+0x160/0x160 [ 989.842717] ? selinux_inode_free_security+0x310/0x310 [ 989.849290] ? cap_capable+0x1ed/0x3f0 [ 989.854297] ? sysfs_file_ops+0x140/0x140 [ 989.859598] ? threaded_store+0x132/0x220 [ 989.864897] threaded_store+0x132/0x220 [ 989.870001] ? tx_maxrate_store+0x4e0/0x4e0 [ 989.875494] ? sysfs_file_ops+0x140/0x140 [ 989.880792] ? sysfs_file_kobj+0xbd/0x1e0 [ 989.886091] ? sysfs_kf_write+0x65/0x160 [ 989.891292] kernfs_fop_write_iter+0x335/0x510 [ 989.897079] ? kernfs_ops+0xf0/0xf0 [ 989.901797] new_sync_write+0x308/0x6f0 [ 989.906902] ? new_sync_read+0x6f0/0x6f0 [ 989.912104] ? lock_acquire.part.0+0xbc/0x260 [ 989.917788] ? ksys_write+0xff/0x200 [ 989.922602] vfs_write+0x62a/0x980 [ 989.927220] ksys_write+0xff/0x200 [ 989.931836] ? __ia32_sys_read+0xc0/0xc0 [ 989.937034] ? kernfs_fop_llseek+0x160/0x1f0 [ 989.942625] ? rcu_is_watching+0x15/0xd0 [ 989.947829] do_syscall_64+0xf3/0x5e0 [ 989.952736] ? exc_page_fault+0x9e/0xb0 [ 989.957840] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 989.964307] RIP: 0033:0x7f734f414c5e [ 989.969116] RSP: 002b:00007ffceadd26c0 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 989.978417] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f734f414c5e [ 989.987225] RDX: 0000000000000001 RSI: 00005598489c0110 RDI: 0000000000000007 [ 989.996033] RBP: 00007ffceadd26d0 R08: 0000000000000000 R09: 0000000000000000 [ 990.004832] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f734f2afe80 [ 990.013634] R13: 00005598489c0110 R14: 0000000000000007 R15: 00007f734fb15b98 | [ 990.424221] #1: ff110001a0980888 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x247/0x510 | [ 990.435184] #2: ff11000121fa50f0 (kn->active#127){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 990.446340] #3: ff11000122421cb8 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 990.456431] | [ 990.458890] =============================================\x0a [ 1112.632393] INFO: task python3:5067 blocked for more than 245 seconds. [ 1112.640541] Not tainted 7.0.0-rc4-babn-g3ea2a328f638 #1 [ 1112.647790] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1112.657382] task:python3 state:D stack:25312 pid:5067 tgid:5067 ppid:5066 task_flags:0x400100 flags:0x00080000 [ 1112.670588] Call Trace: [ 1112.674129] [ 1112.677282] __schedule+0xad9/0x1490 [ 1112.682101] ? io_schedule_timeout+0x130/0x130 [ 1112.687887] ? find_held_lock+0x2b/0x80 [ 1112.692996] ? __lock_release.isra.0+0x59/0x170 [ 1112.698881] schedule+0xd4/0x260 [ 1112.703299] schedule_preempt_disabled+0x18/0x30 [ 1112.709277] __mutex_lock+0xd76/0x1f20 [ 1112.714282] ? threaded_store+0x132/0x220 [ 1112.719581] ? ww_mutex_lock+0x160/0x160 [ 1112.724782] ? selinux_inode_free_security+0x310/0x310 [ 1112.731352] ? cap_capable+0x1ed/0x3f0 [ 1112.736360] ? sysfs_file_ops+0x140/0x140 [ 1112.741660] ? threaded_store+0x132/0x220 [ 1112.746947] threaded_store+0x132/0x220 [ 1112.752050] ? tx_maxrate_store+0x4e0/0x4e0 [ 1112.757544] ? sysfs_file_ops+0x140/0x140 [ 1112.762845] ? sysfs_file_kobj+0xbd/0x1e0 [ 1112.768142] ? sysfs_kf_write+0x65/0x160 [ 1112.773335] kernfs_fop_write_iter+0x335/0x510 [ 1112.779122] ? kernfs_ops+0xf0/0xf0 [ 1112.783835] new_sync_write+0x308/0x6f0 [ 1112.788939] ? new_sync_read+0x6f0/0x6f0 [ 1112.794131] ? lock_acquire.part.0+0xbc/0x260 [ 1112.799818] ? ksys_write+0xff/0x200 [ 1112.804630] vfs_write+0x62a/0x980 [ 1112.809245] ksys_write+0xff/0x200 [ 1112.813851] ? __ia32_sys_read+0xc0/0xc0 [ 1112.819040] ? kernfs_fop_llseek+0x160/0x1f0 [ 1112.824628] ? rcu_is_watching+0x15/0xd0 [ 1112.829830] do_syscall_64+0xf3/0x5e0 [ 1112.834736] ? exc_page_fault+0x9e/0xb0 [ 1112.839841] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1112.846307] RIP: 0033:0x7f734f414c5e [ 1112.851120] RSP: 002b:00007ffceadd26c0 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1112.860419] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f734f414c5e [ 1112.869230] RDX: 0000000000000001 RSI: 00005598489c0110 RDI: 0000000000000007 [ 1112.878037] RBP: 00007ffceadd26d0 R08: 0000000000000000 R09: 0000000000000000 [ 1112.886847] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f734f2afe80 [ 1112.895657] R13: 00005598489c0110 R14: 0000000000000007 R15: 00007f734fb15b98 | [ 1113.266131] #1: ff110001a0980888 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x247/0x510 | [ 1113.277092] #2: ff11000121fa50f0 (kn->active#127){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1113.288243] #3: ff11000122421cb8 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1113.298338] | [ 1113.300806] =============================================\x0a [ 1235.513544] INFO: task python3:5067 blocked for more than 368 seconds. [ 1235.521695] Not tainted 7.0.0-rc4-babn-g3ea2a328f638 #1 [ 1235.528944] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1235.538524] task:python3 state:D stack:25312 pid:5067 tgid:5067 ppid:5066 task_flags:0x400100 flags:0x00080002 [ 1235.551732] Call Trace: [ 1235.555272] [ 1235.558424] __schedule+0xad9/0x1490 [ 1235.563241] ? io_schedule_timeout+0x130/0x130 [ 1235.569025] ? find_held_lock+0x2b/0x80 [ 1235.574133] ? __lock_release.isra.0+0x59/0x170 [ 1235.580015] schedule+0xd4/0x260 [ 1235.584431] schedule_preempt_disabled+0x18/0x30 [ 1235.590410] __mutex_lock+0xd76/0x1f20 [ 1235.595413] ? threaded_store+0x132/0x220 [ 1235.600701] ? ww_mutex_lock+0x160/0x160 [ 1235.605901] ? selinux_inode_free_security+0x310/0x310 [ 1235.612469] ? cap_capable+0x1ed/0x3f0 [ 1235.617476] ? sysfs_file_ops+0x140/0x140 [ 1235.622778] ? threaded_store+0x132/0x220 [ 1235.628074] threaded_store+0x132/0x220 [ 1235.633176] ? tx_maxrate_store+0x4e0/0x4e0 [ 1235.638666] ? sysfs_file_ops+0x140/0x140 [ 1235.643962] ? sysfs_file_kobj+0xbd/0x1e0 [ 1235.649257] ? sysfs_kf_write+0x65/0x160 [ 1235.654457] kernfs_fop_write_iter+0x335/0x510 [ 1235.660234] ? kernfs_ops+0xf0/0xf0 [ 1235.664933] new_sync_write+0x308/0x6f0 [ 1235.670036] ? new_sync_read+0x6f0/0x6f0 [ 1235.675235] ? lock_acquire.part.0+0xbc/0x260 [ 1235.680911] ? ksys_write+0xff/0x200 [ 1235.685723] vfs_write+0x62a/0x980 [ 1235.690338] ksys_write+0xff/0x200 [ 1235.694953] ? __ia32_sys_read+0xc0/0xc0 [ 1235.700149] ? kernfs_fop_llseek+0x160/0x1f0 [ 1235.705737] ? rcu_is_watching+0x15/0xd0 [ 1235.710937] do_syscall_64+0xf3/0x5e0 [ 1235.715842] ? exc_page_fault+0x9e/0xb0 [ 1235.720944] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1235.727410] RIP: 0033:0x7f734f414c5e [ 1235.732217] RSP: 002b:00007ffceadd26c0 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1235.741515] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f734f414c5e [ 1235.750321] RDX: 0000000000000001 RSI: 00005598489c0110 RDI: 0000000000000007 [ 1235.759130] RBP: 00007ffceadd26d0 R08: 0000000000000000 R09: 0000000000000000 [ 1235.767939] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f734f2afe80 [ 1235.776744] R13: 00005598489c0110 R14: 0000000000000007 R15: 00007f734fb15b98 | [ 1236.147173] #1: ff110001a0980888 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x247/0x510 | [ 1236.158134] #2: ff11000121fa50f0 (kn->active#127){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1236.169292] #3: ff11000122421cb8 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1236.179388] | [ 1236.181852] =============================================\x0a [ 1358.394694] INFO: task python3:5067 blocked for more than 491 seconds. [ 1358.402840] Not tainted 7.0.0-rc4-babn-g3ea2a328f638 #1 [ 1358.410089] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1358.419675] task:python3 state:D stack:25312 pid:5067 tgid:5067 ppid:5066 task_flags:0x400100 flags:0x00080002 [ 1358.432883] Call Trace: [ 1358.436422] [ 1358.439575] __schedule+0xad9/0x1490 [ 1358.444388] ? io_schedule_timeout+0x130/0x130 [ 1358.450173] ? find_held_lock+0x2b/0x80 [ 1358.455279] ? __lock_release.isra.0+0x59/0x170 [ 1358.461162] schedule+0xd4/0x260 [ 1358.465577] schedule_preempt_disabled+0x18/0x30 [ 1358.471553] __mutex_lock+0xd76/0x1f20 [ 1358.476556] ? threaded_store+0x132/0x220 [ 1358.481854] ? ww_mutex_lock+0x160/0x160 [ 1358.487049] ? selinux_inode_free_security+0x310/0x310 [ 1358.493618] ? cap_capable+0x1ed/0x3f0 [ 1358.498627] ? sysfs_file_ops+0x140/0x140 [ 1358.503927] ? threaded_store+0x132/0x220 [ 1358.509221] threaded_store+0x132/0x220 [ 1358.514323] ? tx_maxrate_store+0x4e0/0x4e0 [ 1358.519812] ? sysfs_file_ops+0x140/0x140 [ 1358.525107] ? sysfs_file_kobj+0xbd/0x1e0 [ 1358.530402] ? sysfs_kf_write+0x65/0x160 [ 1358.535602] kernfs_fop_write_iter+0x335/0x510 [ 1358.541379] ? kernfs_ops+0xf0/0xf0 [ 1358.546087] new_sync_write+0x308/0x6f0 [ 1358.551189] ? new_sync_read+0x6f0/0x6f0 [ 1358.556390] ? lock_acquire.part.0+0xbc/0x260 [ 1358.562076] ? ksys_write+0xff/0x200 [ 1358.566886] vfs_write+0x62a/0x980 [ 1358.571491] ksys_write+0xff/0x200 [ 1358.576104] ? __ia32_sys_read+0xc0/0xc0 [ 1358.581300] ? kernfs_fop_llseek+0x160/0x1f0 [ 1358.586887] ? rcu_is_watching+0x15/0xd0 [ 1358.592088] do_syscall_64+0xf3/0x5e0 [ 1358.596993] ? exc_page_fault+0x9e/0xb0 [ 1358.602093] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1358.608558] RIP: 0033:0x7f734f414c5e [ 1358.613366] RSP: 002b:00007ffceadd26c0 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1358.622663] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f734f414c5e [ 1358.631463] RDX: 0000000000000001 RSI: 00005598489c0110 RDI: 0000000000000007 [ 1358.640268] RBP: 00007ffceadd26d0 R08: 0000000000000000 R09: 0000000000000000 [ 1358.649073] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f734f2afe80 [ 1358.657878] R13: 00005598489c0110 R14: 0000000000000007 R15: 00007f734fb15b98 Finger prints: __schedule:schedule:schedule_preempt_disabled:__mutex_lock:threaded_store