====================================== | [ 780.753862] nipa-hw-worker: drivers/net:napi_threaded.py: timed out | [ 780.757240] nipa-hw-worker: [5/46] Retrying drivers/net:napi_threaded.py | [ 780.911127] kselftest: Running tests in drivers/net [ 989.697815] INFO: task python3:5656 blocked for more than 122 seconds. [ 989.706048] Not tainted 7.1.0-rc2-jcmo-g6bb4fb3b2b96 #1 [ 989.714013] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 989.723614] task:python3 state:D stack:25672 pid:5656 tgid:5656 ppid:5655 task_flags:0x400100 flags:0x00080000 [ 989.736825] Call Trace: [ 989.740366] [ 989.743519] __schedule+0xcba/0x1940 [ 989.748336] ? io_schedule_timeout+0x140/0x140 [ 989.754125] ? find_held_lock+0x2b/0x80 [ 989.759229] ? __lock_release.isra.0+0x6b/0x1a0 [ 989.765114] schedule+0xd4/0x260 [ 989.769533] schedule_preempt_disabled+0x18/0x30 [ 989.775513] __mutex_lock+0x6a3/0x2000 [ 989.780511] ? threaded_store+0x132/0x220 [ 989.785813] ? ww_mutex_lock+0x160/0x160 [ 989.791014] ? selinux_inode_free_security+0x330/0x330 [ 989.797586] ? cap_capable+0x1d6/0x3e0 [ 989.802595] ? threaded_store+0x132/0x220 [ 989.807893] threaded_store+0x132/0x220 [ 989.812996] ? xps_rxqs_store+0x2e0/0x2e0 [ 989.818296] ? sysfs_file_kobj+0xbd/0x1e0 [ 989.823596] ? sysfs_kf_write+0x65/0x160 [ 989.828797] ? sysfs_file_ops+0x140/0x140 [ 989.834094] kernfs_fop_write_iter+0x335/0x510 [ 989.839879] ? kernfs_ops+0xf0/0xf0 [ 989.844592] new_sync_write+0x333/0x750 [ 989.849697] ? __lock_acquire+0x508/0xc10 [ 989.854993] ? new_sync_read+0x740/0x740 [ 989.860196] ? lock_acquire.part.0+0xbc/0x260 [ 989.865885] ? ksys_write+0x116/0x250 [ 989.870797] vfs_write+0x657/0x9b0 [ 989.875414] ksys_write+0x116/0x250 [ 989.880129] ? __ia32_sys_read+0xc0/0xc0 [ 989.885327] ? kernfs_fop_llseek+0x160/0x1f0 [ 989.890919] ? rcu_is_watching+0x15/0xd0 [ 989.896118] do_syscall_64+0xf3/0x650 [ 989.901024] ? trace_hardirqs_off+0xd/0x30 [ 989.906421] ? exc_page_fault+0xda/0xf0 [ 989.911522] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 989.917993] RIP: 0033:0x7f3da634222e [ 989.922804] RSP: 002b:00007ffcb8d3acb0 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 989.932105] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f3da634222e [ 989.940916] RDX: 0000000000000001 RSI: 000055d958caefe0 RDI: 0000000000000007 [ 989.949722] RBP: 00007ffcb8d3acc0 R08: 0000000000000000 R09: 0000000000000000 [ 989.958530] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f3da61ba390 [ 989.967338] R13: 000055d958caefe0 R14: 0000000000000007 R15: 0000000000000003 | [ 990.404142] #2: ff110017da6d8b50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 990.415299] #3: ff110017da769d68 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 990.425386] | [ 990.427853] =============================================\x0a | [ 1036.290275] kworker/u129:0 (177) used greatest stack depth: 21936 bytes left [ 1112.579202] INFO: task python3:5656 blocked for more than 245 seconds. [ 1112.587365] Not tainted 7.1.0-rc2-jcmo-g6bb4fb3b2b96 #1 [ 1112.594619] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1112.604211] task:python3 state:D stack:25672 pid:5656 tgid:5656 ppid:5655 task_flags:0x400100 flags:0x00080000 [ 1112.617420] Call Trace: [ 1112.620961] [ 1112.624114] __schedule+0xcba/0x1940 [ 1112.628933] ? io_schedule_timeout+0x140/0x140 [ 1112.634718] ? find_held_lock+0x2b/0x80 [ 1112.639826] ? __lock_release.isra.0+0x6b/0x1a0 [ 1112.645711] schedule+0xd4/0x260 [ 1112.650132] schedule_preempt_disabled+0x18/0x30 [ 1112.656113] __mutex_lock+0x6a3/0x2000 [ 1112.661116] ? threaded_store+0x132/0x220 [ 1112.666417] ? ww_mutex_lock+0x160/0x160 [ 1112.671617] ? selinux_inode_free_security+0x330/0x330 [ 1112.678185] ? cap_capable+0x1d6/0x3e0 [ 1112.683194] ? threaded_store+0x132/0x220 [ 1112.688490] threaded_store+0x132/0x220 [ 1112.693594] ? xps_rxqs_store+0x2e0/0x2e0 [ 1112.698892] ? sysfs_file_kobj+0xbd/0x1e0 [ 1112.704192] ? sysfs_kf_write+0x65/0x160 [ 1112.709391] ? sysfs_file_ops+0x140/0x140 [ 1112.714688] kernfs_fop_write_iter+0x335/0x510 [ 1112.720475] ? kernfs_ops+0xf0/0xf0 [ 1112.725185] new_sync_write+0x333/0x750 [ 1112.730280] ? __lock_acquire+0x508/0xc10 [ 1112.735577] ? new_sync_read+0x740/0x740 [ 1112.740779] ? lock_acquire.part.0+0xbc/0x260 [ 1112.746468] ? ksys_write+0x116/0x250 [ 1112.751381] vfs_write+0x657/0x9b0 [ 1112.755999] ksys_write+0x116/0x250 [ 1112.760712] ? __ia32_sys_read+0xc0/0xc0 [ 1112.765904] ? kernfs_fop_llseek+0x160/0x1f0 [ 1112.771494] ? rcu_is_watching+0x15/0xd0 [ 1112.776698] do_syscall_64+0xf3/0x650 [ 1112.781607] ? trace_hardirqs_off+0xd/0x30 [ 1112.787002] ? exc_page_fault+0xda/0xf0 [ 1112.792105] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1112.798576] RIP: 0033:0x7f3da634222e [ 1112.803387] RSP: 002b:00007ffcb8d3acb0 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1112.812685] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f3da634222e [ 1112.821495] RDX: 0000000000000001 RSI: 000055d958caefe0 RDI: 0000000000000007 [ 1112.830304] RBP: 00007ffcb8d3acc0 R08: 0000000000000000 R09: 0000000000000000 [ 1112.839113] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f3da61ba390 [ 1112.847922] R13: 000055d958caefe0 R14: 0000000000000007 R15: 0000000000000003 | [ 1113.219219] #1: ff110001e9bdb880 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 1113.230183] #2: ff110017da6d8b50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1113.241343] #3: ff110017da769d68 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1113.251432] | [ 1113.253898] =============================================\x0a [ 1235.460554] INFO: task python3:5656 blocked for more than 368 seconds. [ 1235.468702] Not tainted 7.1.0-rc2-jcmo-g6bb4fb3b2b96 #1 [ 1235.475954] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1235.485544] task:python3 state:D stack:25672 pid:5656 tgid:5656 ppid:5655 task_flags:0x400100 flags:0x00080002 [ 1235.498750] Call Trace: [ 1235.502292] [ 1235.505442] __schedule+0xcba/0x1940 [ 1235.510258] ? io_schedule_timeout+0x140/0x140 [ 1235.516045] ? find_held_lock+0x2b/0x80 [ 1235.521150] ? __lock_release.isra.0+0x6b/0x1a0 [ 1235.527036] schedule+0xd4/0x260 [ 1235.531455] schedule_preempt_disabled+0x18/0x30 [ 1235.537434] __mutex_lock+0x6a3/0x2000 [ 1235.542438] ? threaded_store+0x132/0x220 [ 1235.547729] ? ww_mutex_lock+0x160/0x160 [ 1235.552930] ? selinux_inode_free_security+0x330/0x330 [ 1235.559500] ? cap_capable+0x1d6/0x3e0 [ 1235.564511] ? threaded_store+0x132/0x220 [ 1235.569805] threaded_store+0x132/0x220 [ 1235.574907] ? xps_rxqs_store+0x2e0/0x2e0 [ 1235.580200] ? sysfs_file_kobj+0xbd/0x1e0 [ 1235.585500] ? sysfs_kf_write+0x65/0x160 [ 1235.590703] ? sysfs_file_ops+0x140/0x140 [ 1235.596002] kernfs_fop_write_iter+0x335/0x510 [ 1235.601789] ? kernfs_ops+0xf0/0xf0 [ 1235.606501] new_sync_write+0x333/0x750 [ 1235.611606] ? __lock_acquire+0x508/0xc10 [ 1235.616904] ? new_sync_read+0x740/0x740 [ 1235.622107] ? lock_acquire.part.0+0xbc/0x260 [ 1235.627794] ? ksys_write+0x116/0x250 [ 1235.632696] vfs_write+0x657/0x9b0 [ 1235.637310] ksys_write+0x116/0x250 [ 1235.642024] ? __ia32_sys_read+0xc0/0xc0 [ 1235.647223] ? kernfs_fop_llseek+0x160/0x1f0 [ 1235.652813] ? rcu_is_watching+0x15/0xd0 [ 1235.658015] do_syscall_64+0xf3/0x650 [ 1235.662924] ? trace_hardirqs_off+0xd/0x30 [ 1235.668320] ? exc_page_fault+0xda/0xf0 [ 1235.673413] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1235.679884] RIP: 0033:0x7f3da634222e [ 1235.684695] RSP: 002b:00007ffcb8d3acb0 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1235.693995] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f3da634222e [ 1235.702806] RDX: 0000000000000001 RSI: 000055d958caefe0 RDI: 0000000000000007 [ 1235.711612] RBP: 00007ffcb8d3acc0 R08: 0000000000000000 R09: 0000000000000000 [ 1235.720419] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f3da61ba390 [ 1235.729230] R13: 000055d958caefe0 R14: 0000000000000007 R15: 0000000000000003 | [ 1236.100571] #1: ff110001e9bdb880 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 1236.111534] #2: ff110017da6d8b50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1236.122691] #3: ff110017da769d68 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1236.132775] | [ 1236.135243] =============================================\x0a [ 1358.341923] INFO: task python3:5656 blocked for more than 491 seconds. [ 1358.350073] Not tainted 7.1.0-rc2-jcmo-g6bb4fb3b2b96 #1 [ 1358.357324] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1358.366914] task:python3 state:D stack:25672 pid:5656 tgid:5656 ppid:5655 task_flags:0x400100 flags:0x00080002 [ 1358.380119] Call Trace: [ 1358.383660] [ 1358.386814] __schedule+0xcba/0x1940 [ 1358.391633] ? io_schedule_timeout+0x140/0x140 [ 1358.397420] ? find_held_lock+0x2b/0x80 [ 1358.402529] ? __lock_release.isra.0+0x6b/0x1a0 [ 1358.408413] schedule+0xd4/0x260 [ 1358.412830] schedule_preempt_disabled+0x18/0x30 [ 1358.418808] __mutex_lock+0x6a3/0x2000 [ 1358.423812] ? threaded_store+0x132/0x220 [ 1358.429101] ? ww_mutex_lock+0x160/0x160 [ 1358.434302] ? selinux_inode_free_security+0x330/0x330 [ 1358.440873] ? cap_capable+0x1d6/0x3e0 [ 1358.445882] ? threaded_store+0x132/0x220 [ 1358.451180] threaded_store+0x132/0x220 [ 1358.456273] ? xps_rxqs_store+0x2e0/0x2e0 [ 1358.461571] ? sysfs_file_kobj+0xbd/0x1e0 [ 1358.466871] ? sysfs_kf_write+0x65/0x160 [ 1358.472074] ? sysfs_file_ops+0x140/0x140 [ 1358.477369] kernfs_fop_write_iter+0x335/0x510 [ 1358.483152] ? kernfs_ops+0xf0/0xf0 [ 1358.487865] new_sync_write+0x333/0x750 [ 1358.492967] ? __lock_acquire+0x508/0xc10 [ 1358.498264] ? new_sync_read+0x740/0x740 [ 1358.503467] ? lock_acquire.part.0+0xbc/0x260 [ 1358.509156] ? ksys_write+0x116/0x250 [ 1358.514065] vfs_write+0x657/0x9b0 [ 1358.518680] ksys_write+0x116/0x250 [ 1358.523394] ? __ia32_sys_read+0xc0/0xc0 [ 1358.528592] ? kernfs_fop_llseek+0x160/0x1f0 [ 1358.534184] ? rcu_is_watching+0x15/0xd0 [ 1358.539386] do_syscall_64+0xf3/0x650 [ 1358.544291] ? trace_hardirqs_off+0xd/0x30 [ 1358.549688] ? exc_page_fault+0xda/0xf0 [ 1358.554781] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1358.561243] RIP: 0033:0x7f3da634222e [ 1358.566055] RSP: 002b:00007ffcb8d3acb0 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1358.575356] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f3da634222e [ 1358.584166] RDX: 0000000000000001 RSI: 000055d958caefe0 RDI: 0000000000000007 [ 1358.592975] RBP: 00007ffcb8d3acc0 R08: 0000000000000000 R09: 0000000000000000 [ 1358.601786] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f3da61ba390 [ 1358.610594] R13: 000055d958caefe0 R14: 0000000000000007 R15: 0000000000000003 Finger prints: __schedule:schedule:schedule_preempt_disabled:__mutex_lock:threaded_store