====================================== | [ 760.737152] nipa-hw-worker: drivers/net:napi_threaded.py: timed out | [ 760.739688] nipa-hw-worker: [4/39] Retrying drivers/net:napi_threaded.py | [ 760.873135] kselftest: Running tests in drivers/net [ 989.746226] INFO: task python3:5028 blocked for more than 122 seconds. [ 989.754455] Not tainted 7.0.0-rc5-lxbh-g5642b8ed565c #1 [ 989.761707] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 989.771298] task:python3 state:D stack:25312 pid:5028 tgid:5028 ppid:5027 task_flags:0x400100 flags:0x00080000 [ 989.784505] Call Trace: [ 989.788050] [ 989.791202] __schedule+0xad9/0x1490 [ 989.796018] ? io_schedule_timeout+0x130/0x130 [ 989.801804] ? find_held_lock+0x2b/0x80 [ 989.806910] ? __lock_release.isra.0+0x59/0x170 [ 989.812798] schedule+0xd4/0x260 [ 989.817218] schedule_preempt_disabled+0x18/0x30 [ 989.823198] __mutex_lock+0xd76/0x1f20 [ 989.828204] ? threaded_store+0x132/0x220 [ 989.833505] ? ww_mutex_lock+0x160/0x160 [ 989.838707] ? selinux_inode_free_security+0x310/0x310 [ 989.845279] ? cap_capable+0x1ed/0x3f0 [ 989.850286] ? sysfs_file_ops+0x140/0x140 [ 989.855584] ? threaded_store+0x132/0x220 [ 989.860881] threaded_store+0x132/0x220 [ 989.865985] ? tx_maxrate_store+0x4e0/0x4e0 [ 989.871480] ? sysfs_file_ops+0x140/0x140 [ 989.876779] ? sysfs_file_kobj+0xbd/0x1e0 [ 989.882066] ? sysfs_kf_write+0x65/0x160 [ 989.887269] kernfs_fop_write_iter+0x335/0x510 [ 989.893058] ? kernfs_ops+0xf0/0xf0 [ 989.897769] new_sync_write+0x308/0x6f0 [ 989.902874] ? new_sync_read+0x6f0/0x6f0 [ 989.908076] ? lock_acquire.part.0+0xbc/0x260 [ 989.913765] ? ksys_write+0xff/0x200 [ 989.918579] vfs_write+0x62a/0x980 [ 989.923196] ksys_write+0xff/0x200 [ 989.927810] ? __ia32_sys_read+0xc0/0xc0 [ 989.933008] ? kernfs_fop_llseek+0x160/0x1f0 [ 989.938597] ? rcu_is_watching+0x15/0xd0 [ 989.943799] do_syscall_64+0xf3/0x5e0 [ 989.948705] ? exc_page_fault+0x9e/0xb0 [ 989.953810] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 989.960281] RIP: 0033:0x7f0619ec7c5e [ 989.965090] RSP: 002b:00007ffebd04c730 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 989.974388] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f0619ec7c5e [ 989.983196] RDX: 0000000000000001 RSI: 000055bf1a27b760 RDI: 0000000000000007 [ 989.992002] RBP: 00007ffebd04c740 R08: 0000000000000000 R09: 0000000000000000 [ 990.000809] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f0619d62e80 [ 990.009620] R13: 000055bf1a27b760 R14: 0000000000000007 R15: 00007f061a5c8b98 | [ 990.380109] #1: ff110001a3bfa688 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x247/0x510 | [ 990.391066] #2: ff1100012296b0f0 (kn->active#127){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 990.402225] #3: ff110001229a9d00 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 990.412318] | [ 990.414783] =============================================\x0a [ 1112.628440] INFO: task python3:5028 blocked for more than 245 seconds. [ 1112.636593] Not tainted 7.0.0-rc5-lxbh-g5642b8ed565c #1 [ 1112.643844] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1112.653435] task:python3 state:D stack:25312 pid:5028 tgid:5028 ppid:5027 task_flags:0x400100 flags:0x00080000 [ 1112.666642] Call Trace: [ 1112.670183] [ 1112.673335] __schedule+0xad9/0x1490 [ 1112.678152] ? io_schedule_timeout+0x130/0x130 [ 1112.683939] ? find_held_lock+0x2b/0x80 [ 1112.689046] ? __lock_release.isra.0+0x59/0x170 [ 1112.694935] schedule+0xd4/0x260 [ 1112.699357] schedule_preempt_disabled+0x18/0x30 [ 1112.705338] __mutex_lock+0xd76/0x1f20 [ 1112.710346] ? threaded_store+0x132/0x220 [ 1112.715644] ? ww_mutex_lock+0x160/0x160 [ 1112.720843] ? selinux_inode_free_security+0x310/0x310 [ 1112.727416] ? cap_capable+0x1ed/0x3f0 [ 1112.732497] ? sysfs_file_ops+0x140/0x140 [ 1112.737789] ? threaded_store+0x132/0x220 [ 1112.743078] threaded_store+0x132/0x220 [ 1112.748183] ? tx_maxrate_store+0x4e0/0x4e0 [ 1112.753679] ? sysfs_file_ops+0x140/0x140 [ 1112.758976] ? sysfs_file_kobj+0xbd/0x1e0 [ 1112.764272] ? sysfs_kf_write+0x65/0x160 [ 1112.769473] kernfs_fop_write_iter+0x335/0x510 [ 1112.775266] ? kernfs_ops+0xf0/0xf0 [ 1112.779978] new_sync_write+0x308/0x6f0 [ 1112.785083] ? new_sync_read+0x6f0/0x6f0 [ 1112.790277] ? lock_acquire.part.0+0xbc/0x260 [ 1112.795963] ? ksys_write+0xff/0x200 [ 1112.800778] vfs_write+0x62a/0x980 [ 1112.805395] ksys_write+0xff/0x200 [ 1112.810008] ? __ia32_sys_read+0xc0/0xc0 [ 1112.815208] ? kernfs_fop_llseek+0x160/0x1f0 [ 1112.820799] ? rcu_is_watching+0x15/0xd0 [ 1112.826003] do_syscall_64+0xf3/0x5e0 [ 1112.830910] ? exc_page_fault+0x9e/0xb0 [ 1112.836014] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1112.842482] RIP: 0033:0x7f0619ec7c5e [ 1112.847291] RSP: 002b:00007ffebd04c730 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1112.856591] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f0619ec7c5e [ 1112.865401] RDX: 0000000000000001 RSI: 000055bf1a27b760 RDI: 0000000000000007 [ 1112.874209] RBP: 00007ffebd04c740 R08: 0000000000000000 R09: 0000000000000000 [ 1112.883009] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f0619d62e80 [ 1112.891888] R13: 000055bf1a27b760 R14: 0000000000000007 R15: 00007f061a5c8b98 | [ 1113.278230] #1: ff110001a3bfa688 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x247/0x510 | [ 1113.289193] #2: ff1100012296b0f0 (kn->active#127){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1113.300350] #3: ff110001229a9d00 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1113.310508] | [ 1113.312975] =============================================\x0a [ 1235.508627] INFO: task python3:5028 blocked for more than 368 seconds. [ 1235.516767] Not tainted 7.0.0-rc5-lxbh-g5642b8ed565c #1 [ 1235.524017] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1235.533606] task:python3 state:D stack:25312 pid:5028 tgid:5028 ppid:5027 task_flags:0x400100 flags:0x00080002 [ 1235.546813] Call Trace: [ 1235.550354] [ 1235.553505] __schedule+0xad9/0x1490 [ 1235.558324] ? io_schedule_timeout+0x130/0x130 [ 1235.564112] ? find_held_lock+0x2b/0x80 [ 1235.569218] ? __lock_release.isra.0+0x59/0x170 [ 1235.575102] schedule+0xd4/0x260 [ 1235.579520] schedule_preempt_disabled+0x18/0x30 [ 1235.585500] __mutex_lock+0xd76/0x1f20 [ 1235.590505] ? threaded_store+0x132/0x220 [ 1235.595806] ? ww_mutex_lock+0x160/0x160 [ 1235.601006] ? selinux_inode_free_security+0x310/0x310 [ 1235.607577] ? cap_capable+0x1ed/0x3f0 [ 1235.612586] ? sysfs_file_ops+0x140/0x140 [ 1235.617888] ? threaded_store+0x132/0x220 [ 1235.623183] threaded_store+0x132/0x220 [ 1235.628291] ? tx_maxrate_store+0x4e0/0x4e0 [ 1235.633786] ? sysfs_file_ops+0x140/0x140 [ 1235.639080] ? sysfs_file_kobj+0xbd/0x1e0 [ 1235.644377] ? sysfs_kf_write+0x65/0x160 [ 1235.649578] kernfs_fop_write_iter+0x335/0x510 [ 1235.655366] ? kernfs_ops+0xf0/0xf0 [ 1235.660079] new_sync_write+0x308/0x6f0 [ 1235.665183] ? new_sync_read+0x6f0/0x6f0 [ 1235.670386] ? lock_acquire.part.0+0xbc/0x260 [ 1235.676073] ? ksys_write+0xff/0x200 [ 1235.680884] vfs_write+0x62a/0x980 [ 1235.685499] ksys_write+0xff/0x200 [ 1235.690115] ? __ia32_sys_read+0xc0/0xc0 [ 1235.695316] ? kernfs_fop_llseek+0x160/0x1f0 [ 1235.700909] ? rcu_is_watching+0x15/0xd0 [ 1235.706110] do_syscall_64+0xf3/0x5e0 [ 1235.711015] ? exc_page_fault+0x9e/0xb0 [ 1235.716118] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1235.722589] RIP: 0033:0x7f0619ec7c5e [ 1235.727401] RSP: 002b:00007ffebd04c730 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1235.736700] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f0619ec7c5e [ 1235.745510] RDX: 0000000000000001 RSI: 000055bf1a27b760 RDI: 0000000000000007 [ 1235.754321] RBP: 00007ffebd04c740 R08: 0000000000000000 R09: 0000000000000000 [ 1235.763129] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f0619d62e80 [ 1235.771940] R13: 000055bf1a27b760 R14: 0000000000000007 R15: 00007f061a5c8b98 | [ 1236.148431] #1: ff110001a3bfa688 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x247/0x510 | [ 1236.159394] #2: ff1100012296b0f0 (kn->active#127){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1236.170553] #3: ff110001229a9d00 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1236.180648] | [ 1236.183116] =============================================\x0a [ 1358.390831] INFO: task python3:5028 blocked for more than 491 seconds. [ 1358.398978] Not tainted 7.0.0-rc5-lxbh-g5642b8ed565c #1 [ 1358.406219] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1358.415808] task:python3 state:D stack:25312 pid:5028 tgid:5028 ppid:5027 task_flags:0x400100 flags:0x00080002 [ 1358.429013] Call Trace: [ 1358.432553] [ 1358.435702] __schedule+0xad9/0x1490 [ 1358.440519] ? io_schedule_timeout+0x130/0x130 [ 1358.446302] ? find_held_lock+0x2b/0x80 [ 1358.451396] ? __lock_release.isra.0+0x59/0x170 [ 1358.457282] schedule+0xd4/0x260 [ 1358.461699] schedule_preempt_disabled+0x18/0x30 [ 1358.467681] __mutex_lock+0xd76/0x1f20 [ 1358.472686] ? threaded_store+0x132/0x220 [ 1358.477986] ? ww_mutex_lock+0x160/0x160 [ 1358.483186] ? selinux_inode_free_security+0x310/0x310 [ 1358.489755] ? cap_capable+0x1ed/0x3f0 [ 1358.494759] ? sysfs_file_ops+0x140/0x140 [ 1358.500059] ? threaded_store+0x132/0x220 [ 1358.505345] threaded_store+0x132/0x220 [ 1358.510450] ? tx_maxrate_store+0x4e0/0x4e0 [ 1358.515944] ? sysfs_file_ops+0x140/0x140 [ 1358.521238] ? sysfs_file_kobj+0xbd/0x1e0 [ 1358.526533] ? sysfs_kf_write+0x65/0x160 [ 1358.531731] kernfs_fop_write_iter+0x335/0x510 [ 1358.537513] ? kernfs_ops+0xf0/0xf0 [ 1358.542222] new_sync_write+0x308/0x6f0 [ 1358.547327] ? new_sync_read+0x6f0/0x6f0 [ 1358.552529] ? lock_acquire.part.0+0xbc/0x260 [ 1358.558216] ? ksys_write+0xff/0x200 [ 1358.563027] vfs_write+0x62a/0x980 [ 1358.567642] ksys_write+0xff/0x200 [ 1358.572256] ? __ia32_sys_read+0xc0/0xc0 [ 1358.577452] ? kernfs_fop_llseek+0x160/0x1f0 [ 1358.583039] ? rcu_is_watching+0x15/0xd0 [ 1358.588240] do_syscall_64+0xf3/0x5e0 [ 1358.593146] ? exc_page_fault+0x9e/0xb0 [ 1358.598245] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1358.604710] RIP: 0033:0x7f0619ec7c5e [ 1358.609518] RSP: 002b:00007ffebd04c730 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1358.618815] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f0619ec7c5e [ 1358.627622] RDX: 0000000000000001 RSI: 000055bf1a27b760 RDI: 0000000000000007 [ 1358.636431] RBP: 00007ffebd04c740 R08: 0000000000000000 R09: 0000000000000000 [ 1358.645237] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f0619d62e80 [ 1358.654044] R13: 000055bf1a27b760 R14: 0000000000000007 R15: 00007f061a5c8b98 Finger prints: __schedule:schedule:schedule_preempt_disabled:__mutex_lock:threaded_store