[60.249150] Initializing watchdogs
[60.249199]   /dev/watchdog0
[60.264573] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics]
[60.269119] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[60.271490] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[60.274843] [001/154] (960s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-blt)
[60.411754] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-blt
[60.416368] Subtest fbc-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s)
[60.515858] [002/154] (960s left) gem_basic (multigpu-create-close)
[60.567758] Starting subtest: multigpu-create-close
[60.572304] Subtest multigpu-create-close: SKIP (0.000s)
[60.687594] [003/154] (960s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-wc)
[60.786241] Starting subtest: fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-wc
[60.790106] Subtest fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[60.894980] [004/154] (959s left) gem_media_fill (media-fill)
[60.952591] Subtest media-fill: SKIP (0.000s)
[61.074164] [005/154] (959s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-gtt)
[61.167424] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-gtt
[61.170957] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[61.267969] [006/154] (959s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-pwrite)
[61.364067] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-pwrite
[61.367784] Subtest psr-2p-scndscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[61.465744] [007/154] (959s left) kms_ccs (crc-primary-suspend-4-tiled-mtl-rc-ccs)
[61.549019] Starting subtest: crc-primary-suspend-4-tiled-mtl-rc-ccs
[61.550636] Starting dynamic subtest: pipe-A-eDP-1
[78.191249] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.331s)
[78.200522] Starting dynamic subtest: pipe-B-eDP-1
[95.180928] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.474s)
[95.191715] Starting dynamic subtest: pipe-C-eDP-1
[112.115838] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.406s)
[112.126300] Starting dynamic subtest: pipe-D-eDP-1
[129.120185] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.377s)
[129.123059] Subtest crc-primary-suspend-4-tiled-mtl-rc-ccs: SUCCESS (9.590s)
[130.340833] [008/154] (890s left) i915_query (query-regions-unallocated)
[130.423542] Starting subtest: query-regions-unallocated
[130.425838] Subtest query-regions-unallocated: SUCCESS (0.000s)
[130.538793] [009/154] (890s left) syncobj_basic (bad-destroy-pad)
[130.736519] Starting subtest: bad-destroy-pad
[130.738961] Subtest bad-destroy-pad: SUCCESS (0.000s)
[130.997465] [010/154] (890s left) gem_exec_reloc (basic-gtt-active)
[131.152594] Subtest basic-gtt-active: SKIP (0.000s)
[131.309704] [011/154] (889s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-pri-shrfb-draw-mmap-wc)
[131.493216] Starting subtest: fbchdr-2p-primscrn-pri-shrfb-draw-mmap-wc
[131.497907] Subtest fbchdr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[131.674553] [012/154] (889s left) kms_chamelium_audio (dp-audio-edid)
[132.091831] Subtest dp-audio-edid: SKIP (0.000s)
[132.936699] [013/154] (888s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[133.045441] Starting subtest: fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[133.050086] Subtest fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[133.154383] [014/154] (888s left) kms_pm_rpm (modeset-pc8-residency-stress)
[134.701329] Starting subtest: modeset-pc8-residency-stress
[134.706544] Subtest modeset-pc8-residency-stress: SKIP (0.000s)
[135.479448] [015/154] (885s left) kms_vrr (flip-basic)
[135.579231] Starting subtest: flip-basic
[135.585714] Subtest flip-basic: SKIP (0.000s)
[135.664958] [016/154] (885s left) kms_color_pipeline (plane-ctm3x4-lut1d)
[135.764407] Starting subtest: plane-ctm3x4-lut1d
[135.932342] Starting dynamic subtest: pipe-A-plane-0
[136.108939] Dynamic subtest pipe-A-plane-0: SUCCESS (0.177s)
[136.119260] Starting dynamic subtest: pipe-A-plane-1
[136.296856] Dynamic subtest pipe-A-plane-1: SUCCESS (0.189s)
[136.307272] Starting dynamic subtest: pipe-A-plane-2
[136.484359] Dynamic subtest pipe-A-plane-2: SUCCESS (0.189s)
[137.669671] Starting dynamic subtest: pipe-B-plane-0
[137.859619] Dynamic subtest pipe-B-plane-0: SUCCESS (0.200s)
[137.870197] Starting dynamic subtest: pipe-B-plane-1
[138.025152] Dynamic subtest pipe-B-plane-1: SUCCESS (0.166s)
[138.037122] Starting dynamic subtest: pipe-B-plane-2
[138.213121] Dynamic subtest pipe-B-plane-2: SUCCESS (0.189s)
[139.420307] Starting dynamic subtest: pipe-C-plane-0
[139.555765] Dynamic subtest pipe-C-plane-0: SUCCESS (0.144s)
[139.566199] Starting dynamic subtest: pipe-C-plane-1
[139.710676] Dynamic subtest pipe-C-plane-1: SUCCESS (0.155s)
[139.721124] Starting dynamic subtest: pipe-C-plane-2
[139.878762] Dynamic subtest pipe-C-plane-2: SUCCESS (0.166s)
[141.112669] Starting dynamic subtest: pipe-D-plane-0
[141.246336] Dynamic subtest pipe-D-plane-0: SUCCESS (0.144s)
[141.257578] Starting dynamic subtest: pipe-D-plane-1
[141.390388] Dynamic subtest pipe-D-plane-1: SUCCESS (0.144s)
[141.401445] Starting dynamic subtest: pipe-D-plane-2
[141.546759] Dynamic subtest pipe-D-plane-2: SUCCESS (0.155s)
[141.834574] Subtest plane-ctm3x4-lut1d: SUCCESS (6.095s)
[142.700689] [017/154] (878s left) syncobj_timeline (invalid-multi-wait-unsubmitted)
[142.753861] Starting subtest: invalid-multi-wait-unsubmitted
[142.755796] Subtest invalid-multi-wait-unsubmitted: SUCCESS (0.000s)
[142.845798] [018/154] (878s left) i915_pm_freq_api (freq-basic-api)
[142.908936] Starting subtest: freq-basic-api
[142.911303] Starting dynamic subtest: gt0
[142.916209] Dynamic subtest gt0: SUCCESS (0.005s)
[142.925336] Starting dynamic subtest: gt1
[142.927572] Dynamic subtest gt1: SUCCESS (0.004s)
[142.929751] Subtest freq-basic-api: SUCCESS (0.010s)
[143.030412] [019/154] (878s left) prime_mmap (test_forked)
[143.092865] Starting subtest: test_forked
[143.095141] Starting dynamic subtest: test_forked-smem
[143.131750] Dynamic subtest test_forked-smem: SUCCESS (0.040s)
[143.134355] Subtest test_forked: SUCCESS (0.040s)
[143.227802] [020/154] (878s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[143.320183] Starting subtest: compare-crc-sanitycheck-xr24
[143.322549] Starting dynamic subtest: pipe-A-eDP-1
[143.971965] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.654s)
[143.982935] Starting dynamic subtest: pipe-B-eDP-1
[145.282682] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.312s)
[145.293959] Starting dynamic subtest: pipe-C-eDP-1
[146.537157] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.256s)
[146.547797] Starting dynamic subtest: pipe-D-eDP-1
[147.774619] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.238s)
[147.776962] Subtest compare-crc-sanitycheck-xr24: SUCCESS (4.465s)
[148.598071] [021/154] (873s left) kms_big_fb (x-tiled-16bpp-rotate-180)
[148.706664] Starting subtest: x-tiled-16bpp-rotate-180
[150.083936] Subtest x-tiled-16bpp-rotate-180: SUCCESS (1.379s)
[150.214969] [022/154] (871s left) gem_mmap_gtt (fault-concurrent)
[150.268639] Subtest fault-concurrent: SKIP (0.000s)
[150.326609] [023/154] (871s left) syncobj_wait (single-wait-all-signaled)
[150.372592] Starting subtest: single-wait-all-signaled
[150.374486] Subtest single-wait-all-signaled: SUCCESS (0.000s)
[150.463228] [024/154] (871s left) vgem_basic (dmabuf-fence-before)
[150.539560] Starting subtest: dmabuf-fence-before
[150.541263] Subtest dmabuf-fence-before: SUCCESS (0.000s)
[150.593748] [FACT vgem_basic (dmabuf-fence-before)] new: kernel.kmod_is_loaded.vgem: true
[150.596550] [025/154] (871s left) kms_psr (fbc-pr-suspend)
[150.682617] Starting subtest: fbc-pr-suspend
[150.686339] Subtest fbc-pr-suspend: SKIP (0.000s)
[150.756124] [026/154] (871s left) kms_frontbuffer_tracking (fbc-rgb565-draw-blt)
[150.857667] Starting subtest: fbc-rgb565-draw-blt
[155.075921] Subtest fbc-rgb565-draw-blt: SUCCESS (4.227s)
[156.300243] [027/154] (865s left) gem_exec_fence (syncobj-timeline-invalid-wait)
[156.848818] Starting subtest: syncobj-timeline-invalid-wait
[156.851683] Subtest syncobj-timeline-invalid-wait: SUCCESS (0.004s)
[158.411814] [028/154] (863s left) sw_sync (sync_multi_consumer)
[158.451401] Starting subtest: sync_multi_consumer
[158.886051] Subtest sync_multi_consumer: SUCCESS (0.434s)
[158.948867] [029/154] (862s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[159.049071] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[159.863388] Subtest x-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SUCCESS (0.815s)
[160.043001] [030/154] (861s left) kms_big_fb (yf-tiled-8bpp-rotate-270)
[160.160374] Starting subtest: yf-tiled-8bpp-rotate-270
[160.164464] Subtest yf-tiled-8bpp-rotate-270: SKIP (0.000s)
[160.260191] [031/154] (861s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-pri-indfb-draw-blt)
[160.371756] Starting subtest: psrhdr-2p-primscrn-pri-indfb-draw-blt
[160.375681] Subtest psrhdr-2p-primscrn-pri-indfb-draw-blt: SKIP (0.000s)
[160.475962] [032/154] (861s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-render)
[160.575916] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-render
[160.580155] Subtest psr-2p-scndscrn-spr-indfb-draw-render: SKIP (0.000s)
[160.676383] [033/154] (861s left) kms_cursor_legacy (cursora-vs-flipb-atomic-transitions)
[160.812288] Starting subtest: cursorA-vs-flipB-atomic-transitions
[160.816005] Subtest cursorA-vs-flipB-atomic-transitions: SKIP (0.001s)
[160.960243] [034/154] (861s left) kms_cursor_legacy (flip-vs-cursor-crc-atomic)
[161.078917] Starting subtest: flip-vs-cursor-crc-atomic
[161.775177] Subtest flip-vs-cursor-crc-atomic: SUCCESS (0.694s)
[162.604166] [035/154] (859s left) kms_psr (psr-primary-page-flip)
[162.698508] Starting subtest: psr-primary-page-flip
[162.700793] Starting dynamic subtest: eDP-1
[164.413132] Dynamic subtest eDP-1: SUCCESS (1.714s)
[164.415718] Subtest psr-primary-page-flip: SUCCESS (1.715s)
[165.277801] [036/154] (856s left) kms_content_protection (atomic)
[165.415160] Starting subtest: atomic
[165.420027] Subtest atomic: SKIP (0.000s)
[165.542863] [037/154] (856s left) kms_frontbuffer_tracking (fbc-rgb565-draw-render)
[165.638232] Starting subtest: fbc-rgb565-draw-render
[169.878749] Subtest fbc-rgb565-draw-render: SUCCESS (4.244s)
[171.079030] [038/154] (851s left) syncobj_timeline (wait-all-for-submit-complex)
[171.130167] Starting subtest: wait-all-for-submit-complex
[171.344569] Subtest wait-all-for-submit-complex: SUCCESS (0.214s)
[171.420864] [039/154] (850s left) gem_exec_params (secure-non-master)
[171.479542] Starting subtest: secure-non-master
[171.483744] Subtest secure-non-master: SKIP (0.000s)
[171.570134] [040/154] (850s left) kms_frontbuffer_tracking (hdr-shrfb-scaledprimary)
[171.670350] Starting subtest: hdr-shrfb-scaledprimary
[171.674591] Subtest hdr-shrfb-scaledprimary: SKIP (0.000s)
[171.768381] [041/154] (850s left) perf_pmu (busy-accuracy-98)
[171.810903] Starting subtest: busy-accuracy-98
[171.812807] Starting dynamic subtest: rcs0
[174.851699] Dynamic subtest rcs0: SUCCESS (3.039s)
[174.861772] Starting dynamic subtest: bcs0
[177.902866] Dynamic subtest bcs0: SUCCESS (3.049s)
[177.913273] Starting dynamic subtest: vcs0
[180.944218] Dynamic subtest vcs0: SUCCESS (3.039s)
[180.958221] Starting dynamic subtest: vcs1
[183.988515] Dynamic subtest vcs1: SUCCESS (3.043s)
[184.000852] Starting dynamic subtest: vecs0
[187.027926] Dynamic subtest vecs0: SUCCESS (3.039s)
[187.040631] Starting dynamic subtest: ccs0
[190.081195] Dynamic subtest ccs0: SUCCESS (3.052s)
[190.083810] Subtest busy-accuracy-98: SUCCESS (18.271s)
[190.196982] [042/154] (832s left) gem_userptr_blits (sd-probe)
[190.260557] Starting subtest: sd-probe
[190.262763] Subtest sd-probe: SUCCESS (0.000s)
[190.414220] [043/154] (832s left) kms_dp_link_training (non-uhbr-sst)
[190.517682] Starting subtest: non-uhbr-sst
[190.526100] Subtest non-uhbr-sst: SKIP (0.000s)
[190.602945] [044/154] (832s left) gem_exec_whisper (basic-sync)
[190.670573] Starting subtest: basic-sync
[197.278209] Subtest basic-sync: SUCCESS (6.607s)
[197.462327] [045/154] (825s left) syncobj_timeline (etime-multi-wait-all-available-unsubmitted-signaled)
[197.515097] Starting subtest: etime-multi-wait-all-available-unsubmitted-signaled
[197.624798] Subtest etime-multi-wait-all-available-unsubmitted-signaled: SUCCESS (0.107s)
[197.703609] [046/154] (824s left) kms_psr (pr-cursor-plane-onoff)
[197.794574] Starting subtest: pr-cursor-plane-onoff
[197.798616] Subtest pr-cursor-plane-onoff: SKIP (0.000s)
[197.883171] [047/154] (824s left) kms_big_fb (4-tiled-8bpp-rotate-270)
[197.982561] Starting subtest: 4-tiled-8bpp-rotate-270
[198.003295] Subtest 4-tiled-8bpp-rotate-270: SKIP (0.020s)
[198.119481] [048/154] (824s left) kms_chamelium_edid (hdmi-edid-change-during-suspend)
[198.496334] Subtest hdmi-edid-change-during-suspend: SKIP (0.000s)
[199.288052] [049/154] (823s left) gem_partial_pwrite_pread (reads-uncached)
[199.357147] Subtest reads-uncached: SKIP (0.000s)
[199.434773] [050/154] (823s left) kms_frontbuffer_tracking (fbchdr-rgb101010-draw-blt)
[199.530076] Starting subtest: fbchdr-rgb101010-draw-blt
[199.534448] Subtest fbchdr-rgb101010-draw-blt: SKIP (0.000s)
[199.630659] [051/154] (823s left) kms_ccs (bad-pixel-format-4-tiled-dg2-mc-ccs)
[199.727251] Starting subtest: bad-pixel-format-4-tiled-dg2-mc-ccs
[199.729541] Starting dynamic subtest: pipe-A-eDP-1
[199.752185] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[199.764538] Starting dynamic subtest: pipe-B-eDP-1
[199.786050] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[199.795866] Starting dynamic subtest: pipe-C-eDP-1
[199.817704] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[199.827332] Starting dynamic subtest: pipe-D-eDP-1
[199.849575] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[199.853758] Subtest bad-pixel-format-4-tiled-dg2-mc-ccs: SKIP (0.004s)
[201.995525] [052/154] (820s left) kms_getfb (getfb-handle-closed)
[202.081985] Starting subtest: getfb-handle-closed
[202.084252] Subtest getfb-handle-closed: SUCCESS (0.000s)
[202.188939] [053/154] (820s left) kms_flip (2x-flip-vs-dpms-on-nop-interruptible)
[202.282957] Starting subtest: 2x-flip-vs-dpms-on-nop-interruptible
[202.296094] Subtest 2x-flip-vs-dpms-on-nop-interruptible: SKIP (0.011s)
[202.383074] [054/154] (820s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-wc)
[202.477876] Starting subtest: fbcpsr-rgb565-draw-mmap-wc
[209.378973] Subtest fbcpsr-rgb565-draw-mmap-wc: SUCCESS (6.909s)
[209.494988] [055/154] (813s left) perf (whitelisted-registers-userspace-config)
[209.623873] Starting subtest: whitelisted-registers-userspace-config
[209.625802] Subtest whitelisted-registers-userspace-config: SUCCESS (0.001s)
[209.673570] [056/154] (813s left) api_intel_bb (object-reloc-purge-cache)
[209.725182] Starting subtest: object-reloc-purge-cache
[209.729933] Subtest object-reloc-purge-cache: SKIP (0.003s)
[209.817316] [057/154] (813s left) kms_flip (2x-plain-flip)
[209.913623] Starting subtest: 2x-plain-flip
[209.925269] Subtest 2x-plain-flip: SKIP (0.011s)
[210.037870] [058/154] (813s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-render)
[210.128629] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-render
[210.132773] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[210.230231] [059/154] (813s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[210.323792] Starting subtest: fbcpsrhdr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[210.327165] Subtest fbcpsrhdr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[210.427100] [060/154] (812s left) syncobj_wait (wait-any-interrupted)
[210.478556] Starting subtest: wait-any-interrupted
[210.584971] Subtest wait-any-interrupted: SUCCESS (0.105s)
[210.664595] [061/154] (812s left) prime_vgem (busy)
[210.728156] Starting subtest: busy
[210.730550] Starting dynamic subtest: rcs0
[210.783759] Dynamic subtest rcs0: SUCCESS (0.055s)
[210.793702] Starting dynamic subtest: bcs0
[210.835468] Dynamic subtest bcs0: SUCCESS (0.051s)
[210.845715] Starting dynamic subtest: vcs0
[210.882420] Dynamic subtest vcs0: SUCCESS (0.046s)
[210.892592] Starting dynamic subtest: vcs1
[210.929763] Dynamic subtest vcs1: SUCCESS (0.047s)
[210.940464] Starting dynamic subtest: vecs0
[210.973855] Dynamic subtest vecs0: SUCCESS (0.043s)
[210.984182] Starting dynamic subtest: ccs0
[211.021298] Dynamic subtest ccs0: SUCCESS (0.047s)
[211.023697] Subtest busy: SUCCESS (0.294s)
[211.134420] [062/154] (812s left) gem_madvise (dontneed-before-exec)
[211.175582] Starting subtest: dontneed-before-exec
[211.202842] Subtest dontneed-before-exec: SKIP (0.025s)
[211.271005] [063/154] (812s left) kms_psr2_sf (fbc-psr2-cursor-plane-update-sf)
[211.377398] Starting subtest: fbc-psr2-cursor-plane-update-sf
[211.379789] Starting dynamic subtest: pipe-A-eDP-1
[211.451122] Dynamic subtest pipe-A-eDP-1: SKIP (0.078s)
[211.462657] Starting dynamic subtest: pipe-B-eDP-1
[212.580241] Dynamic subtest pipe-B-eDP-1: SKIP (1.126s)
[212.585583] Subtest fbc-psr2-cursor-plane-update-sf: SKIP (1.207s)
[213.783763] [064/154] (809s left) kms_getfb (getfb2-accept-nv12)
[213.888706] Starting subtest: getfb2-accept-nv12
[213.890995] Subtest getfb2-accept-nv12: SUCCESS (0.001s)
[214.003911] [065/154] (809s left) kms_plane_multiple (tiling-x)
[214.094364] Starting subtest: tiling-x
[214.096661] Starting dynamic subtest: pipe-A-eDP-1
[218.001690] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.907s)
[218.012668] Starting dynamic subtest: pipe-B-eDP-1
[222.556693] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.555s)
[222.567751] Starting dynamic subtest: pipe-C-eDP-1
[227.107482] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.550s)
[227.118764] Starting dynamic subtest: pipe-D-eDP-1
[231.548732] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.436s)
[231.551109] Subtest tiling-x: SUCCESS (17.453s)
[232.360416] [066/154] (791s left) kms_flip (plain-flip-ts-check)
[232.460575] Starting subtest: plain-flip-ts-check
[232.462896] Starting dynamic subtest: A-eDP1
[241.348360] Dynamic subtest A-eDP1: SUCCESS (8.883s)
[241.362126] Starting dynamic subtest: B-eDP1
[250.183120] Dynamic subtest B-eDP1: SUCCESS (8.832s)
[250.198224] Starting dynamic subtest: C-eDP1
[259.016053] Dynamic subtest C-eDP1: SUCCESS (8.829s)
[259.026599] Starting dynamic subtest: D-eDP1
[267.842864] Dynamic subtest D-eDP1: SUCCESS (8.826s)
[267.845305] Subtest plain-flip-ts-check: SUCCESS (35.380s)
[269.045886] [067/154] (754s left) gem_exec_schedule (smoketest)
[269.223477] Starting subtest: smoketest
[269.225975] Starting dynamic subtest: rcs0
[274.781953] Dynamic subtest rcs0: SUCCESS (5.502s)
[274.794058] Starting dynamic subtest: bcs0
[280.291024] Dynamic subtest bcs0: SUCCESS (5.513s)
[280.304113] Starting dynamic subtest: vcs0
[285.796668] Dynamic subtest vcs0: SUCCESS (5.499s)
[285.808814] Starting dynamic subtest: vcs1
[291.290222] Dynamic subtest vcs1: SUCCESS (5.497s)
[291.304103] Starting dynamic subtest: vecs0
[296.781354] Dynamic subtest vecs0: SUCCESS (5.495s)
[296.800501] Starting dynamic subtest: ccs0
[302.270532] Dynamic subtest ccs0: SUCCESS (5.490s)
[302.272757] Subtest smoketest: SUCCESS (33.002s)
[302.454024] [068/154] (721s left) kms_flip (flip-vs-dpms-off-vs-modeset-interruptible)
[302.547258] Starting subtest: flip-vs-dpms-off-vs-modeset-interruptible
[302.549622] Starting dynamic subtest: A-eDP1
[306.161784] Dynamic subtest A-eDP1: SUCCESS (3.613s)
[306.172622] Starting dynamic subtest: B-eDP1
[309.469348] Dynamic subtest B-eDP1: SUCCESS (3.304s)
[309.480835] Starting dynamic subtest: C-eDP1
[312.773746] Dynamic subtest C-eDP1: SUCCESS (3.302s)
[312.784407] Starting dynamic subtest: D-eDP1
[315.980205] Dynamic subtest D-eDP1: SUCCESS (3.209s)
[315.982838] Subtest flip-vs-dpms-off-vs-modeset-interruptible: SUCCESS (13.438s)
[316.804463] [069/154] (706s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-16bpp-ytile-upscaling)
[316.900196] Starting subtest: flip-64bpp-ytile-to-16bpp-ytile-upscaling
[316.904666] Subtest flip-64bpp-ytile-to-16bpp-ytile-upscaling: SKIP (0.000s)
[316.973008] [070/154] (706s left) kms_rotation_crc (exhaust-fences)
[317.058386] Starting subtest: exhaust-fences
[317.062775] Subtest exhaust-fences: SKIP (0.000s)
[317.133706] [071/154] (706s left) kms_pm_dc (dc6-psr)
[318.230439] Starting subtest: dc6-psr
[321.223147] Subtest dc6-psr: SUCCESS (2.992s)
[322.034608] [072/154] (701s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[322.131520] Starting subtest: read-crc-frame-sequence
[322.133827] Starting dynamic subtest: pipe-A-eDP-1
[322.841100] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.710s)
[322.852485] Starting dynamic subtest: pipe-B-eDP-1
[324.210319] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.365s)
[324.220727] Starting dynamic subtest: pipe-C-eDP-1
[325.625107] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.413s)
[325.636303] Starting dynamic subtest: pipe-D-eDP-1
[326.941977] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.317s)
[326.944351] Subtest read-crc-frame-sequence: SUCCESS (4.811s)
[327.780734] [073/154] (696s left) perf_pmu (init-sema)
[327.820420] Starting subtest: init-sema
[327.822893] Starting dynamic subtest: rcs0
[327.825324] Dynamic subtest rcs0: SUCCESS (0.002s)
[327.837500] Starting dynamic subtest: bcs0
[327.839426] Dynamic subtest bcs0: SUCCESS (0.001s)
[327.849199] Starting dynamic subtest: vcs0
[327.851116] Dynamic subtest vcs0: SUCCESS (0.001s)
[327.860790] Starting dynamic subtest: vcs1
[327.862715] Dynamic subtest vcs1: SUCCESS (0.001s)
[327.871979] Starting dynamic subtest: vecs0
[327.873912] Dynamic subtest vecs0: SUCCESS (0.000s)
[327.884095] Starting dynamic subtest: ccs0
[327.886325] Dynamic subtest ccs0: SUCCESS (0.000s)
[327.888699] Subtest init-sema: SUCCESS (0.008s)
[327.938611] [074/154] (695s left) kms_pm_backlight (basic-brightness)
[328.028740] Starting subtest: basic-brightness
[328.080586] Starting dynamic subtest: eDP-1
[328.597112] Dynamic subtest eDP-1: SUCCESS (0.516s)
[328.599480] Subtest basic-brightness: SUCCESS (0.570s)
[329.404396] [075/154] (694s left) kms_frontbuffer_tracking (psr-2p-primscrn-shrfb-msflip-blt)
[329.502840] Starting subtest: psr-2p-primscrn-shrfb-msflip-blt
[329.506787] Subtest psr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[329.611027] [076/154] (694s left) gem_exec_whisper (basic-contexts-forked)
[329.668103] Starting subtest: basic-contexts-forked
[353.794569] Subtest basic-contexts-forked: SUCCESS (24.127s)
[353.978804] [077/154] (670s left) kms_atomic (plane-primary-legacy)
[354.080768] Starting subtest: plane-primary-legacy
[354.083036] Starting dynamic subtest: pipe-A-eDP-1
[354.504023] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.423s)
[354.506394] Subtest plane-primary-legacy: SUCCESS (0.425s)
[355.329874] [078/154] (668s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[355.439559] Starting subtest: psrhdr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[355.443838] Subtest psrhdr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[355.555724] [079/154] (668s left) kms_flip (2x-flip-vs-absolute-wf_vblank)
[355.659116] Starting subtest: 2x-flip-vs-absolute-wf_vblank
[355.670771] Subtest 2x-flip-vs-absolute-wf_vblank: SKIP (0.011s)
[355.752834] [080/154] (668s left) gem_ctx_persistence (userptr)
[355.809757] Starting subtest: userptr
[355.986950] Subtest userptr: SUCCESS (0.177s)
[356.079977] [081/154] (668s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-spr-indfb-move)
[356.174109] Starting subtest: fbchdr-2p-primscrn-spr-indfb-move
[356.178557] Subtest fbchdr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[356.262891] [082/154] (667s left) gem_exec_params (invalid-fence-in-submit)
[356.313817] Starting subtest: invalid-fence-in-submit
[356.318649] Subtest invalid-fence-in-submit: SUCCESS (0.003s)
[356.444572] [083/154] (667s left) kms_frontbuffer_tracking (hdr-rgb565-draw-mmap-wc)
[356.539708] Starting subtest: hdr-rgb565-draw-mmap-wc
[356.543235] Subtest hdr-rgb565-draw-mmap-wc: SKIP (0.000s)
[356.623716] [084/154] (667s left) i915_drm_fdinfo (most-busy-idle-check-all)
[356.660720] Starting subtest: most-busy-idle-check-all
[356.662925] Starting dynamic subtest: rcs0
[356.666447] Dynamic subtest rcs0: SKIP (0.005s)
[356.676629] Starting dynamic subtest: bcs0
[356.680030] Dynamic subtest bcs0: SKIP (0.000s)
[356.690088] Starting dynamic subtest: vcs0
[356.693722] Dynamic subtest vcs0: SKIP (0.000s)
[356.703113] Starting dynamic subtest: vcs1
[356.706449] Dynamic subtest vcs1: SKIP (0.000s)
[356.716084] Starting dynamic subtest: vecs0
[356.719475] Dynamic subtest vecs0: SKIP (0.000s)
[356.728661] Starting dynamic subtest: ccs0
[356.732019] Dynamic subtest ccs0: SKIP (0.000s)
[356.735367] Subtest most-busy-idle-check-all: SKIP (0.011s)
[356.782461] [085/154] (667s left) gem_exec_reloc (basic-wc-read)
[356.873493] Subtest basic-wc-read: SKIP (0.000s)
[356.946288] [086/154] (667s left) gem_fence_thrash (bo-copy)
[356.982650] Starting subtest: bo-copy
[357.000443] Subtest bo-copy: SKIP (0.017s)
[357.077788] [087/154] (667s left) kms_psr2_sf (psr2-plane-move-sf-dmg-area)
[357.169363] Starting subtest: psr2-plane-move-sf-dmg-area
[357.171340] Starting dynamic subtest: pipe-A-eDP-1
[361.686374] Dynamic subtest pipe-A-eDP-1: SUCCESS (4.517s)
[361.697291] Starting dynamic subtest: pipe-B-eDP-1
[366.996247] Dynamic subtest pipe-B-eDP-1: SUCCESS (5.309s)
[366.998663] Subtest psr2-plane-move-sf-dmg-area: SUCCESS (9.829s)
[367.882921] [088/154] (656s left) gem_mmap_wc (coherency)
[367.947336] Subtest coherency: SKIP (0.000s)
[368.006640] [089/154] (656s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-cur-indfb-move)
[368.109131] Starting subtest: hdr-2p-scndscrn-cur-indfb-move
[368.113292] Subtest hdr-2p-scndscrn-cur-indfb-move: SKIP (0.000s)
[368.194019] [090/154] (656s left) gem_render_copy_redux (normal)
[368.262798] Starting subtest: normal
[368.523356] Subtest normal: SUCCESS (0.260s)
[368.649906] [091/154] (655s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-shrfb-pgflip-blt)
[368.753101] Starting subtest: fbcpsrhdr-2p-primscrn-shrfb-pgflip-blt
[368.757574] Subtest fbcpsrhdr-2p-primscrn-shrfb-pgflip-blt: SKIP (0.000s)
[368.869120] [092/154] (655s left) kms_cursor_legacy (cursor-vs-flip-atomic-transitions)
[368.992603] Starting subtest: cursor-vs-flip-atomic-transitions
[399.424559] Subtest cursor-vs-flip-atomic-transitions: SUCCESS (30.428s)
[400.307362] [093/154] (624s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-mmap-cpu)
[400.408018] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-mmap-cpu
[407.606450] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-mmap-cpu: SUCCESS (7.203s)
[407.721423] [094/154] (617s left) syncobj_timeline (invalid-signal-one-illegal-handle)
[407.774856] Starting subtest: invalid-signal-one-illegal-handle
[407.777228] Subtest invalid-signal-one-illegal-handle: SUCCESS (0.000s)
[407.882016] [095/154] (616s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-shrfb-plflip-blt)
[407.973593] Starting subtest: psrhdr-2p-scndscrn-shrfb-plflip-blt
[407.978046] Subtest psrhdr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[408.082533] [096/154] (616s left) gen9_exec_parse (secure-batches)
[408.135792] Subtest secure-batches: SKIP (0.000s)
[408.217252] [097/154] (616s left) gem_softpin (allocator-nopin-reserve)
[408.266312] Starting subtest: allocator-nopin-reserve
[408.348451] Subtest allocator-nopin-reserve: SUCCESS (0.082s)
[408.519822] [098/154] (616s left) kms_big_fb (linear-16bpp-rotate-90)
[408.627724] Starting subtest: linear-16bpp-rotate-90
[408.648257] Subtest linear-16bpp-rotate-90: SKIP (0.020s)
[408.775777] [099/154] (616s left) kms_ccs (crc-sprite-planes-basic-4-tiled-dg2-mc-ccs)
[408.875546] Starting subtest: crc-sprite-planes-basic-4-tiled-dg2-mc-ccs
[408.877461] Starting dynamic subtest: pipe-A-eDP-1
[408.925916] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[408.936969] Starting dynamic subtest: pipe-B-eDP-1
[408.985313] Dynamic subtest pipe-B-eDP-1: SKIP (0.002s)
[408.996876] Starting dynamic subtest: pipe-C-eDP-1
[409.043675] Dynamic subtest pipe-C-eDP-1: SKIP (0.002s)
[409.054632] Starting dynamic subtest: pipe-D-eDP-1
[409.102154] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[409.105549] Subtest crc-sprite-planes-basic-4-tiled-dg2-mc-ccs: SKIP (0.011s)
[411.183904] [100/154] (613s left) gem_ctx_isolation (preservation-reset)
[411.349160] Starting subtest: preservation-reset
[411.351976] Starting dynamic subtest: rcs0
[411.631471] Dynamic subtest rcs0: SUCCESS (0.281s)
[411.644974] Starting dynamic subtest: bcs0
[411.967596] Dynamic subtest bcs0: SUCCESS (0.335s)
[411.978543] Starting dynamic subtest: vcs0
[412.299827] Dynamic subtest vcs0: SUCCESS (0.331s)
[412.310703] Starting dynamic subtest: vcs1
[413.618749] Dynamic subtest vcs1: SUCCESS (1.318s)
[413.630720] Starting dynamic subtest: vecs0
[415.694343] Dynamic subtest vecs0: SUCCESS (2.075s)
[415.705174] Starting dynamic subtest: ccs0
[417.679451] Dynamic subtest ccs0: SUCCESS (1.983s)
[417.681951] Subtest preservation-reset: SUCCESS (6.332s)
[417.808984] [101/154] (607s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-mmap-gtt)
[417.921147] Starting subtest: psr-2p-primscrn-cur-indfb-draw-mmap-gtt
[417.925545] Subtest psr-2p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[418.040468] [102/154] (607s left) syncobj_timeline (multi-wait-for-submit-available-unsubmitted-submitted-signa
[418.099135] Starting subtest: multi-wait-for-submit-available-unsubmitted-submitted-signaled
[418.101809] Subtest multi-wait-for-submit-available-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[418.195094] [103/154] (607s left) gem_render_copy (yf-tiled-ccs-to-x-tiled)
[418.250126] Starting subtest: yf-tiled-ccs-to-x-tiled
[418.254779] Subtest yf-tiled-ccs-to-x-tiled: SKIP (0.000s)
[418.437162] [104/154] (606s left) gem_create (create-ext-cpu-access-big)
[418.606432] Starting subtest: create-ext-cpu-access-big
[418.611172] Subtest create-ext-cpu-access-big: SKIP (0.001s)
[418.694413] [105/154] (606s left) kms_frontbuffer_tracking (hdr-2p-primscrn-spr-indfb-draw-mmap-cpu)
[418.791233] Starting subtest: hdr-2p-primscrn-spr-indfb-draw-mmap-cpu
[418.795378] Subtest hdr-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[418.896625] [106/154] (606s left) kms_ccs (ccs-on-another-bo-yf-tiled-ccs)
[418.999476] Starting subtest: ccs-on-another-bo-yf-tiled-ccs
[419.001752] Starting dynamic subtest: pipe-A-eDP-1
[419.024206] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[419.037218] Starting dynamic subtest: pipe-B-eDP-1
[419.058869] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[419.069197] Starting dynamic subtest: pipe-C-eDP-1
[419.090964] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[419.101106] Starting dynamic subtest: pipe-D-eDP-1
[419.123250] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[419.127442] Subtest ccs-on-another-bo-yf-tiled-ccs: SKIP (0.004s)
[421.249086] [107/154] (604s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-spr-indfb-move)
[421.358263] Starting subtest: fbchdr-1p-primscrn-spr-indfb-move
[421.362682] Subtest fbchdr-1p-primscrn-spr-indfb-move: SKIP (0.000s)
[421.477709] [108/154] (603s left) kms_draw_crc (draw-method-mmap-wc)
[421.583370] Starting subtest: draw-method-mmap-wc
[421.585700] Starting dynamic subtest: xrgb8888-untiled
[421.851004] Dynamic subtest xrgb8888-untiled: SUCCESS (0.268s)
[421.864007] Starting dynamic subtest: rgb565-untiled
[422.083312] Dynamic subtest rgb565-untiled: SUCCESS (0.233s)
[422.093934] Starting dynamic subtest: xrgb2101010-untiled
[422.305305] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.222s)
[422.318002] Starting dynamic subtest: xbgr16161616f-untiled
[422.538660] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.231s)
[422.552569] Starting dynamic subtest: xrgb8888-xtiled
[422.683277] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.143s)
[422.696053] Starting dynamic subtest: rgb565-xtiled
[422.816577] Dynamic subtest rgb565-xtiled: SUCCESS (0.132s)
[422.828876] Starting dynamic subtest: xrgb2101010-xtiled
[422.938524] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.122s)
[422.955056] Starting dynamic subtest: xbgr16161616f-xtiled
[423.071993] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.133s)
[423.082990] Starting dynamic subtest: xrgb8888-4tiled
[423.227643] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.155s)
[423.238195] Starting dynamic subtest: rgb565-4tiled
[423.372222] Dynamic subtest rgb565-4tiled: SUCCESS (0.143s)
[423.383677] Starting dynamic subtest: xrgb2101010-4tiled
[423.516325] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.143s)
[423.529247] Starting dynamic subtest: xbgr16161616f-4tiled
[423.695027] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.177s)
[423.697054] Subtest draw-method-mmap-wc: SUCCESS (2.114s)
[423.791919] [109/154] (601s left) dmabuf_sync_file (import-multiple-read-only)
[423.829626] Starting subtest: import-multiple-read-only
[423.831786] Subtest import-multiple-read-only: SUCCESS (0.003s)
[423.891180] [110/154] (601s left) kms_cursor_crc (cursor-onscreen-512x170)
[424.213034] Starting subtest: cursor-onscreen-512x170
[424.217004] Subtest cursor-onscreen-512x170: SKIP (0.001s)
[424.381951] [111/154] (601s left) kms_chamelium_hpd (dp-hpd-after-suspend)
[424.764986] Subtest dp-hpd-after-suspend: SKIP (0.000s)
[425.557512] [112/154] (600s left) kms_atomic_transition (plane-all-modeset-transition)
[425.647751] Starting subtest: plane-all-modeset-transition
[425.651716] Subtest plane-all-modeset-transition: SKIP (0.000s)
[425.721243] [113/154] (599s left) gem_mmap_offset (mmap-unmap)
[425.775887] Starting subtest: mmap-unmap
[425.777916] Starting dynamic subtest: smem0
[449.775723] Dynamic subtest smem0: SUCCESS (24.000s)
[449.778302] Subtest mmap-unmap: SUCCESS (24.001s)
[449.921106] [114/154] (575s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-cur-indfb-move)
[450.040442] Starting subtest: psrhdr-1p-primscrn-cur-indfb-move
[450.044870] Subtest psrhdr-1p-primscrn-cur-indfb-move: SKIP (0.000s)
[450.124243] [115/154] (575s left) kms_plane (pixel-format-4-tiled-mtl-rc-ccs-cc-modifier)
[450.206219] Starting subtest: pixel-format-4-tiled-mtl-rc-ccs-cc-modifier
[450.233982] Starting dynamic subtest: pipe-A-plane-0
[450.522076] Dynamic subtest pipe-A-plane-0: SUCCESS (0.288s)
[450.535578] Starting dynamic subtest: pipe-A-plane-3
[450.755975] Dynamic subtest pipe-A-plane-3: SUCCESS (0.231s)
[450.767463] Starting dynamic subtest: pipe-A-plane-5
[450.771865] Dynamic subtest pipe-A-plane-5: SKIP (0.000s)
[451.870231] Starting dynamic subtest: pipe-B-plane-0
[452.145998] Dynamic subtest pipe-B-plane-0: SUCCESS (0.288s)
[452.157533] Starting dynamic subtest: pipe-B-plane-3
[452.389607] Dynamic subtest pipe-B-plane-3: SUCCESS (0.244s)
[452.401523] Starting dynamic subtest: pipe-B-plane-5
[452.406730] Dynamic subtest pipe-B-plane-5: SKIP (0.000s)
[452.705609] Subtest pixel-format-4-tiled-mtl-rc-ccs-cc-modifier: SUCCESS (2.500s)
[453.542155] [116/154] (572s left) vgem_basic (second-client)
[453.592381] Starting subtest: second-client
[453.614275] Subtest second-client: SUCCESS (0.022s)
[453.674901] [117/154] (572s left) kms_plane (plane-position-hole)
[453.768771] Starting subtest: plane-position-hole
[454.286021] Starting dynamic subtest: pipe-A-plane-1
[455.617910] Dynamic subtest pipe-A-plane-1: SUCCESS (1.333s)
[455.630367] Starting dynamic subtest: pipe-A-plane-2
[456.917458] Dynamic subtest pipe-A-plane-2: SUCCESS (1.299s)
[456.933581] Starting dynamic subtest: pipe-A-plane-3
[458.228448] Dynamic subtest pipe-A-plane-3: SUCCESS (1.310s)
[458.240365] Starting dynamic subtest: pipe-A-plane-4
[459.501672] Dynamic subtest pipe-A-plane-4: SUCCESS (1.272s)
[459.512922] Starting dynamic subtest: pipe-A-plane-5
[459.529607] Dynamic subtest pipe-A-plane-5: SUCCESS (0.024s)
[460.756712] Starting dynamic subtest: pipe-B-plane-1
[462.051969] Dynamic subtest pipe-B-plane-1: SUCCESS (1.306s)
[462.062985] Starting dynamic subtest: pipe-B-plane-2
[463.385124] Dynamic subtest pipe-B-plane-2: SUCCESS (1.332s)
[463.396617] Starting dynamic subtest: pipe-B-plane-3
[464.721838] Dynamic subtest pipe-B-plane-3: SUCCESS (1.338s)
[464.733022] Starting dynamic subtest: pipe-B-plane-4
[466.048933] Dynamic subtest pipe-B-plane-4: SUCCESS (1.327s)
[466.061112] Starting dynamic subtest: pipe-B-plane-5
[466.078622] Dynamic subtest pipe-B-plane-5: SUCCESS (0.027s)
[466.080903] Subtest plane-position-hole: SUCCESS (12.309s)
[466.939694] [118/154] (558s left) kms_frontbuffer_tracking (fbchdr-1p-offscreen-pri-indfb-draw-mmap-gtt)
[467.044115] Starting subtest: fbchdr-1p-offscreen-pri-indfb-draw-mmap-gtt
[467.048187] Subtest fbchdr-1p-offscreen-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[467.141601] [119/154] (558s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-pri-indfb-draw-blt)
[467.243298] Starting subtest: fbcpsrhdr-1p-primscrn-pri-indfb-draw-blt
[467.247378] Subtest fbcpsrhdr-1p-primscrn-pri-indfb-draw-blt: SKIP (0.000s)
[467.353037] [120/154] (558s left) gem_exec_schedule (pi-userfault)
[467.525155] Starting subtest: pi-userfault
[467.527255] Starting dynamic subtest: rcs0
[467.532930] Dynamic subtest rcs0: SUCCESS (0.009s)
[467.543775] Starting dynamic subtest: bcs0
[467.545605] Dynamic subtest bcs0: SUCCESS (0.007s)
[467.560199] Starting dynamic subtest: vcs0
[467.562377] Dynamic subtest vcs0: SUCCESS (0.010s)
[467.573577] Starting dynamic subtest: vcs1
[467.575814] Dynamic subtest vcs1: SUCCESS (0.008s)
[467.586710] Starting dynamic subtest: vecs0
[467.588598] Dynamic subtest vecs0: SUCCESS (0.009s)
[467.599357] Starting dynamic subtest: ccs0
[467.601323] Dynamic subtest ccs0: SUCCESS (0.009s)
[467.603290] Subtest pi-userfault: SUCCESS (0.056s)
[467.766119] [121/154] (558s left) api_intel_allocator (fork-simple-stress-signal)
[467.809512] Starting subtest: fork-simple-stress-signal
[473.214759] Subtest fork-simple-stress-signal: SUCCESS (5.407s)
[473.357842] [122/154] (552s left) kms_cursor_crc (cursor-random-256x256)
[473.651344] Starting subtest: cursor-random-256x256
[473.653979] Starting dynamic subtest: pipe-A-eDP-1
[477.092783] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.441s)
[477.125423] Starting dynamic subtest: pipe-D-eDP-1
[481.148402] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.031s)
[481.150953] Subtest cursor-random-256x256: SUCCESS (7.500s)
[482.009979] [123/154] (544s left) kms_universal_plane (universal-plane-pageflip-windowed)
[482.099352] Starting subtest: universal-plane-pageflip-windowed
[482.101705] Starting dynamic subtest: pipe-A-eDP-1
[482.666849] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.565s)
[482.678273] Starting dynamic subtest: pipe-B-eDP-1
[483.894024] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.225s)
[483.905149] Starting dynamic subtest: pipe-C-eDP-1
[485.095316] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.201s)
[485.106229] Starting dynamic subtest: pipe-D-eDP-1
[486.327745] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.231s)
[486.329903] Subtest universal-plane-pageflip-windowed: SUCCESS (4.228s)
[487.207000] [124/154] (538s left) kms_frontbuffer_tracking (fbc-tiling-y)
[487.304716] Starting subtest: fbc-tiling-y
[487.309181] Subtest fbc-tiling-y: SKIP (0.000s)
[487.428531] [125/154] (538s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[487.475753] Starting subtest: framebuffer-vs-set-tiling
[487.479966] Subtest framebuffer-vs-set-tiling: SKIP (0.000s)
[487.613096] [126/154] (538s left) kms_big_fb (y-tiled-64bpp-rotate-0)
[487.730910] Starting subtest: y-tiled-64bpp-rotate-0
[487.735210] Subtest y-tiled-64bpp-rotate-0: SKIP (0.000s)
[487.827776] [127/154] (538s left) kms_frontbuffer_tracking (psr-rgb565-draw-render)
[487.927469] Starting subtest: psr-rgb565-draw-render
[495.051663] Subtest psr-rgb565-draw-render: SUCCESS (7.130s)
[495.176371] [128/154] (531s left) perf (invalid-oa-format-id)
[495.255151] Starting subtest: invalid-oa-format-id
[495.268128] Subtest invalid-oa-format-id: SUCCESS (0.012s)
[495.322646] [129/154] (531s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-blt)
[495.423478] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-blt
[495.427595] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[495.518533] [130/154] (530s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-16bpp-ytile-downscaling)
[495.607400] Starting subtest: flip-64bpp-ytile-to-16bpp-ytile-downscaling
[495.611676] Subtest flip-64bpp-ytile-to-16bpp-ytile-downscaling: SKIP (0.000s)
[495.695996] [131/154] (530s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[495.794658] Subtest addfb25-x-tiled-mismatch-legacy: SKIP (0.000s)
[495.891304] [132/154] (530s left) kms_psr (fbc-psr-cursor-plane-onoff)
[495.981719] Starting subtest: fbc-psr-cursor-plane-onoff
[495.983701] Starting dynamic subtest: eDP-1
[495.987785] Dynamic subtest eDP-1: SKIP (0.000s)
[495.991356] Subtest fbc-psr-cursor-plane-onoff: SKIP (0.001s)
[496.055356] [133/154] (530s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[496.153181] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt
[496.156686] Subtest fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[496.237551] [134/154] (530s left) gem_exec_create (basic)
[496.292402] Starting subtest: basic
[496.294520] Starting dynamic subtest: smem
[500.308537] Dynamic subtest smem: SUCCESS (4.014s)
[500.311593] Subtest basic: SUCCESS (4.016s)
[500.513984] [135/154] (526s left) kms_psr (psr-primary-blt)
[500.615607] Starting subtest: psr-primary-blt
[500.617940] Starting dynamic subtest: eDP-1
[502.340147] Dynamic subtest eDP-1: SUCCESS (1.726s)
[502.342671] Subtest psr-primary-blt: SUCCESS (1.727s)
[503.199793] [136/154] (523s left) kms_invalid_mode (zero-clock)
[503.305052] Starting subtest: zero-clock
[503.307292] Starting dynamic subtest: pipe-A-eDP-1
[503.592652] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.289s)
[503.604144] Starting dynamic subtest: pipe-B-eDP-1
[503.606389] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[503.616974] Starting dynamic subtest: pipe-C-eDP-1
[503.618922] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[503.629480] Starting dynamic subtest: pipe-D-eDP-1
[503.631435] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[503.633444] Subtest zero-clock: SUCCESS (0.295s)
[504.444752] [137/154] (522s left) kms_frontbuffer_tracking (hdr-1p-primscrn-spr-indfb-draw-pwrite)
[504.547078] Starting subtest: hdr-1p-primscrn-spr-indfb-draw-pwrite
[504.551172] Subtest hdr-1p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[504.650265] [138/154] (522s left) gem_render_copy (y-tiled-mc-ccs-to-yf-tiled-ccs)
[504.706900] Starting subtest: y-tiled-mc-ccs-to-yf-tiled-ccs
[504.710943] Subtest y-tiled-mc-ccs-to-yf-tiled-ccs: SKIP (0.000s)
[504.901067] [139/154] (521s left) kms_frontbuffer_tracking (fbcpsr-1p-shrfb-fliptrack-mmap-gtt)
[504.994444] Starting subtest: fbcpsr-1p-shrfb-fliptrack-mmap-gtt
[504.998484] Subtest fbcpsr-1p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[505.095018] [140/154] (521s left) kms_addfb_basic (bad-pitch-999)
[505.142756] Starting subtest: bad-pitch-999
[505.144958] Subtest bad-pitch-999: SUCCESS (0.000s)
[505.286001] [141/154] (521s left) syncobj_wait (single-wait-all-for-submit-signaled)
[505.348060] Starting subtest: single-wait-all-for-submit-signaled
[505.350028] Subtest single-wait-all-for-submit-signaled: SUCCESS (0.000s)
[505.461017] [142/154] (521s left) i915_pm_rc6_residency (rc6-idle)
[505.509413] Starting subtest: rc6-idle
[505.531532] Starting dynamic subtest: gt0-rcs0
[529.744049] Dynamic subtest gt0-rcs0: SUCCESS (24.216s)
[529.754862] Starting dynamic subtest: gt0-bcs0
[554.016153] Dynamic subtest gt0-bcs0: SUCCESS (24.274s)
[554.027012] Starting dynamic subtest: gt0-ccs0
[578.466238] Dynamic subtest gt0-ccs0: SUCCESS (24.452s)
[578.479527] Starting dynamic subtest: gt1-vcs0
[584.644807] Dynamic subtest gt1-vcs0: SUCCESS (6.175s)
[584.658364] Starting dynamic subtest: gt1-vecs0
[590.816983] Dynamic subtest gt1-vecs0: SUCCESS (6.170s)
[590.819917] Subtest rc6-idle: SUCCESS (85.326s)
[590.912414] [143/154] (436s left) gem_exec_fence (basic-await)
[591.082281] Starting subtest: basic-await
[591.085002] Starting dynamic subtest: rcs0
[591.162510] Dynamic subtest rcs0: SUCCESS (0.080s)
[591.175422] Starting dynamic subtest: bcs0
[591.237747] Dynamic subtest bcs0: SUCCESS (0.073s)
[591.250575] Starting dynamic subtest: vcs0
[591.313993] Dynamic subtest vcs0: SUCCESS (0.074s)
[591.327817] Starting dynamic subtest: vcs1
[591.388067] Dynamic subtest vcs1: SUCCESS (0.073s)
[591.402158] Starting dynamic subtest: vecs0
[591.463486] Dynamic subtest vecs0: SUCCESS (0.074s)
[591.478367] Starting dynamic subtest: ccs0
[591.537536] Dynamic subtest ccs0: SUCCESS (0.073s)
[591.540131] Subtest basic-await: SUCCESS (0.456s)
[593.438845] [144/154] (433s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-mmap-wc)
[593.543090] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-mmap-wc
[597.065117] Subtest fbc-1p-primscrn-cur-indfb-draw-mmap-wc: SUCCESS (3.529s)
[598.203710] [145/154] (428s left) kms_frontbuffer_tracking (hdr-1p-primscrn-indfb-msflip-blt)
[598.298257] Starting subtest: hdr-1p-primscrn-indfb-msflip-blt
[598.302303] Subtest hdr-1p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[598.399517] [146/154] (428s left) kms_cursor_edge_walk (128x128-top-bottom)
[598.492289] Starting subtest: 128x128-top-bottom
[598.494654] Starting dynamic subtest: pipe-A-eDP-1
[601.968273] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.475s)
[601.980465] Starting dynamic subtest: pipe-D-eDP-1
[605.188108] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.221s)
[605.190561] Subtest 128x128-top-bottom: SUCCESS (6.699s)
[605.988433] [147/154] (421s left) gem_exec_balancer (full-late)
[606.055827] Starting subtest: full-late
[610.243210] Subtest full-late: SUCCESS (4.187s)
[610.570120] [148/154] (416s left) kms_psr2_sf (pr-primary-plane-update-sf-dmg-area-big-fb)
[610.675945] Starting subtest: pr-primary-plane-update-sf-dmg-area-big-fb
[610.680512] Subtest pr-primary-plane-update-sf-dmg-area-big-fb: SKIP (0.000s)
[610.749290] [149/154] (416s left) gem_pxp (hw-rejects-pxp-buffer)
[610.872071] Starting subtest: hw-rejects-pxp-buffer
[610.876771] Subtest hw-rejects-pxp-buffer: SKIP (0.000s)
[611.000345] [150/154] (416s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-cur-indfb-move)
[611.094256] Starting subtest: psrhdr-2p-primscrn-cur-indfb-move
[611.098351] Subtest psrhdr-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[611.209207] [151/154] (416s left) kms_atomic_interruptible (atomic-setmode)
[611.299648] Starting subtest: atomic-setmode
[611.301932] Starting dynamic subtest: pipe-A-eDP-1
[618.223085] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.917s)
[618.225332] Subtest atomic-setmode: SUCCESS (6.918s)
[619.093693] [152/154] (408s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[619.194230] Starting subtest: fbchdr-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[619.198228] Subtest fbchdr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[619.290814] [153/154] (408s left) kms_busy (extended-modeset-hang-newfb)
[619.386114] Starting subtest: extended-modeset-hang-newfb
[619.388448] Starting dynamic subtest: pipe-A
[655.004729] Dynamic subtest pipe-A: SUCCESS (35.597s)
[655.017391] Starting dynamic subtest: pipe-D
[678.252251] Dynamic subtest pipe-D: SUCCESS (23.238s)
[678.254480] Subtest extended-modeset-hang-newfb: SUCCESS (58.840s)
[679.143958] [154/154] (348s left) gem_exec_reloc (basic-gtt-wc-active)
[679.205272] Subtest basic-gtt-wc-active: SKIP (0.000s)
[679.287439] Closing watchdogs
results: parsing output: 0/ for test: kms_frontbuffer_tracking
results: parsing output: 1/ for test: gem_basic
results: parsing output: 2/ for test: kms_frontbuffer_tracking
results: parsing output: 3/ for test: gem_media_fill
results: parsing output: 4/ for test: kms_frontbuffer_tracking
results: parsing output: 5/ for test: kms_frontbuffer_tracking
results: parsing output: 6/ for test: kms_ccs
results: parsing output: 7/ for test: i915_query
results: parsing output: 8/ for test: syncobj_basic
results: parsing output: 9/ for test: gem_exec_reloc
results: parsing output: 10/ for test: kms_frontbuffer_tracking
results: parsing output: 11/ for test: kms_chamelium_audio
results: parsing output: 12/ for test: kms_frontbuffer_tracking
results: parsing output: 13/ for test: kms_pm_rpm
results: parsing output: 14/ for test: kms_vrr
results: parsing output: 15/ for test: kms_color_pipeline
results: parsing output: 16/ for test: syncobj_timeline
results: parsing output: 17/ for test: i915_pm_freq_api
results: parsing output: 18/ for test: prime_mmap
results: parsing output: 19/ for test: kms_pipe_crc_basic
results: parsing output: 20/ for test: kms_big_fb
results: parsing output: 21/ for test: gem_mmap_gtt
results: parsing output: 22/ for test: syncobj_wait
results: parsing output: 23/ for test: vgem_basic
results: parsing output: 24/ for test: kms_psr
results: parsing output: 25/ for test: kms_frontbuffer_tracking
results: parsing output: 26/ for test: gem_exec_fence
results: parsing output: 27/ for test: sw_sync
results: parsing output: 28/ for test: kms_big_fb
results: parsing output: 29/ for test: kms_big_fb
results: parsing output: 30/ for test: kms_frontbuffer_tracking
results: parsing output: 31/ for test: kms_frontbuffer_tracking
results: parsing output: 32/ for test: kms_cursor_legacy
results: parsing output: 33/ for test: kms_cursor_legacy
results: parsing output: 34/ for test: kms_psr
results: parsing output: 35/ for test: kms_content_protection
results: parsing output: 36/ for test: kms_frontbuffer_tracking
results: parsing output: 37/ for test: syncobj_timeline
results: parsing output: 38/ for test: gem_exec_params
results: parsing output: 39/ for test: kms_frontbuffer_tracking
results: parsing output: 40/ for test: perf_pmu
results: parsing output: 41/ for test: gem_userptr_blits
results: parsing output: 42/ for test: kms_dp_link_training
results: parsing output: 43/ for test: gem_exec_whisper
results: parsing output: 44/ for test: syncobj_timeline
results: parsing output: 45/ for test: kms_psr
results: parsing output: 46/ for test: kms_big_fb
results: parsing output: 47/ for test: kms_chamelium_edid
results: parsing output: 48/ for test: gem_partial_pwrite_pread
results: parsing output: 49/ for test: kms_frontbuffer_tracking
results: parsing output: 50/ for test: kms_ccs
results: parsing output: 51/ for test: kms_getfb
results: parsing output: 52/ for test: kms_flip
results: parsing output: 53/ for test: kms_frontbuffer_tracking
results: parsing output: 54/ for test: perf
results: parsing output: 55/ for test: api_intel_bb
results: parsing output: 56/ for test: kms_flip
results: parsing output: 57/ for test: kms_frontbuffer_tracking
results: parsing output: 58/ for test: kms_frontbuffer_tracking
results: parsing output: 59/ for test: syncobj_wait
results: parsing output: 60/ for test: prime_vgem
results: parsing output: 61/ for test: gem_madvise
results: parsing output: 62/ for test: kms_psr2_sf
results: parsing output: 63/ for test: kms_getfb
results: parsing output: 64/ for test: kms_plane_multiple
results: parsing output: 65/ for test: kms_flip
results: parsing output: 66/ for test: gem_exec_schedule
results: parsing output: 67/ for test: kms_flip
results: parsing output: 68/ for test: kms_flip_scaled_crc
results: parsing output: 69/ for test: kms_rotation_crc
results: parsing output: 70/ for test: kms_pm_dc
results: parsing output: 71/ for test: kms_pipe_crc_basic
results: parsing output: 72/ for test: perf_pmu
results: parsing output: 73/ for test: kms_pm_backlight
results: parsing output: 74/ for test: kms_frontbuffer_tracking
results: parsing output: 75/ for test: gem_exec_whisper
results: parsing output: 76/ for test: kms_atomic
results: parsing output: 77/ for test: kms_frontbuffer_tracking
results: parsing output: 78/ for test: kms_flip
results: parsing output: 79/ for test: gem_ctx_persistence
results: parsing output: 80/ for test: kms_frontbuffer_tracking
results: parsing output: 81/ for test: gem_exec_params
results: parsing output: 82/ for test: kms_frontbuffer_tracking
results: parsing output: 83/ for test: i915_drm_fdinfo
results: parsing output: 84/ for test: gem_exec_reloc
results: parsing output: 85/ for test: gem_fence_thrash
results: parsing output: 86/ for test: kms_psr2_sf
results: parsing output: 87/ for test: gem_mmap_wc
results: parsing output: 88/ for test: kms_frontbuffer_tracking
results: parsing output: 89/ for test: gem_render_copy_redux
results: parsing output: 90/ for test: kms_frontbuffer_tracking
results: parsing output: 91/ for test: kms_cursor_legacy
results: parsing output: 92/ for test: kms_frontbuffer_tracking
results: parsing output: 93/ for test: syncobj_timeline
results: parsing output: 94/ for test: kms_frontbuffer_tracking
results: parsing output: 95/ for test: gen9_exec_parse
results: parsing output: 96/ for test: gem_softpin
results: parsing output: 97/ for test: kms_big_fb
results: parsing output: 98/ for test: kms_ccs
results: parsing output: 99/ for test: gem_ctx_isolation
results: parsing output: 100/ for test: kms_frontbuffer_tracking
results: parsing output: 101/ for test: syncobj_timeline
results: parsing output: 102/ for test: gem_render_copy
results: parsing output: 103/ for test: gem_create
results: parsing output: 104/ for test: kms_frontbuffer_tracking
results: parsing output: 105/ for test: kms_ccs
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: kms_draw_crc
results: parsing output: 108/ for test: dmabuf_sync_file
results: parsing output: 109/ for test: kms_cursor_crc
results: parsing output: 110/ for test: kms_chamelium_hpd
results: parsing output: 111/ for test: kms_atomic_transition
results: parsing output: 112/ for test: gem_mmap_offset
results: parsing output: 113/ for test: kms_frontbuffer_tracking
results: parsing output: 114/ for test: kms_plane
results: parsing output: 115/ for test: vgem_basic
results: parsing output: 116/ for test: kms_plane
results: parsing output: 117/ for test: kms_frontbuffer_tracking
results: parsing output: 118/ for test: kms_frontbuffer_tracking
results: parsing output: 119/ for test: gem_exec_schedule
results: parsing output: 120/ for test: api_intel_allocator
results: parsing output: 121/ for test: kms_cursor_crc
results: parsing output: 122/ for test: kms_universal_plane
results: parsing output: 123/ for test: kms_frontbuffer_tracking
results: parsing output: 124/ for test: kms_addfb_basic
results: parsing output: 125/ for test: kms_big_fb
results: parsing output: 126/ for test: kms_frontbuffer_tracking
results: parsing output: 127/ for test: perf
results: parsing output: 128/ for test: kms_frontbuffer_tracking
results: parsing output: 129/ for test: kms_flip_scaled_crc
results: parsing output: 130/ for test: kms_addfb_basic
results: parsing output: 131/ for test: kms_psr
results: parsing output: 132/ for test: kms_frontbuffer_tracking
results: parsing output: 133/ for test: gem_exec_create
results: parsing output: 134/ for test: kms_psr
results: parsing output: 135/ for test: kms_invalid_mode
results: parsing output: 136/ for test: kms_frontbuffer_tracking
results: parsing output: 137/ for test: gem_render_copy
results: parsing output: 138/ for test: kms_frontbuffer_tracking
results: parsing output: 139/ for test: kms_addfb_basic
results: parsing output: 140/ for test: syncobj_wait
results: parsing output: 141/ for test: i915_pm_rc6_residency
results: parsing output: 142/ for test: gem_exec_fence
results: parsing output: 143/ for test: kms_frontbuffer_tracking
results: parsing output: 144/ for test: kms_frontbuffer_tracking
results: parsing output: 145/ for test: kms_cursor_edge_walk
results: parsing output: 146/ for test: gem_exec_balancer
results: parsing output: 147/ for test: kms_psr2_sf
results: parsing output: 148/ for test: gem_pxp
results: parsing output: 149/ for test: kms_frontbuffer_tracking
results: parsing output: 150/ for test: kms_atomic_interruptible
results: parsing output: 151/ for test: kms_frontbuffer_tracking
results: parsing output: 152/ for test: kms_busy
results: parsing output: 153/ for test: gem_exec_reloc
Done.