Oops#1 Part21 <0>[ 319.206612] kms_big_-2580 11..s.. 297154191us : rps_timer: 0000:00:02.0 busy:0 [0%], max:[0, 0, 0], interval:2 <0>[ 319.206694] kms_big_-2580 11..... 297155730us : __i915_request_commit: 0000:00:02.0 rcs0: fence 1b6:134, current 132 <0>[ 319.206775] kms_big_-2580 11d.s2. 297155754us : __i915_request_submit: 0000:00:02.0 rcs0: fence 1b6:134, current 132 <0>[ 319.206844] kms_big_-2580 11d.s1. 297155760us : execlists_submission_tasklet: 0000:00:02.0 rcs0: context:1b6 schedule-in, ccid:20 <0>[ 319.206912] kms_big_-2580 11d.s1. 297155765us : trace_ports: 0000:00:02.0 rcs0: submit { ccid:20 1b6:134 prio 0 } <0>[ 319.206980] -0 10..s1. 297156108us : process_csb: 0000:00:02.0 rcs0: cs-irq head=2, tail=3 <0>[ 319.207046] -0 10..s1. 297156111us : process_csb: 0000:00:02.0 rcs0: csb[3]: status=0x03ff8000:0x00008001 <0>[ 319.207114] -0 10..s1. 297156115us : trace_ports: 0000:00:02.0 rcs0: promote { ccid:20 1b6:134* prio 0 } <0>[ 319.207181] -0 10..s1. 297156742us : process_csb: 0000:00:02.0 rcs0: cs-irq head=3, tail=4 <0>[ 319.207247] -0 10..s1. 297156743us : process_csb: 0000:00:02.0 rcs0: csb[4]: status=0x00008000:0x03ff8000 <0>[ 319.207314] -0 10..s1. 297156763us : trace_ports: 0000:00:02.0 rcs0: completed { ccid:20 1b6:134! prio 0 } <0>[ 319.207382] -0 10..s1. 297156765us : __execlists_schedule_out: 0000:00:02.0 rcs0: context:1b6 schedule-out, ccid:20 <0>[ 319.207465] kworker/-860 10..... 297156798us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 1b6:134, current 134 Oops#1 Part20 <0>[ 319.207539] kms_big_-2580 11..... 297156820us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: yes <0>[ 319.207605] kworker/-860 10..... 297156842us : __intel_context_do_unpin.part.0: 0000:00:02.0 rcs0: context:1b6 unpin <0>[ 319.207680] kworker/-927 9d..2. 297157041us : intel_gt_invalidate_tlb_full.part.0: 0000:00:02.0 invalidated engines 00000001 <0>[ 319.207754] kms_big_-2580 11..s.. 297157201us : rps_timer: 0000:00:02.0 busy:1022209 [33%], max:[1022209, 0, 0], interval:4 <0>[ 319.207835] kworker/-860 10..... 297160316us : __i915_request_commit: 0000:00:02.0 rcs0: fence 4:277, current 276 <0>[ 319.207903] kworker/-860 10..... 297160323us : __engine_park: 0000:00:02.0 rcs0: parking <0>[ 319.207982] ksoftirq-79 10d.s1. 297160397us : __i915_request_submit: 0000:00:02.0 rcs0: fence 4:277, current 276 <0>[ 319.208050] ksoftirq-79 10d.s.. 297160414us : execlists_submission_tasklet: 0000:00:02.0 rcs0: context:4 schedule-in, ccid:20 <0>[ 319.208119] ksoftirq-79 10d.s.. 297160418us : trace_ports: 0000:00:02.0 rcs0: submit { ccid:20 4:277* prio 2147483646 } <0>[ 319.208186] -0 10dNH2. 297160557us : __intel_context_retire: 0000:00:02.0 rcs0: context:1b6 retire runtime: { total:8704708ns, avg:316479ns } <0>[ 319.208256] -0 10.Ns1. 297160583us : process_csb: 0000:00:02.0 rcs0: cs-irq head=4, tail=6 <0>[ 319.208322] -0 10.Ns1. 297160584us : process_csb: 0000:00:02.0 rcs0: csb[5]: status=0x03ff8000:0x00008001 <0>[ 319.208391] -0 10.Ns1. 297160586us : trace_ports: 0000:00:02.0 rcs0: promote { ccid:20 4:277! prio 2147483646 } Oops#1 Part19 <0>[ 319.208459] -0 10.Ns1. 297160589us : process_csb: 0000:00:02.0 rcs0: csb[6]: status=0x00008000:0x03ff8000 <0>[ 319.208526] -0 10.Ns1. 297160591us : trace_ports: 0000:00:02.0 rcs0: completed { ccid:20 4:277! prio 2147483646 } <0>[ 319.208595] -0 10.Ns1. 297160593us : __execlists_schedule_out: 0000:00:02.0 rcs0: context:4 schedule-out, ccid:20 <0>[ 319.208678] kworker/-860 10..... 297160603us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 4:277, current 277 <0>[ 319.208746] kworker/-860 10..... 297160621us : __engine_park: 0000:00:02.0 rcs0: parked <0>[ 319.208815] kworker/-860 10..... 297160693us : __gt_park: 0000:00:02.0 <0>[ 319.208885] kworker/-219 5..... 297187421us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: no <0>[ 319.208959] kms_big_-2580 5..... 297282604us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: yes <0>[ 319.209032] kworker/-219 5..... 297320545us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: no <0>[ 319.209102] kms_big_-2580 11..... 297359013us : __gt_unpark: 0000:00:02.0 <0>[ 319.209172] kms_big_-2580 11..... 297359038us : intel_rps_unpark: 0000:00:02.0 unpark:15 <0>[ 319.209237] kms_big_-2580 11..... 297359227us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:1b6 active <0>[ 319.209311] kms_big_-2580 11..... 297359237us : intel_timeline_pin: 0000:00:02.0 timeline:1b6 using HWSP offset:fffd5000 <0>[ 319.209380] kms_big_-2580 11..... 297359294us : __engine_unpark: 0000:00:02.0 rcs0: Oops#1 Part18 <0>[ 319.209449] kms_big_-2580 11..... 297359336us : __engine_unpark: 0000:00:02.0 rcs0: context:4 reset { seqno:115, *hwsp:115, ring:108 } <0>[ 319.209517] kms_big_-2580 11..... 297359340us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:1b6 pin ring:{start:fffc7000, head:3aa0, tail:3aa0} <0>[ 319.209587] kms_big_-2580 11..... 297359427us : __intel_context_do_unpin.part.0: 0000:00:02.0 rcs0: context:1b6 unpin <0>[ 319.209653] kms_big_-2580 11..... 297359435us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:1b6 active <0>[ 319.209719] kms_big_-2580 11..... 297359441us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:1b6 pin ring:{start:fffc7000, head:3aa0, tail:3aa0} <0>[ 319.209788] kms_big_-2580 11..... 297359480us : __intel_context_do_unpin.part.0: 0000:00:02.0 rcs0: context:1b6 unpin <0>[ 319.209854] kms_big_-2580 11..... 297359484us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:1b6 active <0>[ 319.209919] kms_big_-2580 11..... 297359489us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:1b6 pin ring:{start:fffc7000, head:3aa0, tail:3aa0} <0>[ 319.209995] kms_big_-2580 11..s.. 297360114us : rps_timer: 0000:00:02.0 busy:180658 [3%], max:[180658, 0, 0], interval:1 <0>[ 319.210068] kms_big_-2580 11..s.. 297362096us : rps_timer: 0000:00:02.0 busy:0 [0%], max:[0, 0, 0], interval:2 <0>[ 319.210150] kms_big_-2580 11..... 297364720us : __i915_request_commit: 0000:00:02.0 rcs0: fence 1b6:136, current 134 <0>[ 319.210232] kms_big_-2580 11d.s2. 297364743us : __i915_request_submit: 0000:00:02.0 rcs0: fence 1b6:136, current 134 Oops#1 Part17 <0>[ 319.210301] kms_big_-2580 11d.s1. 297364750us : execlists_submission_tasklet: 0000:00:02.0 rcs0: context:1b6 schedule-in, ccid:20 <0>[ 319.210369] kms_big_-2580 11d.s1. 297364755us : trace_ports: 0000:00:02.0 rcs0: submit { ccid:20 1b6:136 prio 0 } <0>[ 319.210439] -0 10..s1. 297365095us : process_csb: 0000:00:02.0 rcs0: cs-irq head=6, tail=7 <0>[ 319.210505] -0 10..s1. 297365097us : process_csb: 0000:00:02.0 rcs0: csb[7]: status=0x03ff8000:0x00008001 <0>[ 319.210579] kms_big_-2580 11..s1. 297365099us : rps_timer: 0000:00:02.0 busy:345660 [11%], max:[345660, 0, 0], interval:4 <0>[ 319.210646] -0 10..s1. 297365101us : trace_ports: 0000:00:02.0 rcs0: promote { ccid:20 1b6:136* prio 0 } <0>[ 319.210714] -0 10..s1. 297365733us : process_csb: 0000:00:02.0 rcs0: cs-irq head=7, tail=8 <0>[ 319.210780] -0 10..s1. 297365735us : process_csb: 0000:00:02.0 rcs0: csb[8]: status=0x00008000:0x03ff8000 <0>[ 319.210847] -0 10..s1. 297365739us : trace_ports: 0000:00:02.0 rcs0: completed { ccid:20 1b6:136! prio 0 } <0>[ 319.210914] -0 10..s1. 297365742us : __execlists_schedule_out: 0000:00:02.0 rcs0: context:1b6 schedule-out, ccid:20 <0>[ 319.210996] kworker/-860 10..... 297365776us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 1b6:136, current 136 <0>[ 319.211063] kworker/-860 10..... 297365820us : __intel_context_do_unpin.part.0: 0000:00:02.0 rcs0: context:1b6 unpin <0>[ 319.211139] kworker/-927 9d..2. 297366025us : intel_gt_invalidate_tlb_full.part.0: 0000:00:02.0 invalidated engines 00000001 Oops#1 Part16 <0>[ 319.211213] kms_big_-2580 11..... 297366310us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: yes <0>[ 319.211296] kworker/-860 10..... 297369203us : __i915_request_commit: 0000:00:02.0 rcs0: fence 4:278, current 277 <0>[ 319.211369] kworker/-860 10..... 297369209us : __engine_park: 0000:00:02.0 rcs0: parking <0>[ 319.211453] ksoftirq-79 10d.s1. 297369272us : __i915_request_submit: 0000:00:02.0 rcs0: fence 4:278, current 277 <0>[ 319.211522] ksoftirq-79 10d.s.. 297369288us : execlists_submission_tasklet: 0000:00:02.0 rcs0: context:4 schedule-in, ccid:20 <0>[ 319.211592] ksoftirq-79 10d.s.. 297369292us : trace_ports: 0000:00:02.0 rcs0: submit { ccid:20 4:278* prio 2147483646 } <0>[ 319.211660] -0 10dNH2. 297369437us : __intel_context_retire: 0000:00:02.0 rcs0: context:1b6 retire runtime: { total:9378751ns, avg:361216ns } <0>[ 319.211731] -0 10.Ns1. 297369456us : process_csb: 0000:00:02.0 rcs0: cs-irq head=8, tail=10 <0>[ 319.211798] -0 10.Ns1. 297369457us : process_csb: 0000:00:02.0 rcs0: csb[9]: status=0x03ff8000:0x00008001 <0>[ 319.211865] -0 10.Ns1. 297369459us : trace_ports: 0000:00:02.0 rcs0: promote { ccid:20 4:278! prio 2147483646 } <0>[ 319.211933] -0 10.Ns1. 297369461us : process_csb: 0000:00:02.0 rcs0: csb[10]: status=0x00008000:0x03ff8000 <0>[ 319.212001] -0 10.Ns1. 297369463us : trace_ports: 0000:00:02.0 rcs0: completed { ccid:20 4:278! prio 2147483646 } <0>[ 319.212070] -0 10.Ns1. 297369465us : __execlists_schedule_out: 0000:00:02.0 rcs0: context:4 schedule-out, ccid:20 Oops#1 Part15 <0>[ 319.212151] kworker/-860 10..... 297369475us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 4:278, current 278 <0>[ 319.212219] kworker/-860 10..... 297369492us : __engine_park: 0000:00:02.0 rcs0: parked <0>[ 319.212287] kworker/-860 10..... 297369562us : __gt_park: 0000:00:02.0 <0>[ 319.212357] -0 11..s1. 297370105us : rps_timer: 0000:00:02.0 busy:842825 [16%], max:[842825, 0, 0], interval:8 <0>[ 319.212432] kworker/-87 11..... 297396278us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: no <0>[ 319.212505] kms_big_-2580 5..... 297491328us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: yes <0>[ 319.212577] kworker/-219 5..... 297529198us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: no <0>[ 319.212647] kms_big_-2580 5..... 297567721us : __gt_unpark: 0000:00:02.0 <0>[ 319.212716] kms_big_-2580 5..... 297567746us : intel_rps_unpark: 0000:00:02.0 unpark:15 <0>[ 319.212780] kms_big_-2580 5..... 297567893us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:1b6 active <0>[ 319.212854] kms_big_-2580 5..... 297567904us : intel_timeline_pin: 0000:00:02.0 timeline:1b6 using HWSP offset:fffd5000 <0>[ 319.212922] kms_big_-2580 5..... 297568015us : __engine_unpark: 0000:00:02.0 rcs0: <0>[ 319.212986] kms_big_-2580 5..... 297568078us : __engine_unpark: 0000:00:02.0 rcs0: context:4 reset { seqno:116, *hwsp:116, ring:1b8 } <0>[ 319.213054] kms_big_-2580 5..... 297568084us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:1b6 pin ring:{start:fffc7000, head:3b80, tail:3b80} Oops#1 Part14 <0>[ 319.213129] kms_big_-2580 5..s.. 297568985us : rps_timer: 0000:00:02.0 busy:0 [0%], max:[0, 0, 0], interval:1 <0>[ 319.213202] kms_big_-2580 5..s.. 297570975us : rps_timer: 0000:00:02.0 busy:0 [0%], max:[0, 0, 0], interval:2 <0>[ 319.213282] kms_big_-2580 5..... 297573209us : __i915_request_commit: 0000:00:02.0 rcs0: fence 1b6:138, current 136 <0>[ 319.213364] kms_big_-2580 5d.s2. 297573231us : __i915_request_submit: 0000:00:02.0 rcs0: fence 1b6:138, current 136 <0>[ 319.213435] kms_big_-2580 5d.s1. 297573238us : execlists_submission_tasklet: 0000:00:02.0 rcs0: context:1b6 schedule-in, ccid:20 <0>[ 319.213503] kms_big_-2580 5d.s1. 297573243us : trace_ports: 0000:00:02.0 rcs0: submit { ccid:20 1b6:138 prio 0 } <0>[ 319.213571] -0 10..s1. 297573585us : process_csb: 0000:00:02.0 rcs0: cs-irq head=10, tail=11 <0>[ 319.213637] -0 10..s1. 297573587us : process_csb: 0000:00:02.0 rcs0: csb[11]: status=0x03ff8000:0x00008001 <0>[ 319.213705] -0 10..s1. 297573593us : trace_ports: 0000:00:02.0 rcs0: promote { ccid:20 1b6:138* prio 0 } <0>[ 319.213778] kms_big_-2580 5..s1. 297574000us : rps_timer: 0000:00:02.0 busy:757984 [25%], max:[757984, 0, 0], interval:4 <0>[ 319.213846] -0 10..s1. 297574226us : process_csb: 0000:00:02.0 rcs0: cs-irq head=11, tail=0 <0>[ 319.213912] -0 10..s1. 297574227us : process_csb: 0000:00:02.0 rcs0: csb[0]: status=0x00008000:0x03ff8000 <0>[ 319.213979] -0 10..s1. 297574229us : trace_ports: 0000:00:02.0 rcs0: completed { ccid:20 1b6:138! prio 0 } Oops#1 Part13 <0>[ 319.214046] -0 10..s1. 297574232us : __execlists_schedule_out: 0000:00:02.0 rcs0: context:1b6 schedule-out, ccid:20 <0>[ 319.214127] kworker/-860 10..... 297574265us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 1b6:138, current 138 <0>[ 319.214201] kms_big_-2580 5..... 297574297us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: yes <0>[ 319.214267] kworker/-860 10..... 297574307us : __intel_context_do_unpin.part.0: 0000:00:02.0 rcs0: context:1b6 unpin <0>[ 319.214341] kworker/-927 9d..2. 297574568us : intel_gt_invalidate_tlb_full.part.0: 0000:00:02.0 invalidated engines 00000001 <0>[ 319.214425] kworker/-860 10..... 297578014us : __i915_request_commit: 0000:00:02.0 rcs0: fence 4:279, current 278 <0>[ 319.214493] kworker/-860 10..... 297578029us : __engine_park: 0000:00:02.0 rcs0: parking <0>[ 319.214572] ksoftirq-79 10d.s1. 297578091us : __i915_request_submit: 0000:00:02.0 rcs0: fence 4:279, current 278 <0>[ 319.214642] ksoftirq-79 10d.s.. 297578108us : execlists_submission_tasklet: 0000:00:02.0 rcs0: context:4 schedule-in, ccid:20 <0>[ 319.214711] ksoftirq-79 10d.s.. 297578112us : trace_ports: 0000:00:02.0 rcs0: submit { ccid:20 4:279* prio 2147483646 } <0>[ 319.214778] -0 10dNH2. 297578260us : __intel_context_retire: 0000:00:02.0 rcs0: context:1b6 retire runtime: { total:10098278ns, avg:405953ns } <0>[ 319.214849] -0 10.Ns1. 297578288us : process_csb: 0000:00:02.0 rcs0: cs-irq head=0, tail=2 <0>[ 319.214916] -0 10.Ns1. 297578289us : process_csb: 0000:00:02.0 rcs0: csb[1]: status=0x03ff8000:0x00008001 Oops#1 Part12 <0>[ 319.214984] -0 10.Ns1. 297578291us : trace_ports: 0000:00:02.0 rcs0: promote { ccid:20 4:279! prio 2147483646 } <0>[ 319.215052] -0 10.Ns1. 297578294us : process_csb: 0000:00:02.0 rcs0: csb[2]: status=0x00008000:0x03ff8000 <0>[ 319.215119] -0 10.Ns1. 297578295us : trace_ports: 0000:00:02.0 rcs0: completed { ccid:20 4:279! prio 2147483646 } <0>[ 319.215187] -0 10.Ns1. 297578298us : __execlists_schedule_out: 0000:00:02.0 rcs0: context:4 schedule-out, ccid:20 <0>[ 319.215269] kworker/-860 10..... 297578309us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 4:279, current 279 <0>[ 319.215336] kworker/-860 10..... 297578330us : __engine_park: 0000:00:02.0 rcs0: parked <0>[ 319.215406] kworker/-860 10..... 297578436us : __gt_park: 0000:00:02.0 <0>[ 319.215477] -0 5..s1. 297579050us : rps_timer: 0000:00:02.0 busy:445803 [8%], max:[445803, 0, 0], interval:8 <0>[ 319.215551] kworker/-219 5..... 297585740us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: no <0>[ 319.215623] kms_big_-2580 5..... 297681370us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: yes <0>[ 319.215696] kworker/-219 5..... 297718811us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: no <0>[ 319.215715] kms_big_-2580 5...1. 297758704us : tracing_mark_write: kms_big_fb: finished subtest x-tiled-max-hw-stride-32bpp-rotate-0-hflip, SUCCESS <0>[ 319.215789] kms_big_-2580 3..... 297836348us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: yes Oops#1 Part11 <0>[ 319.215862] kms_big_-2580 3..... 297854747us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: yes <0>[ 319.215934] kworker/-199 3..... 297870908us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: no <0>[ 319.216006] kms_big_-2580 3..... 297872210us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: yes <0>[ 319.216078] kworker/-199 3..... 297889924us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: no <0>[ 319.216095] kms_fron-2581 2...1. 297968162us : tracing_mark_write: kms_frontbuffer_tracking: executing <0>[ 319.216110] kms_fron-2581 2...1. 298042827us : tracing_mark_write: kms_frontbuffer_tracking: starting subtest pipe-fbc-rte <0>[ 319.216127] kms_fron-2581 2...1. 298043436us : tracing_mark_write: kms_frontbuffer_tracking: finished subtest pipe-fbc-rte, SKIP <0>[ 319.216199] kms_fron-2581 2..... 298066398us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: yes <0>[ 319.216271] kms_fron-2581 2.l... 298081985us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: yes <0>[ 319.216343] kworker/-124 2..... 298082469us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: no <0>[ 319.216417] kworker/-124 2..... 298098821us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: no <0>[ 319.216488] kms_fron-2581 2..... 298100005us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: yes <0>[ 319.216559] kworker/-124 2..... 298117789us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: no <0>[ 319.216576] kms_psr-2582 9...1. 298206558us : tracing_mark_write: kms_psr: executing Oops#1 Part10 <0>[ 319.216645] kms_psr-2582 1..... 298286412us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: yes <0>[ 319.216716] kworker/-27 1..... 298288374us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: no <0>[ 319.216733] perf_pmu-2583 0...1. 298342181us : tracing_mark_write: perf_pmu: executing <0>[ 319.216796] perf_pmu-2583 0..... 298345334us : __engine_unpark: 0000:00:02.0 vecs0: <0>[ 319.216863] perf_pmu-2583 0..... 298345342us : __gt_unpark: 0000:00:02.0 <0>[ 319.216932] perf_pmu-2583 0..... 298345350us : intel_rps_unpark: 0000:00:02.0 unpark:15 <0>[ 319.216996] perf_pmu-2583 0..... 298345364us : __engine_unpark: 0000:00:02.0 vecs0: context:7 reset { seqno:ba, *hwsp:ba, ring:d48 } <0>[ 319.217065] perf_pmu-2583 0..... 298345366us : __engine_park: 0000:00:02.0 vecs0: parked <0>[ 319.217128] perf_pmu-2583 0..... 298345793us : __engine_unpark: 0000:00:02.0 vcs0: <0>[ 319.217192] perf_pmu-2583 0..... 298345801us : __engine_unpark: 0000:00:02.0 vcs0: context:6 reset { seqno:be, *hwsp:be, ring:f48 } <0>[ 319.217260] perf_pmu-2583 0..... 298345802us : __engine_park: 0000:00:02.0 vcs0: parked <0>[ 319.217324] perf_pmu-2583 0..... 298346201us : __engine_unpark: 0000:00:02.0 bcs0: <0>[ 319.217390] perf_pmu-2583 0..... 298346208us : __engine_unpark: 0000:00:02.0 bcs0: context:5 reset { seqno:1c9, *hwsp:1c9, ring:4c8 } <0>[ 319.217459] perf_pmu-2583 0..... 298346209us : __engine_park: 0000:00:02.0 bcs0: parked <0>[ 319.217528] perf_pmu-2583 0..s.. 298346560us : rps_timer: 0000:00:02.0 busy:0 [0%], max:[0, 0, 0], interval:1 Oops#1 Part9 <0>[ 319.217594] perf_pmu-2583 0..... 298346588us : __engine_unpark: 0000:00:02.0 rcs0: <0>[ 319.217657] perf_pmu-2583 0..... 298346599us : __engine_unpark: 0000:00:02.0 rcs0: context:4 reset { seqno:117, *hwsp:117, ring:268 } <0>[ 319.217725] perf_pmu-2583 0..... 298346600us : __engine_park: 0000:00:02.0 rcs0: parked <0>[ 319.217791] kworker/-177 0..... 298347566us : __gt_park: 0000:00:02.0 <0>[ 319.217804] perf_pmu-2583 0...1. 298347698us : tracing_mark_write: perf_pmu: starting subtest idle-no-semaphores <0>[ 319.217872] perf_pmu-2583 0..... 298348183us : __gt_unpark: 0000:00:02.0 <0>[ 319.217940] perf_pmu-2583 0..... 298348196us : intel_rps_unpark: 0000:00:02.0 unpark:15 <0>[ 319.218007] perf_pmu-2583 0..... 298348421us : __gt_park: 0000:00:02.0 <0>[ 319.218072] perf_pmu-2583 0..... 298348828us : __gt_unpark: 0000:00:02.0 <0>[ 319.218141] perf_pmu-2583 0..... 298348839us : intel_rps_unpark: 0000:00:02.0 unpark:15 <0>[ 319.218204] perf_pmu-2583 0..... 298348931us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:1b9 active <0>[ 319.218279] perf_pmu-2583 0..... 298349014us : intel_timeline_pin: 0000:00:02.0 timeline:1b9 using HWSP offset:fffd5000 <0>[ 319.218346] perf_pmu-2583 0..... 298349113us : __engine_unpark: 0000:00:02.0 rcs0: <0>[ 319.218413] perf_pmu-2583 0..... 298349125us : __engine_unpark: 0000:00:02.0 rcs0: context:4 reset { seqno:117, *hwsp:117, ring:268 } <0>[ 319.218481] perf_pmu-2583 0..... 298349126us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:1b9 pin ring:{start:0086b000, head:0000, tail:0000} Oops#1 Part8 <0>[ 319.218549] perf_pmu-2583 0..... 298349139us : __intel_context_do_unpin.part.0: 0000:00:02.0 rcs0: context:1b9 unpin <0>[ 319.218614] perf_pmu-2583 0..... 298349141us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:1b9 active <0>[ 319.218680] perf_pmu-2583 0..... 298349143us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:1b9 pin ring:{start:0086b000, head:0000, tail:0000} <0>[ 319.218748] perf_pmu-2583 0..... 298349164us : __intel_context_do_unpin.part.0: 0000:00:02.0 rcs0: context:1b9 unpin <0>[ 319.218813] perf_pmu-2583 0..... 298349165us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:1b9 active <0>[ 319.218878] perf_pmu-2583 0..... 298349166us : __intel_context_do_pin_ww: 0000:00:02.0 rcs0: context:1b9 pin ring:{start:0086b000, head:0000, tail:0000} <0>[ 319.218962] perf_pmu-2583 0..... 298349392us : __i915_request_commit: 0000:00:02.0 rcs0: fence 1b9:2, current 0 <0>[ 319.219043] perf_pmu-2583 0d.s2. 298349400us : __i915_request_submit: 0000:00:02.0 rcs0: fence 1b9:2, current 0 <0>[ 319.219111] perf_pmu-2583 0d.s1. 298349402us : execlists_submission_tasklet: 0000:00:02.0 rcs0: context:1b9 schedule-in, ccid:20 <0>[ 319.219179] perf_pmu-2583 0d.s1. 298349403us : trace_ports: 0000:00:02.0 rcs0: submit { ccid:20 1b9:2 prio 0 } <0>[ 319.219260] perf_pmu-2583 0..... 298349448us : __i915_request_commit: 0000:00:02.0 rcs0: fence 4:280, current 279 <0>[ 319.219328] perf_pmu-2583 0b.... 298349659us : process_csb: 0000:00:02.0 rcs0: cs-irq head=2, tail=3 Oops#1 Part7 <0>[ 319.219396] perf_pmu-2583 0b.... 298349660us : process_csb: 0000:00:02.0 rcs0: csb[3]: status=0x03ff8000:0x00008001 <0>[ 319.219463] perf_pmu-2583 0b.... 298349661us : trace_ports: 0000:00:02.0 rcs0: promote { ccid:20 1b9:2 prio 0 } <0>[ 319.219530] perf_pmu-2583 0D..1. 298349663us : execlists_submission_tasklet: 0000:00:02.0 rcs0: preempting last=1b9:2, prio=0, hint=2147483646 <0>[ 319.219612] perf_pmu-2583 0D..1. 298349663us : __i915_request_unsubmit: 0000:00:02.0 rcs0: fence 1b9:2, current 0 <0>[ 319.219692] perf_pmu-2583 0D..1. 298349664us : __i915_request_submit: 0000:00:02.0 rcs0: fence 4:280, current 279 <0>[ 319.219760] dmesg-2109 10dlh1. 298349699us : execlists_irq_handler: 0000:00:02.0 rcs0: semaphore yield: 00000020 <0>[ 319.219840] perf_pmu-2583 0D..1. 298349932us : __i915_request_submit: 0000:00:02.0 rcs0: fence 1b9:2, current 2 <0>[ 319.219908] perf_pmu-2583 0D.... 298349933us : execlists_submission_tasklet: 0000:00:02.0 rcs0: context:4 schedule-in, ccid:40 <0>[ 319.219975] perf_pmu-2583 0D.... 298349940us : trace_ports: 0000:00:02.0 rcs0: submit { ccid:40 4:280* prio 2147483646 } <0>[ 319.220042] dmesg-2109 10..s.. 298349954us : process_csb: 0000:00:02.0 rcs0: cs-irq head=3, tail=4 <0>[ 319.220108] dmesg-2109 10..s.. 298349954us : process_csb: 0000:00:02.0 rcs0: csb[4]: status=0x00008004:0x00010005 <0>[ 319.220174] dmesg-2109 10..s.. 298349955us : trace_ports: 0000:00:02.0 rcs0: preempted { ccid:20 1b9:2! prio 0 } <0>[ 319.220240] dmesg-2109 10..s.. 298349956us : trace_ports: 0000:00:02.0 rcs0: promote { ccid:40 4:280* prio 2147483646 } Oops#1 Part6 <0>[ 319.220308] dmesg-2109 10..s.. 298349958us : __execlists_schedule_out: 0000:00:02.0 rcs0: context:1b9 schedule-out, ccid:20 <0>[ 319.220374] dmesg-2109 10..s.. 298349966us : process_csb: 0000:00:02.0 rcs0: cs-irq head=4, tail=5 <0>[ 319.220442] dmesg-2109 10..s.. 298349966us : process_csb: 0000:00:02.0 rcs0: csb[5]: status=0x00010000:0x03ff8000 <0>[ 319.220508] dmesg-2109 10..s.. 298349967us : trace_ports: 0000:00:02.0 rcs0: completed { ccid:40 4:280! prio 2147483646 } <0>[ 319.220575] dmesg-2109 10..s.. 298349967us : __execlists_schedule_out: 0000:00:02.0 rcs0: context:4 schedule-out, ccid:40 <0>[ 319.220656] perf_pmu-2583 0..... 298350308us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 1b9:2, current 2 <0>[ 319.220722] perf_pmu-2583 0..... 298350360us : __intel_context_do_unpin.part.0: 0000:00:02.0 rcs0: context:1b9 unpin <0>[ 319.220801] perf_pmu-2583 0..... 298350372us : i915_request_retire.part.0: 0000:00:02.0 rcs0: fence 4:280, current 280 <0>[ 319.220868] perf_pmu-2583 0..... 298350377us : __engine_park: 0000:00:02.0 rcs0: parked <0>[ 319.220931] perf_pmu-2583 0..... 298350429us : __intel_context_retire: 0000:00:02.0 rcs0: context:1b9 retire runtime: { total:277220ns, avg:277220ns } <0>[ 319.221008] kworker/-927 3d..2. 298350540us : intel_gt_invalidate_tlb_full.part.0: 0000:00:02.0 invalidated engines 00000001 <0>[ 319.221081] perf_pmu-2583 0..s1. 298350740us : rps_timer: 0000:00:02.0 busy:566225 [18%], max:[566225, 0, 0], interval:1 Oops#1 Part5 <0>[ 319.221150] kworker/-177 0..... 298350771us : __gt_park: 0000:00:02.0 <0>[ 319.221163] perf_pmu-2583 0...1. 298887718us : tracing_mark_write: perf_pmu: finished subtest rcs0, SUCCESS <0>[ 319.221231] perf_pmu-2583 0..... 298889514us : __gt_unpark: 0000:00:02.0 <0>[ 319.221300] perf_pmu-2583 0..... 298889543us : intel_rps_unpark: 0000:00:02.0 unpark:15 <0>[ 319.221366] perf_pmu-2583 0..... 298890188us : __gt_park: 0000:00:02.0 <0>[ 319.221379] perf_pmu-2583 0...1. 299405829us : tracing_mark_write: perf_pmu: finished subtest bcs0, SUCCESS <0>[ 319.221455] perf_pmu-2583 0..... 299407584us : __gt_unpark: 0000:00:02.0 <0>[ 319.221524] perf_pmu-2583 0..... 299407614us : intel_rps_unpark: 0000:00:02.0 unpark:15 <0>[ 319.221591] perf_pmu-2583 0..... 299408280us : __gt_park: 0000:00:02.0 <0>[ 319.221604] perf_pmu-2583 0...1. 299923569us : tracing_mark_write: perf_pmu: finished subtest vcs0, SUCCESS <0>[ 319.221671] perf_pmu-2583 0..... 299925292us : __gt_unpark: 0000:00:02.0 <0>[ 319.221739] perf_pmu-2583 0..... 299925321us : intel_rps_unpark: 0000:00:02.0 unpark:15 <0>[ 319.221805] perf_pmu-2583 0..... 299925987us : __gt_park: 0000:00:02.0 <0>[ 319.221818] perf_pmu-2583 0...1. 300440345us : tracing_mark_write: perf_pmu: finished subtest vecs0, SUCCESS <0>[ 319.221833] perf_pmu-2583 0...1. 300442187us : tracing_mark_write: perf_pmu: finished subtest idle-no-semaphores, SUCCESS <0>[ 319.221905] perf_pmu-2583 0..... 300445441us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: yes Oops#1 Part4 <0>[ 319.221976] kworker/-192 0..... 300453567us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: no <0>[ 319.222048] perf_pmu-2583 0..... 300455602us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: yes <0>[ 319.222119] kworker/-192 0..... 300470546us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: no <0>[ 319.222190] perf_pmu-2583 6..... 300471636us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: yes <0>[ 319.222262] kworker/-152 6..... 300488961us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: no <0>[ 319.222277] kms_flip-2584 8...1. 300583669us : tracing_mark_write: kms_flip: executing <0>[ 319.222291] kms_flip-2584 9...1. 300660177us : tracing_mark_write: kms_flip: starting subtest plain-flip-fb-recreate-interruptible <0>[ 319.222363] kms_flip-2584 9..... 300738649us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: yes <0>[ 319.222436] kms_flip-2584 9..... 301088702us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: yes <0>[ 319.222507] kworker/-155 8..... 301106326us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: no <0>[ 319.222578] kms_flip-2584 10..... 301106689us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: yes <0>[ 319.222649] kworker/-155 8..... 301125266us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: no <0>[ 319.222719] kms_flip-2584 10..... 301125561us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: yes <0>[ 319.222790] kworker/-155 8..... 301144212us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: no Oops#1 Part3 <0>[ 319.222861] kms_flip-2584 10..... 301144588us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: yes <0>[ 319.222933] kworker/-155 8..... 301163193us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: no <0>[ 319.223004] kms_flip-2584 10..... 301163488us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: yes <0>[ 319.223076] kworker/-155 8..... 301182170us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: no <0>[ 319.223147] kms_flip-2584 10..... 301182468us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: yes <0>[ 319.223219] kworker/-155 8..... 301201175us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: no <0>[ 319.223289] kms_flip-2584 9..... 301201533us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: yes <0>[ 319.223359] kworker/-155 8..... 301220146us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: no <0>[ 319.223434] kms_flip-2584 9..... 301221792us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: yes <0>[ 319.223505] kworker/-155 8..... 301239199us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: no <0>[ 319.223575] kms_flip-2584 9..... 301239514us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: yes <0>[ 319.223645] kworker/-192 0..... 301258183us : intel_rps_mark_interactive: 0000:00:02.0 mark interactive: no <0>[ 319.223708] kworker/-192 0..... 301258217us : intel_fb_unpin_vma: __i915_vma_unpin_fence:388 GEM_BUG_ON(atomic_read(&vma->fence->pin_count) <= 0) <0>[ 319.223724] --------------------------------- Oops#1 Part2 <4>[ 319.224850] ------------[ cut here ]------------ <2>[ 319.224853] kernel BUG at ./drivers/gpu/drm/i915/i915_vma.h:388! <4>[ 319.224868] Oops: invalid opcode: 0000 [#1] SMP NOPTI <4>[ 319.224873] CPU: 0 UID: 0 PID: 192 Comm: kworker/0:1H Tainted: G S U W L 7.0.0-CI_DRM_18353-g84de0c4efa97+ #1 PREEMPT(lazy) <4>[ 319.224880] Tainted: [S]=CPU_OUT_OF_SPEC, [U]=USER, [W]=WARN, [L]=SOFTLOCKUP <4>[ 319.224883] Hardware name: Intel Corporation Rocket Lake Client Platform/RocketLake S UDIMM 6L RVP, BIOS RKLSFWI1.R00.4261.A00.2306281302 06/28/2023 <4>[ 319.224889] Workqueue: i915_cleanup intel_atomic_cleanup_work [i915] <4>[ 319.225076] RIP: 0010:intel_fb_unpin_vma+0x1c1/0x1d0 [i915] <4>[ 319.225177] Code: 00 48 c7 c2 20 b6 1b a1 48 8d 3d 00 00 00 00 e8 45 e8 9a e0 bf 01 00 00 00 e8 cb 8e 9a e0 31 f6 bf 09 00 00 00 e8 5f 92 79 e0 <0f> 0b 66 66 2e 0f 1f 84 00 00 00 00 00 66 90 90 90 90 90 90 90 90 <4>[ 319.225184] RSP: 0018:ffffc90001fc3d60 EFLAGS: 00010246 <4>[ 319.225188] RAX: 0000000000000000 RBX: ffff8881437a7000 RCX: 0000000000000000 <4>[ 319.225192] RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000000000000 <4>[ 319.225195] RBP: ffffc90001fc3d60 R08: 0000000000000000 R09: 0000000000000000 <4>[ 319.225198] R10: 0000000000000000 R11: 0000000000000000 R12: ffff88810f442000 <4>[ 319.225201] R13: ffff88814980d000 R14: 0000000000000000 R15: ffff888109bfc4c0 <4>[ 319.225204] FS: 0000000000000000(0000) GS:ffff8884cb297000(0000) knlGS:0000000000000000 <4>[ 319.225208] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 <4>[ 319.225211] CR2: 00005e8b3b680988 CR3: 000000000344c003 CR4: 0000000000772ef0 <4>[ 319.225215] PKRU: 55555554 Oops#1 Part1 <4>[ 319.225217] Call Trace: <4>[ 319.225219] <4>[ 319.225222] intel_plane_unpin_fb+0x45/0xb0 [i915] <4>[ 319.225319] intel_cleanup_plane_fb+0x51/0x70 [i915] <4>[ 319.225479] drm_atomic_helper_cleanup_planes+0x4c/0x80 <4>[ 319.225485] intel_atomic_cleanup_work+0xe9/0x170 [i915] <4>[ 319.225649] process_one_work+0x239/0x760 <4>[ 319.225655] worker_thread+0x200/0x3f0 <4>[ 319.225658] ? __pfx_worker_thread+0x10/0x10 <4>[ 319.225661] kthread+0x10d/0x150 <4>[ 319.225665] ? __pfx_kthread+0x10/0x10 <4>[ 319.225669] ret_from_fork+0x3d4/0x480 <4>[ 319.225673] ? __pfx_kthread+0x10/0x10 <4>[ 319.225676] ret_from_fork_asm+0x1a/0x30 <4>[ 319.225682] <4>[ 319.225684] Modules linked in: vgem snd_hda_codec_intelhdmi snd_hda_codec_hdmi i915 prime_numbers drm_buddy ttm drm_display_helper cec rc_core i2c_algo_bit kunit intel_rapl_msr intel_rapl_common intel_uncore_frequency intel_uncore_frequency_common x86_pkg_temp_thermal intel_powerclamp cmdlinepart spi_nor coretemp mtd ppdev ee1004 mei_hdcp mei_pxp wmi_bmof kvm_intel kvm irqbypass ghash_clmulni_intel aesni_intel intel_cstate r8169 spi_intel_pci e1000e video spi_intel snd_hda_intel igc snd_intel_dspcfg binfmt_misc realtek snd_hda_codec idma64 snd_hda_core snd_hwdep intel_pmc_core snd_pcm pmt_telemetry pmt_discovery parport_pc snd_timer pmt_class nls_iso8859_1 parport wmi mei_me i2c_i801 intel_pmc_ssram_telemetry snd i2c_mux intel_hid acpi_tad pinctrl_tigerlake intel_vsec soundcore mei acpi_pad i2c_smbus sparse_keymap input_leds serio_raw dm_multipath msr nvme_fabrics fuse efi_pstore nfnetlink autofs4 <4>[ 319.225752] ---[ end trace 0000000000000000 ]---