[62.375007] Initializing watchdogs
[62.375065]   /dev/watchdog0
[62.390446] [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]
[62.394936] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[62.397288] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[62.399390] [001/133] (960s left) kms_pm_rpm (dpms-lpsp)
[64.074873] Starting subtest: dpms-lpsp
[64.636787] Subtest dpms-lpsp: SUCCESS (0.565s)
[64.722810] [002/133] (958s left) perf (create-destroy-userspace-config)
[64.845855] Starting subtest: create-destroy-userspace-config
[64.865944] Subtest create-destroy-userspace-config: SUCCESS (0.021s)
[64.904416] [003/133] (958s left) kms_ccs (missing-ccs-buffer-y-tiled-ccs)
[65.006135] Starting subtest: missing-ccs-buffer-y-tiled-ccs
[65.007081] Starting dynamic subtest: pipe-A-eDP-1
[65.015845] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[65.022537] Starting dynamic subtest: pipe-B-eDP-1
[65.041429] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[65.047222] Starting dynamic subtest: pipe-C-eDP-1
[65.054982] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[65.060483] Starting dynamic subtest: pipe-D-eDP-1
[65.068618] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[65.070241] Subtest missing-ccs-buffer-y-tiled-ccs: SKIP (0.003s)
[67.251251] [004/133] (955s left) kms_addfb_basic (addfb25-4-tiled)
[67.634716] Starting subtest: addfb25-4-tiled
[67.635755] Subtest addfb25-4-tiled: SUCCESS (0.000s)
[67.919795] [005/133] (955s left) kms_vblank (query-forked-busy-hang)
[68.083604] Starting subtest: query-forked-busy-hang
[68.085729] Starting dynamic subtest: pipe-A-eDP-1
[94.749180] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.673s)
[94.759803] Starting dynamic subtest: pipe-D-eDP-1
[109.659295] Dynamic subtest pipe-D-eDP-1: SUCCESS (14.910s)
[109.661044] Subtest query-forked-busy-hang: SUCCESS (41.589s)
[110.489659] [006/133] (912s left) syncobj_wait (wait-any-complex)
[110.537949] Starting subtest: wait-any-complex
[110.602827] Subtest wait-any-complex: SUCCESS (0.064s)
[110.670877] [007/133] (912s left) kms_plane_scaling (planes-unity-scaling-downscale-factor-0-5)
[110.767772] Starting subtest: planes-unity-scaling-downscale-factor-0-5
[110.768760] Starting dynamic subtest: pipe-A
[110.784755] Dynamic subtest pipe-A: SKIP (0.017s)
[110.791270] Starting dynamic subtest: pipe-B
[110.820775] Dynamic subtest pipe-B: SKIP (0.036s)
[110.826918] Starting dynamic subtest: pipe-C
[110.859218] Dynamic subtest pipe-C: SKIP (0.036s)
[110.865558] Starting dynamic subtest: pipe-D
[110.889330] Dynamic subtest pipe-D: SKIP (0.031s)
[110.890917] Subtest planes-unity-scaling-downscale-factor-0-5: SKIP (0.121s)
[111.009700] [008/133] (912s left) kms_lease (lessee-list)
[111.102380] Starting subtest: lessee-list
[111.103302] Starting dynamic subtest: pipe-A-eDP-1
[111.104340] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.002s)
[111.111112] Starting dynamic subtest: pipe-B-eDP-1
[111.111880] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[111.117614] Starting dynamic subtest: pipe-C-eDP-1
[111.118467] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[111.124104] Starting dynamic subtest: pipe-D-eDP-1
[111.124950] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[111.125865] Subtest lessee-list: SUCCESS (0.007s)
[111.195940] [009/133] (911s left) gem_lmem_swapping (random)
[111.227062] Subtest random: SKIP (0.000s)
[111.255605] [010/133] (911s left) gem_ctx_bad_destroy (invalid-pad)
[111.298417] Starting subtest: invalid-pad
[111.299395] Subtest invalid-pad: SUCCESS (0.000s)
[111.358121] [011/133] (911s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-pwrite)
[111.456392] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-pwrite
[111.458193] Subtest fbc-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[111.540560] [012/133] (911s left) syncobj_wait (invalid-multi-wait-all-unsubmitted-submitted)
[111.589191] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted
[111.590241] Subtest invalid-multi-wait-all-unsubmitted-submitted: SUCCESS (0.000s)
[111.675351] [013/133] (911s left) kms_psr_stress_test (flip-primary-invalidate-overlay)
[111.760595] Starting subtest: flip-primary-invalidate-overlay
[123.327268] Subtest flip-primary-invalidate-overlay: SUCCESS (11.567s)
[123.445907] [014/133] (899s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-pwrite)
[123.543157] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-pwrite
[123.544882] Subtest psr-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[123.618759] [015/133] (899s left) kms_ccs (crc-primary-suspend-4-tiled-dg2-rc-ccs)
[123.719031] Starting subtest: crc-primary-suspend-4-tiled-dg2-rc-ccs
[123.719928] Starting dynamic subtest: pipe-A-eDP-1
[123.723417] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[123.729559] Starting dynamic subtest: pipe-B-eDP-1
[123.733048] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[123.739231] Starting dynamic subtest: pipe-C-eDP-1
[123.742755] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[123.748888] Starting dynamic subtest: pipe-D-eDP-1
[123.752476] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[123.754120] Subtest crc-primary-suspend-4-tiled-dg2-rc-ccs: SKIP (0.003s)
[125.887380] [016/133] (897s left) gem_softpin (safe-alignment)
[125.947679] Starting subtest: safe-alignment
[125.968476] Subtest safe-alignment: SUCCESS (0.021s)
[127.927226] [017/133] (895s left) gem_ctx_param (invalid-set-ringsize)
[127.969299] Starting subtest: invalid-set-ringsize
[127.970165] Subtest invalid-set-ringsize: SUCCESS (0.000s)
[128.023916] [018/133] (895s left) kms_big_fb (y-tiled-64bpp-rotate-270)
[128.124923] Starting subtest: y-tiled-64bpp-rotate-270
[128.126967] Subtest y-tiled-64bpp-rotate-270: SKIP (0.000s)
[128.212206] [019/133] (895s left) syncobj_timeline (32bits-limit)
[128.271078] Starting subtest: 32bits-limit
[128.271979] Subtest 32bits-limit: SUCCESS (0.002s)
[128.365819] [020/133] (895s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180-hflip)
[128.455367] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180-hflip
[128.457229] Subtest y-tiled-max-hw-stride-32bpp-rotate-180-hflip: SKIP (0.000s)
[128.542157] [021/133] (895s left) api_intel_bb (render-ccs)
[128.598303] Starting subtest: render-ccs
[128.975789] Subtest render-ccs: SUCCESS (0.368s)
[129.047382] [022/133] (894s left) gem_userptr_blits (process-exit-busy)
[129.102586] Starting subtest: process-exit-busy
[129.118583] Subtest process-exit-busy: SUCCESS (0.016s)
[129.236112] [023/133] (894s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-blt)
[129.333562] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-blt
[129.335391] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[129.415286] [024/133] (894s left) kms_ccs (bad-rotation-90-yf-tiled-ccs)
[129.503124] Starting subtest: bad-rotation-90-yf-tiled-ccs
[129.504030] Starting dynamic subtest: pipe-A-eDP-1
[129.512666] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[129.522704] Starting dynamic subtest: pipe-B-eDP-1
[129.531708] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[129.538588] Starting dynamic subtest: pipe-C-eDP-1
[129.546523] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[129.552160] Starting dynamic subtest: pipe-D-eDP-1
[129.560394] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[129.561961] Subtest bad-rotation-90-yf-tiled-ccs: SKIP (0.003s)
[131.723543] [025/133] (891s left) i915_query (query-garbage)
[131.776793] Starting subtest: query-garbage
[131.777641] Subtest query-garbage: SUCCESS (0.000s)
[131.884398] [026/133] (891s left) kms_frontbuffer_tracking (fbc-1p-pri-indfb-multidraw)
[131.988793] Starting subtest: fbc-1p-pri-indfb-multidraw
[145.083980] Subtest fbc-1p-pri-indfb-multidraw: SUCCESS (13.096s)
[146.455113] [027/133] (877s left) gem_cs_tlb (engines)
[146.506736] Starting subtest: engines
[146.507585] Starting dynamic subtest: rcs0
[148.706637] Dynamic subtest rcs0: SUCCESS (2.152s)
[148.717832] Starting dynamic subtest: bcs0
[150.821764] Dynamic subtest bcs0: SUCCESS (2.151s)
[150.828598] Starting dynamic subtest: vcs0
[153.012226] Dynamic subtest vcs0: SUCCESS (2.152s)
[153.020901] Starting dynamic subtest: vcs1
[155.129247] Dynamic subtest vcs1: SUCCESS (2.152s)
[155.138476] Starting dynamic subtest: vecs0
[157.318727] Dynamic subtest vecs0: SUCCESS (2.152s)
[157.328355] Starting dynamic subtest: ccs0
[159.435732] Dynamic subtest ccs0: SUCCESS (2.153s)
[159.436818] Subtest engines: SUCCESS (12.916s)
[159.539586] [028/133] (864s left) kms_psr2_sf (fbc-psr2-overlay-plane-update-sf-dmg-area)
[159.626389] Starting subtest: fbc-psr2-overlay-plane-update-sf-dmg-area
[159.627322] Starting dynamic subtest: pipe-A-eDP-1
[159.716644] Dynamic subtest pipe-A-eDP-1: SKIP (0.089s)
[159.725850] Starting dynamic subtest: pipe-B-eDP-1
[160.825232] Dynamic subtest pipe-B-eDP-1: SKIP (1.108s)
[160.826975] Subtest fbc-psr2-overlay-plane-update-sf-dmg-area: SKIP (1.199s)
[162.000720] [029/133] (861s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-blt)
[162.091092] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-blt
[162.092722] Subtest psr-2p-scndscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[162.194323] [030/133] (861s left) kms_joiner (basic-ultra-joiner)
[162.271683] Starting subtest: basic-ultra-joiner
[162.273445] Subtest basic-ultra-joiner: SKIP (0.000s)
[162.339755] [031/133] (861s left) kms_cursor_crc (cursor-random-64x64)
[162.500452] Starting subtest: cursor-random-64x64
[162.504055] Starting dynamic subtest: pipe-A-eDP-1
[165.841916] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.337s)
[165.872971] Starting dynamic subtest: pipe-D-eDP-1
[169.814770] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.947s)
[169.815711] Subtest cursor-random-64x64: SUCCESS (7.312s)
[170.694713] [032/133] (853s left) gem_lmem_swapping (parallel-random)
[170.736375] Subtest parallel-random: SKIP (0.000s)
[170.773009] [033/133] (853s left) kms_selftest (drm_format)
[170.801050] Starting subtest: drm_format
[170.848823] Starting dynamic subtest: drm_test_format_block_width_invalid
[170.933220] Dynamic subtest drm_test_format_block_width_invalid: SUCCESS (0.085s)
[170.944694] Starting dynamic subtest: drm_test_format_block_width_one_plane
[171.023259] Dynamic subtest drm_test_format_block_width_one_plane: SUCCESS (0.090s)
[171.034859] Starting dynamic subtest: drm_test_format_block_width_two_plane
[171.113100] Dynamic subtest drm_test_format_block_width_two_plane: SUCCESS (0.090s)
[171.125721] Starting dynamic subtest: drm_test_format_block_width_three_plane
[171.196840] Dynamic subtest drm_test_format_block_width_three_plane: SUCCESS (0.083s)
[171.204309] Starting dynamic subtest: drm_test_format_block_width_tiled
[171.295884] Dynamic subtest drm_test_format_block_width_tiled: SUCCESS (0.100s)
[171.304051] Starting dynamic subtest: drm_test_format_block_height_invalid
[171.406649] Dynamic subtest drm_test_format_block_height_invalid: SUCCESS (0.110s)
[171.414959] Starting dynamic subtest: drm_test_format_block_height_one_plane
[171.498638] Dynamic subtest drm_test_format_block_height_one_plane: SUCCESS (0.089s)
[171.506439] Starting dynamic subtest: drm_test_format_block_height_two_plane
[171.594696] Dynamic subtest drm_test_format_block_height_two_plane: SUCCESS (0.098s)
[171.602410] Starting dynamic subtest: drm_test_format_block_height_three_plane
[171.699606] Dynamic subtest drm_test_format_block_height_three_plane: SUCCESS (0.103s)
[171.707049] Starting dynamic subtest: drm_test_format_block_height_tiled
[171.790240] Dynamic subtest drm_test_format_block_height_tiled: SUCCESS (0.090s)
[171.798650] Starting dynamic subtest: drm_test_format_min_pitch_invalid
[171.887679] Dynamic subtest drm_test_format_min_pitch_invalid: SUCCESS (0.096s)
[171.895773] Starting dynamic subtest: drm_test_format_min_pitch_one_plane_8bpp
[171.972731] Dynamic subtest drm_test_format_min_pitch_one_plane_8bpp: SUCCESS (0.084s)
[171.980477] Starting dynamic subtest: drm_test_format_min_pitch_one_plane_16bpp
[172.063802] Dynamic subtest drm_test_format_min_pitch_one_plane_16bpp: SUCCESS (0.092s)
[172.070131] Starting dynamic subtest: drm_test_format_min_pitch_one_plane_24bpp
[172.158242] Dynamic subtest drm_test_format_min_pitch_one_plane_24bpp: SUCCESS (0.093s)
[172.165950] Starting dynamic subtest: drm_test_format_min_pitch_one_plane_32bpp
[172.242725] Dynamic subtest drm_test_format_min_pitch_one_plane_32bpp: SUCCESS (0.085s)
[172.250380] Starting dynamic subtest: drm_test_format_min_pitch_two_plane
[172.327049] Dynamic subtest drm_test_format_min_pitch_two_plane: SUCCESS (0.083s)
[172.334144] Starting dynamic subtest: drm_test_format_min_pitch_three_plane_8bpp
[172.412980] Dynamic subtest drm_test_format_min_pitch_three_plane_8bpp: SUCCESS (0.086s)
[172.419866] Starting dynamic subtest: drm_test_format_min_pitch_tiled
[172.510165] Dynamic subtest drm_test_format_min_pitch_tiled: SUCCESS (0.095s)
[172.511092] Subtest drm_format: SUCCESS (1.709s)
[172.644402] [034/133] (851s left) sw_sync (timeline_closed_signaled)
[172.680163] Starting subtest: timeline_closed_signaled
[172.681189] Subtest timeline_closed_signaled: SUCCESS (0.000s)
[172.720971] [035/133] (851s left) kms_frontbuffer_tracking (basic)
[172.807972] Starting subtest: basic
[176.098608] Subtest basic: SUCCESS (3.291s)
[176.204553] [036/133] (847s left) gem_ctx_persistence (heartbeat-hostile)
[176.267786] Starting subtest: heartbeat-hostile
[176.269534] Subtest heartbeat-hostile: SKIP (0.000s)
[176.362965] [037/133] (847s left) kms_cursor_crc (cursor-tearing-framebuffer-change)
[176.463579] Starting subtest: cursor-tearing-framebuffer-change
[176.464531] Starting dynamic subtest: pipe-A-eDP-1
[177.040578] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.568s)
[177.047484] Starting dynamic subtest: pipe-D-eDP-1
[178.320301] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.285s)
[178.321229] Subtest cursor-tearing-framebuffer-change: SUCCESS (1.856s)
[179.143340] [038/133] (844s left) gem_exec_schedule (noreorder)
[179.307716] Starting subtest: noreorder
[179.308855] Starting dynamic subtest: rcs0
[179.371795] Dynamic subtest rcs0: SUCCESS (0.064s)
[179.380507] Starting dynamic subtest: vcs0
[180.895730] Dynamic subtest vcs0: SUCCESS (1.512s)
[180.903546] Starting dynamic subtest: vcs1
[182.947496] Dynamic subtest vcs1: SUCCESS (2.050s)
[182.956893] Starting dynamic subtest: vecs0
[184.902637] Dynamic subtest vecs0: SUCCESS (1.953s)
[184.911554] Starting dynamic subtest: ccs0
[184.960797] Dynamic subtest ccs0: SUCCESS (0.066s)
[184.962369] Subtest noreorder: SUCCESS (5.653s)
[185.153473] [039/133] (838s left) kms_frontbuffer_tracking (fbcpsr-tiling-y)
[185.255493] Starting subtest: fbcpsr-tiling-y
[185.257150] Subtest fbcpsr-tiling-y: SKIP (0.000s)
[185.337238] [040/133] (838s left) gen9_exec_parse (basic-rejected)
[185.389206] Subtest basic-rejected: SKIP (0.000s)
[185.462230] [041/133] (838s left) gem_busy (busy)
[185.510616] Starting subtest: busy
[185.511504] Starting dynamic subtest: all-engines
[185.564236] Dynamic subtest all-engines: SUCCESS (0.053s)
[185.572935] Starting dynamic subtest: rcs0
[186.915819] Dynamic subtest rcs0: SUCCESS (1.344s)
[186.924365] Starting dynamic subtest: bcs0
[186.956769] Dynamic subtest bcs0: SUCCESS (0.046s)
[186.963514] Starting dynamic subtest: vcs0
[186.996454] Dynamic subtest vcs0: SUCCESS (0.038s)
[187.004712] Starting dynamic subtest: vcs1
[188.901294] Dynamic subtest vcs1: SUCCESS (1.895s)
[188.909397] Starting dynamic subtest: vecs0
[190.948733] Dynamic subtest vecs0: SUCCESS (2.046s)
[190.956206] Starting dynamic subtest: ccs0
[192.933241] Dynamic subtest ccs0: SUCCESS (1.984s)
[192.934210] Subtest busy: SUCCESS (7.413s)
[193.288832] [042/133] (830s left) gem_exec_balancer (full-late-pulse)
[193.349811] Starting subtest: full-late-pulse
[197.779659] Subtest full-late-pulse: SUCCESS (4.429s)
[198.090167] [043/133] (826s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-mmap-gtt)
[198.183841] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-mmap-gtt
[198.185752] Subtest psr-1p-offscreen-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[198.271865] [044/133] (826s left) i915_selftest (mock)
[200.769804] Starting subtest: mock
[200.771066] Starting dynamic subtest: sanitycheck
[201.442545] Dynamic subtest sanitycheck: SUCCESS (0.673s)
[201.450755] Starting dynamic subtest: shmem
[202.131602] Dynamic subtest shmem: SUCCESS (0.688s)
[202.140426] Starting dynamic subtest: fence
[209.997203] Dynamic subtest fence: SUCCESS (7.863s)
[210.005270] Starting dynamic subtest: scatterlist
[211.649727] Dynamic subtest scatterlist: SUCCESS (1.661s)
[211.658061] Starting dynamic subtest: syncmap
[214.742750] Dynamic subtest syncmap: SUCCESS (3.081s)
[214.749428] Starting dynamic subtest: uncore
[215.420972] Dynamic subtest uncore: SUCCESS (0.677s)
[215.429065] Starting dynamic subtest: ring
[216.096543] Dynamic subtest ring: SUCCESS (0.674s)
[216.104452] Starting dynamic subtest: engine
[216.786541] Dynamic subtest engine: SUCCESS (0.689s)
[216.794722] Starting dynamic subtest: timelines
[220.638570] Dynamic subtest timelines: SUCCESS (3.850s)
[220.647491] Starting dynamic subtest: requests
[223.863388] Dynamic subtest requests: SUCCESS (3.224s)
[223.869621] Starting dynamic subtest: objects
[224.645557] Dynamic subtest objects: SUCCESS (0.782s)
[224.653609] Starting dynamic subtest: phys
[225.422511] Dynamic subtest phys: SUCCESS (0.776s)
[225.430498] Starting dynamic subtest: dmabuf
[226.196032] Dynamic subtest dmabuf: SUCCESS (0.772s)
[226.203782] Starting dynamic subtest: vma
[232.053224] Dynamic subtest vma: SUCCESS (5.856s)
[232.060963] Starting dynamic subtest: evict
[233.507454] Dynamic subtest evict: SUCCESS (1.453s)
[233.515669] Starting dynamic subtest: gtt
[236.871041] Dynamic subtest gtt: SUCCESS (3.362s)
[236.879354] Starting dynamic subtest: hugepages
[237.759627] Dynamic subtest hugepages: SUCCESS (0.888s)
[237.767499] Starting dynamic subtest: memory_region
[240.309799] Dynamic subtest memory_region: SUCCESS (2.549s)
[240.310995] Subtest mock: SUCCESS (39.539s)
[240.330996] [FACT i915_selftest (mock)] deleted: kernel.kmod_is_loaded.i915: true
[240.332393] [045/133] (783s left) gem_ctx_shared (disjoint-timelines)
[241.862771] Starting subtest: disjoint-timelines
[241.911236] Subtest disjoint-timelines: SUCCESS (0.048s)
[242.102710] [FACT gem_ctx_shared (disjoint-timelines)] new: kernel.kmod_is_loaded.i915: true
[242.103163] [FACT gem_ctx_shared (disjoint-timelines)] new: kernel.kmod_is_loaded.vgem: true
[242.104830] [046/133] (782s left) perf_pmu (busy-double-start)
[242.141964] Starting subtest: busy-double-start
[242.143073] Starting dynamic subtest: rcs0
[245.742827] Dynamic subtest rcs0: FAIL (3.581s)
[245.750270] Starting dynamic subtest: bcs0
[249.329626] Dynamic subtest bcs0: SUCCESS (3.605s)
[249.339276] Starting dynamic subtest: vcs0
[253.428225] Dynamic subtest vcs0: SUCCESS (4.097s)
[253.438859] Starting dynamic subtest: vcs1
[257.452505] Dynamic subtest vcs1: SUCCESS (4.023s)
[257.462525] Starting dynamic subtest: vecs0
[261.421257] Dynamic subtest vecs0: SUCCESS (3.967s)
[261.431812] Starting dynamic subtest: ccs0
[265.019583] Dynamic subtest ccs0: FAIL (3.567s)
[265.020443] Subtest busy-double-start: FAIL (22.848s)
[265.098059] [047/133] (759s left) gem_render_copy (y-tiled-ccs-to-yf-tiled-mc-ccs)
[265.160186] Starting subtest: y-tiled-ccs-to-yf-tiled-mc-ccs
[265.161955] Subtest y-tiled-ccs-to-yf-tiled-mc-ccs: SKIP (0.000s)
[265.347301] [048/133] (759s left) kms_psr (psr2-primary-render)
[265.432117] Starting subtest: psr2-primary-render
[265.432955] Starting dynamic subtest: eDP-1
[267.131278] Dynamic subtest eDP-1: SUCCESS (1.698s)
[267.132419] Subtest psr2-primary-render: SUCCESS (1.699s)
[267.990792] [049/133] (756s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytileccs-downscaling)
[268.107940] Starting subtest: flip-32bpp-ytile-to-32bpp-ytileccs-downscaling
[268.109749] Subtest flip-32bpp-ytile-to-32bpp-ytileccs-downscaling: SKIP (0.000s)
[268.194168] [050/133] (756s left) kms_ccs (crc-primary-basic-4-tiled-dg2-mc-ccs)
[268.288411] Starting subtest: crc-primary-basic-4-tiled-dg2-mc-ccs
[268.289502] Starting dynamic subtest: pipe-A-eDP-1
[268.298333] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[268.309489] Starting dynamic subtest: pipe-B-eDP-1
[268.320669] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[268.331038] Starting dynamic subtest: pipe-C-eDP-1
[268.339277] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[268.345460] Starting dynamic subtest: pipe-D-eDP-1
[268.353705] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[268.355509] Subtest crc-primary-basic-4-tiled-dg2-mc-ccs: SKIP (0.005s)
[270.574056] [051/133] (753s left) gem_workarounds (reset)
[270.627776] Starting subtest: reset
[270.783636] Subtest reset: SUCCESS (0.156s)
[270.897033] [052/133] (753s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-signaled)
[270.956309] Starting subtest: invalid-multi-wait-all-unsubmitted-signaled
[270.957516] Subtest invalid-multi-wait-all-unsubmitted-signaled: SUCCESS (0.000s)
[271.047273] [053/133] (753s left) syncobj_timeline (multi-wait-all-available-submitted)
[271.096970] Starting subtest: multi-wait-all-available-submitted
[271.098043] Subtest multi-wait-all-available-submitted: SUCCESS (0.001s)
[271.194173] [054/133] (753s left) kms_frontbuffer_tracking (fbc-2p-primscrn-shrfb-plflip-blt)
[271.290474] Starting subtest: fbc-2p-primscrn-shrfb-plflip-blt
[271.292374] Subtest fbc-2p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[271.381844] [055/133] (753s left) gem_basic (bad-close)
[271.430894] Starting subtest: bad-close
[271.433527] Subtest bad-close: SUCCESS (0.000s)
[271.552950] [056/133] (753s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[271.657762] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[271.659870] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SKIP (0.000s)
[271.748216] [057/133] (753s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-64bpp-4tile-downscaling)
[271.837349] Starting subtest: flip-32bpp-4tile-to-64bpp-4tile-downscaling
[271.838234] Starting dynamic subtest: pipe-A-default-mode
[273.046242] Dynamic subtest pipe-A-default-mode: SKIP (1.207s)
[273.047754] Subtest flip-32bpp-4tile-to-64bpp-4tile-downscaling: SKIP (1.209s)
[273.187472] [058/133] (751s left) sysfs_heartbeat_interval (mixed)
[273.233861] Starting subtest: mixed
[273.235081] Starting dynamic subtest: vecs0
[278.464899] Dynamic subtest vecs0: SUCCESS (5.230s)
[278.474642] Starting dynamic subtest: ccs0
[283.683625] Dynamic subtest ccs0: SUCCESS (5.217s)
[283.694904] Starting dynamic subtest: vcs0
[288.900965] Dynamic subtest vcs0: SUCCESS (5.215s)
[288.912415] Starting dynamic subtest: bcs0
[294.140335] Dynamic subtest bcs0: SUCCESS (5.237s)
[294.150587] Starting dynamic subtest: rcs0
[299.355825] Dynamic subtest rcs0: SUCCESS (5.214s)
[299.366070] Starting dynamic subtest: vcs1
[304.569069] Dynamic subtest vcs1: SUCCESS (5.212s)
[304.570503] Subtest mixed: SUCCESS (31.333s)
[304.642007] [059/133] (720s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-render)
[304.744939] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-render
[304.748289] Subtest fbc-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[304.849550] [060/133] (720s left) kms_cursor_legacy (short-flip-after-cursor-atomic-transitions)
[304.973248] Starting subtest: short-flip-after-cursor-atomic-transitions
[305.926780] Subtest short-flip-after-cursor-atomic-transitions: SUCCESS (0.952s)
[306.757454] [061/133] (718s left) gem_exec_fence (expired-history)
[307.199257] Starting subtest: expired-history
[314.613911] Subtest expired-history: SUCCESS (7.406s)
[315.203064] [062/133] (709s left) kms_flip (2x-flip-vs-blocking-wf-vblank)
[315.288688] Starting subtest: 2x-flip-vs-blocking-wf-vblank
[315.300386] Subtest 2x-flip-vs-blocking-wf-vblank: SKIP (0.011s)
[315.378816] [063/133] (709s left) gem_mmap_gtt (zero-extend)
[315.428243] Subtest zero-extend: SKIP (0.000s)
[315.482143] [064/133] (709s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-cpu)
[315.576666] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-cpu
[315.578579] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[315.690160] [065/133] (709s left) kms_properties (colorop-properties-legacy)
[315.783510] Starting subtest: colorop-properties-legacy
[315.784477] Starting dynamic subtest: pipe-A-eDP-1
[316.121040] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.337s)
[316.128850] Starting dynamic subtest: pipe-B-eDP-1
[317.207874] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.086s)
[317.215605] Starting dynamic subtest: pipe-C-eDP-1
[318.248766] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.039s)
[318.257515] Starting dynamic subtest: pipe-D-eDP-1
[319.288972] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.039s)
[319.289915] Subtest colorop-properties-legacy: SUCCESS (3.506s)
[320.106355] [066/133] (705s left) device_reset (cold-reset-bound)
[320.158993] Starting subtest: cold-reset-bound
[320.161162] Subtest cold-reset-bound: SKIP (0.000s)
[320.231386] [067/133] (704s left) kms_plane (planar-pixel-format-settings)
[320.320533] Starting subtest: planar-pixel-format-settings
[320.387084] Subtest planar-pixel-format-settings: SUCCESS (0.067s)
[320.489017] [068/133] (704s left) syncobj_timeline (multi-wait-for-submit-available-unsubmitted-signaled)
[320.532453] Starting subtest: multi-wait-for-submit-available-unsubmitted-signaled
[320.533380] Subtest multi-wait-for-submit-available-unsubmitted-signaled: SUCCESS (0.000s)
[320.612152] [069/133] (704s left) kms_frontbuffer_tracking (psr-2p-primscrn-shrfb-msflip-blt)
[320.698098] Starting subtest: psr-2p-primscrn-shrfb-msflip-blt
[320.699803] Subtest psr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[320.795478] [070/133] (704s left) kms_chamelium_hpd (hdmi-hpd-with-enabled-mode)
[321.161606] Subtest hdmi-hpd-with-enabled-mode: SKIP (0.000s)
[321.994932] [071/133] (703s left) kms_plane_cursor (primary)
[322.074553] Starting subtest: primary
[322.076343] Starting dynamic subtest: pipe-A-eDP-1-size-64
[324.626370] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (2.552s)
[324.651353] Starting dynamic subtest: pipe-A-eDP-1-size-128
[327.238521] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (2.594s)
[327.263688] Starting dynamic subtest: pipe-A-eDP-1-size-256
[329.806795] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (2.550s)
[330.120022] Starting dynamic subtest: pipe-B-eDP-1-size-64
[333.393178] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (3.281s)
[333.417815] Starting dynamic subtest: pipe-B-eDP-1-size-128
[335.994059] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (2.583s)
[336.019237] Starting dynamic subtest: pipe-B-eDP-1-size-256
[338.562313] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (2.550s)
[338.877340] Starting dynamic subtest: pipe-C-eDP-1-size-64
[341.804226] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (2.935s)
[341.835055] Starting dynamic subtest: pipe-C-eDP-1-size-128
[344.015399] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (2.192s)
[344.042398] Starting dynamic subtest: pipe-C-eDP-1-size-256
[346.237567] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (2.203s)
[346.556406] Starting dynamic subtest: pipe-D-eDP-1-size-64
[349.558388] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (3.010s)
[349.584508] Starting dynamic subtest: pipe-D-eDP-1-size-128
[351.769697] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (2.192s)
[351.795705] Starting dynamic subtest: pipe-D-eDP-1-size-256
[354.003151] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (2.214s)
[354.310759] Subtest primary: SUCCESS (32.234s)
[355.173888] [072/133] (670s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-180-hflip)
[355.273225] Starting subtest: linear-max-hw-stride-32bpp-rotate-180-hflip
[355.274900] Subtest linear-max-hw-stride-32bpp-rotate-180-hflip: SKIP (0.000s)
[355.353358] [073/133] (670s left) i915_pciid
[355.503898] [074/133] (669s left) gem_watchdog (default-physical)
[355.557852] Starting subtest: default-physical
[357.329722] Subtest default-physical: SUCCESS (1.772s)
[357.408580] [075/133] (668s left) kms_addfb_basic (invalid-get-prop-any)
[357.462092] Starting subtest: invalid-get-prop-any
[357.462997] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[357.589217] [076/133] (667s left) gem_exec_schedule (u-submit-late-slice)
[357.644602] Starting subtest: u-submit-late-slice
[357.645601] Starting dynamic subtest: rcs0
[357.681781] Dynamic subtest rcs0: SUCCESS (0.037s)
[357.692181] Starting dynamic subtest: bcs0
[357.719188] Dynamic subtest bcs0: SUCCESS (0.037s)
[357.728609] Starting dynamic subtest: vcs0
[357.748618] Dynamic subtest vcs0: SUCCESS (0.029s)
[357.756860] Starting dynamic subtest: vcs1
[357.783038] Dynamic subtest vcs1: SUCCESS (0.033s)
[357.793347] Starting dynamic subtest: vecs0
[357.817055] Dynamic subtest vecs0: SUCCESS (0.033s)
[357.825412] Starting dynamic subtest: ccs0
[357.857499] Dynamic subtest ccs0: SUCCESS (0.039s)
[357.858764] Subtest u-submit-late-slice: SUCCESS (0.214s)
[359.013265] [077/133] (666s left) gen9_exec_parse (unaligned-jump)
[359.065452] Subtest unaligned-jump: SKIP (0.000s)
[359.138360] [078/133] (666s left) gem_exec_params (invalid-bsd2-flag-on-render)
[359.197765] Starting subtest: invalid-bsd2-flag-on-render
[359.198720] Subtest invalid-bsd2-flag-on-render: SUCCESS (0.000s)
[359.292215] [079/133] (666s left) gem_sync (basic-many-each)
[359.354967] Starting subtest: basic-many-each
[361.623891] Subtest basic-many-each: SUCCESS (2.270s)
[361.820740] [080/133] (663s left) gem_mmap_gtt (basic-write-gtt)
[361.863991] Subtest basic-write-gtt: SKIP (0.000s)
[361.918596] [081/133] (663s left) kms_draw_crc (draw-method-blt)
[362.005262] Starting subtest: draw-method-blt
[362.006166] Starting dynamic subtest: xrgb8888-untiled
[362.239261] Dynamic subtest xrgb8888-untiled: SUCCESS (0.233s)
[362.246891] Starting dynamic subtest: rgb565-untiled
[362.482931] Dynamic subtest rgb565-untiled: SUCCESS (0.243s)
[362.493304] Starting dynamic subtest: xrgb2101010-untiled
[362.705130] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.221s)
[362.714605] Starting dynamic subtest: xbgr16161616f-untiled
[362.939030] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.232s)
[362.948202] Starting dynamic subtest: xrgb8888-xtiled
[363.061500] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.120s)
[363.073934] Starting dynamic subtest: rgb565-xtiled
[363.171719] Dynamic subtest rgb565-xtiled: SUCCESS (0.110s)
[363.179790] Starting dynamic subtest: xrgb2101010-xtiled
[363.282920] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.110s)
[363.290854] Starting dynamic subtest: xbgr16161616f-xtiled
[363.394211] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.110s)
[363.405341] Starting dynamic subtest: xrgb8888-4tiled
[363.505383] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.109s)
[363.514463] Starting dynamic subtest: rgb565-4tiled
[363.627191] Dynamic subtest rgb565-4tiled: SUCCESS (0.121s)
[363.637397] Starting dynamic subtest: xrgb2101010-4tiled
[363.738515] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.111s)
[363.746614] Starting dynamic subtest: xbgr16161616f-4tiled
[363.849609] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.110s)
[363.850542] Subtest draw-method-blt: SUCCESS (1.845s)
[363.970940] [082/133] (661s left) i915_pm_rc6_residency (media-rc6-accuracy)
[364.014540] Starting subtest: media-rc6-accuracy
[364.016506] Subtest media-rc6-accuracy: SKIP (0.000s)
[364.101817] [083/133] (661s left) kms_atomic (plane-invalid-params)
[364.185657] Starting subtest: plane-invalid-params
[364.186477] Starting dynamic subtest: pipe-A-eDP-1
[364.585347] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.400s)
[364.586226] Subtest plane-invalid-params: SUCCESS (0.401s)
[365.373944] [084/133] (660s left) kms_flip (busy-flip)
[365.466471] Starting subtest: busy-flip
[365.468809] Starting dynamic subtest: A-eDP1
[367.189194] Dynamic subtest A-eDP1: SUCCESS (1.721s)
[367.198649] Starting dynamic subtest: B-eDP1
[368.927708] Dynamic subtest B-eDP1: SUCCESS (1.735s)
[368.939041] Starting dynamic subtest: C-eDP1
[370.556696] Dynamic subtest C-eDP1: SUCCESS (1.625s)
[370.566356] Starting dynamic subtest: D-eDP1
[372.188987] Dynamic subtest D-eDP1: SUCCESS (1.630s)
[372.192698] Subtest busy-flip: SUCCESS (6.727s)
[373.356361] [085/133] (652s left) kms_frontbuffer_tracking (psr-slowdraw)
[373.447940] Starting subtest: psr-slowdraw
[398.710220] Subtest psr-slowdraw: SUCCESS (25.262s)
[398.817370] [086/133] (627s left) gem_pxp (fail-invalid-protected-context)
[398.945074] Starting subtest: fail-invalid-protected-context
[398.946084] Subtest fail-invalid-protected-context: SUCCESS (0.000s)
[399.049974] [087/133] (626s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[399.147956] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[399.149915] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[399.242343] [088/133] (626s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-blt)
[399.326378] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-blt
[399.327987] Subtest fbc-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[399.437258] [089/133] (626s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-mmap-gtt)
[399.530293] Starting subtest: psr-1p-primscrn-pri-indfb-draw-mmap-gtt
[399.531946] Subtest psr-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[399.626429] [090/133] (626s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-render)
[399.708106] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-render
[399.710028] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[399.798123] [091/133] (626s left) kms_plane (pixel-format-linear-modifier)
[399.892034] Starting subtest: pixel-format-linear-modifier
[399.919389] Starting dynamic subtest: pipe-A-plane-0
[403.408145] Dynamic subtest pipe-A-plane-0: SUCCESS (3.489s)
[403.417386] Starting dynamic subtest: pipe-A-plane-3
[405.119274] Dynamic subtest pipe-A-plane-3: SUCCESS (1.710s)
[405.129911] Starting dynamic subtest: pipe-A-plane-5
[405.330476] Dynamic subtest pipe-A-plane-5: SUCCESS (0.210s)
[406.459045] Starting dynamic subtest: pipe-B-plane-0
[409.984998] Dynamic subtest pipe-B-plane-0: SUCCESS (3.533s)
[409.993186] Starting dynamic subtest: pipe-B-plane-3
[411.696312] Dynamic subtest pipe-B-plane-3: SUCCESS (1.710s)
[411.706742] Starting dynamic subtest: pipe-B-plane-5
[411.918583] Dynamic subtest pipe-B-plane-5: SUCCESS (0.222s)
[412.240023] Subtest pixel-format-linear-modifier: SUCCESS (12.348s)
[413.038092] [092/133] (613s left) gem_exec_gttfill (engines)
[413.096760] Starting subtest: engines
[413.097809] Starting dynamic subtest: rcs0
[436.218274] Dynamic subtest rcs0: SUCCESS (23.120s)
[436.230523] Starting dynamic subtest: bcs0
[459.554245] Dynamic subtest bcs0: SUCCESS (23.332s)
[459.562959] Starting dynamic subtest: vcs0
[482.879049] Dynamic subtest vcs0: SUCCESS (23.323s)
[482.899294] Starting dynamic subtest: vcs1
[506.223946] Dynamic subtest vcs1: SUCCESS (23.342s)
[506.233801] Starting dynamic subtest: vecs0
[529.577396] Dynamic subtest vecs0: SUCCESS (23.350s)
[529.587483] Starting dynamic subtest: ccs0
[553.009776] Dynamic subtest ccs0: SUCCESS (23.429s)
[553.012794] Subtest engines: SUCCESS (139.909s)
[553.204467] [093/133] (472s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[553.342390] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[554.213492] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SUCCESS (0.871s)
[554.371653] [094/133] (471s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-onoff)
[554.471933] Starting subtest: fbc-2p-primscrn-cur-indfb-onoff
[554.473752] Subtest fbc-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[554.590394] [095/133] (471s left) perf_pmu (rc6-suspend)
[554.626372] Starting subtest: rc6-suspend
[577.181477] Subtest rc6-suspend: SUCCESS (8.068s)
[580.555266] [096/133] (448s left) syncobj_wait (invalid-reset-bad-pad)
[580.614406] Starting subtest: invalid-reset-bad-pad
[580.615382] Subtest invalid-reset-bad-pad: SUCCESS (0.000s)
[580.674560] [097/133] (448s left) kms_properties (get_properties-sanity-atomic)
[580.757911] Starting subtest: get_properties-sanity-atomic
[580.759769] Subtest get_properties-sanity-atomic: SUCCESS (0.002s)
[580.827307] [098/133] (448s left) gem_fence_thrash (bo-write-verify-x)
[580.856329] Starting subtest: bo-write-verify-x
[580.878502] Subtest bo-write-verify-x: SKIP (0.020s)
[580.932344] [099/133] (448s left) kms_color (legacy-gamma-reset)
[581.011982] Starting subtest: legacy-gamma-reset
[581.012807] Starting dynamic subtest: pipe-A-eDP-1
[581.398505] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.395s)
[581.407599] Starting dynamic subtest: pipe-B-eDP-1
[581.411346] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.012s)
[581.420256] Starting dynamic subtest: pipe-C-eDP-1
[581.423949] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.012s)
[581.432688] Starting dynamic subtest: pipe-D-eDP-1
[581.437386] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.013s)
[581.438293] Subtest legacy-gamma-reset: SUCCESS (0.435s)
[582.175845] [100/133] (447s left) kms_psr (pr-primary-mmap-gtt)
[582.254395] Starting subtest: pr-primary-mmap-gtt
[582.256258] Subtest pr-primary-mmap-gtt: SKIP (0.000s)
[582.326215] [101/133] (447s left) kms_fbcon_fbt (psr-suspend)
[582.376534] Starting subtest: psr-suspend
[633.031796] Subtest psr-suspend: SUCCESS (20.671s)
[636.160359] [102/133] (396s left) kms_pipe_crc_basic (suspend-read-crc)
[636.249600] Starting subtest: suspend-read-crc
[636.250401] Starting dynamic subtest: pipe-A-eDP-1
[654.930455] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.976s)
[654.940591] Starting dynamic subtest: pipe-B-eDP-1
[673.938486] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.600s)
[673.947490] Starting dynamic subtest: pipe-C-eDP-1
[692.898342] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.514s)
[692.907852] Starting dynamic subtest: pipe-D-eDP-1
[711.875748] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.423s)
[711.876777] Subtest suspend-read-crc: SUCCESS (13.520s)
[718.716426] [103/133] (319s left) kms_hdmi_inject (inject-audio)
[719.083330] Starting subtest: inject-audio
[719.361375] Subtest inject-audio: SUCCESS (0.279s)
[719.982448] [104/133] (318s left) kms_psr (fbc-psr2-sprite-plane-move)
[720.061410] Starting subtest: fbc-psr2-sprite-plane-move
[720.062261] Starting dynamic subtest: eDP-1
[720.063842] Dynamic subtest eDP-1: SKIP (0.000s)
[720.065592] Subtest fbc-psr2-sprite-plane-move: SKIP (0.001s)
[720.134084] [105/133] (318s left) kms_psr (fbc-psr2-primary-mmap-gtt)
[720.222373] Starting subtest: fbc-psr2-primary-mmap-gtt
[720.223513] Starting dynamic subtest: eDP-1
[720.225376] Dynamic subtest eDP-1: SKIP (0.000s)
[720.227150] Subtest fbc-psr2-primary-mmap-gtt: SKIP (0.001s)
[720.294530] [106/133] (318s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-mmap-wc)
[720.383381] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-mmap-wc
[724.421488] Subtest fbc-1p-offscreen-pri-indfb-draw-mmap-wc: SUCCESS (4.043s)
[725.539651] [107/133] (313s left) kms_frontbuffer_tracking (fbc-stridechange)
[725.646297] Starting subtest: fbc-stridechange
[728.924858] Subtest fbc-stridechange: SUCCESS (3.282s)
[730.118754] [108/133] (308s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-mmap-wc)
[730.210134] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-mmap-wc
[730.211781] Subtest fbc-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[730.289811] [109/133] (308s left) kms_big_fb (4-tiled-64bpp-rotate-180)
[730.394726] Starting subtest: 4-tiled-64bpp-rotate-180
[731.999796] Subtest 4-tiled-64bpp-rotate-180: SUCCESS (1.608s)
[732.190134] [110/133] (306s left) kms_cursor_crc (cursor-onscreen-256x85)
[732.444813] Starting subtest: cursor-onscreen-256x85
[732.490457] Subtest cursor-onscreen-256x85: SKIP (0.043s)
[732.650830] [111/133] (306s left) gem_eio (wait-wedge-10ms)
[732.888157] Starting subtest: wait-wedge-10ms
[734.880888] Subtest wait-wedge-10ms: SUCCESS (1.995s)
[735.008436] [112/133] (303s left) gem_eio (hibernate)
[735.237739] Starting subtest: hibernate
[749.990790] Subtest hibernate: SUCCESS (14.761s)
[756.137690] [113/133] (288s left) kms_pm_rpm (system-suspend-modeset)
[757.934908] Starting subtest: system-suspend-modeset
[780.747843] Subtest system-suspend-modeset: SUCCESS (7.258s)
[783.472582] [114/133] (263s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[783.567285] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[784.380566] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SUCCESS (0.817s)
[784.528625] [115/133] (262s left) kms_flip (2x-flip-vs-expired-vblank-interruptible)
[784.622302] Starting subtest: 2x-flip-vs-expired-vblank-interruptible
[784.634668] Subtest 2x-flip-vs-expired-vblank-interruptible: SKIP (0.011s)
[784.723396] [116/133] (262s left) perf (unprivileged-single-ctx-counters)
[784.862921] Starting subtest: unprivileged-single-ctx-counters
[784.864427] Subtest unprivileged-single-ctx-counters: SKIP (0.000s)
[784.903437] [117/133] (262s left) kms_chamelium_edid (dp-mode-timings)
[785.302600] Subtest dp-mode-timings: SKIP (0.000s)
[786.125449] [118/133] (261s left) gem_exec_schedule (submit-golden-slice)
[786.204050] Starting subtest: submit-golden-slice
[786.205229] Starting dynamic subtest: rcs0
[786.244825] Dynamic subtest rcs0: SUCCESS (0.040s)
[786.257978] Starting dynamic subtest: bcs0
[786.282462] Dynamic subtest bcs0: SUCCESS (0.037s)
[786.292816] Starting dynamic subtest: vcs0
[786.312925] Dynamic subtest vcs0: SUCCESS (0.030s)
[786.328430] Starting dynamic subtest: vcs1
[786.355059] Dynamic subtest vcs1: SUCCESS (0.041s)
[786.365695] Starting dynamic subtest: vecs0
[786.387473] Dynamic subtest vecs0: SUCCESS (0.032s)
[786.400343] Starting dynamic subtest: ccs0
[786.421689] Dynamic subtest ccs0: SUCCESS (0.034s)
[786.422625] Subtest submit-golden-slice: SUCCESS (0.219s)
[787.485130] [119/133] (259s left) syncobj_wait (invalid-multi-wait-unsubmitted)
[787.551499] Starting subtest: invalid-multi-wait-unsubmitted
[787.552565] Subtest invalid-multi-wait-unsubmitted: SUCCESS (0.000s)
[787.649500] [120/133] (259s left) kms_psr (fbc-pr-cursor-render)
[787.745909] Starting subtest: fbc-pr-cursor-render
[787.747870] Subtest fbc-pr-cursor-render: SKIP (0.000s)
[787.819586] [121/133] (259s left) prime_vgem (shrink)
[787.878704] Starting subtest: shrink
[787.902892] Subtest shrink: SUCCESS (0.023s)
[787.992295] [122/133] (259s left) api_intel_allocator (two-level-inception)
[788.034348] Starting subtest: two-level-inception
[793.468221] Subtest two-level-inception: SUCCESS (5.400s)
[793.551445] [123/133] (253s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-onoff)
[793.654113] Starting subtest: psr-1p-primscrn-spr-indfb-onoff
[800.695278] Subtest psr-1p-primscrn-spr-indfb-onoff: SUCCESS (7.085s)
[800.827779] [124/133] (246s left) prime_mmap_coherency (ioctl-errors)
[800.873655] Starting subtest: ioctl-errors
[814.856723] Subtest ioctl-errors: SUCCESS (14.013s)
[815.054531] [125/133] (232s left) kms_flip (flip-vs-panning-vs-hang)
[815.195406] Starting subtest: flip-vs-panning-vs-hang
[815.196375] Starting dynamic subtest: A-eDP1
[854.904890] Dynamic subtest A-eDP1: SUCCESS (39.718s)
[854.918940] Starting dynamic subtest: D-eDP1
[904.892366] Dynamic subtest D-eDP1: SUCCESS (49.991s)
[904.895013] Subtest flip-vs-panning-vs-hang: SUCCESS (89.715s)
[906.034781] [126/133] (141s left) kms_cdclk (mode-transition-all-outputs)
[906.141879] Starting subtest: mode-transition-all-outputs
[906.442987] Subtest mode-transition-all-outputs: SKIP (0.301s)
[907.234596] [127/133] (140s left) kms_content_protection (lic-type-0)
[907.376417] Starting subtest: lic-type-0
[907.378231] Subtest lic-type-0: SKIP (0.000s)
[907.500297] [128/133] (139s left) gem_render_copy (linear-to-vebox-yf-tiled)
[907.562100] Starting subtest: linear-to-vebox-yf-tiled
[907.563954] Subtest linear-to-vebox-yf-tiled: SKIP (0.000s)
[907.746892] [129/133] (139s left) gem_exec_fence (submit)
[907.924666] Starting subtest: submit
[907.926630] Subtest submit: SKIP (0.000s)
[909.408855] [130/133] (138s left) kms_debugfs (display-off-read-all)
[909.502064] Starting subtest: display-off-read-all
[909.788607] Subtest display-off-read-all: SUCCESS (0.288s)
[910.628181] [131/133] (136s left) tools_test (sysfs_l3_parity)
[910.669856] Starting subtest: sysfs_l3_parity
[910.754386] Subtest sysfs_l3_parity: SKIP (0.087s)
[910.811919] [132/133] (136s left) kms_frontbuffer_tracking (fbc-2p-pri-indfb-multidraw)
[910.895739] Starting subtest: fbc-2p-pri-indfb-multidraw
[910.897371] Subtest fbc-2p-pri-indfb-multidraw: SKIP (0.000s)
[910.994618] [133/133] (136s left) syncobj_timeline (single-wait-all-for-submit-available-signaled)
[911.056996] Starting subtest: single-wait-all-for-submit-available-signaled
[911.058187] Subtest single-wait-all-for-submit-available-signaled: SUCCESS (0.000s)
[911.126773] Closing watchdogs
results: parsing output: 0/ for test: kms_pm_rpm
results: parsing output: 1/ for test: perf
results: parsing output: 2/ for test: kms_ccs
results: parsing output: 3/ for test: kms_addfb_basic
results: parsing output: 4/ for test: kms_vblank
results: parsing output: 5/ for test: syncobj_wait
results: parsing output: 6/ for test: kms_plane_scaling
results: parsing output: 7/ for test: kms_lease
results: parsing output: 8/ for test: gem_lmem_swapping
results: parsing output: 9/ for test: gem_ctx_bad_destroy
results: parsing output: 10/ for test: kms_frontbuffer_tracking
results: parsing output: 11/ for test: syncobj_wait
results: parsing output: 12/ for test: kms_psr_stress_test
results: parsing output: 13/ for test: kms_frontbuffer_tracking
results: parsing output: 14/ for test: kms_ccs
results: parsing output: 15/ for test: gem_softpin
results: parsing output: 16/ for test: gem_ctx_param
results: parsing output: 17/ for test: kms_big_fb
results: parsing output: 18/ for test: syncobj_timeline
results: parsing output: 19/ for test: kms_big_fb
results: parsing output: 20/ for test: api_intel_bb
results: parsing output: 21/ for test: gem_userptr_blits
results: parsing output: 22/ for test: kms_frontbuffer_tracking
results: parsing output: 23/ for test: kms_ccs
results: parsing output: 24/ for test: i915_query
results: parsing output: 25/ for test: kms_frontbuffer_tracking
results: parsing output: 26/ for test: gem_cs_tlb
results: parsing output: 27/ for test: kms_psr2_sf
results: parsing output: 28/ for test: kms_frontbuffer_tracking
results: parsing output: 29/ for test: kms_joiner
results: parsing output: 30/ for test: kms_cursor_crc
results: parsing output: 31/ for test: gem_lmem_swapping
results: parsing output: 32/ for test: kms_selftest
results: parsing output: 33/ for test: sw_sync
results: parsing output: 34/ for test: kms_frontbuffer_tracking
results: parsing output: 35/ for test: gem_ctx_persistence
results: parsing output: 36/ for test: kms_cursor_crc
results: parsing output: 37/ for test: gem_exec_schedule
results: parsing output: 38/ for test: kms_frontbuffer_tracking
results: parsing output: 39/ for test: gen9_exec_parse
results: parsing output: 40/ for test: gem_busy
results: parsing output: 41/ for test: gem_exec_balancer
results: parsing output: 42/ for test: kms_frontbuffer_tracking
results: parsing output: 43/ for test: i915_selftest
results: parsing output: 44/ for test: gem_ctx_shared
results: parsing output: 45/ for test: perf_pmu
results: parsing output: 46/ for test: gem_render_copy
results: parsing output: 47/ for test: kms_psr
results: parsing output: 48/ for test: kms_flip_scaled_crc
results: parsing output: 49/ for test: kms_ccs
results: parsing output: 50/ for test: gem_workarounds
results: parsing output: 51/ for test: syncobj_timeline
results: parsing output: 52/ for test: syncobj_timeline
results: parsing output: 53/ for test: kms_frontbuffer_tracking
results: parsing output: 54/ for test: gem_basic
results: parsing output: 55/ for test: kms_big_fb
results: parsing output: 56/ for test: kms_flip_scaled_crc
results: parsing output: 57/ for test: sysfs_heartbeat_interval
results: parsing output: 58/ for test: kms_frontbuffer_tracking
results: parsing output: 59/ for test: kms_cursor_legacy
results: parsing output: 60/ for test: gem_exec_fence
results: parsing output: 61/ for test: kms_flip
results: parsing output: 62/ for test: gem_mmap_gtt
results: parsing output: 63/ for test: kms_frontbuffer_tracking
results: parsing output: 64/ for test: kms_properties
results: parsing output: 65/ for test: device_reset
results: parsing output: 66/ for test: kms_plane
results: parsing output: 67/ for test: syncobj_timeline
results: parsing output: 68/ for test: kms_frontbuffer_tracking
results: parsing output: 69/ for test: kms_chamelium_hpd
results: parsing output: 70/ for test: kms_plane_cursor
results: parsing output: 71/ for test: kms_big_fb
results: parsing output: 72/ for test: i915_pciid
results: parsing output: 73/ for test: gem_watchdog
results: parsing output: 74/ for test: kms_addfb_basic
results: parsing output: 75/ for test: gem_exec_schedule
results: parsing output: 76/ for test: gen9_exec_parse
results: parsing output: 77/ for test: gem_exec_params
results: parsing output: 78/ for test: gem_sync
results: parsing output: 79/ for test: gem_mmap_gtt
results: parsing output: 80/ for test: kms_draw_crc
results: parsing output: 81/ for test: i915_pm_rc6_residency
results: parsing output: 82/ for test: kms_atomic
results: parsing output: 83/ for test: kms_flip
results: parsing output: 84/ for test: kms_frontbuffer_tracking
results: parsing output: 85/ for test: gem_pxp
results: parsing output: 86/ for test: kms_frontbuffer_tracking
results: parsing output: 87/ for test: kms_frontbuffer_tracking
results: parsing output: 88/ for test: kms_frontbuffer_tracking
results: parsing output: 89/ for test: kms_frontbuffer_tracking
results: parsing output: 90/ for test: kms_plane
results: parsing output: 91/ for test: gem_exec_gttfill
results: parsing output: 92/ for test: kms_big_fb
results: parsing output: 93/ for test: kms_frontbuffer_tracking
results: parsing output: 94/ for test: perf_pmu
results: parsing output: 95/ for test: syncobj_wait
results: parsing output: 96/ for test: kms_properties
results: parsing output: 97/ for test: gem_fence_thrash
results: parsing output: 98/ for test: kms_color
results: parsing output: 99/ for test: kms_psr
results: parsing output: 100/ for test: kms_fbcon_fbt
results: parsing output: 101/ for test: kms_pipe_crc_basic
results: parsing output: 102/ for test: kms_hdmi_inject
results: parsing output: 103/ for test: kms_psr
results: parsing output: 104/ for test: kms_psr
results: parsing output: 105/ for test: kms_frontbuffer_tracking
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: kms_frontbuffer_tracking
results: parsing output: 108/ for test: kms_big_fb
results: parsing output: 109/ for test: kms_cursor_crc
results: parsing output: 110/ for test: gem_eio
results: parsing output: 111/ for test: gem_eio
results: parsing output: 112/ for test: kms_pm_rpm
results: parsing output: 113/ for test: kms_big_fb
results: parsing output: 114/ for test: kms_flip
results: parsing output: 115/ for test: perf
results: parsing output: 116/ for test: kms_chamelium_edid
results: parsing output: 117/ for test: gem_exec_schedule
results: parsing output: 118/ for test: syncobj_wait
results: parsing output: 119/ for test: kms_psr
results: parsing output: 120/ for test: prime_vgem
results: parsing output: 121/ for test: api_intel_allocator
results: parsing output: 122/ for test: kms_frontbuffer_tracking
results: parsing output: 123/ for test: prime_mmap_coherency
results: parsing output: 124/ for test: kms_flip
results: parsing output: 125/ for test: kms_cdclk
results: parsing output: 126/ for test: kms_content_protection
results: parsing output: 127/ for test: gem_render_copy
results: parsing output: 128/ for test: gem_exec_fence
results: parsing output: 129/ for test: kms_debugfs
results: parsing output: 130/ for test: tools_test
results: parsing output: 131/ for test: kms_frontbuffer_tracking
results: parsing output: 132/ for test: syncobj_timeline
Done.