====================================== | [ 762.393937] nipa-hw-worker: drivers/net:napi_threaded.py: timed out | [ 762.400047] nipa-hw-worker: [4/39] Retrying drivers/net:napi_threaded.py | [ 762.536838] kselftest: Running tests in drivers/net [ 989.752962] INFO: task python3:5078 blocked for more than 122 seconds. [ 989.761182] Not tainted 7.0.0-rc4-zviy-g253dc35e0749 #1 [ 989.768430] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 989.778019] task:python3 state:D stack:25312 pid:5078 tgid:5078 ppid:5077 task_flags:0x400100 flags:0x00080000 [ 989.791224] Call Trace: [ 989.794762] [ 989.797913] __schedule+0xad9/0x1490 [ 989.802727] ? io_schedule_timeout+0x130/0x130 [ 989.808500] ? find_held_lock+0x2b/0x80 [ 989.813605] ? __lock_release.isra.0+0x59/0x170 [ 989.819490] schedule+0xd4/0x260 [ 989.823908] schedule_preempt_disabled+0x18/0x30 [ 989.829887] __mutex_lock+0xd76/0x1f20 [ 989.834893] ? threaded_store+0x132/0x220 [ 989.840193] ? ww_mutex_lock+0x160/0x160 [ 989.845389] ? selinux_inode_free_security+0x310/0x310 [ 989.851960] ? cap_capable+0x1ed/0x3f0 [ 989.856965] ? sysfs_file_ops+0x140/0x140 [ 989.862262] ? threaded_store+0x132/0x220 [ 989.867558] threaded_store+0x132/0x220 [ 989.872660] ? tx_maxrate_store+0x4e0/0x4e0 [ 989.878145] ? sysfs_file_ops+0x140/0x140 [ 989.883439] ? sysfs_file_kobj+0xbd/0x1e0 [ 989.888736] ? sysfs_kf_write+0x65/0x160 [ 989.893935] kernfs_fop_write_iter+0x335/0x510 [ 989.899719] ? kernfs_ops+0xf0/0xf0 [ 989.904429] new_sync_write+0x308/0x6f0 [ 989.909529] ? new_sync_read+0x6f0/0x6f0 [ 989.914728] ? lock_acquire.part.0+0xbc/0x260 [ 989.920403] ? ksys_write+0xff/0x200 [ 989.925217] vfs_write+0x62a/0x980 [ 989.929829] ksys_write+0xff/0x200 [ 989.934440] ? __ia32_sys_read+0xc0/0xc0 [ 989.939639] ? kernfs_fop_llseek+0x160/0x1f0 [ 989.945229] ? rcu_is_watching+0x15/0xd0 [ 989.950431] do_syscall_64+0xf3/0x5e0 [ 989.955337] ? exc_page_fault+0x9e/0xb0 [ 989.960437] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 989.966905] RIP: 0033:0x7f11edf0fc5e [ 989.971705] RSP: 002b:00007ffcceaefbe0 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 989.981001] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f11edf0fc5e [ 989.989809] RDX: 0000000000000001 RSI: 0000560033a5f110 RDI: 0000000000000007 [ 989.998609] RBP: 00007ffcceaefbf0 R08: 0000000000000000 R09: 0000000000000000 [ 990.007406] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f11eddaae80 [ 990.016213] R13: 0000560033a5f110 R14: 0000000000000007 R15: 00007f11ee610b98 | [ 990.386823] #1: ff110001a4e7e288 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x247/0x510 | [ 990.397787] #2: ff11000122ba70f0 (kn->active#127){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 990.408957] #3: ff11000122b59cb8 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 990.419046] | [ 990.421510] =============================================\x0a [ 1112.634260] INFO: task python3:5078 blocked for more than 245 seconds. [ 1112.642404] Not tainted 7.0.0-rc4-zviy-g253dc35e0749 #1 [ 1112.649653] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1112.659242] task:python3 state:D stack:25312 pid:5078 tgid:5078 ppid:5077 task_flags:0x400100 flags:0x00080000 [ 1112.672441] Call Trace: [ 1112.675982] [ 1112.679131] __schedule+0xad9/0x1490 [ 1112.683946] ? io_schedule_timeout+0x130/0x130 [ 1112.689731] ? find_held_lock+0x2b/0x80 [ 1112.694841] ? __lock_release.isra.0+0x59/0x170 [ 1112.700725] schedule+0xd4/0x260 [ 1112.705142] schedule_preempt_disabled+0x18/0x30 [ 1112.711120] __mutex_lock+0xd76/0x1f20 [ 1112.716125] ? threaded_store+0x132/0x220 [ 1112.721428] ? ww_mutex_lock+0x160/0x160 [ 1112.726628] ? selinux_inode_free_security+0x310/0x310 [ 1112.733197] ? cap_capable+0x1ed/0x3f0 [ 1112.738207] ? sysfs_file_ops+0x140/0x140 [ 1112.743508] ? threaded_store+0x132/0x220 [ 1112.748803] threaded_store+0x132/0x220 [ 1112.753909] ? tx_maxrate_store+0x4e0/0x4e0 [ 1112.759402] ? sysfs_file_ops+0x140/0x140 [ 1112.764700] ? sysfs_file_kobj+0xbd/0x1e0 [ 1112.769987] ? sysfs_kf_write+0x65/0x160 [ 1112.775188] kernfs_fop_write_iter+0x335/0x510 [ 1112.780975] ? kernfs_ops+0xf0/0xf0 [ 1112.785688] new_sync_write+0x308/0x6f0 [ 1112.790791] ? new_sync_read+0x6f0/0x6f0 [ 1112.795993] ? lock_acquire.part.0+0xbc/0x260 [ 1112.801678] ? ksys_write+0xff/0x200 [ 1112.806490] vfs_write+0x62a/0x980 [ 1112.811105] ksys_write+0xff/0x200 [ 1112.815718] ? __ia32_sys_read+0xc0/0xc0 [ 1112.820915] ? kernfs_fop_llseek+0x160/0x1f0 [ 1112.826508] ? rcu_is_watching+0x15/0xd0 [ 1112.831712] do_syscall_64+0xf3/0x5e0 [ 1112.836620] ? exc_page_fault+0x9e/0xb0 [ 1112.841723] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1112.848183] RIP: 0033:0x7f11edf0fc5e [ 1112.852994] RSP: 002b:00007ffcceaefbe0 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1112.862293] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f11edf0fc5e [ 1112.871104] RDX: 0000000000000001 RSI: 0000560033a5f110 RDI: 0000000000000007 [ 1112.879913] RBP: 00007ffcceaefbf0 R08: 0000000000000000 R09: 0000000000000000 [ 1112.888721] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f11eddaae80 [ 1112.897521] R13: 0000560033a5f110 R14: 0000000000000007 R15: 00007f11ee610b98 | [ 1113.268208] #1: ff110001a4e7e288 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x247/0x510 | [ 1113.279169] #2: ff11000122ba70f0 (kn->active#127){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1113.290327] #3: ff11000122b59cb8 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1113.300418] | [ 1113.302886] =============================================\x0a [ 1235.516548] INFO: task python3:5078 blocked for more than 368 seconds. [ 1235.524696] Not tainted 7.0.0-rc4-zviy-g253dc35e0749 #1 [ 1235.531945] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1235.541537] task:python3 state:D stack:25312 pid:5078 tgid:5078 ppid:5077 task_flags:0x400100 flags:0x00080002 [ 1235.554745] Call Trace: [ 1235.558285] [ 1235.561439] __schedule+0xad9/0x1490 [ 1235.566258] ? io_schedule_timeout+0x130/0x130 [ 1235.572044] ? find_held_lock+0x2b/0x80 [ 1235.577152] ? __lock_release.isra.0+0x59/0x170 [ 1235.583036] schedule+0xd4/0x260 [ 1235.587445] schedule_preempt_disabled+0x18/0x30 [ 1235.593422] __mutex_lock+0xd76/0x1f20 [ 1235.598431] ? threaded_store+0x132/0x220 [ 1235.603729] ? ww_mutex_lock+0x160/0x160 [ 1235.608929] ? selinux_inode_free_security+0x310/0x310 [ 1235.615499] ? cap_capable+0x1ed/0x3f0 [ 1235.620507] ? sysfs_file_ops+0x140/0x140 [ 1235.625807] ? threaded_store+0x132/0x220 [ 1235.631103] threaded_store+0x132/0x220 [ 1235.636206] ? tx_maxrate_store+0x4e0/0x4e0 [ 1235.641701] ? sysfs_file_ops+0x140/0x140 [ 1235.646999] ? sysfs_file_kobj+0xbd/0x1e0 [ 1235.652295] ? sysfs_kf_write+0x65/0x160 [ 1235.657495] kernfs_fop_write_iter+0x335/0x510 [ 1235.663273] ? kernfs_ops+0xf0/0xf0 [ 1235.667986] new_sync_write+0x308/0x6f0 [ 1235.673093] ? new_sync_read+0x6f0/0x6f0 [ 1235.678294] ? lock_acquire.part.0+0xbc/0x260 [ 1235.683981] ? ksys_write+0xff/0x200 [ 1235.688793] vfs_write+0x62a/0x980 [ 1235.693408] ksys_write+0xff/0x200 [ 1235.698021] ? __ia32_sys_read+0xc0/0xc0 [ 1235.703221] ? kernfs_fop_llseek+0x160/0x1f0 [ 1235.708813] ? rcu_is_watching+0x15/0xd0 [ 1235.714013] do_syscall_64+0xf3/0x5e0 [ 1235.718913] ? exc_page_fault+0x9e/0xb0 [ 1235.724016] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1235.730485] RIP: 0033:0x7f11edf0fc5e [ 1235.735295] RSP: 002b:00007ffcceaefbe0 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1235.744584] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f11edf0fc5e [ 1235.753395] RDX: 0000000000000001 RSI: 0000560033a5f110 RDI: 0000000000000007 [ 1235.762205] RBP: 00007ffcceaefbf0 R08: 0000000000000000 R09: 0000000000000000 [ 1235.771013] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f11eddaae80 [ 1235.779822] R13: 0000560033a5f110 R14: 0000000000000007 R15: 00007f11ee610b98 | [ 1236.150502] #1: ff110001a4e7e288 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x247/0x510 | [ 1236.161465] #2: ff11000122ba70f0 (kn->active#127){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1236.172622] #3: ff11000122b59cb8 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1236.182707] | [ 1236.185175] =============================================\x0a [ 1358.397842] INFO: task python3:5078 blocked for more than 491 seconds. [ 1358.405990] Not tainted 7.0.0-rc4-zviy-g253dc35e0749 #1 [ 1358.413241] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1358.422833] task:python3 state:D stack:25312 pid:5078 tgid:5078 ppid:5077 task_flags:0x400100 flags:0x00080002 [ 1358.436041] Call Trace: [ 1358.439582] [ 1358.442736] __schedule+0xad9/0x1490 [ 1358.447554] ? io_schedule_timeout+0x130/0x130 [ 1358.453341] ? find_held_lock+0x2b/0x80 [ 1358.458449] ? __lock_release.isra.0+0x59/0x170 [ 1358.464333] schedule+0xd4/0x260 [ 1358.468750] schedule_preempt_disabled+0x18/0x30 [ 1358.474731] __mutex_lock+0xd76/0x1f20 [ 1358.479740] ? threaded_store+0x132/0x220 [ 1358.485038] ? ww_mutex_lock+0x160/0x160 [ 1358.490237] ? selinux_inode_free_security+0x310/0x310 [ 1358.496806] ? cap_capable+0x1ed/0x3f0 [ 1358.501814] ? sysfs_file_ops+0x140/0x140 [ 1358.507114] ? threaded_store+0x132/0x220 [ 1358.512409] threaded_store+0x132/0x220 [ 1358.517514] ? tx_maxrate_store+0x4e0/0x4e0 [ 1358.523008] ? sysfs_file_ops+0x140/0x140 [ 1358.528305] ? sysfs_file_kobj+0xbd/0x1e0 [ 1358.533601] ? sysfs_kf_write+0x65/0x160 [ 1358.538802] kernfs_fop_write_iter+0x335/0x510 [ 1358.544589] ? kernfs_ops+0xf0/0xf0 [ 1358.549301] new_sync_write+0x308/0x6f0 [ 1358.554408] ? new_sync_read+0x6f0/0x6f0 [ 1358.559609] ? lock_acquire.part.0+0xbc/0x260 [ 1358.565295] ? ksys_write+0xff/0x200 [ 1358.570106] vfs_write+0x62a/0x980 [ 1358.574721] ksys_write+0xff/0x200 [ 1358.579334] ? __ia32_sys_read+0xc0/0xc0 [ 1358.584534] ? kernfs_fop_llseek+0x160/0x1f0 [ 1358.590126] ? rcu_is_watching+0x15/0xd0 [ 1358.595326] do_syscall_64+0xf3/0x5e0 [ 1358.600234] ? exc_page_fault+0x9e/0xb0 [ 1358.605337] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1358.611806] RIP: 0033:0x7f11edf0fc5e [ 1358.616617] RSP: 002b:00007ffcceaefbe0 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1358.625917] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f11edf0fc5e [ 1358.634726] RDX: 0000000000000001 RSI: 0000560033a5f110 RDI: 0000000000000007 [ 1358.643535] RBP: 00007ffcceaefbf0 R08: 0000000000000000 R09: 0000000000000000 [ 1358.652343] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f11eddaae80 [ 1358.661151] R13: 0000560033a5f110 R14: 0000000000000007 R15: 00007f11ee610b98 Finger prints: __schedule:schedule:schedule_preempt_disabled:__mutex_lock:threaded_store