<6>[ 64.233907] Console: switching to colour dummy device 80x25
<7>[ 64.233975] [IGT] gem_softpin: executing
<7>[ 64.310695] i915 0000:03:00.0: [drm:i915_gem_open [i915]]
<7>[ 64.312398] i915 0000:03:00.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7>[ 64.322573] i915 0000:03:00.0: [drm:i915_gem_open [i915]]
<7>[ 64.324094] i915 0000:03:00.0: [drm:i915_gem_open [i915]]
<7>[ 64.334632] [IGT] gem_softpin: starting subtest allocator-evict
<7>[ 64.334688] [IGT] gem_softpin: starting dynamic subtest rcs0
<7>[ 64.359427] i915 0000:03:00.0: [drm:i915_gem_open [i915]]
<6>[ 64.360719] gem_softpin (1980): drop_caches: 4
<7>[ 64.360867] i915 0000:03:00.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x00000070 [0x00000070]
<7>[ 64.723757] i915 0000:03:00.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x00000070 [0x00000070]
<7>[ 64.742481] i915 0000:03:00.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7>[ 90.745771] i915 0000:03:00.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7>[ 91.039206] [IGT] gem_softpin: finished subtest rcs0, SUCCESS
<7>[ 91.039262] [IGT] gem_softpin: starting dynamic subtest bcs0
<7>[ 91.039404] i915 0000:03:00.0: [drm:i915_gem_open [i915]]
<7>[ 91.040793] i915 0000:03:00.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x00000070 [0x00000070]
<7>[ 91.520102] i915 0000:03:00.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x00000070 [0x00000070]
<7>[ 91.532506] i915 0000:03:00.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7>[ 117.128408] i915 0000:03:00.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7>[ 117.434003] [IGT] gem_softpin: finished subtest bcs0, SUCCESS
<7>[ 117.434058] [IGT] gem_softpin: starting dynamic subtest vcs0
<7>[ 117.434197] i915 0000:03:00.0: [drm:i915_gem_open [i915]]
<7>[ 117.435501] i915 0000:03:00.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x00000070 [0x00000070]
<7>[ 117.880243] i915 0000:03:00.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x00000070 [0x00000070]
<7>[ 117.895844] i915 0000:03:00.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7>[ 143.639740] i915 0000:03:00.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7>[ 143.915962] [IGT] gem_softpin: finished subtest vcs0, SUCCESS
<7>[ 143.916012] [IGT] gem_softpin: starting dynamic subtest vcs1
<7>[ 143.916154] i915 0000:03:00.0: [drm:i915_gem_open [i915]]
<7>[ 143.918412] i915 0000:03:00.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x00000070 [0x00000070]
<7>[ 144.385457] i915 0000:03:00.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x00000070 [0x00000070]
<7>[ 144.400104] i915 0000:03:00.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7>[ 170.316096] i915 0000:03:00.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7>[ 170.622305] [IGT] gem_softpin: finished subtest vcs1, SUCCESS
<7>[ 170.622358] [IGT] gem_softpin: starting dynamic subtest vecs0
<7>[ 170.622501] i915 0000:03:00.0: [drm:i915_gem_open [i915]]
<7>[ 170.623836] i915 0000:03:00.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x00000070 [0x00000070]
<7>[ 171.072453] i915 0000:03:00.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x00000070 [0x00000070]
<7>[ 171.086856] i915 0000:03:00.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7>[ 196.715430] i915 0000:03:00.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7>[ 197.008000] [IGT] gem_softpin: finished subtest vecs0, SUCCESS
<7>[ 197.008051] [IGT] gem_softpin: starting dynamic subtest vecs1
<7>[ 197.008214] i915 0000:03:00.0: [drm:i915_gem_open [i915]]
<7>[ 197.009574] i915 0000:03:00.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x00000070 [0x00000070]
<7>[ 197.468587] i915 0000:03:00.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x00000070 [0x00000070]
<7>[ 197.482805] i915 0000:03:00.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7>[ 223.083781] i915 0000:03:00.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7>[ 223.363019] [IGT] gem_softpin: finished subtest vecs1, SUCCESS
<7>[ 223.363066] [IGT] gem_softpin: starting dynamic subtest ccs0
<7>[ 223.363204] i915 0000:03:00.0: [drm:i915_gem_open [i915]]
<7>[ 223.364632] i915 0000:03:00.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x00000070 [0x00000070]
<7>[ 223.832460] i915 0000:03:00.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x00000070 [0x00000070]
<7>[ 223.848116] i915 0000:03:00.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7>[ 249.301240] i915 0000:03:00.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7>[ 249.574660] [IGT] gem_softpin: finished subtest ccs0, SUCCESS
<7>[ 249.574728] [IGT] gem_softpin: finished subtest allocator-evict, SUCCESS
<7>[ 249.576598] [IGT] gem_softpin: exiting, ret=0
<7>[ 249.576985] i915 0000:03:00.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7>[ 249.647867] i915 0000:03:00.0: [drm:drm_client_dev_restore] intel-fbdev: ret=0
<6>[ 249.665152] Console: switching to colour frame buffer device 240x67
<6>[ 249.782576] Console: switching to colour dummy device 80x25
<7>[ 249.782628] [IGT] gem_exec_balancer: executing
<7>[ 249.784271] i915 0000:03:00.0: [drm:i915_gem_open [i915]]
<7>[ 249.785856] i915 0000:03:00.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7>[ 249.843315] i915 0000:03:00.0: [drm:i915_gem_open [i915]]
<7>[ 249.844702] i915 0000:03:00.0: [drm:i915_gem_open [i915]]
<4>[ 249.963564] =============================
<4>[ 249.963566] [ BUG: Invalid wait context ]
<4>[ 249.963569] 6.13.0-rc2-CI_DRM_15816-g2223c2c738ec+ #1 Not tainted
<4>[ 249.963572] -----------------------------
<4>[ 249.963575] gem_exec_balanc/1990 is trying to lock:
<4>[ 249.963577] ffff888141b2b910 (&pmu->lock){....}-{3:3}, at: i915_pmu_enable+0x48/0x3a0 [i915]
<4>[ 249.963762] other info that might help us debug this:
<4>[ 249.963764] context-{5:5}
<4>[ 249.963767] 2 locks held by gem_exec_balanc/1990:
<4>[ 249.963769] #0: ffff88845d6320c8 (&cpuctx_mutex){+.+.}-{4:4}, at: __do_sys_perf_event_open+0x65a/0x11d0
<4>[ 249.963778] #1: ffff88845d632038 (&cpuctx_lock){....}-{2:2}, at: __perf_install_in_context+0x3f/0x360
<4>[ 249.963786] stack backtrace:
<4>[ 249.963788] CPU: 0 UID: 0 PID: 1990 Comm: gem_exec_balanc Not tainted 6.13.0-rc2-CI_DRM_15816-g2223c2c738ec+ #1
<4>[ 249.963793] Hardware name: Intel Corporation CoffeeLake Client Platform/CoffeeLake S UDIMM RVP, BIOS CNLSFWR1.R00.X220.B00.2103302221 03/30/2021
<4>[ 249.963798] Call Trace:
<4>[ 249.963801] <TASK>
<4>[ 249.963803] dump_stack_lvl+0x91/0xf0
<4>[ 249.963807] dump_stack+0x10/0x20
<4>[ 249.963810] __lock_acquire+0x990/0x2820
<4>[ 249.963817] lock_acquire+0xc9/0x300
<4>[ 249.963820] ? i915_pmu_enable+0x48/0x3a0 [i915]
<4>[ 249.963957] _raw_spin_lock_irqsave+0x49/0x80
<4>[ 249.963962] ? i915_pmu_enable+0x48/0x3a0 [i915]
<4>[ 249.964097] i915_pmu_enable+0x48/0x3a0 [i915]
<4>[ 249.964232] i915_pmu_event_add+0x71/0x90 [i915]
<4>[ 249.964367] event_sched_in+0xe8/0x200
<4>[ 249.964370] merge_sched_in+0x183/0x410
<4>[ 249.964374] visit_groups_merge.constprop.0.isra.0+0x30a/0x490
<4>[ 249.964379] ctx_sched_in+0x216/0x290
<4>[ 249.964382] perf_event_sched_in+0x7a/0x90
<4>[ 249.964386] ctx_resched+0x1a2/0x2e0
<4>[ 249.964389] __perf_install_in_context+0x232/0x360
<4>[ 249.964393] ? __pfx_remote_function+0x10/0x10
<4>[ 249.964397] remote_function+0x4c/0x70
<4>[ 249.964400] generic_exec_single+0xa5/0x220
<4>[ 249.964404] smp_call_function_single+0x110/0x160
<4>[ 249.964408] ? __pfx_remote_function+0x10/0x10
<4>[ 249.964411] ? exclusive_event_installable+0xb0/0xd0
<4>[ 249.964415] perf_install_in_context+0x1e7/0x200
<4>[ 249.964419] ? __pfx___perf_install_in_context+0x10/0x10
<4>[ 249.964423] __do_sys_perf_event_open+0x9a2/0x11d0
<4>[ 249.964426] ? __slab_free+0x185/0x310
<4>[ 249.964432] __x64_sys_perf_event_open+0x22/0x40
<4>[ 249.964436] x64_sys_call+0x1eb0/0x2650
<4>[ 249.964441] do_syscall_64+0x91/0x180
<4>[ 249.964445] ? trace_hardirqs_off+0x5d/0xe0
<4>[ 249.964449] ? syscall_exit_to_user_mode+0x95/0x260
<4>[ 249.964453] ? do_syscall_64+0x9d/0x180
<4>[ 249.964456] ? clear_bhb_loop+0x15/0x70
<4>[ 249.964459] ? clear_bhb_loop+0x15/0x70
<4>[ 249.964462] ? clear_bhb_loop+0x15/0x70
<4>[ 249.964465] entry_SYSCALL_64_after_hwframe+0x76/0x7e
<4>[ 249.964469] RIP: 0033:0x709d8b32725d
<4>[ 249.964472] Code: ff c3 66 2e 0f 1f 84 00 00 00 00 00 90 f3 0f 1e fa 48 89 f8 48 89 f7 48 89 d6 48 89 ca 4d 89 c2 4d 89 c8 4c 8b 4c 24 08 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 8b 0d 8b bb 0d 00 f7 d8 64 89 01 48
<4>[ 249.964479] RSP: 002b:00007ffec0e46ff8 EFLAGS: 00000202 ORIG_RAX: 000000000000012a
<4>[ 249.964484] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 0000709d8b32725d
<4>[ 249.964488] RDX: 0000000000000000 RSI: 00000000ffffffff RDI: 00007ffec0e47000
<4>[ 249.964491] RBP: 00000000ffffffff R08: 0000000000000000 R09: 0000008800000018
<4>[ 249.964495] R10: 00000000ffffffff R11: 0000000000000202 R12: 00007ffec0e47000
<4>[ 249.964498] R13: 0000000000000006 R14: 0000000000000000 R15: 0000000000000018
<4>[ 249.964504] </TASK>
<7>[ 250.036501] [IGT] gem_exec_balancer: starting subtest parallel-dmabuf-import-out-fence
<7>[ 251.458065] [IGT] gem_exec_balancer: finished subtest parallel-dmabuf-import-out-fence, SUCCESS
<7>[ 251.458553] i915 0000:03:00.0: [drm:error_state_write [i915]] Resetting error state
<7>[ 251.458849] i915 0000:03:00.0: [drm:i915_gem_open [i915]]
<7>[ 251.463061] [IGT] gem_exec_balancer: exiting, ret=0
<7>[ 251.463182] i915 0000:03:00.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7>[ 251.497872] i915 0000:03:00.0: [drm:drm_client_dev_restore] intel-fbdev: ret=0
<6>[ 251.514845] Console: switching to colour frame buffer device 240x67