====================================== | [ 789.277070] nipa-hw-worker: drivers/net:napi_threaded.py: timed out | [ 789.279473] nipa-hw-worker: [5/46] Retrying drivers/net:napi_threaded.py | [ 789.431591] kselftest: Running tests in drivers/net [ 989.698238] INFO: task python3:5816 blocked for more than 122 seconds. [ 989.706477] Not tainted 7.1.0-rc4-yvvk-gc6fc3bdd5ba0 #1 [ 989.714430] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 989.724018] task:python3 state:D stack:25672 pid:5816 tgid:5816 ppid:5815 task_flags:0x400100 flags:0x00080000 [ 989.737225] Call Trace: [ 989.740763] [ 989.743912] __schedule+0xcc3/0x19c0 [ 989.748731] ? io_schedule_timeout+0x140/0x140 [ 989.754514] ? find_held_lock+0x2b/0x80 [ 989.759611] ? __lock_release.isra.0+0x6b/0x1a0 [ 989.765495] schedule+0xd8/0x260 [ 989.769911] schedule_preempt_disabled+0x18/0x30 [ 989.775890] __mutex_lock+0x6af/0x2010 [ 989.780893] ? threaded_store+0x132/0x220 [ 989.786184] ? ww_mutex_lock+0x160/0x160 [ 989.791382] ? selinux_inode_free_security+0x330/0x330 [ 989.797942] ? cap_capable+0x1d6/0x3e0 [ 989.802951] ? threaded_store+0x132/0x220 [ 989.808246] threaded_store+0x132/0x220 [ 989.813346] ? xps_rxqs_store+0x2e0/0x2e0 [ 989.818642] ? sysfs_file_kobj+0xbd/0x1e0 [ 989.823939] ? sysfs_kf_write+0x65/0x160 [ 989.829138] ? sysfs_file_ops+0x140/0x140 [ 989.834434] kernfs_fop_write_iter+0x335/0x510 [ 989.840216] ? kernfs_ops+0xf0/0xf0 [ 989.844926] new_sync_write+0x333/0x750 [ 989.850028] ? __lock_acquire+0x508/0xc10 [ 989.855322] ? new_sync_read+0x740/0x740 [ 989.860523] ? lock_acquire.part.0+0xbc/0x260 [ 989.866210] ? ksys_write+0x116/0x250 [ 989.871109] vfs_write+0x657/0x9b0 [ 989.875725] ksys_write+0x116/0x250 [ 989.880434] ? __ia32_sys_read+0xc0/0xc0 [ 989.885631] ? kernfs_fop_llseek+0x160/0x1f0 [ 989.891218] ? rcu_is_watching+0x15/0xd0 [ 989.896421] do_syscall_64+0xf3/0x650 [ 989.901328] ? trace_hardirqs_off+0xd/0x30 [ 989.906720] ? exc_page_fault+0xda/0xf0 [ 989.911823] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 989.918289] RIP: 0033:0x7f07b773822e [ 989.923100] RSP: 002b:00007ffcc5030d30 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 989.932399] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f07b773822e [ 989.941205] RDX: 0000000000000001 RSI: 000055e31c8a6a90 RDI: 0000000000000007 [ 989.950010] RBP: 00007ffcc5030d40 R08: 0000000000000000 R09: 0000000000000000 [ 989.958816] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f07b75b0390 [ 989.967623] R13: 000055e31c8a6a90 R14: 0000000000000007 R15: 0000000000000003 | [ 990.338505] #1: ff110001f06adc80 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 990.349463] #2: ff11000123504b50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 990.360619] #3: ff11000123579d68 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 990.370710] | [ 990.373175] =============================================\x0a [ 1112.580236] INFO: task python3:5816 blocked for more than 245 seconds. [ 1112.588384] Not tainted 7.1.0-rc4-yvvk-gc6fc3bdd5ba0 #1 [ 1112.595636] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1112.605226] task:python3 state:D stack:25672 pid:5816 tgid:5816 ppid:5815 task_flags:0x400100 flags:0x00080000 [ 1112.618434] Call Trace: [ 1112.621975] [ 1112.625129] __schedule+0xcc3/0x19c0 [ 1112.629947] ? io_schedule_timeout+0x140/0x140 [ 1112.635732] ? find_held_lock+0x2b/0x80 [ 1112.640840] ? __lock_release.isra.0+0x6b/0x1a0 [ 1112.646724] schedule+0xd8/0x260 [ 1112.651144] schedule_preempt_disabled+0x18/0x30 [ 1112.657126] __mutex_lock+0x6af/0x2010 [ 1112.662132] ? threaded_store+0x132/0x220 [ 1112.667435] ? ww_mutex_lock+0x160/0x160 [ 1112.672637] ? selinux_inode_free_security+0x330/0x330 [ 1112.679206] ? cap_capable+0x1d6/0x3e0 [ 1112.684218] ? threaded_store+0x132/0x220 [ 1112.689516] threaded_store+0x132/0x220 [ 1112.694617] ? xps_rxqs_store+0x2e0/0x2e0 [ 1112.699914] ? sysfs_file_kobj+0xbd/0x1e0 [ 1112.705214] ? sysfs_kf_write+0x65/0x160 [ 1112.710415] ? sysfs_file_ops+0x140/0x140 [ 1112.715713] kernfs_fop_write_iter+0x335/0x510 [ 1112.721489] ? kernfs_ops+0xf0/0xf0 [ 1112.726191] new_sync_write+0x333/0x750 [ 1112.731295] ? __lock_acquire+0x508/0xc10 [ 1112.736594] ? new_sync_read+0x740/0x740 [ 1112.741795] ? lock_acquire.part.0+0xbc/0x260 [ 1112.747480] ? ksys_write+0x116/0x250 [ 1112.752393] vfs_write+0x657/0x9b0 [ 1112.757011] ksys_write+0x116/0x250 [ 1112.761724] ? __ia32_sys_read+0xc0/0xc0 [ 1112.766925] ? kernfs_fop_llseek+0x160/0x1f0 [ 1112.772515] ? rcu_is_watching+0x15/0xd0 [ 1112.777717] do_syscall_64+0xf3/0x650 [ 1112.782625] ? trace_hardirqs_off+0xd/0x30 [ 1112.788021] ? exc_page_fault+0xda/0xf0 [ 1112.793123] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1112.799583] RIP: 0033:0x7f07b773822e [ 1112.804393] RSP: 002b:00007ffcc5030d30 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1112.813690] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f07b773822e [ 1112.822500] RDX: 0000000000000001 RSI: 000055e31c8a6a90 RDI: 0000000000000007 [ 1112.831307] RBP: 00007ffcc5030d40 R08: 0000000000000000 R09: 0000000000000000 [ 1112.840118] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f07b75b0390 [ 1112.848926] R13: 000055e31c8a6a90 R14: 0000000000000007 R15: 0000000000000003 | [ 1113.259476] #2: ff11000123504b50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1113.270624] #3: ff11000123579d68 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1113.280716] | [ 1113.283183] =============================================\x0a | [ 1210.884043] kworker/u129:1 (185) used greatest stack depth: 22176 bytes left [ 1235.461267] INFO: task python3:5816 blocked for more than 368 seconds. [ 1235.469408] Not tainted 7.1.0-rc4-yvvk-gc6fc3bdd5ba0 #1 [ 1235.476658] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1235.486251] task:python3 state:D stack:25672 pid:5816 tgid:5816 ppid:5815 task_flags:0x400100 flags:0x00080002 [ 1235.499459] Call Trace: [ 1235.503000] [ 1235.506153] __schedule+0xcc3/0x19c0 [ 1235.510968] ? io_schedule_timeout+0x140/0x140 [ 1235.516754] ? find_held_lock+0x2b/0x80 [ 1235.521863] ? __lock_release.isra.0+0x6b/0x1a0 [ 1235.527746] schedule+0xd8/0x260 [ 1235.532167] schedule_preempt_disabled+0x18/0x30 [ 1235.538147] __mutex_lock+0x6af/0x2010 [ 1235.543154] ? threaded_store+0x132/0x220 [ 1235.548453] ? ww_mutex_lock+0x160/0x160 [ 1235.553653] ? selinux_inode_free_security+0x330/0x330 [ 1235.560226] ? cap_capable+0x1d6/0x3e0 [ 1235.565236] ? threaded_store+0x132/0x220 [ 1235.570536] threaded_store+0x132/0x220 [ 1235.575642] ? xps_rxqs_store+0x2e0/0x2e0 [ 1235.580939] ? sysfs_file_kobj+0xbd/0x1e0 [ 1235.586240] ? sysfs_kf_write+0x65/0x160 [ 1235.591440] ? sysfs_file_ops+0x140/0x140 [ 1235.596737] kernfs_fop_write_iter+0x335/0x510 [ 1235.602523] ? kernfs_ops+0xf0/0xf0 [ 1235.607234] new_sync_write+0x333/0x750 [ 1235.612339] ? __lock_acquire+0x508/0xc10 [ 1235.617629] ? new_sync_read+0x740/0x740 [ 1235.622831] ? lock_acquire.part.0+0xbc/0x260 [ 1235.628517] ? ksys_write+0x116/0x250 [ 1235.633429] vfs_write+0x657/0x9b0 [ 1235.638045] ksys_write+0x116/0x250 [ 1235.642758] ? __ia32_sys_read+0xc0/0xc0 [ 1235.647950] ? kernfs_fop_llseek+0x160/0x1f0 [ 1235.653541] ? rcu_is_watching+0x15/0xd0 [ 1235.658745] do_syscall_64+0xf3/0x650 [ 1235.663652] ? trace_hardirqs_off+0xd/0x30 [ 1235.669047] ? exc_page_fault+0xda/0xf0 [ 1235.674148] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1235.680618] RIP: 0033:0x7f07b773822e [ 1235.685427] RSP: 002b:00007ffcc5030d30 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1235.694726] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f07b773822e [ 1235.703535] RDX: 0000000000000001 RSI: 000055e31c8a6a90 RDI: 0000000000000007 [ 1235.712345] RBP: 00007ffcc5030d40 R08: 0000000000000000 R09: 0000000000000000 [ 1235.721156] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f07b75b0390 [ 1235.729967] R13: 000055e31c8a6a90 R14: 0000000000000007 R15: 0000000000000003 | [ 1236.129600] #1: ff110001f06adc80 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 1236.140560] #2: ff11000123504b50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1236.151717] #3: ff11000123579d68 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1236.161811] | [ 1236.164276] =============================================\x0a [ 1358.342293] INFO: task python3:5816 blocked for more than 491 seconds. [ 1358.350444] Not tainted 7.1.0-rc4-yvvk-gc6fc3bdd5ba0 #1 [ 1358.357694] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1358.367285] task:python3 state:D stack:25672 pid:5816 tgid:5816 ppid:5815 task_flags:0x400100 flags:0x00080002 [ 1358.380493] Call Trace: [ 1358.384031] [ 1358.387181] __schedule+0xcc3/0x19c0 [ 1358.391996] ? io_schedule_timeout+0x140/0x140 [ 1358.397783] ? find_held_lock+0x2b/0x80 [ 1358.402888] ? __lock_release.isra.0+0x6b/0x1a0 [ 1358.408767] schedule+0xd8/0x260 [ 1358.413185] schedule_preempt_disabled+0x18/0x30 [ 1358.419162] __mutex_lock+0x6af/0x2010 [ 1358.424170] ? threaded_store+0x132/0x220 [ 1358.429468] ? ww_mutex_lock+0x160/0x160 [ 1358.434668] ? selinux_inode_free_security+0x330/0x330 [ 1358.441237] ? cap_capable+0x1d6/0x3e0 [ 1358.446243] ? threaded_store+0x132/0x220 [ 1358.451541] threaded_store+0x132/0x220 [ 1358.456643] ? xps_rxqs_store+0x2e0/0x2e0 [ 1358.461943] ? sysfs_file_kobj+0xbd/0x1e0 [ 1358.467245] ? sysfs_kf_write+0x65/0x160 [ 1358.472447] ? sysfs_file_ops+0x140/0x140 [ 1358.477744] kernfs_fop_write_iter+0x335/0x510 [ 1358.483523] ? kernfs_ops+0xf0/0xf0 [ 1358.488235] new_sync_write+0x333/0x750 [ 1358.493340] ? __lock_acquire+0x508/0xc10 [ 1358.498637] ? new_sync_read+0x740/0x740 [ 1358.503829] ? lock_acquire.part.0+0xbc/0x260 [ 1358.509515] ? ksys_write+0x116/0x250 [ 1358.514428] vfs_write+0x657/0x9b0 [ 1358.519045] ksys_write+0x116/0x250 [ 1358.523757] ? __ia32_sys_read+0xc0/0xc0 [ 1358.528957] ? kernfs_fop_llseek+0x160/0x1f0 [ 1358.534548] ? rcu_is_watching+0x15/0xd0 [ 1358.539749] do_syscall_64+0xf3/0x650 [ 1358.544657] ? trace_hardirqs_off+0xd/0x30 [ 1358.550053] ? exc_page_fault+0xda/0xf0 [ 1358.555156] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1358.561626] RIP: 0033:0x7f07b773822e [ 1358.566435] RSP: 002b:00007ffcc5030d30 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1358.575735] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f07b773822e [ 1358.584546] RDX: 0000000000000001 RSI: 000055e31c8a6a90 RDI: 0000000000000007 [ 1358.593353] RBP: 00007ffcc5030d40 R08: 0000000000000000 R09: 0000000000000000 [ 1358.602163] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f07b75b0390 [ 1358.610973] R13: 000055e31c8a6a90 R14: 0000000000000007 R15: 0000000000000003 Finger prints: __schedule:schedule:schedule_preempt_disabled:__mutex_lock:threaded_store