[49.306635] Initializing watchdogs
[49.306700]   /dev/watchdog0
[49.322690] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:9a49 Intel Tigerlake (Gen12) TigerLake-LP GT2 [Iris Xe Graphics]
[49.328515] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[49.331585] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[49.333901] [001/155] (960s left) kms_busy (extended-pageflip-hang-oldfb)
[49.436488] Starting subtest: extended-pageflip-hang-oldfb
[49.437228] Starting dynamic subtest: pipe-A
[49.596473] Dynamic subtest pipe-A: SUCCESS (0.159s)
[49.604927] Starting dynamic subtest: pipe-D
[49.759218] Dynamic subtest pipe-D: SUCCESS (0.160s)
[49.760953] Subtest extended-pageflip-hang-oldfb: SUCCESS (0.324s)
[49.890211] [002/155] (959s left) perf (non-zero-reason)
[49.976188] Starting subtest: non-zero-reason
[49.976948] Starting dynamic subtest: 0-rcs0
[58.541339] Dynamic subtest 0-rcs0: SUCCESS (8.556s)
[58.542076] Subtest non-zero-reason: SUCCESS (8.557s)
[58.570502] [003/155] (951s left) gem_tiling_max_stride
[58.720954] [004/155] (951s left) gem_exec_fence (invalid-timeline-fence-array)
[59.195602] Starting subtest: invalid-timeline-fence-array
[59.196370] Subtest invalid-timeline-fence-array: SUCCESS (0.008s)
[59.360989] [FACT gem_exec_fence (invalid-timeline-fence-array)] new: kernel.kmod_is_loaded.vgem: true
[59.363662] [005/155] (950s left) gem_mmap_wc (write)
[59.397108] Starting subtest: write
[59.432625] Subtest write: SUCCESS (0.036s)
[59.502033] [006/155] (950s left) api_intel_bb (intel-bb-blit-none)
[59.535354] Starting subtest: intel-bb-blit-none
[62.944336] Subtest intel-bb-blit-none: SUCCESS (3.375s)
[63.018463] [007/155] (947s left) gem_exec_store (dword)
[63.060273] Starting subtest: dword
[63.061060] Starting dynamic subtest: rcs0
[63.096661] Dynamic subtest rcs0: SUCCESS (0.036s)
[63.107871] Starting dynamic subtest: bcs0
[63.116393] Dynamic subtest bcs0: SUCCESS (0.019s)
[63.129388] Starting dynamic subtest: vcs0
[63.141417] Dynamic subtest vcs0: SUCCESS (0.024s)
[63.152580] Starting dynamic subtest: vcs1
[63.165301] Dynamic subtest vcs1: SUCCESS (0.023s)
[63.176499] Starting dynamic subtest: vecs0
[63.186411] Dynamic subtest vecs0: SUCCESS (0.021s)
[63.187428] Subtest dword: SUCCESS (0.126s)
[63.373065] [008/155] (946s left) kms_draw_crc (draw-method-mmap-gtt)
[63.463420] Starting subtest: draw-method-mmap-gtt
[63.464194] Starting dynamic subtest: xrgb8888-untiled
[63.622676] Dynamic subtest xrgb8888-untiled: SUCCESS (0.159s)
[63.637830] Starting dynamic subtest: rgb565-untiled
[63.789390] Dynamic subtest rgb565-untiled: SUCCESS (0.166s)
[63.804658] Starting dynamic subtest: xrgb2101010-untiled
[63.939339] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.149s)
[63.951969] Starting dynamic subtest: xbgr16161616f-untiled
[64.089206] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.149s)
[64.102762] Starting dynamic subtest: xrgb8888-xtiled
[64.164634] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.075s)
[64.182421] Starting dynamic subtest: rgb565-xtiled
[64.247933] Dynamic subtest rgb565-xtiled: SUCCESS (0.083s)
[64.261752] Starting dynamic subtest: xrgb2101010-xtiled
[64.314484] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.066s)
[64.331949] Starting dynamic subtest: xbgr16161616f-xtiled
[64.394802] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.074s)
[64.410203] Starting dynamic subtest: xrgb8888-ytiled
[64.481112] Dynamic subtest xrgb8888-ytiled: SUCCESS (0.091s)
[64.494286] Starting dynamic subtest: rgb565-ytiled
[64.564665] Dynamic subtest rgb565-ytiled: SUCCESS (0.083s)
[64.584130] Starting dynamic subtest: xrgb2101010-ytiled
[64.647688] Dynamic subtest xrgb2101010-ytiled: SUCCESS (0.082s)
[64.661992] Starting dynamic subtest: xbgr16161616f-ytiled
[64.756086] Dynamic subtest xbgr16161616f-ytiled: SUCCESS (0.108s)
[64.757179] Subtest draw-method-mmap-gtt: SUCCESS (1.293s)
[64.873443] [009/155] (945s left) kms_psr (fbc-psr-primary-page-flip)
[64.969723] Subtest fbc-psr-primary-page-flip: SKIP (0.000s)
[65.011754] [010/155] (945s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[65.100661] Starting subtest: addfb25-x-tiled-mismatch-legacy
[65.101430] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.000s)
[65.205430] [011/155] (945s left) dmabuf_sync_file (export-multiwait)
[65.240041] Starting subtest: export-multiwait
[65.240797] Subtest export-multiwait: SUCCESS (0.001s)
[65.306161] [012/155] (944s left) kms_vblank (ts-continuation-modeset-hang)
[65.393710] Starting subtest: ts-continuation-modeset-hang
[65.395190] Starting dynamic subtest: pipe-A-HDMI-A-1
[79.509085] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (14.114s)
[79.519575] Starting dynamic subtest: pipe-D-HDMI-A-1
[93.845497] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (14.332s)
[93.846288] Subtest ts-continuation-modeset-hang: SUCCESS (28.451s)
[93.997793] [013/155] (916s left) gem_exec_schedule (submit-late-slice)
[94.051611] Starting subtest: submit-late-slice
[94.052362] Starting dynamic subtest: rcs0
[94.081463] Dynamic subtest rcs0: SUCCESS (0.029s)
[94.089389] Starting dynamic subtest: bcs0
[94.111016] Dynamic subtest bcs0: SUCCESS (0.030s)
[94.118277] Starting dynamic subtest: vcs0
[94.140319] Dynamic subtest vcs0: SUCCESS (0.029s)
[94.149411] Starting dynamic subtest: vcs1
[94.177145] Dynamic subtest vcs1: SUCCESS (0.037s)
[94.184945] Starting dynamic subtest: vecs0
[94.209312] Dynamic subtest vecs0: SUCCESS (0.032s)
[94.210211] Subtest submit-late-slice: SUCCESS (0.158s)
[94.523795] [014/155] (915s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-cur-indfb-draw-pwrite)
[94.619824] Starting subtest: fbcpsrhdr-1p-primscrn-cur-indfb-draw-pwrite
[94.621320] Subtest fbcpsrhdr-1p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[94.730634] [015/155] (915s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-move)
[94.824698] Starting subtest: fbc-2p-scndscrn-spr-indfb-move
[94.826116] Subtest fbc-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[94.929523] [016/155] (915s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[95.023336] Starting subtest: psrhdr-1p-primscrn-pri-shrfb-draw-mmap-gtt
[95.024782] Subtest psrhdr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[95.130169] [017/155] (915s left) gem_workarounds (reset)
[95.180117] Starting subtest: reset
[95.191373] Subtest reset: SUCCESS (0.011s)
[95.297015] [018/155] (915s left) kms_frontbuffer_tracking (hdr-modesetfrombusy)
[95.390165] Starting subtest: hdr-modesetfrombusy
[95.391600] Subtest hdr-modesetfrombusy: SKIP (0.000s)
[95.478753] [019/155] (915s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-mmap-wc)
[95.571161] Starting subtest: psr-1p-offscreen-pri-indfb-draw-mmap-wc
[95.572549] Subtest psr-1p-offscreen-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[95.645094] [020/155] (914s left) gem_exec_balancer (parallel-keep-submit-fence)
[95.806490] Subtest parallel-keep-submit-fence: SKIP (0.000s)
[95.876682] [021/155] (914s left) gem_eio (in-flight-internal-10ms)
[95.920119] Starting subtest: in-flight-internal-10ms
[96.193083] Subtest in-flight-internal-10ms: SUCCESS (0.273s)
[96.325350] [022/155] (914s left) gem_render_copy (y-tiled-ccs-to-y-tiled)
[96.365758] Starting subtest: y-tiled-ccs-to-y-tiled
[96.366510] Starting dynamic subtest: smem
[96.479930] Dynamic subtest smem: SUCCESS (0.106s)
[96.480691] Subtest y-tiled-ccs-to-y-tiled: SUCCESS (0.106s)
[96.658465] [023/155] (914s left) syncobj_timeline (etime-single-wait-all-for-submit-submitted)
[96.693566] Starting subtest: etime-single-wait-all-for-submit-submitted
[96.805879] Subtest etime-single-wait-all-for-submit-submitted: SUCCESS (0.103s)
[96.873261] [024/155] (913s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-cur-indfb-draw-pwrite)
[96.966504] Starting subtest: fbcpsrhdr-2p-scndscrn-cur-indfb-draw-pwrite
[96.967933] Subtest fbcpsrhdr-2p-scndscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[97.072584] [025/155] (913s left) kms_frontbuffer_tracking (fbchdr-tiling-4)
[97.164120] Starting subtest: fbchdr-tiling-4
[97.165471] Subtest fbchdr-tiling-4: SKIP (0.000s)
[97.263912] [026/155] (913s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-indfb-draw-render)
[97.356447] Starting subtest: psrhdr-2p-scndscrn-pri-indfb-draw-render
[97.357919] Subtest psrhdr-2p-scndscrn-pri-indfb-draw-render: SKIP (0.000s)
[97.450645] [027/155] (913s left) gem_exec_parallel (contexts)
[97.492279] Starting subtest: contexts
[97.493095] Starting dynamic subtest: rcs0
[100.277929] Dynamic subtest rcs0: SUCCESS (2.777s)
[100.287218] Starting dynamic subtest: bcs0
[102.939922] Dynamic subtest bcs0: SUCCESS (2.661s)
[102.948339] Starting dynamic subtest: vcs0
[105.315901] Dynamic subtest vcs0: SUCCESS (2.375s)
[105.323379] Starting dynamic subtest: vcs1
[108.669756] Dynamic subtest vcs1: SUCCESS (3.354s)
[108.679974] Starting dynamic subtest: vecs0
[111.168942] Dynamic subtest vecs0: SUCCESS (2.499s)
[111.169849] Subtest contexts: SUCCESS (13.668s)
[111.362979] [028/155] (899s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-submitted)
[111.397241] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-submitted
[111.509757] Subtest etime-multi-wait-all-for-submit-unsubmitted-submitted: SUCCESS (0.103s)
[111.596318] [029/155] (899s left) kms_psr (psr-basic)
[111.685235] Subtest psr-basic: SKIP (0.000s)
[111.724449] [030/155] (899s left) syncobj_timeline (single-wait-signaled)
[111.759345] Starting subtest: single-wait-signaled
[111.760121] Subtest single-wait-signaled: SUCCESS (0.000s)
[111.839674] [031/155] (899s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[111.935930] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[112.251370] Subtest x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SUCCESS (0.315s)
[112.384366] [032/155] (898s left) prime_busy (hang)
[112.422584] Starting subtest: hang
[112.423316] Starting dynamic subtest: rcs0
[126.591339] Dynamic subtest rcs0: SUCCESS (14.135s)
[126.598741] Starting dynamic subtest: bcs0
[133.567389] Dynamic subtest bcs0: SUCCESS (6.975s)
[133.576043] Starting dynamic subtest: vcs0
[140.607836] Dynamic subtest vcs0: SUCCESS (7.040s)
[140.615369] Starting dynamic subtest: vcs1
[147.584193] Dynamic subtest vcs1: SUCCESS (6.976s)
[147.591548] Starting dynamic subtest: vecs0
[154.623642] Dynamic subtest vecs0: SUCCESS (7.040s)
[154.624744] Subtest hang: SUCCESS (42.168s)
[154.704317] [033/155] (856s left) kms_frontbuffer_tracking (fbcpsr-slowdraw)
[154.798156] Starting subtest: fbcpsr-slowdraw
[154.799545] Subtest fbcpsr-slowdraw: SKIP (0.000s)
[154.891674] [034/155] (856s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-mmap-wc)
[154.984338] Starting subtest: psr-1p-primscrn-pri-indfb-draw-mmap-wc
[154.985721] Subtest psr-1p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[155.098564] [035/155] (856s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-cur-indfb-draw-pwrite)
[155.190170] Starting subtest: fbcpsrhdr-2p-primscrn-cur-indfb-draw-pwrite
[155.191557] Subtest fbcpsrhdr-2p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[155.287906] [036/155] (855s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-spr-indfb-draw-mmap-cpu)
[155.379171] Starting subtest: fbchdr-2p-primscrn-spr-indfb-draw-mmap-cpu
[155.380540] Subtest fbchdr-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[155.482745] [037/155] (855s left) kms_plane (pixel-format-4-tiled-mtl-rc-ccs-cc-modifier-source-clamping)
[155.570987] Starting subtest: pixel-format-4-tiled-mtl-rc-ccs-cc-modifier-source-clamping
[155.572497] Subtest pixel-format-4-tiled-mtl-rc-ccs-cc-modifier-source-clamping: SKIP (0.000s)
[155.626786] [038/155] (855s left) gem_ctx_persistence (legacy-engines-persistence)
[155.665338] Starting subtest: legacy-engines-persistence
[155.666050] Starting dynamic subtest: render
[156.414479] Dynamic subtest render: SUCCESS (0.748s)
[156.426220] Starting dynamic subtest: bsd1
[157.179358] Dynamic subtest bsd1: SUCCESS (0.763s)
[157.190319] Starting dynamic subtest: bsd2
[157.949344] Dynamic subtest bsd2: SUCCESS (0.769s)
[157.959923] Starting dynamic subtest: blt
[158.716356] Dynamic subtest blt: SUCCESS (0.766s)
[158.726969] Starting dynamic subtest: vebox
[159.486423] Dynamic subtest vebox: SUCCESS (0.769s)
[159.487532] Subtest legacy-engines-persistence: SUCCESS (3.821s)
[159.557226] [039/155] (851s left) gem_basic (create-fd-close)
[159.590866] Starting subtest: create-fd-close
[159.592275] Subtest create-fd-close: SUCCESS (0.000s)
[159.699384] [040/155] (851s left) gem_pxp (regular-baseline-src-copy-readible)
[159.799299] Starting subtest: regular-baseline-src-copy-readible
[159.846313] Subtest regular-baseline-src-copy-readible: SUCCESS (0.048s)
[159.960533] [041/155] (851s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-indfb-msflip-blt)
[160.053091] Starting subtest: fbchdr-2p-primscrn-indfb-msflip-blt
[160.054433] Subtest fbchdr-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[160.145604] [042/155] (851s left) gem_exec_schedule (implicit-write-read)
[160.188490] Starting subtest: implicit-write-read
[160.189233] Starting dynamic subtest: rcs0
[160.251544] Dynamic subtest rcs0: SUCCESS (0.062s)
[160.262992] Starting dynamic subtest: bcs0
[160.315846] Dynamic subtest bcs0: SUCCESS (0.064s)
[160.325619] Starting dynamic subtest: vcs0
[160.378387] Dynamic subtest vcs0: SUCCESS (0.062s)
[160.389709] Starting dynamic subtest: vcs1
[160.444987] Dynamic subtest vcs1: SUCCESS (0.066s)
[160.455982] Starting dynamic subtest: vecs0
[160.510639] Dynamic subtest vecs0: SUCCESS (0.065s)
[160.512051] Subtest implicit-write-read: SUCCESS (0.322s)
[160.819431] [043/155] (850s left) gem_busy (parallel)
[160.993435] Starting subtest: parallel
[160.994493] Starting dynamic subtest: rcs0
[161.037479] Dynamic subtest rcs0: SUCCESS (0.052s)
[161.050583] Starting dynamic subtest: bcs0
[161.068549] Dynamic subtest bcs0: SUCCESS (0.031s)
[161.082867] Starting dynamic subtest: vcs0
[161.097269] Dynamic subtest vcs0: SUCCESS (0.028s)
[161.111213] Starting dynamic subtest: vcs1
[161.125579] Dynamic subtest vcs1: SUCCESS (0.028s)
[161.138290] Starting dynamic subtest: vecs0
[161.154478] Dynamic subtest vecs0: SUCCESS (0.028s)
[161.155827] Subtest parallel: SUCCESS (0.170s)
[161.402269] [044/155] (850s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-mmap-wc)
[161.497451] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-mmap-wc
[162.549110] Subtest fbc-1p-offscreen-pri-shrfb-draw-mmap-wc: SUCCESS (1.053s)
[162.674212] [045/155] (848s left) gem_set_tiling_vs_blt (untiled-to-tiled)
[162.712452] Starting subtest: untiled-to-tiled
[162.844131] Subtest untiled-to-tiled: SUCCESS (0.122s)
[162.950058] [046/155] (848s left) gem_partial_pwrite_pread (writes-after-reads-uncached)
[162.987149] Starting subtest: writes-after-reads-uncached
[175.239153] Subtest writes-after-reads-uncached: SUCCESS (12.253s)
[175.348177] [047/155] (836s left) syncobj_timeline (multi-wait-all-for-submit-signaled)
[175.382265] Starting subtest: multi-wait-all-for-submit-signaled
[175.382968] Subtest multi-wait-all-for-submit-signaled: SUCCESS (0.001s)
[175.462299] [048/155] (836s left) kms_psr (pr-cursor-render)
[175.551435] Subtest pr-cursor-render: SKIP (0.000s)
[175.598409] [049/155] (836s left) kms_plane_cursor (primary)
[175.685518] Starting subtest: primary
[175.686954] Starting dynamic subtest: pipe-A-HDMI-A-1-size-64
[176.892211] Dynamic subtest pipe-A-HDMI-A-1-size-64: SUCCESS (1.206s)
[176.933322] Starting dynamic subtest: pipe-A-HDMI-A-1-size-128
[178.133980] Dynamic subtest pipe-A-HDMI-A-1-size-128: SUCCESS (1.217s)
[178.175051] Starting dynamic subtest: pipe-A-HDMI-A-1-size-256
[179.375827] Dynamic subtest pipe-A-HDMI-A-1-size-256: SUCCESS (1.217s)
[179.461471] Starting dynamic subtest: pipe-B-HDMI-A-1-size-64
[180.719257] Dynamic subtest pipe-B-HDMI-A-1-size-64: SUCCESS (1.267s)
[180.750913] Starting dynamic subtest: pipe-B-HDMI-A-1-size-128
[181.952692] Dynamic subtest pipe-B-HDMI-A-1-size-128: SUCCESS (1.217s)
[181.984352] Starting dynamic subtest: pipe-B-HDMI-A-1-size-256
[183.186220] Dynamic subtest pipe-B-HDMI-A-1-size-256: SUCCESS (1.217s)
[183.262322] Starting dynamic subtest: pipe-C-HDMI-A-1-size-64
[184.519563] Dynamic subtest pipe-C-HDMI-A-1-size-64: SUCCESS (1.265s)
[184.550786] Starting dynamic subtest: pipe-C-HDMI-A-1-size-128
[185.753121] Dynamic subtest pipe-C-HDMI-A-1-size-128: SUCCESS (1.217s)
[185.779692] Starting dynamic subtest: pipe-C-HDMI-A-1-size-256
[186.978203] Dynamic subtest pipe-C-HDMI-A-1-size-256: SUCCESS (1.208s)
[187.061529] Starting dynamic subtest: pipe-D-HDMI-A-1-size-64
[188.317828] Dynamic subtest pipe-D-HDMI-A-1-size-64: SUCCESS (1.267s)
[188.344407] Starting dynamic subtest: pipe-D-HDMI-A-1-size-128
[189.542902] Dynamic subtest pipe-D-HDMI-A-1-size-128: SUCCESS (1.208s)
[189.570281] Starting dynamic subtest: pipe-D-HDMI-A-1-size-256
[190.776394] Dynamic subtest pipe-D-HDMI-A-1-size-256: SUCCESS (1.216s)
[190.843836] Subtest primary: SUCCESS (15.159s)
[190.960029] [050/155] (820s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[191.060773] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-wc
[191.062184] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[191.170523] [051/155] (820s left) gem_render_copy (y-tiled-mc-ccs-to-vebox-yf-tiled)
[191.209591] Starting subtest: y-tiled-mc-ccs-to-vebox-yf-tiled
[191.210333] Starting dynamic subtest: smem
[191.286815] Dynamic subtest smem: SUCCESS (0.077s)
[191.287526] Subtest y-tiled-mc-ccs-to-vebox-yf-tiled: SUCCESS (0.077s)
[191.471782] [052/155] (820s left) kms_ccs (bad-pixel-format-4-tiled-dg2-rc-ccs)
[191.558838] Starting subtest: bad-pixel-format-4-tiled-dg2-rc-ccs
[191.559546] Starting dynamic subtest: pipe-A-HDMI-A-1
[191.566211] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.000s)
[191.574161] Starting dynamic subtest: pipe-B-HDMI-A-1
[191.580909] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.000s)
[191.588815] Starting dynamic subtest: pipe-C-HDMI-A-1
[191.595826] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.000s)
[191.603489] Starting dynamic subtest: pipe-D-HDMI-A-1
[191.610359] Dynamic subtest pipe-D-HDMI-A-1: SKIP (0.000s)
[191.611727] Subtest bad-pixel-format-4-tiled-dg2-rc-ccs: SKIP (0.005s)
[191.821480] [053/155] (820s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling)
[191.909824] Starting subtest: flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling
[191.911361] Subtest flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling: SKIP (0.000s)
[191.983072] [054/155] (820s left) kms_frontbuffer_tracking (fbc-rgb565-draw-blt)
[192.076604] Starting subtest: fbc-rgb565-draw-blt
[193.341754] Subtest fbc-rgb565-draw-blt: SUCCESS (1.266s)
[193.463712] [055/155] (818s left) sw_sync (sync_multi_producer_single_consumer)
[193.497268] Starting subtest: sync_multi_producer_single_consumer
[194.580196] Subtest sync_multi_producer_single_consumer: SUCCESS (1.075s)
[194.629820] [056/155] (817s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-shrfb-pgflip-blt)
[194.724591] Starting subtest: fbcpsr-2p-scndscrn-shrfb-pgflip-blt
[194.725992] Subtest fbcpsr-2p-scndscrn-shrfb-pgflip-blt: SKIP (0.000s)
[194.805646] [057/155] (817s left) kms_ccs (crc-primary-suspend-4-tiled-lnl-ccs)
[194.893020] Starting subtest: crc-primary-suspend-4-tiled-lnl-ccs
[194.894452] Subtest crc-primary-suspend-4-tiled-lnl-ccs: SKIP (0.000s)
[195.066262] [058/155] (817s left) gem_ctx_shared (exec-single-timeline)
[195.110477] Starting subtest: exec-single-timeline
[195.111215] Starting dynamic subtest: rcs0
[196.133195] Dynamic subtest rcs0: SUCCESS (1.014s)
[196.141582] Starting dynamic subtest: bcs0
[197.156900] Dynamic subtest bcs0: SUCCESS (1.023s)
[197.165740] Starting dynamic subtest: vcs0
[198.181146] Dynamic subtest vcs0: SUCCESS (1.023s)
[198.190840] Starting dynamic subtest: vcs1
[199.204898] Dynamic subtest vcs1: SUCCESS (1.023s)
[199.213482] Starting dynamic subtest: vecs0
[200.229265] Dynamic subtest vecs0: SUCCESS (1.023s)
[200.230316] Subtest exec-single-timeline: SUCCESS (5.111s)
[200.405973] [059/155] (811s left) gem_eio (suspend)
[200.449860] Starting subtest: suspend
[211.617894] Subtest suspend: SUCCESS (11.169s)
[221.798035] [060/155] (800s left) kms_cursor_legacy (torture-move)
[221.899652] Starting subtest: torture-move
[221.900372] Starting dynamic subtest: pipe-A
[227.408130] Dynamic subtest pipe-A: SUCCESS (5.474s)
[227.420142] Starting dynamic subtest: pipe-B
[232.891131] Dynamic subtest pipe-B: SUCCESS (5.482s)
[232.903142] Starting dynamic subtest: pipe-C
[238.343033] Dynamic subtest pipe-C: SUCCESS (5.478s)
[238.354661] Starting dynamic subtest: pipe-D
[243.842875] Dynamic subtest pipe-D: SUCCESS (5.472s)
[243.857823] Starting dynamic subtest: all-pipes
[249.333713] Dynamic subtest all-pipes: SUCCESS (5.490s)
[249.334408] Subtest torture-move: SUCCESS (27.400s)
[249.388629] [061/155] (773s left) gem_mmap_gtt (basic-write)
[249.438607] Starting subtest: basic-write
[249.569455] Subtest basic-write: SUCCESS (0.122s)
[249.644478] [062/155] (772s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-spr-indfb-onoff)
[249.740316] Starting subtest: psrhdr-2p-scndscrn-spr-indfb-onoff
[249.741792] Subtest psrhdr-2p-scndscrn-spr-indfb-onoff: SKIP (0.000s)
[249.838930] [063/155] (772s left) kms_cursor_crc (cursor-sliding-256x256)
[249.931328] Starting subtest: cursor-sliding-256x256
[249.935719] Starting dynamic subtest: pipe-A-HDMI-A-1
[251.765637] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (1.830s)
[251.803495] Starting dynamic subtest: pipe-D-HDMI-A-1
[253.608214] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (1.815s)
[253.608989] Subtest cursor-sliding-256x256: SUCCESS (3.677s)
[253.772165] [064/155] (768s left) kms_pm_rpm (modeset-lpsp)
[255.183368] Starting subtest: modeset-lpsp
[256.516191] Subtest modeset-lpsp: SUCCESS (1.330s)
[256.630545] [065/155] (765s left) kms_lease (lease-again)
[256.719971] Starting subtest: lease-again
[256.722095] Starting dynamic subtest: pipe-A-HDMI-A-1
[256.723834] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.002s)
[256.732684] Starting dynamic subtest: pipe-B-HDMI-A-1
[256.733390] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.001s)
[256.742164] Starting dynamic subtest: pipe-C-HDMI-A-1
[256.742871] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.001s)
[256.751473] Starting dynamic subtest: pipe-D-HDMI-A-1
[256.752178] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (0.001s)
[256.752877] Subtest lease-again: SUCCESS (0.011s)
[256.831229] [066/155] (765s left) kms_getfb (getfb-repeated-different-handles)
[256.921205] Starting subtest: getfb-repeated-different-handles
[256.921981] Subtest getfb-repeated-different-handles: SUCCESS (0.000s)
[257.042174] [067/155] (765s left) gem_mmap_wc (fault-concurrent)
[257.077168] Starting subtest: fault-concurrent
[257.153752] Subtest fault-concurrent: SUCCESS (0.077s)
[257.226019] [068/155] (765s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-16bpp-4tile-upscaling)
[257.317555] Starting subtest: flip-64bpp-4tile-to-16bpp-4tile-upscaling
[257.319052] Subtest flip-64bpp-4tile-to-16bpp-4tile-upscaling: SKIP (0.000s)
[257.388997] [069/155] (765s left) gen7_exec_parse (basic-rejected)
[257.427067] Subtest basic-rejected: SKIP (0.000s)
[257.480142] [070/155] (765s left) perf_pmu (invalid-open)
[257.516553] Starting subtest: invalid-open
[257.517277] Subtest invalid-open: SUCCESS (0.000s)
[257.580838] [071/155] (765s left) kms_panel_fitting (legacy)
[257.672303] Starting subtest: legacy
[257.673768] Subtest legacy: SKIP (0.000s)
[257.728848] [072/155] (765s left) gem_userptr_blits (create-destroy-unsync)
[257.768729] Subtest create-destroy-unsync: SKIP (0.000s)
[257.904110] [073/155] (764s left) kms_rotation_crc (primary-4-tiled-reflect-x-180)
[257.990868] Starting subtest: primary-4-tiled-reflect-x-180
[258.043495] Subtest primary-4-tiled-reflect-x-180: SKIP (0.051s)
[258.133862] [074/155] (764s left) kms_psr (fbc-psr2-sprite-render)
[258.222533] Subtest fbc-psr2-sprite-render: SKIP (0.000s)
[258.266796] [075/155] (764s left) kms_cursor_crc (cursor-sliding-32x10)
[258.358776] Starting subtest: cursor-sliding-32x10
[258.411239] Subtest cursor-sliding-32x10: SKIP (0.052s)
[258.511172] [076/155] (764s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0-hflip)
[258.610492] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0-hflip
[258.611923] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0-hflip: SKIP (0.000s)
[258.695146] [077/155] (764s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[258.789323] Starting subtest: fbchdr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[258.790706] Subtest fbchdr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[258.891266] [078/155] (764s left) kms_frontbuffer_tracking (hdr-2p-primscrn-indfb-plflip-blt)
[258.984289] Starting subtest: hdr-2p-primscrn-indfb-plflip-blt
[258.985683] Subtest hdr-2p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[259.081494] [079/155] (764s left) kms_addfb_basic (small-bo)
[259.171732] Starting subtest: small-bo
[259.172407] Subtest small-bo: SUCCESS (0.000s)
[259.269969] [080/155] (763s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-pri-indfb-draw-mmap-gtt)
[259.366291] Starting subtest: fbcpsrhdr-2p-primscrn-pri-indfb-draw-mmap-gtt
[259.367639] Subtest fbcpsrhdr-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[259.461327] [081/155] (763s left) kms_flip (bo-too-big-interruptible)
[259.554079] Starting subtest: bo-too-big-interruptible
[259.556850] Starting dynamic subtest: A-HDMI-A1
[259.884230] Dynamic subtest A-HDMI-A1: SUCCESS (0.327s)
[259.899767] Starting dynamic subtest: B-HDMI-A1
[260.180012] Dynamic subtest B-HDMI-A1: SUCCESS (0.293s)
[260.191460] Starting dynamic subtest: C-HDMI-A1
[260.476144] Dynamic subtest C-HDMI-A1: SUCCESS (0.290s)
[260.485939] Starting dynamic subtest: D-HDMI-A1
[260.786450] Dynamic subtest D-HDMI-A1: SUCCESS (0.309s)
[260.787212] Subtest bo-too-big-interruptible: SUCCESS (1.231s)
[260.972799] [082/155] (762s left) kms_big_fb (x-tiled-32bpp-rotate-90)
[261.074094] Starting subtest: x-tiled-32bpp-rotate-90
[261.157498] Subtest x-tiled-32bpp-rotate-90: SKIP (0.077s)
[261.288906] [083/155] (762s left) kms_frontbuffer_tracking (fbc-indfb-scaledprimary)
[261.383231] Starting subtest: fbc-indfb-scaledprimary
[262.016932] Subtest fbc-indfb-scaledprimary: SUCCESS (0.633s)
[262.150316] [084/155] (761s left) gem_exec_balancer (invalid-bonds)
[262.193211] Starting subtest: invalid-bonds
[262.196086] Subtest invalid-bonds: SUCCESS (0.003s)
[262.388483] [085/155] (761s left) kms_big_fb (y-tiled-64bpp-rotate-270)
[262.490508] Starting subtest: y-tiled-64bpp-rotate-270
[262.534285] Subtest y-tiled-64bpp-rotate-270: SKIP (0.042s)
[262.655934] [086/155] (760s left) gem_exec_flush (basic-uc-pro-default)
[262.699987] Starting subtest: basic-uc-pro-default
[268.133126] Subtest basic-uc-pro-default: SUCCESS (5.432s)
[268.349528] [087/155] (755s left) gem_exec_reloc (basic-wc-read-active)
[268.387765] Starting subtest: basic-wc-read-active
[268.521135] Subtest basic-wc-read-active: SUCCESS (0.124s)
[268.650316] [088/155] (754s left) kms_frontbuffer_tracking (hdr-2p-primscrn-indfb-msflip-blt)
[268.745593] Starting subtest: hdr-2p-primscrn-indfb-msflip-blt
[268.747006] Subtest hdr-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[268.851905] [089/155] (754s left) gem_spin_batch (resubmit-all)
[268.894917] Starting subtest: resubmit-all
[268.895729] Starting dynamic subtest: rcs0
[268.898295] Dynamic subtest rcs0: SUCCESS (0.004s)
[268.909905] Starting dynamic subtest: bcs0
[268.910643] Dynamic subtest bcs0: SUCCESS (0.002s)
[268.919646] Starting dynamic subtest: vcs0
[268.920350] Dynamic subtest vcs0: SUCCESS (0.002s)
[268.928674] Starting dynamic subtest: vcs1
[268.929385] Dynamic subtest vcs1: SUCCESS (0.003s)
[268.937731] Starting dynamic subtest: vecs0
[268.938435] Dynamic subtest vecs0: SUCCESS (0.002s)
[268.939127] Subtest resubmit-all: SUCCESS (0.016s)
[269.093647] [090/155] (754s left) gem_madvise (dontneed-after-mmap)
[269.126162] Starting subtest: dontneed-after-mmap
[269.131937] Subtest dontneed-after-mmap: SUCCESS (0.006s)
[269.234251] [091/155] (754s left) kms_frontbuffer_tracking (fbchdr-1p-pri-indfb-multidraw)
[269.327472] Starting subtest: fbchdr-1p-pri-indfb-multidraw
[269.328887] Subtest fbchdr-1p-pri-indfb-multidraw: SKIP (0.000s)
[269.425481] [092/155] (754s left) kms_plane_scaling (plane-upscale-factor-0-25-with-modifiers)
[269.515030] Starting subtest: plane-upscale-factor-0-25-with-modifiers
[269.515835] Starting dynamic subtest: pipe-A
[269.884080] Dynamic subtest pipe-A: SUCCESS (0.369s)
[269.895978] Starting dynamic subtest: pipe-B
[270.334976] Dynamic subtest pipe-B: SUCCESS (0.450s)
[270.344377] Starting dynamic subtest: pipe-C
[270.776766] Dynamic subtest pipe-C: SUCCESS (0.441s)
[270.789609] Starting dynamic subtest: pipe-D
[271.218341] Dynamic subtest pipe-D: SUCCESS (0.441s)
[271.219077] Subtest plane-upscale-factor-0-25-with-modifiers: SUCCESS (1.704s)
[271.404037] [093/155] (752s left) gem_softpin (evict-snoop)
[271.448187] Starting subtest: evict-snoop
[271.449640] Subtest evict-snoop: SKIP (0.000s)
[271.509852] [094/155] (752s left) gem_create (create-massive)
[271.542959] Starting subtest: create-massive
[271.543731] Subtest create-massive: SUCCESS (0.000s)
[271.730826] [095/155] (752s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-move)
[271.827273] Starting subtest: psr-2p-primscrn-spr-indfb-move
[271.828690] Subtest psr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[271.938122] [096/155] (752s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-pri-indfb-draw-render)
[272.031797] Starting subtest: fbchdr-1p-primscrn-pri-indfb-draw-render
[272.033213] Subtest fbchdr-1p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[272.137832] [097/155] (751s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-pwrite)
[272.230488] Starting subtest: psr-1p-primscrn-spr-indfb-draw-pwrite
[272.231984] Subtest psr-1p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[272.325510] [098/155] (751s left) kms_frontbuffer_tracking (fbcpsrhdr-slowdraw)
[272.419807] Starting subtest: fbcpsrhdr-slowdraw
[272.421353] Subtest fbcpsrhdr-slowdraw: SKIP (0.000s)
[272.519378] [099/155] (751s left) gem_ctx_persistence (engines-cleanup)
[272.557763] Starting subtest: engines-cleanup
[272.558552] Starting dynamic subtest: rcs0
[272.596584] Dynamic subtest rcs0: SUCCESS (0.038s)
[272.617401] Starting dynamic subtest: bcs0
[272.640417] Dynamic subtest bcs0: SUCCESS (0.042s)
[272.660079] Starting dynamic subtest: vcs0
[272.678452] Dynamic subtest vcs0: SUCCESS (0.036s)
[272.695914] Starting dynamic subtest: vcs1
[272.719421] Dynamic subtest vcs1: SUCCESS (0.039s)
[272.740769] Starting dynamic subtest: vecs0
[272.765500] Dynamic subtest vecs0: SUCCESS (0.044s)
[272.766579] Subtest engines-cleanup: SUCCESS (0.209s)
[272.843887] [100/155] (751s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-pri-shrfb-draw-render)
[272.938575] Starting subtest: hdr-2p-scndscrn-pri-shrfb-draw-render
[272.940009] Subtest hdr-2p-scndscrn-pri-shrfb-draw-render: SKIP (0.000s)
[273.025597] [101/155] (751s left) kms_atomic_transition (modeset-transition-nonblocking)
[273.115078] Starting subtest: modeset-transition-nonblocking
[273.115792] Starting dynamic subtest: 1x-outputs
[274.380124] Dynamic subtest 1x-outputs: SUCCESS (1.265s)
[274.380915] Subtest modeset-transition-nonblocking: SUCCESS (1.266s)
[274.526612] [102/155] (749s left) kms_color_pipeline (plane-lut1d-pre-ctm3x4)
[274.622059] Starting subtest: plane-lut1d-pre-ctm3x4
[274.708291] Starting dynamic subtest: pipe-A-plane-0
[274.807619] Dynamic subtest pipe-A-plane-0: SUCCESS (0.099s)
[274.824585] Starting dynamic subtest: pipe-A-plane-1
[274.899247] Dynamic subtest pipe-A-plane-1: SUCCESS (0.090s)
[274.914517] Starting dynamic subtest: pipe-A-plane-2
[274.998886] Dynamic subtest pipe-A-plane-2: SUCCESS (0.096s)
[275.222597] Starting dynamic subtest: pipe-B-plane-0
[275.306298] Dynamic subtest pipe-B-plane-0: SUCCESS (0.092s)
[275.322210] Starting dynamic subtest: pipe-B-plane-1
[275.406220] Dynamic subtest pipe-B-plane-1: SUCCESS (0.098s)
[275.422214] Starting dynamic subtest: pipe-B-plane-2
[275.497724] Dynamic subtest pipe-B-plane-2: SUCCESS (0.089s)
[275.726829] Starting dynamic subtest: pipe-C-plane-0
[275.809199] Dynamic subtest pipe-C-plane-0: SUCCESS (0.092s)
[275.824863] Starting dynamic subtest: pipe-C-plane-1
[275.907795] Dynamic subtest pipe-C-plane-1: SUCCESS (0.097s)
[275.923376] Starting dynamic subtest: pipe-C-plane-2
[276.007990] Dynamic subtest pipe-C-plane-2: SUCCESS (0.097s)
[276.225418] Starting dynamic subtest: pipe-D-plane-0
[276.300223] Dynamic subtest pipe-D-plane-0: SUCCESS (0.083s)
[276.315632] Starting dynamic subtest: pipe-D-plane-1
[276.392650] Dynamic subtest pipe-D-plane-1: SUCCESS (0.089s)
[276.406860] Starting dynamic subtest: pipe-D-plane-2
[276.491868] Dynamic subtest pipe-D-plane-2: SUCCESS (0.098s)
[276.541268] Subtest plane-lut1d-pre-ctm3x4: SUCCESS (1.919s)
[276.663944] [103/155] (747s left) kms_frontbuffer_tracking (fbcpsr-2p-rte)
[276.763565] Starting subtest: fbcpsr-2p-rte
[276.764987] Subtest fbcpsr-2p-rte: SKIP (0.000s)
[276.862928] [104/155] (747s left) kms_frontbuffer_tracking (psrhdr-rgb565-draw-pwrite)
[276.959742] Starting subtest: psrhdr-rgb565-draw-pwrite
[276.961214] Subtest psrhdr-rgb565-draw-pwrite: SKIP (0.000s)
[277.073804] [105/155] (747s left) kms_plane_multiple (2x-tiling-x)
[277.164780] Starting subtest: 2x-tiling-x
[277.166279] Subtest 2x-tiling-x: SKIP (0.000s)
[277.232220] [106/155] (747s left) i915_drm_fdinfo (busy-idle-check-all)
[277.270114] Starting subtest: busy-idle-check-all
[277.270906] Starting dynamic subtest: rcs0
[277.890750] Dynamic subtest rcs0: SUCCESS (0.619s)
[277.905896] Starting dynamic subtest: bcs0
[278.523261] Dynamic subtest bcs0: SUCCESS (0.630s)
[278.538128] Starting dynamic subtest: vcs0
[279.152234] Dynamic subtest vcs0: SUCCESS (0.624s)
[279.168106] Starting dynamic subtest: vcs1
[279.781978] Dynamic subtest vcs1: SUCCESS (0.628s)
[279.796020] Starting dynamic subtest: vecs0
[280.409123] Dynamic subtest vecs0: SUCCESS (0.625s)
[280.413218] Subtest busy-idle-check-all: SUCCESS (3.140s)
[280.482787] [107/155] (743s left) sw_sync (sync_random_merge)
[280.515085] Starting subtest: sync_random_merge
[280.579311] Subtest sync_random_merge: SUCCESS (0.064s)
[280.633122] [108/155] (743s left) kms_sequence (get-forked-busy)
[280.724947] Starting subtest: get-forked-busy
[280.726815] Starting dynamic subtest: pipe-A-HDMI-A-1
[282.063432] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (1.337s)
[282.077055] Starting dynamic subtest: pipe-B-HDMI-A-1
[283.369901] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (1.304s)
[283.380665] Starting dynamic subtest: pipe-C-HDMI-A-1
[284.661824] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (1.290s)
[284.673646] Starting dynamic subtest: pipe-D-HDMI-A-1
[285.980159] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (1.314s)
[285.981024] Subtest get-forked-busy: SUCCESS (5.255s)
[286.119112] [109/155] (738s left) gem_exec_params (readonly)
[286.159099] Starting subtest: readonly
[286.160964] Subtest readonly: SUCCESS (0.001s)
[286.263520] [110/155] (738s left) gem_softpin (evict-active-interruptible)
[286.303662] Starting subtest: evict-active-interruptible
[286.307763] Subtest evict-active-interruptible: SUCCESS (0.004s)
[286.422754] [111/155] (738s left) kms_plane_scaling (plane-scaler-unity-scaling-with-modifiers)
[286.514012] Starting subtest: plane-scaler-unity-scaling-with-modifiers
[286.514744] Starting dynamic subtest: pipe-A
[286.905152] Dynamic subtest pipe-A: SUCCESS (0.391s)
[286.915795] Starting dynamic subtest: pipe-B
[287.346853] Dynamic subtest pipe-B: SUCCESS (0.441s)
[287.359274] Starting dynamic subtest: pipe-C
[287.790380] Dynamic subtest pipe-C: SUCCESS (0.442s)
[287.803786] Starting dynamic subtest: pipe-D
[288.234428] Dynamic subtest pipe-D: SUCCESS (0.443s)
[288.235119] Subtest plane-scaler-unity-scaling-with-modifiers: SUCCESS (1.722s)
[288.424980] [112/155] (736s left) syncobj_wait (invalid-multi-wait-unsubmitted-submitted)
[288.461084] Starting subtest: invalid-multi-wait-unsubmitted-submitted
[288.461837] Subtest invalid-multi-wait-unsubmitted-submitted: SUCCESS (0.000s)
[288.529112] [113/155] (736s left) kms_chamelium_hpd (dp-hpd-storm-disable)
[288.678885] Subtest dp-hpd-storm-disable: SKIP (0.000s)
[288.781515] [114/155] (735s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-wc)
[288.878434] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-wc
[288.879824] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[288.985105] [115/155] (735s left) i915_pm_rc6_residency (rc6-idle)
[289.019079] Starting subtest: rc6-idle
[289.022951] Subtest rc6-idle: SKIP (0.004s)
[289.092743] [116/155] (735s left) gem_mmap_offset (partial-mmap)
[289.125821] Starting subtest: partial-mmap
[289.126548] Starting dynamic subtest: smem0
[289.128907] Dynamic subtest smem0: SUCCESS (0.003s)
[289.129597] Subtest partial-mmap: SUCCESS (0.004s)
[289.232099] [117/155] (735s left) kms_ccs (crc-sprite-planes-basic-4-tiled-bmg-ccs)
[289.321229] Starting subtest: crc-sprite-planes-basic-4-tiled-bmg-ccs
[289.322681] Subtest crc-sprite-planes-basic-4-tiled-bmg-ccs: SKIP (0.000s)
[289.488565] [118/155] (735s left) kms_psr2_sf (fbc-psr2-cursor-plane-move-continuous-exceed-sf)
[289.584980] Subtest fbc-psr2-cursor-plane-move-continuous-exceed-sf: SKIP (0.000s)
[289.640970] [119/155] (735s left) gem_wait (write-busy)
[289.688716] Starting subtest: write-busy
[289.689480] Starting dynamic subtest: all-engines
[290.230154] Dynamic subtest all-engines: SUCCESS (0.541s)
[290.241701] Starting dynamic subtest: rcs0
[290.759730] Dynamic subtest rcs0: SUCCESS (0.528s)
[290.769995] Starting dynamic subtest: bcs0
[291.281965] Dynamic subtest bcs0: SUCCESS (0.521s)
[291.295321] Starting dynamic subtest: vcs0
[291.804916] Dynamic subtest vcs0: SUCCESS (0.522s)
[291.816056] Starting dynamic subtest: vcs1
[292.323276] Dynamic subtest vcs1: SUCCESS (0.517s)
[292.333361] Starting dynamic subtest: vecs0
[292.838472] Dynamic subtest vecs0: SUCCESS (0.514s)
[292.839296] Subtest write-busy: SUCCESS (3.150s)
[293.027626] [120/155] (731s left) kms_cursor_crc (cursor-onscreen-max-size)
[293.127876] Starting subtest: cursor-onscreen-max-size
[293.130110] Subtest cursor-onscreen-max-size: SKIP (0.000s)
[293.208307] [121/155] (731s left) syncobj_timeline (reset-unsignaled)
[293.243345] Starting subtest: reset-unsignaled
[293.244063] Subtest reset-unsignaled: SUCCESS (0.000s)
[293.305978] [122/155] (731s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[293.404575] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[293.406008] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SKIP (0.000s)
[293.516106] [123/155] (731s left) api_intel_allocator (gem-pool)
[293.550140] Starting subtest: gem-pool
[293.554333] Subtest gem-pool: SUCCESS (0.005s)
[293.675632] [124/155] (731s left) gem_mmap_gtt (big-copy-xy)
[293.709912] Starting subtest: big-copy-XY
[304.136937] Subtest big-copy-XY: SUCCESS (10.427s)
[304.214213] [125/155] (720s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-gtt)
[304.356392] Starting subtest: psr-rgb565-draw-mmap-gtt
[304.357796] Subtest psr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[304.468669] [126/155] (720s left) kms_psr2_sf (pr-plane-move-sf-dmg-area)
[304.559477] Subtest pr-plane-move-sf-dmg-area: SKIP (0.000s)
[304.601987] [127/155] (720s left) kms_big_fb (4-tiled-8bpp-rotate-0)
[304.706119] Starting subtest: 4-tiled-8bpp-rotate-0
[304.707534] Subtest 4-tiled-8bpp-rotate-0: SKIP (0.000s)
[304.797017] [128/155] (720s left) gem_fence_thrash (bo-write-verify-none)
[304.830050] Starting subtest: bo-write-verify-none
[306.027751] Subtest bo-write-verify-none: SUCCESS (1.190s)
[306.103290] [129/155] (719s left) kms_flip (wf_vblank-ts-check-interruptible)
[306.202302] Starting subtest: wf_vblank-ts-check-interruptible
[306.205427] Starting dynamic subtest: A-HDMI-A1
[314.039726] Dynamic subtest A-HDMI-A1: SUCCESS (7.834s)
[314.059811] Starting dynamic subtest: B-HDMI-A1
[321.835680] Dynamic subtest B-HDMI-A1: SUCCESS (7.791s)
[321.848358] Starting dynamic subtest: C-HDMI-A1
[329.628973] Dynamic subtest C-HDMI-A1: SUCCESS (7.790s)
[329.642889] Starting dynamic subtest: D-HDMI-A1
[337.421030] Dynamic subtest D-HDMI-A1: SUCCESS (7.788s)
[337.421786] Subtest wf_vblank-ts-check-interruptible: SUCCESS (31.218s)
[337.608349] [130/155] (687s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-spr-indfb-draw-render)
[337.707148] Starting subtest: psrhdr-2p-primscrn-spr-indfb-draw-render
[337.708618] Subtest psrhdr-2p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[337.797102] [131/155] (687s left) kms_chamelium_frames (dp-crc-multiple)
[337.939176] Subtest dp-crc-multiple: SKIP (0.000s)
[338.046584] [132/155] (687s left) gem_exec_whisper (basic-queues-priority)
[338.096614] Starting subtest: basic-queues-priority
[340.358657] Subtest basic-queues-priority: SUCCESS (2.253s)
[340.532496] [133/155] (684s left) gem_render_copy_redux (interruptible)
[340.571716] Starting subtest: interruptible
[340.914614] Subtest interruptible: SUCCESS (0.334s)
[341.030800] [134/155] (684s left) i915_hangman (gt-engine-hang)
[341.076787] Starting subtest: gt-engine-hang
[341.077599] Starting dynamic subtest: rcs0
[341.645692] Dynamic subtest rcs0: SUCCESS (0.569s)
[341.659734] Starting dynamic subtest: bcs0
[342.226794] Dynamic subtest bcs0: SUCCESS (0.578s)
[342.242913] Starting dynamic subtest: vcs0
[342.808109] Dynamic subtest vcs0: SUCCESS (0.579s)
[342.819989] Starting dynamic subtest: vcs1
[343.387614] Dynamic subtest vcs1: SUCCESS (0.578s)
[343.403719] Starting dynamic subtest: vecs0
[343.966644] Dynamic subtest vecs0: SUCCESS (0.577s)
[343.969896] Subtest gt-engine-hang: SUCCESS (2.892s)
[344.097967] [135/155] (681s left) kms_flip (2x-flip-vs-expired-vblank-interruptible)
[344.198016] Starting subtest: 2x-flip-vs-expired-vblank-interruptible
[344.207826] Subtest 2x-flip-vs-expired-vblank-interruptible: SKIP (0.009s)
[344.270839] [136/155] (681s left) perf (rc6-disable)
[344.363363] Starting subtest: rc6-disable
[344.543093] Subtest rc6-disable: SUCCESS (0.179s)
[344.603670] [137/155] (681s left) kms_frontbuffer_tracking (hdr-1p-primscrn-pri-shrfb-draw-mmap-cpu)
[344.697712] Starting subtest: hdr-1p-primscrn-pri-shrfb-draw-mmap-cpu
[344.699132] Subtest hdr-1p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[344.811661] [138/155] (680s left) kms_content_protection (legacy-hdcp14)
[344.905538] Starting subtest: legacy-hdcp14
[344.911169] Subtest legacy-hdcp14: SKIP (0.004s)
[344.998531] [139/155] (680s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-render)
[345.091303] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-render
[345.092738] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[345.193132] [140/155] (680s left) kms_addfb_basic (unused-offsets)
[345.229020] Starting subtest: unused-offsets
[345.229737] Subtest unused-offsets: SUCCESS (0.000s)
[345.402259] [141/155] (680s left) kms_ccs (bad-rotation-90-y-tiled-gen12-mc-ccs)
[345.494084] Starting subtest: bad-rotation-90-y-tiled-gen12-mc-ccs
[345.494799] Starting dynamic subtest: pipe-A-HDMI-A-1
[345.501417] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.000s)
[345.511385] Starting dynamic subtest: pipe-B-HDMI-A-1
[345.518029] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.000s)
[345.527448] Starting dynamic subtest: pipe-C-HDMI-A-1
[345.534075] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.000s)
[345.543537] Starting dynamic subtest: pipe-D-HDMI-A-1
[345.550567] Dynamic subtest pipe-D-HDMI-A-1: SKIP (0.000s)
[345.552039] Subtest bad-rotation-90-y-tiled-gen12-mc-ccs: SKIP (0.007s)
[345.757313] [142/155] (680s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-shrfb-draw-mmap-wc)
[345.853116] Starting subtest: psrhdr-1p-primscrn-pri-shrfb-draw-mmap-wc
[345.854629] Subtest psrhdr-1p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[345.949109] [143/155] (680s left) i915_selftest (perf)
[346.954674] Starting subtest: perf
[346.955511] Starting dynamic subtest: engine_cs
[348.296887] Dynamic subtest engine_cs: SUCCESS (1.333s)
[348.309466] Starting dynamic subtest: request
[352.636879] Dynamic subtest request: SUCCESS (4.338s)
[352.649591] Starting dynamic subtest: migrate
[354.011767] Dynamic subtest migrate: SUCCESS (1.373s)
[354.020804] Starting dynamic subtest: region
[356.295769] Dynamic subtest region: SUCCESS (2.282s)
[356.296617] Subtest perf: SUCCESS (9.334s)
[356.327907] [FACT i915_selftest (perf)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[356.328702] [FACT i915_selftest (perf)] deleted: kernel.kmod_is_loaded.i915: true
[356.332106] [144/155] (669s left) gem_ppgtt (shrink-vs-evict-pinned)
[357.381483] Starting subtest: shrink-vs-evict-pinned
[362.497231] Subtest shrink-vs-evict-pinned: SUCCESS (5.082s)
[362.554601] [FACT gem_ppgtt (shrink-vs-evict-pinned)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[362.555948] [FACT gem_ppgtt (shrink-vs-evict-pinned)] new: kernel.kmod_is_loaded.i915: true
[362.559006] [145/155] (663s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-blt)
[362.657479] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-blt
[362.658880] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[362.752231] [146/155] (663s left) kms_joiner (basic-big-joiner)
[362.846120] Starting subtest: basic-big-joiner
[362.847582] Subtest basic-big-joiner: SKIP (0.000s)
[362.908883] [147/155] (663s left) kms_cursor_legacy (cursorb-vs-flipb-atomic-transitions)
[363.000146] Starting subtest: cursorB-vs-flipB-atomic-transitions
[363.002711] Subtest cursorB-vs-flipB-atomic-transitions: SKIP (0.001s)
[363.060065] [148/155] (663s left) kms_frontbuffer_tracking (fbcpsr-2p-indfb-fliptrack-mmap-gtt)
[363.154570] Starting subtest: fbcpsr-2p-indfb-fliptrack-mmap-gtt
[363.155998] Subtest fbcpsr-2p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[363.243293] [149/155] (662s left) gem_mmap_gtt (cpuset-basic-small-copy-odd)
[363.277038] Starting subtest: cpuset-basic-small-copy-odd
[366.805758] Subtest cpuset-basic-small-copy-odd: SUCCESS (3.528s)
[366.883091] [150/155] (659s left) kms_frontbuffer_tracking (fbcpsrhdr-rgb101010-draw-mmap-gtt)
[367.029179] Starting subtest: fbcpsrhdr-rgb101010-draw-mmap-gtt
[367.030674] Subtest fbcpsrhdr-rgb101010-draw-mmap-gtt: SKIP (0.000s)
[367.104616] [151/155] (659s left) kms_chamelium_color (ctm-max)
[367.196771] Subtest ctm-max: SKIP (0.000s)
[367.240273] [152/155] (659s left) kms_pm_rpm (system-suspend-idle)
[378.695270] Starting subtest: system-suspend-idle
[398.631964] Subtest system-suspend-idle: SUCCESS (4.950s)
[408.873650] [153/155] (627s left) kms_psr (fbc-pr-primary-blt)
[408.966215] Subtest fbc-pr-primary-blt: SKIP (0.000s)
[409.012556] [154/155] (627s left) gem_userptr_blits (forbidden-operations)
[409.055046] Starting subtest: forbidden-operations
[409.055776] Subtest forbidden-operations: SUCCESS (0.001s)
[409.189577] [155/155] (627s left) syncobj_timeline (wait-zero-handles)
[409.231858] Starting subtest: wait-zero-handles
[409.232732] Subtest wait-zero-handles: SUCCESS (0.000s)
[409.311338] 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.