====================================== | [ 783.085322] nipa-hw-worker: drivers/net:napi_threaded.py: timed out | [ 783.087742] nipa-hw-worker: [5/43] Retrying drivers/net:napi_threaded.py | [ 783.243168] kselftest: Running tests in drivers/net [ 989.688091] INFO: task python3:5785 blocked for more than 122 seconds. [ 989.696331] Not tainted 7.1.0-rc1-aygn-gb2b8d3c2f799 #1 [ 989.704260] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 989.713848] task:python3 state:D stack:25128 pid:5785 tgid:5785 ppid:5784 task_flags:0x400100 flags:0x00080000 [ 989.727057] Call Trace: [ 989.730596] [ 989.733749] __schedule+0xcba/0x1940 [ 989.738569] ? io_schedule_timeout+0x140/0x140 [ 989.744355] ? find_held_lock+0x2b/0x80 [ 989.749463] ? __lock_release.isra.0+0x6b/0x1a0 [ 989.755349] schedule+0xd4/0x260 [ 989.759771] schedule_preempt_disabled+0x18/0x30 [ 989.765752] __mutex_lock+0x6a3/0x2000 [ 989.770760] ? threaded_store+0x132/0x220 [ 989.776062] ? ww_mutex_lock+0x160/0x160 [ 989.781263] ? selinux_inode_free_security+0x330/0x330 [ 989.787833] ? cap_capable+0x1d6/0x3e0 [ 989.792845] ? threaded_store+0x132/0x220 [ 989.798143] threaded_store+0x132/0x220 [ 989.803243] ? xps_rxqs_store+0x2e0/0x2e0 [ 989.808540] ? sysfs_file_kobj+0xbd/0x1e0 [ 989.813837] ? sysfs_kf_write+0x65/0x160 [ 989.819037] ? sysfs_file_ops+0x140/0x140 [ 989.824334] kernfs_fop_write_iter+0x335/0x510 [ 989.830119] ? kernfs_ops+0xf0/0xf0 [ 989.834831] new_sync_write+0x333/0x750 [ 989.839937] ? __lock_acquire+0x508/0xc10 [ 989.845234] ? new_sync_read+0x740/0x740 [ 989.850436] ? lock_acquire.part.0+0xbc/0x260 [ 989.856123] ? ksys_write+0x116/0x250 [ 989.861026] vfs_write+0x657/0x9b0 [ 989.865643] ksys_write+0x116/0x250 [ 989.870357] ? __ia32_sys_read+0xc0/0xc0 [ 989.875555] ? kernfs_fop_llseek+0x160/0x1f0 [ 989.881146] ? rcu_is_watching+0x15/0xd0 [ 989.886346] do_syscall_64+0xf3/0x650 [ 989.891253] ? trace_hardirqs_off+0xd/0x30 [ 989.896650] ? exc_page_fault+0xda/0xf0 [ 989.901753] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 989.908213] RIP: 0033:0x7fa500d0c22e [ 989.913025] RSP: 002b:00007ffcf7367db0 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 989.922322] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007fa500d0c22e [ 989.931123] RDX: 0000000000000001 RSI: 000055d78d1aaa10 RDI: 0000000000000007 [ 989.939933] RBP: 00007ffcf7367dc0 R08: 0000000000000000 R09: 0000000000000000 [ 989.948742] R10: 0000000000000000 R11: 0000000000000202 R12: 00007fa500b84390 [ 989.957551] R13: 000055d78d1aaa10 R14: 0000000000000007 R15: 0000000000000003 | [ 990.363144] #1: ff110001b252f480 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 990.374105] #2: ff11000111c80b50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 990.385255] #3: ff11000111d01d60 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 990.395345] | [ 990.397802] =============================================\x0a [ 1112.568324] INFO: task python3:5785 blocked for more than 245 seconds. [ 1112.576469] Not tainted 7.1.0-rc1-aygn-gb2b8d3c2f799 #1 [ 1112.583718] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1112.593308] task:python3 state:D stack:25128 pid:5785 tgid:5785 ppid:5784 task_flags:0x400100 flags:0x00080000 [ 1112.606514] Call Trace: [ 1112.610054] [ 1112.613204] __schedule+0xcba/0x1940 [ 1112.618020] ? io_schedule_timeout+0x140/0x140 [ 1112.623806] ? find_held_lock+0x2b/0x80 [ 1112.628910] ? __lock_release.isra.0+0x6b/0x1a0 [ 1112.634793] schedule+0xd4/0x260 [ 1112.639202] schedule_preempt_disabled+0x18/0x30 [ 1112.645182] __mutex_lock+0x6a3/0x2000 [ 1112.650185] ? threaded_store+0x132/0x220 [ 1112.655484] ? ww_mutex_lock+0x160/0x160 [ 1112.660682] ? selinux_inode_free_security+0x330/0x330 [ 1112.667250] ? cap_capable+0x1d6/0x3e0 [ 1112.672258] ? threaded_store+0x132/0x220 [ 1112.677554] threaded_store+0x132/0x220 [ 1112.682655] ? xps_rxqs_store+0x2e0/0x2e0 [ 1112.687950] ? sysfs_file_kobj+0xbd/0x1e0 [ 1112.693247] ? sysfs_kf_write+0x65/0x160 [ 1112.698446] ? sysfs_file_ops+0x140/0x140 [ 1112.703742] kernfs_fop_write_iter+0x335/0x510 [ 1112.709524] ? kernfs_ops+0xf0/0xf0 [ 1112.714234] new_sync_write+0x333/0x750 [ 1112.719337] ? __lock_acquire+0x508/0xc10 [ 1112.724633] ? new_sync_read+0x740/0x740 [ 1112.729831] ? lock_acquire.part.0+0xbc/0x260 [ 1112.735517] ? ksys_write+0x116/0x250 [ 1112.740424] vfs_write+0x657/0x9b0 [ 1112.745028] ksys_write+0x116/0x250 [ 1112.749740] ? __ia32_sys_read+0xc0/0xc0 [ 1112.754937] ? kernfs_fop_llseek+0x160/0x1f0 [ 1112.760522] ? rcu_is_watching+0x15/0xd0 [ 1112.765723] do_syscall_64+0xf3/0x650 [ 1112.770629] ? trace_hardirqs_off+0xd/0x30 [ 1112.776024] ? exc_page_fault+0xda/0xf0 [ 1112.781125] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1112.787591] RIP: 0033:0x7fa500d0c22e [ 1112.792397] RSP: 002b:00007ffcf7367db0 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1112.801696] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007fa500d0c22e [ 1112.810503] RDX: 0000000000000001 RSI: 000055d78d1aaa10 RDI: 0000000000000007 [ 1112.819310] RBP: 00007ffcf7367dc0 R08: 0000000000000000 R09: 0000000000000000 [ 1112.828116] R10: 0000000000000000 R11: 0000000000000202 R12: 00007fa500b84390 [ 1112.836924] R13: 000055d78d1aaa10 R14: 0000000000000007 R15: 0000000000000003 | [ 1113.213673] #1: ff110001b252f480 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 1113.224634] #2: ff11000111c80b50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1113.235792] #3: ff11000111d01d60 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1113.245882] | [ 1113.248344] =============================================\x0a [ 1235.449572] INFO: task python3:5785 blocked for more than 368 seconds. [ 1235.457724] Not tainted 7.1.0-rc1-aygn-gb2b8d3c2f799 #1 [ 1235.464974] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1235.474563] task:python3 state:D stack:25128 pid:5785 tgid:5785 ppid:5784 task_flags:0x400100 flags:0x00080002 [ 1235.487761] Call Trace: [ 1235.491300] [ 1235.494450] __schedule+0xcba/0x1940 [ 1235.499266] ? io_schedule_timeout+0x140/0x140 [ 1235.505052] ? find_held_lock+0x2b/0x80 [ 1235.510156] ? __lock_release.isra.0+0x6b/0x1a0 [ 1235.516039] schedule+0xd4/0x260 [ 1235.520449] schedule_preempt_disabled+0x18/0x30 [ 1235.526429] __mutex_lock+0x6a3/0x2000 [ 1235.531423] ? threaded_store+0x132/0x220 [ 1235.536719] ? ww_mutex_lock+0x160/0x160 [ 1235.541918] ? selinux_inode_free_security+0x330/0x330 [ 1235.548477] ? cap_capable+0x1d6/0x3e0 [ 1235.553487] ? threaded_store+0x132/0x220 [ 1235.558784] threaded_store+0x132/0x220 [ 1235.563883] ? xps_rxqs_store+0x2e0/0x2e0 [ 1235.569179] ? sysfs_file_kobj+0xbd/0x1e0 [ 1235.574465] ? sysfs_kf_write+0x65/0x160 [ 1235.579662] ? sysfs_file_ops+0x140/0x140 [ 1235.584958] kernfs_fop_write_iter+0x335/0x510 [ 1235.590740] ? kernfs_ops+0xf0/0xf0 [ 1235.595451] new_sync_write+0x333/0x750 [ 1235.600546] ? __lock_acquire+0x508/0xc10 [ 1235.605832] ? new_sync_read+0x740/0x740 [ 1235.611034] ? lock_acquire.part.0+0xbc/0x260 [ 1235.616720] ? ksys_write+0x116/0x250 [ 1235.621630] vfs_write+0x657/0x9b0 [ 1235.626245] ksys_write+0x116/0x250 [ 1235.630957] ? __ia32_sys_read+0xc0/0xc0 [ 1235.636154] ? kernfs_fop_llseek+0x160/0x1f0 [ 1235.641739] ? rcu_is_watching+0x15/0xd0 [ 1235.646940] do_syscall_64+0xf3/0x650 [ 1235.651846] ? trace_hardirqs_off+0xd/0x30 [ 1235.657237] ? exc_page_fault+0xda/0xf0 [ 1235.662339] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1235.668805] RIP: 0033:0x7fa500d0c22e [ 1235.673606] RSP: 002b:00007ffcf7367db0 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1235.682893] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007fa500d0c22e [ 1235.691700] RDX: 0000000000000001 RSI: 000055d78d1aaa10 RDI: 0000000000000007 [ 1235.700506] RBP: 00007ffcf7367dc0 R08: 0000000000000000 R09: 0000000000000000 [ 1235.709312] R10: 0000000000000000 R11: 0000000000000202 R12: 00007fa500b84390 [ 1235.718122] R13: 000055d78d1aaa10 R14: 0000000000000007 R15: 0000000000000003 | [ 1236.123035] #1: ff110001b252f480 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 1236.133993] #2: ff11000111c80b50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1236.145146] #3: ff11000111d01d60 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1236.155237] | [ 1236.157702] =============================================\x0a [ 1358.331781] INFO: task python3:5785 blocked for more than 491 seconds. [ 1358.339931] Not tainted 7.1.0-rc1-aygn-gb2b8d3c2f799 #1 [ 1358.347182] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1358.356770] task:python3 state:D stack:25128 pid:5785 tgid:5785 ppid:5784 task_flags:0x400100 flags:0x00080002 [ 1358.369977] Call Trace: [ 1358.373518] [ 1358.376669] __schedule+0xcba/0x1940 [ 1358.381487] ? io_schedule_timeout+0x140/0x140 [ 1358.387273] ? find_held_lock+0x2b/0x80 [ 1358.392377] ? __lock_release.isra.0+0x6b/0x1a0 [ 1358.398261] schedule+0xd4/0x260 [ 1358.402680] schedule_preempt_disabled+0x18/0x30 [ 1358.408660] __mutex_lock+0x6a3/0x2000 [ 1358.413662] ? threaded_store+0x132/0x220 [ 1358.418959] ? ww_mutex_lock+0x160/0x160 [ 1358.424158] ? selinux_inode_free_security+0x330/0x330 [ 1358.430725] ? cap_capable+0x1d6/0x3e0 [ 1358.435733] ? threaded_store+0x132/0x220 [ 1358.441029] threaded_store+0x132/0x220 [ 1358.446129] ? xps_rxqs_store+0x2e0/0x2e0 [ 1358.451425] ? sysfs_file_kobj+0xbd/0x1e0 [ 1358.456723] ? sysfs_kf_write+0x65/0x160 [ 1358.461925] ? sysfs_file_ops+0x140/0x140 [ 1358.467219] kernfs_fop_write_iter+0x335/0x510 [ 1358.473004] ? kernfs_ops+0xf0/0xf0 [ 1358.477704] new_sync_write+0x333/0x750 [ 1358.482809] ? __lock_acquire+0x508/0xc10 [ 1358.488103] ? new_sync_read+0x740/0x740 [ 1358.493305] ? lock_acquire.part.0+0xbc/0x260 [ 1358.498989] ? ksys_write+0x116/0x250 [ 1358.503898] vfs_write+0x657/0x9b0 [ 1358.508514] ksys_write+0x116/0x250 [ 1358.513226] ? __ia32_sys_read+0xc0/0xc0 [ 1358.518422] ? kernfs_fop_llseek+0x160/0x1f0 [ 1358.524012] ? rcu_is_watching+0x15/0xd0 [ 1358.529214] do_syscall_64+0xf3/0x650 [ 1358.534118] ? trace_hardirqs_off+0xd/0x30 [ 1358.539513] ? exc_page_fault+0xda/0xf0 [ 1358.544616] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1358.551086] RIP: 0033:0x7fa500d0c22e [ 1358.555896] RSP: 002b:00007ffcf7367db0 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1358.565184] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007fa500d0c22e [ 1358.574065] RDX: 0000000000000001 RSI: 000055d78d1aaa10 RDI: 0000000000000007 [ 1358.582937] RBP: 00007ffcf7367dc0 R08: 0000000000000000 R09: 0000000000000000 [ 1358.591744] R10: 0000000000000000 R11: 0000000000000202 R12: 00007fa500b84390 [ 1358.600552] R13: 000055d78d1aaa10 R14: 0000000000000007 R15: 0000000000000003 Finger prints: __schedule:schedule:schedule_preempt_disabled:__mutex_lock:threaded_store