[57.306868] Initializing watchdogs
[57.307019]   /dev/watchdog0
[57.323220] [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]
[57.327572] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.329926] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.333366] [001/156] (960s left) gen9_exec_parse (batch-without-end)
[57.416117] Subtest batch-without-end: SKIP (0.000s)
[57.461627] [002/156] (960s left) kms_psr2_sf (fbc-psr2-overlay-primary-update-sf-dmg-area)
[57.568079] Starting subtest: fbc-psr2-overlay-primary-update-sf-dmg-area
[57.570412] Starting dynamic subtest: pipe-A-eDP-1
[57.740319] Dynamic subtest pipe-A-eDP-1: SKIP (0.179s)
[57.750932] Starting dynamic subtest: pipe-B-eDP-1
[58.928588] Dynamic subtest pipe-B-eDP-1: SKIP (1.202s)
[58.933403] Subtest fbc-psr2-overlay-primary-update-sf-dmg-area: SKIP (1.383s)
[60.215849] [003/156] (957s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-mmap-gtt)
[60.311042] Starting subtest: fbcpsr-rgb101010-draw-mmap-gtt
[60.315334] Subtest fbcpsr-rgb101010-draw-mmap-gtt: SKIP (0.000s)
[60.409189] [004/156] (957s left) kms_chamelium_color (ctm-limited-range)
[60.501991] Subtest ctm-limited-range: SKIP (0.000s)
[60.544761] [005/156] (957s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0-hflip)
[60.654072] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0-hflip
[60.658078] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0-hflip: SKIP (0.000s)
[60.750914] [006/156] (957s left) kms_vblank (query-forked)
[60.846052] Starting subtest: query-forked
[60.847997] Starting dynamic subtest: pipe-A-eDP-1
[62.655511] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.824s)
[62.668946] Starting dynamic subtest: pipe-D-eDP-1
[65.180970] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.539s)
[65.182925] Subtest query-forked: SUCCESS (4.365s)
[66.025111] [007/156] (952s left) kms_big_fb (x-tiled-64bpp-rotate-0)
[66.125924] Starting subtest: x-tiled-64bpp-rotate-0
[67.685474] Subtest x-tiled-64bpp-rotate-0: SUCCESS (1.568s)
[67.846777] [008/156] (950s left) syncobj_timeline (single-wait-all-available-signaled)
[67.900617] Starting subtest: single-wait-all-available-signaled
[67.902762] Subtest single-wait-all-available-signaled: SUCCESS (0.000s)
[67.990828] [009/156] (950s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-render)
[68.096428] Starting subtest: psr-1p-primscrn-pri-indfb-draw-render
[75.360032] Subtest psr-1p-primscrn-pri-indfb-draw-render: SUCCESS (7.284s)
[75.483435] [010/156] (942s left) gem_pxp (display-protected-crc)
[75.617576] Starting subtest: display-protected-crc
[77.230757] Subtest display-protected-crc: SUCCESS (1.618s)
[77.342950] [011/156] (940s left) gem_mmap_wc (write-gtt-read-wc)
[77.393974] Subtest write-gtt-read-wc: SKIP (0.000s)
[77.455241] [012/156] (940s left) kms_atomic_transition (plane-all-transition)
[77.546910] Starting subtest: plane-all-transition
[77.549259] Starting dynamic subtest: pipe-A-eDP-1
[80.039208] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.496s)
[80.344079] Starting dynamic subtest: pipe-B-eDP-1
[83.545435] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.212s)
[83.847129] Subtest plane-all-transition: SUCCESS (6.305s)
[84.675708] [013/156] (933s left) kms_frontbuffer_tracking (psrhdr-rgb101010-draw-blt)
[84.777200] Starting subtest: psrhdr-rgb101010-draw-blt
[84.781548] Subtest psrhdr-rgb101010-draw-blt: SKIP (0.000s)
[84.870538] [014/156] (933s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[84.971418] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu
[84.975429] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[85.058308] [015/156] (933s left) kms_getfb (getfb-handle-closed)
[85.144450] Starting subtest: getfb-handle-closed
[85.146692] Subtest getfb-handle-closed: SUCCESS (0.000s)
[85.240723] [016/156] (933s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-indfb-msflip-blt)
[85.339178] Starting subtest: fbcpsrhdr-2p-primscrn-indfb-msflip-blt
[85.343652] Subtest fbcpsrhdr-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[85.456586] [017/156] (932s left) kms_atomic (atomic-plane-damage)
[85.542990] Starting subtest: atomic-plane-damage
[85.587922] Starting dynamic subtest: pipe-A-eDP-1
[86.121487] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.531s)
[86.123893] Subtest atomic-plane-damage: SUCCESS (0.579s)
[86.999970] [018/156] (931s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-cpu)
[87.101110] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-cpu
[87.106085] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[87.177883] [019/156] (931s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-hflip)
[87.269957] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180-hflip
[88.215378] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-hflip: SUCCESS (0.945s)
[88.333976] [020/156] (930s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-spr-indfb-draw-render)
[88.433665] Starting subtest: psrhdr-1p-primscrn-spr-indfb-draw-render
[88.437760] Subtest psrhdr-1p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[88.545805] [021/156] (930s left) gem_exec_params (batch-first)
[88.608097] Starting subtest: batch-first
[88.613710] Subtest batch-first: SUCCESS (0.006s)
[88.739418] [022/156] (929s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-blt)
[88.834381] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-blt
[96.084423] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-blt: SUCCESS (7.250s)
[96.224895] [023/156] (922s left) kms_plane_scaling (planes-upscale-factor-0-25)
[96.325260] Starting subtest: planes-upscale-factor-0-25
[96.327519] Starting dynamic subtest: pipe-A
[96.386375] Dynamic subtest pipe-A: SUCCESS (0.062s)
[96.397117] Starting dynamic subtest: pipe-B
[97.569275] Dynamic subtest pipe-B: SUCCESS (1.181s)
[97.580496] Starting dynamic subtest: pipe-C
[98.679521] Dynamic subtest pipe-C: SUCCESS (1.109s)
[98.688416] Starting dynamic subtest: pipe-D
[99.811642] Dynamic subtest pipe-D: SUCCESS (1.132s)
[99.814082] Subtest planes-upscale-factor-0-25: SUCCESS (3.487s)
[100.972328] [024/156] (917s left) kms_cursor_legacy (2x-flip-vs-cursor-atomic)
[101.088765] Starting subtest: 2x-flip-vs-cursor-atomic
[101.093529] Subtest 2x-flip-vs-cursor-atomic: SKIP (0.001s)
[101.229447] [025/156] (917s left) kms_chamelium_edid (dp-mode-timings)
[101.614299] Subtest dp-mode-timings: SKIP (0.000s)
[102.392981] [026/156] (916s left) kms_psr (psr2-cursor-plane-onoff)
[102.484966] Starting subtest: psr2-cursor-plane-onoff
[102.487277] Starting dynamic subtest: eDP-1
[104.211783] Dynamic subtest eDP-1: SUCCESS (1.725s)
[104.214452] Subtest psr2-cursor-plane-onoff: SUCCESS (1.726s)
[105.105366] [027/156] (913s left) kms_content_protection (lic-type-0-hdcp14)
[105.247894] Starting subtest: lic-type-0-hdcp14
[105.252646] Subtest lic-type-0-hdcp14: SKIP (0.000s)
[105.372549] [028/156] (913s left) kms_rotation_crc (primary-rotation-180)
[105.466346] Starting subtest: primary-rotation-180
[112.391996] Subtest primary-rotation-180: SUCCESS (6.927s)
[113.559636] [029/156] (905s left) kms_frontbuffer_tracking (hdr-1p-primscrn-cur-indfb-draw-mmap-cpu)
[113.652847] Starting subtest: hdr-1p-primscrn-cur-indfb-draw-mmap-cpu
[113.657224] Subtest hdr-1p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[113.731795] [030/156] (905s left) i915_query (query-regions-unallocated)
[113.794515] Starting subtest: query-regions-unallocated
[113.796559] Subtest query-regions-unallocated: SUCCESS (0.000s)
[113.886517] [031/156] (905s left) kms_plane (pixel-format-y-tiled-ccs-modifier)
[113.976275] Starting subtest: pixel-format-y-tiled-ccs-modifier
[113.980696] Subtest pixel-format-y-tiled-ccs-modifier: SKIP (0.000s)
[114.047111] [032/156] (904s left) kms_cursor_crc (cursor-random-64x21)
[114.234064] Starting subtest: cursor-random-64x21
[114.280326] Subtest cursor-random-64x21: SKIP (0.041s)
[114.494524] [033/156] (904s left) kms_cursor_legacy (cursora-vs-flipa-atomic)
[114.601494] Starting subtest: cursorA-vs-flipA-atomic
[125.744822] Subtest cursorA-vs-flipA-atomic: SUCCESS (11.141s)
[126.602832] [034/156] (892s left) kms_vblank (wait-idle-hang)
[126.697058] Starting subtest: wait-idle-hang
[126.699332] Starting dynamic subtest: pipe-A-eDP-1
[153.041948] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.343s)
[153.054718] Starting dynamic subtest: pipe-D-eDP-1
[178.045738] Dynamic subtest pipe-D-eDP-1: SUCCESS (24.999s)
[178.048051] Subtest wait-idle-hang: SUCCESS (51.346s)
[178.862289] [035/156] (840s left) gen7_exec_parse (basic-offset)
[178.926218] Subtest basic-offset: SKIP (0.000s)
[178.989674] [036/156] (840s left) kms_color_pipeline (plane-lut1d-ctm3x4-lut1d)
[179.082043] Starting subtest: plane-lut1d-ctm3x4-lut1d
[179.205292] Starting dynamic subtest: pipe-A-plane-0
[179.349763] Dynamic subtest pipe-A-plane-0: SUCCESS (0.144s)
[179.357901] Starting dynamic subtest: pipe-A-plane-1
[179.483091] Dynamic subtest pipe-A-plane-1: SUCCESS (0.133s)
[179.491505] Starting dynamic subtest: pipe-A-plane-2
[179.616589] Dynamic subtest pipe-A-plane-2: SUCCESS (0.133s)
[180.897598] Starting dynamic subtest: pipe-B-plane-0
[181.035223] Dynamic subtest pipe-B-plane-0: SUCCESS (0.144s)
[181.044072] Starting dynamic subtest: pipe-B-plane-1
[181.179956] Dynamic subtest pipe-B-plane-1: SUCCESS (0.144s)
[181.188467] Starting dynamic subtest: pipe-B-plane-2
[181.312635] Dynamic subtest pipe-B-plane-2: SUCCESS (0.133s)
[182.493906] Starting dynamic subtest: pipe-C-plane-0
[182.643167] Dynamic subtest pipe-C-plane-0: SUCCESS (0.155s)
[182.652113] Starting dynamic subtest: pipe-C-plane-1
[182.776126] Dynamic subtest pipe-C-plane-1: SUCCESS (0.133s)
[182.784872] Starting dynamic subtest: pipe-C-plane-2
[182.909798] Dynamic subtest pipe-C-plane-2: SUCCESS (0.133s)
[184.121987] Starting dynamic subtest: pipe-D-plane-0
[184.259205] Dynamic subtest pipe-D-plane-0: SUCCESS (0.144s)
[184.267679] Starting dynamic subtest: pipe-D-plane-1
[184.392692] Dynamic subtest pipe-D-plane-1: SUCCESS (0.133s)
[184.402454] Starting dynamic subtest: pipe-D-plane-2
[184.526215] Dynamic subtest pipe-D-plane-2: SUCCESS (0.133s)
[184.817958] Subtest plane-lut1d-ctm3x4-lut1d: SUCCESS (5.738s)
[185.696469] [037/156] (833s left) kms_ccs (crc-primary-basic-4-tiled-mtl-rc-ccs)
[185.793279] Starting subtest: crc-primary-basic-4-tiled-mtl-rc-ccs
[185.795651] Starting dynamic subtest: pipe-A-eDP-1
[186.614792] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.813s)
[186.623549] Starting dynamic subtest: pipe-B-eDP-1
[188.178639] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.564s)
[188.189336] Starting dynamic subtest: pipe-C-eDP-1
[189.725083] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.543s)
[189.734395] Starting dynamic subtest: pipe-D-eDP-1
[191.253043] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.528s)
[191.254949] Subtest crc-primary-basic-4-tiled-mtl-rc-ccs: SUCCESS (5.452s)
[192.439183] [038/156] (826s left) gem_ctx_param (invalid-ctx-get)
[192.495101] Starting subtest: invalid-ctx-get
[192.497538] Subtest invalid-ctx-get: SUCCESS (0.000s)
[192.567370] [039/156] (826s left) gem_exec_schedule (preempt-queue-contexts-chain)
[192.628669] Subtest preempt-queue-contexts-chain: SKIP (0.000s)
[192.924751] [040/156] (826s left) kms_joiner (basic-max-non-joiner)
[193.024774] Starting subtest: basic-max-non-joiner
[193.029166] Subtest basic-max-non-joiner: SKIP (0.000s)
[193.106707] [041/156] (826s left) gem_ctx_persistence (saturated-hostile)
[193.157605] Starting subtest: saturated-hostile
[193.160051] Starting dynamic subtest: rcs0
[193.964065] Dynamic subtest rcs0: SUCCESS (0.805s)
[193.975402] Starting dynamic subtest: bcs0
[194.755039] Dynamic subtest bcs0: SUCCESS (0.789s)
[194.766740] Starting dynamic subtest: vcs0
[195.533699] Dynamic subtest vcs0: SUCCESS (0.776s)
[195.549150] Starting dynamic subtest: vcs1
[196.328523] Dynamic subtest vcs1: SUCCESS (0.793s)
[196.339870] Starting dynamic subtest: vecs0
[197.118568] Dynamic subtest vecs0: SUCCESS (0.788s)
[197.130320] Starting dynamic subtest: ccs0
[197.946600] Dynamic subtest ccs0: SUCCESS (0.826s)
[197.949642] Subtest saturated-hostile: SUCCESS (4.789s)
[198.027373] [042/156] (821s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-cur-indfb-onoff)
[198.128097] Starting subtest: psrhdr-1p-primscrn-cur-indfb-onoff
[198.132185] Subtest psrhdr-1p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[198.222676] [043/156] (821s left) gem_render_copy (x-tiled-to-vebox-yf-tiled)
[198.284413] Starting subtest: x-tiled-to-vebox-yf-tiled
[198.289324] Subtest x-tiled-to-vebox-yf-tiled: SKIP (0.000s)
[198.476155] [044/156] (821s left) gem_lmem_swapping (parallel-multi)
[198.517799] Subtest parallel-multi: SKIP (0.000s)
[198.560729] [045/156] (820s left) i915_hangman (engine-engine-hang)
[198.621229] Starting subtest: engine-engine-hang
[198.623212] Starting dynamic subtest: rcs0
[200.284488] Dynamic subtest rcs0: SUCCESS (1.663s)
[200.296070] Starting dynamic subtest: bcs0
[202.266048] Dynamic subtest bcs0: SUCCESS (1.979s)
[202.277838] Starting dynamic subtest: vcs0
[204.250945] Dynamic subtest vcs0: SUCCESS (1.982s)
[204.262668] Starting dynamic subtest: vcs1
[206.234331] Dynamic subtest vcs1: SUCCESS (1.980s)
[206.245445] Starting dynamic subtest: vecs0
[208.282833] Dynamic subtest vecs0: SUCCESS (2.045s)
[208.294921] Starting dynamic subtest: ccs0
[210.265071] Dynamic subtest ccs0: SUCCESS (1.980s)
[210.268261] Subtest engine-engine-hang: SUCCESS (11.646s)
[210.343565] [046/156] (809s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-mmap-wc)
[210.449489] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-mmap-wc
[217.075553] Subtest psr-1p-offscreen-pri-shrfb-draw-mmap-wc: SUCCESS (6.630s)
[217.185695] [047/156] (802s left) kms_frontbuffer_tracking (hdr-1p-offscreen-pri-shrfb-draw-pwrite)
[217.279645] Starting subtest: hdr-1p-offscreen-pri-shrfb-draw-pwrite
[217.284032] Subtest hdr-1p-offscreen-pri-shrfb-draw-pwrite: SKIP (0.000s)
[217.378921] [048/156] (802s left) kms_cursor_crc (cursor-sliding-512x170)
[217.660857] Starting subtest: cursor-sliding-512x170
[217.665461] Subtest cursor-sliding-512x170: SKIP (0.001s)
[217.811215] [049/156] (801s left) i915_pm_rpm (sysfs-read)
[219.431956] Starting subtest: sysfs-read
[220.596808] Subtest sysfs-read: SUCCESS (1.161s)
[220.999661] [050/156] (798s left) kms_async_flips (crc)
[221.090526] Starting subtest: crc
[221.463773] Starting dynamic subtest: pipe-A-eDP-1
[224.394852] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.929s)
[224.769282] Starting dynamic subtest: pipe-B-eDP-1
[227.728872] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.966s)
[228.118023] Starting dynamic subtest: pipe-C-eDP-1
[231.032218] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.924s)
[231.394704] Starting dynamic subtest: pipe-D-eDP-1
[234.264480] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.878s)
[234.266870] Subtest crc: SUCCESS (13.177s)
[235.440676] [051/156] (784s left) kms_ccs (missing-ccs-buffer-y-tiled-gen12-mc-ccs)
[235.532408] Starting subtest: missing-ccs-buffer-y-tiled-gen12-mc-ccs
[235.534697] Starting dynamic subtest: pipe-A-eDP-1
[235.557456] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[235.565966] Starting dynamic subtest: pipe-B-eDP-1
[235.586987] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[235.594971] Starting dynamic subtest: pipe-C-eDP-1
[235.615602] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[235.623013] Starting dynamic subtest: pipe-D-eDP-1
[235.643837] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[235.648097] Subtest missing-ccs-buffer-y-tiled-gen12-mc-ccs: SKIP (0.004s)
[237.800415] [052/156] (781s left) gem_flink_basic (double-flink)
[237.857552] Starting subtest: double-flink
[237.859979] Subtest double-flink: SUCCESS (0.000s)
[237.963588] [053/156] (781s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[238.063321] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[238.865117] Subtest x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SUCCESS (0.804s)
[239.026288] [054/156] (780s left) kms_flip (2x-flip-vs-dpms-on-nop)
[239.126136] Starting subtest: 2x-flip-vs-dpms-on-nop
[239.134188] Subtest 2x-flip-vs-dpms-on-nop: SKIP (0.010s)
[239.222131] [055/156] (780s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[239.319392] Starting subtest: compare-crc-sanitycheck-nv12
[239.321841] Starting dynamic subtest: pipe-A-eDP-1
[239.960212] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.638s)
[239.969843] Starting dynamic subtest: pipe-B-eDP-1
[241.291579] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.329s)
[241.300320] Starting dynamic subtest: pipe-C-eDP-1
[242.557316] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.264s)
[242.565730] Starting dynamic subtest: pipe-D-eDP-1
[243.781129] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.223s)
[243.783023] Subtest compare-crc-sanitycheck-nv12: SUCCESS (4.460s)
[244.627198] [056/156] (775s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-pri-shrfb-draw-pwrite)
[244.731524] Starting subtest: hdr-2p-scndscrn-pri-shrfb-draw-pwrite
[244.736100] Subtest hdr-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[244.834866] [057/156] (775s left) api_intel_bb (destroy-bb)
[244.886974] Starting subtest: destroy-bb
[244.896939] Subtest destroy-bb: SUCCESS (0.008s)
[245.016838] [058/156] (775s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-pwrite)
[245.110068] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-pwrite
[245.114267] Subtest psr-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[245.198821] [059/156] (774s left) kms_cursor_legacy (cursorb-vs-flipb-atomic-transitions-varying-size)
[245.310439] Starting subtest: cursorB-vs-flipB-atomic-transitions-varying-size
[245.314872] Subtest cursorB-vs-flipB-atomic-transitions-varying-size: SKIP (0.001s)
[245.466160] [060/156] (774s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-pwrite)
[245.557567] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-pwrite
[254.290972] Subtest psr-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (8.737s)
[254.392890] [061/156] (765s left) syncobj_timeline (etime-single-wait-all-for-submit-available-unsubmitted)
[254.445695] Starting subtest: etime-single-wait-all-for-submit-available-unsubmitted
[254.552676] Subtest etime-single-wait-all-for-submit-available-unsubmitted: SUCCESS (0.106s)
[254.625570] [062/156] (765s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-pri-shrfb-draw-mmap-wc)
[254.721841] Starting subtest: fbchdr-2p-primscrn-pri-shrfb-draw-mmap-wc
[254.726301] Subtest fbchdr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[254.811457] [063/156] (765s left) gem_ctx_persistence (engines-mixed-process)
[254.872250] Starting subtest: engines-mixed-process
[254.874621] Starting dynamic subtest: rcs0
[256.340806] Dynamic subtest rcs0: SUCCESS (1.468s)
[256.350819] Starting dynamic subtest: bcs0
[258.286226] Dynamic subtest bcs0: SUCCESS (1.941s)
[258.294655] Starting dynamic subtest: vcs0
[260.301939] Dynamic subtest vcs0: SUCCESS (2.017s)
[260.312978] Starting dynamic subtest: vcs1
[262.277814] Dynamic subtest vcs1: SUCCESS (1.974s)
[262.288693] Starting dynamic subtest: vecs0
[264.330667] Dynamic subtest vecs0: SUCCESS (2.051s)
[264.341057] Starting dynamic subtest: ccs0
[266.282449] Dynamic subtest ccs0: SUCCESS (1.949s)
[266.284744] Subtest engines-mixed-process: SUCCESS (11.409s)
[266.384427] [064/156] (753s left) gem_exec_whisper (basic-fds-all)
[266.447183] Starting subtest: basic-fds-all
[278.909025] Subtest basic-fds-all: SUCCESS (12.464s)
[279.092371] [065/156] (741s left) kms_big_fb (y-tiled-32bpp-rotate-180)
[279.199304] Starting subtest: y-tiled-32bpp-rotate-180
[279.203573] Subtest y-tiled-32bpp-rotate-180: SKIP (0.000s)
[279.295335] [066/156] (741s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[279.399578] Starting subtest: fbcpsrhdr-2p-primscrn-pri-indfb-draw-mmap-cpu
[279.403809] Subtest fbcpsrhdr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[279.499990] [067/156] (740s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-pri-indfb-multidraw)
[279.600723] Starting subtest: fbcpsrhdr-1p-pri-indfb-multidraw
[279.604787] Subtest fbcpsrhdr-1p-pri-indfb-multidraw: SKIP (0.000s)
[279.690420] [068/156] (740s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[279.784700] Starting subtest: psrhdr-2p-primscrn-pri-indfb-draw-mmap-cpu
[279.788940] Subtest psrhdr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[279.889689] [069/156] (740s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-32bpp-yftile-downscaling)
[279.974210] Starting subtest: flip-64bpp-yftile-to-32bpp-yftile-downscaling
[279.978585] Subtest flip-64bpp-yftile-to-32bpp-yftile-downscaling: SKIP (0.000s)
[280.041818] [070/156] (740s left) kms_flip (flip-vs-panning-interruptible)
[280.125875] Starting subtest: flip-vs-panning-interruptible
[280.127542] Starting dynamic subtest: A-eDP1
[288.855253] Dynamic subtest A-eDP1: SUCCESS (8.728s)
[288.871883] Starting dynamic subtest: B-eDP1
[297.581323] Dynamic subtest B-eDP1: SUCCESS (8.724s)
[297.592701] Starting dynamic subtest: C-eDP1
[306.293823] Dynamic subtest C-eDP1: SUCCESS (8.712s)
[306.304534] Starting dynamic subtest: D-eDP1
[315.020690] Dynamic subtest D-eDP1: SUCCESS (8.727s)
[315.024905] Subtest flip-vs-panning-interruptible: SUCCESS (34.901s)
[316.181427] [071/156] (704s left) prime_vgem (busy)
[316.331090] Starting subtest: busy
[316.332917] Starting dynamic subtest: rcs0
[316.385491] Dynamic subtest rcs0: SUCCESS (0.056s)
[316.393850] Starting dynamic subtest: bcs0
[316.430062] Dynamic subtest bcs0: SUCCESS (0.044s)
[316.438622] Starting dynamic subtest: vcs0
[316.477450] Dynamic subtest vcs0: SUCCESS (0.047s)
[316.486055] Starting dynamic subtest: vcs1
[316.522294] Dynamic subtest vcs1: SUCCESS (0.044s)
[316.530744] Starting dynamic subtest: vecs0
[316.574071] Dynamic subtest vecs0: SUCCESS (0.051s)
[316.582604] Starting dynamic subtest: ccs0
[316.617338] Dynamic subtest ccs0: SUCCESS (0.042s)
[316.619493] Subtest busy: SUCCESS (0.289s)
[316.747249] [FACT prime_vgem (busy)] new: kernel.kmod_is_loaded.vgem: true
[316.751055] [072/156] (703s left) gem_exec_fence (submit3)
[316.933893] Starting subtest: submit3
[316.938165] Subtest submit3: SKIP (0.000s)
[318.345500] [073/156] (702s left) kms_flip (single-buffer-flip-vs-dpms-off-vs-modeset)
[318.435733] Starting subtest: single-buffer-flip-vs-dpms-off-vs-modeset
[318.438199] Starting dynamic subtest: A-eDP1
[322.014970] Dynamic subtest A-eDP1: SUCCESS (3.576s)
[322.023765] Starting dynamic subtest: B-eDP1
[325.224365] Dynamic subtest B-eDP1: SUCCESS (3.211s)
[325.233975] Starting dynamic subtest: C-eDP1
[328.770863] Dynamic subtest C-eDP1: SUCCESS (3.545s)
[328.781538] Starting dynamic subtest: D-eDP1
[331.976820] Dynamic subtest D-eDP1: SUCCESS (3.205s)
[331.979214] Subtest single-buffer-flip-vs-dpms-off-vs-modeset: SUCCESS (13.545s)
[332.795861] [074/156] (687s left) kms_properties (crtc-properties-atomic)
[332.893669] Starting subtest: crtc-properties-atomic
[332.895528] Starting dynamic subtest: pipe-A-eDP-1
[333.331129] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.436s)
[333.339979] Starting dynamic subtest: pipe-B-eDP-1
[334.551913] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.221s)
[334.562497] Starting dynamic subtest: pipe-C-eDP-1
[335.681836] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.128s)
[335.692214] Starting dynamic subtest: pipe-D-eDP-1
[336.821073] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.137s)
[336.822967] Subtest crtc-properties-atomic: SUCCESS (3.927s)
[337.629682] [075/156] (683s left) kms_frontbuffer_tracking (fbchdr-rgb565-draw-render)
[337.738393] Starting subtest: fbchdr-rgb565-draw-render
[337.742261] Subtest fbchdr-rgb565-draw-render: SKIP (0.000s)
[337.834567] [076/156] (682s left) kms_feature_discovery (display)
[337.923185] Starting subtest: display
[337.925517] Subtest display: SUCCESS (0.000s)
[337.994144] [077/156] (682s left) perf_pmu (semaphore-wait)
[338.037215] Starting subtest: semaphore-wait
[338.039441] Starting dynamic subtest: rcs0
[338.550221] Dynamic subtest rcs0: SUCCESS (0.513s)
[338.562773] Starting dynamic subtest: bcs0
[339.062703] Dynamic subtest bcs0: SUCCESS (0.511s)
[339.076068] Starting dynamic subtest: vcs0
[339.578366] Dynamic subtest vcs0: SUCCESS (0.511s)
[339.589631] Starting dynamic subtest: vcs1
[340.091560] Dynamic subtest vcs1: SUCCESS (0.510s)
[340.102894] Starting dynamic subtest: vecs0
[340.605218] Dynamic subtest vecs0: SUCCESS (0.510s)
[340.617127] Starting dynamic subtest: ccs0
[341.120601] Dynamic subtest ccs0: SUCCESS (0.512s)
[341.123573] Subtest semaphore-wait: SUCCESS (3.086s)
[342.340520] [078/156] (678s left) kms_frontbuffer_tracking (hdr-rgb565-draw-mmap-wc)
[342.447122] Starting subtest: hdr-rgb565-draw-mmap-wc
[342.451279] Subtest hdr-rgb565-draw-mmap-wc: SKIP (0.000s)
[342.526344] [079/156] (678s left) gem_mmap_gtt (medium-copy)
[342.586587] Subtest medium-copy: SKIP (0.000s)
[342.647655] [080/156] (678s left) gem_render_copy_redux (flink-interruptible)
[342.703027] Starting subtest: flink-interruptible
[343.515044] Subtest flink-interruptible: SUCCESS (0.815s)
[343.658009] [081/156] (677s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-cur-indfb-draw-pwrite)
[343.748531] Starting subtest: fbchdr-1p-primscrn-cur-indfb-draw-pwrite
[343.752179] Subtest fbchdr-1p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[343.855377] [082/156] (677s left) kms_rmfb (close-fd)
[343.946592] Starting subtest: close-fd
[343.948544] Starting dynamic subtest: pipe-A-eDP-1
[344.051205] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.106s)
[344.059844] Starting dynamic subtest: pipe-B-eDP-1
[345.200936] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.146s)
[345.213212] Starting dynamic subtest: pipe-C-eDP-1
[346.421354] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.220s)
[346.431679] Starting dynamic subtest: pipe-D-eDP-1
[347.572227] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.148s)
[347.574551] Subtest close-fd: SUCCESS (3.626s)
[348.779646] [083/156] (672s left) syncobj_timeline (multi-wait-available-submitted)
[348.831709] Starting subtest: multi-wait-available-submitted
[348.833797] Subtest multi-wait-available-submitted: SUCCESS (0.000s)
[348.921174] [084/156] (672s left) gem_eio (in-flight-internal-1us)
[349.127046] Starting subtest: in-flight-internal-1us
[350.341434] Subtest in-flight-internal-1us: SUCCESS (1.214s)
[350.471884] [085/156] (670s left) kms_frontbuffer_tracking (fbc-farfromfence-mmap-gtt)
[350.566858] Starting subtest: fbc-farfromfence-mmap-gtt
[350.571427] Subtest fbc-farfromfence-mmap-gtt: SKIP (0.000s)
[350.647043] [086/156] (670s left) gem_exec_fence (basic-busy)
[350.821859] Starting subtest: basic-busy
[350.824291] Starting dynamic subtest: rcs0
[350.906940] Dynamic subtest rcs0: SUCCESS (0.085s)
[350.917936] Starting dynamic subtest: bcs0
[350.961659] Dynamic subtest bcs0: SUCCESS (0.053s)
[350.971881] Starting dynamic subtest: vcs0
[351.016167] Dynamic subtest vcs0: SUCCESS (0.053s)
[351.025571] Starting dynamic subtest: vcs1
[351.075926] Dynamic subtest vcs1: SUCCESS (0.058s)
[351.086191] Starting dynamic subtest: vecs0
[351.130610] Dynamic subtest vecs0: SUCCESS (0.052s)
[351.146182] Starting dynamic subtest: ccs0
[351.183796] Dynamic subtest ccs0: SUCCESS (0.052s)
[351.186787] Subtest basic-busy: SUCCESS (0.363s)
[353.331997] [087/156] (667s left) gem_tiled_partial_pwrite_pread (reads)
[353.387268] Subtest reads: SKIP (0.000s)
[353.463644] [088/156] (667s left) kms_psr (fbc-psr-sprite-blt)
[353.562995] Starting subtest: fbc-psr-sprite-blt
[353.565433] Starting dynamic subtest: eDP-1
[353.569844] Dynamic subtest eDP-1: SKIP (0.000s)
[353.574233] Subtest fbc-psr-sprite-blt: SKIP (0.001s)
[353.651034] [089/156] (667s left) gem_softpin (allocator-evict)
[353.721771] Starting subtest: allocator-evict
[353.724168] Starting dynamic subtest: rcs0
[377.467760] Dynamic subtest rcs0: SUCCESS (23.748s)
[377.477705] Starting dynamic subtest: bcs0
[401.364542] Dynamic subtest bcs0: SUCCESS (23.895s)
[401.377176] Starting dynamic subtest: vcs0
[425.213574] Dynamic subtest vcs0: SUCCESS (23.848s)
[425.223684] Starting dynamic subtest: vcs1
[448.963773] Dynamic subtest vcs1: SUCCESS (23.748s)
[448.974344] Starting dynamic subtest: vecs0
[472.652852] Dynamic subtest vecs0: SUCCESS (23.687s)
[472.663890] Starting dynamic subtest: ccs0
[496.725287] Dynamic subtest ccs0: SUCCESS (24.070s)
[496.727635] Subtest allocator-evict: SUCCESS (143.003s)
[496.806812] [090/156] (524s left) gem_userptr_blits (relocations)
[496.909183] Starting subtest: relocations
[496.913529] Subtest relocations: SKIP (0.001s)
[497.035575] [091/156] (524s left) kms_psr2_sf (fbc-pr-overlay-plane-move-continuous-sf)
[497.148879] Starting subtest: fbc-pr-overlay-plane-move-continuous-sf
[497.152930] Subtest fbc-pr-overlay-plane-move-continuous-sf: SKIP (0.000s)
[497.218315] [092/156] (524s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-mc-ccs)
[497.319371] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-mc-ccs
[497.321284] Starting dynamic subtest: pipe-A-eDP-1
[499.452205] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.135s)
[499.462094] Starting dynamic subtest: pipe-B-eDP-1
[501.561483] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.108s)
[501.571205] Starting dynamic subtest: pipe-C-eDP-1
[503.596356] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.033s)
[503.606724] Starting dynamic subtest: pipe-D-eDP-1
[505.629666] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.029s)
[505.631969] Subtest crc-primary-rotation-180-4-tiled-mtl-mc-ccs: SUCCESS (8.310s)
[506.796411] [093/156] (514s left) kms_rotation_crc (primary-y-tiled-reflect-x-180)
[506.887396] Starting subtest: primary-y-tiled-reflect-x-180
[506.946302] Subtest primary-y-tiled-reflect-x-180: SKIP (0.059s)
[507.050993] [094/156] (514s left) gem_eio (context-create)
[507.273551] Starting subtest: context-create
[507.606708] Subtest context-create: SUCCESS (0.333s)
[507.732125] [095/156] (513s left) gem_exec_schedule (u-independent)
[507.800274] Starting subtest: u-independent
[507.801922] Starting dynamic subtest: rcs0
[509.243480] Dynamic subtest rcs0: SUCCESS (1.445s)
[509.258992] Starting dynamic subtest: vcs0
[511.304629] Dynamic subtest vcs0: SUCCESS (2.058s)
[511.316677] Starting dynamic subtest: vcs1
[513.285100] Dynamic subtest vcs1: SUCCESS (1.978s)
[513.300579] Starting dynamic subtest: vecs0
[515.269832] Dynamic subtest vecs0: SUCCESS (1.982s)
[515.283062] Starting dynamic subtest: ccs0
[517.307900] Dynamic subtest ccs0: SUCCESS (2.036s)
[517.310714] Subtest u-independent: SUCCESS (9.511s)
[517.611452] [096/156] (503s left) kms_psr (psr-primary-mmap-gtt)
[517.704771] Starting subtest: psr-primary-mmap-gtt
[517.707037] Starting dynamic subtest: eDP-1
[519.188029] Dynamic subtest eDP-1: SKIP (1.479s)
[519.193173] Subtest psr-primary-mmap-gtt: SKIP (1.482s)
[519.328021] [097/156] (502s left) sysfs_timeslice_duration (duration)
[519.381022] Starting subtest: duration
[519.383421] Starting dynamic subtest: vecs0
[534.237837] Dynamic subtest vecs0: SUCCESS (14.853s)
[534.251417] Starting dynamic subtest: ccs0
[547.390028] Dynamic subtest ccs0: SUCCESS (13.151s)
[547.402933] Starting dynamic subtest: vcs0
[561.177248] Dynamic subtest vcs0: SUCCESS (13.783s)
[561.190407] Starting dynamic subtest: bcs0
[574.328996] Dynamic subtest bcs0: SUCCESS (13.150s)
[574.342117] Starting dynamic subtest: rcs0
[587.479413] Dynamic subtest rcs0: SUCCESS (13.147s)
[587.492187] Starting dynamic subtest: vcs1
[601.177846] Dynamic subtest vcs1: SUCCESS (13.694s)
[601.180718] Subtest duration: SUCCESS (81.793s)
[601.269850] [098/156] (420s left) kms_plane_multiple (2x-tiling-y)
[601.376125] Starting subtest: 2x-tiling-y
[601.380189] Subtest 2x-tiling-y: SKIP (0.000s)
[601.450952] [099/156] (420s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-cur-indfb-draw-blt)
[601.541411] Starting subtest: fbchdr-2p-scndscrn-cur-indfb-draw-blt
[601.545402] Subtest fbchdr-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[601.630878] [100/156] (420s left) kms_frontbuffer_tracking (fbcpsrhdr-rgb565-draw-mmap-wc)
[601.723773] Starting subtest: fbcpsrhdr-rgb565-draw-mmap-wc
[601.728082] Subtest fbcpsrhdr-rgb565-draw-mmap-wc: SKIP (0.000s)
[601.816156] [101/156] (419s left) gem_render_copy (y-tiled)
[601.875286] Starting subtest: y-tiled
[601.879812] Subtest y-tiled: SKIP (0.000s)
[602.058973] [102/156] (419s left) gem_exec_reloc (basic-cpu)
[602.129949] Subtest basic-cpu: SKIP (0.000s)
[602.201535] [103/156] (419s left) gem_exec_balancer (fairslice)
[602.261646] Starting subtest: fairslice
[605.183799] Subtest fairslice: SUCCESS (2.920s)
[605.532239] [104/156] (416s left) gem_eio (in-flight-contexts-1us)
[605.736971] Starting subtest: in-flight-contexts-1us
[619.335196] Subtest in-flight-contexts-1us: SUCCESS (13.597s)
[619.506626] [105/156] (402s left) gem_lmem_swapping (parallel-random-verify-ccs)
[619.551265] Subtest parallel-random-verify-ccs: SKIP (0.000s)
[619.593604] [106/156] (402s left) kms_prime (basic-crc-hybrid)
[619.675418] Subtest basic-crc-hybrid: SKIP (0.000s)
[619.795039] [107/156] (402s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-indfb-pgflip-blt)
[619.884727] Starting subtest: psrhdr-2p-scndscrn-indfb-pgflip-blt
[619.888893] Subtest psrhdr-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[619.980602] [108/156] (402s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-mmap-wc)
[620.081562] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-mmap-wc
[620.085738] Subtest fbc-2p-scndscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[620.175045] [109/156] (401s left) kms_flip (flip-vs-panning)
[620.269891] Starting subtest: flip-vs-panning
[620.272318] Starting dynamic subtest: A-eDP1
[629.024842] Dynamic subtest A-eDP1: SUCCESS (8.753s)
[629.035032] Starting dynamic subtest: B-eDP1
[637.758097] Dynamic subtest B-eDP1: SUCCESS (8.733s)
[637.767871] Starting dynamic subtest: C-eDP1
[646.468901] Dynamic subtest C-eDP1: SUCCESS (8.709s)
[646.481974] Starting dynamic subtest: D-eDP1
[655.202688] Dynamic subtest D-eDP1: SUCCESS (8.730s)
[655.207174] Subtest flip-vs-panning: SUCCESS (34.937s)
[656.483468] [110/156] (365s left) perf (enable-disable)
[656.623133] Starting subtest: enable-disable
[656.625424] Starting dynamic subtest: 0-rcs0
[658.888277] Dynamic subtest 0-rcs0: SUCCESS (2.264s)
[658.899532] Starting dynamic subtest: 1-vcs0
[663.365036] Dynamic subtest 1-vcs0: SUCCESS (4.476s)
[663.367389] Subtest enable-disable: SUCCESS (6.742s)
[663.416018] [111/156] (358s left) gem_exec_parallel (userptr)
[663.469801] Starting subtest: userptr
[663.472086] Starting dynamic subtest: rcs0
[665.024617] Dynamic subtest rcs0: SUCCESS (1.554s)
[665.037526] Starting dynamic subtest: bcs0
[666.535880] Dynamic subtest bcs0: SUCCESS (1.510s)
[666.549075] Starting dynamic subtest: vcs0
[668.300740] Dynamic subtest vcs0: SUCCESS (1.763s)
[668.312847] Starting dynamic subtest: vcs1
[670.266847] Dynamic subtest vcs1: SUCCESS (1.965s)
[670.279677] Starting dynamic subtest: vecs0
[672.250970] Dynamic subtest vecs0: SUCCESS (1.982s)
[672.261969] Starting dynamic subtest: ccs0
[673.770517] Dynamic subtest ccs0: SUCCESS (1.518s)
[673.773105] Subtest userptr: SUCCESS (10.301s)
[673.962832] [112/156] (348s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-shrfb-plflip-blt)
[674.067085] Starting subtest: fbcpsr-2p-scndscrn-shrfb-plflip-blt
[674.071481] Subtest fbcpsr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[674.165461] [113/156] (348s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-cur-indfb-move)
[674.262768] Starting subtest: fbcpsrhdr-1p-primscrn-cur-indfb-move
[674.267084] Subtest fbcpsrhdr-1p-primscrn-cur-indfb-move: SKIP (0.000s)
[674.352283] [114/156] (347s left) core_setmaster_vs_auth
[674.501191] [115/156] (347s left) kms_pm_rpm (basic-pci-d3-state)
[676.422793] Starting subtest: basic-pci-d3-state
[677.914719] Subtest basic-pci-d3-state: SUCCESS (1.492s)
[678.005168] [116/156] (344s left) kms_chamelium_hpd (dp-hpd-after-suspend)
[678.401732] Subtest dp-hpd-after-suspend: SKIP (0.000s)
[679.190261] [117/156] (343s left) kms_cursor_crc (cursor-offscreen-64x64)
[679.371962] Starting subtest: cursor-offscreen-64x64
[679.374305] Starting dynamic subtest: pipe-A-eDP-1
[681.306553] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.933s)
[681.341884] Starting dynamic subtest: pipe-D-eDP-1
[683.925892] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.592s)
[683.927785] Subtest cursor-offscreen-64x64: SUCCESS (4.554s)
[684.767895] [118/156] (337s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-pri-indfb-draw-pwrite)
[684.867718] Starting subtest: fbchdr-2p-scndscrn-pri-indfb-draw-pwrite
[684.872048] Subtest fbchdr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[684.966477] [119/156] (337s left) kms_cursor_legacy (2x-flip-vs-cursor-legacy)
[685.076288] Starting subtest: 2x-flip-vs-cursor-legacy
[685.080739] Subtest 2x-flip-vs-cursor-legacy: SKIP (0.001s)
[685.220430] [120/156] (337s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-mmap-cpu)
[685.316121] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-mmap-cpu
[688.929593] Subtest fbc-1p-primscrn-cur-indfb-draw-mmap-cpu: SUCCESS (3.618s)
[690.073492] [121/156] (332s left) drm_read (fault-buffer)
[690.234574] Starting subtest: fault-buffer
[690.236687] Subtest fault-buffer: SUCCESS (0.001s)
[690.359732] [122/156] (332s left) sw_sync (alloc_merge_fence)
[690.400287] Starting subtest: alloc_merge_fence
[690.402142] Subtest alloc_merge_fence: SUCCESS (0.000s)
[690.463489] [123/156] (332s left) gem_create (create-size-update)
[690.527149] Starting subtest: create-size-update
[690.529366] Subtest create-size-update: SUCCESS (0.000s)
[690.736017] [124/156] (332s left) kms_plane_scaling (planes-downscale-factor-0-75)
[690.817407] Starting subtest: planes-downscale-factor-0-75
[690.819780] Starting dynamic subtest: pipe-A
[690.838252] Dynamic subtest pipe-A: SKIP (0.017s)
[690.849430] Starting dynamic subtest: pipe-B
[690.867975] Dynamic subtest pipe-B: SKIP (0.030s)
[690.878777] Starting dynamic subtest: pipe-C
[690.899681] Dynamic subtest pipe-C: SKIP (0.031s)
[690.909925] Starting dynamic subtest: pipe-D
[690.930756] Dynamic subtest pipe-D: SKIP (0.030s)
[690.934559] Subtest planes-downscale-factor-0-75: SKIP (0.110s)
[691.058551] [125/156] (331s left) kms_display_modes (extended-mode-basic)
[691.150317] Starting subtest: extended-mode-basic
[691.154368] Subtest extended-mode-basic: SKIP (0.000s)
[691.197553] [126/156] (331s left) kms_frontbuffer_tracking (psr-2p-primscrn-shrfb-plflip-blt)
[691.308542] Starting subtest: psr-2p-primscrn-shrfb-plflip-blt
[691.312844] Subtest psr-2p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[691.401445] [127/156] (331s left) kms_psr2_sf (psr2-primary-plane-update-sf-dmg-area)
[691.503202] Starting subtest: psr2-primary-plane-update-sf-dmg-area
[691.505546] Starting dynamic subtest: pipe-A-eDP-1
[697.362680] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.866s)
[697.372919] Starting dynamic subtest: pipe-B-eDP-1
[704.005417] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.640s)
[704.007870] Subtest psr2-primary-plane-update-sf-dmg-area: SUCCESS (12.508s)
[704.867641] [128/156] (318s left) gem_partial_pwrite_pread (writes-after-reads-display)
[704.919899] Subtest writes-after-reads-display: SKIP (0.000s)
[705.023227] [129/156] (317s left) kms_psr (fbc-psr2-basic)
[705.108969] Starting subtest: fbc-psr2-basic
[705.111213] Starting dynamic subtest: eDP-1
[705.115500] Dynamic subtest eDP-1: SKIP (0.000s)
[705.119644] Subtest fbc-psr2-basic: SKIP (0.001s)
[705.186034] [130/156] (317s left) syncobj_timeline (wait-all-complex)
[705.238844] Starting subtest: wait-all-complex
[705.452231] Subtest wait-all-complex: SUCCESS (0.214s)
[705.529367] [131/156] (317s left) api_intel_allocator (fork-simple-stress-signal)
[705.584518] Starting subtest: fork-simple-stress-signal
[710.983607] Subtest fork-simple-stress-signal: SUCCESS (5.401s)
[711.112702] [132/156] (311s left) gem_mmap_gtt (ptrace)
[711.177631] Subtest ptrace: SKIP (0.000s)
[711.247337] [133/156] (311s left) kms_frontbuffer_tracking (hdr-1p-primscrn-spr-indfb-move)
[711.348670] Starting subtest: hdr-1p-primscrn-spr-indfb-move
[711.352953] Subtest hdr-1p-primscrn-spr-indfb-move: SKIP (0.000s)
[711.471833] [134/156] (311s left) syncobj_timeline (etime-multi-wait-all-submitted)
[711.529609] Starting subtest: etime-multi-wait-all-submitted
[711.629877] Subtest etime-multi-wait-all-submitted: SUCCESS (0.102s)
[711.708128] [135/156] (311s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-move)
[711.808016] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-move
[711.812197] Subtest fbcpsr-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[711.907431] [136/156] (311s left) gem_partial_pwrite_pread (reads-display)
[711.979499] Subtest reads-display: SKIP (0.000s)
[712.077520] [137/156] (311s left) dumb_buffer (create-valid-dumb)
[712.143592] Starting subtest: create-valid-dumb
[712.145525] Subtest create-valid-dumb: SUCCESS (0.000s)
[712.265300] [138/156] (311s left) gem_mmap (short-mmap)
[712.351609] Subtest short-mmap: SKIP (0.000s)
[712.423462] [139/156] (310s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-spr-indfb-draw-mmap-gtt)
[712.536835] Starting subtest: fbchdr-1p-primscrn-spr-indfb-draw-mmap-gtt
[712.540291] Subtest fbchdr-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[712.691416] [140/156] (310s left) gem_ccs (ctrl-surf-copy)
[712.768076] Starting subtest: ctrl-surf-copy
[712.772109] Subtest ctrl-surf-copy: SKIP (0.000s)
[712.873528] [141/156] (310s left) kms_frontbuffer_tracking (hdr-2p-primscrn-pri-shrfb-draw-mmap-wc)
[712.977238] Starting subtest: hdr-2p-primscrn-pri-shrfb-draw-mmap-wc
[712.980548] Subtest hdr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[713.080088] [142/156] (310s left) kms_big_fb (linear-16bpp-rotate-270)
[713.207065] Starting subtest: linear-16bpp-rotate-270
[713.227022] Subtest linear-16bpp-rotate-270: SKIP (0.020s)
[713.368487] [143/156] (310s left) prime_mmap (test_userptr)
[713.422067] Starting subtest: test_userptr
[713.424100] Starting dynamic subtest: test_userptr-smem
[713.426037] Dynamic subtest test_userptr-smem: SUCCESS (0.000s)
[713.427991] Subtest test_userptr: SUCCESS (0.002s)
[713.534211] [144/156] (310s left) perf (blocking-parameterized)
[713.660126] Starting subtest: blocking-parameterized
[733.681544] Subtest blocking-parameterized: SUCCESS (20.019s)
[733.731047] [145/156] (289s left) kms_busy (extended-pageflip-hang-oldfb)
[733.838164] Starting subtest: extended-pageflip-hang-oldfb
[733.840516] Starting dynamic subtest: pipe-A
[734.226819] Dynamic subtest pipe-A: SUCCESS (0.388s)
[734.236879] Starting dynamic subtest: pipe-D
[735.388194] Dynamic subtest pipe-D: SUCCESS (1.158s)
[735.390791] Subtest extended-pageflip-hang-oldfb: SUCCESS (1.550s)
[736.233760] [146/156] (287s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-pri-indfb-draw-pwrite)
[736.334971] Starting subtest: fbcpsrhdr-2p-scndscrn-pri-indfb-draw-pwrite
[736.339299] Subtest fbcpsrhdr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[736.432101] [147/156] (287s left) kms_selftest (drm_dp_mst_helper)
[736.472957] Starting subtest: drm_dp_mst_helper
[736.540494] Starting dynamic subtest: drm_test_dp_mst_calc_pbn_mode
[736.637292] Dynamic subtest drm_test_dp_mst_calc_pbn_mode: SUCCESS (0.098s)
[736.649194] Starting dynamic subtest: drm_test_dp_mst_calc_pbn_div
[736.733691] Dynamic subtest drm_test_dp_mst_calc_pbn_div: SUCCESS (0.096s)
[736.743543] Starting dynamic subtest: drm_test_dp_mst_sideband_msg_req_decode
[736.825609] Dynamic subtest drm_test_dp_mst_sideband_msg_req_decode: SUCCESS (0.090s)
[736.827869] Subtest drm_dp_mst_helper: SUCCESS (0.352s)
[736.969818] [148/156] (286s left) gem_gtt_cpu_tlb
[737.087564] [149/156] (286s left) kms_plane_lowres (tiling-x)
[737.182449] Starting subtest: tiling-x
[737.184337] Starting dynamic subtest: pipe-A-eDP-1
[737.187963] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[737.479984] Starting dynamic subtest: pipe-B-eDP-1
[737.484666] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[737.494791] Starting dynamic subtest: pipe-C-eDP-1
[737.499393] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[737.509209] Starting dynamic subtest: pipe-D-eDP-1
[737.513194] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[737.517245] Subtest tiling-x: SKIP (0.293s)
[738.311893] [150/156] (285s left) gem_render_copy (yf-tiled-to-vebox-yf-tiled)
[738.375841] Starting subtest: yf-tiled-to-vebox-yf-tiled
[738.380491] Subtest yf-tiled-to-vebox-yf-tiled: SKIP (0.000s)
[738.568564] [151/156] (285s left) vgem_basic (dmabuf-mmap)
[738.603731] Starting subtest: dmabuf-mmap
[738.608674] Subtest dmabuf-mmap: SUCCESS (0.009s)
[738.700204] [152/156] (285s left) gen9_exec_parse (unaligned-jump)
[738.749182] Subtest unaligned-jump: SKIP (0.000s)
[738.819466] [153/156] (285s left) perf (oa-exponents)
[738.904954] Starting subtest: oa-exponents
[738.907313] Starting dynamic subtest: 0-rcs0
[740.690642] Dynamic subtest 0-rcs0: SUCCESS (1.783s)
[740.704420] Starting dynamic subtest: 1-vcs0
[742.521299] Dynamic subtest 1-vcs0: SUCCESS (1.830s)
[742.523676] Subtest oa-exponents: SUCCESS (3.616s)
[742.572331] [154/156] (281s left) gem_workarounds (reset-fd)
[742.640724] Starting subtest: reset-fd
[742.821069] Subtest reset-fd: SUCCESS (0.179s)
[742.979419] [155/156] (281s left) kms_ccs (bad-rotation-90-4-tiled-dg2-mc-ccs)
[743.078623] Starting subtest: bad-rotation-90-4-tiled-dg2-mc-ccs
[743.080898] Starting dynamic subtest: pipe-A-eDP-1
[743.105273] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[743.117937] Starting dynamic subtest: pipe-B-eDP-1
[743.139647] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[743.149140] Starting dynamic subtest: pipe-C-eDP-1
[743.170825] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[743.180126] Starting dynamic subtest: pipe-D-eDP-1
[743.201636] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[743.205686] Subtest bad-rotation-90-4-tiled-dg2-mc-ccs: SKIP (0.004s)
[745.330828] [156/156] (278s left) kms_hdr (invalid-metadata-sizes)
[745.415738] Starting subtest: invalid-metadata-sizes
[745.418042] Starting dynamic subtest: pipe-A-eDP-1-XRGB2101010
[745.422143] Dynamic subtest pipe-A-eDP-1-XRGB2101010: SKIP (0.000s)
[745.432801] Starting dynamic subtest: pipe-A-eDP-1-XRGB16161616F
[745.436730] Dynamic subtest pipe-A-eDP-1-XRGB16161616F: SKIP (0.000s)
[745.440697] Subtest invalid-metadata-sizes: SKIP (0.003s)
[745.509659] Closing watchdogs
results: parsing output: 0/ for test: gen9_exec_parse
results: parsing output: 1/ for test: kms_psr2_sf
results: parsing output: 2/ for test: kms_frontbuffer_tracking
results: parsing output: 3/ for test: kms_chamelium_color
results: parsing output: 4/ for test: kms_big_fb
results: parsing output: 5/ for test: kms_vblank
results: parsing output: 6/ for test: kms_big_fb
results: parsing output: 7/ for test: syncobj_timeline
results: parsing output: 8/ for test: kms_frontbuffer_tracking
results: parsing output: 9/ for test: gem_pxp
results: parsing output: 10/ for test: gem_mmap_wc
results: parsing output: 11/ for test: kms_atomic_transition
results: parsing output: 12/ for test: kms_frontbuffer_tracking
results: parsing output: 13/ for test: kms_frontbuffer_tracking
results: parsing output: 14/ for test: kms_getfb
results: parsing output: 15/ for test: kms_frontbuffer_tracking
results: parsing output: 16/ for test: kms_atomic
results: parsing output: 17/ for test: kms_frontbuffer_tracking
results: parsing output: 18/ for test: kms_big_fb
results: parsing output: 19/ for test: kms_frontbuffer_tracking
results: parsing output: 20/ for test: gem_exec_params
results: parsing output: 21/ for test: kms_frontbuffer_tracking
results: parsing output: 22/ for test: kms_plane_scaling
results: parsing output: 23/ for test: kms_cursor_legacy
results: parsing output: 24/ for test: kms_chamelium_edid
results: parsing output: 25/ for test: kms_psr
results: parsing output: 26/ for test: kms_content_protection
results: parsing output: 27/ for test: kms_rotation_crc
results: parsing output: 28/ for test: kms_frontbuffer_tracking
results: parsing output: 29/ for test: i915_query
results: parsing output: 30/ for test: kms_plane
results: parsing output: 31/ for test: kms_cursor_crc
results: parsing output: 32/ for test: kms_cursor_legacy
results: parsing output: 33/ for test: kms_vblank
results: parsing output: 34/ for test: gen7_exec_parse
results: parsing output: 35/ for test: kms_color_pipeline
results: parsing output: 36/ for test: kms_ccs
results: parsing output: 37/ for test: gem_ctx_param
results: parsing output: 38/ for test: gem_exec_schedule
results: parsing output: 39/ for test: kms_joiner
results: parsing output: 40/ for test: gem_ctx_persistence
results: parsing output: 41/ for test: kms_frontbuffer_tracking
results: parsing output: 42/ for test: gem_render_copy
results: parsing output: 43/ for test: gem_lmem_swapping
results: parsing output: 44/ for test: i915_hangman
results: parsing output: 45/ for test: kms_frontbuffer_tracking
results: parsing output: 46/ for test: kms_frontbuffer_tracking
results: parsing output: 47/ for test: kms_cursor_crc
results: parsing output: 48/ for test: i915_pm_rpm
results: parsing output: 49/ for test: kms_async_flips
results: parsing output: 50/ for test: kms_ccs
results: parsing output: 51/ for test: gem_flink_basic
results: parsing output: 52/ for test: kms_big_fb
results: parsing output: 53/ for test: kms_flip
results: parsing output: 54/ for test: kms_pipe_crc_basic
results: parsing output: 55/ for test: kms_frontbuffer_tracking
results: parsing output: 56/ for test: api_intel_bb
results: parsing output: 57/ for test: kms_frontbuffer_tracking
results: parsing output: 58/ for test: kms_cursor_legacy
results: parsing output: 59/ for test: kms_frontbuffer_tracking
results: parsing output: 60/ for test: syncobj_timeline
results: parsing output: 61/ for test: kms_frontbuffer_tracking
results: parsing output: 62/ for test: gem_ctx_persistence
results: parsing output: 63/ for test: gem_exec_whisper
results: parsing output: 64/ for test: kms_big_fb
results: parsing output: 65/ for test: kms_frontbuffer_tracking
results: parsing output: 66/ for test: kms_frontbuffer_tracking
results: parsing output: 67/ for test: kms_frontbuffer_tracking
results: parsing output: 68/ for test: kms_flip_scaled_crc
results: parsing output: 69/ for test: kms_flip
results: parsing output: 70/ for test: prime_vgem
results: parsing output: 71/ for test: gem_exec_fence
results: parsing output: 72/ for test: kms_flip
results: parsing output: 73/ for test: kms_properties
results: parsing output: 74/ for test: kms_frontbuffer_tracking
results: parsing output: 75/ for test: kms_feature_discovery
results: parsing output: 76/ for test: perf_pmu
results: parsing output: 77/ for test: kms_frontbuffer_tracking
results: parsing output: 78/ for test: gem_mmap_gtt
results: parsing output: 79/ for test: gem_render_copy_redux
results: parsing output: 80/ for test: kms_frontbuffer_tracking
results: parsing output: 81/ for test: kms_rmfb
results: parsing output: 82/ for test: syncobj_timeline
results: parsing output: 83/ for test: gem_eio
results: parsing output: 84/ for test: kms_frontbuffer_tracking
results: parsing output: 85/ for test: gem_exec_fence
results: parsing output: 86/ for test: gem_tiled_partial_pwrite_pread
results: parsing output: 87/ for test: kms_psr
results: parsing output: 88/ for test: gem_softpin
results: parsing output: 89/ for test: gem_userptr_blits
results: parsing output: 90/ for test: kms_psr2_sf
results: parsing output: 91/ for test: kms_ccs
results: parsing output: 92/ for test: kms_rotation_crc
results: parsing output: 93/ for test: gem_eio
results: parsing output: 94/ for test: gem_exec_schedule
results: parsing output: 95/ for test: kms_psr
results: parsing output: 96/ for test: sysfs_timeslice_duration
results: parsing output: 97/ for test: kms_plane_multiple
results: parsing output: 98/ for test: kms_frontbuffer_tracking
results: parsing output: 99/ for test: kms_frontbuffer_tracking
results: parsing output: 100/ for test: gem_render_copy
results: parsing output: 101/ for test: gem_exec_reloc
results: parsing output: 102/ for test: gem_exec_balancer
results: parsing output: 103/ for test: gem_eio
results: parsing output: 104/ for test: gem_lmem_swapping
results: parsing output: 105/ for test: kms_prime
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_flip
results: parsing output: 109/ for test: perf
results: parsing output: 110/ for test: gem_exec_parallel
results: parsing output: 111/ for test: kms_frontbuffer_tracking
results: parsing output: 112/ for test: kms_frontbuffer_tracking
results: parsing output: 113/ for test: core_setmaster_vs_auth
results: parsing output: 114/ for test: kms_pm_rpm
results: parsing output: 115/ for test: kms_chamelium_hpd
results: parsing output: 116/ for test: kms_cursor_crc
results: parsing output: 117/ for test: kms_frontbuffer_tracking
results: parsing output: 118/ for test: kms_cursor_legacy
results: parsing output: 119/ for test: kms_frontbuffer_tracking
results: parsing output: 120/ for test: drm_read
results: parsing output: 121/ for test: sw_sync
results: parsing output: 122/ for test: gem_create
results: parsing output: 123/ for test: kms_plane_scaling
results: parsing output: 124/ for test: kms_display_modes
results: parsing output: 125/ for test: kms_frontbuffer_tracking
results: parsing output: 126/ for test: kms_psr2_sf
results: parsing output: 127/ for test: gem_partial_pwrite_pread
results: parsing output: 128/ for test: kms_psr
results: parsing output: 129/ for test: syncobj_timeline
results: parsing output: 130/ for test: api_intel_allocator
results: parsing output: 131/ for test: gem_mmap_gtt
results: parsing output: 132/ for test: kms_frontbuffer_tracking
results: parsing output: 133/ for test: syncobj_timeline
results: parsing output: 134/ for test: kms_frontbuffer_tracking
results: parsing output: 135/ for test: gem_partial_pwrite_pread
results: parsing output: 136/ for test: dumb_buffer
results: parsing output: 137/ for test: gem_mmap
results: parsing output: 138/ for test: kms_frontbuffer_tracking
results: parsing output: 139/ for test: gem_ccs
results: parsing output: 140/ for test: kms_frontbuffer_tracking
results: parsing output: 141/ for test: kms_big_fb
results: parsing output: 142/ for test: prime_mmap
results: parsing output: 143/ for test: perf
results: parsing output: 144/ for test: kms_busy
results: parsing output: 145/ for test: kms_frontbuffer_tracking
results: parsing output: 146/ for test: kms_selftest
results: parsing output: 147/ for test: gem_gtt_cpu_tlb
results: parsing output: 148/ for test: kms_plane_lowres
results: parsing output: 149/ for test: gem_render_copy
results: parsing output: 150/ for test: vgem_basic
results: parsing output: 151/ for test: gen9_exec_parse
results: parsing output: 152/ for test: perf
results: parsing output: 153/ for test: gem_workarounds
results: parsing output: 154/ for test: kms_ccs
results: parsing output: 155/ for test: kms_hdr
Done.