[67.781923] Initializing watchdogs
[67.782011]   /dev/watchdog0
[67.813261] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:03:00.0: 8086:4905 Intel Dg1 (Gen12) DG1 [Iris Xe MAX Graphics]
[67.828535] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:03:00.0: card0
[67.833280] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[67.836408] [001/155] (960s left) kms_busy (extended-pageflip-hang-oldfb)
[69.386831] Starting subtest: extended-pageflip-hang-oldfb
[69.388676] Starting dynamic subtest: pipe-A
[69.643804] Dynamic subtest pipe-A: SUCCESS (0.254s)
[69.655193] Starting dynamic subtest: pipe-D
[69.840741] Dynamic subtest pipe-D: SUCCESS (0.193s)
[69.842295] Subtest extended-pageflip-hang-oldfb: SUCCESS (0.453s)
[70.082381] [002/155] (958s left) perf (non-zero-reason)
[70.214401] Starting subtest: non-zero-reason
[70.216116] Starting dynamic subtest: 0-rcs0
[82.025847] Dynamic subtest 0-rcs0: SUCCESS (11.779s)
[82.027844] Subtest non-zero-reason: SUCCESS (11.779s)
[82.083665] [003/155] (946s left) gem_tiling_max_stride
[82.207372] [004/155] (946s left) gem_exec_fence (invalid-timeline-fence-array)
[82.809650] Starting subtest: invalid-timeline-fence-array
[82.820212] Subtest invalid-timeline-fence-array: SUCCESS (0.014s)
[83.086052] [FACT gem_exec_fence (invalid-timeline-fence-array)] new: kernel.kmod_is_loaded.vgem: true
[83.089039] [005/155] (945s left) gem_mmap_wc (write)
[83.138373] Subtest write: SKIP (0.000s)
[83.214524] [006/155] (945s left) api_intel_bb (intel-bb-blit-none)
[83.263055] Starting subtest: intel-bb-blit-none
[83.950892] Subtest intel-bb-blit-none: SUCCESS (0.689s)
[84.144806] [007/155] (944s left) gem_exec_store (dword)
[84.223612] Starting subtest: dword
[84.224950] Starting dynamic subtest: rcs0
[84.282449] Dynamic subtest rcs0: SUCCESS (0.059s)
[84.292220] Starting dynamic subtest: bcs0
[84.322638] Dynamic subtest bcs0: SUCCESS (0.040s)
[84.334501] Starting dynamic subtest: vcs0
[84.364583] Dynamic subtest vcs0: SUCCESS (0.041s)
[84.381565] Starting dynamic subtest: vcs1
[84.409181] Dynamic subtest vcs1: SUCCESS (0.044s)
[84.438514] Starting dynamic subtest: vecs0
[84.457567] Dynamic subtest vecs0: SUCCESS (0.047s)
[84.459734] Subtest dword: SUCCESS (0.234s)
[84.760416] [008/155] (944s left) kms_draw_crc (draw-method-mmap-gtt)
[86.253338] Starting subtest: draw-method-mmap-gtt
[86.257200] Subtest draw-method-mmap-gtt: SKIP (0.000s)
[86.445950] [009/155] (942s left) kms_psr (fbc-psr-primary-page-flip)
[87.941306] Subtest fbc-psr-primary-page-flip: SKIP (0.000s)
[88.017345] [010/155] (941s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[89.511809] Subtest addfb25-x-tiled-mismatch-legacy: SKIP (0.000s)
[89.693978] [011/155] (939s left) dmabuf_sync_file (export-multiwait)
[89.760234] Starting subtest: export-multiwait
[89.761997] Subtest export-multiwait: SUCCESS (0.001s)
[89.867042] [012/155] (939s left) kms_vblank (ts-continuation-modeset-hang)
[91.354697] Starting subtest: ts-continuation-modeset-hang
[91.356324] Starting dynamic subtest: pipe-A-HDMI-A-4
[117.484540] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (26.119s)
[117.496805] Starting dynamic subtest: pipe-D-HDMI-A-4
[142.479870] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (24.986s)
[142.481616] Subtest ts-continuation-modeset-hang: SUCCESS (51.112s)
[142.680653] [013/155] (886s left) gem_exec_schedule (submit-late-slice)
[142.744909] Starting subtest: submit-late-slice
[142.746546] Starting dynamic subtest: rcs0
[142.805208] Dynamic subtest rcs0: SUCCESS (0.060s)
[142.815209] Starting dynamic subtest: bcs0
[142.848594] Dynamic subtest bcs0: SUCCESS (0.043s)
[142.858782] Starting dynamic subtest: vcs0
[142.891552] Dynamic subtest vcs0: SUCCESS (0.043s)
[142.901847] Starting dynamic subtest: vcs1
[142.933753] Dynamic subtest vcs1: SUCCESS (0.042s)
[142.944117] Starting dynamic subtest: vecs0
[142.979031] Dynamic subtest vecs0: SUCCESS (0.045s)
[142.980862] Subtest submit-late-slice: SUCCESS (0.234s)
[143.473078] [014/155] (885s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-cur-indfb-draw-pwrite)
[144.971569] Starting subtest: fbcpsrhdr-1p-primscrn-cur-indfb-draw-pwrite
[144.974612] Subtest fbcpsrhdr-1p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[145.161281] [015/155] (884s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-move)
[146.692825] Starting subtest: fbc-2p-scndscrn-spr-indfb-move
[146.696029] Subtest fbc-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[146.865828] [016/155] (882s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[148.367670] Starting subtest: psrhdr-1p-primscrn-pri-shrfb-draw-mmap-gtt
[148.370456] Subtest psrhdr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[148.544411] [017/155] (881s left) gem_workarounds (reset)
[148.623879] Starting subtest: reset
[148.779434] Subtest reset: SUCCESS (0.154s)
[148.965952] [018/155] (880s left) kms_frontbuffer_tracking (hdr-modesetfrombusy)
[150.465913] Starting subtest: hdr-modesetfrombusy
[150.469195] Subtest hdr-modesetfrombusy: SKIP (0.000s)
[150.635438] [019/155] (879s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-mmap-wc)
[152.135104] Starting subtest: psr-1p-offscreen-pri-indfb-draw-mmap-wc
[152.137895] Subtest psr-1p-offscreen-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[152.380136] [020/155] (877s left) gem_exec_balancer (parallel-keep-submit-fence)
[152.750763] Starting subtest: parallel-keep-submit-fence
[155.146524] Subtest parallel-keep-submit-fence: SUCCESS (2.396s)
[155.263635] [021/155] (874s left) gem_eio (in-flight-internal-10ms)
[155.463209] Starting subtest: in-flight-internal-10ms
[156.837265] Subtest in-flight-internal-10ms: SUCCESS (1.375s)
[158.450392] [022/155] (871s left) gem_render_copy (y-tiled-ccs-to-y-tiled)
[158.507252] Starting subtest: y-tiled-ccs-to-y-tiled
[158.508897] Starting dynamic subtest: smem
[158.600316] Dynamic subtest smem: SUCCESS (0.093s)
[158.611382] Starting dynamic subtest: lmem0
[159.540476] Dynamic subtest lmem0: SUCCESS (0.936s)
[159.542214] Subtest y-tiled-ccs-to-y-tiled: SUCCESS (1.030s)
[159.800937] [023/155] (870s left) syncobj_timeline (etime-single-wait-all-for-submit-submitted)
[159.851747] Starting subtest: etime-single-wait-all-for-submit-submitted
[159.962672] Subtest etime-single-wait-all-for-submit-submitted: SUCCESS (0.108s)
[160.128765] [024/155] (870s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-cur-indfb-draw-pwrite)
[161.627520] Starting subtest: fbcpsrhdr-2p-scndscrn-cur-indfb-draw-pwrite
[161.630710] Subtest fbcpsrhdr-2p-scndscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[161.829118] [025/155] (868s left) kms_frontbuffer_tracking (fbchdr-tiling-4)
[163.329665] Starting subtest: fbchdr-tiling-4
[163.333100] Subtest fbchdr-tiling-4: SKIP (0.000s)
[163.558618] [026/155] (867s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-indfb-draw-render)
[165.060238] Starting subtest: psrhdr-2p-scndscrn-pri-indfb-draw-render
[165.063675] Subtest psrhdr-2p-scndscrn-pri-indfb-draw-render: SKIP (0.000s)
[165.262399] [027/155] (865s left) gem_exec_parallel (contexts)
[165.326551] Starting subtest: contexts
[165.328174] Starting dynamic subtest: rcs0
[169.808140] Dynamic subtest rcs0: SUCCESS (4.478s)
[169.819208] Starting dynamic subtest: bcs0
[173.551797] Dynamic subtest bcs0: SUCCESS (3.743s)
[173.563254] Starting dynamic subtest: vcs0
[177.604615] Dynamic subtest vcs0: SUCCESS (4.052s)
[177.616006] Starting dynamic subtest: vcs1
[181.879748] Dynamic subtest vcs1: SUCCESS (4.275s)
[181.891113] Starting dynamic subtest: vecs0
[185.588758] Dynamic subtest vecs0: SUCCESS (3.709s)
[185.590605] Subtest contexts: SUCCESS (20.259s)
[185.855243] [028/155] (845s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-submitted)
[185.904973] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-submitted
[186.006482] Subtest etime-multi-wait-all-for-submit-unsubmitted-submitted: SUCCESS (0.102s)
[186.138394] [029/155] (844s left) kms_psr (psr-basic)
[187.629509] Subtest psr-basic: SKIP (0.000s)
[187.708426] [030/155] (843s left) syncobj_timeline (single-wait-signaled)
[187.756598] Starting subtest: single-wait-signaled
[187.757914] Subtest single-wait-signaled: SUCCESS (0.000s)
[187.878727] [031/155] (843s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[189.390123] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[189.980284] Subtest x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SUCCESS (0.591s)
[190.276882] [032/155] (841s left) prime_busy (hang)
[190.328073] Starting subtest: hang
[190.329593] Starting dynamic subtest: rcs0
[216.440162] Dynamic subtest rcs0: SUCCESS (26.109s)
[216.451599] Starting dynamic subtest: bcs0
[223.586124] Dynamic subtest bcs0: SUCCESS (7.147s)
[223.597676] Starting dynamic subtest: vcs0
[230.564107] Dynamic subtest vcs0: SUCCESS (6.977s)
[230.575481] Starting dynamic subtest: vcs1
[237.603457] Dynamic subtest vcs1: SUCCESS (7.039s)
[237.615205] Starting dynamic subtest: vecs0
[244.584341] Dynamic subtest vecs0: SUCCESS (6.980s)
[244.586619] Subtest hang: SUCCESS (54.254s)
[244.742370] [033/155] (786s left) kms_frontbuffer_tracking (fbcpsr-slowdraw)
[246.239955] Starting subtest: fbcpsr-slowdraw
[246.242903] Subtest fbcpsr-slowdraw: SKIP (0.000s)
[246.406806] [034/155] (785s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-mmap-wc)
[247.940165] Starting subtest: psr-1p-primscrn-pri-indfb-draw-mmap-wc
[247.943225] Subtest psr-1p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[248.113126] [035/155] (783s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-cur-indfb-draw-pwrite)
[249.611569] Starting subtest: fbcpsrhdr-2p-primscrn-cur-indfb-draw-pwrite
[249.614363] Subtest fbcpsrhdr-2p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[249.758391] [036/155] (781s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-spr-indfb-draw-mmap-cpu)
[251.256984] Starting subtest: fbchdr-2p-primscrn-spr-indfb-draw-mmap-cpu
[251.259952] Subtest fbchdr-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[251.425708] [037/155] (780s left) kms_plane (pixel-format-4-tiled-mtl-rc-ccs-cc-modifier-source-clamping)
[252.918039] Starting subtest: pixel-format-4-tiled-mtl-rc-ccs-cc-modifier-source-clamping
[252.920849] Subtest pixel-format-4-tiled-mtl-rc-ccs-cc-modifier-source-clamping: SKIP (0.000s)
[253.058301] [038/155] (778s left) gem_ctx_persistence (legacy-engines-persistence)
[253.122814] Starting subtest: legacy-engines-persistence
[253.124262] Starting dynamic subtest: render
[253.932829] Dynamic subtest render: SUCCESS (0.809s)
[253.946626] Starting dynamic subtest: bsd1
[254.694742] Dynamic subtest bsd1: SUCCESS (0.761s)
[254.707774] Starting dynamic subtest: bsd2
[255.462747] Dynamic subtest bsd2: SUCCESS (0.767s)
[255.475401] Starting dynamic subtest: blt
[256.238863] Dynamic subtest blt: SUCCESS (0.775s)
[256.252211] Starting dynamic subtest: vebox
[257.002847] Dynamic subtest vebox: SUCCESS (0.763s)
[257.004561] Subtest legacy-engines-persistence: SUCCESS (3.880s)
[257.177326] [039/155] (774s left) gem_basic (create-fd-close)
[257.234595] Starting subtest: create-fd-close
[257.237878] Subtest create-fd-close: SUCCESS (0.000s)
[257.399776] [040/155] (774s left) gem_pxp (regular-baseline-src-copy-readible)
[259.969632] Starting subtest: regular-baseline-src-copy-readible
[259.973192] Subtest regular-baseline-src-copy-readible: SKIP (0.000s)
[260.097597] [041/155] (772s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-indfb-msflip-blt)
[261.595785] Starting subtest: fbchdr-2p-primscrn-indfb-msflip-blt
[261.599045] Subtest fbchdr-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[261.797395] [042/155] (770s left) gem_exec_schedule (implicit-write-read)
[261.859651] Starting subtest: implicit-write-read
[261.861231] Starting dynamic subtest: rcs0
[262.055897] Dynamic subtest rcs0: SUCCESS (0.195s)
[262.067881] Starting dynamic subtest: bcs0
[262.185410] Dynamic subtest bcs0: SUCCESS (0.132s)
[262.196362] Starting dynamic subtest: vcs0
[262.319384] Dynamic subtest vcs0: SUCCESS (0.134s)
[262.330776] Starting dynamic subtest: vcs1
[262.467729] Dynamic subtest vcs1: SUCCESS (0.148s)
[262.478999] Starting dynamic subtest: vecs0
[262.612947] Dynamic subtest vecs0: SUCCESS (0.145s)
[262.614659] Subtest implicit-write-read: SUCCESS (0.755s)
[263.035851] [043/155] (769s left) gem_busy (parallel)
[263.230184] Starting subtest: parallel
[263.231895] Starting dynamic subtest: rcs0
[263.345344] Dynamic subtest rcs0: SUCCESS (0.115s)
[263.359581] Starting dynamic subtest: bcs0
[263.401366] Dynamic subtest bcs0: SUCCESS (0.055s)
[263.428300] Starting dynamic subtest: vcs0
[263.472695] Dynamic subtest vcs0: SUCCESS (0.071s)
[263.500879] Starting dynamic subtest: vcs1
[263.542370] Dynamic subtest vcs1: SUCCESS (0.069s)
[263.563609] Starting dynamic subtest: vecs0
[263.607340] Dynamic subtest vecs0: SUCCESS (0.064s)
[263.609283] Subtest parallel: SUCCESS (0.377s)
[263.942225] [044/155] (768s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-mmap-wc)
[265.442503] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-mmap-wc
[265.446116] Subtest fbc-1p-offscreen-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[265.608442] [045/155] (766s left) gem_set_tiling_vs_blt (untiled-to-tiled)
[265.667953] Subtest untiled-to-tiled: SKIP (0.000s)
[265.744342] [046/155] (766s left) gem_partial_pwrite_pread (writes-after-reads-uncached)
[265.795096] Subtest writes-after-reads-uncached: SKIP (0.000s)
[265.898360] [047/155] (766s left) syncobj_timeline (multi-wait-all-for-submit-signaled)
[265.945409] Starting subtest: multi-wait-all-for-submit-signaled
[265.946855] Subtest multi-wait-all-for-submit-signaled: SUCCESS (0.001s)
[266.053391] [048/155] (766s left) kms_psr (pr-cursor-render)
[267.543405] Subtest pr-cursor-render: SKIP (0.000s)
[267.615193] [049/155] (765s left) kms_plane_cursor (primary)
[269.104964] Starting subtest: primary
[269.108401] Starting dynamic subtest: pipe-A-HDMI-A-4-size-64
[272.336200] Dynamic subtest pipe-A-HDMI-A-4-size-64: SUCCESS (3.229s)
[272.395161] Starting dynamic subtest: pipe-A-HDMI-A-4-size-128
[275.569652] Dynamic subtest pipe-A-HDMI-A-4-size-128: SUCCESS (3.184s)
[275.629111] Starting dynamic subtest: pipe-A-HDMI-A-4-size-256
[278.785957] Dynamic subtest pipe-A-HDMI-A-4-size-256: SUCCESS (3.167s)
[278.872943] Starting dynamic subtest: pipe-B-HDMI-A-4-size-64
[282.071463] Dynamic subtest pipe-B-HDMI-A-4-size-64: SUCCESS (3.210s)
[282.113527] Starting dynamic subtest: pipe-B-HDMI-A-4-size-128
[285.221086] Dynamic subtest pipe-B-HDMI-A-4-size-128: SUCCESS (3.117s)
[285.262804] Starting dynamic subtest: pipe-B-HDMI-A-4-size-256
[288.370752] Dynamic subtest pipe-B-HDMI-A-4-size-256: SUCCESS (3.117s)
[288.440757] Starting dynamic subtest: pipe-C-HDMI-A-4-size-64
[291.686969] Dynamic subtest pipe-C-HDMI-A-4-size-64: SUCCESS (3.257s)
[291.729144] Starting dynamic subtest: pipe-C-HDMI-A-4-size-128
[294.836994] Dynamic subtest pipe-C-HDMI-A-4-size-128: SUCCESS (3.117s)
[294.895859] Starting dynamic subtest: pipe-C-HDMI-A-4-size-256
[298.069763] Dynamic subtest pipe-C-HDMI-A-4-size-256: SUCCESS (3.199s)
[298.139517] Starting dynamic subtest: pipe-D-HDMI-A-4-size-64
[301.299580] Dynamic subtest pipe-D-HDMI-A-4-size-64: SUCCESS (3.171s)
[301.341720] Starting dynamic subtest: pipe-D-HDMI-A-4-size-128
[304.549573] Dynamic subtest pipe-D-HDMI-A-4-size-128: SUCCESS (3.217s)
[304.591519] Starting dynamic subtest: pipe-D-HDMI-A-4-size-256
[307.799396] Dynamic subtest pipe-D-HDMI-A-4-size-256: SUCCESS (3.217s)
[307.872558] Subtest primary: SUCCESS (38.769s)
[308.078507] [050/155] (724s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[309.580959] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-wc
[309.585144] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[309.755683] [051/155] (723s left) gem_render_copy (y-tiled-mc-ccs-to-vebox-yf-tiled)
[309.809215] Starting subtest: y-tiled-mc-ccs-to-vebox-yf-tiled
[309.810718] Starting dynamic subtest: smem
[309.901505] Dynamic subtest smem: SUCCESS (0.092s)
[309.913049] Starting dynamic subtest: lmem0
[310.838994] Dynamic subtest lmem0: SUCCESS (0.934s)
[310.840649] Subtest y-tiled-mc-ccs-to-vebox-yf-tiled: SUCCESS (1.027s)
[311.104366] [052/155] (721s left) kms_ccs (bad-pixel-format-4-tiled-dg2-rc-ccs)
[312.592983] Starting subtest: bad-pixel-format-4-tiled-dg2-rc-ccs
[312.594755] Starting dynamic subtest: pipe-A-HDMI-A-4
[312.611962] Dynamic subtest pipe-A-HDMI-A-4: SKIP (0.000s)
[312.623838] Starting dynamic subtest: pipe-B-HDMI-A-4
[312.649684] Dynamic subtest pipe-B-HDMI-A-4: SKIP (0.000s)
[312.660918] Starting dynamic subtest: pipe-C-HDMI-A-4
[312.675111] Dynamic subtest pipe-C-HDMI-A-4: SKIP (0.000s)
[312.686195] Starting dynamic subtest: pipe-D-HDMI-A-4
[312.701114] Dynamic subtest pipe-D-HDMI-A-4: SKIP (0.000s)
[312.703978] Subtest bad-pixel-format-4-tiled-dg2-rc-ccs: SKIP (0.006s)
[312.866929] [053/155] (720s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling)
[314.357488] Starting subtest: flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling
[314.360405] Subtest flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling: SKIP (0.000s)
[314.468056] [054/155] (718s left) kms_frontbuffer_tracking (fbc-rgb565-draw-blt)
[315.973940] Starting subtest: fbc-rgb565-draw-blt
[318.693533] Subtest fbc-rgb565-draw-blt: SUCCESS (2.723s)
[318.905498] [055/155] (714s left) sw_sync (sync_multi_producer_single_consumer)
[318.954767] Starting subtest: sync_multi_producer_single_consumer
[320.033238] Subtest sync_multi_producer_single_consumer: SUCCESS (1.075s)
[320.127126] [056/155] (713s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-shrfb-pgflip-blt)
[321.625272] Starting subtest: fbcpsr-2p-scndscrn-shrfb-pgflip-blt
[321.629240] Subtest fbcpsr-2p-scndscrn-shrfb-pgflip-blt: SKIP (0.000s)
[321.826178] [057/155] (711s left) kms_ccs (crc-primary-suspend-4-tiled-lnl-ccs)
[323.320682] Starting subtest: crc-primary-suspend-4-tiled-lnl-ccs
[323.323905] Subtest crc-primary-suspend-4-tiled-lnl-ccs: SKIP (0.000s)
[323.533973] [058/155] (710s left) gem_ctx_shared (exec-single-timeline)
[323.592769] Starting subtest: exec-single-timeline
[323.594208] Starting dynamic subtest: rcs0
[324.677953] Dynamic subtest rcs0: SUCCESS (1.084s)
[324.689752] Starting dynamic subtest: bcs0
[325.705500] Dynamic subtest bcs0: SUCCESS (1.024s)
[325.717437] Starting dynamic subtest: vcs0
[326.729683] Dynamic subtest vcs0: SUCCESS (1.021s)
[326.742046] Starting dynamic subtest: vcs1
[327.753614] Dynamic subtest vcs1: SUCCESS (1.021s)
[327.765432] Starting dynamic subtest: vecs0
[328.777579] Dynamic subtest vecs0: SUCCESS (1.021s)
[328.779212] Subtest exec-single-timeline: SUCCESS (5.183s)
[329.042443] [059/155] (704s left) gem_eio (suspend)
[329.203458] Starting subtest: suspend
[342.104541] Subtest suspend: SUCCESS (12.901s)
[348.680618] [060/155] (690s left) kms_cursor_legacy (torture-move)
[350.173765] Starting subtest: torture-move
[350.175288] Starting dynamic subtest: pipe-A
[355.780780] Dynamic subtest pipe-A: SUCCESS (5.603s)
[355.794569] Starting dynamic subtest: pipe-B
[361.414154] Dynamic subtest pipe-B: SUCCESS (5.633s)
[361.435385] Starting dynamic subtest: pipe-C
[367.009590] Dynamic subtest pipe-C: SUCCESS (5.594s)
[367.022576] Starting dynamic subtest: pipe-D
[372.568663] Dynamic subtest pipe-D: SUCCESS (5.559s)
[372.589657] Starting dynamic subtest: all-pipes
[378.046220] Dynamic subtest all-pipes: SUCCESS (5.477s)
[378.047885] Subtest torture-move: SUCCESS (27.870s)
[378.236925] [061/155] (660s left) gem_mmap_gtt (basic-write)
[378.294176] Subtest basic-write: SKIP (0.000s)
[378.381117] [062/155] (660s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-spr-indfb-onoff)
[379.878117] Starting subtest: psrhdr-2p-scndscrn-spr-indfb-onoff
[379.880746] Subtest psrhdr-2p-scndscrn-spr-indfb-onoff: SKIP (0.000s)
[380.081381] [063/155] (658s left) kms_cursor_crc (cursor-sliding-256x256)
[381.812790] Starting subtest: cursor-sliding-256x256
[381.819296] Starting dynamic subtest: pipe-A-HDMI-A-4
[386.187485] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (4.367s)
[386.221531] Starting dynamic subtest: pipe-D-HDMI-A-4
[390.544237] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (4.334s)
[390.545822] Subtest cursor-sliding-256x256: SUCCESS (8.730s)
[390.929847] [064/155] (648s left) kms_pm_rpm (modeset-lpsp)
[395.048657] Starting subtest: modeset-lpsp
[396.059193] Subtest modeset-lpsp: SKIP (1.009s)
[397.124805] [065/155] (642s left) kms_lease (lease-again)
[398.622508] Starting subtest: lease-again
[398.623946] Starting dynamic subtest: pipe-A-HDMI-A-4
[398.625464] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (0.003s)
[398.640351] Starting dynamic subtest: pipe-B-HDMI-A-4
[398.641816] Dynamic subtest pipe-B-HDMI-A-4: SUCCESS (0.002s)
[398.656941] Starting dynamic subtest: pipe-C-HDMI-A-4
[398.658436] Dynamic subtest pipe-C-HDMI-A-4: SUCCESS (0.002s)
[398.671040] Starting dynamic subtest: pipe-D-HDMI-A-4
[398.672519] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (0.002s)
[398.673973] Subtest lease-again: SUCCESS (0.018s)
[398.782046] [066/155] (640s left) kms_getfb (getfb-repeated-different-handles)
[400.273476] Starting subtest: getfb-repeated-different-handles
[400.275137] Subtest getfb-repeated-different-handles: SUCCESS (0.000s)
[400.499720] [067/155] (638s left) gem_mmap_wc (fault-concurrent)
[400.551258] Subtest fault-concurrent: SKIP (0.000s)
[400.632275] [068/155] (638s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-16bpp-4tile-upscaling)
[402.131926] Starting subtest: flip-64bpp-4tile-to-16bpp-4tile-upscaling
[402.135361] Subtest flip-64bpp-4tile-to-16bpp-4tile-upscaling: SKIP (0.000s)
[402.297192] [069/155] (637s left) gen7_exec_parse (basic-rejected)
[402.355320] Subtest basic-rejected: SKIP (0.000s)
[402.430451] [070/155] (637s left) perf_pmu (invalid-open)
[402.480419] Starting subtest: invalid-open
[402.481791] Subtest invalid-open: SUCCESS (0.000s)
[402.620376] [071/155] (637s left) kms_panel_fitting (legacy)
[404.107105] Starting subtest: legacy
[404.110429] Subtest legacy: SKIP (0.000s)
[404.246164] [072/155] (635s left) gem_userptr_blits (create-destroy-unsync)
[404.325796] Subtest create-destroy-unsync: SKIP (0.000s)
[404.504543] [073/155] (635s left) kms_rotation_crc (primary-4-tiled-reflect-x-180)
[405.992235] Starting subtest: primary-4-tiled-reflect-x-180
[406.085065] Subtest primary-4-tiled-reflect-x-180: SKIP (0.092s)
[406.305270] [074/155] (633s left) kms_psr (fbc-psr2-sprite-render)
[407.795574] Subtest fbc-psr2-sprite-render: SKIP (0.000s)
[407.881303] [075/155] (632s left) kms_cursor_crc (cursor-sliding-32x10)
[409.480784] Starting subtest: cursor-sliding-32x10
[409.531825] Subtest cursor-sliding-32x10: SKIP (0.047s)
[409.909668] [076/155] (630s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0-hflip)
[411.420810] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0-hflip
[411.423890] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0-hflip: SKIP (0.000s)
[411.597882] [077/155] (628s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[413.095831] Starting subtest: fbchdr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[413.098708] Subtest fbchdr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[413.252847] [078/155] (627s left) kms_frontbuffer_tracking (hdr-2p-primscrn-indfb-plflip-blt)
[414.756195] Starting subtest: hdr-2p-primscrn-indfb-plflip-blt
[414.759097] Subtest hdr-2p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[414.962774] [079/155] (625s left) kms_addfb_basic (small-bo)
[416.455429] Starting subtest: small-bo
[416.457228] Subtest small-bo: SUCCESS (0.000s)
[416.631741] [080/155] (624s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-pri-indfb-draw-mmap-gtt)
[418.132215] Starting subtest: fbcpsrhdr-2p-primscrn-pri-indfb-draw-mmap-gtt
[418.135336] Subtest fbcpsrhdr-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[418.279581] [081/155] (622s left) kms_flip (bo-too-big-interruptible)
[419.784449] Starting subtest: bo-too-big-interruptible
[419.792708] Starting dynamic subtest: A-HDMI-A4
[420.012747] Dynamic subtest A-HDMI-A4: SUCCESS (0.220s)
[420.031258] Starting dynamic subtest: B-HDMI-A4
[420.174698] Dynamic subtest B-HDMI-A4: SUCCESS (0.161s)
[420.187705] Starting dynamic subtest: C-HDMI-A4
[420.320711] Dynamic subtest C-HDMI-A4: SUCCESS (0.145s)
[420.333812] Starting dynamic subtest: D-HDMI-A4
[420.470416] Dynamic subtest D-HDMI-A4: SUCCESS (0.148s)
[420.472632] Subtest bo-too-big-interruptible: SUCCESS (0.688s)
[420.752334] [082/155] (620s left) kms_big_fb (x-tiled-32bpp-rotate-90)
[422.262743] Starting subtest: x-tiled-32bpp-rotate-90
[422.363430] Subtest x-tiled-32bpp-rotate-90: SKIP (0.098s)
[422.513966] [083/155] (618s left) kms_frontbuffer_tracking (fbc-indfb-scaledprimary)
[424.015147] Starting subtest: fbc-indfb-scaledprimary
[425.410864] Subtest fbc-indfb-scaledprimary: SUCCESS (1.401s)
[425.601845] [084/155] (615s left) gem_exec_balancer (invalid-bonds)
[425.661623] Starting subtest: invalid-bonds
[425.664854] Subtest invalid-bonds: SKIP (0.000s)
[426.143598] [085/155] (614s left) kms_big_fb (y-tiled-64bpp-rotate-270)
[427.652389] Starting subtest: y-tiled-64bpp-rotate-270
[427.718114] Subtest y-tiled-64bpp-rotate-270: SKIP (0.063s)
[427.914951] [086/155] (613s left) gem_exec_flush (basic-uc-pro-default)
[427.969705] Subtest basic-uc-pro-default: SKIP (0.000s)
[428.077479] [087/155] (613s left) gem_exec_reloc (basic-wc-read-active)
[428.132710] Subtest basic-wc-read-active: SKIP (0.000s)
[428.224489] [088/155] (613s left) kms_frontbuffer_tracking (hdr-2p-primscrn-indfb-msflip-blt)
[429.723716] Starting subtest: hdr-2p-primscrn-indfb-msflip-blt
[429.729164] Subtest hdr-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[429.915189] [089/155] (611s left) gem_spin_batch (resubmit-all)
[429.974256] Starting subtest: resubmit-all
[429.975956] Starting dynamic subtest: rcs0
[429.998542] Dynamic subtest rcs0: SUCCESS (0.024s)
[430.012223] Starting dynamic subtest: bcs0
[430.014547] Dynamic subtest bcs0: SUCCESS (0.002s)
[430.027715] Starting dynamic subtest: vcs0
[430.029564] Dynamic subtest vcs0: SUCCESS (0.002s)
[430.042192] Starting dynamic subtest: vcs1
[430.043957] Dynamic subtest vcs1: SUCCESS (0.002s)
[430.056679] Starting dynamic subtest: vecs0
[430.058771] Dynamic subtest vecs0: SUCCESS (0.002s)
[430.060655] Subtest resubmit-all: SUCCESS (0.037s)
[430.375235] [090/155] (611s left) gem_madvise (dontneed-after-mmap)
[430.420597] Starting subtest: dontneed-after-mmap
[430.427627] Subtest dontneed-after-mmap: SUCCESS (0.004s)
[430.564540] [091/155] (611s left) kms_frontbuffer_tracking (fbchdr-1p-pri-indfb-multidraw)
[432.062338] Starting subtest: fbchdr-1p-pri-indfb-multidraw
[432.065743] Subtest fbchdr-1p-pri-indfb-multidraw: SKIP (0.000s)
[432.243513] [092/155] (609s left) kms_plane_scaling (plane-upscale-factor-0-25-with-modifiers)
[433.752547] Starting subtest: plane-upscale-factor-0-25-with-modifiers
[433.754232] Starting dynamic subtest: pipe-A
[434.492977] Dynamic subtest pipe-A: SUCCESS (0.742s)
[434.510805] Starting dynamic subtest: pipe-B
[435.266062] Dynamic subtest pipe-B: SUCCESS (0.771s)
[435.281172] Starting dynamic subtest: pipe-C
[436.039686] Dynamic subtest pipe-C: SUCCESS (0.772s)
[436.054702] Starting dynamic subtest: pipe-D
[436.813375] Dynamic subtest pipe-D: SUCCESS (0.772s)
[436.815169] Subtest plane-upscale-factor-0-25-with-modifiers: SUCCESS (3.064s)
[437.061594] [093/155] (604s left) gem_softpin (evict-snoop)
[437.121454] Starting subtest: evict-snoop
[437.125281] Subtest evict-snoop: SKIP (0.000s)
[437.275831] [094/155] (604s left) gem_create (create-massive)
[437.323729] Starting subtest: create-massive
[437.324991] Subtest create-massive: SUCCESS (0.000s)
[437.568832] [095/155] (604s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-move)
[439.065027] Starting subtest: psr-2p-primscrn-spr-indfb-move
[439.068306] Subtest psr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[439.225266] [096/155] (602s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-pri-indfb-draw-render)
[440.732575] Starting subtest: fbchdr-1p-primscrn-pri-indfb-draw-render
[440.735809] Subtest fbchdr-1p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[440.887856] [097/155] (601s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-pwrite)
[442.390692] Starting subtest: psr-1p-primscrn-spr-indfb-draw-pwrite
[442.393782] Subtest psr-1p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[442.566326] [098/155] (599s left) kms_frontbuffer_tracking (fbcpsrhdr-slowdraw)
[444.067105] Starting subtest: fbcpsrhdr-slowdraw
[444.070039] Subtest fbcpsrhdr-slowdraw: SKIP (0.000s)
[444.220889] [099/155] (598s left) gem_ctx_persistence (engines-cleanup)
[444.280704] Starting subtest: engines-cleanup
[444.282255] Starting dynamic subtest: rcs0
[444.337759] Dynamic subtest rcs0: SUCCESS (0.058s)
[444.351866] Starting dynamic subtest: bcs0
[444.355880] Dynamic subtest bcs0: SUCCESS (0.015s)
[444.369851] Starting dynamic subtest: vcs0
[444.415995] Dynamic subtest vcs0: SUCCESS (0.061s)
[444.443856] Starting dynamic subtest: vcs1
[444.463058] Dynamic subtest vcs1: SUCCESS (0.045s)
[444.490800] Starting dynamic subtest: vecs0
[444.499959] Dynamic subtest vecs0: SUCCESS (0.035s)
[444.501742] Subtest engines-cleanup: SUCCESS (0.222s)
[444.731672] [100/155] (597s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-pri-shrfb-draw-render)
[446.233910] Starting subtest: hdr-2p-scndscrn-pri-shrfb-draw-render
[446.237192] Subtest hdr-2p-scndscrn-pri-shrfb-draw-render: SKIP (0.000s)
[446.389708] [101/155] (596s left) kms_atomic_transition (modeset-transition-nonblocking)
[447.885176] Starting subtest: modeset-transition-nonblocking
[447.886735] Starting dynamic subtest: 1x-outputs
[450.041551] Dynamic subtest 1x-outputs: SUCCESS (2.153s)
[450.043250] Subtest modeset-transition-nonblocking: SUCCESS (2.157s)
[450.251680] [102/155] (592s left) kms_color_pipeline (plane-lut1d-pre-ctm3x4)
[451.741601] Starting subtest: plane-lut1d-pre-ctm3x4
[451.935317] Starting dynamic subtest: pipe-A-plane-0
[452.214957] Dynamic subtest pipe-A-plane-0: SUCCESS (0.280s)
[452.237592] Starting dynamic subtest: pipe-A-plane-1
[452.399780] Dynamic subtest pipe-A-plane-1: SUCCESS (0.181s)
[452.442649] Starting dynamic subtest: pipe-A-plane-2
[452.600493] Dynamic subtest pipe-A-plane-2: SUCCESS (0.192s)
[452.904343] Starting dynamic subtest: pipe-B-plane-0
[453.062662] Dynamic subtest pipe-B-plane-0: SUCCESS (0.168s)
[453.099609] Starting dynamic subtest: pipe-B-plane-1
[453.262154] Dynamic subtest pipe-B-plane-1: SUCCESS (0.192s)
[453.275405] Starting dynamic subtest: pipe-B-plane-2
[453.425967] Dynamic subtest pipe-B-plane-2: SUCCESS (0.161s)
[453.758707] Starting dynamic subtest: pipe-C-plane-0
[453.940714] Dynamic subtest pipe-C-plane-0: SUCCESS (0.214s)
[453.964395] Starting dynamic subtest: pipe-C-plane-1
[454.140867] Dynamic subtest pipe-C-plane-1: SUCCESS (0.193s)
[454.176427] Starting dynamic subtest: pipe-C-plane-2
[454.356768] Dynamic subtest pipe-C-plane-2: SUCCESS (0.210s)
[454.679689] Starting dynamic subtest: pipe-D-plane-0
[454.848091] Dynamic subtest pipe-D-plane-0: SUCCESS (0.197s)
[454.865788] Starting dynamic subtest: pipe-D-plane-1
[455.031648] Dynamic subtest pipe-D-plane-1: SUCCESS (0.178s)
[455.070297] Starting dynamic subtest: pipe-D-plane-2
[455.246051] Dynamic subtest pipe-D-plane-2: SUCCESS (0.209s)
[455.269235] Subtest plane-lut1d-pre-ctm3x4: SUCCESS (3.529s)
[455.465732] [103/155] (587s left) kms_frontbuffer_tracking (fbcpsr-2p-rte)
[456.965964] Starting subtest: fbcpsr-2p-rte
[456.969477] Subtest fbcpsr-2p-rte: SKIP (0.000s)
[457.140754] [104/155] (585s left) kms_frontbuffer_tracking (psrhdr-rgb565-draw-pwrite)
[458.643959] Starting subtest: psrhdr-rgb565-draw-pwrite
[458.647201] Subtest psrhdr-rgb565-draw-pwrite: SKIP (0.000s)
[458.834917] [105/155] (584s left) kms_plane_multiple (2x-tiling-x)
[460.327792] Starting subtest: 2x-tiling-x
[460.330997] Subtest 2x-tiling-x: SKIP (0.000s)
[460.467131] [106/155] (582s left) i915_drm_fdinfo (busy-idle-check-all)
[460.528196] Starting subtest: busy-idle-check-all
[460.529952] Starting dynamic subtest: rcs0
[460.546850] Dynamic subtest rcs0: SKIP (0.016s)
[460.560175] Starting dynamic subtest: bcs0
[460.564067] Dynamic subtest bcs0: SKIP (0.001s)
[460.577213] Starting dynamic subtest: vcs0
[460.581220] Dynamic subtest vcs0: SKIP (0.000s)
[460.595804] Starting dynamic subtest: vcs1
[460.599539] Dynamic subtest vcs1: SKIP (0.000s)
[460.612325] Starting dynamic subtest: vecs0
[460.616188] Dynamic subtest vecs0: SKIP (0.000s)
[460.620178] Subtest busy-idle-check-all: SKIP (0.025s)
[460.687199] [107/155] (582s left) sw_sync (sync_random_merge)
[460.734422] Starting subtest: sync_random_merge
[460.829325] Subtest sync_random_merge: SUCCESS (0.095s)
[460.926723] [108/155] (582s left) kms_sequence (get-forked-busy)
[462.447857] Starting subtest: get-forked-busy
[462.449424] Starting dynamic subtest: pipe-A-HDMI-A-4
[465.121963] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (2.669s)
[465.153515] Starting dynamic subtest: pipe-B-HDMI-A-4
[467.654445] Dynamic subtest pipe-B-HDMI-A-4: SUCCESS (2.523s)
[467.682937] Starting dynamic subtest: pipe-C-HDMI-A-4
[470.203200] Dynamic subtest pipe-C-HDMI-A-4: SUCCESS (2.544s)
[470.221558] Starting dynamic subtest: pipe-D-HDMI-A-4
[472.731143] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (2.524s)
[472.733289] Subtest get-forked-busy: SUCCESS (10.284s)
[472.939579] [109/155] (570s left) gem_exec_params (readonly)
[472.994119] Starting subtest: readonly
[472.997878] Subtest readonly: SUCCESS (0.002s)
[473.173340] [110/155] (570s left) gem_softpin (evict-active-interruptible)
[473.229854] Starting subtest: evict-active-interruptible
[473.241848] Subtest evict-active-interruptible: SUCCESS (0.012s)
[473.392515] [111/155] (570s left) kms_plane_scaling (plane-scaler-unity-scaling-with-modifiers)
[474.883707] Starting subtest: plane-scaler-unity-scaling-with-modifiers
[474.885296] Starting dynamic subtest: pipe-A
[475.666658] Dynamic subtest pipe-A: SUCCESS (0.785s)
[475.683615] Starting dynamic subtest: pipe-B
[476.440715] Dynamic subtest pipe-B: SUCCESS (0.772s)
[476.462951] Starting dynamic subtest: pipe-C
[477.230812] Dynamic subtest pipe-C: SUCCESS (0.788s)
[477.247233] Starting dynamic subtest: pipe-D
[478.003876] Dynamic subtest pipe-D: SUCCESS (0.771s)
[478.007688] Subtest plane-scaler-unity-scaling-with-modifiers: SUCCESS (3.123s)
[478.235409] [112/155] (565s left) syncobj_wait (invalid-multi-wait-unsubmitted-submitted)
[478.289998] Starting subtest: invalid-multi-wait-unsubmitted-submitted
[478.291933] Subtest invalid-multi-wait-unsubmitted-submitted: SUCCESS (0.000s)
[478.504836] [113/155] (565s left) kms_chamelium_hpd (dp-hpd-storm-disable)
[480.039167] Subtest dp-hpd-storm-disable: SKIP (0.000s)
[480.169178] [114/155] (563s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-wc)
[481.668337] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-wc
[481.671625] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[481.841651] [115/155] (562s left) i915_pm_rc6_residency (rc6-idle)
[481.892505] Starting subtest: rc6-idle
[481.899584] Starting dynamic subtest: gt0-rcs0
[506.248613] Dynamic subtest gt0-rcs0: SUCCESS (24.347s)
[506.266415] Starting dynamic subtest: gt0-bcs0
[530.642159] Dynamic subtest gt0-bcs0: SUCCESS (24.388s)
[530.660201] Starting dynamic subtest: gt0-vcs0
[554.975365] Dynamic subtest gt0-vcs0: SUCCESS (24.328s)
[554.989803] Starting dynamic subtest: gt0-vecs0
[579.224817] Dynamic subtest gt0-vecs0: SUCCESS (24.247s)
[579.228573] Subtest rc6-idle: SUCCESS (97.332s)
[579.409935] [116/155] (464s left) gem_mmap_offset (partial-mmap)
[579.457800] Starting subtest: partial-mmap
[579.459287] Starting dynamic subtest: lmem0
[579.463142] Dynamic subtest lmem0: SUCCESS (0.005s)
[579.477682] Starting dynamic subtest: smem0
[579.479257] Dynamic subtest smem0: SUCCESS (0.006s)
[579.480863] Subtest partial-mmap: SUCCESS (0.014s)
[579.680185] [117/155] (464s left) kms_ccs (crc-sprite-planes-basic-4-tiled-bmg-ccs)
[581.169704] Starting subtest: crc-sprite-planes-basic-4-tiled-bmg-ccs
[581.172590] Subtest crc-sprite-planes-basic-4-tiled-bmg-ccs: SKIP (0.000s)
[581.386250] [118/155] (462s left) kms_psr2_sf (fbc-psr2-cursor-plane-move-continuous-exceed-sf)
[582.878050] Subtest fbc-psr2-cursor-plane-move-continuous-exceed-sf: SKIP (0.000s)
[582.953232] [119/155] (461s left) gem_wait (write-busy)
[583.018597] Starting subtest: write-busy
[583.020119] Starting dynamic subtest: all-engines
[583.613943] Dynamic subtest all-engines: SUCCESS (0.593s)
[583.656900] Starting dynamic subtest: rcs0
[584.192448] Dynamic subtest rcs0: SUCCESS (0.572s)
[584.207396] Starting dynamic subtest: bcs0
[584.740466] Dynamic subtest bcs0: SUCCESS (0.546s)
[584.754862] Starting dynamic subtest: vcs0
[585.285514] Dynamic subtest vcs0: SUCCESS (0.543s)
[585.299798] Starting dynamic subtest: vcs1
[585.827445] Dynamic subtest vcs1: SUCCESS (0.540s)
[585.841719] Starting dynamic subtest: vecs0
[586.369515] Dynamic subtest vecs0: SUCCESS (0.540s)
[586.371152] Subtest write-busy: SUCCESS (3.351s)
[586.664619] [120/155] (457s left) kms_cursor_crc (cursor-onscreen-max-size)
[588.494929] Starting subtest: cursor-onscreen-max-size
[588.498959] Subtest cursor-onscreen-max-size: SKIP (0.000s)
[588.736296] [121/155] (455s left) syncobj_timeline (reset-unsignaled)
[588.790310] Starting subtest: reset-unsignaled
[588.791756] Subtest reset-unsignaled: SUCCESS (0.000s)
[588.915497] [122/155] (455s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[590.424588] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[590.430936] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SKIP (0.000s)
[590.665613] [123/155] (454s left) api_intel_allocator (gem-pool)
[590.714394] Starting subtest: gem-pool
[590.736616] Subtest gem-pool: SUCCESS (0.024s)
[590.947863] [124/155] (453s left) gem_mmap_gtt (big-copy-xy)
[590.998877] Subtest big-copy-XY: SKIP (0.000s)
[591.087278] [125/155] (453s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-gtt)
[592.585308] Starting subtest: psr-rgb565-draw-mmap-gtt
[592.588561] Subtest psr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[592.774506] [126/155] (452s left) kms_psr2_sf (pr-plane-move-sf-dmg-area)
[594.268409] Subtest pr-plane-move-sf-dmg-area: SKIP (0.000s)
[594.452877] [127/155] (450s left) kms_big_fb (4-tiled-8bpp-rotate-0)
[595.963131] Starting subtest: 4-tiled-8bpp-rotate-0
[595.966771] Subtest 4-tiled-8bpp-rotate-0: SKIP (0.000s)
[596.158727] [128/155] (449s left) gem_fence_thrash (bo-write-verify-none)
[596.205872] Starting subtest: bo-write-verify-none
[596.211792] Subtest bo-write-verify-none: SKIP (0.003s)
[596.287644] [129/155] (449s left) kms_flip (wf_vblank-ts-check-interruptible)
[597.783564] Starting subtest: wf_vblank-ts-check-interruptible
[597.792224] Starting dynamic subtest: A-HDMI-A4
[605.815315] Dynamic subtest A-HDMI-A4: SUCCESS (8.022s)
[605.833186] Starting dynamic subtest: B-HDMI-A4
[613.776846] Dynamic subtest B-HDMI-A4: SUCCESS (7.959s)
[613.795862] Starting dynamic subtest: C-HDMI-A4
[621.739882] Dynamic subtest C-HDMI-A4: SUCCESS (7.960s)
[621.756869] Starting dynamic subtest: D-HDMI-A4
[629.702817] Dynamic subtest D-HDMI-A4: SUCCESS (7.960s)
[629.704552] Subtest wf_vblank-ts-check-interruptible: SUCCESS (31.918s)
[629.898966] [130/155] (415s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-spr-indfb-draw-render)
[631.398209] Starting subtest: psrhdr-2p-primscrn-spr-indfb-draw-render
[631.401352] Subtest psrhdr-2p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[631.567295] [131/155] (413s left) kms_chamelium_frames (dp-crc-multiple)
[633.103643] Subtest dp-crc-multiple: SKIP (0.000s)
[633.235339] [132/155] (412s left) gem_exec_whisper (basic-queues-priority)
[633.297791] Starting subtest: basic-queues-priority
[648.748728] Subtest basic-queues-priority: SUCCESS (15.446s)
[648.987996] [133/155] (396s left) gem_render_copy_redux (interruptible)
[649.042026] Starting subtest: interruptible
[649.918885] Subtest interruptible: SUCCESS (0.877s)
[650.114972] [134/155] (395s left) i915_hangman (gt-engine-hang)
[650.179938] Starting subtest: gt-engine-hang
[650.181628] Starting dynamic subtest: rcs0
[650.875035] Dynamic subtest rcs0: SUCCESS (0.696s)
[650.889562] Starting dynamic subtest: bcs0
[651.535934] Dynamic subtest bcs0: SUCCESS (0.659s)
[651.550821] Starting dynamic subtest: vcs0
[652.198852] Dynamic subtest vcs0: SUCCESS (0.661s)
[652.213592] Starting dynamic subtest: vcs1
[652.870955] Dynamic subtest vcs1: SUCCESS (0.671s)
[652.886755] Starting dynamic subtest: vecs0
[653.547016] Dynamic subtest vecs0: SUCCESS (0.674s)
[653.548768] Subtest gt-engine-hang: SUCCESS (3.370s)
[653.726828] [135/155] (392s left) kms_flip (2x-flip-vs-expired-vblank-interruptible)
[655.224489] Starting subtest: 2x-flip-vs-expired-vblank-interruptible
[655.243303] Subtest 2x-flip-vs-expired-vblank-interruptible: SKIP (0.017s)
[655.400988] [136/155] (390s left) perf (rc6-disable)
[655.528363] Starting subtest: rc6-disable
[655.729360] Subtest rc6-disable: SUCCESS (0.200s)
[655.826909] [137/155] (390s left) kms_frontbuffer_tracking (hdr-1p-primscrn-pri-shrfb-draw-mmap-cpu)
[657.328105] Starting subtest: hdr-1p-primscrn-pri-shrfb-draw-mmap-cpu
[657.331235] Subtest hdr-1p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[657.490938] [138/155] (388s left) kms_content_protection (legacy-hdcp14)
[659.039501] Starting subtest: legacy-hdcp14
[659.046844] Subtest legacy-hdcp14: SKIP (0.002s)
[659.242524] [139/155] (387s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-render)
[660.740157] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-render
[660.743120] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[660.918799] [140/155] (385s left) kms_addfb_basic (unused-offsets)
[660.965960] Starting subtest: unused-offsets
[660.967639] Subtest unused-offsets: SUCCESS (0.000s)
[662.618475] [141/155] (383s left) kms_ccs (bad-rotation-90-y-tiled-gen12-mc-ccs)
[664.122256] Starting subtest: bad-rotation-90-y-tiled-gen12-mc-ccs
[664.126103] Starting dynamic subtest: pipe-A-HDMI-A-4
[664.141689] Dynamic subtest pipe-A-HDMI-A-4: SKIP (0.000s)
[664.156122] Starting dynamic subtest: pipe-B-HDMI-A-4
[664.171775] Dynamic subtest pipe-B-HDMI-A-4: SKIP (0.000s)
[664.185922] Starting dynamic subtest: pipe-C-HDMI-A-4
[664.202612] Dynamic subtest pipe-C-HDMI-A-4: SKIP (0.000s)
[664.216903] Starting dynamic subtest: pipe-D-HDMI-A-4
[664.235989] Dynamic subtest pipe-D-HDMI-A-4: SKIP (0.000s)
[664.239351] Subtest bad-rotation-90-y-tiled-gen12-mc-ccs: SKIP (0.010s)
[664.389283] [142/155] (382s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-shrfb-draw-mmap-wc)
[665.886152] Starting subtest: psrhdr-1p-primscrn-pri-shrfb-draw-mmap-wc
[665.889050] Subtest psrhdr-1p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[666.130480] [143/155] (380s left) i915_selftest (perf)
[667.245302] Starting subtest: perf
[667.246995] Starting dynamic subtest: engine_cs
[668.689659] Dynamic subtest engine_cs: SUCCESS (1.446s)
[668.700806] Starting dynamic subtest: request
[673.167502] Dynamic subtest request: SUCCESS (4.476s)
[673.178686] Starting dynamic subtest: migrate
[674.744468] Dynamic subtest migrate: SUCCESS (1.575s)
[674.755893] Starting dynamic subtest: region
[688.626885] Dynamic subtest region: SUCCESS (13.880s)
[688.628622] Subtest perf: SUCCESS (21.383s)
[688.683076] [FACT i915_selftest (perf)] deleted: hardware.pci.drm_card_at_addr.0000:03:00.0: card0
[688.684202] [FACT i915_selftest (perf)] deleted: kernel.kmod_is_loaded.i915: true
[688.687829] [144/155] (358s left) gem_ppgtt (shrink-vs-evict-pinned)
[692.018557] Starting subtest: shrink-vs-evict-pinned
[697.860173] Subtest shrink-vs-evict-pinned: SUCCESS (5.838s)
[697.964325] [FACT gem_ppgtt (shrink-vs-evict-pinned)] new: hardware.pci.drm_card_at_addr.0000:03:00.0: card0
[697.967700] [FACT gem_ppgtt (shrink-vs-evict-pinned)] new: kernel.kmod_is_loaded.i915: true
[697.971452] [145/155] (348s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-blt)
[699.473306] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-blt
[699.476534] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[699.665147] [146/155] (347s left) kms_joiner (basic-big-joiner)
[701.158998] Starting subtest: basic-big-joiner
[701.164844] Subtest basic-big-joiner: SKIP (0.000s)
[701.302178] [147/155] (345s left) kms_cursor_legacy (cursorb-vs-flipb-atomic-transitions)
[702.798937] Starting subtest: cursorB-vs-flipB-atomic-transitions
[702.802116] Subtest cursorB-vs-flipB-atomic-transitions: SKIP (0.001s)
[702.913394] [148/155] (344s left) kms_frontbuffer_tracking (fbcpsr-2p-indfb-fliptrack-mmap-gtt)
[704.413697] Starting subtest: fbcpsr-2p-indfb-fliptrack-mmap-gtt
[704.416757] Subtest fbcpsr-2p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[704.599793] [149/155] (342s left) gem_mmap_gtt (cpuset-basic-small-copy-odd)
[704.650825] Subtest cpuset-basic-small-copy-odd: SKIP (0.000s)
[704.724561] [150/155] (342s left) kms_frontbuffer_tracking (fbcpsrhdr-rgb101010-draw-mmap-gtt)
[706.220917] Starting subtest: fbcpsrhdr-rgb101010-draw-mmap-gtt
[706.223875] Subtest fbcpsrhdr-rgb101010-draw-mmap-gtt: SKIP (0.000s)
[706.413473] [151/155] (341s left) kms_chamelium_color (ctm-max)
[707.905033] Subtest ctm-max: SKIP (0.000s)
[707.989681] [152/155] (339s left) kms_pm_rpm (system-suspend-idle)
[712.004910] Starting subtest: system-suspend-idle
[731.484837] Subtest system-suspend-idle: SUCCESS (4.562s)
[736.568438] [153/155] (315s left) kms_psr (fbc-pr-primary-blt)
[738.061253] Subtest fbc-pr-primary-blt: SKIP (0.000s)
[738.148566] [154/155] (313s left) gem_userptr_blits (forbidden-operations)
[738.207247] Starting subtest: forbidden-operations
[738.210763] Subtest forbidden-operations: SKIP (0.000s)
[738.425454] [155/155] (313s left) syncobj_timeline (wait-zero-handles)
[738.489601] Starting subtest: wait-zero-handles
[738.491612] Subtest wait-zero-handles: SUCCESS (0.000s)
[738.598891] Closing watchdogs
results: parsing output: 0/ for test: kms_busy
results: parsing output: 1/ for test: perf
results: parsing output: 2/ for test: gem_tiling_max_stride
results: parsing output: 3/ for test: gem_exec_fence
results: parsing output: 4/ for test: gem_mmap_wc
results: parsing output: 5/ for test: api_intel_bb
results: parsing output: 6/ for test: gem_exec_store
results: parsing output: 7/ for test: kms_draw_crc
results: parsing output: 8/ for test: kms_psr
results: parsing output: 9/ for test: kms_addfb_basic
results: parsing output: 10/ for test: dmabuf_sync_file
results: parsing output: 11/ for test: kms_vblank
results: parsing output: 12/ for test: gem_exec_schedule
results: parsing output: 13/ for test: kms_frontbuffer_tracking
results: parsing output: 14/ for test: kms_frontbuffer_tracking
results: parsing output: 15/ for test: kms_frontbuffer_tracking
results: parsing output: 16/ for test: gem_workarounds
results: parsing output: 17/ for test: kms_frontbuffer_tracking
results: parsing output: 18/ for test: kms_frontbuffer_tracking
results: parsing output: 19/ for test: gem_exec_balancer
results: parsing output: 20/ for test: gem_eio
results: parsing output: 21/ for test: gem_render_copy
results: parsing output: 22/ for test: syncobj_timeline
results: parsing output: 23/ for test: kms_frontbuffer_tracking
results: parsing output: 24/ for test: kms_frontbuffer_tracking
results: parsing output: 25/ for test: kms_frontbuffer_tracking
results: parsing output: 26/ for test: gem_exec_parallel
results: parsing output: 27/ for test: syncobj_timeline
results: parsing output: 28/ for test: kms_psr
results: parsing output: 29/ for test: syncobj_timeline
results: parsing output: 30/ for test: kms_big_fb
results: parsing output: 31/ for test: prime_busy
results: parsing output: 32/ for test: kms_frontbuffer_tracking
results: parsing output: 33/ for test: kms_frontbuffer_tracking
results: parsing output: 34/ for test: kms_frontbuffer_tracking
results: parsing output: 35/ for test: kms_frontbuffer_tracking
results: parsing output: 36/ for test: kms_plane
results: parsing output: 37/ for test: gem_ctx_persistence
results: parsing output: 38/ for test: gem_basic
results: parsing output: 39/ for test: gem_pxp
results: parsing output: 40/ for test: kms_frontbuffer_tracking
results: parsing output: 41/ for test: gem_exec_schedule
results: parsing output: 42/ for test: gem_busy
results: parsing output: 43/ for test: kms_frontbuffer_tracking
results: parsing output: 44/ for test: gem_set_tiling_vs_blt
results: parsing output: 45/ for test: gem_partial_pwrite_pread
results: parsing output: 46/ for test: syncobj_timeline
results: parsing output: 47/ for test: kms_psr
results: parsing output: 48/ for test: kms_plane_cursor
results: parsing output: 49/ for test: kms_frontbuffer_tracking
results: parsing output: 50/ for test: gem_render_copy
results: parsing output: 51/ for test: kms_ccs
results: parsing output: 52/ for test: kms_flip_scaled_crc
results: parsing output: 53/ for test: kms_frontbuffer_tracking
results: parsing output: 54/ for test: sw_sync
results: parsing output: 55/ for test: kms_frontbuffer_tracking
results: parsing output: 56/ for test: kms_ccs
results: parsing output: 57/ for test: gem_ctx_shared
results: parsing output: 58/ for test: gem_eio
results: parsing output: 59/ for test: kms_cursor_legacy
results: parsing output: 60/ for test: gem_mmap_gtt
results: parsing output: 61/ for test: kms_frontbuffer_tracking
results: parsing output: 62/ for test: kms_cursor_crc
results: parsing output: 63/ for test: kms_pm_rpm
results: parsing output: 64/ for test: kms_lease
results: parsing output: 65/ for test: kms_getfb
results: parsing output: 66/ for test: gem_mmap_wc
results: parsing output: 67/ for test: kms_flip_scaled_crc
results: parsing output: 68/ for test: gen7_exec_parse
results: parsing output: 69/ for test: perf_pmu
results: parsing output: 70/ for test: kms_panel_fitting
results: parsing output: 71/ for test: gem_userptr_blits
results: parsing output: 72/ for test: kms_rotation_crc
results: parsing output: 73/ for test: kms_psr
results: parsing output: 74/ for test: kms_cursor_crc
results: parsing output: 75/ for test: kms_big_fb
results: parsing output: 76/ for test: kms_frontbuffer_tracking
results: parsing output: 77/ for test: kms_frontbuffer_tracking
results: parsing output: 78/ for test: kms_addfb_basic
results: parsing output: 79/ for test: kms_frontbuffer_tracking
results: parsing output: 80/ for test: kms_flip
results: parsing output: 81/ for test: kms_big_fb
results: parsing output: 82/ for test: kms_frontbuffer_tracking
results: parsing output: 83/ for test: gem_exec_balancer
results: parsing output: 84/ for test: kms_big_fb
results: parsing output: 85/ for test: gem_exec_flush
results: parsing output: 86/ for test: gem_exec_reloc
results: parsing output: 87/ for test: kms_frontbuffer_tracking
results: parsing output: 88/ for test: gem_spin_batch
results: parsing output: 89/ for test: gem_madvise
results: parsing output: 90/ for test: kms_frontbuffer_tracking
results: parsing output: 91/ for test: kms_plane_scaling
results: parsing output: 92/ for test: gem_softpin
results: parsing output: 93/ for test: gem_create
results: parsing output: 94/ for test: kms_frontbuffer_tracking
results: parsing output: 95/ for test: kms_frontbuffer_tracking
results: parsing output: 96/ for test: kms_frontbuffer_tracking
results: parsing output: 97/ for test: kms_frontbuffer_tracking
results: parsing output: 98/ for test: gem_ctx_persistence
results: parsing output: 99/ for test: kms_frontbuffer_tracking
results: parsing output: 100/ for test: kms_atomic_transition
results: parsing output: 101/ for test: kms_color_pipeline
results: parsing output: 102/ for test: kms_frontbuffer_tracking
results: parsing output: 103/ for test: kms_frontbuffer_tracking
results: parsing output: 104/ for test: kms_plane_multiple
results: parsing output: 105/ for test: i915_drm_fdinfo
results: parsing output: 106/ for test: sw_sync
results: parsing output: 107/ for test: kms_sequence
results: parsing output: 108/ for test: gem_exec_params
results: parsing output: 109/ for test: gem_softpin
results: parsing output: 110/ for test: kms_plane_scaling
results: parsing output: 111/ for test: syncobj_wait
results: parsing output: 112/ for test: kms_chamelium_hpd
results: parsing output: 113/ for test: kms_frontbuffer_tracking
results: parsing output: 114/ for test: i915_pm_rc6_residency
results: parsing output: 115/ for test: gem_mmap_offset
results: parsing output: 116/ for test: kms_ccs
results: parsing output: 117/ for test: kms_psr2_sf
results: parsing output: 118/ for test: gem_wait
results: parsing output: 119/ for test: kms_cursor_crc
results: parsing output: 120/ for test: syncobj_timeline
results: parsing output: 121/ for test: kms_big_fb
results: parsing output: 122/ for test: api_intel_allocator
results: parsing output: 123/ for test: gem_mmap_gtt
results: parsing output: 124/ for test: kms_frontbuffer_tracking
results: parsing output: 125/ for test: kms_psr2_sf
results: parsing output: 126/ for test: kms_big_fb
results: parsing output: 127/ for test: gem_fence_thrash
results: parsing output: 128/ for test: kms_flip
results: parsing output: 129/ for test: kms_frontbuffer_tracking
results: parsing output: 130/ for test: kms_chamelium_frames
results: parsing output: 131/ for test: gem_exec_whisper
results: parsing output: 132/ for test: gem_render_copy_redux
results: parsing output: 133/ for test: i915_hangman
results: parsing output: 134/ for test: kms_flip
results: parsing output: 135/ for test: perf
results: parsing output: 136/ for test: kms_frontbuffer_tracking
results: parsing output: 137/ for test: kms_content_protection
results: parsing output: 138/ for test: kms_frontbuffer_tracking
results: parsing output: 139/ for test: kms_addfb_basic
results: parsing output: 140/ for test: kms_ccs
results: parsing output: 141/ for test: kms_frontbuffer_tracking
results: parsing output: 142/ for test: i915_selftest
results: parsing output: 143/ for test: gem_ppgtt
results: parsing output: 144/ for test: kms_frontbuffer_tracking
results: parsing output: 145/ for test: kms_joiner
results: parsing output: 146/ for test: kms_cursor_legacy
results: parsing output: 147/ for test: kms_frontbuffer_tracking
results: parsing output: 148/ for test: gem_mmap_gtt
results: parsing output: 149/ for test: kms_frontbuffer_tracking
results: parsing output: 150/ for test: kms_chamelium_color
results: parsing output: 151/ for test: kms_pm_rpm
results: parsing output: 152/ for test: kms_psr
results: parsing output: 153/ for test: gem_userptr_blits
results: parsing output: 154/ for test: syncobj_timeline
Done.