[57.415318] Initializing watchdogs
[57.415423]   /dev/watchdog0
[57.429837] [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.434059] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.436178] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.439667] [001/133] (960s left) kms_big_fb (4-tiled-16bpp-rotate-90)
[57.558542] Starting subtest: 4-tiled-16bpp-rotate-90
[57.591124] Subtest 4-tiled-16bpp-rotate-90: SKIP (0.032s)
[57.748392] [002/133] (960s left) gem_exec_fence (basic-busy)
[57.928656] Starting subtest: basic-busy
[57.931223] Starting dynamic subtest: rcs0
[58.037013] Dynamic subtest rcs0: SUCCESS (0.109s)
[58.045378] Starting dynamic subtest: bcs0
[58.097406] Dynamic subtest bcs0: SUCCESS (0.060s)
[58.105050] Starting dynamic subtest: vcs0
[58.160689] Dynamic subtest vcs0: SUCCESS (0.063s)
[58.169201] Starting dynamic subtest: vcs1
[58.225173] Dynamic subtest vcs1: SUCCESS (0.063s)
[58.234646] Starting dynamic subtest: vecs0
[58.286825] Dynamic subtest vecs0: SUCCESS (0.060s)
[58.294438] Starting dynamic subtest: ccs0
[58.348542] Dynamic subtest ccs0: SUCCESS (0.061s)
[58.351125] Subtest basic-busy: SUCCESS (0.421s)
[59.999338] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true
[60.002375] [003/133] (958s left) kms_pm_rpm (modeset-lpsp-stress-no-wait)
[62.071628] Starting subtest: modeset-lpsp-stress-no-wait
[71.892400] Subtest modeset-lpsp-stress-no-wait: SUCCESS (9.825s)
[71.979444] [004/133] (946s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-16bpp-xtile-downscaling)
[72.075046] Starting subtest: flip-64bpp-xtile-to-16bpp-xtile-downscaling
[72.077338] Starting dynamic subtest: pipe-A-default-mode
[73.207229] Dynamic subtest pipe-A-default-mode: SKIP (1.130s)
[73.211920] Subtest flip-64bpp-xtile-to-16bpp-xtile-downscaling: SKIP (1.131s)
[73.374149] [005/133] (944s left) syncobj_timeline (invalid-query-zero-handles)
[73.437726] Starting subtest: invalid-query-zero-handles
[73.440307] Subtest invalid-query-zero-handles: SUCCESS (0.000s)
[73.569304] [006/133] (944s left) kms_rmfb (close-fd)
[73.671743] Starting subtest: close-fd
[73.673955] Starting dynamic subtest: pipe-A-eDP-1
[73.777785] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.106s)
[73.786787] Starting dynamic subtest: pipe-B-eDP-1
[75.156076] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.378s)
[75.164854] Starting dynamic subtest: pipe-C-eDP-1
[76.271495] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.115s)
[76.278536] Starting dynamic subtest: pipe-D-eDP-1
[77.408962] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.136s)
[77.411260] Subtest close-fd: SUCCESS (3.738s)
[78.534160] [007/133] (939s left) gem_spin_batch (spin-all)
[78.592855] Starting subtest: spin-all
[78.606939] Subtest spin-all: SUCCESS (0.013s)
[79.956529] [008/133] (938s left) gem_exec_fence (keep-in-fence)
[80.133205] Starting subtest: keep-in-fence
[80.135739] Starting dynamic subtest: rcs0
[80.195235] Dynamic subtest rcs0: SUCCESS (0.062s)
[80.204878] Starting dynamic subtest: bcs0
[80.247809] Dynamic subtest bcs0: SUCCESS (0.052s)
[80.257639] Starting dynamic subtest: vcs0
[81.879566] Dynamic subtest vcs0: SUCCESS (1.629s)
[81.890556] Starting dynamic subtest: vcs1
[83.926729] Dynamic subtest vcs1: SUCCESS (2.046s)
[83.936984] Starting dynamic subtest: vecs0
[85.968153] Dynamic subtest vecs0: SUCCESS (1.983s)
[85.977544] Starting dynamic subtest: ccs0
[86.149751] Dynamic subtest ccs0: SUCCESS (0.240s)
[86.152475] Subtest keep-in-fence: SUCCESS (6.018s)
[87.968834] [009/133] (930s left) kms_frontbuffer_tracking (fbc-1p-shrfb-fliptrack-mmap-gtt)
[88.077885] Starting subtest: fbc-1p-shrfb-fliptrack-mmap-gtt
[88.082411] Subtest fbc-1p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[88.186657] [010/133] (930s left) kms_psr (fbc-psr-dpms)
[88.290528] Starting subtest: fbc-psr-dpms
[88.292660] Starting dynamic subtest: eDP-1
[88.296863] Dynamic subtest eDP-1: SKIP (0.000s)
[88.301104] Subtest fbc-psr-dpms: SKIP (0.001s)
[88.386657] [011/133] (929s left) syncobj_timeline (multi-wait-signaled)
[88.446298] Starting subtest: multi-wait-signaled
[88.448616] Subtest multi-wait-signaled: SUCCESS (0.001s)
[88.567046] [012/133] (929s left) kms_flip (blocking-wf_vblank)
[88.663667] Starting subtest: blocking-wf_vblank
[88.669095] Starting dynamic subtest: A-eDP1
[97.604816] Dynamic subtest A-eDP1: SUCCESS (8.903s)
[97.614816] Starting dynamic subtest: B-eDP1
[106.525235] Dynamic subtest B-eDP1: SUCCESS (8.921s)
[106.537450] Starting dynamic subtest: C-eDP1
[115.459111] Dynamic subtest C-eDP1: SUCCESS (8.925s)
[115.469170] Starting dynamic subtest: D-eDP1
[124.396624] Dynamic subtest D-eDP1: SUCCESS (8.931s)
[124.399226] Subtest blocking-wf_vblank: SUCCESS (35.694s)
[125.583852] [013/133] (892s left) kms_dsc (dsc-with-output-formats)
[125.677945] Starting subtest: dsc-with-output-formats
[125.699605] Subtest dsc-with-output-formats: SKIP (0.001s)
[125.762762] [014/133] (892s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[125.859069] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-wc
[125.863074] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[125.960119] [015/133] (892s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-render)
[126.065453] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-render
[133.419846] Subtest psr-1p-primscrn-pri-shrfb-draw-render: SUCCESS (7.358s)
[133.577485] [016/133] (884s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-pwrite)
[133.696753] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-pwrite
[133.701041] Subtest fbc-2p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[133.849258] [017/133] (884s left) gem_lmem_swapping (parallel-random-engines)
[133.888844] Subtest parallel-random-engines: SKIP (0.000s)
[133.930159] [018/133] (884s left) gem_exec_reloc (basic-wc-cpu-active)
[133.997775] Subtest basic-wc-cpu-active: SKIP (0.000s)
[134.089967] [019/133] (884s left) gem_ctx_isolation (clean)
[134.150433] Starting subtest: clean
[134.152847] Starting dynamic subtest: rcs0
[134.213829] Dynamic subtest rcs0: SUCCESS (0.062s)
[134.223252] Starting dynamic subtest: bcs0
[134.313538] Dynamic subtest bcs0: SUCCESS (0.099s)
[134.323075] Starting dynamic subtest: vcs0
[134.402663] Dynamic subtest vcs0: SUCCESS (0.089s)
[134.409702] Starting dynamic subtest: vcs1
[135.899157] Dynamic subtest vcs1: SUCCESS (1.495s)
[135.908494] Starting dynamic subtest: vecs0
[137.925718] Dynamic subtest vecs0: SUCCESS (2.026s)
[137.935704] Starting dynamic subtest: ccs0
[140.005955] Dynamic subtest ccs0: SUCCESS (2.050s)
[140.008396] Subtest clean: SUCCESS (5.826s)
[140.251456] [020/133] (878s left) kms_rotation_crc (sprite-rotation-90)
[140.349595] Starting subtest: sprite-rotation-90
[140.353799] Subtest sprite-rotation-90: SKIP (0.001s)
[140.447138] [021/133] (878s left) gem_ctx_engines (invalid-engines)
[140.508980] Starting subtest: invalid-engines
[140.513997] Subtest invalid-engines: SUCCESS (0.007s)
[140.779192] [022/133] (878s left) kms_frontbuffer_tracking (psr-farfromfence-mmap-gtt)
[140.875391] Starting subtest: psr-farfromfence-mmap-gtt
[140.879642] Subtest psr-farfromfence-mmap-gtt: SKIP (0.000s)
[141.001185] [023/133] (877s left) kms_big_fb (linear-32bpp-rotate-180)
[141.108396] Starting subtest: linear-32bpp-rotate-180
[142.541645] Subtest linear-32bpp-rotate-180: SUCCESS (1.433s)
[142.725516] [024/133] (876s left) gem_render_copy (y-tiled-ccs-to-y-tiled-ccs)
[142.784700] Starting subtest: y-tiled-ccs-to-y-tiled-ccs
[142.788526] Subtest y-tiled-ccs-to-y-tiled-ccs: SKIP (0.000s)
[142.992168] [025/133] (875s left) kms_ccs (crc-sprite-planes-basic-4-tiled-dg2-mc-ccs)
[143.077146] Starting subtest: crc-sprite-planes-basic-4-tiled-dg2-mc-ccs
[143.079573] Starting dynamic subtest: pipe-A-eDP-1
[143.138050] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[143.147120] Starting dynamic subtest: pipe-B-eDP-1
[143.206509] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[143.215256] Starting dynamic subtest: pipe-C-eDP-1
[143.272478] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[143.280906] Starting dynamic subtest: pipe-D-eDP-1
[143.337534] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[143.341929] Subtest crc-sprite-planes-basic-4-tiled-dg2-mc-ccs: SKIP (0.007s)
[145.254352] [026/133] (873s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[145.355065] Starting subtest: compare-crc-sanitycheck-nv12
[145.357549] Starting dynamic subtest: pipe-A-eDP-1
[145.995816] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.641s)
[146.004604] Starting dynamic subtest: pipe-B-eDP-1
[147.301311] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.303s)
[147.309230] Starting dynamic subtest: pipe-C-eDP-1
[148.540561] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.239s)
[148.550186] Starting dynamic subtest: pipe-D-eDP-1
[149.747142] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.206s)
[149.749491] Subtest compare-crc-sanitycheck-nv12: SUCCESS (4.394s)
[150.588360] [027/133] (868s left) kms_invalid_mode (uint-max-clock)
[150.689349] Starting subtest: uint-max-clock
[150.691613] Starting dynamic subtest: pipe-A-eDP-1
[150.971453] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.284s)
[150.979373] Starting dynamic subtest: pipe-B-eDP-1
[150.981288] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[150.987588] Starting dynamic subtest: pipe-C-eDP-1
[150.989500] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[150.996328] Starting dynamic subtest: pipe-D-eDP-1
[150.998624] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[151.000795] Subtest uint-max-clock: SUCCESS (0.289s)
[151.820241] [028/133] (867s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[151.916598] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[151.921052] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[152.024143] [029/133] (867s left) kms_plane (pixel-format-4-tiled-mtl-rc-ccs-modifier)
[152.123342] Starting subtest: pixel-format-4-tiled-mtl-rc-ccs-modifier
[152.151651] Starting dynamic subtest: pipe-A-plane-0
[152.440359] Dynamic subtest pipe-A-plane-0: SUCCESS (0.289s)
[152.447645] Starting dynamic subtest: pipe-A-plane-3
[152.662747] Dynamic subtest pipe-A-plane-3: SUCCESS (0.222s)
[152.670558] Starting dynamic subtest: pipe-A-plane-5
[152.674509] Dynamic subtest pipe-A-plane-5: SKIP (0.000s)
[153.793650] Starting dynamic subtest: pipe-B-plane-0
[154.087102] Dynamic subtest pipe-B-plane-0: SUCCESS (0.300s)
[154.094483] Starting dynamic subtest: pipe-B-plane-3
[154.320729] Dynamic subtest pipe-B-plane-3: SUCCESS (0.233s)
[154.329055] Starting dynamic subtest: pipe-B-plane-5
[154.333135] Dynamic subtest pipe-B-plane-5: SKIP (0.000s)
[154.633101] Subtest pixel-format-4-tiled-mtl-rc-ccs-modifier: SUCCESS (2.508s)
[155.490317] [030/133] (863s left) gem_exec_schedule (semaphore-codependency)
[155.566673] Starting subtest: semaphore-codependency
[155.573587] Subtest semaphore-codependency: SUCCESS (0.015s)
[155.941536] [031/133] (863s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-shrfb-pgflip-blt)
[156.041979] Starting subtest: fbcpsr-1p-primscrn-shrfb-pgflip-blt
[163.427565] Subtest fbcpsr-1p-primscrn-shrfb-pgflip-blt: SUCCESS (7.390s)
[163.599419] [032/133] (855s left) gem_exec_params (sol-reset-not-gen7)
[163.663716] Starting subtest: sol-reset-not-gen7
[163.666054] Subtest sol-reset-not-gen7: SUCCESS (0.002s)
[163.813914] [033/133] (855s left) gem_exec_balancer (bonded-false-hang)
[164.073335] Starting subtest: bonded-false-hang
[164.078072] Subtest bonded-false-hang: SKIP (0.000s)
[164.201381] [034/133] (855s left) gen9_exec_parse (allowed-all)
[164.269785] Subtest allowed-all: SKIP (0.000s)
[164.351008] [035/133] (854s left) gem_ccs (block-multicopy-compressed)
[164.420358] Starting subtest: block-multicopy-compressed
[164.424814] Subtest block-multicopy-compressed: SKIP (0.000s)
[164.526403] [036/133] (854s left) gem_ctx_bad_destroy (invalid-pad)
[164.577514] Starting subtest: invalid-pad
[164.579916] Subtest invalid-pad: SUCCESS (0.000s)
[164.664353] [037/133] (854s left) kms_async_flips (invalid-async-flip-atomic)
[164.768050] Starting subtest: invalid-async-flip-atomic
[165.140451] Starting dynamic subtest: pipe-A-eDP-1
[165.916593] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.777s)
[166.271698] Starting dynamic subtest: pipe-B-eDP-1
[167.045418] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.781s)
[167.408747] Starting dynamic subtest: pipe-C-eDP-1
[168.155389] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.752s)
[168.508174] Starting dynamic subtest: pipe-D-eDP-1
[169.281395] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.778s)
[169.283755] Subtest invalid-async-flip-atomic: SUCCESS (4.515s)
[170.465318] [038/133] (848s left) vgem_slow (nohang)
[170.520209] Starting subtest: nohang
[180.850914] Subtest nohang: SUCCESS (10.332s)
[180.930290] [039/133] (838s left) kms_chamelium_hpd (hdmi-hpd)
[181.330631] Subtest hdmi-hpd: SKIP (0.000s)
[182.136427] [040/133] (837s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-pwrite)
[182.235812] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-pwrite
[191.405840] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-pwrite: SUCCESS (9.172s)
[191.568671] [041/133] (827s left) kms_addfb_basic (unused-offsets)
[191.630241] Starting subtest: unused-offsets
[191.632505] Subtest unused-offsets: SUCCESS (0.000s)
[191.768634] [042/133] (827s left) prime_udl
[191.852617] [043/133] (827s left) kms_cursor_legacy (2x-cursor-vs-flip-atomic)
[191.976526] Starting subtest: 2x-cursor-vs-flip-atomic
[191.981055] Subtest 2x-cursor-vs-flip-atomic: SKIP (0.001s)
[192.131853] [044/133] (827s left) kms_lease (empty-lease)
[192.226359] Starting subtest: empty-lease
[192.228718] Starting dynamic subtest: pipe-A-eDP-1
[192.231078] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.002s)
[192.240099] Starting dynamic subtest: pipe-B-eDP-1
[192.242175] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.002s)
[192.249895] Starting dynamic subtest: pipe-C-eDP-1
[192.252181] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.002s)
[192.259668] Starting dynamic subtest: pipe-D-eDP-1
[192.261964] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.002s)
[192.264206] Subtest empty-lease: SUCCESS (0.024s)
[192.365969] [045/133] (827s left) gen3_render_tiledy_blits
[192.511943] [046/133] (827s left) kms_cursor_crc (cursor-onscreen-128x128)
[192.733664] Starting subtest: cursor-onscreen-128x128
[192.738388] Starting dynamic subtest: pipe-A-eDP-1
[194.835938] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.098s)
[194.863633] Starting dynamic subtest: pipe-D-eDP-1
[197.612925] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.754s)
[197.615265] Subtest cursor-onscreen-128x128: SUCCESS (4.877s)
[198.436680] [047/133] (821s left) gem_ctx_shared (q-smoketest-all)
[198.503315] Starting subtest: Q-smoketest-all
[230.891472] Subtest Q-smoketest-all: SUCCESS (32.338s)
[232.026473] [048/133] (787s left) gem_mmap_gtt (basic-write-read-distinct)
[232.085160] Subtest basic-write-read-distinct: SKIP (0.000s)
[232.146398] [049/133] (787s left) kms_frontbuffer_tracking (fbc-stridechange)
[232.254231] Starting subtest: fbc-stridechange
[235.531677] Subtest fbc-stridechange: SUCCESS (3.281s)
[236.718235] [050/133] (783s left) kms_plane (planar-pixel-format-settings)
[236.821274] Starting subtest: planar-pixel-format-settings
[236.883276] Subtest planar-pixel-format-settings: SUCCESS (0.063s)
[237.030411] [051/133] (782s left) kms_plane_scaling (plane-upscale-20x20-with-rotation)
[237.130440] Starting subtest: plane-upscale-20x20-with-rotation
[237.132827] Starting dynamic subtest: pipe-A
[237.354402] Dynamic subtest pipe-A: SUCCESS (0.227s)
[237.362698] Starting dynamic subtest: pipe-B
[238.676327] Dynamic subtest pipe-B: SUCCESS (1.321s)
[238.688263] Starting dynamic subtest: pipe-C
[240.014407] Dynamic subtest pipe-C: SUCCESS (1.337s)
[240.022801] Starting dynamic subtest: pipe-D
[241.340370] Dynamic subtest pipe-D: SUCCESS (1.325s)
[241.342759] Subtest plane-upscale-20x20-with-rotation: SUCCESS (4.214s)
[242.539631] [052/133] (777s left) i915_pm_rps (engine-order)
[242.620068] Starting subtest: engine-order
[247.784901] Subtest engine-order: SUCCESS (5.163s)
[247.952160] [053/133] (772s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-mmap-wc)
[248.056282] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-mmap-wc
[248.060141] Subtest fbc-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[248.189148] [054/133] (771s left) kms_ccs (bad-pixel-format-4-tiled-mtl-rc-ccs-cc)
[248.287282] Starting subtest: bad-pixel-format-4-tiled-mtl-rc-ccs-cc
[248.289480] Starting dynamic subtest: pipe-A-eDP-1
[248.310184] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[248.320250] Starting dynamic subtest: pipe-B-eDP-1
[248.339091] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[248.347190] Starting dynamic subtest: pipe-C-eDP-1
[248.366420] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[248.374489] Starting dynamic subtest: pipe-D-eDP-1
[248.393486] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[248.395394] Subtest bad-pixel-format-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.005s)
[250.512379] [055/133] (769s left) gem_mmap (short-mmap)
[250.566995] Subtest short-mmap: SKIP (0.000s)
[250.632617] [056/133] (769s left) gem_partial_pwrite_pread (reads-uncached)
[250.695555] Subtest reads-uncached: SKIP (0.000s)
[250.794644] [057/133] (769s left) syncobj_wait (wait-all-delayed-signal)
[250.854853] Starting subtest: wait-all-delayed-signal
[250.958324] Subtest wait-all-delayed-signal: SUCCESS (0.104s)
[251.065266] [058/133] (769s left) gem_mmap_offset (mmap-boundaries)
[251.120923] Starting subtest: mmap-boundaries
[251.123486] Starting dynamic subtest: smem0
[251.394957] Dynamic subtest smem0: SUCCESS (0.277s)
[251.397883] Subtest mmap-boundaries: SUCCESS (0.277s)
[251.548925] [059/133] (768s left) syncobj_timeline (wait-for-submit-snapshot)
[251.611670] Starting subtest: wait-for-submit-snapshot
[251.694406] Subtest wait-for-submit-snapshot: SUCCESS (0.082s)
[251.794925] [060/133] (768s left) kms_chamelium_color (ctm-negative)
[251.883659] Subtest ctm-negative: SKIP (0.000s)
[251.928347] [061/133] (768s left) device_reset (cold-reset-bound)
[251.975297] Starting subtest: cold-reset-bound
[251.979891] Subtest cold-reset-bound: SKIP (0.000s)
[252.064442] [062/133] (768s left) kms_flip (flip-vs-dpms-off-vs-modeset-interruptible)
[252.164732] Starting subtest: flip-vs-dpms-off-vs-modeset-interruptible
[252.167274] Starting dynamic subtest: A-eDP1
[255.740243] Dynamic subtest A-eDP1: SUCCESS (3.575s)
[255.749589] Starting dynamic subtest: B-eDP1
[258.929588] Dynamic subtest B-eDP1: SUCCESS (3.187s)
[258.944957] Starting dynamic subtest: C-eDP1
[262.044402] Dynamic subtest C-eDP1: SUCCESS (3.113s)
[262.053170] Starting dynamic subtest: D-eDP1
[265.217496] Dynamic subtest D-eDP1: SUCCESS (3.174s)
[265.219758] Subtest flip-vs-dpms-off-vs-modeset-interruptible: SUCCESS (13.058s)
[266.027421] [063/133] (754s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-cpu)
[266.140049] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-cpu
[266.143969] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[266.262728] [064/133] (754s left) gem_exec_params (invalid-ring2)
[266.325390] Starting subtest: invalid-ring2
[266.327277] Subtest invalid-ring2: SUCCESS (0.000s)
[266.446464] [065/133] (754s left) gem_userptr_blits (process-exit-busy)
[266.513105] Starting subtest: process-exit-busy
[266.524499] Subtest process-exit-busy: SUCCESS (0.011s)
[266.692735] [066/133] (753s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-onoff)
[266.794229] Starting subtest: fbc-1p-primscrn-cur-indfb-onoff
[270.750531] Subtest fbc-1p-primscrn-cur-indfb-onoff: SUCCESS (3.960s)
[271.932236] [067/133] (748s left) kms_psr (fbc-pr-cursor-mmap-gtt)
[272.041384] Starting subtest: fbc-pr-cursor-mmap-gtt
[272.045478] Subtest fbc-pr-cursor-mmap-gtt: SKIP (0.000s)
[272.120915] [068/133] (748s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-mmap-wc)
[272.211372] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-mmap-wc
[272.215342] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[272.343791] [069/133] (748s left) api_intel_allocator (simple-allocator)
[272.396446] Starting subtest: simple-allocator
[272.399301] Starting dynamic subtest: basic
[272.401425] Dynamic subtest basic: SUCCESS (0.003s)
[272.413540] Starting dynamic subtest: parallel-one
[272.569897] Dynamic subtest parallel-one: SUCCESS (0.171s)
[272.581102] Starting dynamic subtest: print
[272.583292] Dynamic subtest print: SUCCESS (0.000s)
[272.591548] Starting dynamic subtest: reuse
[272.593806] Dynamic subtest reuse: SUCCESS (0.000s)
[272.601642] Starting dynamic subtest: reserve
[272.603913] Dynamic subtest reserve: SUCCESS (0.000s)
[272.611452] Starting dynamic subtest: fork-reopen-allocator
[272.613749] Dynamic subtest fork-reopen-allocator: SUCCESS (0.010s)
[272.616025] Subtest simple-allocator: SUCCESS (0.191s)
[272.735208] [070/133] (748s left) syncobj_timeline (etime-multi-wait-submitted)
[272.798851] Starting subtest: etime-multi-wait-submitted
[272.906601] Subtest etime-multi-wait-submitted: SUCCESS (0.107s)
[272.990978] [071/133] (747s left) kms_chamelium_edid (dp-edid-read)
[273.375841] Subtest dp-edid-read: SKIP (0.000s)
[274.226211] [072/133] (746s left) kms_flip (2x-blocking-absolute-wf_vblank-interruptible)
[274.325235] Starting subtest: 2x-blocking-absolute-wf_vblank-interruptible
[274.336138] Subtest 2x-blocking-absolute-wf_vblank-interruptible: SKIP (0.010s)
[274.464488] [073/133] (746s left) gem_exec_whisper (basic-contexts)
[274.533278] Starting subtest: basic-contexts
[279.210854] Subtest basic-contexts: SUCCESS (4.678s)
[279.405417] [074/133] (741s left) gem_pwrite (basic-self)
[279.471656] Subtest basic-self: SKIP (0.000s)
[279.530544] [075/133] (741s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-mmap-gtt)
[279.646972] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-mmap-gtt
[279.651372] Subtest fbc-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[279.778032] [076/133] (741s left) kms_cursor_legacy (single-bo)
[279.905451] Starting subtest: single-bo
[279.907678] Starting dynamic subtest: pipe-A
[285.341456] Dynamic subtest pipe-A: SUCCESS (5.379s)
[285.351176] Starting dynamic subtest: pipe-B
[290.722326] Dynamic subtest pipe-B: SUCCESS (5.379s)
[290.732840] Starting dynamic subtest: pipe-C
[296.103791] Dynamic subtest pipe-C: SUCCESS (5.379s)
[296.114466] Starting dynamic subtest: pipe-D
[301.485710] Dynamic subtest pipe-D: SUCCESS (5.379s)
[301.495479] Starting dynamic subtest: all-pipes
[306.822346] Dynamic subtest all-pipes: SUCCESS (5.385s)
[306.824802] Subtest single-bo: SUCCESS (26.914s)
[306.980130] [077/133] (714s left) syncobj_timeline (wait-any-interrupted)
[307.045083] Starting subtest: wait-any-interrupted
[307.148561] Subtest wait-any-interrupted: SUCCESS (0.102s)
[307.234256] [078/133] (713s left) i915_suspend (sysfs-reader)
[307.302162] Starting subtest: sysfs-reader
[325.041429] Subtest sysfs-reader: SUCCESS (3.237s)
[325.093106] [079/133] (696s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-rc-ccs)
[325.216046] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-rc-ccs
[325.218009] Starting dynamic subtest: pipe-A-eDP-1
[325.238066] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[325.249765] Starting dynamic subtest: pipe-B-eDP-1
[325.267381] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[325.276100] Starting dynamic subtest: pipe-C-eDP-1
[325.294474] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[325.304125] Starting dynamic subtest: pipe-D-eDP-1
[325.322465] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[325.326055] Subtest crc-primary-rotation-180-4-tiled-dg2-rc-ccs: SKIP (0.006s)
[327.393842] [080/133] (693s left) kms_plane_multiple (2x-tiling-x)
[327.482655] Starting subtest: 2x-tiling-x
[327.486703] Subtest 2x-tiling-x: SKIP (0.000s)
[327.570395] [081/133] (693s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-wc)
[327.672821] Starting subtest: fbcpsr-rgb565-draw-mmap-wc
[334.414436] Subtest fbcpsr-rgb565-draw-mmap-wc: SUCCESS (6.752s)
[334.577284] [082/133] (686s left) perf_pmu (semaphore-busy)
[334.621851] Starting subtest: semaphore-busy
[334.623768] Starting dynamic subtest: rcs0
[336.277505] Dynamic subtest rcs0: SUCCESS (1.653s)
[336.289467] Starting dynamic subtest: bcs0
[336.902424] Dynamic subtest bcs0: FAIL (0.572s)
[336.913510] Starting dynamic subtest: vcs0
[337.464660] Dynamic subtest vcs0: FAIL (0.553s)
[337.474233] Starting dynamic subtest: vcs1
[339.010637] Dynamic subtest vcs1: FAIL (1.540s)
[339.021016] Starting dynamic subtest: vecs0
[340.996567] Dynamic subtest vecs0: FAIL (1.986s)
[341.006873] Starting dynamic subtest: ccs0
[342.982398] Dynamic subtest ccs0: FAIL (1.984s)
[342.984327] Subtest semaphore-busy: FAIL (8.297s)
[343.083559] [083/133] (678s left) kms_cursor_crc (cursor-random-64x21)
[343.276537] Starting subtest: cursor-random-64x21
[343.318801] Subtest cursor-random-64x21: SKIP (0.039s)
[343.579672] [084/133] (677s left) kms_psr (pr-cursor-mmap-gtt)
[343.681698] Starting subtest: pr-cursor-mmap-gtt
[343.686020] Subtest pr-cursor-mmap-gtt: SKIP (0.000s)
[343.765802] [085/133] (677s left) kms_vblank (ts-continuation-idle-hang)
[343.864986] Starting subtest: ts-continuation-idle-hang
[343.867169] Starting dynamic subtest: pipe-A-eDP-1
[370.173433] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.312s)
[370.186851] Starting dynamic subtest: pipe-D-eDP-1
[396.162900] Dynamic subtest pipe-D-eDP-1: SUCCESS (25.992s)
[396.165263] Subtest ts-continuation-idle-hang: SUCCESS (52.308s)
[396.983649] [086/133] (624s left) syncobj_wait (multi-wait-submitted-signaled)
[397.045305] Starting subtest: multi-wait-submitted-signaled
[397.047608] Subtest multi-wait-submitted-signaled: SUCCESS (0.000s)
[397.159058] [087/133] (624s left) kms_color (degamma)
[397.262636] Starting subtest: degamma
[397.264833] Starting dynamic subtest: pipe-A-eDP-1
[397.983669] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.721s)
[397.993564] Starting dynamic subtest: pipe-B-eDP-1
[399.356193] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.373s)
[399.366366] Starting dynamic subtest: pipe-C-eDP-1
[400.759747] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.401s)
[400.769436] Starting dynamic subtest: pipe-D-eDP-1
[402.095363] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.337s)
[402.097711] Subtest degamma: SUCCESS (4.835s)
[402.912290] [088/133] (618s left) gem_mmap_gtt (cpuset-medium-copy-odd)
[402.970454] Subtest cpuset-medium-copy-odd: SKIP (0.000s)
[403.040552] [089/133] (618s left) kms_pm_rpm (cursor)
[404.560818] Starting subtest: cursor
[404.565847] Subtest cursor: SKIP (0.000s)
[405.348531] [090/133] (616s left) gem_exec_schedule (lateslice)
[405.425952] Starting subtest: lateslice
[405.427872] Starting dynamic subtest: rcs0
[405.435219] Dynamic subtest rcs0: SUCCESS (0.020s)
[405.447597] Starting dynamic subtest: bcs0
[405.453145] Dynamic subtest bcs0: SUCCESS (0.016s)
[405.462886] Starting dynamic subtest: vcs0
[405.468688] Dynamic subtest vcs0: SUCCESS (0.016s)
[405.477325] Starting dynamic subtest: vcs1
[405.488052] Dynamic subtest vcs1: SUCCESS (0.018s)
[405.496801] Starting dynamic subtest: vecs0
[405.504601] Dynamic subtest vecs0: SUCCESS (0.016s)
[405.513515] Starting dynamic subtest: ccs0
[405.521803] Dynamic subtest ccs0: SUCCESS (0.016s)
[405.524000] Subtest lateslice: SUCCESS (0.108s)
[407.534962] [091/133] (614s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180-hflip)
[407.639345] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180-hflip
[407.643857] Subtest y-tiled-max-hw-stride-64bpp-rotate-180-hflip: SKIP (0.000s)
[407.764088] [092/133] (613s left) kms_big_fb (x-tiled-8bpp-rotate-270)
[407.875267] Starting subtest: x-tiled-8bpp-rotate-270
[407.896327] Subtest x-tiled-8bpp-rotate-270: SKIP (0.020s)
[408.046969] [093/133] (613s left) gem_wait (await)
[408.112709] Starting subtest: await
[408.114571] Starting dynamic subtest: all-engines
[409.217308] Dynamic subtest all-engines: SUCCESS (1.107s)
[409.234719] Starting dynamic subtest: rcs0
[410.278632] Dynamic subtest rcs0: SUCCESS (1.059s)
[410.295564] Starting dynamic subtest: bcs0
[411.337989] Dynamic subtest bcs0: SUCCESS (1.057s)
[411.358687] Starting dynamic subtest: vcs0
[412.397036] Dynamic subtest vcs0: SUCCESS (1.056s)
[412.415830] Starting dynamic subtest: vcs1
[413.453015] Dynamic subtest vcs1: SUCCESS (1.053s)
[413.470038] Starting dynamic subtest: vecs0
[414.506704] Dynamic subtest vecs0: SUCCESS (1.051s)
[414.523630] Starting dynamic subtest: ccs0
[415.563741] Dynamic subtest ccs0: SUCCESS (1.055s)
[415.566337] Subtest await: SUCCESS (7.456s)
[415.769571] [094/133] (606s left) kms_psr2_sf (pr-overlay-plane-update-continuous-sf)
[415.873507] Starting subtest: pr-overlay-plane-update-continuous-sf
[415.877730] Subtest pr-overlay-plane-update-continuous-sf: SKIP (0.000s)
[415.951883] [095/133] (605s left) kms_cursor_crc (cursor-tearing-position-change)
[416.074077] Starting subtest: cursor-tearing-position-change
[416.076403] Starting dynamic subtest: pipe-A-eDP-1
[416.628700] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.551s)
[416.638431] Starting dynamic subtest: pipe-D-eDP-1
[417.843979] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.216s)
[417.845906] Subtest cursor-tearing-position-change: SUCCESS (1.769s)
[418.713090] [096/133] (603s left) kms_async_flips (async-flip-with-page-flip-events-linear-atomic)
[418.809428] Starting subtest: async-flip-with-page-flip-events-linear-atomic
[419.183120] Starting dynamic subtest: pipe-A-eDP-1
[422.012911] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.773s)
[422.336009] Starting dynamic subtest: pipe-B-eDP-1
[425.150714] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.771s)
[425.462098] Starting dynamic subtest: pipe-C-eDP-1
[428.281717] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.773s)
[428.590834] Starting dynamic subtest: pipe-D-eDP-1
[431.416088] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.779s)
[431.418395] Subtest async-flip-with-page-flip-events-linear-atomic: SUCCESS (12.554s)
[432.572456] [097/133] (589s left) kms_cursor_legacy (cursorb-vs-flipb-atomic-transitions)
[432.707865] Starting subtest: cursorB-vs-flipB-atomic-transitions
[432.712358] Subtest cursorB-vs-flipB-atomic-transitions: SKIP (0.001s)
[432.878799] [098/133] (589s left) perf_pmu (busy-accuracy-98)
[432.916308] Starting subtest: busy-accuracy-98
[432.918244] Starting dynamic subtest: rcs0
[435.953029] Dynamic subtest rcs0: SUCCESS (3.033s)
[435.965067] Starting dynamic subtest: bcs0
[439.068320] Dynamic subtest bcs0: SUCCESS (3.048s)
[439.078515] Starting dynamic subtest: vcs0
[442.099768] Dynamic subtest vcs0: SUCCESS (3.044s)
[442.111399] Starting dynamic subtest: vcs1
[445.139608] Dynamic subtest vcs1: SUCCESS (3.039s)
[445.150308] Starting dynamic subtest: vecs0
[448.186120] Dynamic subtest vecs0: SUCCESS (3.042s)
[448.197117] Starting dynamic subtest: ccs0
[451.241087] Dynamic subtest ccs0: SUCCESS (3.052s)
[451.243501] Subtest busy-accuracy-98: SUCCESS (18.273s)
[451.325880] [099/133] (570s left) kms_big_fb (yf-tiled-16bpp-rotate-270)
[451.432273] Starting subtest: yf-tiled-16bpp-rotate-270
[451.436497] Subtest yf-tiled-16bpp-rotate-270: SKIP (0.000s)
[451.563540] [100/133] (570s left) kms_prop_blob (invalid-set-prop)
[451.622698] Starting subtest: invalid-set-prop
[451.624582] Subtest invalid-set-prop: SUCCESS (0.000s)
[451.763381] [101/133] (570s left) gem_exec_params (cliprects-invalid)
[451.826977] Starting subtest: cliprects-invalid
[451.829378] Subtest cliprects-invalid: SUCCESS (0.000s)
[451.967303] [102/133] (570s left) sriov_basic (enable-vfs-bind-unbind-each)
[452.032178] Starting subtest: enable-vfs-bind-unbind-each
[452.034134] Starting dynamic subtest: numvfs-1
[452.108405] Dynamic subtest numvfs-1: FAIL (0.007s)
[452.119603] Starting dynamic subtest: numvfs-2
[452.192338] Dynamic subtest numvfs-2: FAIL (0.003s)
[452.203134] Starting dynamic subtest: numvfs-3
[452.262968] Dynamic subtest numvfs-3: FAIL (0.003s)
[452.273234] Starting dynamic subtest: numvfs-4
[452.334706] Dynamic subtest numvfs-4: FAIL (0.003s)
[452.345019] Starting dynamic subtest: numvfs-5
[452.403786] Dynamic subtest numvfs-5: FAIL (0.004s)
[452.413396] Starting dynamic subtest: numvfs-6
[452.472532] Dynamic subtest numvfs-6: FAIL (0.003s)
[452.482024] Starting dynamic subtest: numvfs-7
[452.540951] Dynamic subtest numvfs-7: FAIL (0.003s)
[452.550753] Starting dynamic subtest: numvfs-random
[452.609919] Dynamic subtest numvfs-random: FAIL (0.003s)
[452.611629] Subtest enable-vfs-bind-unbind-each: FAIL (0.036s)
[452.648651] [103/133] (569s left) gem_exec_flush (basic-uc-pro-default)
[452.704939] Starting subtest: basic-uc-pro-default
[458.188480] Subtest basic-uc-pro-default: SUCCESS (5.455s)
[458.361343] [104/133] (563s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-blt)
[458.466122] Starting subtest: psr-2p-primscrn-spr-indfb-draw-blt
[458.470573] Subtest psr-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[458.580733] [105/133] (563s left) kms_pipe_crc_basic (bad-source)
[458.679042] Starting subtest: bad-source
[458.680921] Subtest bad-source: SUCCESS (0.000s)
[458.769579] [106/133] (563s left) kms_atomic_transition (plane-primary-toggle-with-vblank-wait)
[458.866892] Starting subtest: plane-primary-toggle-with-vblank-wait
[458.869152] Starting dynamic subtest: pipe-A-eDP-1
[459.039707] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.174s)
[459.357082] Starting dynamic subtest: pipe-B-eDP-1
[460.246607] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.900s)
[460.545458] Subtest plane-primary-toggle-with-vblank-wait: SUCCESS (1.678s)
[461.424664] [107/133] (560s left) kms_ccs (bad-rotation-90-y-tiled-gen12-mc-ccs)
[461.518925] Starting subtest: bad-rotation-90-y-tiled-gen12-mc-ccs
[461.521228] Starting dynamic subtest: pipe-A-eDP-1
[461.545566] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[461.555479] Starting dynamic subtest: pipe-B-eDP-1
[461.578395] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[461.589258] Starting dynamic subtest: pipe-C-eDP-1
[461.610914] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[461.619821] Starting dynamic subtest: pipe-D-eDP-1
[461.643280] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[461.647913] Subtest bad-rotation-90-y-tiled-gen12-mc-ccs: SKIP (0.004s)
[463.784084] [108/133] (558s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-pwrite)
[463.887073] Starting subtest: psr-1p-primscrn-cur-indfb-draw-pwrite
[470.673083] Subtest psr-1p-primscrn-cur-indfb-draw-pwrite: SUCCESS (6.789s)
[470.787450] [109/133] (551s left) kms_big_fb (y-tiled-16bpp-rotate-270)
[470.890738] Starting subtest: y-tiled-16bpp-rotate-270
[470.895060] Subtest y-tiled-16bpp-rotate-270: SKIP (0.000s)
[471.005369] [110/133] (551s left) syncobj_eventfd (invalid-bad-pad)
[471.063711] Starting subtest: invalid-bad-pad
[471.065962] Subtest invalid-bad-pad: SUCCESS (0.000s)
[471.184749] [111/133] (551s left) gem_exec_params (larger-than-life-batch)
[471.251484] Starting subtest: larger-than-life-batch
[473.495596] Subtest larger-than-life-batch: SUCCESS (2.244s)
[474.493442] [112/133] (548s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[474.647237] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu
[474.651270] Subtest fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[474.743875] [113/133] (547s left) kms_psr (psr-cursor-mmap-cpu)
[474.847384] Starting subtest: psr-cursor-mmap-cpu
[474.849652] Starting dynamic subtest: eDP-1
[476.556928] Dynamic subtest eDP-1: SUCCESS (1.710s)
[476.559378] Subtest psr-cursor-mmap-cpu: SUCCESS (1.711s)
[477.437814] [114/133] (545s left) kms_big_fb (yf-tiled-64bpp-rotate-90)
[477.536220] Starting subtest: yf-tiled-64bpp-rotate-90
[477.540273] Subtest yf-tiled-64bpp-rotate-90: SKIP (0.000s)
[477.667628] [115/133] (544s left) gem_workarounds (suspend-resume)
[477.760307] Starting subtest: suspend-resume
[494.053844] Subtest suspend-resume: SUCCESS (1.219s)
[494.218075] [116/133] (528s left) gem_ctx_persistence (smoketest)
[494.292393] Starting subtest: smoketest
[534.402650] Subtest smoketest: SUCCESS (40.133s)
[534.492847] [117/133] (488s left) kms_flip (2x-flip-vs-suspend)
[534.597791] Starting subtest: 2x-flip-vs-suspend
[534.606636] Subtest 2x-flip-vs-suspend: SKIP (0.011s)
[534.717296] [118/133] (488s left) gem_exec_reloc (basic-gtt-cpu)
[534.775634] Subtest basic-gtt-cpu: SKIP (0.000s)
[534.861532] [119/133] (487s left) i915_drm_fdinfo (all-busy-idle-check-all)
[534.900182] Starting subtest: all-busy-idle-check-all
[534.904300] Subtest all-busy-idle-check-all: SKIP (0.004s)
[534.983497] [120/133] (487s left) core_auth (getclient-master-drop)
[535.022696] Starting subtest: getclient-master-drop
[535.061591] Subtest getclient-master-drop: SUCCESS (0.040s)
[535.182580] [121/133] (487s left) kms_psr (fbc-psr2-cursor-plane-onoff)
[535.274754] Starting subtest: fbc-psr2-cursor-plane-onoff
[535.276937] Starting dynamic subtest: eDP-1
[535.280975] Dynamic subtest eDP-1: SKIP (0.000s)
[535.284959] Subtest fbc-psr2-cursor-plane-onoff: SKIP (0.001s)
[535.363051] [122/133] (487s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-cpu)
[535.466608] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-cpu
[535.469960] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[535.589161] [123/133] (487s left) kms_rotation_crc (primary-4-tiled-reflect-x-180)
[535.676727] Starting subtest: primary-4-tiled-reflect-x-180
[542.702795] Subtest primary-4-tiled-reflect-x-180: SUCCESS (7.027s)
[543.890960] [124/133] (479s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[543.999041] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-gtt
[544.003136] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[544.120207] [125/133] (478s left) gem_mmap_wc (set-cache-level)
[544.171299] Subtest set-cache-level: SKIP (0.000s)
[544.233627] [126/133] (478s left) prime_mmap_coherency (write)
[544.307192] Starting subtest: write
[549.741617] Subtest write: SUCCESS (5.379s)
[549.849209] [127/133] (473s left) i915_hangman (engine-engine-hang)
[549.927300] Starting subtest: engine-engine-hang
[549.929649] Starting dynamic subtest: rcs0
[551.424017] Dynamic subtest rcs0: SUCCESS (1.494s)
[551.436204] Starting dynamic subtest: bcs0
[553.473216] Dynamic subtest bcs0: SUCCESS (2.047s)
[553.487161] Starting dynamic subtest: vcs0
[555.457024] Dynamic subtest vcs0: SUCCESS (1.981s)
[555.470526] Starting dynamic subtest: vcs1
[557.441652] Dynamic subtest vcs1: SUCCESS (1.982s)
[557.455763] Starting dynamic subtest: vecs0
[559.488628] Dynamic subtest vecs0: SUCCESS (2.044s)
[559.505310] Starting dynamic subtest: ccs0
[561.409761] Dynamic subtest ccs0: SUCCESS (1.919s)
[561.412671] Subtest engine-engine-hang: SUCCESS (11.484s)
[561.503180] [128/133] (461s left) gem_softpin (overlap)
[561.580715] Starting subtest: overlap
[562.419225] Subtest overlap: SUCCESS (0.837s)
[562.565405] [129/133] (460s left) kms_sequence (get-forked)
[562.668703] Starting subtest: get-forked
[562.670943] Starting dynamic subtest: pipe-A-eDP-1
[564.622660] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.954s)
[564.636145] Starting dynamic subtest: pipe-B-eDP-1
[567.187083] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.565s)
[567.198038] Starting dynamic subtest: pipe-C-eDP-1
[569.740821] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.551s)
[569.751078] Starting dynamic subtest: pipe-D-eDP-1
[572.254634] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.513s)
[572.257059] Subtest get-forked: SUCCESS (9.589s)
[573.114108] [130/133] (450s left) kms_content_protection (uevent)
[573.262554] Starting subtest: uevent
[573.266941] Subtest uevent: SKIP (0.000s)
[573.438440] [131/133] (449s left) gem_ctx_create (basic)
[573.502766] Starting subtest: basic
[573.505098] Subtest basic: SUCCESS (0.000s)
[573.712148] [132/133] (449s left) gem_pxp (protected-encrypted-src-copy-not-readible)
[573.845953] Starting subtest: protected-encrypted-src-copy-not-readible
[573.956135] Subtest protected-encrypted-src-copy-not-readible: SUCCESS (0.111s)
[574.112999] [133/133] (449s left) gem_ppgtt (blt-vs-render-ctxn)
[574.190270] Starting subtest: blt-vs-render-ctxN
[607.029448] Subtest blt-vs-render-ctxN: SUCCESS (32.839s)
[607.196894] Closing watchdogs
results: parsing output: 0/ for test: kms_big_fb
results: parsing output: 1/ for test: gem_exec_fence
results: parsing output: 2/ for test: kms_pm_rpm
results: parsing output: 3/ for test: kms_flip_scaled_crc
results: parsing output: 4/ for test: syncobj_timeline
results: parsing output: 5/ for test: kms_rmfb
results: parsing output: 6/ for test: gem_spin_batch
results: parsing output: 7/ for test: gem_exec_fence
results: parsing output: 8/ for test: kms_frontbuffer_tracking
results: parsing output: 9/ for test: kms_psr
results: parsing output: 10/ for test: syncobj_timeline
results: parsing output: 11/ for test: kms_flip
results: parsing output: 12/ for test: kms_dsc
results: parsing output: 13/ for test: kms_frontbuffer_tracking
results: parsing output: 14/ for test: kms_frontbuffer_tracking
results: parsing output: 15/ for test: kms_frontbuffer_tracking
results: parsing output: 16/ for test: gem_lmem_swapping
results: parsing output: 17/ for test: gem_exec_reloc
results: parsing output: 18/ for test: gem_ctx_isolation
results: parsing output: 19/ for test: kms_rotation_crc
results: parsing output: 20/ for test: gem_ctx_engines
results: parsing output: 21/ for test: kms_frontbuffer_tracking
results: parsing output: 22/ for test: kms_big_fb
results: parsing output: 23/ for test: gem_render_copy
results: parsing output: 24/ for test: kms_ccs
results: parsing output: 25/ for test: kms_pipe_crc_basic
results: parsing output: 26/ for test: kms_invalid_mode
results: parsing output: 27/ for test: kms_frontbuffer_tracking
results: parsing output: 28/ for test: kms_plane
results: parsing output: 29/ for test: gem_exec_schedule
results: parsing output: 30/ for test: kms_frontbuffer_tracking
results: parsing output: 31/ for test: gem_exec_params
results: parsing output: 32/ for test: gem_exec_balancer
results: parsing output: 33/ for test: gen9_exec_parse
results: parsing output: 34/ for test: gem_ccs
results: parsing output: 35/ for test: gem_ctx_bad_destroy
results: parsing output: 36/ for test: kms_async_flips
results: parsing output: 37/ for test: vgem_slow
results: parsing output: 38/ for test: kms_chamelium_hpd
results: parsing output: 39/ for test: kms_frontbuffer_tracking
results: parsing output: 40/ for test: kms_addfb_basic
results: parsing output: 41/ for test: prime_udl
results: parsing output: 42/ for test: kms_cursor_legacy
results: parsing output: 43/ for test: kms_lease
results: parsing output: 44/ for test: gen3_render_tiledy_blits
results: parsing output: 45/ for test: kms_cursor_crc
results: parsing output: 46/ for test: gem_ctx_shared
results: parsing output: 47/ for test: gem_mmap_gtt
results: parsing output: 48/ for test: kms_frontbuffer_tracking
results: parsing output: 49/ for test: kms_plane
results: parsing output: 50/ for test: kms_plane_scaling
results: parsing output: 51/ for test: i915_pm_rps
results: parsing output: 52/ for test: kms_frontbuffer_tracking
results: parsing output: 53/ for test: kms_ccs
results: parsing output: 54/ for test: gem_mmap
results: parsing output: 55/ for test: gem_partial_pwrite_pread
results: parsing output: 56/ for test: syncobj_wait
results: parsing output: 57/ for test: gem_mmap_offset
results: parsing output: 58/ for test: syncobj_timeline
results: parsing output: 59/ for test: kms_chamelium_color
results: parsing output: 60/ for test: device_reset
results: parsing output: 61/ for test: kms_flip
results: parsing output: 62/ for test: kms_frontbuffer_tracking
results: parsing output: 63/ for test: gem_exec_params
results: parsing output: 64/ for test: gem_userptr_blits
results: parsing output: 65/ for test: kms_frontbuffer_tracking
results: parsing output: 66/ for test: kms_psr
results: parsing output: 67/ for test: kms_frontbuffer_tracking
results: parsing output: 68/ for test: api_intel_allocator
results: parsing output: 69/ for test: syncobj_timeline
results: parsing output: 70/ for test: kms_chamelium_edid
results: parsing output: 71/ for test: kms_flip
results: parsing output: 72/ for test: gem_exec_whisper
results: parsing output: 73/ for test: gem_pwrite
results: parsing output: 74/ for test: kms_frontbuffer_tracking
results: parsing output: 75/ for test: kms_cursor_legacy
results: parsing output: 76/ for test: syncobj_timeline
results: parsing output: 77/ for test: i915_suspend
results: parsing output: 78/ for test: kms_ccs
results: parsing output: 79/ for test: kms_plane_multiple
results: parsing output: 80/ for test: kms_frontbuffer_tracking
results: parsing output: 81/ for test: perf_pmu
results: parsing output: 82/ for test: kms_cursor_crc
results: parsing output: 83/ for test: kms_psr
results: parsing output: 84/ for test: kms_vblank
results: parsing output: 85/ for test: syncobj_wait
results: parsing output: 86/ for test: kms_color
results: parsing output: 87/ for test: gem_mmap_gtt
results: parsing output: 88/ for test: kms_pm_rpm
results: parsing output: 89/ for test: gem_exec_schedule
results: parsing output: 90/ for test: kms_big_fb
results: parsing output: 91/ for test: kms_big_fb
results: parsing output: 92/ for test: gem_wait
results: parsing output: 93/ for test: kms_psr2_sf
results: parsing output: 94/ for test: kms_cursor_crc
results: parsing output: 95/ for test: kms_async_flips
results: parsing output: 96/ for test: kms_cursor_legacy
results: parsing output: 97/ for test: perf_pmu
results: parsing output: 98/ for test: kms_big_fb
results: parsing output: 99/ for test: kms_prop_blob
results: parsing output: 100/ for test: gem_exec_params
results: parsing output: 101/ for test: sriov_basic
results: parsing output: 102/ for test: gem_exec_flush
results: parsing output: 103/ for test: kms_frontbuffer_tracking
results: parsing output: 104/ for test: kms_pipe_crc_basic
results: parsing output: 105/ for test: kms_atomic_transition
results: parsing output: 106/ for test: kms_ccs
results: parsing output: 107/ for test: kms_frontbuffer_tracking
results: parsing output: 108/ for test: kms_big_fb
results: parsing output: 109/ for test: syncobj_eventfd
results: parsing output: 110/ for test: gem_exec_params
results: parsing output: 111/ for test: kms_frontbuffer_tracking
results: parsing output: 112/ for test: kms_psr
results: parsing output: 113/ for test: kms_big_fb
results: parsing output: 114/ for test: gem_workarounds
results: parsing output: 115/ for test: gem_ctx_persistence
results: parsing output: 116/ for test: kms_flip
results: parsing output: 117/ for test: gem_exec_reloc
results: parsing output: 118/ for test: i915_drm_fdinfo
results: parsing output: 119/ for test: core_auth
results: parsing output: 120/ for test: kms_psr
results: parsing output: 121/ for test: kms_frontbuffer_tracking
results: parsing output: 122/ for test: kms_rotation_crc
results: parsing output: 123/ for test: kms_frontbuffer_tracking
results: parsing output: 124/ for test: gem_mmap_wc
results: parsing output: 125/ for test: prime_mmap_coherency
results: parsing output: 126/ for test: i915_hangman
results: parsing output: 127/ for test: gem_softpin
results: parsing output: 128/ for test: kms_sequence
results: parsing output: 129/ for test: kms_content_protection
results: parsing output: 130/ for test: gem_ctx_create
results: parsing output: 131/ for test: gem_pxp
results: parsing output: 132/ for test: gem_ppgtt
Done.