====================================== | [ 787.481021] nipa-hw-worker: drivers/net:napi_threaded.py: timed out | [ 787.483505] nipa-hw-worker: [5/46] Retrying drivers/net:napi_threaded.py | [ 787.643230] kselftest: Running tests in drivers/net [ 989.701163] INFO: task python3:5775 blocked for more than 122 seconds. [ 989.709354] Not tainted 7.1.0-rc4-erhu-g4a613c0be53a #1 [ 989.716877] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 989.726464] task:python3 state:D stack:25448 pid:5775 tgid:5775 ppid:5774 task_flags:0x400100 flags:0x00080000 [ 989.739670] Call Trace: [ 989.743207] [ 989.746356] __schedule+0xcc3/0x19c0 [ 989.751170] ? io_schedule_timeout+0x140/0x140 [ 989.756943] ? find_held_lock+0x2b/0x80 [ 989.762047] ? __lock_release.isra.0+0x6b/0x1a0 [ 989.767931] schedule+0xd8/0x260 [ 989.772349] schedule_preempt_disabled+0x18/0x30 [ 989.778326] __mutex_lock+0x6af/0x2010 [ 989.783329] ? threaded_store+0x132/0x220 [ 989.788618] ? ww_mutex_lock+0x160/0x160 [ 989.793818] ? selinux_inode_free_security+0x330/0x330 [ 989.800388] ? cap_capable+0x1d6/0x3e0 [ 989.805393] ? threaded_store+0x132/0x220 [ 989.810677] threaded_store+0x132/0x220 [ 989.815778] ? xps_rxqs_store+0x2e0/0x2e0 [ 989.821076] ? sysfs_file_kobj+0xbd/0x1e0 [ 989.826376] ? sysfs_kf_write+0x65/0x160 [ 989.831575] ? sysfs_file_ops+0x140/0x140 [ 989.836867] kernfs_fop_write_iter+0x335/0x510 [ 989.842651] ? kernfs_ops+0xf0/0xf0 [ 989.847359] new_sync_write+0x333/0x750 [ 989.852460] ? __lock_acquire+0x508/0xc10 [ 989.857757] ? new_sync_read+0x740/0x740 [ 989.862955] ? lock_acquire.part.0+0xbc/0x260 [ 989.868638] ? ksys_write+0x116/0x250 [ 989.873548] vfs_write+0x657/0x9b0 [ 989.878162] ksys_write+0x116/0x250 [ 989.882873] ? __ia32_sys_read+0xc0/0xc0 [ 989.888071] ? kernfs_fop_llseek+0x160/0x1f0 [ 989.893660] ? rcu_is_watching+0x15/0xd0 [ 989.898853] do_syscall_64+0xf3/0x650 [ 989.903758] ? trace_hardirqs_off+0xd/0x30 [ 989.909152] ? exc_page_fault+0xda/0xf0 [ 989.914252] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 989.920729] RIP: 0033:0x7fa8dee9022e [ 989.925536] RSP: 002b:00007ffc966d0310 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 989.934834] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007fa8dee9022e [ 989.943641] RDX: 0000000000000001 RSI: 000055f83ce5e6b0 RDI: 0000000000000007 [ 989.952447] RBP: 00007ffc966d0320 R08: 0000000000000000 R09: 0000000000000000 [ 989.961254] R10: 0000000000000000 R11: 0000000000000202 R12: 00007fa8ded08390 [ 989.970051] R13: 000055f83ce5e6b0 R14: 0000000000000007 R15: 0000000000000003 | [ 990.386443] #1: ff110001ead72680 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 990.397403] #2: ff110001220d2b50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 990.408558] #3: ff11000112171d68 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 990.418644] | [ 990.421108] =============================================\x0a [ 1112.582865] INFO: task python3:5775 blocked for more than 245 seconds. [ 1112.591008] Not tainted 7.1.0-rc4-erhu-g4a613c0be53a #1 [ 1112.598257] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1112.607837] task:python3 state:D stack:25448 pid:5775 tgid:5775 ppid:5774 task_flags:0x400100 flags:0x00080000 [ 1112.621039] Call Trace: [ 1112.624578] [ 1112.627726] __schedule+0xcc3/0x19c0 [ 1112.632542] ? io_schedule_timeout+0x140/0x140 [ 1112.638327] ? find_held_lock+0x2b/0x80 [ 1112.643433] ? __lock_release.isra.0+0x6b/0x1a0 [ 1112.649316] schedule+0xd8/0x260 [ 1112.653731] schedule_preempt_disabled+0x18/0x30 [ 1112.659712] __mutex_lock+0x6af/0x2010 [ 1112.664708] ? threaded_store+0x132/0x220 [ 1112.670005] ? ww_mutex_lock+0x160/0x160 [ 1112.675203] ? selinux_inode_free_security+0x330/0x330 [ 1112.681762] ? cap_capable+0x1d6/0x3e0 [ 1112.686770] ? threaded_store+0x132/0x220 [ 1112.692068] threaded_store+0x132/0x220 [ 1112.697158] ? xps_rxqs_store+0x2e0/0x2e0 [ 1112.702455] ? sysfs_file_kobj+0xbd/0x1e0 [ 1112.707752] ? sysfs_kf_write+0x65/0x160 [ 1112.712949] ? sysfs_file_ops+0x140/0x140 [ 1112.718245] kernfs_fop_write_iter+0x335/0x510 [ 1112.724027] ? kernfs_ops+0xf0/0xf0 [ 1112.728738] new_sync_write+0x333/0x750 [ 1112.733830] ? __lock_acquire+0x508/0xc10 [ 1112.739126] ? new_sync_read+0x740/0x740 [ 1112.744328] ? lock_acquire.part.0+0xbc/0x260 [ 1112.750014] ? ksys_write+0x116/0x250 [ 1112.754923] vfs_write+0x657/0x9b0 [ 1112.759535] ksys_write+0x116/0x250 [ 1112.764244] ? __ia32_sys_read+0xc0/0xc0 [ 1112.769441] ? kernfs_fop_llseek+0x160/0x1f0 [ 1112.775027] ? rcu_is_watching+0x15/0xd0 [ 1112.780228] do_syscall_64+0xf3/0x650 [ 1112.785133] ? trace_hardirqs_off+0xd/0x30 [ 1112.790524] ? exc_page_fault+0xda/0xf0 [ 1112.795627] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1112.802092] RIP: 0033:0x7fa8dee9022e [ 1112.806899] RSP: 002b:00007ffc966d0310 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1112.816198] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007fa8dee9022e [ 1112.825004] RDX: 0000000000000001 RSI: 000055f83ce5e6b0 RDI: 0000000000000007 [ 1112.833808] RBP: 00007ffc966d0320 R08: 0000000000000000 R09: 0000000000000000 [ 1112.842613] R10: 0000000000000000 R11: 0000000000000202 R12: 00007fa8ded08390 [ 1112.851422] R13: 000055f83ce5e6b0 R14: 0000000000000007 R15: 0000000000000003 | [ 1113.256265] #1: ff110001ead72680 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 1113.267225] #2: ff110001220d2b50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1113.278381] #3: ff11000112171d68 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1113.288467] | [ 1113.290932] =============================================\x0a [ 1235.464135] INFO: task python3:5775 blocked for more than 368 seconds. [ 1235.472280] Not tainted 7.1.0-rc4-erhu-g4a613c0be53a #1 [ 1235.479531] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1235.489122] task:python3 state:D stack:25448 pid:5775 tgid:5775 ppid:5774 task_flags:0x400100 flags:0x00080002 [ 1235.502328] Call Trace: [ 1235.505868] [ 1235.509018] __schedule+0xcc3/0x19c0 [ 1235.513837] ? io_schedule_timeout+0x140/0x140 [ 1235.519623] ? find_held_lock+0x2b/0x80 [ 1235.524730] ? __lock_release.isra.0+0x6b/0x1a0 [ 1235.530615] schedule+0xd8/0x260 [ 1235.535032] schedule_preempt_disabled+0x18/0x30 [ 1235.541012] __mutex_lock+0x6af/0x2010 [ 1235.546015] ? threaded_store+0x132/0x220 [ 1235.551317] ? ww_mutex_lock+0x160/0x160 [ 1235.556516] ? selinux_inode_free_security+0x330/0x330 [ 1235.563085] ? cap_capable+0x1d6/0x3e0 [ 1235.568094] ? threaded_store+0x132/0x220 [ 1235.573391] threaded_store+0x132/0x220 [ 1235.578492] ? xps_rxqs_store+0x2e0/0x2e0 [ 1235.583790] ? sysfs_file_kobj+0xbd/0x1e0 [ 1235.589089] ? sysfs_kf_write+0x65/0x160 [ 1235.594292] ? sysfs_file_ops+0x140/0x140 [ 1235.599589] kernfs_fop_write_iter+0x335/0x510 [ 1235.605375] ? kernfs_ops+0xf0/0xf0 [ 1235.610086] new_sync_write+0x333/0x750 [ 1235.615192] ? __lock_acquire+0x508/0xc10 [ 1235.620491] ? new_sync_read+0x740/0x740 [ 1235.625691] ? lock_acquire.part.0+0xbc/0x260 [ 1235.631368] ? ksys_write+0x116/0x250 [ 1235.636278] vfs_write+0x657/0x9b0 [ 1235.640895] ksys_write+0x116/0x250 [ 1235.645608] ? __ia32_sys_read+0xc0/0xc0 [ 1235.650801] ? kernfs_fop_llseek+0x160/0x1f0 [ 1235.656392] ? rcu_is_watching+0x15/0xd0 [ 1235.661593] do_syscall_64+0xf3/0x650 [ 1235.666501] ? trace_hardirqs_off+0xd/0x30 [ 1235.671896] ? exc_page_fault+0xda/0xf0 [ 1235.676998] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1235.683469] RIP: 0033:0x7fa8dee9022e [ 1235.688280] RSP: 002b:00007ffc966d0310 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1235.697577] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007fa8dee9022e [ 1235.706377] RDX: 0000000000000001 RSI: 000055f83ce5e6b0 RDI: 0000000000000007 [ 1235.715185] RBP: 00007ffc966d0320 R08: 0000000000000000 R09: 0000000000000000 [ 1235.723996] R10: 0000000000000000 R11: 0000000000000202 R12: 00007fa8ded08390 [ 1235.732805] R13: 000055f83ce5e6b0 R14: 0000000000000007 R15: 0000000000000003 | [ 1236.149691] #1: ff110001ead72680 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x248/0x510 | [ 1236.160655] #2: ff110001220d2b50 (kn->active#118){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1236.171814] #3: ff11000112171d68 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1236.181904] | [ 1236.184371] =============================================\x0a [ 1358.345721] INFO: task python3:5775 blocked for more than 491 seconds. [ 1358.353867] Not tainted 7.1.0-rc4-erhu-g4a613c0be53a #1 [ 1358.361111] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1358.370706] task:python3 state:D stack:25448 pid:5775 tgid:5775 ppid:5774 task_flags:0x400100 flags:0x00080002 [ 1358.383920] Call Trace: [ 1358.387461] [ 1358.390618] __schedule+0xcc3/0x19c0 [ 1358.395438] ? io_schedule_timeout+0x140/0x140 [ 1358.401229] ? find_held_lock+0x2b/0x80 [ 1358.406337] ? __lock_release.isra.0+0x6b/0x1a0 [ 1358.412233] schedule+0xd8/0x260 [ 1358.416655] schedule_preempt_disabled+0x18/0x30 [ 1358.422640] __mutex_lock+0x6af/0x2010 [ 1358.427648] ? threaded_store+0x132/0x220 [ 1358.432956] ? ww_mutex_lock+0x160/0x160 [ 1358.438159] ? selinux_inode_free_security+0x330/0x330 [ 1358.444740] ? cap_capable+0x1d6/0x3e0 [ 1358.449753] ? threaded_store+0x132/0x220 [ 1358.455053] threaded_store+0x132/0x220 [ 1358.460159] ? xps_rxqs_store+0x2e0/0x2e0 [ 1358.465459] ? sysfs_file_kobj+0xbd/0x1e0 [ 1358.470765] ? sysfs_kf_write+0x65/0x160 [ 1358.475976] ? sysfs_file_ops+0x140/0x140 [ 1358.481274] kernfs_fop_write_iter+0x335/0x510 [ 1358.487062] ? kernfs_ops+0xf0/0xf0 [ 1358.491777] new_sync_write+0x333/0x750 [ 1358.496884] ? __lock_acquire+0x508/0xc10 [ 1358.502182] ? new_sync_read+0x740/0x740 [ 1358.507392] ? lock_acquire.part.0+0xbc/0x260 [ 1358.513082] ? ksys_write+0x116/0x250 [ 1358.518000] vfs_write+0x657/0x9b0 [ 1358.522621] ksys_write+0x116/0x250 [ 1358.527339] ? __ia32_sys_read+0xc0/0xc0 [ 1358.532539] ? kernfs_fop_llseek+0x160/0x1f0 [ 1358.538132] ? rcu_is_watching+0x15/0xd0 [ 1358.543340] do_syscall_64+0xf3/0x650 [ 1358.548252] ? trace_hardirqs_off+0xd/0x30 [ 1358.553651] ? exc_page_fault+0xda/0xf0 [ 1358.558760] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1358.565231] RIP: 0033:0x7fa8dee9022e [ 1358.570046] RSP: 002b:00007ffc966d0310 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1358.579350] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007fa8dee9022e [ 1358.588164] RDX: 0000000000000001 RSI: 000055f83ce5e6b0 RDI: 0000000000000007 [ 1358.596975] RBP: 00007ffc966d0320 R08: 0000000000000000 R09: 0000000000000000 [ 1358.605791] R10: 0000000000000000 R11: 0000000000000202 R12: 00007fa8ded08390 [ 1358.614602] R13: 000055f83ce5e6b0 R14: 0000000000000007 R15: 0000000000000003 Finger prints: __schedule:schedule:schedule_preempt_disabled:__mutex_lock:threaded_store