====================================== | [ 765.601665] nipa-hw-worker: drivers/net:napi_threaded.py: timed out | [ 765.603988] nipa-hw-worker: [5/47] Retrying drivers/net:napi_threaded.py | [ 765.754310] kselftest: Running tests in drivers/net [ 989.681855] INFO: task python3:5485 blocked for more than 122 seconds. [ 989.690077] Not tainted 7.1.0-rc6-kazs-gac309bb0374c #1 [ 989.697972] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 989.707563] task:python3 state:D stack:25672 pid:5485 tgid:5485 ppid:5484 task_flags:0x400100 flags:0x00080000 [ 989.720768] Call Trace: [ 989.724305] [ 989.727454] __schedule+0xcc3/0x19c0 [ 989.732269] ? io_schedule_timeout+0x140/0x140 [ 989.738051] ? find_held_lock+0x2b/0x80 [ 989.743157] ? __lock_release.isra.0+0x6b/0x1a0 [ 989.749029] schedule+0xd8/0x260 [ 989.753448] schedule_preempt_disabled+0x18/0x30 [ 989.759417] __mutex_lock+0x6af/0x2010 [ 989.764422] ? threaded_store+0x132/0x220 [ 989.769722] ? ww_mutex_lock+0x160/0x160 [ 989.774919] ? selinux_inode_free_security+0x330/0x330 [ 989.781486] ? cap_capable+0x1d6/0x3e0 [ 989.786491] ? threaded_store+0x132/0x220 [ 989.791777] threaded_store+0x132/0x220 [ 989.796880] ? xps_rxqs_store+0x2e0/0x2e0 [ 989.802166] ? sysfs_file_kobj+0xbd/0x1e0 [ 989.807464] ? sysfs_kf_write+0x65/0x160 [ 989.812663] ? sysfs_file_ops+0x140/0x140 [ 989.817958] kernfs_fop_write_iter+0x335/0x510 [ 989.823740] ? kernfs_ops+0xf0/0xf0 [ 989.828448] new_sync_write+0x333/0x750 [ 989.833550] ? __lock_acquire+0x508/0xc10 [ 989.838848] ? new_sync_read+0x740/0x740 [ 989.844048] ? lock_acquire.part.0+0xbc/0x260 [ 989.849734] ? ksys_write+0x116/0x250 [ 989.854641] vfs_write+0x657/0x9b0 [ 989.859247] ksys_write+0x116/0x250 [ 989.863959] ? __ia32_sys_read+0xc0/0xc0 [ 989.869155] ? kernfs_fop_llseek+0x160/0x1f0 [ 989.874745] ? rcu_is_watching+0x15/0xd0 [ 989.879944] do_syscall_64+0xf3/0x650 [ 989.884848] ? trace_hardirqs_off+0xd/0x30 [ 989.890244] ? exc_page_fault+0xda/0xf0 [ 989.895344] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 989.901811] RIP: 0033:0x7f0896da964e [ 989.906620] RSP: 002b:00007fffc91f63e0 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 989.915915] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f0896da964e [ 989.924721] RDX: 0000000000000001 RSI: 0000555dfaf2ebf0 RDI: 0000000000000007 [ 989.933528] RBP: 00007fffc91f63f0 R08: 0000000000000000 R09: 0000000000000000 [ 989.942327] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f0896c22390 [ 989.951125] R13: 0000555dfaf2ebf0 R14: 0000000000000007 R15: 00007f0896bb5120 | [ 990.362552] #1: ff110001c08f5c80 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 990.373503] #2: ff11000123310b50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 990.384659] #3: ff110001232c1db8 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 990.394743] | [ 990.397207] =============================================\x0a [ 1112.563155] INFO: task python3:5485 blocked for more than 245 seconds. [ 1112.571301] Not tainted 7.1.0-rc6-kazs-gac309bb0374c #1 [ 1112.578552] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1112.588139] task:python3 state:D stack:25672 pid:5485 tgid:5485 ppid:5484 task_flags:0x400100 flags:0x00080000 [ 1112.601342] Call Trace: [ 1112.604881] [ 1112.608030] __schedule+0xcc3/0x19c0 [ 1112.612847] ? io_schedule_timeout+0x140/0x140 [ 1112.618630] ? find_held_lock+0x2b/0x80 [ 1112.623736] ? __lock_release.isra.0+0x6b/0x1a0 [ 1112.629621] schedule+0xd8/0x260 [ 1112.634037] schedule_preempt_disabled+0x18/0x30 [ 1112.640013] __mutex_lock+0x6af/0x2010 [ 1112.645016] ? threaded_store+0x132/0x220 [ 1112.650314] ? ww_mutex_lock+0x160/0x160 [ 1112.655513] ? selinux_inode_free_security+0x330/0x330 [ 1112.662082] ? cap_capable+0x1d6/0x3e0 [ 1112.667090] ? threaded_store+0x132/0x220 [ 1112.672385] threaded_store+0x132/0x220 [ 1112.677485] ? xps_rxqs_store+0x2e0/0x2e0 [ 1112.682782] ? sysfs_file_kobj+0xbd/0x1e0 [ 1112.688081] ? sysfs_kf_write+0x65/0x160 [ 1112.693272] ? sysfs_file_ops+0x140/0x140 [ 1112.698565] kernfs_fop_write_iter+0x335/0x510 [ 1112.704348] ? kernfs_ops+0xf0/0xf0 [ 1112.709059] new_sync_write+0x333/0x750 [ 1112.714150] ? __lock_acquire+0x508/0xc10 [ 1112.719446] ? new_sync_read+0x740/0x740 [ 1112.724649] ? lock_acquire.part.0+0xbc/0x260 [ 1112.730335] ? ksys_write+0x116/0x250 [ 1112.735245] vfs_write+0x657/0x9b0 [ 1112.739857] ksys_write+0x116/0x250 [ 1112.744566] ? __ia32_sys_read+0xc0/0xc0 [ 1112.749764] ? kernfs_fop_llseek+0x160/0x1f0 [ 1112.755351] ? rcu_is_watching+0x15/0xd0 [ 1112.760552] do_syscall_64+0xf3/0x650 [ 1112.765457] ? trace_hardirqs_off+0xd/0x30 [ 1112.770849] ? exc_page_fault+0xda/0xf0 [ 1112.775951] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1112.782418] RIP: 0033:0x7f0896da964e [ 1112.787229] RSP: 002b:00007fffc91f63e0 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1112.796527] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f0896da964e [ 1112.805337] RDX: 0000000000000001 RSI: 0000555dfaf2ebf0 RDI: 0000000000000007 [ 1112.814142] RBP: 00007fffc91f63f0 R08: 0000000000000000 R09: 0000000000000000 [ 1112.822946] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f0896c22390 [ 1112.831753] R13: 0000555dfaf2ebf0 R14: 0000000000000007 R15: 00007f0896bb5120 | [ 1113.243541] #1: ff110001c08f5c80 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 1113.254499] #2: ff11000123310b50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1113.265654] #3: ff110001232c1db8 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1113.275742] | [ 1113.278208] =============================================\x0a [ 1235.444462] INFO: task python3:5485 blocked for more than 368 seconds. [ 1235.452610] Not tainted 7.1.0-rc6-kazs-gac309bb0374c #1 [ 1235.459850] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1235.469428] task:python3 state:D stack:25672 pid:5485 tgid:5485 ppid:5484 task_flags:0x400100 flags:0x00080002 [ 1235.482632] Call Trace: [ 1235.486171] [ 1235.489319] __schedule+0xcc3/0x19c0 [ 1235.494134] ? io_schedule_timeout+0x140/0x140 [ 1235.499919] ? find_held_lock+0x2b/0x80 [ 1235.505021] ? __lock_release.isra.0+0x6b/0x1a0 [ 1235.510906] schedule+0xd8/0x260 [ 1235.515323] schedule_preempt_disabled+0x18/0x30 [ 1235.521304] __mutex_lock+0x6af/0x2010 [ 1235.526310] ? threaded_store+0x132/0x220 [ 1235.531608] ? ww_mutex_lock+0x160/0x160 [ 1235.536804] ? selinux_inode_free_security+0x330/0x330 [ 1235.543374] ? cap_capable+0x1d6/0x3e0 [ 1235.548383] ? threaded_store+0x132/0x220 [ 1235.553677] threaded_store+0x132/0x220 [ 1235.558779] ? xps_rxqs_store+0x2e0/0x2e0 [ 1235.564074] ? sysfs_file_kobj+0xbd/0x1e0 [ 1235.569374] ? sysfs_kf_write+0x65/0x160 [ 1235.574576] ? sysfs_file_ops+0x140/0x140 [ 1235.579870] kernfs_fop_write_iter+0x335/0x510 [ 1235.585652] ? kernfs_ops+0xf0/0xf0 [ 1235.590364] new_sync_write+0x333/0x750 [ 1235.595465] ? __lock_acquire+0x508/0xc10 [ 1235.600761] ? new_sync_read+0x740/0x740 [ 1235.605964] ? lock_acquire.part.0+0xbc/0x260 [ 1235.611651] ? ksys_write+0x116/0x250 [ 1235.616559] vfs_write+0x657/0x9b0 [ 1235.621172] ksys_write+0x116/0x250 [ 1235.625884] ? __ia32_sys_read+0xc0/0xc0 [ 1235.631072] ? kernfs_fop_llseek+0x160/0x1f0 [ 1235.636658] ? rcu_is_watching+0x15/0xd0 [ 1235.641858] do_syscall_64+0xf3/0x650 [ 1235.646763] ? trace_hardirqs_off+0xd/0x30 [ 1235.652155] ? exc_page_fault+0xda/0xf0 [ 1235.657256] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1235.663714] RIP: 0033:0x7f0896da964e [ 1235.668520] RSP: 002b:00007fffc91f63e0 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1235.677816] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f0896da964e [ 1235.686622] RDX: 0000000000000001 RSI: 0000555dfaf2ebf0 RDI: 0000000000000007 [ 1235.695430] RBP: 00007fffc91f63f0 R08: 0000000000000000 R09: 0000000000000000 [ 1235.704238] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f0896c22390 [ 1235.713048] R13: 0000555dfaf2ebf0 R14: 0000000000000007 R15: 00007f0896bb5120 | [ 1236.113485] #1: ff110001c08f5c80 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 1236.124443] #2: ff11000123310b50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1236.135597] #3: ff110001232c1db8 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1236.145688] | [ 1236.148143] =============================================\x0a [ 1358.325774] INFO: task python3:5485 blocked for more than 491 seconds. [ 1358.333939] Not tainted 7.1.0-rc6-kazs-gac309bb0374c #1 [ 1358.341190] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1358.350766] task:python3 state:D stack:25672 pid:5485 tgid:5485 ppid:5484 task_flags:0x400100 flags:0x00080002 [ 1358.363968] Call Trace: [ 1358.367508] [ 1358.370660] __schedule+0xcc3/0x19c0 [ 1358.375473] ? io_schedule_timeout+0x140/0x140 [ 1358.381259] ? find_held_lock+0x2b/0x80 [ 1358.386366] ? __lock_release.isra.0+0x6b/0x1a0 [ 1358.392249] schedule+0xd8/0x260 [ 1358.396664] schedule_preempt_disabled+0x18/0x30 [ 1358.402640] __mutex_lock+0x6af/0x2010 [ 1358.407642] ? threaded_store+0x132/0x220 [ 1358.412940] ? ww_mutex_lock+0x160/0x160 [ 1358.418139] ? selinux_inode_free_security+0x330/0x330 [ 1358.424707] ? cap_capable+0x1d6/0x3e0 [ 1358.429713] ? threaded_store+0x132/0x220 [ 1358.435010] threaded_store+0x132/0x220 [ 1358.440109] ? xps_rxqs_store+0x2e0/0x2e0 [ 1358.445403] ? sysfs_file_kobj+0xbd/0x1e0 [ 1358.450698] ? sysfs_kf_write+0x65/0x160 [ 1358.455898] ? sysfs_file_ops+0x140/0x140 [ 1358.461182] kernfs_fop_write_iter+0x335/0x510 [ 1358.466963] ? kernfs_ops+0xf0/0xf0 [ 1358.471674] new_sync_write+0x333/0x750 [ 1358.476769] ? __lock_acquire+0x508/0xc10 [ 1358.482064] ? new_sync_read+0x740/0x740 [ 1358.487263] ? lock_acquire.part.0+0xbc/0x260 [ 1358.492951] ? ksys_write+0x116/0x250 [ 1358.497851] vfs_write+0x657/0x9b0 [ 1358.502465] ksys_write+0x116/0x250 [ 1358.507174] ? __ia32_sys_read+0xc0/0xc0 [ 1358.512371] ? kernfs_fop_llseek+0x160/0x1f0 [ 1358.517957] ? rcu_is_watching+0x15/0xd0 [ 1358.523158] do_syscall_64+0xf3/0x650 [ 1358.528054] ? trace_hardirqs_off+0xd/0x30 [ 1358.533446] ? exc_page_fault+0xda/0xf0 [ 1358.538548] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1358.545014] RIP: 0033:0x7f0896da964e [ 1358.549820] RSP: 002b:00007fffc91f63e0 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1358.559117] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f0896da964e [ 1358.567923] RDX: 0000000000000001 RSI: 0000555dfaf2ebf0 RDI: 0000000000000007 [ 1358.576729] RBP: 00007fffc91f63f0 R08: 0000000000000000 R09: 0000000000000000 [ 1358.585536] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f0896c22390 [ 1358.594332] R13: 0000555dfaf2ebf0 R14: 0000000000000007 R15: 00007f0896bb5120 Finger prints: __schedule:schedule:schedule_preempt_disabled:__mutex_lock:threaded_store