[65.967056] Initializing watchdogs
[65.967118]   /dev/watchdog0
[65.987077] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:03:00.0: 8086:56a0 Intel Dg2 (Gen12) DG2 [Arc A770]
[65.997780] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:03:00.0: card0
[66.001626] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[66.004049] [001/131] (960s left) gem_userptr_blits (input-checking)
[66.087583] Starting subtest: input-checking
[66.088412] Subtest input-checking: SUCCESS (0.000s)
[66.287832] [002/131] (960s left) gen7_exec_parse (basic-allocation)
[66.364031] Subtest basic-allocation: SKIP (0.000s)
[66.436019] [003/131] (960s left) kms_hdr (static-toggle-dpms)
[66.613446] Starting subtest: static-toggle-dpms
[67.108013] Starting dynamic subtest: pipe-A-DP-3
[68.297170] Dynamic subtest pipe-A-DP-3: SUCCESS (1.189s)
[68.298332] Subtest static-toggle-dpms: SUCCESS (1.684s)
[68.671569] [004/131] (958s left) gem_caching (reads)
[68.746455] Starting subtest: reads
[71.762040] Subtest reads: SUCCESS (3.016s)
[71.927079] [005/131] (954s left) kms_big_fb (linear-16bpp-rotate-270)
[72.124509] Starting subtest: linear-16bpp-rotate-270
[72.177925] Subtest linear-16bpp-rotate-270: SKIP (0.053s)
[72.386626] [006/131] (954s left) kms_ccs (crc-primary-rotation-180-y-tiled-gen12-mc-ccs)
[72.566494] Starting subtest: crc-primary-rotation-180-y-tiled-gen12-mc-ccs
[72.567397] Starting dynamic subtest: pipe-A-DP-3
[72.575739] Dynamic subtest pipe-A-DP-3: SKIP (0.001s)
[72.583915] Starting dynamic subtest: pipe-B-DP-3
[72.592218] Dynamic subtest pipe-B-DP-3: SKIP (0.001s)
[72.600236] Starting dynamic subtest: pipe-C-DP-3
[72.608209] Dynamic subtest pipe-C-DP-3: SKIP (0.001s)
[72.616190] Starting dynamic subtest: pipe-D-DP-3
[72.625468] Dynamic subtest pipe-D-DP-3: SKIP (0.001s)
[72.627319] Subtest crc-primary-rotation-180-y-tiled-gen12-mc-ccs: SKIP (0.006s)
[73.051751] [007/131] (953s left) syncobj_wait (invalid-signal-one-illegal-handle)
[73.119964] Starting subtest: invalid-signal-one-illegal-handle
[73.120952] Subtest invalid-signal-one-illegal-handle: SUCCESS (0.000s)
[73.315184] [008/131] (953s left) gem_exec_reloc (basic-range)
[73.389355] Subtest basic-range: SKIP (0.000s)
[73.458589] [009/131] (953s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-blt)
[73.641527] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-blt
[73.643153] Subtest psr-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s)
[73.778450] [010/131] (953s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[73.967062] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[75.003621] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SUCCESS (1.037s)
[75.242321] [011/131] (951s left) sysfs_preempt_timeout (idempotent)
[75.317932] Starting subtest: idempotent
[75.318911] Starting dynamic subtest: vecs0
[75.319805] Dynamic subtest vecs0: SUCCESS (0.001s)
[75.328639] Starting dynamic subtest: ccs0
[75.329628] Dynamic subtest ccs0: SUCCESS (0.001s)
[75.338378] Starting dynamic subtest: vcs0
[75.339630] Dynamic subtest vcs0: SUCCESS (0.001s)
[75.347813] Starting dynamic subtest: bcs0
[75.348811] Dynamic subtest bcs0: SUCCESS (0.001s)
[75.357201] Starting dynamic subtest: vecs1
[75.358253] Dynamic subtest vecs1: SUCCESS (0.001s)
[75.366829] Starting dynamic subtest: rcs0
[75.368033] Dynamic subtest rcs0: SUCCESS (0.001s)
[75.376631] Starting dynamic subtest: vcs1
[75.377773] Dynamic subtest vcs1: SUCCESS (0.001s)
[75.378709] Subtest idempotent: SUCCESS (0.007s)
[75.477499] [012/131] (951s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-mmap-cpu)
[75.658574] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-mmap-cpu
[75.660212] Subtest fbc-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[75.847182] [013/131] (951s left) kms_busy (extended-pageflip-hang-newfb)
[76.030946] Starting subtest: extended-pageflip-hang-newfb
[76.032908] Starting dynamic subtest: pipe-A
[83.879875] Dynamic subtest pipe-A: SUCCESS (7.847s)
[83.893268] Starting dynamic subtest: pipe-D
[91.753638] Dynamic subtest pipe-D: SUCCESS (7.869s)
[91.754609] Subtest extended-pageflip-hang-newfb: SUCCESS (15.723s)
[92.028901] [014/131] (935s left) kms_plane_scaling (plane-downscale-factor-0-75-with-modifiers)
[92.203217] Starting subtest: plane-downscale-factor-0-75-with-modifiers
[92.204072] Starting dynamic subtest: pipe-A
[92.799381] Dynamic subtest pipe-A: SUCCESS (0.596s)
[92.818173] Starting dynamic subtest: pipe-B
[93.444292] Dynamic subtest pipe-B: SUCCESS (0.645s)
[93.456824] Starting dynamic subtest: pipe-C
[94.073889] Dynamic subtest pipe-C: SUCCESS (0.629s)
[94.092424] Starting dynamic subtest: pipe-D
[94.718403] Dynamic subtest pipe-D: SUCCESS (0.644s)
[94.719361] Subtest plane-downscale-factor-0-75-with-modifiers: SUCCESS (2.515s)
[95.064842] [015/131] (932s left) kms_psr2_sf (fbc-psr2-overlay-plane-move-continuous-sf)
[95.247964] Subtest fbc-psr2-overlay-plane-move-continuous-sf: SKIP (0.000s)
[95.289222] [016/131] (932s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-16bpp-yftile-upscaling)
[95.464412] Starting subtest: flip-64bpp-yftile-to-16bpp-yftile-upscaling
[95.465331] Starting dynamic subtest: pipe-A-valid-mode
[95.544200] Dynamic subtest pipe-A-valid-mode: SKIP (0.078s)
[95.546051] Subtest flip-64bpp-yftile-to-16bpp-yftile-upscaling: SKIP (0.079s)
[95.794868] [017/131] (931s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[95.971539] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt
[95.973201] Subtest fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[96.176068] [018/131] (931s left) gem_pwrite_snooped
[96.334509] [019/131] (931s left) gem_lmem_swapping (random-engines)
[98.986308] Starting subtest: random-engines
[98.987522] Starting dynamic subtest: lmem0
[99.967495] Dynamic subtest lmem0: SUCCESS (0.981s)
[99.968382] Subtest random-engines: SUCCESS (0.982s)
[102.962082] [FACT gem_lmem_swapping (random-engines)] deleted: kernel.kmod_is_loaded.i915: true
[102.963565] [020/131] (924s left) kms_draw_crc (draw-method-render)
[104.584983] Starting subtest: draw-method-render
[104.585826] Starting dynamic subtest: xrgb8888-untiled
[104.966247] Dynamic subtest xrgb8888-untiled: SUCCESS (0.380s)
[105.002322] Starting dynamic subtest: rgb565-untiled
[105.316260] Dynamic subtest rgb565-untiled: SUCCESS (0.348s)
[105.352361] Starting dynamic subtest: xrgb2101010-untiled
[105.616275] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.298s)
[105.653068] Starting dynamic subtest: xbgr16161616f-untiled
[105.966143] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.348s)
[106.002243] Starting dynamic subtest: xrgb8888-xtiled
[106.166606] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.198s)
[106.205337] Starting dynamic subtest: rgb565-xtiled
[106.349619] Dynamic subtest rgb565-xtiled: SUCCESS (0.181s)
[106.361207] Starting dynamic subtest: xrgb2101010-xtiled
[106.498252] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.148s)
[106.506964] Starting dynamic subtest: xbgr16161616f-xtiled
[106.649709] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.150s)
[106.682804] Starting dynamic subtest: xrgb8888-4tiled
[106.833137] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.181s)
[106.870984] Starting dynamic subtest: rgb565-4tiled
[107.016594] Dynamic subtest rgb565-4tiled: SUCCESS (0.181s)
[107.054550] Starting dynamic subtest: xrgb2101010-4tiled
[107.199636] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.181s)
[107.236425] Starting dynamic subtest: xbgr16161616f-4tiled
[107.383136] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.181s)
[107.385032] Subtest draw-method-render: SUCCESS (2.798s)
[107.610136] [FACT kms_draw_crc (draw-method-render)] new: kernel.kmod_is_loaded.i915: true
[107.612444] [021/131] (920s left) kms_hdr (bpc-switch)
[107.789038] Starting subtest: bpc-switch
[110.976548] Starting dynamic subtest: pipe-A-DP-3
[112.086611] Dynamic subtest pipe-A-DP-3: SUCCESS (1.109s)
[112.088181] Subtest bpc-switch: SUCCESS (4.299s)
[112.478682] [022/131] (915s left) kms_vblank (ts-continuation-idle)
[112.657012] Starting subtest: ts-continuation-idle
[112.657872] Starting dynamic subtest: pipe-A-DP-3
[112.972620] Dynamic subtest pipe-A-DP-3: SUCCESS (0.314s)
[112.989663] Starting dynamic subtest: pipe-D-DP-3
[113.235242] Dynamic subtest pipe-D-DP-3: SUCCESS (0.257s)
[113.236352] Subtest ts-continuation-idle: SUCCESS (0.578s)
[113.499714] [023/131] (914s left) kms_cursor_edge_walk (256x256-top-edge)
[113.676471] Starting subtest: 256x256-top-edge
[113.678384] Starting dynamic subtest: pipe-A-DP-3
[117.133171] Dynamic subtest pipe-A-DP-3: SUCCESS (3.455s)
[117.145131] Starting dynamic subtest: pipe-D-DP-3
[120.453411] Dynamic subtest pipe-D-DP-3: SUCCESS (3.318s)
[120.454393] Subtest 256x256-top-edge: SUCCESS (6.777s)
[120.789890] [024/131] (907s left) kms_chamelium_edid (dp-edid-resolution-list)
[121.052082] Subtest dp-edid-resolution-list: SKIP (0.000s)
[121.190237] [025/131] (906s left) gem_exec_balancer (bonded-true-hang)
[121.549245] Starting subtest: bonded-true-hang
[121.551157] Subtest bonded-true-hang: SKIP (0.000s)
[121.690823] [026/131] (906s left) kms_big_fb (yf-tiled-64bpp-rotate-90)
[121.876826] Starting subtest: yf-tiled-64bpp-rotate-90
[121.878515] Subtest yf-tiled-64bpp-rotate-90: SKIP (0.000s)
[122.071181] [027/131] (906s left) prime_mmap (test_forked_cpu_write)
[122.140115] Starting subtest: test_forked_cpu_write
[122.141026] Starting dynamic subtest: test_forked_cpu_write-smem
[122.148503] Dynamic subtest test_forked_cpu_write-smem: SUCCESS (0.008s)
[122.157513] Starting dynamic subtest: test_forked_cpu_write-lmem0
[122.158418] Dynamic subtest test_forked_cpu_write-lmem0: SUCCESS (0.008s)
[122.159247] Subtest test_forked_cpu_write: SUCCESS (0.018s)
[122.354978] [028/131] (905s left) kms_dirtyfb (psr-dirtyfb-ioctl)
[122.528544] Starting subtest: psr-dirtyfb-ioctl
[122.533403] Subtest psr-dirtyfb-ioctl: SKIP (0.000s)
[122.714394] [029/131] (905s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted)
[122.782426] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted
[122.887599] Subtest etime-multi-wait-all-for-submit-available-unsubmitted: SUCCESS (0.104s)
[123.070095] [030/131] (905s left) kms_frontbuffer_tracking (psr-2p-primscrn-indfb-pgflip-blt)
[123.254566] Starting subtest: psr-2p-primscrn-indfb-pgflip-blt
[123.256199] Subtest psr-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[123.458430] [031/131] (905s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc)
[123.646648] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc
[123.648279] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[123.811412] [032/131] (904s left) kms_cursor_crc (cursor-random-64x64)
[124.128937] Starting subtest: cursor-random-64x64
[124.133266] Starting dynamic subtest: pipe-A-DP-3
[128.703711] Dynamic subtest pipe-A-DP-3: SUCCESS (4.570s)
[128.753801] Starting dynamic subtest: pipe-D-DP-3
[133.278501] Dynamic subtest pipe-D-DP-3: SUCCESS (4.534s)
[133.279441] Subtest cursor-random-64x64: SUCCESS (9.149s)
[133.824944] [033/131] (894s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytileccs-downscaling)
[134.002169] Starting subtest: flip-32bpp-ytile-to-32bpp-ytileccs-downscaling
[134.003010] Starting dynamic subtest: pipe-A-valid-mode
[134.078494] Dynamic subtest pipe-A-valid-mode: SKIP (0.075s)
[134.080270] Subtest flip-32bpp-ytile-to-32bpp-ytileccs-downscaling: SKIP (0.077s)
[134.327617] [034/131] (894s left) gem_exec_fence (syncobj-timeline-multiple-ext-nodes)
[134.970650] Starting subtest: syncobj-timeline-multiple-ext-nodes
[134.973833] Subtest syncobj-timeline-multiple-ext-nodes: SUCCESS (0.003s)
[135.242380] [FACT gem_exec_fence (syncobj-timeline-multiple-ext-nodes)] new: kernel.kmod_is_loaded.vgem: true
[135.244503] [035/131] (893s left) kms_psr (fbc-pr-primary-page-flip)
[135.438184] Subtest fbc-pr-primary-page-flip: SKIP (0.000s)
[135.489301] [036/131] (893s left) kms_psr (psr2-basic)
[135.677186] Subtest psr2-basic: SKIP (0.000s)
[135.737270] [037/131] (893s left) kms_ccs (bad-pixel-format-4-tiled-dg2-rc-ccs)
[135.928581] Starting subtest: bad-pixel-format-4-tiled-dg2-rc-ccs
[135.930642] Starting dynamic subtest: pipe-A-DP-3
[135.939049] Dynamic subtest pipe-A-DP-3: SKIP (0.000s)
[135.948300] Starting dynamic subtest: pipe-B-DP-3
[135.956460] Dynamic subtest pipe-B-DP-3: SKIP (0.000s)
[135.965553] Starting dynamic subtest: pipe-C-DP-3
[135.973666] Dynamic subtest pipe-C-DP-3: SKIP (0.000s)
[135.982710] Starting dynamic subtest: pipe-D-DP-3
[135.992477] Dynamic subtest pipe-D-DP-3: SKIP (0.000s)
[135.994294] Subtest bad-pixel-format-4-tiled-dg2-rc-ccs: SKIP (0.006s)
[136.390121] [038/131] (892s left) gem_exec_reloc (basic-wc-gtt-noreloc)
[136.472426] Subtest basic-wc-gtt-noreloc: SKIP (0.000s)
[136.585683] [039/131] (892s left) gem_ctx_param (invalid-get-engines)
[136.662578] Starting subtest: invalid-get-engines
[136.664569] Subtest invalid-get-engines: SUCCESS (0.002s)
[136.825584] [040/131] (892s left) kms_chamelium_color (ctm-blue-to-red)
[137.014562] Subtest ctm-blue-to-red: SKIP (0.000s)
[137.081459] [041/131] (892s left) gem_exec_schedule (deep)
[137.294933] Starting subtest: deep
[137.295873] Starting dynamic subtest: rcs0
[151.740440] Dynamic subtest rcs0: SUCCESS (14.445s)
[151.753621] Starting dynamic subtest: bcs0
[166.532197] Dynamic subtest bcs0: SUCCESS (14.787s)
[166.541967] Starting dynamic subtest: vcs0
[181.253655] Dynamic subtest vcs0: SUCCESS (14.720s)
[181.263636] Starting dynamic subtest: vcs1
[196.123966] Dynamic subtest vcs1: SUCCESS (14.869s)
[196.134210] Starting dynamic subtest: vecs0
[210.934239] Dynamic subtest vecs0: SUCCESS (14.809s)
[210.944615] Starting dynamic subtest: vecs1
[225.476989] Dynamic subtest vecs1: SUCCESS (14.542s)
[225.486924] Starting dynamic subtest: ccs0
[240.688067] Dynamic subtest ccs0: SUCCESS (15.211s)
[240.689227] Subtest deep: SUCCESS (103.393s)
[241.639890] [042/131] (787s left) kms_cursor_crc (cursor-offscreen-64x64)
[241.960270] Starting subtest: cursor-offscreen-64x64
[241.964499] Starting dynamic subtest: pipe-A-DP-3
[244.497238] Dynamic subtest pipe-A-DP-3: SUCCESS (2.528s)
[244.546794] Starting dynamic subtest: pipe-D-DP-3
[247.010692] Dynamic subtest pipe-D-DP-3: SUCCESS (2.472s)
[247.011843] Subtest cursor-offscreen-64x64: SUCCESS (5.050s)
[247.514891] [043/131] (781s left) perf (gen12-unprivileged-single-ctx-counters)
[247.903902] Starting subtest: gen12-unprivileged-single-ctx-counters
[247.904721] Starting dynamic subtest: rcs0
[248.000993] Dynamic subtest rcs0: SUCCESS (0.097s)
[248.001941] Subtest gen12-unprivileged-single-ctx-counters: SUCCESS (0.098s)
[248.053631] [044/131] (781s left) kms_pm_rpm (system-suspend-idle)
[250.072192] Starting subtest: system-suspend-idle
[270.011842] Subtest system-suspend-idle: SUCCESS (5.247s)
[289.495862] [045/131] (759s left) syncobj_timeline (single-wait-all-available-signaled)
[289.584260] Starting subtest: single-wait-all-available-signaled
[289.585148] Subtest single-wait-all-available-signaled: SUCCESS (0.000s)
[289.744569] [046/131] (758s left) kms_psr (fbc-psr-cursor-render)
[289.930726] Subtest fbc-psr-cursor-render: SKIP (0.000s)
[289.988646] [047/131] (758s left) gem_ctx_persistence (process)
[290.072656] Starting subtest: process
[290.292879] Subtest process: SUCCESS (0.220s)
[290.480477] [048/131] (758s left) kms_atomic_transition (plane-all-modeset-transition-fencing-internal-panels)
[290.672145] Starting subtest: plane-all-modeset-transition-fencing-internal-panels
[290.673934] Subtest plane-all-modeset-transition-fencing-internal-panels: SKIP (0.000s)
[290.825763] [049/131] (757s left) kms_psr (fbc-psr2-primary-render)
[291.010254] Subtest fbc-psr2-primary-render: SKIP (0.000s)
[291.070944] [050/131] (757s left) gem_render_copy (linear)
[291.154868] Starting subtest: linear
[291.155820] Starting dynamic subtest: smem
[291.265043] Dynamic subtest smem: SUCCESS (0.109s)
[291.276290] Starting dynamic subtest: lmem0
[291.505036] Dynamic subtest lmem0: SUCCESS (0.240s)
[291.505871] Subtest linear: SUCCESS (0.350s)
[291.864328] [051/131] (757s left) syncobj_wait (wait-delayed-signal)
[291.944415] Starting subtest: wait-delayed-signal
[292.045282] Subtest wait-delayed-signal: SUCCESS (0.102s)
[292.208734] [052/131] (756s left) kms_plane_alpha_blend (coverage-vs-premult-vs-constant)
[292.397591] Starting subtest: coverage-vs-premult-vs-constant
[292.634057] Starting dynamic subtest: pipe-A-DP-3
[293.085431] Dynamic subtest pipe-A-DP-3: SUCCESS (0.451s)
[293.105062] Starting dynamic subtest: pipe-D-DP-3
[293.602940] Dynamic subtest pipe-D-DP-3: SUCCESS (0.515s)
[293.603827] Subtest coverage-vs-premult-vs-constant: SUCCESS (1.205s)
[293.893081] [053/131] (755s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[294.082453] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[294.084046] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[294.291504] [054/131] (754s left) kms_big_fb (4-tiled-16bpp-rotate-90)
[294.484944] Starting subtest: 4-tiled-16bpp-rotate-90
[294.538803] Subtest 4-tiled-16bpp-rotate-90: SKIP (0.053s)
[294.772427] [055/131] (754s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-mmap-wc)
[294.959524] Starting subtest: fbcpsr-rgb101010-draw-mmap-wc
[294.961112] Subtest fbcpsr-rgb101010-draw-mmap-wc: SKIP (0.000s)
[295.156575] [056/131] (754s left) kms_sequence (queue-idle)
[295.339830] Starting subtest: queue-idle
[295.340771] Starting dynamic subtest: pipe-A-DP-3
[297.713148] Dynamic subtest pipe-A-DP-3: SUCCESS (2.372s)
[297.726136] Starting dynamic subtest: pipe-B-DP-3
[299.975027] Dynamic subtest pipe-B-DP-3: SUCCESS (2.260s)
[299.986772] Starting dynamic subtest: pipe-C-DP-3
[302.257622] Dynamic subtest pipe-C-DP-3: SUCCESS (2.280s)
[302.271808] Starting dynamic subtest: pipe-D-DP-3
[304.545808] Dynamic subtest pipe-D-DP-3: SUCCESS (2.285s)
[304.546757] Subtest queue-idle: SUCCESS (9.205s)
[304.835764] [057/131] (744s left) gem_create (create-massive)
[304.913929] Starting subtest: create-massive
[304.914855] Subtest create-massive: SUCCESS (0.000s)
[305.220234] [058/131] (744s left) sw_sync (sync_busy_fork)
[305.266066] Starting subtest: sync_busy_fork
[306.278801] Subtest sync_busy_fork: SUCCESS (1.012s)
[306.383178] [059/131] (743s left) i915_pm_freq_api (freq-reset)
[306.466764] Starting subtest: freq-reset
[306.467719] Starting dynamic subtest: gt0
[306.864867] Dynamic subtest gt0: SUCCESS (0.397s)
[306.865693] Subtest freq-reset: SUCCESS (0.398s)
[307.044626] [060/131] (742s left) gem_exec_flush (basic-wb-prw-default)
[307.127487] Subtest basic-wb-prw-default: SKIP (0.000s)
[307.220163] [061/131] (742s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-render)
[307.412536] Starting subtest: psr-1p-primscrn-cur-indfb-draw-render
[307.414172] Subtest psr-1p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[307.619187] [062/131] (742s left) gem_exec_fence (basic-await)
[307.828074] Starting subtest: basic-await
[307.829296] Starting dynamic subtest: rcs0
[307.956090] Dynamic subtest rcs0: SUCCESS (0.129s)
[307.970983] Starting dynamic subtest: bcs0
[308.041954] Dynamic subtest bcs0: SUCCESS (0.085s)
[308.054391] Starting dynamic subtest: vcs0
[308.126263] Dynamic subtest vcs0: SUCCESS (0.083s)
[308.140161] Starting dynamic subtest: vcs1
[308.214290] Dynamic subtest vcs1: SUCCESS (0.087s)
[308.227694] Starting dynamic subtest: vecs0
[308.301044] Dynamic subtest vecs0: SUCCESS (0.086s)
[308.314097] Starting dynamic subtest: vecs1
[308.386936] Dynamic subtest vecs1: SUCCESS (0.085s)
[308.404800] Starting dynamic subtest: ccs0
[308.478844] Dynamic subtest ccs0: SUCCESS (0.091s)
[308.480064] Subtest basic-await: SUCCESS (0.653s)
[309.272943] [063/131] (740s left) kms_flip (busy-flip)
[309.457570] Starting subtest: busy-flip
[309.462118] Starting dynamic subtest: A-DP3
[310.293529] Dynamic subtest A-DP3: SUCCESS (0.830s)
[310.342486] Starting dynamic subtest: B-DP3
[311.091588] Dynamic subtest B-DP3: SUCCESS (0.792s)
[311.105511] Starting dynamic subtest: C-DP3
[311.859476] Dynamic subtest C-DP3: SUCCESS (0.766s)
[311.875620] Starting dynamic subtest: D-DP3
[312.627979] Dynamic subtest D-DP3: SUCCESS (0.764s)
[312.630243] Subtest busy-flip: SUCCESS (3.172s)
[313.015866] [064/131] (737s left) syncobj_timeline (multi-wait-all-for-submit-available-submitted)
[313.098889] Starting subtest: multi-wait-all-for-submit-available-submitted
[313.099990] Subtest multi-wait-all-for-submit-available-submitted: SUCCESS (0.000s)
[313.311982] [065/131] (736s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-submitted-signaled)
[313.400031] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted-signaled
[313.400944] Subtest invalid-multi-wait-all-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[313.598402] [066/131] (736s left) api_intel_bb (blit-noreloc-purge-cache)
[313.674102] Starting subtest: blit-noreloc-purge-cache
[313.709611] Subtest blit-noreloc-purge-cache: SUCCESS (0.037s)
[313.942461] [067/131] (736s left) gem_exec_whisper (basic-sync)
[314.029711] Starting subtest: basic-sync
[320.086720] Subtest basic-sync: SUCCESS (6.055s)
[320.342239] [068/131] (730s left) i915_query (query-topology-kernel-writes)
[320.417166] Starting subtest: query-topology-kernel-writes
[320.418059] Subtest query-topology-kernel-writes: SUCCESS (0.000s)
[320.611363] [069/131] (729s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-indfb-pgflip-blt)
[320.803627] Starting subtest: fbc-2p-scndscrn-indfb-pgflip-blt
[320.805327] Subtest fbc-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[320.987499] [070/131] (729s left) gem_fenced_exec_thrash (no-spare-fences-interruptible)
[321.068177] Subtest no-spare-fences-interruptible: SKIP (0.000s)
[321.138844] [071/131] (729s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-gtt)
[321.327631] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-gtt
[321.329322] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[321.532449] [072/131] (729s left) gem_exec_schedule (u-submit-late-slice)
[321.628403] Starting subtest: u-submit-late-slice
[321.629255] Starting dynamic subtest: rcs0
[321.708255] Dynamic subtest rcs0: SUCCESS (0.079s)
[321.722039] Starting dynamic subtest: bcs0
[321.759548] Dynamic subtest bcs0: SUCCESS (0.050s)
[321.771720] Starting dynamic subtest: vcs0
[321.812407] Dynamic subtest vcs0: SUCCESS (0.052s)
[321.824930] Starting dynamic subtest: vcs1
[321.863549] Dynamic subtest vcs1: SUCCESS (0.050s)
[321.876905] Starting dynamic subtest: vecs0
[321.915032] Dynamic subtest vecs0: SUCCESS (0.050s)
[321.928373] Starting dynamic subtest: vecs1
[321.963503] Dynamic subtest vecs1: SUCCESS (0.047s)
[321.977062] Starting dynamic subtest: ccs0
[322.015951] Dynamic subtest ccs0: SUCCESS (0.051s)
[322.016874] Subtest u-submit-late-slice: SUCCESS (0.388s)
[322.532128] [073/131] (728s left) gem_eio (in-flight-internal-10ms)
[322.827037] Starting subtest: in-flight-internal-10ms
[324.582875] Subtest in-flight-internal-10ms: SUCCESS (1.756s)
[324.883047] [074/131] (725s left) sw_sync (sync_multi_consumer)
[324.929122] Starting subtest: sync_multi_consumer
[325.249897] Subtest sync_multi_consumer: SUCCESS (0.321s)
[325.376979] [075/131] (725s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-render)
[325.568754] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-render
[325.570475] Subtest fbc-2p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[325.781754] [076/131] (725s left) gem_exec_schedule (pi-distinct-iova)
[325.997054] Starting subtest: pi-distinct-iova
[325.998418] Starting dynamic subtest: rcs0
[326.255154] Dynamic subtest rcs0: SUCCESS (0.259s)
[326.269126] Starting dynamic subtest: bcs0
[326.433387] Dynamic subtest bcs0: SUCCESS (0.177s)
[326.445682] Starting dynamic subtest: vcs0
[326.623006] Dynamic subtest vcs0: SUCCESS (0.189s)
[326.635164] Starting dynamic subtest: vcs1
[326.812135] Dynamic subtest vcs1: SUCCESS (0.188s)
[326.824481] Starting dynamic subtest: vecs0
[327.000684] Dynamic subtest vecs0: SUCCESS (0.187s)
[327.012741] Starting dynamic subtest: vecs1
[327.192439] Dynamic subtest vecs1: SUCCESS (0.191s)
[327.204767] Starting dynamic subtest: ccs0
[327.377475] Dynamic subtest ccs0: SUCCESS (0.184s)
[327.378380] Subtest pi-distinct-iova: SUCCESS (1.383s)
[327.811838] [077/131] (723s left) kms_big_fb (x-tiled-16bpp-rotate-0)
[328.015088] Starting subtest: x-tiled-16bpp-rotate-0
[329.828153] Subtest x-tiled-16bpp-rotate-0: SUCCESS (1.812s)
[330.061121] [078/131] (721s left) kms_chamelium_hpd (vga-hpd-with-enabled-mode)
[330.318720] Subtest vga-hpd-with-enabled-mode: SKIP (0.000s)
[330.466059] [079/131] (720s left) gem_spin_batch (spin-all-new)
[330.554015] Starting subtest: spin-all-new
[330.611582] Subtest spin-all-new: SUCCESS (0.058s)
[330.923095] [080/131] (720s left) kms_selftest (drm_cmdline_parser)
[330.971051] Starting subtest: drm_cmdline_parser
[331.005672] Starting dynamic subtest: drm_test_cmdline_force_d_only
[331.085018] Dynamic subtest drm_test_cmdline_force_d_only: SUCCESS (0.080s)
[331.119834] Starting dynamic subtest: drm_test_cmdline_force_D_only_dvi
[331.156622] Dynamic subtest drm_test_cmdline_force_D_only_dvi: SUCCESS (0.070s)
[331.186635] Starting dynamic subtest: drm_test_cmdline_force_D_only_hdmi
[331.230540] Dynamic subtest drm_test_cmdline_force_D_only_hdmi: SUCCESS (0.070s)
[331.266918] Starting dynamic subtest: drm_test_cmdline_force_D_only_not_digital
[331.325915] Dynamic subtest drm_test_cmdline_force_D_only_not_digital: SUCCESS (0.092s)
[331.369074] Starting dynamic subtest: drm_test_cmdline_force_e_only
[331.406320] Dynamic subtest drm_test_cmdline_force_e_only: SUCCESS (0.075s)
[331.425637] Starting dynamic subtest: drm_test_cmdline_res
[331.495004] Dynamic subtest drm_test_cmdline_res: SUCCESS (0.086s)
[331.543214] Starting dynamic subtest: drm_test_cmdline_res_vesa
[331.597118] Dynamic subtest drm_test_cmdline_res_vesa: SUCCESS (0.097s)
[331.609586] Starting dynamic subtest: drm_test_cmdline_res_vesa_rblank
[331.676004] Dynamic subtest drm_test_cmdline_res_vesa_rblank: SUCCESS (0.078s)
[331.688513] Starting dynamic subtest: drm_test_cmdline_res_rblank
[331.759674] Dynamic subtest drm_test_cmdline_res_rblank: SUCCESS (0.080s)
[331.808711] Starting dynamic subtest: drm_test_cmdline_res_bpp
[331.846586] Dynamic subtest drm_test_cmdline_res_bpp: SUCCESS (0.083s)
[331.872761] Starting dynamic subtest: drm_test_cmdline_res_refresh
[331.940955] Dynamic subtest drm_test_cmdline_res_refresh: SUCCESS (0.092s)
[331.991008] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh
[332.018429] Dynamic subtest drm_test_cmdline_res_bpp_refresh: SUCCESS (0.073s)
[332.036803] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_interlaced
[332.087052] Dynamic subtest drm_test_cmdline_res_bpp_refresh_interlaced: SUCCESS (0.068s)
[332.099846] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_margins
[332.154254] Dynamic subtest drm_test_cmdline_res_bpp_refresh_margins: SUCCESS (0.065s)
[332.196327] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_force_off
[332.227530] Dynamic subtest drm_test_cmdline_res_bpp_refresh_force_off: SUCCESS (0.071s)
[332.248573] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_force_on
[332.306040] Dynamic subtest drm_test_cmdline_res_bpp_refresh_force_on: SUCCESS (0.077s)
[332.319732] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_force_on_analog
[332.389149] Dynamic subtest drm_test_cmdline_res_bpp_refresh_force_on_analog: SUCCESS (0.080s)
[332.436923] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_force_on_digital
[332.471367] Dynamic subtest drm_test_cmdline_res_bpp_refresh_force_on_digital: SUCCESS (0.078s)
[332.491706] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_interlaced_margins_force_on
[332.554890] Dynamic subtest drm_test_cmdline_res_bpp_refresh_interlaced_margins_force_on: SUCCESS (0.082s)
[332.605626] Starting dynamic subtest: drm_test_cmdline_res_margins_force_on
[332.635254] Dynamic subtest drm_test_cmdline_res_margins_force_on: SUCCESS (0.075s)
[332.653705] Starting dynamic subtest: drm_test_cmdline_res_vesa_margins
[332.730033] Dynamic subtest drm_test_cmdline_res_vesa_margins: SUCCESS (0.090s)
[332.772708] Starting dynamic subtest: drm_test_cmdline_name
[332.816507] Dynamic subtest drm_test_cmdline_name: SUCCESS (0.085s)
[332.839275] Starting dynamic subtest: drm_test_cmdline_name_bpp
[332.898084] Dynamic subtest drm_test_cmdline_name_bpp: SUCCESS (0.080s)
[332.911362] Starting dynamic subtest: drm_test_cmdline_name_option
[332.977053] Dynamic subtest drm_test_cmdline_name_option: SUCCESS (0.078s)
[332.991347] Starting dynamic subtest: drm_test_cmdline_name_bpp_option
[333.068846] Dynamic subtest drm_test_cmdline_name_bpp_option: SUCCESS (0.089s)
[333.084525] Starting dynamic subtest: drm_test_cmdline_rotate_0
[333.153573] Dynamic subtest drm_test_cmdline_rotate_0: SUCCESS (0.080s)
[333.195600] Starting dynamic subtest: drm_test_cmdline_rotate_90
[333.238713] Dynamic subtest drm_test_cmdline_rotate_90: SUCCESS (0.082s)
[333.267935] Starting dynamic subtest: drm_test_cmdline_rotate_180
[333.318618] Dynamic subtest drm_test_cmdline_rotate_180: SUCCESS (0.076s)
[333.362075] Starting dynamic subtest: drm_test_cmdline_rotate_270
[333.410846] Dynamic subtest drm_test_cmdline_rotate_270: SUCCESS (0.089s)
[333.460105] Starting dynamic subtest: drm_test_cmdline_hmirror
[333.493253] Dynamic subtest drm_test_cmdline_hmirror: SUCCESS (0.080s)
[333.511394] Starting dynamic subtest: drm_test_cmdline_vmirror
[333.573199] Dynamic subtest drm_test_cmdline_vmirror: SUCCESS (0.078s)
[333.587056] Starting dynamic subtest: drm_test_cmdline_margin_options
[333.666973] Dynamic subtest drm_test_cmdline_margin_options: SUCCESS (0.091s)
[333.715102] Starting dynamic subtest: drm_test_cmdline_multiple_options
[333.755883] Dynamic subtest drm_test_cmdline_multiple_options: SUCCESS (0.084s)
[333.783220] Starting dynamic subtest: drm_test_cmdline_bpp_extra_and_option
[333.848858] Dynamic subtest drm_test_cmdline_bpp_extra_and_option: SUCCESS (0.091s)
[333.864328] Starting dynamic subtest: drm_test_cmdline_extra_and_option
[333.935538] Dynamic subtest drm_test_cmdline_extra_and_option: SUCCESS (0.081s)
[333.977815] Starting dynamic subtest: drm_test_cmdline_freestanding_options
[334.026587] Dynamic subtest drm_test_cmdline_freestanding_options: SUCCESS (0.087s)
[334.041795] Starting dynamic subtest: drm_test_cmdline_freestanding_force_e_and_options
[334.112448] Dynamic subtest drm_test_cmdline_freestanding_force_e_and_options: SUCCESS (0.082s)
[334.155265] Starting dynamic subtest: drm_test_cmdline_panel_orientation
[334.194825] Dynamic subtest drm_test_cmdline_panel_orientation: SUCCESS (0.079s)
[334.227099] Starting dynamic subtest: drm_test_cmdline_invalid
[334.328289] Dynamic subtest drm_test_cmdline_invalid: SUCCESS (0.129s)
[334.368307] Starting dynamic subtest: drm_test_cmdline_tv_options
[334.409890] Dynamic subtest drm_test_cmdline_tv_options: SUCCESS (0.079s)
[334.410818] Subtest drm_cmdline_parser: SUCCESS (3.441s)
[334.678343] [081/131] (716s left) kms_atomic_transition (plane-all-transition)
[334.861479] Starting subtest: plane-all-transition
[334.863899] Starting dynamic subtest: pipe-A-DP-3
[338.608034] Dynamic subtest pipe-A-DP-3: SUCCESS (3.744s)
[338.728278] Starting dynamic subtest: pipe-B-DP-3
[342.586992] Dynamic subtest pipe-B-DP-3: SUCCESS (3.871s)
[342.675633] Subtest plane-all-transition: SUCCESS (7.812s)
[342.947765] [082/131] (708s left) fbdev (unaligned-read)
[343.008588] Starting subtest: unaligned-read
[343.020803] Subtest unaligned-read: SUCCESS (0.012s)
[343.148655] [083/131] (708s left) kms_pm_rpm (dpms-mode-unset-lpsp)
[344.756335] Starting subtest: dpms-mode-unset-lpsp
[345.036158] Subtest dpms-mode-unset-lpsp: SKIP (0.290s)
[345.514283] [084/131] (706s left) i915_drm_fdinfo (virtual-busy)
[345.583191] Starting subtest: virtual-busy
[345.584989] Subtest virtual-busy: SKIP (0.000s)
[345.746875] [085/131] (705s left) gem_mmap_offset (clear)
[345.823942] Starting subtest: clear
[345.824918] Starting dynamic subtest: lmem0
[371.515113] Dynamic subtest lmem0: SUCCESS (25.686s)
[371.527207] Starting dynamic subtest: smem0
[396.901681] Dynamic subtest smem0: SUCCESS (25.383s)
[396.902903] Subtest clear: SUCCESS (51.072s)
[397.134210] [086/131] (654s left) kms_async_flips (test-time-stamp-atomic)
[397.365913] Starting subtest: test-time-stamp-atomic
[397.600339] Starting dynamic subtest: pipe-A-DP-3
[397.909546] Dynamic subtest pipe-A-DP-3: SUCCESS (0.308s)
[398.157694] Starting dynamic subtest: pipe-B-DP-3
[398.284299] Dynamic subtest pipe-B-DP-3: SUCCESS (0.138s)
[398.497540] Starting dynamic subtest: pipe-C-DP-3
[398.623801] Dynamic subtest pipe-C-DP-3: SUCCESS (0.137s)
[398.850495] Starting dynamic subtest: pipe-D-DP-3
[398.978374] Dynamic subtest pipe-D-DP-3: SUCCESS (0.138s)
[398.979895] Subtest test-time-stamp-atomic: SUCCESS (1.613s)
[399.559675] [087/131] (652s left) kms_cursor_legacy (cursorb-vs-flipa-atomic-transitions-varying-size)
[399.743968] Starting subtest: cursorB-vs-flipA-atomic-transitions-varying-size
[399.747013] Subtest cursorB-vs-flipA-atomic-transitions-varying-size: SKIP (0.002s)
[399.907225] [088/131] (652s left) i915_pm_rps (thresholds-idle-park)
[399.995904] Starting subtest: thresholds-idle-park
[399.997802] Subtest thresholds-idle-park: SKIP (0.000s)
[400.164859] [089/131] (651s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-wc)
[400.352713] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-wc
[400.354232] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[400.580239] [090/131] (651s left) kms_lease (lease-invalid-connector)
[400.767514] Starting subtest: lease-invalid-connector
[400.769762] Starting dynamic subtest: pipe-A-DP-3
[400.770628] Dynamic subtest pipe-A-DP-3: SUCCESS (0.000s)
[400.783280] Starting dynamic subtest: pipe-B-DP-3
[400.784380] Dynamic subtest pipe-B-DP-3: SUCCESS (0.000s)
[400.797213] Starting dynamic subtest: pipe-C-DP-3
[400.798395] Dynamic subtest pipe-C-DP-3: SUCCESS (0.000s)
[400.811150] Starting dynamic subtest: pipe-D-DP-3
[400.812227] Dynamic subtest pipe-D-DP-3: SUCCESS (0.000s)
[400.813228] Subtest lease-invalid-connector: SUCCESS (0.007s)
[400.994568] [091/131] (651s left) gem_pxp (create-regular-context-1)
[403.601559] Starting subtest: create-regular-context-1
[403.603214] Subtest create-regular-context-1: SKIP (0.000s)
[403.775904] [092/131] (648s left) gem_pxp (reject-modify-context-protection-on)
[406.380311] Starting subtest: reject-modify-context-protection-on
[406.382249] Subtest reject-modify-context-protection-on: SKIP (0.000s)
[406.524027] [093/131] (645s left) kms_plane_scaling (planes-unity-scaling-downscale-factor-0-5)
[406.708859] Starting subtest: planes-unity-scaling-downscale-factor-0-5
[406.709604] Starting dynamic subtest: pipe-A
[406.789652] Dynamic subtest pipe-A: SUCCESS (0.081s)
[406.803601] Starting dynamic subtest: pipe-B
[406.984656] Dynamic subtest pipe-B: SUCCESS (0.194s)
[407.007713] Starting dynamic subtest: pipe-C
[407.197879] Dynamic subtest pipe-C: SUCCESS (0.211s)
[407.220857] Starting dynamic subtest: pipe-D
[407.409660] Dynamic subtest pipe-D: SUCCESS (0.210s)
[407.410765] Subtest planes-unity-scaling-downscale-factor-0-5: SUCCESS (0.703s)
[407.777920] [094/131] (644s left) kms_flip (blocking-absolute-wf_vblank)
[407.961694] Starting subtest: blocking-absolute-wf_vblank
[407.966356] Starting dynamic subtest: A-DP3
[416.005322] Dynamic subtest A-DP3: SUCCESS (8.039s)
[416.020894] Starting dynamic subtest: B-DP3
[423.933972] Dynamic subtest B-DP3: SUCCESS (7.928s)
[423.948698] Starting dynamic subtest: C-DP3
[431.867150] Dynamic subtest C-DP3: SUCCESS (7.932s)
[431.882177] Starting dynamic subtest: D-DP3
[439.632888] Dynamic subtest D-DP3: SUCCESS (7.765s)
[439.635096] Subtest blocking-absolute-wf_vblank: SUCCESS (31.676s)
[440.002435] [095/131] (612s left) api_intel_bb (misplaced-blitter)
[440.080292] Starting subtest: misplaced-blitter
[440.112904] Subtest misplaced-blitter: SUCCESS (0.034s)
[440.352017] [096/131] (612s left) prime_vgem (basic-gtt)
[440.453210] Starting subtest: basic-gtt
[440.455493] Subtest basic-gtt: SKIP (0.000s)
[440.667544] [097/131] (612s left) gem_render_copy (y-tiled-ccs-to-y-tiled-ccs)
[440.755124] Starting subtest: y-tiled-ccs-to-y-tiled-ccs
[440.757051] Subtest y-tiled-ccs-to-y-tiled-ccs: SKIP (0.000s)
[441.048492] [098/131] (611s left) syncobj_eventfd (invalid-bad-flags)
[441.137188] Starting subtest: invalid-bad-flags
[441.138253] Subtest invalid-bad-flags: SUCCESS (0.000s)
[441.317865] [099/131] (611s left) kms_cursor_legacy (short-flip-before-cursor-atomic-transitions-varying-size)
[441.509054] Starting subtest: short-flip-before-cursor-atomic-transitions-varying-size
[442.571571] Subtest short-flip-before-cursor-atomic-transitions-varying-size: SUCCESS (1.062s)
[442.876011] [100/131] (610s left) kms_legacy_colorkey (basic)
[443.058413] Starting subtest: basic
[443.059271] Starting dynamic subtest: pipe-A
[443.119306] Dynamic subtest pipe-A: SUCCESS (0.060s)
[443.142152] Starting dynamic subtest: pipe-B
[443.143389] Dynamic subtest pipe-B: SUCCESS (0.001s)
[443.162831] Starting dynamic subtest: pipe-C
[443.164069] Dynamic subtest pipe-C: SUCCESS (0.001s)
[443.177491] Starting dynamic subtest: pipe-D
[443.178656] Dynamic subtest pipe-D: SUCCESS (0.001s)
[443.179865] Subtest basic: SUCCESS (0.068s)
[443.253395] [101/131] (609s left) kms_addfb_basic (basic-x-tiled-legacy)
[443.333352] Starting subtest: basic-x-tiled-legacy
[443.335044] Subtest basic-x-tiled-legacy: SKIP (0.000s)
[443.657602] [102/131] (609s left) gem_reg_read (timestamp-monotonic)
[443.734123] Starting subtest: timestamp-monotonic
[448.439907] Subtest timestamp-monotonic: SUCCESS (4.707s)
[448.577180] [103/131] (604s left) dmabuf (all-tests)
[448.628351] Starting subtest: all-tests
[448.629197] Starting dynamic subtest: sanitycheck
[448.732225] Dynamic subtest sanitycheck: SUCCESS (0.103s)
[448.749008] Starting dynamic subtest: dma_fence
[448.964463] Dynamic subtest dma_fence: SUCCESS (0.229s)
[449.007969] Starting dynamic subtest: dma_fence_chain
[458.134157] Dynamic subtest dma_fence_chain: SUCCESS (9.166s)
[458.154811] Starting dynamic subtest: dma_fence_unwrap
[458.217516] Dynamic subtest dma_fence_unwrap: SUCCESS (0.080s)
[458.261865] Starting dynamic subtest: dma_resv
[458.330117] Dynamic subtest dma_resv: SUCCESS (0.109s)
[458.335084] Subtest all-tests: SUCCESS (9.703s)
[458.498346] [104/131] (594s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-render)
[458.697778] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-render
[458.699485] Subtest psr-1p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[458.881664] [105/131] (594s left) gem_exec_schedule (smoketest)
[459.086009] Starting subtest: smoketest
[459.086947] Starting dynamic subtest: rcs0
[464.554376] Dynamic subtest rcs0: SUCCESS (5.462s)
[464.568673] Starting dynamic subtest: bcs0
[470.006868] Dynamic subtest bcs0: SUCCESS (5.451s)
[470.020486] Starting dynamic subtest: vcs0
[475.464398] Dynamic subtest vcs0: SUCCESS (5.455s)
[475.478084] Starting dynamic subtest: vcs1
[480.926101] Dynamic subtest vcs1: SUCCESS (5.461s)
[480.940491] Starting dynamic subtest: vecs0
[486.389535] Dynamic subtest vecs0: SUCCESS (5.462s)
[486.404114] Starting dynamic subtest: vecs1
[491.853307] Dynamic subtest vecs1: SUCCESS (5.462s)
[491.866773] Starting dynamic subtest: ccs0
[497.313522] Dynamic subtest ccs0: SUCCESS (5.459s)
[497.314635] Subtest smoketest: SUCCESS (38.221s)
[497.654019] [106/131] (555s left) syncobj_wait (wait-any-complex)
[497.730897] Starting subtest: wait-any-complex
[497.793479] Subtest wait-any-complex: SUCCESS (0.062s)
[497.966722] [107/131] (555s left) kms_psr2_sf (pr-overlay-plane-move-continuous-sf)
[498.155761] Subtest pr-overlay-plane-move-continuous-sf: SKIP (0.000s)
[498.218361] [108/131] (555s left) kms_big_fb (y-tiled-16bpp-rotate-180)
[498.417976] Starting subtest: y-tiled-16bpp-rotate-180
[498.419804] Subtest y-tiled-16bpp-rotate-180: SKIP (0.000s)
[498.624518] [109/131] (555s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-mmap-cpu)
[498.815574] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-mmap-cpu
[498.817247] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[499.005749] [110/131] (554s left) kms_ccs (ccs-on-another-bo-yf-tiled-ccs)
[499.191069] Starting subtest: ccs-on-another-bo-yf-tiled-ccs
[499.191935] Starting dynamic subtest: pipe-A-DP-3
[499.200089] Dynamic subtest pipe-A-DP-3: SKIP (0.000s)
[499.213014] Starting dynamic subtest: pipe-B-DP-3
[499.221512] Dynamic subtest pipe-B-DP-3: SKIP (0.000s)
[499.233913] Starting dynamic subtest: pipe-C-DP-3
[499.241945] Dynamic subtest pipe-C-DP-3: SKIP (0.000s)
[499.254225] Starting dynamic subtest: pipe-D-DP-3
[499.263970] Dynamic subtest pipe-D-DP-3: SKIP (0.000s)
[499.265814] Subtest ccs-on-another-bo-yf-tiled-ccs: SKIP (0.007s)
[499.659301] [111/131] (554s left) gem_exec_reloc (basic-write-read-noreloc)
[499.742373] Subtest basic-write-read-noreloc: SKIP (0.000s)
[499.813086] [112/131] (554s left) syncobj_wait (reset-unsignaled)
[499.888058] Starting subtest: reset-unsignaled
[499.888971] Subtest reset-unsignaled: SUCCESS (0.000s)
[500.084748] [113/131] (553s left) kms_rotation_crc (primary-rotation-90)
[500.267395] Starting subtest: primary-rotation-90
[500.270685] Subtest primary-rotation-90: SKIP (0.001s)
[500.434397] [114/131] (553s left) kms_content_protection (atomic-dpms)
[500.679269] Starting subtest: atomic-dpms
[500.714866] Starting dynamic subtest: pipe-A-DP-3
[623.797547] Dynamic subtest pipe-A-DP-3: FAIL (122.924s)
[623.798837] Subtest atomic-dpms: FAIL (123.061s)
[624.062684] [115/131] (430s left) kms_frontbuffer_tracking (fbcpsr-tiling-y)
[624.248204] Starting subtest: fbcpsr-tiling-y
[624.249873] Subtest fbcpsr-tiling-y: SKIP (0.000s)
[624.444463] [116/131] (429s left) gem_ctx_shared (single-timeline)
[624.532017] Starting subtest: single-timeline
[624.573090] Subtest single-timeline: SUCCESS (0.040s)
[624.932045] [117/131] (429s left) i915_pm_rpm (gem-execbuf)
[626.729228] Starting subtest: gem-execbuf
[626.730250] Starting dynamic subtest: lmem0
[628.141308] Dynamic subtest lmem0: SUCCESS (1.412s)
[628.175108] Starting dynamic subtest: smem0
[629.438799] Dynamic subtest smem0: SUCCESS (1.292s)
[629.445669] Subtest gem-execbuf: SUCCESS (2.714s)
[629.856314] [118/131] (424s left) gen9_exec_parse (bb-start-param)
[629.943608] Subtest bb-start-param: SKIP (0.000s)
[630.037581] [119/131] (424s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-mmap-wc)
[630.225782] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-mmap-wc
[630.227515] Subtest fbc-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[630.440081] [120/131] (424s left) kms_flip (basic-flip-vs-dpms)
[630.622703] Starting subtest: basic-flip-vs-dpms
[630.627351] Starting dynamic subtest: A-DP3
[631.608845] Dynamic subtest A-DP3: SUCCESS (0.980s)
[631.637860] Starting dynamic subtest: B-DP3
[632.376849] Dynamic subtest B-DP3: SUCCESS (0.766s)
[632.397285] Starting dynamic subtest: C-DP3
[633.117939] Dynamic subtest C-DP3: SUCCESS (0.738s)
[633.148066] Starting dynamic subtest: D-DP3
[633.888124] Dynamic subtest D-DP3: SUCCESS (0.768s)
[633.891296] Subtest basic-flip-vs-dpms: SUCCESS (3.269s)
[634.229082] [121/131] (420s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-onoff)
[634.425715] Starting subtest: psr-2p-scndscrn-cur-indfb-onoff
[634.427428] Subtest psr-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[634.634565] [122/131] (420s left) syncobj_timeline (wait-all-delayed-signal)
[634.710999] Starting subtest: wait-all-delayed-signal
[634.812709] Subtest wait-all-delayed-signal: SUCCESS (0.101s)
[634.948262] [123/131] (419s left) kms_psr (psr-primary-blt)
[635.140172] Subtest psr-primary-blt: SKIP (0.000s)
[635.201778] [124/131] (419s left) gem_exec_reloc (basic-write-gtt-noreloc)
[635.289904] Subtest basic-write-gtt-noreloc: SKIP (0.000s)
[635.403963] [125/131] (419s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc)
[635.587921] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc
[635.588817] Starting dynamic subtest: pipe-A-DP-3
[635.609509] Dynamic subtest pipe-A-DP-3: SKIP (0.002s)
[635.622774] Starting dynamic subtest: pipe-B-DP-3
[635.643602] Dynamic subtest pipe-B-DP-3: SKIP (0.002s)
[635.656787] Starting dynamic subtest: pipe-C-DP-3
[635.681633] Dynamic subtest pipe-C-DP-3: SKIP (0.002s)
[635.694544] Starting dynamic subtest: pipe-D-DP-3
[635.716967] Dynamic subtest pipe-D-DP-3: SKIP (0.002s)
[635.719081] Subtest crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc: SKIP (0.014s)
[636.068242] [126/131] (419s left) perf_pmu (event-wait)
[636.137979] Starting subtest: event-wait
[636.138891] Starting dynamic subtest: rcs0
[636.140597] Dynamic subtest rcs0: SKIP (0.000s)
[636.142167] Subtest event-wait: SKIP (0.001s)
[636.334210] [127/131] (418s left) kms_flip (flip-vs-dpms-on-nop-interruptible)
[636.523837] Starting subtest: flip-vs-dpms-on-nop-interruptible
[636.528823] Starting dynamic subtest: A-DP3
[637.623954] Dynamic subtest A-DP3: SUCCESS (1.095s)
[637.641205] Starting dynamic subtest: B-DP3
[638.653713] Dynamic subtest B-DP3: SUCCESS (1.027s)
[638.700192] Starting dynamic subtest: C-DP3
[639.689784] Dynamic subtest C-DP3: SUCCESS (1.029s)
[639.713403] Starting dynamic subtest: D-DP3
[640.707289] Dynamic subtest D-DP3: SUCCESS (1.012s)
[640.709758] Subtest flip-vs-dpms-on-nop-interruptible: SUCCESS (4.185s)
[641.098389] [128/131] (414s left) kms_frontbuffer_tracking (psr-rgb565-draw-render)
[641.287790] Starting subtest: psr-rgb565-draw-render
[641.289519] Subtest psr-rgb565-draw-render: SKIP (0.000s)
[641.491524] [129/131] (413s left) gem_userptr_blits (map-fixed-invalidate)
[641.581424] Starting subtest: map-fixed-invalidate
[641.583127] Subtest map-fixed-invalidate: SKIP (0.000s)
[641.776845] [130/131] (413s left) kms_flip (nonblocking-read)
[641.955922] Starting subtest: nonblocking-read
[641.956809] Subtest nonblocking-read: SUCCESS (0.000s)
[642.177097] [131/131] (413s left) gem_softpin (noreloc)
[642.263306] Starting subtest: noreloc
[647.701697] Subtest noreloc: SUCCESS (5.438s)
[647.888087] Closing watchdogs
results: parsing output: 0/ for test: gem_userptr_blits
results: parsing output: 1/ for test: gen7_exec_parse
results: parsing output: 2/ for test: kms_hdr
results: parsing output: 3/ for test: gem_caching
results: parsing output: 4/ for test: kms_big_fb
results: parsing output: 5/ for test: kms_ccs
results: parsing output: 6/ for test: syncobj_wait
results: parsing output: 7/ for test: gem_exec_reloc
results: parsing output: 8/ for test: kms_frontbuffer_tracking
results: parsing output: 9/ for test: kms_big_fb
results: parsing output: 10/ for test: sysfs_preempt_timeout
results: parsing output: 11/ for test: kms_frontbuffer_tracking
results: parsing output: 12/ for test: kms_busy
results: parsing output: 13/ for test: kms_plane_scaling
results: parsing output: 14/ for test: kms_psr2_sf
results: parsing output: 15/ for test: kms_flip_scaled_crc
results: parsing output: 16/ for test: kms_frontbuffer_tracking
results: parsing output: 17/ for test: gem_pwrite_snooped
results: parsing output: 18/ for test: gem_lmem_swapping
results: parsing output: 19/ for test: kms_draw_crc
results: parsing output: 20/ for test: kms_hdr
results: parsing output: 21/ for test: kms_vblank
results: parsing output: 22/ for test: kms_cursor_edge_walk
results: parsing output: 23/ for test: kms_chamelium_edid
results: parsing output: 24/ for test: gem_exec_balancer
results: parsing output: 25/ for test: kms_big_fb
results: parsing output: 26/ for test: prime_mmap
results: parsing output: 27/ for test: kms_dirtyfb
results: parsing output: 28/ for test: syncobj_timeline
results: parsing output: 29/ for test: kms_frontbuffer_tracking
results: parsing output: 30/ for test: kms_frontbuffer_tracking
results: parsing output: 31/ for test: kms_cursor_crc
results: parsing output: 32/ for test: kms_flip_scaled_crc
results: parsing output: 33/ for test: gem_exec_fence
results: parsing output: 34/ for test: kms_psr
results: parsing output: 35/ for test: kms_psr
results: parsing output: 36/ for test: kms_ccs
results: parsing output: 37/ for test: gem_exec_reloc
results: parsing output: 38/ for test: gem_ctx_param
results: parsing output: 39/ for test: kms_chamelium_color
results: parsing output: 40/ for test: gem_exec_schedule
results: parsing output: 41/ for test: kms_cursor_crc
results: parsing output: 42/ for test: perf
results: parsing output: 43/ for test: kms_pm_rpm
results: parsing output: 44/ for test: syncobj_timeline
results: parsing output: 45/ for test: kms_psr
results: parsing output: 46/ for test: gem_ctx_persistence
results: parsing output: 47/ for test: kms_atomic_transition
results: parsing output: 48/ for test: kms_psr
results: parsing output: 49/ for test: gem_render_copy
results: parsing output: 50/ for test: syncobj_wait
results: parsing output: 51/ for test: kms_plane_alpha_blend
results: parsing output: 52/ for test: kms_frontbuffer_tracking
results: parsing output: 53/ for test: kms_big_fb
results: parsing output: 54/ for test: kms_frontbuffer_tracking
results: parsing output: 55/ for test: kms_sequence
results: parsing output: 56/ for test: gem_create
results: parsing output: 57/ for test: sw_sync
results: parsing output: 58/ for test: i915_pm_freq_api
results: parsing output: 59/ for test: gem_exec_flush
results: parsing output: 60/ for test: kms_frontbuffer_tracking
results: parsing output: 61/ for test: gem_exec_fence
results: parsing output: 62/ for test: kms_flip
results: parsing output: 63/ for test: syncobj_timeline
results: parsing output: 64/ for test: syncobj_timeline
results: parsing output: 65/ for test: api_intel_bb
results: parsing output: 66/ for test: gem_exec_whisper
results: parsing output: 67/ for test: i915_query
results: parsing output: 68/ for test: kms_frontbuffer_tracking
results: parsing output: 69/ for test: gem_fenced_exec_thrash
results: parsing output: 70/ for test: kms_frontbuffer_tracking
results: parsing output: 71/ for test: gem_exec_schedule
results: parsing output: 72/ for test: gem_eio
results: parsing output: 73/ for test: sw_sync
results: parsing output: 74/ for test: kms_frontbuffer_tracking
results: parsing output: 75/ for test: gem_exec_schedule
results: parsing output: 76/ for test: kms_big_fb
results: parsing output: 77/ for test: kms_chamelium_hpd
results: parsing output: 78/ for test: gem_spin_batch
results: parsing output: 79/ for test: kms_selftest
results: parsing output: 80/ for test: kms_atomic_transition
results: parsing output: 81/ for test: fbdev
results: parsing output: 82/ for test: kms_pm_rpm
results: parsing output: 83/ for test: i915_drm_fdinfo
results: parsing output: 84/ for test: gem_mmap_offset
results: parsing output: 85/ for test: kms_async_flips
results: parsing output: 86/ for test: kms_cursor_legacy
results: parsing output: 87/ for test: i915_pm_rps
results: parsing output: 88/ for test: kms_frontbuffer_tracking
results: parsing output: 89/ for test: kms_lease
results: parsing output: 90/ for test: gem_pxp
results: parsing output: 91/ for test: gem_pxp
results: parsing output: 92/ for test: kms_plane_scaling
results: parsing output: 93/ for test: kms_flip
results: parsing output: 94/ for test: api_intel_bb
results: parsing output: 95/ for test: prime_vgem
results: parsing output: 96/ for test: gem_render_copy
results: parsing output: 97/ for test: syncobj_eventfd
results: parsing output: 98/ for test: kms_cursor_legacy
results: parsing output: 99/ for test: kms_legacy_colorkey
results: parsing output: 100/ for test: kms_addfb_basic
results: parsing output: 101/ for test: gem_reg_read
results: parsing output: 102/ for test: dmabuf
results: parsing output: 103/ for test: kms_frontbuffer_tracking
results: parsing output: 104/ for test: gem_exec_schedule
results: parsing output: 105/ for test: syncobj_wait
results: parsing output: 106/ for test: kms_psr2_sf
results: parsing output: 107/ for test: kms_big_fb
results: parsing output: 108/ for test: kms_frontbuffer_tracking
results: parsing output: 109/ for test: kms_ccs
results: parsing output: 110/ for test: gem_exec_reloc
results: parsing output: 111/ for test: syncobj_wait
results: parsing output: 112/ for test: kms_rotation_crc
results: parsing output: 113/ for test: kms_content_protection
results: parsing output: 114/ for test: kms_frontbuffer_tracking
results: parsing output: 115/ for test: gem_ctx_shared
results: parsing output: 116/ for test: i915_pm_rpm
results: parsing output: 117/ for test: gen9_exec_parse
results: parsing output: 118/ for test: kms_frontbuffer_tracking
results: parsing output: 119/ for test: kms_flip
results: parsing output: 120/ for test: kms_frontbuffer_tracking
results: parsing output: 121/ for test: syncobj_timeline
results: parsing output: 122/ for test: kms_psr
results: parsing output: 123/ for test: gem_exec_reloc
results: parsing output: 124/ for test: kms_ccs
results: parsing output: 125/ for test: perf_pmu
results: parsing output: 126/ for test: kms_flip
results: parsing output: 127/ for test: kms_frontbuffer_tracking
results: parsing output: 128/ for test: gem_userptr_blits
results: parsing output: 129/ for test: kms_flip
results: parsing output: 130/ for test: gem_softpin
Done.