[60.680327] Initializing watchdogs
[60.680371]   /dev/watchdog0
[60.694465] [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]
[60.698801] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[60.700929] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[60.702907] [001/132] (960s left) kms_cursor_edge_walk (128x128-left-edge)
[60.803136] Starting subtest: 128x128-left-edge
[60.804223] Starting dynamic subtest: pipe-A-eDP-1
[64.225202] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.435s)
[64.230939] Starting dynamic subtest: pipe-D-eDP-1
[67.341525] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.122s)
[67.342434] Subtest 128x128-left-edge: SUCCESS (6.559s)
[68.153515] [002/132] (953s left) kms_psr (psr2-cursor-mmap-gtt)
[68.252937] Starting subtest: psr2-cursor-mmap-gtt
[68.253998] Starting dynamic subtest: eDP-1
[69.907473] Dynamic subtest eDP-1: SUCCESS (1.656s)
[69.908500] Subtest psr2-cursor-mmap-gtt: SUCCESS (1.657s)
[70.715106] [003/132] (950s left) kms_cursor_legacy (single-move)
[70.825979] Starting subtest: single-move
[70.826919] Starting dynamic subtest: pipe-A
[76.202298] Dynamic subtest pipe-A: SUCCESS (5.379s)
[76.210948] Starting dynamic subtest: pipe-B
[81.584047] Dynamic subtest pipe-B: SUCCESS (5.380s)
[81.592328] Starting dynamic subtest: pipe-C
[86.964748] Dynamic subtest pipe-C: SUCCESS (5.380s)
[86.972591] Starting dynamic subtest: pipe-D
[92.347075] Dynamic subtest pipe-D: SUCCESS (5.380s)
[92.355889] Starting dynamic subtest: all-pipes
[97.734250] Dynamic subtest all-pipes: SUCCESS (5.386s)
[97.735443] Subtest single-move: SUCCESS (26.911s)
[97.893707] [004/132] (923s left) dumb_buffer (invalid-bpp)
[97.954283] Starting subtest: invalid-bpp
[97.955475] Subtest invalid-bpp: SUCCESS (0.000s)
[98.079374] [005/132] (923s left) kms_fbcon_fbt (fbc)
[98.132149] Starting subtest: fbc
[104.450256] Subtest fbc: SUCCESS (6.315s)
[105.611381] [006/132] (915s left) gen9_exec_parse (bb-start-far)
[105.687047] Subtest bb-start-far: SKIP (0.000s)
[105.724995] [007/132] (915s left) kms_vblank (ts-continuation-dpms-suspend)
[105.820291] Starting subtest: ts-continuation-dpms-suspend
[105.821522] Starting dynamic subtest: pipe-A-eDP-1
[123.355136] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.356s)
[123.365222] Starting dynamic subtest: pipe-D-eDP-1
[141.355246] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.863s)
[141.356407] Subtest ts-continuation-dpms-suspend: SUCCESS (5.222s)
[148.174434] [008/132] (879s left) gem_tiled_swapping (non-threaded)
[148.236161] Subtest non-threaded: SKIP (0.000s)
[148.298793] [009/132] (879s left) api_intel_bb (object-noreloc-keep-cache-simple)
[148.354599] Starting subtest: object-noreloc-keep-cache-simple
[148.362378] Subtest object-noreloc-keep-cache-simple: SUCCESS (0.008s)
[148.523442] [010/132] (878s left) kms_psr (fbc-pr-cursor-mmap-cpu)
[148.630349] Starting subtest: fbc-pr-cursor-mmap-cpu
[148.632089] Subtest fbc-pr-cursor-mmap-cpu: SKIP (0.000s)
[148.721873] [011/132] (878s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-move)
[148.827783] Starting subtest: psr-1p-primscrn-spr-indfb-move
[156.255964] Subtest psr-1p-primscrn-spr-indfb-move: SUCCESS (7.567s)
[156.430241] [012/132] (871s left) kms_frontbuffer_tracking (fbcpsr-tiling-4)
[156.524203] Starting subtest: fbcpsr-tiling-4
[163.503717] Subtest fbcpsr-tiling-4: SUCCESS (7.030s)
[163.643885] [013/132] (863s left) kms_invalid_mode (overflow-vrefresh)
[163.732135] Starting subtest: overflow-vrefresh
[163.733036] Starting dynamic subtest: pipe-A-eDP-1
[164.028395] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.296s)
[164.035760] Starting dynamic subtest: pipe-B-eDP-1
[164.036855] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[164.043185] Starting dynamic subtest: pipe-C-eDP-1
[164.044089] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[164.050437] Starting dynamic subtest: pipe-D-eDP-1
[164.051661] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[164.052623] Subtest overflow-vrefresh: SUCCESS (0.301s)
[164.864318] [014/132] (862s left) gem_mmap (basic-small-bo)
[164.922908] Subtest basic-small-bo: SKIP (0.000s)
[164.986316] [015/132] (862s left) syncobj_timeline (single-wait-available-signaled)
[165.046845] Starting subtest: single-wait-available-signaled
[165.047854] Subtest single-wait-available-signaled: SUCCESS (0.000s)
[165.174894] [016/132] (862s left) prime_mmap_kms (buffer-sharing)
[165.273936] Starting subtest: buffer-sharing
[165.718001] Subtest buffer-sharing: SUCCESS (0.447s)
[166.602009] [017/132] (861s left) kms_async_flips (async-flip-with-page-flip-events-tiled)
[166.700076] Starting subtest: async-flip-with-page-flip-events-tiled
[167.070317] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs
[169.851292] Dynamic subtest pipe-A-eDP-1-4-rc-ccs: SUCCESS (2.786s)
[169.859587] Starting dynamic subtest: pipe-A-eDP-1-4
[172.043602] Dynamic subtest pipe-A-eDP-1-4: SUCCESS (2.196s)
[172.053467] Starting dynamic subtest: pipe-A-eDP-1-4
[174.047241] Dynamic subtest pipe-A-eDP-1-4: SUCCESS (2.008s)
[174.054709] Starting dynamic subtest: pipe-A-eDP-1-x
[176.223352] Dynamic subtest pipe-A-eDP-1-x: SUCCESS (2.178s)
[176.599436] Starting dynamic subtest: pipe-B-eDP-1-4-rc-ccs
[179.366994] Dynamic subtest pipe-B-eDP-1-4-rc-ccs: SUCCESS (2.773s)
[179.375982] Starting dynamic subtest: pipe-B-eDP-1-4
[181.562991] Dynamic subtest pipe-B-eDP-1-4: SUCCESS (2.193s)
[181.573089] Starting dynamic subtest: pipe-B-eDP-1-4
[183.573668] Dynamic subtest pipe-B-eDP-1-4: SUCCESS (2.009s)
[183.583198] Starting dynamic subtest: pipe-B-eDP-1-x
[185.776461] Dynamic subtest pipe-B-eDP-1-x: SUCCESS (2.200s)
[186.137079] Starting dynamic subtest: pipe-C-eDP-1-4-rc-ccs
[188.893402] Dynamic subtest pipe-C-eDP-1-4-rc-ccs: SUCCESS (2.760s)
[188.902433] Starting dynamic subtest: pipe-C-eDP-1-4
[191.066732] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.175s)
[191.073889] Starting dynamic subtest: pipe-C-eDP-1-4
[193.079245] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.010s)
[193.089089] Starting dynamic subtest: pipe-C-eDP-1-x
[195.257368] Dynamic subtest pipe-C-eDP-1-x: SUCCESS (2.178s)
[195.625938] Starting dynamic subtest: pipe-D-eDP-1-4-rc-ccs
[198.418921] Dynamic subtest pipe-D-eDP-1-4-rc-ccs: SUCCESS (2.797s)
[198.426018] Starting dynamic subtest: pipe-D-eDP-1-4
[200.593867] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.175s)
[200.601051] Starting dynamic subtest: pipe-D-eDP-1-4
[202.603063] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.009s)
[202.611543] Starting dynamic subtest: pipe-D-eDP-1-x
[204.781356] Dynamic subtest pipe-D-eDP-1-x: SUCCESS (2.178s)
[204.782318] Subtest async-flip-with-page-flip-events-tiled: SUCCESS (38.099s)
[206.024325] [018/132] (821s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-blt)
[206.125994] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-blt
[206.127799] Subtest fbc-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[206.262898] [019/132] (821s left) gem_wait (write-busy)
[206.338953] Starting subtest: write-busy
[206.339955] Starting dynamic subtest: all-engines
[206.991814] Dynamic subtest all-engines: SUCCESS (0.654s)
[207.007239] Starting dynamic subtest: rcs0
[207.539995] Dynamic subtest rcs0: SUCCESS (0.547s)
[207.554123] Starting dynamic subtest: bcs0
[208.089207] Dynamic subtest bcs0: SUCCESS (0.549s)
[208.103301] Starting dynamic subtest: vcs0
[209.217848] Dynamic subtest vcs0: SUCCESS (1.125s)
[209.232071] Starting dynamic subtest: vcs1
[211.202418] Dynamic subtest vcs1: SUCCESS (1.983s)
[211.216295] Starting dynamic subtest: vecs0
[213.184789] Dynamic subtest vecs0: SUCCESS (1.981s)
[213.198370] Starting dynamic subtest: ccs0
[213.733489] Dynamic subtest ccs0: SUCCESS (0.550s)
[213.734728] Subtest write-busy: SUCCESS (7.398s)
[213.947177] [FACT gem_wait (write-busy)] new: kernel.kmod_is_loaded.vgem: true
[213.949257] [020/132] (813s left) gem_render_copy (y-tiled-ccs-to-y-tiled-mc-ccs)
[214.009062] Starting subtest: y-tiled-ccs-to-y-tiled-mc-ccs
[214.010719] Subtest y-tiled-ccs-to-y-tiled-mc-ccs: SKIP (0.000s)
[214.210611] [021/132] (813s left) kms_psr (psr-sprite-mmap-gtt)
[214.317620] Starting subtest: psr-sprite-mmap-gtt
[214.318624] Starting dynamic subtest: eDP-1
[215.730858] Dynamic subtest eDP-1: SKIP (1.410s)
[215.733180] Subtest psr-sprite-mmap-gtt: SKIP (1.411s)
[215.938278] [022/132] (811s left) kms_frontbuffer_tracking (fbcpsr-suspend)
[216.027796] Starting subtest: fbcpsr-suspend
[257.417309] Subtest fbcpsr-suspend: SUCCESS (10.797s)
[260.614520] [023/132] (770s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc)
[260.706993] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc
[267.205041] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc: SUCCESS (6.621s)
[267.349412] [024/132] (763s left) kms_async_flips (invalid-async-flip-atomic)
[267.444528] Starting subtest: invalid-async-flip-atomic
[267.824078] Starting dynamic subtest: pipe-A-eDP-1
[268.653823] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.839s)
[269.016071] Starting dynamic subtest: pipe-B-eDP-1
[269.791329] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.789s)
[270.154685] Starting dynamic subtest: pipe-C-eDP-1
[270.924268] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.782s)
[271.289206] Starting dynamic subtest: pipe-D-eDP-1
[272.053172] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.775s)
[272.054261] Subtest invalid-async-flip-atomic: SUCCESS (4.651s)
[273.205361] [025/132] (757s left) kms_ccs (bad-pixel-format-y-tiled-gen12-mc-ccs)
[273.294578] Starting subtest: bad-pixel-format-y-tiled-gen12-mc-ccs
[273.295380] Starting dynamic subtest: pipe-A-eDP-1
[273.303504] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[273.310642] Starting dynamic subtest: pipe-B-eDP-1
[273.318897] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[273.326541] Starting dynamic subtest: pipe-C-eDP-1
[273.334718] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[273.341933] Starting dynamic subtest: pipe-D-eDP-1
[273.351104] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[273.352811] Subtest bad-pixel-format-y-tiled-gen12-mc-ccs: SKIP (0.004s)
[275.479949] [026/132] (755s left) perf_pmu (idle)
[275.515404] Starting subtest: idle
[275.516686] Starting dynamic subtest: rcs0
[276.104937] Dynamic subtest rcs0: SUCCESS (0.591s)
[276.115328] Starting dynamic subtest: bcs0
[276.635702] Dynamic subtest bcs0: SUCCESS (0.528s)
[276.645013] Starting dynamic subtest: vcs0
[277.162435] Dynamic subtest vcs0: SUCCESS (0.528s)
[277.172866] Starting dynamic subtest: vcs1
[277.682361] Dynamic subtest vcs1: SUCCESS (0.519s)
[277.692607] Starting dynamic subtest: vecs0
[278.202412] Dynamic subtest vecs0: SUCCESS (0.517s)
[278.211548] Starting dynamic subtest: ccs0
[278.718407] Dynamic subtest ccs0: SUCCESS (0.517s)
[278.720199] Subtest idle: SUCCESS (3.215s)
[278.837314] [027/132] (752s left) syncobj_timeline (invalid-signal-one-illegal-handle)
[278.884696] Starting subtest: invalid-signal-one-illegal-handle
[278.885667] Subtest invalid-signal-one-illegal-handle: SUCCESS (0.000s)
[279.020704] [028/132] (752s left) kms_pm_dc (dc3co-vpb-simulation)
[280.120504] Starting subtest: dc3co-vpb-simulation
[289.105487] Subtest dc3co-vpb-simulation: SKIP (8.997s)
[289.275298] [029/132] (741s left) gen9_exec_parse (shadow-peek)
[289.336232] Subtest shadow-peek: SKIP (0.000s)
[289.415327] [030/132] (741s left) kms_psr2_sf (fbc-psr2-overlay-plane-move-continuous-exceed-fully-sf)
[294.401749] Starting subtest: fbc-psr2-overlay-plane-move-continuous-exceed-fully-sf
[294.403777] Starting dynamic subtest: pipe-A-eDP-1
[295.204434] Dynamic subtest pipe-A-eDP-1: SKIP (0.800s)
[295.213512] Starting dynamic subtest: pipe-B-eDP-1
[296.340312] Dynamic subtest pipe-B-eDP-1: SKIP (1.133s)
[296.342246] Subtest fbc-psr2-overlay-plane-move-continuous-exceed-fully-sf: SKIP (1.938s)
[297.653276] [031/132] (733s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-downscaling)
[297.743064] Starting subtest: flip-64bpp-linear-to-16bpp-linear-downscaling
[297.744071] Starting dynamic subtest: pipe-A-default-mode
[298.889990] Dynamic subtest pipe-A-default-mode: SKIP (1.143s)
[298.891951] Subtest flip-64bpp-linear-to-16bpp-linear-downscaling: SKIP (1.144s)
[299.085037] [032/132] (732s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-wc)
[299.176713] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-wc
[299.178420] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[299.289901] [033/132] (731s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-render)
[299.389407] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-render
[299.391175] Subtest fbc-2p-scndscrn-pri-shrfb-draw-render: SKIP (0.000s)
[299.500719] [034/132] (731s left) gem_exec_schedule (reorder-wide)
[299.671879] Subtest reorder-wide: SKIP (0.000s)
[299.872514] [035/132] (731s left) kms_prop_blob (blob-prop-lifetime)
[299.938977] Starting subtest: blob-prop-lifetime
[299.942644] Subtest blob-prop-lifetime: SUCCESS (0.003s)
[300.085639] [036/132] (731s left) perf_pmu (invalid-init)
[300.125945] Starting subtest: invalid-init
[300.127038] Subtest invalid-init: SUCCESS (0.000s)
[300.247553] [037/132] (731s left) kms_flip (basic-flip-vs-modeset)
[300.344649] Starting subtest: basic-flip-vs-modeset
[300.346833] Starting dynamic subtest: A-eDP1
[302.948981] Dynamic subtest A-eDP1: SUCCESS (2.602s)
[302.956925] Starting dynamic subtest: B-eDP1
[305.119394] Dynamic subtest B-eDP1: SUCCESS (2.169s)
[305.126784] Starting dynamic subtest: C-eDP1
[307.282236] Dynamic subtest C-eDP1: SUCCESS (2.162s)
[307.290531] Starting dynamic subtest: D-eDP1
[309.440950] Dynamic subtest D-eDP1: SUCCESS (2.158s)
[309.443520] Subtest basic-flip-vs-modeset: SUCCESS (9.097s)
[310.265675] [038/132] (721s left) i915_query (query-topology-unsupported)
[310.327855] Starting subtest: query-topology-unsupported
[310.329760] Subtest query-topology-unsupported: SKIP (0.000s)
[310.461203] [039/132] (720s left) kms_addfb_basic (small-bo)
[310.549751] Starting subtest: small-bo
[310.550668] Subtest small-bo: SUCCESS (0.000s)
[310.683608] [040/132] (720s left) sw_sync (alloc_fence)
[310.716547] Starting subtest: alloc_fence
[310.717547] Subtest alloc_fence: SUCCESS (0.000s)
[310.792692] [041/132] (720s left) kms_color_pipeline (plane-lut1d-pre-ctm3x4)
[310.882815] Starting subtest: plane-lut1d-pre-ctm3x4
[310.897949] Starting dynamic subtest: pipe-A-eDP-1
[311.349830] Dynamic subtest pipe-A-eDP-1: FAIL (0.303s)
[312.236795] Starting dynamic subtest: pipe-B-eDP-1
[312.713187] Dynamic subtest pipe-B-eDP-1: FAIL (0.291s)
[313.572862] Starting dynamic subtest: pipe-C-eDP-1
[314.030964] Dynamic subtest pipe-C-eDP-1: FAIL (0.247s)
[314.898355] Starting dynamic subtest: pipe-D-eDP-1
[315.359428] Dynamic subtest pipe-D-eDP-1: FAIL (0.235s)
[315.413705] Subtest plane-lut1d-pre-ctm3x4: FAIL (4.532s)
[316.295891] [042/132] (715s left) gem_lmem_swapping (parallel-random)
[316.332186] Subtest parallel-random: SKIP (0.000s)
[316.363858] [043/132] (715s left) kms_plane_alpha_blend (alpha-transparent-fb)
[316.465390] Starting subtest: alpha-transparent-fb
[316.751934] Starting dynamic subtest: pipe-A-eDP-1
[317.341001] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.587s)
[317.349775] Starting dynamic subtest: pipe-D-eDP-1
[318.713964] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.373s)
[318.715116] Subtest alpha-transparent-fb: SUCCESS (2.250s)
[319.557903] [044/132] (712s left) kms_big_fb (linear-addfb)
[319.659067] Starting subtest: linear-addfb
[319.660009] Subtest linear-addfb: SUCCESS (0.000s)
[319.795895] [045/132] (711s left) syncobj_timeline (invalid-wait-illegal-handle)
[319.855008] Starting subtest: invalid-wait-illegal-handle
[319.855971] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s)
[319.987418] [046/132] (711s left) gem_exec_params (sol-reset-invalid)
[320.038684] Starting subtest: sol-reset-invalid
[320.041398] Subtest sol-reset-invalid: SUCCESS (0.003s)
[321.908254] [047/132] (709s left) gem_flink_basic (bad-flink)
[321.972401] Starting subtest: bad-flink
[321.973349] Subtest bad-flink: SUCCESS (0.000s)
[322.097606] [048/132] (709s left) gem_exec_fence (syncobj-timeline-repeat)
[322.654277] Starting subtest: syncobj-timeline-repeat
[322.885449] Subtest syncobj-timeline-repeat: SUCCESS (0.231s)
[323.967024] [049/132] (707s left) syncobj_wait (signal)
[324.033885] Starting subtest: signal
[324.034838] Subtest signal: SUCCESS (0.000s)
[324.166926] [050/132] (707s left) gem_exec_schedule (implicit-write-read)
[324.234414] Starting subtest: implicit-write-read
[324.235441] Starting dynamic subtest: rcs0
[324.413251] Dynamic subtest rcs0: SUCCESS (0.178s)
[324.425266] Starting dynamic subtest: vcs0
[324.529011] Dynamic subtest vcs0: SUCCESS (0.116s)
[324.539236] Starting dynamic subtest: vcs1
[324.626673] Dynamic subtest vcs1: SUCCESS (0.096s)
[324.636837] Starting dynamic subtest: vecs0
[324.727812] Dynamic subtest vecs0: SUCCESS (0.099s)
[324.736776] Starting dynamic subtest: ccs0
[324.847754] Dynamic subtest ccs0: SUCCESS (0.119s)
[324.849129] Subtest implicit-write-read: SUCCESS (0.613s)
[325.967683] [051/132] (705s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-mmap-wc)
[326.067300] Starting subtest: fbcpsr-rgb101010-draw-mmap-wc
[332.737230] Subtest fbcpsr-rgb101010-draw-mmap-wc: SUCCESS (6.672s)
[332.874971] [052/132] (699s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-mc-ccs)
[332.967807] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-mc-ccs
[332.968702] Starting dynamic subtest: pipe-A-eDP-1
[332.977040] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[332.989314] Starting dynamic subtest: pipe-B-eDP-1
[332.998064] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[333.006268] Starting dynamic subtest: pipe-C-eDP-1
[333.014547] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[333.021421] Starting dynamic subtest: pipe-D-eDP-1
[333.029722] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[333.031334] Subtest crc-primary-rotation-180-4-tiled-dg2-mc-ccs: SKIP (0.006s)
[335.142776] [053/132] (696s left) kms_chamelium_frames (hdmi-crc-nonplanar-formats)
[335.528429] Subtest hdmi-crc-nonplanar-formats: SKIP (0.000s)
[336.317707] [054/132] (695s left) gem_ctx_engines (independent)
[336.372420] Starting subtest: independent
[336.373543] Starting dynamic subtest: rcs0
[336.502712] Dynamic subtest rcs0: SUCCESS (0.128s)
[336.513512] Starting dynamic subtest: bcs0
[336.628294] Dynamic subtest bcs0: SUCCESS (0.125s)
[336.637982] Starting dynamic subtest: vcs0
[336.734890] Dynamic subtest vcs0: SUCCESS (0.108s)
[336.744030] Starting dynamic subtest: vcs1
[336.864244] Dynamic subtest vcs1: SUCCESS (0.127s)
[336.872796] Starting dynamic subtest: vecs0
[336.991736] Dynamic subtest vecs0: SUCCESS (0.126s)
[336.999662] Starting dynamic subtest: ccs0
[337.108556] Dynamic subtest ccs0: SUCCESS (0.117s)
[337.116535] Starting dynamic subtest: all-engines
[337.616205] Dynamic subtest all-engines: SUCCESS (0.507s)
[337.617250] Subtest independent: SUCCESS (1.243s)
[338.032389] [055/132] (693s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0)
[338.132503] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-0
[339.087797] Subtest 4-tiled-max-hw-stride-32bpp-rotate-0: SUCCESS (0.956s)
[339.256574] [056/132] (692s left) kms_frontbuffer_tracking (fbc-1p-primscrn-shrfb-plflip-blt)
[339.352413] Starting subtest: fbc-1p-primscrn-shrfb-plflip-blt
[343.963249] Subtest fbc-1p-primscrn-shrfb-plflip-blt: SUCCESS (4.610s)
[345.168362] [057/132] (686s left) syncobj_wait (wait-for-submit-complex)
[345.223597] Starting subtest: wait-for-submit-complex
[345.285315] Subtest wait-for-submit-complex: SUCCESS (0.062s)
[345.400977] [058/132] (686s left) gem_eio (context-create)
[345.634504] Starting subtest: context-create
[345.974817] Subtest context-create: SUCCESS (0.340s)
[346.160231] [059/132] (685s left) gem_exec_balancer (bonded-chain)
[346.213698] Starting subtest: bonded-chain
[353.202728] Subtest bonded-chain: SUCCESS (6.990s)
[353.856593] [060/132] (678s left) api_intel_allocator (simple-allocator)
[353.904429] Starting subtest: simple-allocator
[353.905485] Starting dynamic subtest: basic
[353.907003] Dynamic subtest basic: SUCCESS (0.004s)
[353.918186] Starting dynamic subtest: parallel-one
[354.099637] Dynamic subtest parallel-one: SUCCESS (0.189s)
[354.110329] Starting dynamic subtest: print
[354.111291] Dynamic subtest print: SUCCESS (0.000s)
[354.120841] Starting dynamic subtest: reuse
[354.121610] Dynamic subtest reuse: SUCCESS (0.000s)
[354.129238] Starting dynamic subtest: reserve
[354.130064] Dynamic subtest reserve: SUCCESS (0.000s)
[354.137659] Starting dynamic subtest: fork-reopen-allocator
[354.138512] Dynamic subtest fork-reopen-allocator: SUCCESS (0.011s)
[354.139383] Subtest simple-allocator: SUCCESS (0.212s)
[354.277122] [061/132] (677s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-mmap-cpu)
[354.372272] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-mmap-cpu
[358.363658] Subtest fbc-1p-offscreen-pri-indfb-draw-mmap-cpu: SUCCESS (3.992s)
[359.561309] [062/132] (672s left) gem_exec_basic (basic)
[359.618796] Starting subtest: basic
[359.619881] Starting dynamic subtest: rcs0-smem
[359.622509] Dynamic subtest rcs0-smem: SUCCESS (0.003s)
[359.631241] Starting dynamic subtest: bcs0-smem
[359.632066] Dynamic subtest bcs0-smem: SUCCESS (0.001s)
[359.639880] Starting dynamic subtest: vcs0-smem
[359.640654] Dynamic subtest vcs0-smem: SUCCESS (0.001s)
[359.648396] Starting dynamic subtest: vcs1-smem
[359.649283] Dynamic subtest vcs1-smem: SUCCESS (0.001s)
[359.657063] Starting dynamic subtest: vecs0-smem
[359.657911] Dynamic subtest vecs0-smem: SUCCESS (0.001s)
[359.665567] Starting dynamic subtest: ccs0-smem
[359.666363] Dynamic subtest ccs0-smem: SUCCESS (0.001s)
[359.667213] Subtest basic: SUCCESS (0.014s)
[360.893711] [063/132] (671s left) gem_mmap_gtt (basic-small-bo-tiledy)
[360.934001] Subtest basic-small-bo-tiledY: SKIP (0.000s)
[360.999505] [064/132] (671s left) gem_mmap_wc (bad-size)
[361.053937] Subtest bad-size: SKIP (0.000s)
[361.118897] [065/132] (671s left) sw_sync (sync_multi_consumer)
[361.152157] Starting subtest: sync_multi_consumer
[361.566552] Subtest sync_multi_consumer: SUCCESS (0.413s)
[361.647735] [066/132] (670s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180-hflip)
[361.765978] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180-hflip
[361.767900] Subtest y-tiled-max-hw-stride-64bpp-rotate-180-hflip: SKIP (0.000s)
[361.896636] [067/132] (670s left) kms_frontbuffer_tracking (fbcpsr-2p-pri-indfb-multidraw)
[361.991483] Starting subtest: fbcpsr-2p-pri-indfb-multidraw
[361.993129] Subtest fbcpsr-2p-pri-indfb-multidraw: SKIP (0.000s)
[362.122488] [068/132] (670s left) syncobj_wait (multi-wait-for-submit-signaled)
[362.172173] Starting subtest: multi-wait-for-submit-signaled
[362.173117] Subtest multi-wait-for-submit-signaled: SUCCESS (0.000s)
[362.296715] [069/132] (670s left) gem_mmap_gtt (cpuset-big-copy)
[362.354477] Subtest cpuset-big-copy: SKIP (0.000s)
[362.416551] [070/132] (670s left) prime_busy (hang-wait)
[362.476906] Starting subtest: hang-wait
[362.478050] Starting dynamic subtest: rcs0
[388.333972] Dynamic subtest rcs0: SUCCESS (25.848s)
[388.342097] Starting dynamic subtest: bcs0
[395.418959] Dynamic subtest bcs0: SUCCESS (7.082s)
[395.428671] Starting dynamic subtest: vcs0
[402.505033] Dynamic subtest vcs0: SUCCESS (7.085s)
[402.514703] Starting dynamic subtest: vcs1
[409.496678] Dynamic subtest vcs1: SUCCESS (6.989s)
[409.505387] Starting dynamic subtest: vecs0
[416.401981] Dynamic subtest vecs0: SUCCESS (6.907s)
[416.411351] Starting dynamic subtest: ccs0
[430.349239] Dynamic subtest ccs0: SUCCESS (13.944s)
[430.350508] Subtest hang-wait: SUCCESS (67.868s)
[430.519426] [071/132] (602s left) kms_frontbuffer_tracking (fbc-rgb565-draw-pwrite)
[430.624412] Starting subtest: fbc-rgb565-draw-pwrite
[435.247818] Subtest fbc-rgb565-draw-pwrite: SUCCESS (4.624s)
[436.459263] [072/132] (596s left) kms_cursor_crc (cursor-offscreen-128x128)
[436.683828] Starting subtest: cursor-offscreen-128x128
[436.691401] Starting dynamic subtest: pipe-A-eDP-1
[438.687927] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.997s)
[438.718650] Starting dynamic subtest: pipe-D-eDP-1
[441.339037] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.630s)
[441.339940] Subtest cursor-offscreen-128x128: SUCCESS (4.657s)
[442.212594] [073/132] (590s left) gem_ctx_param (invalid-set-no-zeromap)
[442.265656] Starting subtest: invalid-set-no-zeromap
[442.266746] Subtest invalid-set-no-zeromap: SUCCESS (0.000s)
[442.361174] [074/132] (590s left) kms_rotation_crc (primary-yf-tiled-reflect-x-180)
[442.456632] Starting subtest: primary-yf-tiled-reflect-x-180
[442.516749] Subtest primary-yf-tiled-reflect-x-180: SKIP (0.059s)
[442.634697] [075/132] (590s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-pwrite)
[442.725019] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-pwrite
[447.255968] Subtest fbc-1p-primscrn-spr-indfb-draw-pwrite: SUCCESS (4.532s)
[448.417883] [076/132] (584s left) kms_selftest (drm_cmdline_parser)
[448.448396] Starting subtest: drm_cmdline_parser
[448.483443] Starting dynamic subtest: drm_test_cmdline_force_d_only
[448.616742] Dynamic subtest drm_test_cmdline_force_d_only: SUCCESS (0.132s)
[448.624704] Starting dynamic subtest: drm_test_cmdline_force_D_only_dvi
[448.723970] Dynamic subtest drm_test_cmdline_force_D_only_dvi: SUCCESS (0.106s)
[448.733949] Starting dynamic subtest: drm_test_cmdline_force_D_only_hdmi
[448.852661] Dynamic subtest drm_test_cmdline_force_D_only_hdmi: SUCCESS (0.128s)
[448.860535] Starting dynamic subtest: drm_test_cmdline_force_D_only_not_digital
[448.943994] Dynamic subtest drm_test_cmdline_force_D_only_not_digital: SUCCESS (0.090s)
[448.954180] Starting dynamic subtest: drm_test_cmdline_force_e_only
[449.080641] Dynamic subtest drm_test_cmdline_force_e_only: SUCCESS (0.134s)
[449.090183] Starting dynamic subtest: drm_test_cmdline_res
[449.170807] Dynamic subtest drm_test_cmdline_res: SUCCESS (0.092s)
[449.181301] Starting dynamic subtest: drm_test_cmdline_res_vesa
[449.321161] Dynamic subtest drm_test_cmdline_res_vesa: SUCCESS (0.145s)
[449.330786] Starting dynamic subtest: drm_test_cmdline_res_vesa_rblank
[449.417937] Dynamic subtest drm_test_cmdline_res_vesa_rblank: SUCCESS (0.098s)
[449.427391] Starting dynamic subtest: drm_test_cmdline_res_rblank
[449.543097] Dynamic subtest drm_test_cmdline_res_rblank: SUCCESS (0.122s)
[449.552953] Starting dynamic subtest: drm_test_cmdline_res_bpp
[449.683771] Dynamic subtest drm_test_cmdline_res_bpp: SUCCESS (0.141s)
[449.691906] Starting dynamic subtest: drm_test_cmdline_res_refresh
[449.806749] Dynamic subtest drm_test_cmdline_res_refresh: SUCCESS (0.122s)
[449.816268] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh
[449.948847] Dynamic subtest drm_test_cmdline_res_bpp_refresh: SUCCESS (0.141s)
[449.957359] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_interlaced
[450.077604] Dynamic subtest drm_test_cmdline_res_bpp_refresh_interlaced: SUCCESS (0.128s)
[450.085518] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_margins
[450.225556] Dynamic subtest drm_test_cmdline_res_bpp_refresh_margins: SUCCESS (0.146s)
[450.233534] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_force_off
[450.363599] Dynamic subtest drm_test_cmdline_res_bpp_refresh_force_off: SUCCESS (0.138s)
[450.371623] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_force_on
[450.449260] Dynamic subtest drm_test_cmdline_res_bpp_refresh_force_on: SUCCESS (0.087s)
[450.457170] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_force_on_analog
[450.582603] Dynamic subtest drm_test_cmdline_res_bpp_refresh_force_on_analog: SUCCESS (0.130s)
[450.591946] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_force_on_digital
[450.686512] Dynamic subtest drm_test_cmdline_res_bpp_refresh_force_on_digital: SUCCESS (0.105s)
[450.694509] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_interlaced_margins_force_on
[450.797082] Dynamic subtest drm_test_cmdline_res_bpp_refresh_interlaced_margins_force_on: SUCCESS (0.109s)
[450.806421] Starting dynamic subtest: drm_test_cmdline_res_margins_force_on
[450.907906] Dynamic subtest drm_test_cmdline_res_margins_force_on: SUCCESS (0.111s)
[450.917328] Starting dynamic subtest: drm_test_cmdline_res_vesa_margins
[451.025746] Dynamic subtest drm_test_cmdline_res_vesa_margins: SUCCESS (0.115s)
[451.034973] Starting dynamic subtest: drm_test_cmdline_name
[451.128406] Dynamic subtest drm_test_cmdline_name: SUCCESS (0.104s)
[451.136422] Starting dynamic subtest: drm_test_cmdline_name_bpp
[451.260222] Dynamic subtest drm_test_cmdline_name_bpp: SUCCESS (0.129s)
[451.268173] Starting dynamic subtest: drm_test_cmdline_name_option
[451.373581] Dynamic subtest drm_test_cmdline_name_option: SUCCESS (0.112s)
[451.382913] Starting dynamic subtest: drm_test_cmdline_name_bpp_option
[451.488674] Dynamic subtest drm_test_cmdline_name_bpp_option: SUCCESS (0.115s)
[451.496400] Starting dynamic subtest: drm_test_cmdline_rotate_0
[451.602561] Dynamic subtest drm_test_cmdline_rotate_0: SUCCESS (0.112s)
[451.612032] Starting dynamic subtest: drm_test_cmdline_rotate_90
[451.718840] Dynamic subtest drm_test_cmdline_rotate_90: SUCCESS (0.116s)
[451.726610] Starting dynamic subtest: drm_test_cmdline_rotate_180
[451.848897] Dynamic subtest drm_test_cmdline_rotate_180: SUCCESS (0.129s)
[451.856780] Starting dynamic subtest: drm_test_cmdline_rotate_270
[451.990212] Dynamic subtest drm_test_cmdline_rotate_270: SUCCESS (0.139s)
[451.999849] Starting dynamic subtest: drm_test_cmdline_hmirror
[452.116006] Dynamic subtest drm_test_cmdline_hmirror: SUCCESS (0.127s)
[452.123748] Starting dynamic subtest: drm_test_cmdline_vmirror
[452.221316] Dynamic subtest drm_test_cmdline_vmirror: SUCCESS (0.105s)
[452.230409] Starting dynamic subtest: drm_test_cmdline_margin_options
[452.348988] Dynamic subtest drm_test_cmdline_margin_options: SUCCESS (0.126s)
[452.356876] Starting dynamic subtest: drm_test_cmdline_multiple_options
[452.485142] Dynamic subtest drm_test_cmdline_multiple_options: SUCCESS (0.135s)
[452.493151] Starting dynamic subtest: drm_test_cmdline_bpp_extra_and_option
[452.625776] Dynamic subtest drm_test_cmdline_bpp_extra_and_option: SUCCESS (0.140s)
[452.635314] Starting dynamic subtest: drm_test_cmdline_extra_and_option
[452.720163] Dynamic subtest drm_test_cmdline_extra_and_option: SUCCESS (0.095s)
[452.729513] Starting dynamic subtest: drm_test_cmdline_freestanding_options
[452.810633] Dynamic subtest drm_test_cmdline_freestanding_options: SUCCESS (0.090s)
[452.819684] Starting dynamic subtest: drm_test_cmdline_freestanding_force_e_and_options
[452.920649] Dynamic subtest drm_test_cmdline_freestanding_force_e_and_options: SUCCESS (0.108s)
[452.929663] Starting dynamic subtest: drm_test_cmdline_panel_orientation
[453.048017] Dynamic subtest drm_test_cmdline_panel_orientation: SUCCESS (0.125s)
[453.056039] Starting dynamic subtest: drm_test_cmdline_invalid
[453.189331] Dynamic subtest drm_test_cmdline_invalid: SUCCESS (0.139s)
[453.197300] Starting dynamic subtest: drm_test_cmdline_tv_options
[453.336996] Dynamic subtest drm_test_cmdline_tv_options: SUCCESS (0.147s)
[453.338015] Subtest drm_cmdline_parser: SUCCESS (4.889s)
[453.545011] [077/132] (579s left) gem_ctx_param (set-priority-not-supported)
[453.600037] Starting subtest: set-priority-not-supported
[453.602137] Subtest set-priority-not-supported: SKIP (0.000s)
[453.696634] [078/132] (579s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-render)
[453.811037] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-render
[453.812856] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[453.950960] [079/132] (578s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180)
[454.048452] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180
[454.050296] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180: SKIP (0.000s)
[454.173940] [080/132] (578s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-180)
[454.276547] Starting subtest: linear-max-hw-stride-32bpp-rotate-180
[455.298166] Subtest linear-max-hw-stride-32bpp-rotate-180: SUCCESS (1.022s)
[455.463206] [081/132] (577s left) kms_plane_multiple (tiling-4)
[455.558788] Starting subtest: tiling-4
[455.559822] Starting dynamic subtest: pipe-A-eDP-1
[459.202515] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.644s)
[459.210871] Starting dynamic subtest: pipe-B-eDP-1
[463.555016] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.353s)
[463.563669] Starting dynamic subtest: pipe-C-eDP-1
[467.875163] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.320s)
[467.883309] Starting dynamic subtest: pipe-D-eDP-1
[472.229706] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.353s)
[472.230672] Subtest tiling-4: SUCCESS (16.676s)
[473.059029] [082/132] (559s left) kms_ccs (missing-ccs-buffer-y-tiled-gen12-mc-ccs)
[473.159831] Starting subtest: missing-ccs-buffer-y-tiled-gen12-mc-ccs
[473.160720] Starting dynamic subtest: pipe-A-eDP-1
[473.169231] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[473.177765] Starting dynamic subtest: pipe-B-eDP-1
[473.186139] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[473.194501] Starting dynamic subtest: pipe-C-eDP-1
[473.202726] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[473.210323] Starting dynamic subtest: pipe-D-eDP-1
[473.218847] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[473.220450] Subtest missing-ccs-buffer-y-tiled-gen12-mc-ccs: SKIP (0.004s)
[475.342295] [083/132] (557s left) kms_chamelium_hpd (vga-hpd-after-suspend)
[475.735382] Subtest vga-hpd-after-suspend: SKIP (0.000s)
[476.525398] [084/132] (556s left) perf (gen12-group-exclusive-stream-ctx-handle)
[476.645869] Starting subtest: gen12-group-exclusive-stream-ctx-handle
[476.670622] Subtest gen12-group-exclusive-stream-ctx-handle: SUCCESS (0.024s)
[476.709937] [085/132] (556s left) kms_setmode (basic)
[477.799377] Starting subtest: basic
[478.128136] Starting dynamic subtest: pipe-A-eDP-1
[480.174231] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.042s)
[480.528098] Starting dynamic subtest: pipe-B-eDP-1
[482.598488] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.075s)
[482.913424] Subtest basic: SUCCESS (5.117s)
[483.771300] [086/132] (549s left) kms_psr (fbc-psr-primary-render)
[483.869942] Starting subtest: fbc-psr-primary-render
[483.870939] Starting dynamic subtest: eDP-1
[483.872878] Dynamic subtest eDP-1: SKIP (0.000s)
[483.874581] Subtest fbc-psr-primary-render: SKIP (0.001s)
[483.966748] [087/132] (549s left) gem_create (create-valid-nonaligned)
[484.021549] Starting subtest: create-valid-nonaligned
[484.022614] Subtest create-valid-nonaligned: SUCCESS (0.000s)
[484.224387] [088/132] (548s left) kms_atomic_transition (plane-all-transition-fencing)
[484.317958] Starting subtest: plane-all-transition-fencing
[484.318948] Starting dynamic subtest: pipe-A-eDP-1
[486.813604] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.495s)
[487.126591] Starting dynamic subtest: pipe-B-eDP-1
[490.393483] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.274s)
[490.689242] Subtest plane-all-transition-fencing: SUCCESS (6.369s)
[491.533154] [089/132] (541s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-indfb-msflip-blt)
[491.635104] Starting subtest: fbcpsr-2p-scndscrn-indfb-msflip-blt
[491.636968] Subtest fbcpsr-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[491.762249] [090/132] (541s left) kms_lease (lease-unleased-connector)
[491.850821] Starting subtest: lease-unleased-connector
[491.851828] Starting dynamic subtest: pipe-A-eDP-1
[491.857835] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.006s)
[491.866747] Starting dynamic subtest: pipe-B-eDP-1
[491.867604] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.007s)
[491.876131] Starting dynamic subtest: pipe-C-eDP-1
[491.876973] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.006s)
[491.885692] Starting dynamic subtest: pipe-D-eDP-1
[491.886496] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.007s)
[491.887382] Subtest lease-unleased-connector: SUCCESS (0.031s)
[492.007373] [091/132] (541s left) drm_read (short-buffer-nonblock)
[492.151677] Starting subtest: short-buffer-nonblock
[492.152716] Subtest short-buffer-nonblock: SUCCESS (0.000s)
[492.308332] [092/132] (540s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-mmap-wc)
[492.407128] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-mmap-wc
[499.029131] Subtest psr-1p-offscreen-pri-shrfb-draw-mmap-wc: SUCCESS (6.618s)
[499.157838] [093/132] (534s left) kms_ccs (crc-sprite-planes-basic-4-tiled-dg2-rc-ccs)
[499.256528] Starting subtest: crc-sprite-planes-basic-4-tiled-dg2-rc-ccs
[499.257364] Starting dynamic subtest: pipe-A-eDP-1
[499.278241] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[499.289892] Starting dynamic subtest: pipe-B-eDP-1
[499.315061] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[499.331608] Starting dynamic subtest: pipe-C-eDP-1
[499.354694] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[499.364559] Starting dynamic subtest: pipe-D-eDP-1
[499.386004] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[499.387624] Subtest crc-sprite-planes-basic-4-tiled-dg2-rc-ccs: SKIP (0.009s)
[501.444029] [094/132] (531s left) kms_pm_rpm (fences-dpms)
[502.971432] Starting subtest: fences-dpms
[502.974229] Subtest fences-dpms: SKIP (0.000s)
[503.694360] [095/132] (529s left) i915_getparams_basic (basic-subslice-total)
[503.753314] Starting subtest: basic-subslice-total
[503.755441] Subtest basic-subslice-total: SUCCESS (0.000s)
[503.857185] [096/132] (529s left) gem_exec_fence (submit67)
[504.028190] Starting subtest: submit67
[504.030505] Subtest submit67: SKIP (0.000s)
[505.873968] [097/132] (527s left) kms_plane_scaling (planes-unity-scaling-downscale-factor-0-5)
[505.965167] Starting subtest: planes-unity-scaling-downscale-factor-0-5
[505.966150] Starting dynamic subtest: pipe-A
[505.983917] Dynamic subtest pipe-A: SKIP (0.018s)
[505.992207] Starting dynamic subtest: pipe-B
[506.020244] Dynamic subtest pipe-B: SKIP (0.029s)
[506.028263] Starting dynamic subtest: pipe-C
[506.045118] Dynamic subtest pipe-C: SKIP (0.029s)
[506.053425] Starting dynamic subtest: pipe-D
[506.078442] Dynamic subtest pipe-D: SKIP (0.032s)
[506.080062] Subtest planes-unity-scaling-downscale-factor-0-5: SKIP (0.112s)
[506.210273] [098/132] (527s left) sw_sync (sync_busy)
[506.243986] Starting subtest: sync_busy
[506.246190] Subtest sync_busy: SUCCESS (0.002s)
[506.319756] [099/132] (527s left) i915_pm_rpm (sysfs-read)
[508.040496] Starting subtest: sysfs-read
[509.208234] Subtest sysfs-read: SUCCESS (1.166s)
[509.584237] [100/132] (523s left) kms_flip (flip-vs-dpms-on-nop-interruptible)
[509.680168] Starting subtest: flip-vs-dpms-on-nop-interruptible
[509.682529] Starting dynamic subtest: A-eDP1
[511.562281] Dynamic subtest A-eDP1: SUCCESS (1.879s)
[511.573494] Starting dynamic subtest: B-eDP1
[513.399904] Dynamic subtest B-eDP1: SUCCESS (1.834s)
[513.414141] Starting dynamic subtest: C-eDP1
[515.218201] Dynamic subtest C-eDP1: SUCCESS (1.815s)
[515.228681] Starting dynamic subtest: D-eDP1
[517.045864] Dynamic subtest D-eDP1: SUCCESS (1.826s)
[517.048514] Subtest flip-vs-dpms-on-nop-interruptible: SUCCESS (7.366s)
[518.237115] [101/132] (515s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[518.326826] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[518.328440] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[518.452866] [102/132] (515s left) prime_mmap_coherency (write)
[518.508533] Starting subtest: write
[523.887019] Subtest write: SUCCESS (5.377s)
[524.072760] [103/132] (509s left) gem_mmap_wc (read)
[524.118584] Subtest read: SKIP (0.000s)
[524.172754] [104/132] (509s left) sysfs_timeslice_duration (timeout)
[524.222661] Starting subtest: timeout
[524.223657] Starting dynamic subtest: vecs0
[533.847355] Dynamic subtest vecs0: SUCCESS (9.622s)
[533.860940] Starting dynamic subtest: ccs0
[535.418900] Dynamic subtest ccs0: SUCCESS (1.569s)
[535.429873] Starting dynamic subtest: vcs0
[544.856671] Dynamic subtest vcs0: SUCCESS (9.432s)
[544.867095] Starting dynamic subtest: bcs0
[545.626096] Dynamic subtest bcs0: SUCCESS (0.769s)
[545.637888] Starting dynamic subtest: rcs0
[546.989353] Dynamic subtest rcs0: SUCCESS (1.360s)
[546.999495] Starting dynamic subtest: vcs1
[556.818615] Dynamic subtest vcs1: SUCCESS (9.827s)
[556.819832] Subtest timeout: SUCCESS (32.594s)
[556.913967] [105/132] (476s left) gem_softpin (allocator-nopin)
[556.974155] Starting subtest: allocator-nopin
[557.087356] Subtest allocator-nopin: SUCCESS (0.111s)
[557.251335] [106/132] (476s left) gem_mmap_gtt (isolation)
[557.310498] Subtest isolation: SKIP (0.000s)
[557.373915] [107/132] (476s left) kms_flip (flip-vs-absolute-wf_vblank)
[557.466042] Starting subtest: flip-vs-absolute-wf_vblank
[557.468324] Starting dynamic subtest: A-eDP1
[566.372447] Dynamic subtest A-eDP1: SUCCESS (8.904s)
[566.384559] Starting dynamic subtest: B-eDP1
[575.306534] Dynamic subtest B-eDP1: SUCCESS (8.932s)
[575.316127] Starting dynamic subtest: C-eDP1
[584.219421] Dynamic subtest C-eDP1: SUCCESS (8.912s)
[584.228056] Starting dynamic subtest: D-eDP1
[593.149365] Dynamic subtest D-eDP1: SUCCESS (8.929s)
[593.152237] Subtest flip-vs-absolute-wf_vblank: SUCCESS (35.687s)
[594.385084] [108/132] (439s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-pwrite)
[594.479771] Starting subtest: psr-2p-primscrn-spr-indfb-draw-pwrite
[594.481493] Subtest psr-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[594.604304] [109/132] (439s left) syncobj_eventfd (invalid-bad-pad)
[594.660616] Starting subtest: invalid-bad-pad
[594.661496] Subtest invalid-bad-pad: SUCCESS (0.000s)
[594.774390] [110/132] (439s left) kms_big_fb (yf-tiled-32bpp-rotate-180)
[594.877402] Starting subtest: yf-tiled-32bpp-rotate-180
[594.879090] Subtest yf-tiled-32bpp-rotate-180: SKIP (0.000s)
[594.999243] [111/132] (438s left) prime_self_import (export-vs-gem_close-race)
[595.037037] Starting subtest: export-vs-gem_close-race
[600.140748] Subtest export-vs-gem_close-race: SUCCESS (5.105s)
[600.239355] [112/132] (433s left) gem_render_copy (mixed-tiled-to-y-tiled-ccs)
[600.299223] Starting subtest: mixed-tiled-to-y-tiled-ccs
[600.301123] Subtest mixed-tiled-to-y-tiled-ccs: SKIP (0.000s)
[600.504379] [113/132] (433s left) prime_mmap (test_correct)
[600.552770] Starting subtest: test_correct
[600.553741] Starting dynamic subtest: test_correct-smem
[600.585049] Dynamic subtest test_correct-smem: SUCCESS (0.033s)
[600.586130] Subtest test_correct: SUCCESS (0.034s)
[600.716952] [114/132] (433s left) kms_psr2_sf (pr-overlay-plane-update-sf-dmg-area)
[605.755572] Starting subtest: pr-overlay-plane-update-sf-dmg-area
[605.758522] Subtest pr-overlay-plane-update-sf-dmg-area: SKIP (0.002s)
[606.606768] [115/132] (427s left) gem_exec_schedule (u-submit-early-slice)
[606.668687] Starting subtest: u-submit-early-slice
[606.669674] Starting dynamic subtest: rcs0
[606.710664] Dynamic subtest rcs0: SUCCESS (0.043s)
[606.721676] Starting dynamic subtest: bcs0
[606.750890] Dynamic subtest bcs0: SUCCESS (0.040s)
[606.759989] Starting dynamic subtest: vcs0
[606.790574] Dynamic subtest vcs0: SUCCESS (0.039s)
[606.799561] Starting dynamic subtest: vcs1
[606.833283] Dynamic subtest vcs1: SUCCESS (0.042s)
[606.843768] Starting dynamic subtest: vecs0
[606.874371] Dynamic subtest vecs0: SUCCESS (0.041s)
[606.883010] Starting dynamic subtest: ccs0
[606.918768] Dynamic subtest ccs0: SUCCESS (0.044s)
[606.919604] Subtest u-submit-early-slice: SUCCESS (0.252s)
[607.945237] [116/132] (426s left) dumb_buffer (map-valid)
[608.001638] Starting subtest: map-valid
[608.002666] Subtest map-valid: SUCCESS (0.000s)
[608.109091] [117/132] (425s left) kms_atomic_interruptible (legacy-cursor)
[608.202050] Starting subtest: legacy-cursor
[608.202989] Starting dynamic subtest: pipe-A-eDP-1
[615.618446] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.416s)
[615.619450] Subtest legacy-cursor: SUCCESS (7.418s)
[616.476236] [118/132] (417s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-onoff)
[616.567765] Starting subtest: fbc-1p-primscrn-spr-indfb-onoff
[620.575084] Subtest fbc-1p-primscrn-spr-indfb-onoff: SUCCESS (4.009s)
[621.776441] [119/132] (412s left) sysfs_heartbeat_interval (mixed)
[621.835732] Starting subtest: mixed
[621.837281] Starting dynamic subtest: vecs0
[627.095566] Dynamic subtest vecs0: SUCCESS (5.260s)
[627.108074] Starting dynamic subtest: ccs0
[632.339390] Dynamic subtest ccs0: SUCCESS (5.242s)
[632.350957] Starting dynamic subtest: vcs0
[637.536328] Dynamic subtest vcs0: SUCCESS (5.196s)
[637.548253] Starting dynamic subtest: bcs0
[642.751381] Dynamic subtest bcs0: SUCCESS (5.213s)
[642.764202] Starting dynamic subtest: rcs0
[647.964108] Dynamic subtest rcs0: SUCCESS (5.212s)
[647.976186] Starting dynamic subtest: vcs1
[653.217792] Dynamic subtest vcs1: SUCCESS (5.253s)
[653.219414] Subtest mixed: SUCCESS (31.387s)
[653.314153] [120/132] (380s left) gem_exec_reloc (basic-gtt-cpu-noreloc)
[653.380750] Subtest basic-gtt-cpu-noreloc: SKIP (0.000s)
[653.467409] [121/132] (380s left) kms_dsc (dsc-with-output-formats)
[653.573794] Starting subtest: dsc-with-output-formats
[653.582409] Subtest dsc-with-output-formats: SKIP (0.001s)
[653.664293] [122/132] (380s left) kms_ccs (random-ccs-data-yf-tiled-ccs)
[653.756567] Starting subtest: random-ccs-data-yf-tiled-ccs
[653.757577] Starting dynamic subtest: pipe-A-eDP-1
[653.767922] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[653.777770] Starting dynamic subtest: pipe-B-eDP-1
[653.787928] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[653.797007] Starting dynamic subtest: pipe-C-eDP-1
[653.806709] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[653.815033] Starting dynamic subtest: pipe-D-eDP-1
[653.824966] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[653.826730] Subtest random-ccs-data-yf-tiled-ccs: SKIP (0.005s)
[655.939054] [123/132] (378s left) kms_psr (fbc-psr2-cursor-plane-move)
[656.026861] Starting subtest: fbc-psr2-cursor-plane-move
[656.027826] Starting dynamic subtest: eDP-1
[656.029618] Dynamic subtest eDP-1: SKIP (0.000s)
[656.031369] Subtest fbc-psr2-cursor-plane-move: SKIP (0.001s)
[656.120244] [124/132] (378s left) i915_suspend (basic-s2idle-without-i915)
[656.150966] Starting subtest: basic-s2idle-without-i915
[678.219556] Subtest basic-s2idle-without-i915: SUCCESS (6.139s)
[683.331354] [125/132] (356s left) gem_exec_reloc (basic-cpu-gtt)
[683.396368] Subtest basic-cpu-gtt: SKIP (0.000s)
[683.468391] [126/132] (355s left) gem_fenced_exec_thrash (no-spare-fences-busy)
[683.522496] Subtest no-spare-fences-busy: SKIP (0.000s)
[683.602133] [127/132] (355s left) gem_ctx_persistence (userptr)
[683.676556] Starting subtest: userptr
[683.880228] Subtest userptr: SUCCESS (0.206s)
[684.022213] [128/132] (355s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180)
[684.126449] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180
[684.128190] Subtest y-tiled-max-hw-stride-32bpp-rotate-180: SKIP (0.000s)
[684.249572] [129/132] (355s left) kms_cursor_crc (cursor-rapid-movement-512x512)
[684.500688] Starting subtest: cursor-rapid-movement-512x512
[684.502357] Subtest cursor-rapid-movement-512x512: SKIP (0.001s)
[684.680291] [130/132] (354s left) kms_content_protection (atomic-hdcp14)
[684.809210] Starting subtest: atomic-hdcp14
[684.810908] Subtest atomic-hdcp14: SKIP (0.000s)
[684.956087] [131/132] (354s left) syncobj_wait (wait-all-complex)
[685.007804] Starting subtest: wait-all-complex
[685.221568] Subtest wait-all-complex: SUCCESS (0.217s)
[685.312621] [132/132] (354s left) syncobj_timeline (etime-single-wait-all-for-submit-unsubmitted)
[685.363105] Starting subtest: etime-single-wait-all-for-submit-unsubmitted
[685.468678] Subtest etime-single-wait-all-for-submit-unsubmitted: SUCCESS (0.105s)
[685.564084] Closing watchdogs
results: parsing output: 0/ for test: kms_cursor_edge_walk
results: parsing output: 1/ for test: kms_psr
results: parsing output: 2/ for test: kms_cursor_legacy
results: parsing output: 3/ for test: dumb_buffer
results: parsing output: 4/ for test: kms_fbcon_fbt
results: parsing output: 5/ for test: gen9_exec_parse
results: parsing output: 6/ for test: kms_vblank
results: parsing output: 7/ for test: gem_tiled_swapping
results: parsing output: 8/ for test: api_intel_bb
results: parsing output: 9/ for test: kms_psr
results: parsing output: 10/ for test: kms_frontbuffer_tracking
results: parsing output: 11/ for test: kms_frontbuffer_tracking
results: parsing output: 12/ for test: kms_invalid_mode
results: parsing output: 13/ for test: gem_mmap
results: parsing output: 14/ for test: syncobj_timeline
results: parsing output: 15/ for test: prime_mmap_kms
results: parsing output: 16/ for test: kms_async_flips
results: parsing output: 17/ for test: kms_frontbuffer_tracking
results: parsing output: 18/ for test: gem_wait
results: parsing output: 19/ for test: gem_render_copy
results: parsing output: 20/ for test: kms_psr
results: parsing output: 21/ for test: kms_frontbuffer_tracking
results: parsing output: 22/ for test: kms_frontbuffer_tracking
results: parsing output: 23/ for test: kms_async_flips
results: parsing output: 24/ for test: kms_ccs
results: parsing output: 25/ for test: perf_pmu
results: parsing output: 26/ for test: syncobj_timeline
results: parsing output: 27/ for test: kms_pm_dc
results: parsing output: 28/ for test: gen9_exec_parse
results: parsing output: 29/ for test: kms_psr2_sf
results: parsing output: 30/ for test: kms_flip_scaled_crc
results: parsing output: 31/ for test: kms_frontbuffer_tracking
results: parsing output: 32/ for test: kms_frontbuffer_tracking
results: parsing output: 33/ for test: gem_exec_schedule
results: parsing output: 34/ for test: kms_prop_blob
results: parsing output: 35/ for test: perf_pmu
results: parsing output: 36/ for test: kms_flip
results: parsing output: 37/ for test: i915_query
results: parsing output: 38/ for test: kms_addfb_basic
results: parsing output: 39/ for test: sw_sync
results: parsing output: 40/ for test: kms_color_pipeline
results: parsing output: 41/ for test: gem_lmem_swapping
results: parsing output: 42/ for test: kms_plane_alpha_blend
results: parsing output: 43/ for test: kms_big_fb
results: parsing output: 44/ for test: syncobj_timeline
results: parsing output: 45/ for test: gem_exec_params
results: parsing output: 46/ for test: gem_flink_basic
results: parsing output: 47/ for test: gem_exec_fence
results: parsing output: 48/ for test: syncobj_wait
results: parsing output: 49/ for test: gem_exec_schedule
results: parsing output: 50/ for test: kms_frontbuffer_tracking
results: parsing output: 51/ for test: kms_ccs
results: parsing output: 52/ for test: kms_chamelium_frames
results: parsing output: 53/ for test: gem_ctx_engines
results: parsing output: 54/ for test: kms_big_fb
results: parsing output: 55/ for test: kms_frontbuffer_tracking
results: parsing output: 56/ for test: syncobj_wait
results: parsing output: 57/ for test: gem_eio
results: parsing output: 58/ for test: gem_exec_balancer
results: parsing output: 59/ for test: api_intel_allocator
results: parsing output: 60/ for test: kms_frontbuffer_tracking
results: parsing output: 61/ for test: gem_exec_basic
results: parsing output: 62/ for test: gem_mmap_gtt
results: parsing output: 63/ for test: gem_mmap_wc
results: parsing output: 64/ for test: sw_sync
results: parsing output: 65/ for test: kms_big_fb
results: parsing output: 66/ for test: kms_frontbuffer_tracking
results: parsing output: 67/ for test: syncobj_wait
results: parsing output: 68/ for test: gem_mmap_gtt
results: parsing output: 69/ for test: prime_busy
results: parsing output: 70/ for test: kms_frontbuffer_tracking
results: parsing output: 71/ for test: kms_cursor_crc
results: parsing output: 72/ for test: gem_ctx_param
results: parsing output: 73/ for test: kms_rotation_crc
results: parsing output: 74/ for test: kms_frontbuffer_tracking
results: parsing output: 75/ for test: kms_selftest
results: parsing output: 76/ for test: gem_ctx_param
results: parsing output: 77/ for test: kms_frontbuffer_tracking
results: parsing output: 78/ for test: kms_big_fb
results: parsing output: 79/ for test: kms_big_fb
results: parsing output: 80/ for test: kms_plane_multiple
results: parsing output: 81/ for test: kms_ccs
results: parsing output: 82/ for test: kms_chamelium_hpd
results: parsing output: 83/ for test: perf
results: parsing output: 84/ for test: kms_setmode
results: parsing output: 85/ for test: kms_psr
results: parsing output: 86/ for test: gem_create
results: parsing output: 87/ for test: kms_atomic_transition
results: parsing output: 88/ for test: kms_frontbuffer_tracking
results: parsing output: 89/ for test: kms_lease
results: parsing output: 90/ for test: drm_read
results: parsing output: 91/ for test: kms_frontbuffer_tracking
results: parsing output: 92/ for test: kms_ccs
results: parsing output: 93/ for test: kms_pm_rpm
results: parsing output: 94/ for test: i915_getparams_basic
results: parsing output: 95/ for test: gem_exec_fence
results: parsing output: 96/ for test: kms_plane_scaling
results: parsing output: 97/ for test: sw_sync
results: parsing output: 98/ for test: i915_pm_rpm
results: parsing output: 99/ for test: kms_flip
results: parsing output: 100/ for test: kms_frontbuffer_tracking
results: parsing output: 101/ for test: prime_mmap_coherency
results: parsing output: 102/ for test: gem_mmap_wc
results: parsing output: 103/ for test: sysfs_timeslice_duration
results: parsing output: 104/ for test: gem_softpin
results: parsing output: 105/ for test: gem_mmap_gtt
results: parsing output: 106/ for test: kms_flip
results: parsing output: 107/ for test: kms_frontbuffer_tracking
results: parsing output: 108/ for test: syncobj_eventfd
results: parsing output: 109/ for test: kms_big_fb
results: parsing output: 110/ for test: prime_self_import
results: parsing output: 111/ for test: gem_render_copy
results: parsing output: 112/ for test: prime_mmap
results: parsing output: 113/ for test: kms_psr2_sf
results: parsing output: 114/ for test: gem_exec_schedule
results: parsing output: 115/ for test: dumb_buffer
results: parsing output: 116/ for test: kms_atomic_interruptible
results: parsing output: 117/ for test: kms_frontbuffer_tracking
results: parsing output: 118/ for test: sysfs_heartbeat_interval
results: parsing output: 119/ for test: gem_exec_reloc
results: parsing output: 120/ for test: kms_dsc
results: parsing output: 121/ for test: kms_ccs
results: parsing output: 122/ for test: kms_psr
results: parsing output: 123/ for test: i915_suspend
results: parsing output: 124/ for test: gem_exec_reloc
results: parsing output: 125/ for test: gem_fenced_exec_thrash
results: parsing output: 126/ for test: gem_ctx_persistence
results: parsing output: 127/ for test: kms_big_fb
results: parsing output: 128/ for test: kms_cursor_crc
results: parsing output: 129/ for test: kms_content_protection
results: parsing output: 130/ for test: syncobj_wait
results: parsing output: 131/ for test: syncobj_timeline
Done.