====================================== | [ 760.359271] nipa-hw-worker: drivers/net:napi_threaded.py: timed out | [ 760.361791] nipa-hw-worker: [4/39] Retrying drivers/net:napi_threaded.py | [ 760.525023] kselftest: Running tests in drivers/net [ 989.757376] INFO: task python3:4994 blocked for more than 122 seconds. [ 989.765598] Not tainted 7.0.0-rc4-jlmg-g193b4228ec54 #1 [ 989.772848] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 989.782436] task:python3 state:D stack:25312 pid:4994 tgid:4994 ppid:4993 task_flags:0x400100 flags:0x00080000 [ 989.795641] Call Trace: [ 989.799179] [ 989.802331] __schedule+0xad9/0x1490 [ 989.807147] ? io_schedule_timeout+0x130/0x130 [ 989.812931] ? find_held_lock+0x2b/0x80 [ 989.818036] ? __lock_release.isra.0+0x59/0x170 [ 989.823921] schedule+0xd4/0x260 [ 989.828340] schedule_preempt_disabled+0x18/0x30 [ 989.834315] __mutex_lock+0xd76/0x1f20 [ 989.839321] ? threaded_store+0x132/0x220 [ 989.844621] ? ww_mutex_lock+0x160/0x160 [ 989.849819] ? selinux_inode_free_security+0x310/0x310 [ 989.856386] ? cap_capable+0x1ed/0x3f0 [ 989.861392] ? sysfs_file_ops+0x140/0x140 [ 989.866691] ? threaded_store+0x132/0x220 [ 989.871989] threaded_store+0x132/0x220 [ 989.877081] ? tx_maxrate_store+0x4e0/0x4e0 [ 989.882574] ? sysfs_file_ops+0x140/0x140 [ 989.887869] ? sysfs_file_kobj+0xbd/0x1e0 [ 989.893163] ? sysfs_kf_write+0x65/0x160 [ 989.898362] kernfs_fop_write_iter+0x335/0x510 [ 989.904146] ? kernfs_ops+0xf0/0xf0 [ 989.908857] new_sync_write+0x308/0x6f0 [ 989.913962] ? new_sync_read+0x6f0/0x6f0 [ 989.919162] ? lock_acquire.part.0+0xbc/0x260 [ 989.924838] ? ksys_write+0xff/0x200 [ 989.929652] vfs_write+0x62a/0x980 [ 989.934266] ksys_write+0xff/0x200 [ 989.938878] ? __ia32_sys_read+0xc0/0xc0 [ 989.944073] ? kernfs_fop_llseek+0x160/0x1f0 [ 989.949662] ? rcu_is_watching+0x15/0xd0 [ 989.954852] do_syscall_64+0xf3/0x5e0 [ 989.959759] ? exc_page_fault+0x9e/0xb0 [ 989.964864] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 989.971333] RIP: 0033:0x7f1d9e5b4c5e [ 989.976144] RSP: 002b:00007ffd5cb02fe0 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 989.985442] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f1d9e5b4c5e [ 989.994247] RDX: 0000000000000001 RSI: 00005559182f2110 RDI: 0000000000000007 [ 990.003052] RBP: 00007ffd5cb02ff0 R08: 0000000000000000 R09: 0000000000000000 [ 990.011860] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f1d9e44fe80 [ 990.020667] R13: 00005559182f2110 R14: 0000000000000007 R15: 00007f1d9ecb5b98 | [ 990.391259] #1: ff110001bcc8a088 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x247/0x510 | [ 990.402213] #2: ff110017daa250f0 (kn->active#127){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 990.413371] #3: ff110017daa99cb8 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 990.423451] | [ 990.425915] =============================================\x0a [ 1112.637698] INFO: task python3:4994 blocked for more than 245 seconds. [ 1112.645843] Not tainted 7.0.0-rc4-jlmg-g193b4228ec54 #1 [ 1112.653096] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1112.662686] task:python3 state:D stack:25312 pid:4994 tgid:4994 ppid:4993 task_flags:0x400100 flags:0x00080000 [ 1112.675894] Call Trace: [ 1112.679436] [ 1112.682587] __schedule+0xad9/0x1490 [ 1112.687402] ? io_schedule_timeout+0x130/0x130 [ 1112.693186] ? find_held_lock+0x2b/0x80 [ 1112.698291] ? __lock_release.isra.0+0x59/0x170 [ 1112.704167] schedule+0xd4/0x260 [ 1112.708585] schedule_preempt_disabled+0x18/0x30 [ 1112.714564] __mutex_lock+0xd76/0x1f20 [ 1112.719570] ? threaded_store+0x132/0x220 [ 1112.724870] ? ww_mutex_lock+0x160/0x160 [ 1112.730068] ? selinux_inode_free_security+0x310/0x310 [ 1112.736631] ? cap_capable+0x1ed/0x3f0 [ 1112.741641] ? sysfs_file_ops+0x140/0x140 [ 1112.746938] ? threaded_store+0x132/0x220 [ 1112.752236] threaded_store+0x132/0x220 [ 1112.757333] ? tx_maxrate_store+0x4e0/0x4e0 [ 1112.762828] ? sysfs_file_ops+0x140/0x140 [ 1112.768123] ? sysfs_file_kobj+0xbd/0x1e0 [ 1112.773420] ? sysfs_kf_write+0x65/0x160 [ 1112.778618] kernfs_fop_write_iter+0x335/0x510 [ 1112.784404] ? kernfs_ops+0xf0/0xf0 [ 1112.789106] new_sync_write+0x308/0x6f0 [ 1112.794211] ? new_sync_read+0x6f0/0x6f0 [ 1112.799414] ? lock_acquire.part.0+0xbc/0x260 [ 1112.805102] ? ksys_write+0xff/0x200 [ 1112.809916] vfs_write+0x62a/0x980 [ 1112.814536] ksys_write+0xff/0x200 [ 1112.819152] ? __ia32_sys_read+0xc0/0xc0 [ 1112.824350] ? kernfs_fop_llseek+0x160/0x1f0 [ 1112.829940] ? rcu_is_watching+0x15/0xd0 [ 1112.835133] do_syscall_64+0xf3/0x5e0 [ 1112.840040] ? exc_page_fault+0x9e/0xb0 [ 1112.845145] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1112.851613] RIP: 0033:0x7f1d9e5b4c5e [ 1112.856426] RSP: 002b:00007ffd5cb02fe0 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1112.865727] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f1d9e5b4c5e [ 1112.874540] RDX: 0000000000000001 RSI: 00005559182f2110 RDI: 0000000000000007 [ 1112.883347] RBP: 00007ffd5cb02ff0 R08: 0000000000000000 R09: 0000000000000000 [ 1112.892150] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f1d9e44fe80 [ 1112.900956] R13: 00005559182f2110 R14: 0000000000000007 R15: 00007f1d9ecb5b98 | [ 1113.271650] #1: ff110001bcc8a088 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x247/0x510 | [ 1113.282612] #2: ff110017daa250f0 (kn->active#127){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1113.293771] #3: ff110017daa99cb8 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1113.303862] | [ 1113.306330] =============================================\x0a [ 1235.519041] INFO: task python3:4994 blocked for more than 368 seconds. [ 1235.527190] Not tainted 7.0.0-rc4-jlmg-g193b4228ec54 #1 [ 1235.534440] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1235.544029] task:python3 state:D stack:25312 pid:4994 tgid:4994 ppid:4993 task_flags:0x400100 flags:0x00080002 [ 1235.557225] Call Trace: [ 1235.560839] [ 1235.563993] __schedule+0xad9/0x1490 [ 1235.568807] ? io_schedule_timeout+0x130/0x130 [ 1235.574584] ? find_held_lock+0x2b/0x80 [ 1235.579692] ? __lock_release.isra.0+0x59/0x170 [ 1235.585577] schedule+0xd4/0x260 [ 1235.589994] schedule_preempt_disabled+0x18/0x30 [ 1235.595971] __mutex_lock+0xd76/0x1f20 [ 1235.600976] ? threaded_store+0x132/0x220 [ 1235.606274] ? ww_mutex_lock+0x160/0x160 [ 1235.611473] ? selinux_inode_free_security+0x310/0x310 [ 1235.618041] ? cap_capable+0x1ed/0x3f0 [ 1235.623049] ? sysfs_file_ops+0x140/0x140 [ 1235.628350] ? threaded_store+0x132/0x220 [ 1235.633650] threaded_store+0x132/0x220 [ 1235.638752] ? tx_maxrate_store+0x4e0/0x4e0 [ 1235.644243] ? sysfs_file_ops+0x140/0x140 [ 1235.649541] ? sysfs_file_kobj+0xbd/0x1e0 [ 1235.654832] ? sysfs_kf_write+0x65/0x160 [ 1235.660033] kernfs_fop_write_iter+0x335/0x510 [ 1235.665819] ? kernfs_ops+0xf0/0xf0 [ 1235.670533] new_sync_write+0x308/0x6f0 [ 1235.675636] ? new_sync_read+0x6f0/0x6f0 [ 1235.680837] ? lock_acquire.part.0+0xbc/0x260 [ 1235.686523] ? ksys_write+0xff/0x200 [ 1235.691335] vfs_write+0x62a/0x980 [ 1235.695951] ksys_write+0xff/0x200 [ 1235.700566] ? __ia32_sys_read+0xc0/0xc0 [ 1235.705766] ? kernfs_fop_llseek+0x160/0x1f0 [ 1235.711357] ? rcu_is_watching+0x15/0xd0 [ 1235.716557] do_syscall_64+0xf3/0x5e0 [ 1235.721462] ? exc_page_fault+0x9e/0xb0 [ 1235.726565] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1235.733035] RIP: 0033:0x7f1d9e5b4c5e [ 1235.737845] RSP: 002b:00007ffd5cb02fe0 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1235.747144] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f1d9e5b4c5e [ 1235.755950] RDX: 0000000000000001 RSI: 00005559182f2110 RDI: 0000000000000007 [ 1235.764759] RBP: 00007ffd5cb02ff0 R08: 0000000000000000 R09: 0000000000000000 [ 1235.773569] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f1d9e44fe80 [ 1235.782378] R13: 00005559182f2110 R14: 0000000000000007 R15: 00007f1d9ecb5b98 | [ 1236.153082] #1: ff110001bcc8a088 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x247/0x510 | [ 1236.164042] #2: ff110017daa250f0 (kn->active#127){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 | [ 1236.175200] #3: ff110017daa99cb8 (&dev->lock){+.+.}-{4:4}, at: threaded_store+0x132/0x220 | [ 1236.185284] | [ 1236.187750] =============================================\x0a [ 1358.401369] INFO: task python3:4994 blocked for more than 491 seconds. [ 1358.409515] Not tainted 7.0.0-rc4-jlmg-g193b4228ec54 #1 [ 1358.416762] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1358.426350] task:python3 state:D stack:25312 pid:4994 tgid:4994 ppid:4993 task_flags:0x400100 flags:0x00080002 [ 1358.439553] Call Trace: [ 1358.443094] [ 1358.446242] __schedule+0xad9/0x1490 [ 1358.451055] ? io_schedule_timeout+0x130/0x130 [ 1358.456836] ? find_held_lock+0x2b/0x80 [ 1358.461938] ? __lock_release.isra.0+0x59/0x170 [ 1358.467845] schedule+0xd4/0x260 [ 1358.472264] schedule_preempt_disabled+0x18/0x30 [ 1358.478240] __mutex_lock+0xd76/0x1f20 [ 1358.483243] ? threaded_store+0x132/0x220 [ 1358.488542] ? ww_mutex_lock+0x160/0x160 [ 1358.493740] ? selinux_inode_free_security+0x310/0x310 [ 1358.500310] ? cap_capable+0x1ed/0x3f0 [ 1358.505317] ? sysfs_file_ops+0x140/0x140 [ 1358.510613] ? threaded_store+0x132/0x220 [ 1358.515907] threaded_store+0x132/0x220 [ 1358.521010] ? tx_maxrate_store+0x4e0/0x4e0 [ 1358.526501] ? sysfs_file_ops+0x140/0x140 [ 1358.531795] ? sysfs_file_kobj+0xbd/0x1e0 [ 1358.537090] ? sysfs_kf_write+0x65/0x160 [ 1358.542291] kernfs_fop_write_iter+0x335/0x510 [ 1358.548073] ? kernfs_ops+0xf0/0xf0 [ 1358.552786] new_sync_write+0x308/0x6f0 [ 1358.557888] ? new_sync_read+0x6f0/0x6f0 [ 1358.563088] ? lock_acquire.part.0+0xbc/0x260 [ 1358.568775] ? ksys_write+0xff/0x200 [ 1358.573586] vfs_write+0x62a/0x980 [ 1358.578200] ksys_write+0xff/0x200 [ 1358.582814] ? __ia32_sys_read+0xc0/0xc0 [ 1358.588010] ? kernfs_fop_llseek+0x160/0x1f0 [ 1358.593597] ? rcu_is_watching+0x15/0xd0 [ 1358.598798] do_syscall_64+0xf3/0x5e0 [ 1358.603694] ? exc_page_fault+0x9e/0xb0 [ 1358.608794] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1358.615259] RIP: 0033:0x7f1d9e5b4c5e [ 1358.620066] RSP: 002b:00007ffd5cb02fe0 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 1358.629365] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f1d9e5b4c5e [ 1358.638171] RDX: 0000000000000001 RSI: 00005559182f2110 RDI: 0000000000000007 [ 1358.646976] RBP: 00007ffd5cb02ff0 R08: 0000000000000000 R09: 0000000000000000 [ 1358.655781] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f1d9e44fe80 [ 1358.664589] R13: 00005559182f2110 R14: 0000000000000007 R15: 00007f1d9ecb5b98 Finger prints: __schedule:schedule:schedule_preempt_disabled:__mutex_lock:threaded_store