[61.592376] Initializing watchdogs
[61.592422]   /dev/watchdog0
[61.608328] [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]
[61.612970] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[61.615308] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[61.617597] [001/156] (960s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-blt)
[61.725805] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-blt
[66.496483] Subtest fbc-1p-offscreen-pri-shrfb-draw-blt: SUCCESS (4.773s)
[67.661277] [002/156] (954s left) kms_chamelium_hpd (dp-hpd-with-enabled-mode)
[68.060310] Subtest dp-hpd-with-enabled-mode: SKIP (0.000s)
[68.867442] [003/156] (953s left) kms_cursor_legacy (flip-vs-cursor-atomic)
[68.983576] Starting subtest: flip-vs-cursor-atomic
[76.931400] Subtest flip-vs-cursor-atomic: SUCCESS (7.947s)
[77.814726] [004/156] (944s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-pwrite)
[77.919124] Starting subtest: psr-2p-primscrn-spr-indfb-draw-pwrite
[77.920782] Subtest psr-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[78.013139] [005/156] (944s left) kms_vblank (ts-continuation-dpms-suspend)
[78.107889] Starting subtest: ts-continuation-dpms-suspend
[78.108779] Starting dynamic subtest: pipe-A-eDP-1
[95.722422] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.428s)
[95.734584] Starting dynamic subtest: pipe-D-eDP-1
[113.699977] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.967s)
[113.700943] Subtest ts-continuation-dpms-suspend: SUCCESS (5.398s)
[120.528122] [006/156] (907s left) sw_sync (sync_merge)
[120.560831] Starting subtest: sync_merge
[120.562163] Subtest sync_merge: SUCCESS (0.000s)
[120.609498] [007/156] (907s left) gem_workarounds (reset-fd)
[120.695218] Starting subtest: reset-fd
[120.829812] Subtest reset-fd: SUCCESS (0.136s)
[120.958225] [008/156] (907s left) gem_pxp (reject-modify-context-protection-off-2)
[121.072570] Starting subtest: reject-modify-context-protection-off-2
[121.075228] Subtest reject-modify-context-protection-off-2: SUCCESS (0.003s)
[121.176743] [009/156] (907s left) kms_setmode (invalid-clone-exclusive-crtc)
[121.236378] Starting subtest: invalid-clone-exclusive-crtc
[121.239374] Subtest invalid-clone-exclusive-crtc: SKIP (0.000s)
[121.312375] [010/156] (907s left) kms_rotation_crc (primary-y-tiled-reflect-x-0)
[121.415661] Starting subtest: primary-y-tiled-reflect-x-0
[121.488254] Subtest primary-y-tiled-reflect-x-0: SKIP (0.070s)
[121.594485] [011/156] (906s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-onoff)
[121.692008] Starting subtest: fbc-2p-primscrn-cur-indfb-onoff
[121.693677] Subtest fbc-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[121.794140] [012/156] (906s left) gem_pread (snoop)
[121.839249] Subtest snoop: SKIP (0.000s)
[121.908794] [013/156] (906s left) kms_frontbuffer_tracking (fbc-tiling-4)
[121.995294] Starting subtest: fbc-tiling-4
[126.308328] Subtest fbc-tiling-4: SUCCESS (4.313s)
[127.434876] [014/156] (901s left) kms_psr (fbc-psr2-primary-mmap-gtt)
[127.527973] Starting subtest: fbc-psr2-primary-mmap-gtt
[127.528990] Starting dynamic subtest: eDP-1
[127.530662] Dynamic subtest eDP-1: SKIP (0.000s)
[127.532361] Subtest fbc-psr2-primary-mmap-gtt: SKIP (0.000s)
[127.607864] [015/156] (901s left) gem_close_race (basic-threads)
[127.661353] Starting subtest: basic-threads
[128.971069] Subtest basic-threads: SUCCESS (1.309s)
[129.161323] [016/156] (899s left) kms_psr (psr-sprite-plane-onoff)
[129.245054] Starting subtest: psr-sprite-plane-onoff
[129.245960] Starting dynamic subtest: eDP-1
[130.974736] Dynamic subtest eDP-1: SUCCESS (1.729s)
[130.975648] Subtest psr-sprite-plane-onoff: SUCCESS (1.730s)
[131.810632] [017/156] (896s left) gem_fenced_exec_thrash (2-spare-fences)
[131.869290] Subtest 2-spare-fences: SKIP (0.000s)
[131.933921] [018/156] (896s left) gem_exec_params (cliprects-invalid)
[131.987180] Starting subtest: cliprects-invalid
[131.988049] Subtest cliprects-invalid: SUCCESS (0.000s)
[132.107001] [019/156] (896s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-pgflip-blt)
[132.207201] Starting subtest: psr-1p-primscrn-indfb-pgflip-blt
[139.680401] Subtest psr-1p-primscrn-indfb-pgflip-blt: SUCCESS (7.475s)
[139.799892] [020/156] (888s left) gem_exec_schedule (semaphore-noskip)
[139.866911] Starting subtest: semaphore-noskip
[140.315472] Subtest semaphore-noskip: SUCCESS (0.438s)
[140.646753] [021/156] (888s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[140.750353] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[140.751937] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[140.842584] [022/156] (888s left) kms_lease (simple-lease)
[140.938213] Starting subtest: simple-lease
[140.939248] Starting dynamic subtest: pipe-A-eDP-1
[141.439747] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.501s)
[141.447939] Starting dynamic subtest: pipe-B-eDP-1
[142.868790] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.427s)
[142.878524] Starting dynamic subtest: pipe-C-eDP-1
[144.383226] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.514s)
[144.392895] Starting dynamic subtest: pipe-D-eDP-1
[145.796331] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.410s)
[145.797192] Subtest simple-lease: SUCCESS (4.858s)
[146.593208] [023/156] (882s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-spr-indfb-draw-mmap-wc)
[146.695736] Starting subtest: psrhdr-2p-scndscrn-spr-indfb-draw-mmap-wc
[146.697573] Subtest psrhdr-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[146.808485] [024/156] (882s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-onoff)
[146.903227] Starting subtest: psr-2p-scndscrn-cur-indfb-onoff
[146.905405] Subtest psr-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[146.997511] [025/156] (881s left) syncobj_eventfd (invalid-illegal-handle)
[147.044172] Starting subtest: invalid-illegal-handle
[147.045108] Subtest invalid-illegal-handle: SUCCESS (0.000s)
[147.131350] [026/156] (881s left) kms_cursor_legacy (cursora-vs-flipb-varying-size)
[147.247285] Starting subtest: cursorA-vs-flipB-varying-size
[147.249247] Subtest cursorA-vs-flipB-varying-size: SKIP (0.001s)
[147.397966] [027/156] (881s left) gem_exec_schedule (pi-common)
[147.575014] Starting subtest: pi-common
[147.576088] Starting dynamic subtest: rcs0
[147.631769] Dynamic subtest rcs0: SUCCESS (0.058s)
[147.644334] Starting dynamic subtest: bcs0
[147.657660] Dynamic subtest bcs0: SUCCESS (0.025s)
[147.670098] Starting dynamic subtest: vcs0
[147.684591] Dynamic subtest vcs0: SUCCESS (0.027s)
[147.697132] Starting dynamic subtest: vcs1
[147.711737] Dynamic subtest vcs1: SUCCESS (0.027s)
[147.725203] Starting dynamic subtest: vecs0
[147.737941] Dynamic subtest vecs0: SUCCESS (0.026s)
[147.749328] Starting dynamic subtest: ccs0
[147.764092] Dynamic subtest ccs0: SUCCESS (0.026s)
[147.764932] Subtest pi-common: SUCCESS (0.190s)
[147.980507] [FACT gem_exec_schedule (pi-common)] new: kernel.kmod_is_loaded.vgem: true
[147.982509] [028/156] (881s left) syncobj_wait (multi-wait-for-submit-submitted-signaled)
[148.029759] Starting subtest: multi-wait-for-submit-submitted-signaled
[148.030678] Subtest multi-wait-for-submit-submitted-signaled: SUCCESS (0.001s)
[148.137585] [029/156] (880s left) kms_cursor_legacy (cursor-vs-flip-atomic)
[148.258433] Starting subtest: cursor-vs-flip-atomic
[178.209443] Subtest cursor-vs-flip-atomic: SUCCESS (29.948s)
[179.020825] [030/156] (850s left) kms_busy (basic-hang)
[179.112291] Starting subtest: basic-hang
[179.113239] Starting dynamic subtest: flip-pipe-A
[179.708078] Dynamic subtest flip-pipe-A: SUCCESS (0.594s)
[179.716539] Starting dynamic subtest: modeset-pipe-A
[183.159483] Dynamic subtest modeset-pipe-A: SUCCESS (3.451s)
[183.171118] Starting dynamic subtest: flip-pipe-D
[184.398991] Dynamic subtest flip-pipe-D: SUCCESS (1.237s)
[184.408537] Starting dynamic subtest: modeset-pipe-D
[187.823640] Dynamic subtest modeset-pipe-D: SUCCESS (3.424s)
[187.824685] Subtest basic-hang: SUCCESS (8.712s)
[188.626609] [031/156] (840s left) i915_pm_rps (thresholds-idle-park)
[188.681244] Starting subtest: thresholds-idle-park
[188.683562] Subtest thresholds-idle-park: SKIP (0.001s)
[188.772476] [032/156] (840s left) gen9_exec_parse (bb-secure)
[188.832892] Subtest bb-secure: SKIP (0.000s)
[188.901188] [033/156] (840s left) kms_color_pipeline (plane-lut1d-pre-ctm3x4)
[188.993252] Starting subtest: plane-lut1d-pre-ctm3x4
[189.140699] Starting dynamic subtest: pipe-A-plane-0
[189.308111] Dynamic subtest pipe-A-plane-0: SUCCESS (0.167s)
[189.318486] Starting dynamic subtest: pipe-A-plane-1
[189.481358] Dynamic subtest pipe-A-plane-1: SUCCESS (0.166s)
[189.489753] Starting dynamic subtest: pipe-A-plane-2
[189.632086] Dynamic subtest pipe-A-plane-2: SUCCESS (0.157s)
[190.827680] Starting dynamic subtest: pipe-B-plane-0
[191.020048] Dynamic subtest pipe-B-plane-0: SUCCESS (0.200s)
[191.029178] Starting dynamic subtest: pipe-B-plane-1
[191.186736] Dynamic subtest pipe-B-plane-1: SUCCESS (0.166s)
[191.195749] Starting dynamic subtest: pipe-B-plane-2
[191.375691] Dynamic subtest pipe-B-plane-2: SUCCESS (0.188s)
[192.556464] Starting dynamic subtest: pipe-C-plane-0
[192.712435] Dynamic subtest pipe-C-plane-0: SUCCESS (0.166s)
[192.721397] Starting dynamic subtest: pipe-C-plane-1
[192.856943] Dynamic subtest pipe-C-plane-1: SUCCESS (0.144s)
[192.866007] Starting dynamic subtest: pipe-C-plane-2
[193.023585] Dynamic subtest pipe-C-plane-2: SUCCESS (0.166s)
[194.193367] Starting dynamic subtest: pipe-D-plane-0
[194.330204] Dynamic subtest pipe-D-plane-0: SUCCESS (0.144s)
[194.339513] Starting dynamic subtest: pipe-D-plane-1
[194.496049] Dynamic subtest pipe-D-plane-1: SUCCESS (0.166s)
[194.504685] Starting dynamic subtest: pipe-D-plane-2
[194.662915] Dynamic subtest pipe-D-plane-2: SUCCESS (0.166s)
[194.963558] Subtest plane-lut1d-pre-ctm3x4: SUCCESS (5.970s)
[195.777347] [034/156] (833s left) kms_plane (pixel-format-yf-tiled-modifier)
[195.870759] Starting subtest: pixel-format-yf-tiled-modifier
[195.872601] Subtest pixel-format-yf-tiled-modifier: SKIP (0.000s)
[195.951715] [035/156] (833s left) kms_psr2_sf (psr2-overlay-plane-update-continuous-sf)
[196.044011] Starting subtest: psr2-overlay-plane-update-continuous-sf
[196.044883] Starting dynamic subtest: pipe-A-eDP-1
[197.195543] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.151s)
[197.203593] Starting dynamic subtest: pipe-B-eDP-1
[198.939711] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.744s)
[198.940586] Subtest psr2-overlay-plane-update-continuous-sf: SUCCESS (2.897s)
[199.748174] [036/156] (829s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[199.835387] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[199.837283] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[199.943803] [037/156] (829s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-cur-indfb-draw-render)
[200.032167] Starting subtest: fbcpsrhdr-2p-primscrn-cur-indfb-draw-render
[200.033668] Subtest fbcpsrhdr-2p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[200.144984] [038/156] (829s left) syncobj_timeline (wait-any-interrupted)
[200.190888] Starting subtest: wait-any-interrupted
[200.307828] Subtest wait-any-interrupted: SUCCESS (0.108s)
[200.386854] [039/156] (829s left) syncobj_wait (reset-multiple-signaled)
[200.433339] Starting subtest: reset-multiple-signaled
[200.434347] Subtest reset-multiple-signaled: SUCCESS (0.000s)
[200.524676] [040/156] (828s left) kms_atomic (plane-primary-legacy)
[200.605314] Starting subtest: plane-primary-legacy
[200.606282] Starting dynamic subtest: pipe-A-eDP-1
[201.029287] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.423s)
[201.030352] Subtest plane-primary-legacy: SUCCESS (0.424s)
[201.895639] [041/156] (827s left) kms_plane_scaling (plane-scaler-unity-scaling-with-rotation)
[201.981036] Starting subtest: plane-scaler-unity-scaling-with-rotation
[201.981998] Starting dynamic subtest: pipe-A
[202.208011] Dynamic subtest pipe-A: SUCCESS (0.228s)
[202.218733] Starting dynamic subtest: pipe-B
[203.480089] Dynamic subtest pipe-B: SUCCESS (1.270s)
[203.491086] Starting dynamic subtest: pipe-C
[204.813789] Dynamic subtest pipe-C: SUCCESS (1.331s)
[204.823138] Starting dynamic subtest: pipe-D
[206.143418] Dynamic subtest pipe-D: SUCCESS (1.329s)
[206.144309] Subtest plane-scaler-unity-scaling-with-rotation: SUCCESS (4.165s)
[207.315797] [042/156] (822s left) kms_cursor_legacy (modeset-atomic-cursor-hotspot)
[207.437539] Starting subtest: modeset-atomic-cursor-hotspot
[207.439681] Subtest modeset-atomic-cursor-hotspot: SKIP (0.000s)
[207.576747] [043/156] (822s left) kms_big_fb (linear-max-hw-stride-64bpp-rotate-0)
[207.669449] Starting subtest: linear-max-hw-stride-64bpp-rotate-0
[208.703975] Subtest linear-max-hw-stride-64bpp-rotate-0: SUCCESS (1.035s)
[208.822750] [044/156] (820s left) gem_mmap_gtt (cpuset-big-copy-xy)
[208.870505] Subtest cpuset-big-copy-XY: SKIP (0.000s)
[208.922239] [045/156] (820s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-spr-indfb-move)
[209.012993] Starting subtest: psrhdr-1p-primscrn-spr-indfb-move
[209.014815] Subtest psrhdr-1p-primscrn-spr-indfb-move: SKIP (0.000s)
[209.110948] [046/156] (820s left) gem_mmap_offset (mmap-unmap)
[209.155383] Starting subtest: mmap-unmap
[209.156357] Starting dynamic subtest: smem0
[234.802408] Dynamic subtest smem0: SUCCESS (25.600s)
[234.803615] Subtest mmap-unmap: SUCCESS (25.601s)
[234.858312] [047/156] (794s left) kms_frontbuffer_tracking (psr-1p-primscrn-shrfb-pgflip-blt)
[234.955459] Starting subtest: psr-1p-primscrn-shrfb-pgflip-blt
[242.378942] Subtest psr-1p-primscrn-shrfb-pgflip-blt: SUCCESS (7.425s)
[242.487707] [048/156] (787s left) kms_hdr (bpc-switch-dpms)
[242.588072] Starting subtest: bpc-switch-dpms
[242.588860] Starting dynamic subtest: pipe-A-eDP-1-XRGB2101010
[248.166167] Dynamic subtest pipe-A-eDP-1-XRGB2101010: SUCCESS (5.577s)
[248.175944] Starting dynamic subtest: pipe-A-eDP-1-XRGB16161616F
[253.816116] Dynamic subtest pipe-A-eDP-1-XRGB16161616F: SUCCESS (5.649s)
[253.817205] Subtest bpc-switch-dpms: SUCCESS (11.229s)
[253.928510] [049/156] (775s left) kms_frontbuffer_tracking (hdr-1p-primscrn-cur-indfb-draw-mmap-cpu)
[254.029323] Starting subtest: hdr-1p-primscrn-cur-indfb-draw-mmap-cpu
[254.030989] Subtest hdr-1p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[254.120598] [050/156] (775s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-spr-indfb-draw-mmap-wc)
[254.205561] Starting subtest: fbchdr-2p-primscrn-spr-indfb-draw-mmap-wc
[254.207510] Subtest fbchdr-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[254.297633] [051/156] (775s left) kms_ccs (crc-primary-rotation-180-y-tiled-ccs)
[254.382583] Starting subtest: crc-primary-rotation-180-y-tiled-ccs
[254.384129] Starting dynamic subtest: pipe-A-eDP-1
[254.394958] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[254.405793] Starting dynamic subtest: pipe-B-eDP-1
[254.415038] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[254.423669] Starting dynamic subtest: pipe-C-eDP-1
[254.432764] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[254.440771] Starting dynamic subtest: pipe-D-eDP-1
[254.449635] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[254.451389] Subtest crc-primary-rotation-180-y-tiled-ccs: SKIP (0.006s)
[256.630352] [052/156] (773s left) kms_vblank (crtc-id)
[256.716561] Starting subtest: crtc-id
[256.717622] Starting dynamic subtest: pipe-A-eDP-1
[257.171430] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.454s)
[257.184496] Starting dynamic subtest: pipe-D-eDP-1
[258.265306] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.092s)
[258.266341] Subtest crtc-id: SUCCESS (1.549s)
[259.106751] [053/156] (770s left) kms_pipe_crc_basic (nonblocking-crc)
[259.208265] Starting subtest: nonblocking-crc
[259.209142] Starting dynamic subtest: pipe-A-eDP-1
[259.968503] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.760s)
[259.979201] Starting dynamic subtest: pipe-B-eDP-1
[261.403489] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.432s)
[261.412456] Starting dynamic subtest: pipe-C-eDP-1
[262.803316] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.398s)
[262.814647] Starting dynamic subtest: pipe-D-eDP-1
[264.241049] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.436s)
[264.242010] Subtest nonblocking-crc: SUCCESS (5.033s)
[265.045496] [054/156] (764s left) kms_cursor_legacy (2x-cursor-vs-flip-atomic)
[265.169470] Starting subtest: 2x-cursor-vs-flip-atomic
[265.173070] Subtest 2x-cursor-vs-flip-atomic: SKIP (0.001s)
[265.314541] [055/156] (764s left) kms_async_flips (async-flip-suspend-resume)
[265.397885] Starting subtest: async-flip-suspend-resume
[265.770919] Starting dynamic subtest: pipe-A-eDP-1
[284.112580] Dynamic subtest pipe-A-eDP-1: SUCCESS (4.048s)
[284.476783] Starting dynamic subtest: pipe-B-eDP-1
[303.099842] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.289s)
[303.466494] Starting dynamic subtest: pipe-C-eDP-1
[322.118381] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.307s)
[322.493907] Starting dynamic subtest: pipe-D-eDP-1
[341.144549] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.348s)
[341.145759] Subtest async-flip-suspend-resume: SUCCESS (15.455s)
[348.324156] [056/156] (687s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-pri-indfb-draw-blt)
[348.435521] Starting subtest: hdr-2p-scndscrn-pri-indfb-draw-blt
[348.437294] Subtest hdr-2p-scndscrn-pri-indfb-draw-blt: SKIP (0.000s)
[348.517039] [057/156] (687s left) syncobj_wait (invalid-multi-wait-all-unsubmitted-signaled)
[348.561228] Starting subtest: invalid-multi-wait-all-unsubmitted-signaled
[348.562529] Subtest invalid-multi-wait-all-unsubmitted-signaled: SUCCESS (0.000s)
[348.655703] [058/156] (687s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-mmap-gtt)
[348.745271] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-mmap-gtt
[348.747345] Subtest fbc-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[348.839722] [059/156] (687s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-shrfb-fliptrack-mmap-gtt)
[348.924665] Starting subtest: fbcpsrhdr-2p-shrfb-fliptrack-mmap-gtt
[348.926204] Subtest fbcpsrhdr-2p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[349.010323] [060/156] (687s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-gtt)
[349.101320] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-gtt
[349.103270] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[349.196581] [061/156] (687s left) gem_render_copy_redux (flink)
[349.241920] Starting subtest: flink
[350.036542] Subtest flink: SUCCESS (0.794s)
[350.134676] [062/156] (686s left) gem_exec_whisper (basic-contexts-all)
[350.189292] Starting subtest: basic-contexts-all
[360.348163] Subtest basic-contexts-all: SUCCESS (10.143s)
[360.520437] [063/156] (675s left) drm_buddy (drm_buddy)
[360.554272] Starting subtest: drm_buddy
[360.556257] Subtest drm_buddy: SKIP (0.001s)
[360.606990] [064/156] (675s left) perf (stress-open-close)
[360.722562] Starting subtest: stress-open-close
[360.723539] Starting dynamic subtest: 0-rcs0
[362.889983] Dynamic subtest 0-rcs0: SUCCESS (2.167s)
[362.904275] Starting dynamic subtest: 1-vcs0
[365.053755] Dynamic subtest 1-vcs0: SUCCESS (2.163s)
[365.055402] Subtest stress-open-close: SUCCESS (4.332s)
[365.093217] [065/156] (671s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-shrfb-draw-pwrite)
[365.192910] Starting subtest: psrhdr-2p-scndscrn-pri-shrfb-draw-pwrite
[365.194645] Subtest psrhdr-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[365.295727] [066/156] (671s left) kms_big_fb (yf-tiled-32bpp-rotate-270)
[365.390731] Starting subtest: yf-tiled-32bpp-rotate-270
[365.392883] Subtest yf-tiled-32bpp-rotate-270: SKIP (0.000s)
[365.479608] [067/156] (670s left) kms_frontbuffer_tracking (hdr-2p-primscrn-pri-indfb-draw-pwrite)
[365.568197] Starting subtest: hdr-2p-primscrn-pri-indfb-draw-pwrite
[365.569878] Subtest hdr-2p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[365.654997] [068/156] (670s left) gem_ccs (block-copy-uncompressed)
[365.712176] Starting subtest: block-copy-uncompressed
[365.713270] Starting dynamic subtest: linear-uncompressed-compfmt0-smem-smem
[365.735995] Dynamic subtest linear-uncompressed-compfmt0-smem-smem: SUCCESS (0.023s)
[365.745689] Starting dynamic subtest: xmajor-uncompressed-compfmt0-smem-smem
[365.754301] Dynamic subtest xmajor-uncompressed-compfmt0-smem-smem: SUCCESS (0.017s)
[365.762751] Starting dynamic subtest: tile4-uncompressed-compfmt0-smem-smem
[365.771376] Dynamic subtest tile4-uncompressed-compfmt0-smem-smem: SUCCESS (0.016s)
[365.779847] Starting dynamic subtest: tile64-uncompressed-compfmt0-smem-smem
[365.788521] Dynamic subtest tile64-uncompressed-compfmt0-smem-smem: SUCCESS (0.016s)
[365.789790] Subtest block-copy-uncompressed: SUCCESS (0.077s)
[365.898195] [069/156] (670s left) kms_ccs (random-ccs-data-yf-tiled-ccs)
[365.990431] Starting subtest: random-ccs-data-yf-tiled-ccs
[365.991525] Starting dynamic subtest: pipe-A-eDP-1
[366.003396] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[366.012561] Starting dynamic subtest: pipe-B-eDP-1
[366.024335] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[366.033234] Starting dynamic subtest: pipe-C-eDP-1
[366.043116] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[366.051740] Starting dynamic subtest: pipe-D-eDP-1
[366.062647] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[366.064416] Subtest random-ccs-data-yf-tiled-ccs: SKIP (0.004s)
[368.277279] [070/156] (668s left) gem_ctx_sseu (invalid-args)
[368.329677] Subtest invalid-args: SKIP (0.000s)
[368.401104] [071/156] (668s left) gem_wait (wait)
[368.451747] Starting subtest: wait
[368.453195] Starting dynamic subtest: all-engines
[369.817731] Dynamic subtest all-engines: SUCCESS (1.356s)
[369.829370] Starting dynamic subtest: rcs0
[370.861736] Dynamic subtest rcs0: SUCCESS (1.050s)
[370.874725] Starting dynamic subtest: bcs0
[371.916006] Dynamic subtest bcs0: SUCCESS (1.052s)
[371.929146] Starting dynamic subtest: vcs0
[372.968674] Dynamic subtest vcs0: SUCCESS (1.049s)
[372.980418] Starting dynamic subtest: vcs1
[374.021916] Dynamic subtest vcs1: SUCCESS (1.050s)
[374.036006] Starting dynamic subtest: vecs0
[375.071710] Dynamic subtest vecs0: SUCCESS (1.047s)
[375.084752] Starting dynamic subtest: ccs0
[376.124717] Dynamic subtest ccs0: SUCCESS (1.051s)
[376.128578] Subtest wait: SUCCESS (7.674s)
[376.320775] [072/156] (660s left) kms_psr (psr2-sprite-render)
[376.412179] Starting subtest: psr2-sprite-render
[376.413509] Starting dynamic subtest: eDP-1
[378.171292] Dynamic subtest eDP-1: SUCCESS (1.759s)
[378.172515] Subtest psr2-sprite-render: SUCCESS (1.760s)
[379.044063] [073/156] (657s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[379.132335] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc
[379.134355] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[379.225621] [074/156] (657s left) sysfs_timeslice_duration (invalid)
[379.282672] Starting subtest: invalid
[379.283774] Starting dynamic subtest: vecs0
[379.284901] Dynamic subtest vecs0: SUCCESS (0.000s)
[379.294214] Starting dynamic subtest: ccs0
[379.295199] Dynamic subtest ccs0: SUCCESS (0.000s)
[379.303983] Starting dynamic subtest: vcs0
[379.305519] Dynamic subtest vcs0: SUCCESS (0.000s)
[379.315167] Starting dynamic subtest: bcs0
[379.316499] Dynamic subtest bcs0: SUCCESS (0.000s)
[379.325152] Starting dynamic subtest: rcs0
[379.326123] Dynamic subtest rcs0: SUCCESS (0.000s)
[379.334611] Starting dynamic subtest: vcs1
[379.335514] Dynamic subtest vcs1: SUCCESS (0.000s)
[379.336397] Subtest invalid: SUCCESS (0.006s)
[379.395756] [075/156] (657s left) vgem_basic (setversion)
[379.426600] Starting subtest: setversion
[379.429595] Subtest setversion: SUCCESS (0.000s)
[379.479104] [076/156] (657s left) i915_pm_rc6_residency (rc6-accuracy)
[379.524802] Starting subtest: rc6-accuracy
[379.526411] Starting dynamic subtest: gt0
[382.705028] Dynamic subtest gt0: SUCCESS (3.169s)
[382.714545] Starting dynamic subtest: gt1
[385.877177] Dynamic subtest gt1: SUCCESS (3.168s)
[385.878508] Subtest rc6-accuracy: SUCCESS (6.343s)
[385.941512] [077/156] (650s left) kms_big_fb (4-tiled-8bpp-rotate-90)
[386.041741] Starting subtest: 4-tiled-8bpp-rotate-90
[386.064451] Subtest 4-tiled-8bpp-rotate-90: SKIP (0.022s)
[386.187311] [078/156] (650s left) kms_psr (pr-cursor-mmap-cpu)
[386.276732] Starting subtest: pr-cursor-mmap-cpu
[386.278536] Subtest pr-cursor-mmap-cpu: SKIP (0.000s)
[386.357429] [079/156] (650s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-wc)
[386.447620] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-wc
[393.182756] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-wc: SUCCESS (6.726s)
[393.287820] [080/156] (643s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-pri-shrfb-draw-mmap-wc)
[393.385480] Starting subtest: fbchdr-2p-primscrn-pri-shrfb-draw-mmap-wc
[393.387197] Subtest fbchdr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[393.471160] [081/156] (643s left) gem_render_copy (y-tiled-ccs-to-yf-tiled)
[393.523980] Starting subtest: y-tiled-ccs-to-yf-tiled
[393.526652] Subtest y-tiled-ccs-to-yf-tiled: SKIP (0.000s)
[393.726521] [082/156] (643s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[393.826075] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[393.827752] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[393.921744] [083/156] (643s left) kms_psr (fbc-pr-cursor-render)
[394.003518] Starting subtest: fbc-pr-cursor-render
[394.005567] Subtest fbc-pr-cursor-render: SKIP (0.000s)
[394.073714] [084/156] (642s left) kms_frontbuffer_tracking (hdr-rgb565-draw-mmap-wc)
[394.159958] Starting subtest: hdr-rgb565-draw-mmap-wc
[394.162249] Subtest hdr-rgb565-draw-mmap-wc: SKIP (0.000s)
[394.256203] [085/156] (642s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[394.368417] Starting subtest: fbchdr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[394.370370] Subtest fbchdr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[394.452312] [086/156] (642s left) gem_ctx_persistence (saturated-hostile)
[394.509801] Starting subtest: saturated-hostile
[394.511388] Starting dynamic subtest: rcs0
[395.322678] Dynamic subtest rcs0: SUCCESS (0.812s)
[395.335670] Starting dynamic subtest: bcs0
[396.128444] Dynamic subtest bcs0: SUCCESS (0.804s)
[396.138894] Starting dynamic subtest: vcs0
[396.929577] Dynamic subtest vcs0: SUCCESS (0.799s)
[396.940737] Starting dynamic subtest: vcs1
[397.721427] Dynamic subtest vcs1: SUCCESS (0.790s)
[397.733654] Starting dynamic subtest: vecs0
[398.512682] Dynamic subtest vecs0: SUCCESS (0.790s)
[398.524978] Starting dynamic subtest: ccs0
[399.329282] Dynamic subtest ccs0: SUCCESS (0.815s)
[399.330781] Subtest saturated-hostile: SUCCESS (4.820s)
[399.405701] [087/156] (637s left) kms_prime (basic-crc-hybrid)
[399.486400] Subtest basic-crc-hybrid: SKIP (0.000s)
[399.601243] [088/156] (637s left) gem_exec_balancer (busy)
[399.657274] Starting subtest: busy
[401.791758] Subtest busy: SUCCESS (2.125s)
[402.101967] [089/156] (635s left) gen9_exec_parse (valid-registers)
[402.151503] Subtest valid-registers: SKIP (0.000s)
[402.219030] [090/156] (635s left) gem_exec_fence (syncobj-timeline-wait)
[402.775032] Starting subtest: syncobj-timeline-wait
[403.821598] Subtest syncobj-timeline-wait: SUCCESS (1.047s)
[405.926236] [091/156] (631s left) vgem_basic (unload)
[405.956826] Starting subtest: unload
[435.019640] Subtest unload: SUCCESS (29.053s)
[435.104443] [092/156] (602s left) kms_sequence (queue-idle)
[435.201814] Starting subtest: queue-idle
[435.203656] Starting dynamic subtest: pipe-A-eDP-1
[437.019178] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.813s)
[437.028234] Starting dynamic subtest: pipe-B-eDP-1
[439.560189] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.540s)
[439.569677] Starting dynamic subtest: pipe-C-eDP-1
[442.071395] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.510s)
[442.081251] Starting dynamic subtest: pipe-D-eDP-1
[444.505571] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.433s)
[444.506535] Subtest queue-idle: SUCCESS (9.303s)
[445.313962] [093/156] (592s left) gem_eio (in-flight-external)
[445.515981] Starting subtest: in-flight-external
[446.861579] Subtest in-flight-external: SUCCESS (1.344s)
[446.998759] [094/156] (590s left) gem_exec_reloc (basic-gtt-wc-noreloc)
[447.051605] Subtest basic-gtt-wc-noreloc: SKIP (0.000s)
[447.109041] [095/156] (590s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[447.202546] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[448.095513] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SUCCESS (0.893s)
[448.239582] [096/156] (589s left) kms_invalid_mode (clock-too-high)
[448.332752] Starting subtest: clock-too-high
[448.333993] Starting dynamic subtest: pipe-A-eDP-1
[448.618541] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.285s)
[448.627779] Starting dynamic subtest: pipe-B-eDP-1
[448.630385] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[448.639197] Starting dynamic subtest: pipe-C-eDP-1
[448.641706] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[448.650139] Starting dynamic subtest: pipe-D-eDP-1
[448.652763] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[448.653952] Subtest clock-too-high: SUCCESS (0.290s)
[449.443188] [097/156] (588s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-blt)
[449.540746] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-blt
[449.543229] Subtest fbc-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[449.627700] [098/156] (587s left) syncobj_timeline (multi-wait-available-unsubmitted-submitted-signaled)
[449.680727] Starting subtest: multi-wait-available-unsubmitted-submitted-signaled
[449.682517] Subtest multi-wait-available-unsubmitted-submitted-signaled: SUCCESS (0.001s)
[449.766593] [099/156] (587s left) kms_flip (2x-flip-vs-dpms-on-nop)
[449.856165] Starting subtest: 2x-flip-vs-dpms-on-nop
[449.866748] Subtest 2x-flip-vs-dpms-on-nop: SKIP (0.011s)
[449.942228] [100/156] (587s left) kms_cursor_crc (cursor-onscreen-32x32)
[450.081279] Starting subtest: cursor-onscreen-32x32
[450.124630] Subtest cursor-onscreen-32x32: SKIP (0.041s)
[450.438414] [101/156] (587s left) kms_vrr (seamless-rr-switch-vrr)
[450.526316] Starting subtest: seamless-rr-switch-vrr
[450.529175] Subtest seamless-rr-switch-vrr: SKIP (0.000s)
[450.595138] [102/156] (587s left) api_intel_allocator (reopen)
[450.639762] Starting subtest: reopen
[450.644531] Subtest reopen: SUCCESS (0.006s)
[450.759903] [103/156] (586s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-pwrite)
[450.848107] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-pwrite
[450.849695] Subtest fbc-2p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[450.951251] [104/156] (586s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-16bpp-yftile-downscaling)
[451.041852] Starting subtest: flip-64bpp-yftile-to-16bpp-yftile-downscaling
[451.044337] Subtest flip-64bpp-yftile-to-16bpp-yftile-downscaling: SKIP (0.000s)
[451.111031] [105/156] (586s left) gem_ctx_engines (execute-allforone)
[451.170203] Starting subtest: execute-allforone
[451.183685] Subtest execute-allforone: SUCCESS (0.015s)
[452.862304] [106/156] (584s left) syncobj_timeline (etime-single-wait-for-submit-unsubmitted)
[452.913811] Starting subtest: etime-single-wait-for-submit-unsubmitted
[453.020789] Subtest etime-single-wait-for-submit-unsubmitted: SUCCESS (0.106s)
[453.092737] [107/156] (584s left) kms_content_protection (suspend-resume)
[453.233638] Starting subtest: suspend-resume
[453.236458] Subtest suspend-resume: SKIP (0.000s)
[453.350964] [108/156] (584s left) kms_flip (nonblocking-read)
[453.436562] Starting subtest: nonblocking-read
[453.437624] Subtest nonblocking-read: SUCCESS (0.000s)
[453.526170] [109/156] (584s left) kms_big_fb (y-tiled-16bpp-rotate-0)
[453.618422] Starting subtest: y-tiled-16bpp-rotate-0
[453.620822] Subtest y-tiled-16bpp-rotate-0: SKIP (0.000s)
[453.696342] [110/156] (584s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-cpu)
[453.787388] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-cpu
[460.919449] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-cpu: SUCCESS (7.133s)
[461.037114] [111/156] (576s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-cur-indfb-draw-mmap-wc)
[461.123666] Starting subtest: fbcpsrhdr-2p-scndscrn-cur-indfb-draw-mmap-wc
[461.125582] Subtest fbcpsrhdr-2p-scndscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[461.211948] [112/156] (576s left) kms_frontbuffer_tracking (psr-rgb101010-draw-mmap-gtt)
[461.310763] Starting subtest: psr-rgb101010-draw-mmap-gtt
[461.312604] Subtest psr-rgb101010-draw-mmap-gtt: SKIP (0.000s)
[461.399190] [113/156] (576s left) kms_rotation_crc (sprite-rotation-180)
[461.490177] Starting subtest: sprite-rotation-180
[468.587844] Subtest sprite-rotation-180: SUCCESS (7.099s)
[469.753341] [114/156] (568s left) kms_frontbuffer_tracking (hdr-2p-primscrn-cur-indfb-draw-blt)
[469.846264] Starting subtest: hdr-2p-primscrn-cur-indfb-draw-blt
[469.848282] Subtest hdr-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[469.935635] [115/156] (568s left) syncobj_timeline (multi-wait-all-for-submit-available-signaled)
[469.988418] Starting subtest: multi-wait-all-for-submit-available-signaled
[469.989214] Subtest multi-wait-all-for-submit-available-signaled: SUCCESS (0.000s)
[470.085751] [116/156] (568s left) kms_cursor_crc (cursor-random-256x85)
[470.355587] Starting subtest: cursor-random-256x85
[470.399654] Subtest cursor-random-256x85: SKIP (0.043s)
[470.558389] [117/156] (567s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[470.651668] Starting subtest: psrhdr-2p-primscrn-pri-shrfb-draw-mmap-cpu
[470.653563] Subtest psrhdr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[470.744233] [118/156] (567s left) kms_frontbuffer_tracking (psrhdr-1p-offscreen-pri-indfb-draw-mmap-wc)
[470.830484] Starting subtest: psrhdr-1p-offscreen-pri-indfb-draw-mmap-wc
[470.832624] Subtest psrhdr-1p-offscreen-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[470.930878] [119/156] (567s left) gem_exec_flush (basic-wb-set-default)
[470.988175] Starting subtest: basic-wb-set-default
[476.449312] Subtest basic-wb-set-default: SUCCESS (5.458s)
[476.641009] [120/156] (561s left) gem_mmap_wc (bad-object)
[476.687261] Subtest bad-object: SKIP (0.000s)
[476.742340] [121/156] (561s left) kms_pm_rpm (cursor)
[478.971637] Starting subtest: cursor
[478.974475] Subtest cursor: SKIP (0.000s)
[479.362708] [122/156] (559s left) kms_frontbuffer_tracking (fbc-2p-pri-indfb-multidraw)
[479.462444] Starting subtest: fbc-2p-pri-indfb-multidraw
[479.463991] Subtest fbc-2p-pri-indfb-multidraw: SKIP (0.000s)
[479.551010] [123/156] (558s left) syncobj_basic (bad-pad-handle-to-fd)
[479.603667] Starting subtest: bad-pad-handle-to-fd
[479.604906] Subtest bad-pad-handle-to-fd: SUCCESS (0.000s)
[479.708334] [124/156] (558s left) api_intel_bb (lot-of-buffers)
[479.752376] Starting subtest: lot-of-buffers
[480.862440] Subtest lot-of-buffers: SUCCESS (1.101s)
[481.344181] [125/156] (557s left) gem_exec_capture (capture-invisible)
[481.398457] Starting subtest: capture-invisible
[481.399843] Starting dynamic subtest: smem0
[481.402545] Dynamic subtest smem0: SKIP (0.000s)
[481.404635] Subtest capture-invisible: SKIP (0.001s)
[481.496239] [126/156] (557s left) gem_mmap_wc (write-gtt-read-wc)
[481.545052] Subtest write-gtt-read-wc: SKIP (0.000s)
[481.604528] [127/156] (556s left) gem_mmap (bad-object)
[481.646773] Subtest bad-object: SKIP (0.000s)
[481.705851] [128/156] (556s left) gem_lmem_swapping (parallel-random-verify)
[481.739559] Subtest parallel-random-verify: SKIP (0.000s)
[481.780362] [129/156] (556s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-move)
[481.881540] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-move
[489.220846] Subtest fbcpsr-1p-primscrn-spr-indfb-move: SUCCESS (7.341s)
[489.353378] [130/156] (549s left) perf_pmu (semaphore-wait)
[489.394661] Starting subtest: semaphore-wait
[489.395760] Starting dynamic subtest: rcs0
[489.918964] Dynamic subtest rcs0: SUCCESS (0.514s)
[489.928374] Starting dynamic subtest: bcs0
[490.434516] Dynamic subtest bcs0: SUCCESS (0.512s)
[490.447900] Starting dynamic subtest: vcs0
[490.949420] Dynamic subtest vcs0: SUCCESS (0.510s)
[490.962383] Starting dynamic subtest: vcs1
[491.463526] Dynamic subtest vcs1: SUCCESS (0.511s)
[491.475648] Starting dynamic subtest: vecs0
[491.978007] Dynamic subtest vecs0: SUCCESS (0.510s)
[491.990255] Starting dynamic subtest: ccs0
[492.493750] Dynamic subtest ccs0: SUCCESS (0.512s)
[492.495285] Subtest semaphore-wait: SUCCESS (3.090s)
[493.846178] [131/156] (544s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-mmap-wc)
[493.941729] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-mmap-wc
[493.943409] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[494.036659] [132/156] (544s left) kms_ccs (bad-rotation-90-4-tiled-dg2-rc-ccs)
[494.123364] Starting subtest: bad-rotation-90-4-tiled-dg2-rc-ccs
[494.124716] Starting dynamic subtest: pipe-A-eDP-1
[494.135610] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[494.145596] Starting dynamic subtest: pipe-B-eDP-1
[494.155476] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[494.165226] Starting dynamic subtest: pipe-C-eDP-1
[494.173987] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[494.182875] Starting dynamic subtest: pipe-D-eDP-1
[494.194217] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[494.196121] Subtest bad-rotation-90-4-tiled-dg2-rc-ccs: SKIP (0.005s)
[496.456488] [133/156] (542s left) kms_frontbuffer_tracking (fbchdr-shrfb-scaledprimary)
[496.547479] Starting subtest: fbchdr-shrfb-scaledprimary
[496.549560] Subtest fbchdr-shrfb-scaledprimary: SKIP (0.000s)
[496.661662] [134/156] (542s left) kms_ccs (crc-primary-basic-4-tiled-mtl-rc-ccs-cc)
[496.750087] Starting subtest: crc-primary-basic-4-tiled-mtl-rc-ccs-cc
[496.751402] Starting dynamic subtest: pipe-A-eDP-1
[497.591264] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.837s)
[497.600884] Starting dynamic subtest: pipe-B-eDP-1
[499.245221] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.653s)
[499.254582] Starting dynamic subtest: pipe-C-eDP-1
[500.765874] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.518s)
[500.775622] Starting dynamic subtest: pipe-D-eDP-1
[502.302616] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.536s)
[502.303738] Subtest crc-primary-basic-4-tiled-mtl-rc-ccs-cc: SUCCESS (5.550s)
[503.475326] [135/156] (535s left) kms_psr2_sf (fbc-pr-cursor-plane-update-sf)
[503.566714] Starting subtest: fbc-pr-cursor-plane-update-sf
[503.568360] Subtest fbc-pr-cursor-plane-update-sf: SKIP (0.000s)
[503.636100] [136/156] (535s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-indfb-pgflip-blt)
[503.728607] Starting subtest: fbcpsrhdr-1p-primscrn-indfb-pgflip-blt
[503.731382] Subtest fbcpsrhdr-1p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[503.806751] [137/156] (535s left) kms_pm_backlight (fade)
[503.900247] Starting subtest: fade
[503.949422] Starting dynamic subtest: eDP-1
[506.936699] Dynamic subtest eDP-1: SUCCESS (2.986s)
[506.938133] Subtest fade: SUCCESS (3.036s)
[507.809377] [138/156] (531s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-cur-indfb-move)
[507.902532] Starting subtest: fbchdr-1p-primscrn-cur-indfb-move
[507.904529] Subtest fbchdr-1p-primscrn-cur-indfb-move: SKIP (0.000s)
[508.010077] [139/156] (531s left) kms_atomic_transition (plane-all-modeset-transition)
[508.103030] Starting subtest: plane-all-modeset-transition
[508.105587] Subtest plane-all-modeset-transition: SKIP (0.000s)
[508.177841] [140/156] (530s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-pri-shrfb-draw-render)
[508.273206] Starting subtest: fbcpsrhdr-2p-primscrn-pri-shrfb-draw-render
[508.275283] Subtest fbcpsrhdr-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[508.364383] [141/156] (530s left) kms_chamelium_edid (hdmi-mode-timings)
[508.736455] Subtest hdmi-mode-timings: SKIP (0.000s)
[509.517280] [142/156] (529s left) kms_psr (pr-sprite-plane-onoff)
[509.611899] Starting subtest: pr-sprite-plane-onoff
[509.613836] Subtest pr-sprite-plane-onoff: SKIP (0.000s)
[509.680613] [143/156] (529s left) kms_ccs (missing-ccs-buffer-4-tiled-mtl-rc-ccs-cc)
[509.761704] Starting subtest: missing-ccs-buffer-4-tiled-mtl-rc-ccs-cc
[509.763246] Starting dynamic subtest: pipe-A-eDP-1
[509.775240] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[509.786226] Starting dynamic subtest: pipe-B-eDP-1
[509.796182] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[509.805655] Starting dynamic subtest: pipe-C-eDP-1
[509.816465] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[509.825905] Starting dynamic subtest: pipe-D-eDP-1
[509.835508] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[509.836598] Subtest missing-ccs-buffer-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.006s)
[512.018700] [144/156] (527s left) gem_exec_suspend (basic-s4-devices)
[512.073161] Starting subtest: basic-S4-devices
[512.074447] Starting dynamic subtest: smem
[523.803216] Dynamic subtest smem: SUCCESS (11.718s)
[523.804851] Subtest basic-S4-devices: SUCCESS (11.720s)
[529.036299] [145/156] (515s left) kms_plane (pixel-format-4-tiled-bmg-ccs-modifier)
[529.138746] Starting subtest: pixel-format-4-tiled-bmg-ccs-modifier
[529.141379] Subtest pixel-format-4-tiled-bmg-ccs-modifier: SKIP (0.000s)
[529.218552] [146/156] (515s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-shrfb-draw-mmap-cpu)
[529.302422] Starting subtest: psrhdr-1p-primscrn-pri-shrfb-draw-mmap-cpu
[529.312964] Subtest psrhdr-1p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[529.409320] [147/156] (514s left) i915_pm_freq_api (freq-suspend)
[529.462411] Starting subtest: freq-suspend
[529.463811] Starting dynamic subtest: gt0
[546.099544] Dynamic subtest gt0: SUCCESS (1.643s)
[546.117801] Starting dynamic subtest: gt1
[563.115182] Dynamic subtest gt1: SUCCESS (1.581s)
[563.116286] Subtest freq-suspend: SUCCESS (3.228s)
[577.215152] [148/156] (481s left) gem_exec_fence (long-history)
[577.680567] Starting subtest: long-history
[584.980829] Subtest long-history: SUCCESS (7.284s)
[585.596186] [149/156] (472s left) kms_cursor_crc (cursor-tearing-framebuffer-change)
[585.702285] Starting subtest: cursor-tearing-framebuffer-change
[585.703166] Starting dynamic subtest: pipe-A-eDP-1
[586.275071] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.568s)
[586.286007] Starting dynamic subtest: pipe-D-eDP-1
[587.528085] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.252s)
[587.529081] Subtest cursor-tearing-framebuffer-change: SUCCESS (1.823s)
[588.364258] [150/156] (470s left) gem_ctx_persistence (engines-mixed-process)
[588.415773] Starting subtest: engines-mixed-process
[588.416869] Starting dynamic subtest: rcs0
[590.272429] Dynamic subtest rcs0: SUCCESS (1.855s)
[590.286231] Starting dynamic subtest: bcs0
[592.259312] Dynamic subtest bcs0: SUCCESS (1.984s)
[592.273982] Starting dynamic subtest: vcs0
[594.224379] Dynamic subtest vcs0: SUCCESS (1.963s)
[594.238345] Starting dynamic subtest: vcs1
[596.213906] Dynamic subtest vcs1: SUCCESS (1.987s)
[596.227274] Starting dynamic subtest: vecs0
[598.260038] Dynamic subtest vecs0: SUCCESS (2.041s)
[598.272864] Starting dynamic subtest: ccs0
[600.255978] Dynamic subtest ccs0: SUCCESS (1.997s)
[600.257074] Subtest engines-mixed-process: SUCCESS (11.837s)
[600.341114] [151/156] (458s left) perf (invalid-open-flags)
[600.462294] Starting subtest: invalid-open-flags
[600.463360] Subtest invalid-open-flags: SUCCESS (0.000s)
[600.510984] [152/156] (458s left) kms_frontbuffer_tracking (fbc-2p-primscrn-shrfb-plflip-blt)
[600.604116] Starting subtest: fbc-2p-primscrn-shrfb-plflip-blt
[600.605838] Subtest fbc-2p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[600.704701] [153/156] (457s left) gem_softpin (zero)
[600.765597] Starting subtest: zero
[600.769209] Subtest zero: SUCCESS (0.004s)
[600.886429] [154/156] (457s left) kms_frontbuffer_tracking (fbchdr-1p-shrfb-fliptrack-mmap-gtt)
[600.976514] Starting subtest: fbchdr-1p-shrfb-fliptrack-mmap-gtt
[600.978118] Subtest fbchdr-1p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[601.067444] [155/156] (457s left) kms_hdmi_inject (inject-4k)
[601.404264] Starting subtest: inject-4k
[601.758132] Subtest inject-4k: SUCCESS (0.354s)
[602.246648] [156/156] (456s left) kms_feature_discovery (display-1x)
[602.336169] Starting subtest: display-1x
[602.337144] Subtest display-1x: SUCCESS (0.000s)
[602.407780] Closing watchdogs
results: parsing output: 0/ for test: kms_frontbuffer_tracking
results: parsing output: 1/ for test: kms_chamelium_hpd
results: parsing output: 2/ for test: kms_cursor_legacy
results: parsing output: 3/ for test: kms_frontbuffer_tracking
results: parsing output: 4/ for test: kms_vblank
results: parsing output: 5/ for test: sw_sync
results: parsing output: 6/ for test: gem_workarounds
results: parsing output: 7/ for test: gem_pxp
results: parsing output: 8/ for test: kms_setmode
results: parsing output: 9/ for test: kms_rotation_crc
results: parsing output: 10/ for test: kms_frontbuffer_tracking
results: parsing output: 11/ for test: gem_pread
results: parsing output: 12/ for test: kms_frontbuffer_tracking
results: parsing output: 13/ for test: kms_psr
results: parsing output: 14/ for test: gem_close_race
results: parsing output: 15/ for test: kms_psr
results: parsing output: 16/ for test: gem_fenced_exec_thrash
results: parsing output: 17/ for test: gem_exec_params
results: parsing output: 18/ for test: kms_frontbuffer_tracking
results: parsing output: 19/ for test: gem_exec_schedule
results: parsing output: 20/ for test: kms_frontbuffer_tracking
results: parsing output: 21/ for test: kms_lease
results: parsing output: 22/ for test: kms_frontbuffer_tracking
results: parsing output: 23/ for test: kms_frontbuffer_tracking
results: parsing output: 24/ for test: syncobj_eventfd
results: parsing output: 25/ for test: kms_cursor_legacy
results: parsing output: 26/ for test: gem_exec_schedule
results: parsing output: 27/ for test: syncobj_wait
results: parsing output: 28/ for test: kms_cursor_legacy
results: parsing output: 29/ for test: kms_busy
results: parsing output: 30/ for test: i915_pm_rps
results: parsing output: 31/ for test: gen9_exec_parse
results: parsing output: 32/ for test: kms_color_pipeline
results: parsing output: 33/ for test: kms_plane
results: parsing output: 34/ for test: kms_psr2_sf
results: parsing output: 35/ for test: kms_frontbuffer_tracking
results: parsing output: 36/ for test: kms_frontbuffer_tracking
results: parsing output: 37/ for test: syncobj_timeline
results: parsing output: 38/ for test: syncobj_wait
results: parsing output: 39/ for test: kms_atomic
results: parsing output: 40/ for test: kms_plane_scaling
results: parsing output: 41/ for test: kms_cursor_legacy
results: parsing output: 42/ for test: kms_big_fb
results: parsing output: 43/ for test: gem_mmap_gtt
results: parsing output: 44/ for test: kms_frontbuffer_tracking
results: parsing output: 45/ for test: gem_mmap_offset
results: parsing output: 46/ for test: kms_frontbuffer_tracking
results: parsing output: 47/ for test: kms_hdr
results: parsing output: 48/ for test: kms_frontbuffer_tracking
results: parsing output: 49/ for test: kms_frontbuffer_tracking
results: parsing output: 50/ for test: kms_ccs
results: parsing output: 51/ for test: kms_vblank
results: parsing output: 52/ for test: kms_pipe_crc_basic
results: parsing output: 53/ for test: kms_cursor_legacy
results: parsing output: 54/ for test: kms_async_flips
results: parsing output: 55/ for test: kms_frontbuffer_tracking
results: parsing output: 56/ for test: syncobj_wait
results: parsing output: 57/ for test: kms_frontbuffer_tracking
results: parsing output: 58/ for test: kms_frontbuffer_tracking
results: parsing output: 59/ for test: kms_frontbuffer_tracking
results: parsing output: 60/ for test: gem_render_copy_redux
results: parsing output: 61/ for test: gem_exec_whisper
results: parsing output: 62/ for test: drm_buddy
results: parsing output: 63/ for test: perf
results: parsing output: 64/ for test: kms_frontbuffer_tracking
results: parsing output: 65/ for test: kms_big_fb
results: parsing output: 66/ for test: kms_frontbuffer_tracking
results: parsing output: 67/ for test: gem_ccs
results: parsing output: 68/ for test: kms_ccs
results: parsing output: 69/ for test: gem_ctx_sseu
results: parsing output: 70/ for test: gem_wait
results: parsing output: 71/ for test: kms_psr
results: parsing output: 72/ for test: kms_frontbuffer_tracking
results: parsing output: 73/ for test: sysfs_timeslice_duration
results: parsing output: 74/ for test: vgem_basic
results: parsing output: 75/ for test: i915_pm_rc6_residency
results: parsing output: 76/ for test: kms_big_fb
results: parsing output: 77/ for test: kms_psr
results: parsing output: 78/ for test: kms_frontbuffer_tracking
results: parsing output: 79/ for test: kms_frontbuffer_tracking
results: parsing output: 80/ for test: gem_render_copy
results: parsing output: 81/ for test: kms_big_fb
results: parsing output: 82/ for test: kms_psr
results: parsing output: 83/ for test: kms_frontbuffer_tracking
results: parsing output: 84/ for test: kms_frontbuffer_tracking
results: parsing output: 85/ for test: gem_ctx_persistence
results: parsing output: 86/ for test: kms_prime
results: parsing output: 87/ for test: gem_exec_balancer
results: parsing output: 88/ for test: gen9_exec_parse
results: parsing output: 89/ for test: gem_exec_fence
results: parsing output: 90/ for test: vgem_basic
results: parsing output: 91/ for test: kms_sequence
results: parsing output: 92/ for test: gem_eio
results: parsing output: 93/ for test: gem_exec_reloc
results: parsing output: 94/ for test: kms_big_fb
results: parsing output: 95/ for test: kms_invalid_mode
results: parsing output: 96/ for test: kms_frontbuffer_tracking
results: parsing output: 97/ for test: syncobj_timeline
results: parsing output: 98/ for test: kms_flip
results: parsing output: 99/ for test: kms_cursor_crc
results: parsing output: 100/ for test: kms_vrr
results: parsing output: 101/ for test: api_intel_allocator
results: parsing output: 102/ for test: kms_frontbuffer_tracking
results: parsing output: 103/ for test: kms_flip_scaled_crc
results: parsing output: 104/ for test: gem_ctx_engines
results: parsing output: 105/ for test: syncobj_timeline
results: parsing output: 106/ for test: kms_content_protection
results: parsing output: 107/ for test: kms_flip
results: parsing output: 108/ for test: kms_big_fb
results: parsing output: 109/ for test: kms_frontbuffer_tracking
results: parsing output: 110/ for test: kms_frontbuffer_tracking
results: parsing output: 111/ for test: kms_frontbuffer_tracking
results: parsing output: 112/ for test: kms_rotation_crc
results: parsing output: 113/ for test: kms_frontbuffer_tracking
results: parsing output: 114/ for test: syncobj_timeline
results: parsing output: 115/ for test: kms_cursor_crc
results: parsing output: 116/ for test: kms_frontbuffer_tracking
results: parsing output: 117/ for test: kms_frontbuffer_tracking
results: parsing output: 118/ for test: gem_exec_flush
results: parsing output: 119/ for test: gem_mmap_wc
results: parsing output: 120/ for test: kms_pm_rpm
results: parsing output: 121/ for test: kms_frontbuffer_tracking
results: parsing output: 122/ for test: syncobj_basic
results: parsing output: 123/ for test: api_intel_bb
results: parsing output: 124/ for test: gem_exec_capture
results: parsing output: 125/ for test: gem_mmap_wc
results: parsing output: 126/ for test: gem_mmap
results: parsing output: 127/ for test: gem_lmem_swapping
results: parsing output: 128/ for test: kms_frontbuffer_tracking
results: parsing output: 129/ for test: perf_pmu
results: parsing output: 130/ for test: kms_frontbuffer_tracking
results: parsing output: 131/ for test: kms_ccs
results: parsing output: 132/ for test: kms_frontbuffer_tracking
results: parsing output: 133/ for test: kms_ccs
results: parsing output: 134/ for test: kms_psr2_sf
results: parsing output: 135/ for test: kms_frontbuffer_tracking
results: parsing output: 136/ for test: kms_pm_backlight
results: parsing output: 137/ for test: kms_frontbuffer_tracking
results: parsing output: 138/ for test: kms_atomic_transition
results: parsing output: 139/ for test: kms_frontbuffer_tracking
results: parsing output: 140/ for test: kms_chamelium_edid
results: parsing output: 141/ for test: kms_psr
results: parsing output: 142/ for test: kms_ccs
results: parsing output: 143/ for test: gem_exec_suspend
results: parsing output: 144/ for test: kms_plane
results: parsing output: 145/ for test: kms_frontbuffer_tracking
results: parsing output: 146/ for test: i915_pm_freq_api
results: parsing output: 147/ for test: gem_exec_fence
results: parsing output: 148/ for test: kms_cursor_crc
results: parsing output: 149/ for test: gem_ctx_persistence
results: parsing output: 150/ for test: perf
results: parsing output: 151/ for test: kms_frontbuffer_tracking
results: parsing output: 152/ for test: gem_softpin
results: parsing output: 153/ for test: kms_frontbuffer_tracking
results: parsing output: 154/ for test: kms_hdmi_inject
results: parsing output: 155/ for test: kms_feature_discovery
Done.