[57.978656] Initializing watchdogs
[57.978707]   /dev/watchdog0
[57.993261] [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.998272] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[58.000863] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[58.004532] [001/133] (960s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-cpu)
[58.126311] Starting subtest: psr-rgb565-draw-mmap-cpu
[65.054064] Subtest psr-rgb565-draw-mmap-cpu: SUCCESS (6.904s)
[65.154586] [002/133] (953s left) gem_exec_schedule (wide)
[65.339701] Starting subtest: wide
[65.342598] Starting dynamic subtest: rcs0
[76.021844] Dynamic subtest rcs0: SUCCESS (10.669s)
[76.029907] Starting dynamic subtest: vcs0
[85.848739] Dynamic subtest vcs0: SUCCESS (9.822s)
[85.859071] Starting dynamic subtest: vcs1
[97.309954] Dynamic subtest vcs1: SUCCESS (11.457s)
[97.321729] Starting dynamic subtest: vecs0
[108.798699] Dynamic subtest vecs0: SUCCESS (11.485s)
[108.808387] Starting dynamic subtest: ccs0
[122.501553] Dynamic subtest ccs0: SUCCESS (13.701s)
[122.504541] Subtest wide: SUCCESS (57.139s)
[124.012994] [FACT gem_exec_schedule (wide)] new: kernel.kmod_is_loaded.vgem: true
[124.015812] [003/133] (894s left) gem_mmap_gtt (coherency)
[124.088775] Subtest coherency: SKIP (0.000s)
[124.144263] [004/133] (894s left) kms_hdr (invalid-metadata-sizes)
[124.249740] Starting subtest: invalid-metadata-sizes
[124.256380] Subtest invalid-metadata-sizes: SKIP (0.000s)
[124.319759] [005/133] (894s left) gem_softpin (noreloc)
[124.381736] Starting subtest: noreloc
[129.775371] Subtest noreloc: SUCCESS (5.392s)
[129.898086] [006/133] (888s left) gem_mmap_gtt (ptrace)
[129.956968] Subtest ptrace: SKIP (0.000s)
[130.019901] [007/133] (888s left) kms_psr2_sf (psr2-primary-plane-update-sf-dmg-area-big-fb)
[130.131463] Starting subtest: psr2-primary-plane-update-sf-dmg-area-big-fb
[130.133706] Starting dynamic subtest: pipe-A-eDP-1
[136.793965] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.664s)
[136.803520] Starting dynamic subtest: pipe-B-eDP-1
[143.930806] Dynamic subtest pipe-B-eDP-1: SUCCESS (7.134s)
[143.933067] Subtest psr2-primary-plane-update-sf-dmg-area-big-fb: SUCCESS (13.801s)
[144.776254] [008/133] (874s left) kms_dsc (dsc-with-bpc-formats)
[144.869235] Starting subtest: dsc-with-bpc-formats
[144.982097] Subtest dsc-with-bpc-formats: SKIP (0.003s)
[145.032308] [009/133] (873s left) gem_pxp (verify-pxp-key-change-after-suspend-resume)
[145.148364] Starting subtest: verify-pxp-key-change-after-suspend-resume
[152.464287] Subtest verify-pxp-key-change-after-suspend-resume: SUCCESS (7.315s)
[152.583231] [010/133] (866s left) kms_setmode (clone-exclusive-crtc)
[152.647901] Starting subtest: clone-exclusive-crtc
[152.654639] Subtest clone-exclusive-crtc: SKIP (0.000s)
[152.758619] [011/133] (866s left) kms_vblank (wait-idle)
[152.847079] Starting subtest: wait-idle
[152.849447] Starting dynamic subtest: pipe-A-eDP-1
[154.612420] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.763s)
[154.625856] Starting dynamic subtest: pipe-D-eDP-1
[157.083266] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.470s)
[157.085645] Subtest wait-idle: SUCCESS (4.236s)
[157.902054] [012/133] (861s left) kms_async_flips (test-time-stamp)
[158.012460] Starting subtest: test-time-stamp
[158.398397] Starting dynamic subtest: pipe-A-eDP-1
[159.207766] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.807s)
[159.604061] Starting dynamic subtest: pipe-B-eDP-1
[160.349708] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.751s)
[160.745915] Starting dynamic subtest: pipe-C-eDP-1
[161.497058] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.757s)
[161.884352] Starting dynamic subtest: pipe-D-eDP-1
[162.728238] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.847s)
[162.730539] Subtest test-time-stamp: SUCCESS (4.714s)
[163.892653] [013/133] (855s left) kms_big_fb (y-tiled-64bpp-rotate-90)
[163.982329] Starting subtest: y-tiled-64bpp-rotate-90
[163.986784] Subtest y-tiled-64bpp-rotate-90: SKIP (0.000s)
[164.085227] [014/133] (854s left) kms_dp_linktrain_fallback (dsc-fallback)
[164.173434] Starting subtest: dsc-fallback
[164.179776] Subtest dsc-fallback: SKIP (0.000s)
[164.243474] [015/133] (854s left) gem_create (create-ext-placement-each)
[164.417353] Starting subtest: create-ext-placement-each
[164.419858] Subtest create-ext-placement-each: SUCCESS (0.000s)
[164.536736] [016/133] (854s left) gem_ctx_param (invalid-param-set)
[164.582832] Starting subtest: invalid-param-set
[164.585274] Subtest invalid-param-set: SUCCESS (0.000s)
[164.657314] [017/133] (854s left) kms_flip (flip-vs-rmfb-interruptible)
[164.744316] Starting subtest: flip-vs-rmfb-interruptible
[164.746623] Starting dynamic subtest: A-eDP1
[173.379488] Dynamic subtest A-eDP1: SUCCESS (8.634s)
[173.389703] Starting dynamic subtest: B-eDP1
[182.006259] Dynamic subtest B-eDP1: SUCCESS (8.624s)
[182.017829] Starting dynamic subtest: C-eDP1
[190.664641] Dynamic subtest C-eDP1: SUCCESS (8.655s)
[190.674322] Starting dynamic subtest: D-eDP1
[199.257703] Dynamic subtest D-eDP1: SUCCESS (8.591s)
[199.262184] Subtest flip-vs-rmfb-interruptible: SUCCESS (34.518s)
[200.437946] [018/133] (818s left) gem_exec_params (rel-constants-invalid-ring)
[200.499114] Starting subtest: rel-constants-invalid-ring
[200.501028] Subtest rel-constants-invalid-ring: SUCCESS (0.000s)
[200.591424] [019/133] (818s left) i915_drm_fdinfo (virtual-busy-hang-all)
[200.636167] Starting subtest: virtual-busy-hang-all
[200.640197] Subtest virtual-busy-hang-all: SKIP (0.000s)
[200.702717] [020/133] (818s left) api_intel_bb (object-reloc-keep-cache)
[200.753889] Starting subtest: object-reloc-keep-cache
[200.758459] Subtest object-reloc-keep-cache: SKIP (0.003s)
[200.861600] [021/133] (818s left) kms_ccs (ccs-on-another-bo-yf-tiled-ccs)
[200.950306] Starting subtest: ccs-on-another-bo-yf-tiled-ccs
[200.952650] Starting dynamic subtest: pipe-A-eDP-1
[200.976149] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[200.986780] Starting dynamic subtest: pipe-B-eDP-1
[201.008442] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[201.016781] Starting dynamic subtest: pipe-C-eDP-1
[201.038397] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[201.046319] Starting dynamic subtest: pipe-D-eDP-1
[201.068223] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[201.072552] Subtest ccs-on-another-bo-yf-tiled-ccs: SKIP (0.005s)
[203.212877] [022/133] (816s left) kms_big_fb (linear-addfb)
[203.317072] Starting subtest: linear-addfb
[203.319398] Subtest linear-addfb: SUCCESS (0.000s)
[203.427335] [023/133] (815s left) kms_cursor_crc (cursor-random-64x21)
[203.623912] Starting subtest: cursor-random-64x21
[203.671911] Subtest cursor-random-64x21: SKIP (0.043s)
[203.913837] [024/133] (815s left) core_hotunplug (unbind-rebind)
[204.016203] Starting subtest: unbind-rebind
[209.970716] Subtest unbind-rebind: SUCCESS (5.956s)
[210.010468] [025/133] (809s left) gem_flink_basic (flink-lifetime)
[211.900910] Starting subtest: flink-lifetime
[211.906332] Subtest flink-lifetime: SUCCESS (0.008s)
[212.032924] [026/133] (807s left) kms_prop_blob (blob-prop-lifetime)
[212.115180] Starting subtest: blob-prop-lifetime
[212.117438] Subtest blob-prop-lifetime: SUCCESS (0.001s)
[212.235208] [027/133] (807s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt)
[212.349300] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt
[212.353318] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[212.464809] [028/133] (807s left) kms_ccs (bad-aux-stride-4-tiled-mtl-mc-ccs)
[212.560081] Starting subtest: bad-aux-stride-4-tiled-mtl-mc-ccs
[212.562198] Starting dynamic subtest: pipe-A-eDP-1
[212.621843] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.063s)
[212.630754] Starting dynamic subtest: pipe-B-eDP-1
[212.686690] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.063s)
[212.695444] Starting dynamic subtest: pipe-C-eDP-1
[212.750606] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.062s)
[212.759271] Starting dynamic subtest: pipe-D-eDP-1
[212.815612] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.061s)
[212.817811] Subtest bad-aux-stride-4-tiled-mtl-mc-ccs: SUCCESS (0.256s)
[215.128976] [029/133] (804s left) gem_wait (write-wait)
[215.217753] Starting subtest: write-wait
[215.219696] Starting dynamic subtest: all-engines
[216.315707] Dynamic subtest all-engines: SUCCESS (1.100s)
[216.336539] Starting dynamic subtest: rcs0
[217.380134] Dynamic subtest rcs0: SUCCESS (1.061s)
[217.403998] Starting dynamic subtest: bcs0
[218.444848] Dynamic subtest bcs0: SUCCESS (1.061s)
[218.461704] Starting dynamic subtest: vcs0
[219.497639] Dynamic subtest vcs0: SUCCESS (1.050s)
[219.513954] Starting dynamic subtest: vcs1
[220.551590] Dynamic subtest vcs1: SUCCESS (1.051s)
[220.568044] Starting dynamic subtest: vecs0
[221.604268] Dynamic subtest vecs0: SUCCESS (1.050s)
[221.620955] Starting dynamic subtest: ccs0
[222.663325] Dynamic subtest ccs0: SUCCESS (1.054s)
[222.665834] Subtest write-wait: SUCCESS (7.447s)
[222.863875] [030/133] (796s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-mmap-cpu)
[222.964086] Starting subtest: psr-1p-offscreen-pri-indfb-draw-mmap-cpu
[229.625968] Subtest psr-1p-offscreen-pri-indfb-draw-mmap-cpu: SUCCESS (6.663s)
[229.742663] [031/133] (789s left) kms_pm_rpm (dpms-lpsp)
[231.268433] Starting subtest: dpms-lpsp
[231.924661] Subtest dpms-lpsp: SUCCESS (0.660s)
[232.015490] [032/133] (787s left) gem_blits (basic)
[232.086077] Starting subtest: basic
[233.484116] Subtest basic: SUCCESS (1.398s)
[233.600094] [033/133] (786s left) kms_sequence (get-busy)
[233.683983] Starting subtest: get-busy
[233.686246] Starting dynamic subtest: pipe-A-eDP-1
[235.632174] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.946s)
[235.642263] Starting dynamic subtest: pipe-B-eDP-1
[238.203771] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.569s)
[238.218028] Starting dynamic subtest: pipe-C-eDP-1
[240.813116] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.612s)
[240.822723] Starting dynamic subtest: pipe-D-eDP-1
[243.370381] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.554s)
[243.372652] Subtest get-busy: SUCCESS (9.687s)
[244.242671] [034/133] (775s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-cpu)
[244.340429] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-cpu
[244.344900] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[244.425153] [035/133] (775s left) syncobj_wait (reset-multiple-signaled)
[244.490903] Starting subtest: reset-multiple-signaled
[244.493320] Subtest reset-multiple-signaled: SUCCESS (0.000s)
[244.585814] [036/133] (775s left) kms_plane (pixel-format-linear-modifier)
[244.678394] Starting subtest: pixel-format-linear-modifier
[244.706885] Starting dynamic subtest: pipe-A-plane-0
[248.173443] Dynamic subtest pipe-A-plane-0: SUCCESS (3.467s)
[248.190215] Starting dynamic subtest: pipe-A-plane-3
[249.839956] Dynamic subtest pipe-A-plane-3: SUCCESS (1.664s)
[249.854647] Starting dynamic subtest: pipe-A-plane-5
[250.051060] Dynamic subtest pipe-A-plane-5: SUCCESS (0.210s)
[251.139152] Starting dynamic subtest: pipe-B-plane-0
[254.626655] Dynamic subtest pipe-B-plane-0: SUCCESS (3.499s)
[254.637801] Starting dynamic subtest: pipe-B-plane-3
[256.338862] Dynamic subtest pipe-B-plane-3: SUCCESS (1.709s)
[256.347870] Starting dynamic subtest: pipe-B-plane-5
[256.559838] Dynamic subtest pipe-B-plane-5: SUCCESS (0.221s)
[256.877071] Subtest pixel-format-linear-modifier: SUCCESS (12.199s)
[257.708130] [037/133] (762s left) gem_ctx_shared (q-promotion)
[257.770621] Starting subtest: Q-promotion
[257.773074] Starting dynamic subtest: rcs0
[257.880569] Dynamic subtest rcs0: SUCCESS (0.108s)
[257.898889] Starting dynamic subtest: vcs0
[257.974588] Dynamic subtest vcs0: SUCCESS (0.093s)
[257.993997] Starting dynamic subtest: vcs1
[258.056023] Dynamic subtest vcs1: SUCCESS (0.081s)
[258.072731] Starting dynamic subtest: vecs0
[258.136840] Dynamic subtest vecs0: SUCCESS (0.079s)
[258.153315] Starting dynamic subtest: ccs0
[258.216771] Dynamic subtest ccs0: SUCCESS (0.080s)
[258.219204] Subtest Q-promotion: SUCCESS (0.446s)
[260.019030] [038/133] (760s left) kms_rotation_crc (primary-x-tiled-reflect-x-0)
[260.113951] Starting subtest: primary-x-tiled-reflect-x-0
[267.000657] Subtest primary-x-tiled-reflect-x-0: SUCCESS (6.889s)
[268.192825] [039/133] (751s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-mmap-wc)
[268.286350] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-mmap-wc
[268.290392] Subtest fbc-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[268.380290] [040/133] (751s left) kms_color (invalid-ctm-matrix-sizes)
[268.474707] Starting subtest: invalid-ctm-matrix-sizes
[268.477013] Starting dynamic subtest: pipe-A
[268.479206] Dynamic subtest pipe-A: SUCCESS (0.002s)
[268.488994] Starting dynamic subtest: pipe-B
[268.491594] Dynamic subtest pipe-B: SUCCESS (0.003s)
[268.500692] Starting dynamic subtest: pipe-C
[268.502921] Dynamic subtest pipe-C: SUCCESS (0.004s)
[268.511452] Starting dynamic subtest: pipe-D
[268.513737] Dynamic subtest pipe-D: SUCCESS (0.004s)
[268.515966] Subtest invalid-ctm-matrix-sizes: SUCCESS (0.016s)
[268.571447] [041/133] (751s left) perf (invalid-oa-format-id)
[268.715607] Starting subtest: invalid-oa-format-id
[268.726578] Subtest invalid-oa-format-id: SUCCESS (0.010s)
[268.777768] [042/133] (751s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-wc)
[268.872309] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-wc
[275.956381] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-wc: SUCCESS (7.087s)
[276.056503] [043/133] (744s left) kms_cdclk (mode-transition)
[276.151120] Starting subtest: mode-transition
[276.153381] Starting dynamic subtest: pipe-A-eDP-1
[276.448472] Dynamic subtest pipe-A-eDP-1: SKIP (0.296s)
[276.458118] Starting dynamic subtest: pipe-B-eDP-1
[276.462571] Dynamic subtest pipe-B-eDP-1: SKIP (0.002s)
[276.471488] Starting dynamic subtest: pipe-C-eDP-1
[276.475955] Dynamic subtest pipe-C-eDP-1: SKIP (0.004s)
[276.485241] Starting dynamic subtest: pipe-D-eDP-1
[276.489788] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[276.494181] Subtest mode-transition: SKIP (0.310s)
[277.244098] [044/133] (743s left) kms_plane_scaling (plane-downscale-factor-0-5-with-rotation)
[277.345081] Starting subtest: plane-downscale-factor-0-5-with-rotation
[277.347425] Starting dynamic subtest: pipe-A
[277.365311] Dynamic subtest pipe-A: SKIP (0.017s)
[277.374900] Starting dynamic subtest: pipe-B
[277.396778] Dynamic subtest pipe-B: SKIP (0.031s)
[277.406151] Starting dynamic subtest: pipe-C
[277.427634] Dynamic subtest pipe-C: SKIP (0.030s)
[277.437261] Starting dynamic subtest: pipe-D
[277.457339] Dynamic subtest pipe-D: SKIP (0.030s)
[277.460946] Subtest plane-downscale-factor-0-5-with-rotation: SKIP (0.110s)
[277.568794] [045/133] (742s left) kms_cursor_crc (cursor-tearing-framebuffer-change)
[277.679981] Starting subtest: cursor-tearing-framebuffer-change
[277.682331] Starting dynamic subtest: pipe-A-eDP-1
[278.232119] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.550s)
[278.242417] Starting dynamic subtest: pipe-D-eDP-1
[279.472935] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.239s)
[279.475194] Subtest cursor-tearing-framebuffer-change: SUCCESS (1.792s)
[280.302571] [046/133] (740s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[280.428598] Starting subtest: basic-flip-before-cursor-legacy
[281.342213] Subtest basic-flip-before-cursor-legacy: SUCCESS (0.910s)
[282.139479] [047/133] (738s left) kms_big_fb (x-tiled-32bpp-rotate-180)
[282.242558] Starting subtest: x-tiled-32bpp-rotate-180
[283.711181] Subtest x-tiled-32bpp-rotate-180: SUCCESS (1.468s)
[283.863957] [048/133] (736s left) kms_chamelium_color (ctm-red-to-blue)
[283.968222] Subtest ctm-red-to-blue: SKIP (0.000s)
[284.016482] [049/133] (736s left) syncobj_eventfd (timeline-wait)
[284.067521] Starting subtest: timeline-wait
[284.076247] Subtest timeline-wait: SUCCESS (0.010s)
[284.148760] [050/133] (736s left) kms_setmode (basic-clone-single-crtc)
[284.195750] Starting subtest: basic-clone-single-crtc
[284.201070] Subtest basic-clone-single-crtc: SKIP (0.000s)
[284.275916] [051/133] (736s left) syncobj_timeline (etime-multi-wait-all-submitted)
[284.321239] Starting subtest: etime-multi-wait-all-submitted
[284.426076] Subtest etime-multi-wait-all-submitted: SUCCESS (0.103s)
[284.515253] [052/133] (736s left) gem_userptr_blits (sync-unmap)
[284.577473] Starting subtest: sync-unmap
[284.590967] Subtest sync-unmap: SUCCESS (0.014s)
[284.702509] [053/133] (735s left) gem_exec_balancer (bonded-false-hang)
[284.960721] Starting subtest: bonded-false-hang
[284.966506] Subtest bonded-false-hang: SKIP (0.000s)
[285.075192] [054/133] (735s left) kms_frontbuffer_tracking (fbcpsr-1p-indfb-fliptrack-mmap-gtt)
[285.171754] Starting subtest: fbcpsr-1p-indfb-fliptrack-mmap-gtt
[285.176053] Subtest fbcpsr-1p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[285.262893] [055/133] (735s left) gem_ctx_persistence (legacy-engines-hang)
[285.328288] Starting subtest: legacy-engines-hang
[285.330694] Starting dynamic subtest: render
[285.401432] Dynamic subtest render: SUCCESS (0.071s)
[285.413771] Starting dynamic subtest: bsd1
[285.513503] Dynamic subtest bsd1: SUCCESS (0.109s)
[285.528657] Starting dynamic subtest: bsd2
[285.597062] Dynamic subtest bsd2: SUCCESS (0.081s)
[285.609110] Starting dynamic subtest: blt
[285.686145] Dynamic subtest blt: SUCCESS (0.087s)
[285.703232] Starting dynamic subtest: vebox
[285.766914] Dynamic subtest vebox: SUCCESS (0.078s)
[285.769418] Subtest legacy-engines-hang: SUCCESS (0.439s)
[285.892464] [056/133] (734s left) kms_flip (2x-flip-vs-absolute-wf_vblank)
[285.986567] Starting subtest: 2x-flip-vs-absolute-wf_vblank
[285.999065] Subtest 2x-flip-vs-absolute-wf_vblank: SKIP (0.012s)
[286.078243] [057/133] (734s left) gem_create (create-valid-nonaligned)
[286.132403] Starting subtest: create-valid-nonaligned
[286.134075] Subtest create-valid-nonaligned: SUCCESS (0.000s)
[286.318225] [058/133] (734s left) sw_sync (sync_merge_invalid)
[286.365630] Starting subtest: sync_merge_invalid
[286.385967] Subtest sync_merge_invalid: SUCCESS (0.018s)
[286.453674] [059/133] (734s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling)
[286.540338] Starting subtest: flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling
[286.544762] Subtest flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling: SKIP (0.000s)
[286.613733] [060/133] (734s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc)
[286.705416] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc
[286.707789] Starting dynamic subtest: pipe-A-eDP-1
[286.730435] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[286.743511] Starting dynamic subtest: pipe-B-eDP-1
[286.765225] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[286.773959] Starting dynamic subtest: pipe-C-eDP-1
[286.794847] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[286.803157] Starting dynamic subtest: pipe-D-eDP-1
[286.824641] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[286.829065] Subtest crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc: SKIP (0.006s)
[288.999313] [061/133] (732s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[289.109371] Starting subtest: basic-busy-flip-before-cursor-legacy
[289.113691] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s)
[289.253911] [062/133] (731s left) kms_cursor_legacy (cursora-vs-flipb-atomic-transitions-varying-size)
[289.373578] Starting subtest: cursorA-vs-flipB-atomic-transitions-varying-size
[289.378193] Subtest cursorA-vs-flipB-atomic-transitions-varying-size: SKIP (0.001s)
[289.532792] [063/133] (731s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-16bpp-xtile-upscaling)
[289.621336] Starting subtest: flip-64bpp-xtile-to-16bpp-xtile-upscaling
[289.623645] Starting dynamic subtest: pipe-A-default-mode
[292.309278] Dynamic subtest pipe-A-default-mode: SUCCESS (2.688s)
[292.311717] Subtest flip-64bpp-xtile-to-16bpp-xtile-upscaling: SUCCESS (2.690s)
[293.177952] [064/133] (727s left) kms_flip (plain-flip-interruptible)
[293.275036] Starting subtest: plain-flip-interruptible
[293.277444] Starting dynamic subtest: A-eDP1
[294.985524] Dynamic subtest A-eDP1: SUCCESS (1.709s)
[294.998090] Starting dynamic subtest: B-eDP1
[296.606658] Dynamic subtest B-eDP1: SUCCESS (1.617s)
[296.621455] Starting dynamic subtest: C-eDP1
[298.231978] Dynamic subtest C-eDP1: SUCCESS (1.622s)
[298.241363] Starting dynamic subtest: D-eDP1
[299.873561] Dynamic subtest D-eDP1: SUCCESS (1.639s)
[299.879214] Subtest plain-flip-interruptible: SUCCESS (6.604s)
[301.088165] [065/133] (720s left) kms_plane_scaling (planes-downscale-factor-0-75-unity-scaling)
[301.196257] Starting subtest: planes-downscale-factor-0-75-unity-scaling
[301.198430] Starting dynamic subtest: pipe-A
[301.260064] Dynamic subtest pipe-A: SUCCESS (0.065s)
[301.270158] Starting dynamic subtest: pipe-B
[302.379549] Dynamic subtest pipe-B: SUCCESS (1.117s)
[302.390628] Starting dynamic subtest: pipe-C
[303.499645] Dynamic subtest pipe-C: SUCCESS (1.119s)
[303.508887] Starting dynamic subtest: pipe-D
[304.619567] Dynamic subtest pipe-D: SUCCESS (1.119s)
[304.621900] Subtest planes-downscale-factor-0-75-unity-scaling: SUCCESS (3.426s)
[305.780408] [066/133] (715s left) kms_big_fb (4-tiled-64bpp-rotate-270)
[305.876166] Starting subtest: 4-tiled-64bpp-rotate-270
[305.898216] Subtest 4-tiled-64bpp-rotate-270: SKIP (0.019s)
[305.999852] [067/133] (715s left) perf (stress-open-close)
[306.091062] Starting subtest: stress-open-close
[306.093287] Starting dynamic subtest: 0-rcs0
[308.257600] Dynamic subtest 0-rcs0: SUCCESS (2.168s)
[308.266876] Starting dynamic subtest: 1-vcs0
[310.423468] Dynamic subtest 1-vcs0: SUCCESS (2.164s)
[310.425595] Subtest stress-open-close: SUCCESS (4.334s)
[310.472733] [068/133] (710s left) gem_ctx_param (invalid-set-no-zeromap)
[310.519646] Starting subtest: invalid-set-no-zeromap
[310.521777] Subtest invalid-set-no-zeromap: SUCCESS (0.000s)
[310.607763] [069/133] (710s left) perf_pmu (busy)
[310.641170] Starting subtest: busy
[310.643190] Starting dynamic subtest: rcs0
[311.255538] Dynamic subtest rcs0: SUCCESS (0.614s)
[311.266036] Starting dynamic subtest: bcs0
[311.819663] Dynamic subtest bcs0: SUCCESS (0.562s)
[311.831656] Starting dynamic subtest: vcs0
[313.876942] Dynamic subtest vcs0: SUCCESS (2.053s)
[313.889848] Starting dynamic subtest: vcs1
[315.926327] Dynamic subtest vcs1: SUCCESS (2.046s)
[315.938843] Starting dynamic subtest: vecs0
[317.910075] Dynamic subtest vecs0: SUCCESS (1.980s)
[317.922879] Starting dynamic subtest: ccs0
[318.481316] Dynamic subtest ccs0: SUCCESS (0.570s)
[318.483799] Subtest busy: SUCCESS (7.841s)
[318.599937] [070/133] (702s left) kms_flip (2x-flip-vs-wf_vblank)
[318.703122] Starting subtest: 2x-flip-vs-wf_vblank
[318.711902] Subtest 2x-flip-vs-wf_vblank: SKIP (0.011s)
[318.830241] [071/133] (702s left) gem_exec_reloc (basic-gtt-active)
[318.898020] Subtest basic-gtt-active: SKIP (0.000s)
[318.966452] [072/133] (702s left) api_intel_bb (crc32)
[319.028616] Starting subtest: crc32
[319.030893] Starting dynamic subtest: rcs0-smem0
[319.316547] Dynamic subtest rcs0-smem0: SUCCESS (0.287s)
[319.329369] Starting dynamic subtest: bcs0-smem0
[319.642047] Dynamic subtest bcs0-smem0: SUCCESS (0.323s)
[319.652694] Starting dynamic subtest: vcs0-smem0
[320.018573] Dynamic subtest vcs0-smem0: SUCCESS (0.374s)
[320.031759] Starting dynamic subtest: vcs1-smem0
[320.395512] Dynamic subtest vcs1-smem0: SUCCESS (0.374s)
[320.408675] Starting dynamic subtest: vecs0-smem0
[320.791335] Dynamic subtest vecs0-smem0: SUCCESS (0.393s)
[320.802101] Starting dynamic subtest: ccs0-smem0
[321.159098] Dynamic subtest ccs0-smem0: SUCCESS (0.365s)
[321.161785] Subtest crc32: SUCCESS (2.132s)
[321.293858] [073/133] (700s left) kms_lease (lease-revoke)
[321.395279] Starting subtest: lease-revoke
[321.397501] Starting dynamic subtest: pipe-A-eDP-1
[321.426635] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.030s)
[321.440161] Starting dynamic subtest: pipe-B-eDP-1
[321.454444] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.025s)
[321.468254] Starting dynamic subtest: pipe-C-eDP-1
[321.482187] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.028s)
[321.494200] Starting dynamic subtest: pipe-D-eDP-1
[321.511212] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.027s)
[321.513096] Subtest lease-revoke: SUCCESS (0.116s)
[321.650090] [074/133] (699s left) kms_ccs (bad-rotation-90-4-tiled-dg2-rc-ccs-cc)
[321.743253] Starting subtest: bad-rotation-90-4-tiled-dg2-rc-ccs-cc
[321.745495] Starting dynamic subtest: pipe-A-eDP-1
[321.768687] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[321.780800] Starting dynamic subtest: pipe-B-eDP-1
[321.803660] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[321.813203] Starting dynamic subtest: pipe-C-eDP-1
[321.834839] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[321.844151] Starting dynamic subtest: pipe-D-eDP-1
[321.865888] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[321.869985] Subtest bad-rotation-90-4-tiled-dg2-rc-ccs-cc: SKIP (0.005s)
[323.963704] [075/133] (697s left) kms_ccs (missing-ccs-buffer-4-tiled-mtl-rc-ccs-cc)
[324.054711] Starting subtest: missing-ccs-buffer-4-tiled-mtl-rc-ccs-cc
[324.056958] Starting dynamic subtest: pipe-A-eDP-1
[324.076485] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[324.087071] Starting dynamic subtest: pipe-B-eDP-1
[324.105538] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[324.114207] Starting dynamic subtest: pipe-C-eDP-1
[324.133544] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[324.146830] Starting dynamic subtest: pipe-D-eDP-1
[324.166376] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[324.168313] Subtest missing-ccs-buffer-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.006s)
[326.332496] [076/133] (695s left) gem_ctx_param (set-priority-not-supported)
[326.381773] Starting subtest: set-priority-not-supported
[326.385836] Subtest set-priority-not-supported: SKIP (0.000s)
[326.456371] [077/133] (695s left) kms_flip (2x-plain-flip-ts-check-interruptible)
[326.549534] Starting subtest: 2x-plain-flip-ts-check-interruptible
[326.562849] Subtest 2x-plain-flip-ts-check-interruptible: SKIP (0.012s)
[326.644104] [078/133] (695s left) gem_exercise_blt (fast-copy)
[326.701345] Starting subtest: fast-copy
[326.703706] Starting dynamic subtest: linear-smem-smem
[326.726105] Dynamic subtest linear-smem-smem: SUCCESS (0.024s)
[326.736030] Starting dynamic subtest: xmajor-smem-smem
[326.746116] Dynamic subtest xmajor-smem-smem: SUCCESS (0.019s)
[326.755831] Starting dynamic subtest: tile4-smem-smem
[326.766252] Dynamic subtest tile4-smem-smem: SUCCESS (0.019s)
[326.775741] Starting dynamic subtest: tile64-smem-smem
[326.785748] Dynamic subtest tile64-smem-smem: SUCCESS (0.019s)
[326.788127] Subtest fast-copy: SUCCESS (0.085s)
[326.910449] [079/133] (694s left) gem_ctx_exec (basic)
[326.968746] Starting subtest: basic
[326.972201] Subtest basic: SUCCESS (0.006s)
[327.098176] [080/133] (694s left) perf (non-system-wide-paranoid)
[327.196151] Starting subtest: non-system-wide-paranoid
[327.217965] Subtest non-system-wide-paranoid: SUCCESS (0.024s)
[327.268333] [081/133] (694s left) kms_psr (pr-sprite-render)
[327.371978] Starting subtest: pr-sprite-render
[327.375730] Subtest pr-sprite-render: SKIP (0.000s)
[327.448618] [082/133] (694s left) i915_pm_rpm (sysfs-read)
[329.072358] Starting subtest: sysfs-read
[330.232084] Subtest sysfs-read: SUCCESS (1.160s)
[330.607549] [083/133] (691s left) gem_exec_gttfill (all-engines)
[330.681684] Starting subtest: all-engines
[353.842639] Subtest all-engines: SUCCESS (23.162s)
[354.050008] [084/133] (667s left) gem_exec_fence (syncobj-import)
[354.575019] Starting subtest: syncobj-import
[354.578951] Subtest syncobj-import: SUCCESS (0.007s)
[356.003424] [085/133] (666s left) gem_exec_schedule (u-submit-early-slice)
[356.087439] Starting subtest: u-submit-early-slice
[356.089745] Starting dynamic subtest: rcs0
[356.143969] Dynamic subtest rcs0: SUCCESS (0.064s)
[356.157171] Starting dynamic subtest: bcs0
[356.204487] Dynamic subtest bcs0: SUCCESS (0.059s)
[356.217490] Starting dynamic subtest: vcs0
[356.264708] Dynamic subtest vcs0: SUCCESS (0.059s)
[356.277844] Starting dynamic subtest: vcs1
[356.321275] Dynamic subtest vcs1: SUCCESS (0.055s)
[356.332567] Starting dynamic subtest: vecs0
[356.374901] Dynamic subtest vecs0: SUCCESS (0.053s)
[356.389929] Starting dynamic subtest: ccs0
[356.433821] Dynamic subtest ccs0: SUCCESS (0.058s)
[356.436244] Subtest u-submit-early-slice: SUCCESS (0.355s)
[357.995448] [086/133] (664s left) gem_mmap_gtt (fault-concurrent-x)
[358.059740] Subtest fault-concurrent-X: SKIP (0.000s)
[358.127416] [087/133] (664s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-gtt)
[358.237569] Starting subtest: fbcpsr-rgb565-draw-mmap-gtt
[358.242030] Subtest fbcpsr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[358.343928] [088/133] (663s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-blt)
[358.443489] Starting subtest: psr-1p-primscrn-cur-indfb-draw-blt
[365.188434] Subtest psr-1p-primscrn-cur-indfb-draw-blt: SUCCESS (6.748s)
[365.304270] [089/133] (656s left) perf (buffer-fill)
[365.429425] Starting subtest: buffer-fill
[365.431955] Starting dynamic subtest: 0-rcs0
[367.681107] Dynamic subtest 0-rcs0: SUCCESS (2.253s)
[367.692591] Starting dynamic subtest: 1-vecs0
[372.133340] Dynamic subtest 1-vecs0: SUCCESS (4.450s)
[372.136058] Subtest buffer-fill: SUCCESS (6.707s)
[372.207398] [090/133] (650s left) kms_psr (fbc-psr-cursor-plane-onoff)
[372.310082] Starting subtest: fbc-psr-cursor-plane-onoff
[372.312439] Starting dynamic subtest: eDP-1
[372.316949] Dynamic subtest eDP-1: SKIP (0.000s)
[372.321508] Subtest fbc-psr-cursor-plane-onoff: SKIP (0.001s)
[372.387279] [091/133] (649s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[372.491737] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu
[372.496178] Subtest fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[372.583462] [092/133] (649s left) gen9_exec_parse (basic-rejected-ctx-param)
[372.657646] Subtest basic-rejected-ctx-param: SKIP (0.000s)
[372.743656] [093/133] (649s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-pwrite)
[372.839603] Starting subtest: psr-2p-primscrn-pri-indfb-draw-pwrite
[372.843792] Subtest psr-2p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[372.934274] [094/133] (649s left) gem_userptr_blits (coherency-unsync)
[373.002179] Subtest coherency-unsync: SKIP (0.000s)
[373.120240] [095/133] (649s left) gem_spin_batch (resubmit-new)
[373.175362] Starting subtest: resubmit-new
[373.177281] Starting dynamic subtest: rcs0
[373.184094] Dynamic subtest rcs0: SUCCESS (0.008s)
[373.195731] Starting dynamic subtest: bcs0
[373.197663] Dynamic subtest bcs0: SUCCESS (0.004s)
[373.206940] Starting dynamic subtest: vcs0
[373.208565] Dynamic subtest vcs0: SUCCESS (0.005s)
[373.218128] Starting dynamic subtest: vcs1
[373.220036] Dynamic subtest vcs1: SUCCESS (0.005s)
[373.229370] Starting dynamic subtest: vecs0
[373.231109] Dynamic subtest vecs0: SUCCESS (0.005s)
[373.239795] Starting dynamic subtest: ccs0
[373.241553] Dynamic subtest ccs0: SUCCESS (0.005s)
[373.243277] Subtest resubmit-new: SUCCESS (0.036s)
[374.975136] [096/133] (647s left) gem_exec_basic (basic)
[375.028962] Starting subtest: basic
[375.031298] Starting dynamic subtest: rcs0-smem
[375.033781] Dynamic subtest rcs0-smem: SUCCESS (0.004s)
[375.047308] Starting dynamic subtest: bcs0-smem
[375.049638] Dynamic subtest bcs0-smem: SUCCESS (0.002s)
[375.059505] Starting dynamic subtest: vcs0-smem
[375.061647] Dynamic subtest vcs0-smem: SUCCESS (0.002s)
[375.070743] Starting dynamic subtest: vcs1-smem
[375.072839] Dynamic subtest vcs1-smem: SUCCESS (0.001s)
[375.082072] Starting dynamic subtest: vecs0-smem
[375.084225] Dynamic subtest vecs0-smem: SUCCESS (0.001s)
[375.093614] Starting dynamic subtest: ccs0-smem
[375.095817] Dynamic subtest ccs0-smem: SUCCESS (0.001s)
[375.097979] Subtest basic: SUCCESS (0.016s)
[376.960203] [097/133] (645s left) kms_plane_multiple (2x-tiling-4)
[377.062193] Starting subtest: 2x-tiling-4
[377.066654] Subtest 2x-tiling-4: SKIP (0.000s)
[377.149407] [098/133] (645s left) gem_eio (in-flight-contexts-10ms)
[377.385628] Starting subtest: in-flight-contexts-10ms
[390.961702] Subtest in-flight-contexts-10ms: SUCCESS (13.577s)
[391.111327] [099/133] (631s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[391.206439] Subtest addfb25-x-tiled-mismatch-legacy: SKIP (0.000s)
[391.307797] [100/133] (631s left) gem_exec_schedule (noreorder)
[391.492224] Starting subtest: noreorder
[391.494463] Starting dynamic subtest: rcs0
[391.558091] Dynamic subtest rcs0: SUCCESS (0.068s)
[391.571114] Starting dynamic subtest: vcs0
[392.912867] Dynamic subtest vcs0: SUCCESS (1.352s)
[392.926618] Starting dynamic subtest: vcs1
[394.896720] Dynamic subtest vcs1: SUCCESS (1.981s)
[394.910346] Starting dynamic subtest: vecs0
[396.884325] Dynamic subtest vecs0: SUCCESS (1.985s)
[396.897351] Starting dynamic subtest: ccs0
[396.959996] Dynamic subtest ccs0: SUCCESS (0.074s)
[396.962799] Subtest noreorder: SUCCESS (5.471s)
[397.153085] [101/133] (625s left) syncobj_timeline (multi-wait-signaled)
[397.219571] Starting subtest: multi-wait-signaled
[397.221961] Subtest multi-wait-signaled: SUCCESS (0.001s)
[397.318624] [102/133] (625s left) kms_getfb (getfb-handle-valid)
[397.410975] Starting subtest: getfb-handle-valid
[397.413212] Subtest getfb-handle-valid: SUCCESS (0.000s)
[397.508330] [103/133] (625s left) gem_mmap_wc (write-read)
[397.558591] Subtest write-read: SKIP (0.000s)
[397.629299] [104/133] (625s left) sw_sync (sync_multi_producer_single_consumer)
[397.666094] Starting subtest: sync_multi_producer_single_consumer
[398.741389] Subtest sync_multi_producer_single_consumer: SUCCESS (1.075s)
[398.802812] [105/133] (624s left) kms_pm_rpm (modeset-pc8-residency-stress)
[401.055046] Starting subtest: modeset-pc8-residency-stress
[401.060518] Subtest modeset-pc8-residency-stress: SKIP (0.000s)
[401.423331] [106/133] (621s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180-hflip)
[401.526088] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180-hflip
[402.527721] Subtest x-tiled-max-hw-stride-64bpp-rotate-180-hflip: SUCCESS (1.001s)
[402.660818] [107/133] (620s left) gem_exec_reloc (basic-wc-active)
[402.726623] Subtest basic-wc-active: SKIP (0.000s)
[402.799683] [108/133] (620s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[402.888404] Starting subtest: nonblocking-crc-frame-sequence
[402.890383] Starting dynamic subtest: pipe-A-eDP-1
[403.663816] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.774s)
[403.674688] Starting dynamic subtest: pipe-B-eDP-1
[405.043201] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.378s)
[405.054094] Starting dynamic subtest: pipe-C-eDP-1
[406.427403] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.383s)
[406.438490] Starting dynamic subtest: pipe-D-eDP-1
[407.762986] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.334s)
[407.765298] Subtest nonblocking-crc-frame-sequence: SUCCESS (4.875s)
[408.571032] [109/133] (614s left) kms_psr (fbc-psr2-cursor-plane-move)
[408.662460] Starting subtest: fbc-psr2-cursor-plane-move
[408.664504] Starting dynamic subtest: eDP-1
[408.668803] Dynamic subtest eDP-1: SKIP (0.000s)
[408.673141] Subtest fbc-psr2-cursor-plane-move: SKIP (0.001s)
[408.741322] [110/133] (614s left) gem_mmap_wc (set-cache-level)
[408.788057] Subtest set-cache-level: SKIP (0.000s)
[408.845363] [111/133] (614s left) kms_hdr (static-toggle-dpms)
[408.948177] Starting subtest: static-toggle-dpms
[408.954733] Subtest static-toggle-dpms: SKIP (0.000s)
[409.036571] [112/133] (614s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-cpu)
[409.130037] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-mmap-cpu
[412.743432] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-cpu: SUCCESS (3.616s)
[413.975145] [113/133] (609s left) gem_render_copy (y-tiled-to-vebox-x-tiled)
[414.039057] Starting subtest: y-tiled-to-vebox-x-tiled
[414.043394] Subtest y-tiled-to-vebox-x-tiled: SKIP (0.000s)
[414.225962] [114/133] (609s left) syncobj_timeline (invalid-wait-bad-flags)
[414.284689] Starting subtest: invalid-wait-bad-flags
[414.286876] Subtest invalid-wait-bad-flags: SUCCESS (0.000s)
[414.396813] [115/133] (608s left) sysfs_preempt_timeout (timeout)
[414.461549] Starting subtest: timeout
[414.463848] Starting dynamic subtest: vecs0
[423.896956] Dynamic subtest vecs0: SUCCESS (9.434s)
[423.909442] Starting dynamic subtest: ccs0
[425.217306] Dynamic subtest ccs0: SUCCESS (1.317s)
[425.227593] Starting dynamic subtest: vcs0
[434.913079] Dynamic subtest vcs0: SUCCESS (9.694s)
[434.929616] Starting dynamic subtest: bcs0
[436.206633] Dynamic subtest bcs0: SUCCESS (1.289s)
[436.218324] Starting dynamic subtest: rcs0
[437.494777] Dynamic subtest rcs0: SUCCESS (1.287s)
[437.507151] Starting dynamic subtest: vcs1
[446.931471] Dynamic subtest vcs1: SUCCESS (9.432s)
[446.934048] Subtest timeout: SUCCESS (32.466s)
[447.011589] [116/133] (576s left) i915_pm_sseu (full-enable)
[447.082048] Subtest full-enable: SKIP (0.000s)
[447.181728] [117/133] (576s left) kms_scaling_modes (scaling-mode-center)
[447.282823] Starting subtest: scaling-mode-center
[447.284990] Starting dynamic subtest: pipe-A-eDP-1
[447.374395] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.093s)
[447.385172] Starting dynamic subtest: pipe-B-eDP-1
[448.574554] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.198s)
[448.588354] Starting dynamic subtest: pipe-C-eDP-1
[449.691504] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.113s)
[449.712154] Starting dynamic subtest: pipe-D-eDP-1
[450.822626] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.128s)
[450.824885] Subtest scaling-mode-center: SUCCESS (3.542s)
[451.986557] [118/133] (571s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-gtt)
[452.088540] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-gtt
[452.092650] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[452.197759] [119/133] (571s left) gem_exec_create (forked)
[452.268837] Starting subtest: forked
[452.271321] Starting dynamic subtest: smem
[473.942070] Dynamic subtest smem: SUCCESS (21.673s)
[473.945036] Subtest forked: SUCCESS (21.675s)
[474.134346] [120/133] (549s left) gem_softpin (overlap)
[474.221939] Starting subtest: overlap
[475.185510] Subtest overlap: SUCCESS (0.955s)
[475.323281] [121/133] (548s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[475.435648] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[475.440033] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[475.537212] [122/133] (548s left) kms_psr2_sf (psr2-cursor-plane-move-continuous-exceed-sf)
[475.636701] Starting subtest: psr2-cursor-plane-move-continuous-exceed-sf
[475.638996] Starting dynamic subtest: pipe-A-eDP-1
[486.312313] Dynamic subtest pipe-A-eDP-1: SUCCESS (10.680s)
[486.323355] Starting dynamic subtest: pipe-B-eDP-1
[497.686988] Dynamic subtest pipe-B-eDP-1: SUCCESS (11.369s)
[497.689108] Subtest psr2-cursor-plane-move-continuous-exceed-sf: SUCCESS (22.052s)
[498.538866] [123/133] (525s left) kms_content_protection (type1)
[498.675717] Starting subtest: type1
[498.680385] Subtest type1: SKIP (0.000s)
[498.818585] [124/133] (524s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[498.913229] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt
[498.917385] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[499.032318] [125/133] (524s left) kms_psr (psr2-no-drrs)
[499.132890] Starting subtest: psr2-no-drrs
[499.135092] Starting dynamic subtest: eDP-1
[500.700072] Dynamic subtest eDP-1: SUCCESS (1.566s)
[500.701959] Subtest psr2-no-drrs: SUCCESS (1.567s)
[501.494665] [126/133] (522s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-render)
[501.594662] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-render
[501.598617] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[501.698586] [127/133] (522s left) gem_ppgtt (shrink-vs-evict-any)
[501.766735] Starting subtest: shrink-vs-evict-any
[508.909335] Subtest shrink-vs-evict-any: SUCCESS (7.141s)
[509.000619] [128/133] (514s left) i915_drm_fdinfo (busy)
[509.043462] Starting subtest: busy
[509.045876] Starting dynamic subtest: rcs0
[509.073178] Dynamic subtest rcs0: SKIP (0.027s)
[509.085773] Starting dynamic subtest: bcs0
[509.089719] Dynamic subtest bcs0: SKIP (0.001s)
[509.099831] Starting dynamic subtest: vcs0
[509.104061] Dynamic subtest vcs0: SKIP (0.000s)
[509.113845] Starting dynamic subtest: vcs1
[509.117758] Dynamic subtest vcs1: SKIP (0.000s)
[509.127298] Starting dynamic subtest: vecs0
[509.131155] Dynamic subtest vecs0: SKIP (0.000s)
[509.140795] Starting dynamic subtest: ccs0
[509.144717] Dynamic subtest ccs0: SKIP (0.000s)
[509.148612] Subtest busy: SKIP (0.035s)
[509.188913] [129/133] (514s left) perf_pmu (multi-client)
[509.233950] Starting subtest: multi-client
[509.236202] Starting dynamic subtest: rcs0
[510.300484] Dynamic subtest rcs0: SUCCESS (1.066s)
[510.314600] Starting dynamic subtest: bcs0
[511.355069] Dynamic subtest bcs0: SUCCESS (1.051s)
[511.367978] Starting dynamic subtest: vcs0
[512.409332] Dynamic subtest vcs0: SUCCESS (1.052s)
[512.422564] Starting dynamic subtest: vcs1
[513.932553] Dynamic subtest vcs1: SUCCESS (1.520s)
[513.945685] Starting dynamic subtest: vecs0
[515.919231] Dynamic subtest vecs0: SUCCESS (1.983s)
[515.932433] Starting dynamic subtest: ccs0
[517.902056] Dynamic subtest ccs0: SUCCESS (1.981s)
[517.904993] Subtest multi-client: SUCCESS (8.669s)
[518.020219] [130/133] (506s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[518.126918] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[518.131317] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SKIP (0.000s)
[518.223391] [131/133] (505s left) syncobj_timeline (single-wait-all-for-submit-signaled)
[518.279439] Starting subtest: single-wait-all-for-submit-signaled
[518.281387] Subtest single-wait-all-for-submit-signaled: SUCCESS (0.000s)
[518.383092] [132/133] (505s left) kms_chamelium_hpd (dp-hpd-storm)
[518.776243] Subtest dp-hpd-storm: SKIP (0.000s)
[519.613928] [133/133] (504s left) kms_cursor_crc (cursor-onscreen-256x85)
[519.864874] Starting subtest: cursor-onscreen-256x85
[519.913441] Subtest cursor-onscreen-256x85: SKIP (0.042s)
[520.078753] Closing watchdogs
results: parsing output: 0/ for test: kms_frontbuffer_tracking
results: parsing output: 1/ for test: gem_exec_schedule
results: parsing output: 2/ for test: gem_mmap_gtt
results: parsing output: 3/ for test: kms_hdr
results: parsing output: 4/ for test: gem_softpin
results: parsing output: 5/ for test: gem_mmap_gtt
results: parsing output: 6/ for test: kms_psr2_sf
results: parsing output: 7/ for test: kms_dsc
results: parsing output: 8/ for test: gem_pxp
results: parsing output: 9/ for test: kms_setmode
results: parsing output: 10/ for test: kms_vblank
results: parsing output: 11/ for test: kms_async_flips
results: parsing output: 12/ for test: kms_big_fb
results: parsing output: 13/ for test: kms_dp_linktrain_fallback
results: parsing output: 14/ for test: gem_create
results: parsing output: 15/ for test: gem_ctx_param
results: parsing output: 16/ for test: kms_flip
results: parsing output: 17/ for test: gem_exec_params
results: parsing output: 18/ for test: i915_drm_fdinfo
results: parsing output: 19/ for test: api_intel_bb
results: parsing output: 20/ for test: kms_ccs
results: parsing output: 21/ for test: kms_big_fb
results: parsing output: 22/ for test: kms_cursor_crc
results: parsing output: 23/ for test: core_hotunplug
results: parsing output: 24/ for test: gem_flink_basic
results: parsing output: 25/ for test: kms_prop_blob
results: parsing output: 26/ for test: kms_frontbuffer_tracking
results: parsing output: 27/ for test: kms_ccs
results: parsing output: 28/ for test: gem_wait
results: parsing output: 29/ for test: kms_frontbuffer_tracking
results: parsing output: 30/ for test: kms_pm_rpm
results: parsing output: 31/ for test: gem_blits
results: parsing output: 32/ for test: kms_sequence
results: parsing output: 33/ for test: kms_frontbuffer_tracking
results: parsing output: 34/ for test: syncobj_wait
results: parsing output: 35/ for test: kms_plane
results: parsing output: 36/ for test: gem_ctx_shared
results: parsing output: 37/ for test: kms_rotation_crc
results: parsing output: 38/ for test: kms_frontbuffer_tracking
results: parsing output: 39/ for test: kms_color
results: parsing output: 40/ for test: perf
results: parsing output: 41/ for test: kms_frontbuffer_tracking
results: parsing output: 42/ for test: kms_cdclk
results: parsing output: 43/ for test: kms_plane_scaling
results: parsing output: 44/ for test: kms_cursor_crc
results: parsing output: 45/ for test: kms_cursor_legacy
results: parsing output: 46/ for test: kms_big_fb
results: parsing output: 47/ for test: kms_chamelium_color
results: parsing output: 48/ for test: syncobj_eventfd
results: parsing output: 49/ for test: kms_setmode
results: parsing output: 50/ for test: syncobj_timeline
results: parsing output: 51/ for test: gem_userptr_blits
results: parsing output: 52/ for test: gem_exec_balancer
results: parsing output: 53/ for test: kms_frontbuffer_tracking
results: parsing output: 54/ for test: gem_ctx_persistence
results: parsing output: 55/ for test: kms_flip
results: parsing output: 56/ for test: gem_create
results: parsing output: 57/ for test: sw_sync
results: parsing output: 58/ for test: kms_flip_scaled_crc
results: parsing output: 59/ for test: kms_ccs
results: parsing output: 60/ for test: kms_cursor_legacy
results: parsing output: 61/ for test: kms_cursor_legacy
results: parsing output: 62/ for test: kms_flip_scaled_crc
results: parsing output: 63/ for test: kms_flip
results: parsing output: 64/ for test: kms_plane_scaling
results: parsing output: 65/ for test: kms_big_fb
results: parsing output: 66/ for test: perf
results: parsing output: 67/ for test: gem_ctx_param
results: parsing output: 68/ for test: perf_pmu
results: parsing output: 69/ for test: kms_flip
results: parsing output: 70/ for test: gem_exec_reloc
results: parsing output: 71/ for test: api_intel_bb
results: parsing output: 72/ for test: kms_lease
results: parsing output: 73/ for test: kms_ccs
results: parsing output: 74/ for test: kms_ccs
results: parsing output: 75/ for test: gem_ctx_param
results: parsing output: 76/ for test: kms_flip
results: parsing output: 77/ for test: gem_exercise_blt
results: parsing output: 78/ for test: gem_ctx_exec
results: parsing output: 79/ for test: perf
results: parsing output: 80/ for test: kms_psr
results: parsing output: 81/ for test: i915_pm_rpm
results: parsing output: 82/ for test: gem_exec_gttfill
results: parsing output: 83/ for test: gem_exec_fence
results: parsing output: 84/ for test: gem_exec_schedule
results: parsing output: 85/ for test: gem_mmap_gtt
results: parsing output: 86/ for test: kms_frontbuffer_tracking
results: parsing output: 87/ for test: kms_frontbuffer_tracking
results: parsing output: 88/ for test: perf
results: parsing output: 89/ for test: kms_psr
results: parsing output: 90/ for test: kms_frontbuffer_tracking
results: parsing output: 91/ for test: gen9_exec_parse
results: parsing output: 92/ for test: kms_frontbuffer_tracking
results: parsing output: 93/ for test: gem_userptr_blits
results: parsing output: 94/ for test: gem_spin_batch
results: parsing output: 95/ for test: gem_exec_basic
results: parsing output: 96/ for test: kms_plane_multiple
results: parsing output: 97/ for test: gem_eio
results: parsing output: 98/ for test: kms_addfb_basic
results: parsing output: 99/ for test: gem_exec_schedule
results: parsing output: 100/ for test: syncobj_timeline
results: parsing output: 101/ for test: kms_getfb
results: parsing output: 102/ for test: gem_mmap_wc
results: parsing output: 103/ for test: sw_sync
results: parsing output: 104/ for test: kms_pm_rpm
results: parsing output: 105/ for test: kms_big_fb
results: parsing output: 106/ for test: gem_exec_reloc
results: parsing output: 107/ for test: kms_pipe_crc_basic
results: parsing output: 108/ for test: kms_psr
results: parsing output: 109/ for test: gem_mmap_wc
results: parsing output: 110/ for test: kms_hdr
results: parsing output: 111/ for test: kms_frontbuffer_tracking
results: parsing output: 112/ for test: gem_render_copy
results: parsing output: 113/ for test: syncobj_timeline
results: parsing output: 114/ for test: sysfs_preempt_timeout
results: parsing output: 115/ for test: i915_pm_sseu
results: parsing output: 116/ for test: kms_scaling_modes
results: parsing output: 117/ for test: kms_frontbuffer_tracking
results: parsing output: 118/ for test: gem_exec_create
results: parsing output: 119/ for test: gem_softpin
results: parsing output: 120/ for test: kms_frontbuffer_tracking
results: parsing output: 121/ for test: kms_psr2_sf
results: parsing output: 122/ for test: kms_content_protection
results: parsing output: 123/ for test: kms_frontbuffer_tracking
results: parsing output: 124/ for test: kms_psr
results: parsing output: 125/ for test: kms_frontbuffer_tracking
results: parsing output: 126/ for test: gem_ppgtt
results: parsing output: 127/ for test: i915_drm_fdinfo
results: parsing output: 128/ for test: perf_pmu
results: parsing output: 129/ for test: kms_big_fb
results: parsing output: 130/ for test: syncobj_timeline
results: parsing output: 131/ for test: kms_chamelium_hpd
results: parsing output: 132/ for test: kms_cursor_crc
Done.