[58.781216] Initializing watchdogs
[58.781262]   /dev/watchdog0
[58.795544] [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]
[58.799868] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[58.802103] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[58.804141] [001/132] (960s left) kms_cursor_edge_walk (128x128-left-edge)
[58.903923] Starting subtest: 128x128-left-edge
[58.904842] Starting dynamic subtest: pipe-A-eDP-1
[62.429764] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.500s)
[62.437346] Starting dynamic subtest: pipe-D-eDP-1
[65.563869] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.120s)
[65.564805] Subtest 128x128-left-edge: SUCCESS (6.623s)
[66.380396] [002/132] (952s left) kms_psr (psr2-cursor-mmap-gtt)
[66.472186] Starting subtest: psr2-cursor-mmap-gtt
[66.473924] Starting dynamic subtest: eDP-1
[68.145663] Dynamic subtest eDP-1: SUCCESS (1.668s)
[68.146574] Subtest psr2-cursor-mmap-gtt: SUCCESS (1.669s)
[68.951165] [003/132] (950s left) kms_cursor_legacy (single-move)
[69.057688] Starting subtest: single-move
[69.058662] Starting dynamic subtest: pipe-A
[74.449902] Dynamic subtest pipe-A: SUCCESS (5.379s)
[74.458369] Starting dynamic subtest: pipe-B
[79.836072] Dynamic subtest pipe-B: SUCCESS (5.380s)
[79.844768] Starting dynamic subtest: pipe-C
[85.222805] Dynamic subtest pipe-C: SUCCESS (5.381s)
[85.231375] Starting dynamic subtest: pipe-D
[90.610044] Dynamic subtest pipe-D: SUCCESS (5.381s)
[90.618994] Starting dynamic subtest: all-pipes
[96.001735] Dynamic subtest all-pipes: SUCCESS (5.388s)
[96.003292] Subtest single-move: SUCCESS (26.916s)
[96.159836] [004/132] (923s left) dumb_buffer (invalid-bpp)
[96.211265] Starting subtest: invalid-bpp
[96.212275] Subtest invalid-bpp: SUCCESS (0.000s)
[96.319795] [005/132] (923s left) kms_fbcon_fbt (fbc)
[96.371467] Starting subtest: fbc
[102.920523] Subtest fbc: SUCCESS (6.544s)
[103.982201] [006/132] (915s left) gen9_exec_parse (bb-start-far)
[104.046254] Subtest bb-start-far: SKIP (0.000s)
[104.099986] [007/132] (915s left) kms_vblank (ts-continuation-dpms-suspend)
[104.191723] Starting subtest: ts-continuation-dpms-suspend
[104.192693] Starting dynamic subtest: pipe-A-eDP-1
[121.226994] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.050s)
[121.235074] Starting dynamic subtest: pipe-D-eDP-1
[139.238616] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.883s)
[139.239618] Subtest ts-continuation-dpms-suspend: SUCCESS (8.936s)
[146.082582] [008/132] (879s left) gem_tiled_swapping (non-threaded)
[146.145140] Subtest non-threaded: SKIP (0.000s)
[146.204035] [009/132] (879s left) api_intel_bb (object-noreloc-keep-cache-simple)
[146.260693] Starting subtest: object-noreloc-keep-cache-simple
[146.268163] Subtest object-noreloc-keep-cache-simple: SUCCESS (0.008s)
[146.418270] [010/132] (879s left) kms_psr (fbc-pr-cursor-mmap-cpu)
[146.524637] Starting subtest: fbc-pr-cursor-mmap-cpu
[146.526631] Subtest fbc-pr-cursor-mmap-cpu: SKIP (0.000s)
[146.616107] [011/132] (879s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-move)
[146.726169] Starting subtest: psr-1p-primscrn-spr-indfb-move
[154.386882] Subtest psr-1p-primscrn-spr-indfb-move: SUCCESS (7.489s)
[154.569447] [012/132] (871s left) kms_frontbuffer_tracking (fbcpsr-tiling-4)
[154.674428] Starting subtest: fbcpsr-tiling-4
[161.881582] Subtest fbcpsr-tiling-4: SUCCESS (7.147s)
[162.036466] [013/132] (863s left) kms_invalid_mode (overflow-vrefresh)
[162.128493] Starting subtest: overflow-vrefresh
[162.129373] Starting dynamic subtest: pipe-A-eDP-1
[162.422246] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.292s)
[162.432622] Starting dynamic subtest: pipe-B-eDP-1
[162.433616] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[162.441972] Starting dynamic subtest: pipe-C-eDP-1
[162.442835] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[162.448911] Starting dynamic subtest: pipe-D-eDP-1
[162.449737] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[162.450559] Subtest overflow-vrefresh: SUCCESS (0.297s)
[163.231888] [014/132] (862s left) gem_mmap (basic-small-bo)
[163.296836] Subtest basic-small-bo: SKIP (0.000s)
[163.363944] [015/132] (862s left) syncobj_timeline (single-wait-available-signaled)
[163.426255] Starting subtest: single-wait-available-signaled
[163.427239] Subtest single-wait-available-signaled: SUCCESS (0.000s)
[163.554807] [016/132] (862s left) prime_mmap_kms (buffer-sharing)
[163.649212] Starting subtest: buffer-sharing
[164.115438] Subtest buffer-sharing: SUCCESS (0.465s)
[164.973547] [017/132] (860s left) kms_async_flips (async-flip-with-page-flip-events-tiled)
[165.056720] Starting subtest: async-flip-with-page-flip-events-tiled
[165.441649] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs
[168.251835] Dynamic subtest pipe-A-eDP-1-4-rc-ccs: SUCCESS (2.799s)
[168.258548] Starting dynamic subtest: pipe-A-eDP-1-4
[170.430093] Dynamic subtest pipe-A-eDP-1-4: SUCCESS (2.174s)
[170.441513] Starting dynamic subtest: pipe-A-eDP-1-4
[172.446275] Dynamic subtest pipe-A-eDP-1-4: SUCCESS (2.010s)
[172.453460] Starting dynamic subtest: pipe-A-eDP-1-x
[174.617790] Dynamic subtest pipe-A-eDP-1-x: SUCCESS (2.168s)
[174.976905] Starting dynamic subtest: pipe-B-eDP-1-4-rc-ccs
[177.705366] Dynamic subtest pipe-B-eDP-1-4-rc-ccs: SUCCESS (2.732s)
[177.714986] Starting dynamic subtest: pipe-B-eDP-1-4
[179.891842] Dynamic subtest pipe-B-eDP-1-4: SUCCESS (2.186s)
[179.901127] Starting dynamic subtest: pipe-B-eDP-1-4
[181.901222] Dynamic subtest pipe-B-eDP-1-4: SUCCESS (2.009s)
[181.907889] Starting dynamic subtest: pipe-B-eDP-1-x
[184.093067] Dynamic subtest pipe-B-eDP-1-x: SUCCESS (2.190s)
[184.454320] Starting dynamic subtest: pipe-C-eDP-1-4-rc-ccs
[187.238745] Dynamic subtest pipe-C-eDP-1-4-rc-ccs: SUCCESS (2.788s)
[187.247744] Starting dynamic subtest: pipe-C-eDP-1-4
[189.414998] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.175s)
[189.424667] Starting dynamic subtest: pipe-C-eDP-1-4
[191.424161] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.009s)
[191.434047] Starting dynamic subtest: pipe-C-eDP-1-x
[193.604789] Dynamic subtest pipe-C-eDP-1-x: SUCCESS (2.178s)
[193.963940] Starting dynamic subtest: pipe-D-eDP-1-4-rc-ccs
[196.771998] Dynamic subtest pipe-D-eDP-1-4-rc-ccs: SUCCESS (2.810s)
[196.780569] Starting dynamic subtest: pipe-D-eDP-1-4
[198.947807] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.174s)
[198.957537] Starting dynamic subtest: pipe-D-eDP-1-4
[200.958506] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.009s)
[200.968471] Starting dynamic subtest: pipe-D-eDP-1-x
[203.137911] Dynamic subtest pipe-D-eDP-1-x: SUCCESS (2.178s)
[203.139370] Subtest async-flip-with-page-flip-events-tiled: SUCCESS (38.051s)
[204.389326] [018/132] (821s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-blt)
[204.493110] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-blt
[204.494741] Subtest fbc-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[204.607501] [019/132] (821s left) gem_wait (write-busy)
[204.669107] Starting subtest: write-busy
[204.670268] Starting dynamic subtest: all-engines
[205.340329] Dynamic subtest all-engines: SUCCESS (0.670s)
[205.355390] Starting dynamic subtest: rcs0
[205.893164] Dynamic subtest rcs0: SUCCESS (0.551s)
[205.906775] Starting dynamic subtest: bcs0
[206.441457] Dynamic subtest bcs0: SUCCESS (0.547s)
[206.454967] Starting dynamic subtest: vcs0
[208.021103] Dynamic subtest vcs0: SUCCESS (1.576s)
[208.035190] Starting dynamic subtest: vcs1
[210.003879] Dynamic subtest vcs1: SUCCESS (1.978s)
[210.017891] Starting dynamic subtest: vecs0
[212.052371] Dynamic subtest vecs0: SUCCESS (2.046s)
[212.066270] Starting dynamic subtest: ccs0
[212.601584] Dynamic subtest ccs0: SUCCESS (0.550s)
[212.602632] Subtest write-busy: SUCCESS (7.929s)
[212.812749] [FACT gem_wait (write-busy)] new: kernel.kmod_is_loaded.vgem: true
[212.814682] [020/132] (813s left) gem_render_copy (y-tiled-ccs-to-y-tiled-mc-ccs)
[212.873164] Starting subtest: y-tiled-ccs-to-y-tiled-mc-ccs
[212.875522] Subtest y-tiled-ccs-to-y-tiled-mc-ccs: SKIP (0.000s)
[213.084501] [021/132] (812s left) kms_psr (psr-sprite-mmap-gtt)
[213.175495] Starting subtest: psr-sprite-mmap-gtt
[213.176382] Starting dynamic subtest: eDP-1
[214.597281] Dynamic subtest eDP-1: SKIP (1.417s)
[214.599661] Subtest psr-sprite-mmap-gtt: SKIP (1.419s)
[214.820101] [022/132] (811s left) kms_frontbuffer_tracking (fbcpsr-suspend)
[214.914842] Starting subtest: fbcpsr-suspend
[255.289266] Subtest fbcpsr-suspend: SUCCESS (10.797s)
[258.418856] [023/132] (770s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc)
[258.510512] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc
[265.165159] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc: SUCCESS (6.479s)
[265.325232] [024/132] (763s left) kms_async_flips (invalid-async-flip-atomic)
[265.424288] Starting subtest: invalid-async-flip-atomic
[265.806212] Starting dynamic subtest: pipe-A-eDP-1
[266.655792] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.836s)
[267.016141] Starting dynamic subtest: pipe-B-eDP-1
[267.800598] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.780s)
[268.159404] Starting dynamic subtest: pipe-C-eDP-1
[268.929122] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.767s)
[269.299201] Starting dynamic subtest: pipe-D-eDP-1
[270.075607] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.775s)
[270.076607] Subtest invalid-async-flip-atomic: SUCCESS (4.592s)
[271.250155] [025/132] (757s left) kms_ccs (bad-pixel-format-y-tiled-gen12-mc-ccs)
[271.332267] Starting subtest: bad-pixel-format-y-tiled-gen12-mc-ccs
[271.333236] Starting dynamic subtest: pipe-A-eDP-1
[271.342021] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[271.349198] Starting dynamic subtest: pipe-B-eDP-1
[271.357858] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[271.364883] Starting dynamic subtest: pipe-C-eDP-1
[271.373406] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[271.380277] Starting dynamic subtest: pipe-D-eDP-1
[271.388914] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[271.390562] Subtest bad-pixel-format-y-tiled-gen12-mc-ccs: SKIP (0.004s)
[273.548180] [026/132] (755s left) perf_pmu (idle)
[273.594841] Starting subtest: idle
[273.595752] Starting dynamic subtest: rcs0
[274.158185] Dynamic subtest rcs0: SUCCESS (0.558s)
[274.168745] Starting dynamic subtest: bcs0
[274.678026] Dynamic subtest bcs0: SUCCESS (0.513s)
[274.688094] Starting dynamic subtest: vcs0
[275.195458] Dynamic subtest vcs0: SUCCESS (0.514s)
[275.207494] Starting dynamic subtest: vcs1
[275.714841] Dynamic subtest vcs1: SUCCESS (0.513s)
[275.725188] Starting dynamic subtest: vecs0
[276.234008] Dynamic subtest vecs0: SUCCESS (0.514s)
[276.244129] Starting dynamic subtest: ccs0
[276.753438] Dynamic subtest ccs0: SUCCESS (0.513s)
[276.755016] Subtest idle: SUCCESS (3.142s)
[276.890794] [027/132] (752s left) syncobj_timeline (invalid-signal-one-illegal-handle)
[276.958399] Starting subtest: invalid-signal-one-illegal-handle
[276.959284] Subtest invalid-signal-one-illegal-handle: SUCCESS (0.001s)
[277.092300] [028/132] (752s left) kms_pm_dc (dc3co-vpb-simulation)
[278.200873] Starting subtest: dc3co-vpb-simulation
[286.998753] Subtest dc3co-vpb-simulation: SKIP (8.775s)
[287.145631] [029/132] (742s left) gen9_exec_parse (shadow-peek)
[287.195347] Subtest shadow-peek: SKIP (0.000s)
[287.275738] [030/132] (741s left) kms_psr2_sf (fbc-psr2-overlay-plane-move-continuous-exceed-fully-sf)
[292.257346] Starting subtest: fbc-psr2-overlay-plane-move-continuous-exceed-fully-sf
[292.258497] Starting dynamic subtest: pipe-A-eDP-1
[293.064949] Dynamic subtest pipe-A-eDP-1: SKIP (0.805s)
[293.075680] Starting dynamic subtest: pipe-B-eDP-1
[294.195856] Dynamic subtest pipe-B-eDP-1: SKIP (1.128s)
[294.197552] Subtest fbc-psr2-overlay-plane-move-continuous-exceed-fully-sf: SKIP (1.938s)
[295.420435] [031/132] (733s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-downscaling)
[295.526160] Starting subtest: flip-64bpp-linear-to-16bpp-linear-downscaling
[295.527069] Starting dynamic subtest: pipe-A-default-mode
[296.681103] Dynamic subtest pipe-A-default-mode: SKIP (1.153s)
[296.682700] Subtest flip-64bpp-linear-to-16bpp-linear-downscaling: SKIP (1.155s)
[296.866416] [032/132] (732s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-wc)
[296.962941] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-wc
[296.964566] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[297.099891] [033/132] (732s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-render)
[297.193334] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-render
[297.194989] Subtest fbc-2p-scndscrn-pri-shrfb-draw-render: SKIP (0.000s)
[297.328986] [034/132] (732s left) gem_exec_schedule (reorder-wide)
[297.509034] Subtest reorder-wide: SKIP (0.000s)
[297.704734] [035/132] (731s left) kms_prop_blob (blob-prop-lifetime)
[297.765602] Starting subtest: blob-prop-lifetime
[297.766501] Subtest blob-prop-lifetime: SUCCESS (0.002s)
[297.910733] [036/132] (731s left) perf_pmu (invalid-init)
[297.942372] Starting subtest: invalid-init
[297.943285] Subtest invalid-init: SUCCESS (0.000s)
[298.073169] [037/132] (731s left) kms_flip (basic-flip-vs-modeset)
[298.156517] Starting subtest: basic-flip-vs-modeset
[298.158835] Starting dynamic subtest: A-eDP1
[300.744963] Dynamic subtest A-eDP1: SUCCESS (2.585s)
[300.753739] Starting dynamic subtest: B-eDP1
[302.894660] Dynamic subtest B-eDP1: SUCCESS (2.146s)
[302.905663] Starting dynamic subtest: C-eDP1
[305.057742] Dynamic subtest C-eDP1: SUCCESS (2.161s)
[305.065453] Starting dynamic subtest: D-eDP1
[307.206291] Dynamic subtest D-eDP1: SUCCESS (2.147s)
[307.208309] Subtest basic-flip-vs-modeset: SUCCESS (9.048s)
[308.043373] [038/132] (721s left) i915_query (query-topology-unsupported)
[308.087978] Starting subtest: query-topology-unsupported
[308.090135] Subtest query-topology-unsupported: SKIP (0.000s)
[308.218703] [039/132] (721s left) kms_addfb_basic (small-bo)
[308.306881] Starting subtest: small-bo
[308.307721] Subtest small-bo: SUCCESS (0.000s)
[308.430189] [040/132] (721s left) sw_sync (alloc_fence)
[308.460188] Starting subtest: alloc_fence
[308.461286] Subtest alloc_fence: SUCCESS (0.000s)
[308.529456] [041/132] (721s left) kms_color_pipeline (plane-lut1d-pre-ctm3x4)
[308.638536] Starting subtest: plane-lut1d-pre-ctm3x4
[308.658262] Starting dynamic subtest: pipe-A-eDP-1
[309.122098] Dynamic subtest pipe-A-eDP-1: FAIL (0.320s)
[310.102105] Starting dynamic subtest: pipe-B-eDP-1
[310.595893] Dynamic subtest pipe-B-eDP-1: FAIL (0.313s)
[311.445108] Starting dynamic subtest: pipe-C-eDP-1
[311.911862] Dynamic subtest pipe-C-eDP-1: FAIL (0.257s)
[312.780747] Starting dynamic subtest: pipe-D-eDP-1
[313.223729] Dynamic subtest pipe-D-eDP-1: FAIL (0.235s)
[313.286483] Subtest plane-lut1d-pre-ctm3x4: FAIL (4.646s)
[314.173704] [042/132] (715s left) gem_lmem_swapping (parallel-random)
[314.213147] Subtest parallel-random: SKIP (0.000s)
[314.251108] [043/132] (715s left) kms_plane_alpha_blend (alpha-transparent-fb)
[314.344124] Starting subtest: alpha-transparent-fb
[314.633964] Starting dynamic subtest: pipe-A-eDP-1
[315.234963] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.600s)
[315.242944] Starting dynamic subtest: pipe-D-eDP-1
[316.571027] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.335s)
[316.571870] Subtest alpha-transparent-fb: SUCCESS (2.227s)
[317.430061] [044/132] (712s left) kms_big_fb (linear-addfb)
[317.532482] Starting subtest: linear-addfb
[317.533528] Subtest linear-addfb: SUCCESS (0.000s)
[317.652722] [045/132] (712s left) syncobj_timeline (invalid-wait-illegal-handle)
[317.713010] Starting subtest: invalid-wait-illegal-handle
[317.714216] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s)
[317.836784] [046/132] (711s left) gem_exec_params (sol-reset-invalid)
[317.899496] Starting subtest: sol-reset-invalid
[317.902380] Subtest sol-reset-invalid: SUCCESS (0.003s)
[319.687578] [047/132] (710s left) gem_flink_basic (bad-flink)
[319.733240] Starting subtest: bad-flink
[319.734189] Subtest bad-flink: SUCCESS (0.000s)
[319.846013] [048/132] (709s left) gem_exec_fence (syncobj-timeline-repeat)
[320.412848] Starting subtest: syncobj-timeline-repeat
[320.644900] Subtest syncobj-timeline-repeat: SUCCESS (0.232s)
[321.696601] [049/132] (708s left) syncobj_wait (signal)
[321.750563] Starting subtest: signal
[321.752001] Subtest signal: SUCCESS (0.000s)
[321.867313] [050/132] (708s left) gem_exec_schedule (implicit-write-read)
[321.923628] Starting subtest: implicit-write-read
[321.924555] Starting dynamic subtest: rcs0
[322.118692] Dynamic subtest rcs0: SUCCESS (0.192s)
[322.129764] Starting dynamic subtest: vcs0
[322.257464] Dynamic subtest vcs0: SUCCESS (0.140s)
[322.268366] Starting dynamic subtest: vcs1
[322.361376] Dynamic subtest vcs1: SUCCESS (0.103s)
[322.372150] Starting dynamic subtest: vecs0
[322.476297] Dynamic subtest vecs0: SUCCESS (0.114s)
[322.486943] Starting dynamic subtest: ccs0
[322.596193] Dynamic subtest ccs0: SUCCESS (0.118s)
[322.597903] Subtest implicit-write-read: SUCCESS (0.672s)
[323.727474] [051/132] (706s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-mmap-wc)
[323.829691] Starting subtest: fbcpsr-rgb101010-draw-mmap-wc
[330.526209] Subtest fbcpsr-rgb101010-draw-mmap-wc: SUCCESS (6.694s)
[330.683457] [052/132] (699s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-mc-ccs)
[330.769945] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-mc-ccs
[330.770770] Starting dynamic subtest: pipe-A-eDP-1
[330.791492] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[330.801585] Starting dynamic subtest: pipe-B-eDP-1
[330.809476] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[330.817093] Starting dynamic subtest: pipe-C-eDP-1
[330.824937] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[330.832334] Starting dynamic subtest: pipe-D-eDP-1
[330.840551] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[330.842178] Subtest crc-primary-rotation-180-4-tiled-dg2-mc-ccs: SKIP (0.006s)
[333.017730] [053/132] (696s left) kms_chamelium_frames (hdmi-crc-nonplanar-formats)
[333.399862] Subtest hdmi-crc-nonplanar-formats: SKIP (0.000s)
[334.205333] [054/132] (695s left) gem_ctx_engines (independent)
[334.258554] Starting subtest: independent
[334.259509] Starting dynamic subtest: rcs0
[334.390720] Dynamic subtest rcs0: SUCCESS (0.130s)
[334.400849] Starting dynamic subtest: bcs0
[334.519612] Dynamic subtest bcs0: SUCCESS (0.128s)
[334.528049] Starting dynamic subtest: vcs0
[334.629645] Dynamic subtest vcs0: SUCCESS (0.111s)
[334.638284] Starting dynamic subtest: vcs1
[334.739857] Dynamic subtest vcs1: SUCCESS (0.109s)
[334.750243] Starting dynamic subtest: vecs0
[334.867589] Dynamic subtest vecs0: SUCCESS (0.126s)
[334.877615] Starting dynamic subtest: ccs0
[334.997221] Dynamic subtest ccs0: SUCCESS (0.128s)
[335.008310] Starting dynamic subtest: all-engines
[335.400834] Dynamic subtest all-engines: SUCCESS (0.403s)
[335.402422] Subtest independent: SUCCESS (1.142s)
[336.805377] [055/132] (693s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0)
[336.905107] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-0
[337.861329] Subtest 4-tiled-max-hw-stride-32bpp-rotate-0: SUCCESS (0.956s)
[338.013507] [056/132] (692s left) kms_frontbuffer_tracking (fbc-1p-primscrn-shrfb-plflip-blt)
[338.124627] Starting subtest: fbc-1p-primscrn-shrfb-plflip-blt
[342.858616] Subtest fbc-1p-primscrn-shrfb-plflip-blt: SUCCESS (4.731s)
[344.062915] [057/132] (686s left) syncobj_wait (wait-for-submit-complex)
[344.117253] Starting subtest: wait-for-submit-complex
[344.180167] Subtest wait-for-submit-complex: SUCCESS (0.063s)
[344.273710] [058/132] (685s left) gem_eio (context-create)
[344.520054] Starting subtest: context-create
[344.894917] Subtest context-create: SUCCESS (0.375s)
[345.049380] [059/132] (685s left) gem_exec_balancer (bonded-chain)
[345.111561] Starting subtest: bonded-chain
[352.140632] Subtest bonded-chain: SUCCESS (7.026s)
[352.659893] [060/132] (677s left) api_intel_allocator (simple-allocator)
[352.716038] Starting subtest: simple-allocator
[352.717044] Starting dynamic subtest: basic
[352.720443] Dynamic subtest basic: SUCCESS (0.006s)
[352.732347] Starting dynamic subtest: parallel-one
[352.898862] Dynamic subtest parallel-one: SUCCESS (0.175s)
[352.909693] Starting dynamic subtest: print
[352.910714] Dynamic subtest print: SUCCESS (0.000s)
[352.918664] Starting dynamic subtest: reuse
[352.919626] Dynamic subtest reuse: SUCCESS (0.000s)
[352.927588] Starting dynamic subtest: reserve
[352.928532] Dynamic subtest reserve: SUCCESS (0.000s)
[352.936366] Starting dynamic subtest: fork-reopen-allocator
[352.937283] Dynamic subtest fork-reopen-allocator: SUCCESS (0.009s)
[352.938212] Subtest simple-allocator: SUCCESS (0.196s)
[353.062112] [061/132] (677s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-mmap-cpu)
[353.176467] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-mmap-cpu
[357.193297] Subtest fbc-1p-offscreen-pri-indfb-draw-mmap-cpu: SUCCESS (4.016s)
[358.365681] [062/132] (671s left) gem_exec_basic (basic)
[358.415166] Starting subtest: basic
[358.416098] Starting dynamic subtest: rcs0-smem
[358.418510] Dynamic subtest rcs0-smem: SUCCESS (0.003s)
[358.428284] Starting dynamic subtest: bcs0-smem
[358.429167] Dynamic subtest bcs0-smem: SUCCESS (0.002s)
[358.437594] Starting dynamic subtest: vcs0-smem
[358.438523] Dynamic subtest vcs0-smem: SUCCESS (0.002s)
[358.446484] Starting dynamic subtest: vcs1-smem
[358.447434] Dynamic subtest vcs1-smem: SUCCESS (0.002s)
[358.456128] Starting dynamic subtest: vecs0-smem
[358.457018] Dynamic subtest vecs0-smem: SUCCESS (0.002s)
[358.464784] Starting dynamic subtest: ccs0-smem
[358.465677] Dynamic subtest ccs0-smem: SUCCESS (0.002s)
[358.466561] Subtest basic: SUCCESS (0.017s)
[359.636154] [063/132] (670s left) gem_mmap_gtt (basic-small-bo-tiledy)
[359.703898] Subtest basic-small-bo-tiledY: SKIP (0.000s)
[359.775366] [064/132] (670s left) gem_mmap_wc (bad-size)
[359.843166] Subtest bad-size: SKIP (0.000s)
[359.906397] [065/132] (670s left) sw_sync (sync_multi_consumer)
[359.941884] Starting subtest: sync_multi_consumer
[360.351840] Subtest sync_multi_consumer: SUCCESS (0.411s)
[360.432300] [066/132] (670s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180-hflip)
[360.529011] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180-hflip
[360.530673] Subtest y-tiled-max-hw-stride-64bpp-rotate-180-hflip: SKIP (0.000s)
[360.641164] [067/132] (669s left) kms_frontbuffer_tracking (fbcpsr-2p-pri-indfb-multidraw)
[360.740361] Starting subtest: fbcpsr-2p-pri-indfb-multidraw
[360.742118] Subtest fbcpsr-2p-pri-indfb-multidraw: SKIP (0.000s)
[360.844912] [068/132] (669s left) syncobj_wait (multi-wait-for-submit-signaled)
[360.907499] Starting subtest: multi-wait-for-submit-signaled
[360.908498] Subtest multi-wait-for-submit-signaled: SUCCESS (0.000s)
[361.034002] [069/132] (669s left) gem_mmap_gtt (cpuset-big-copy)
[361.086124] Subtest cpuset-big-copy: SKIP (0.000s)
[361.146415] [070/132] (669s left) prime_busy (hang-wait)
[361.197708] Starting subtest: hang-wait
[361.198901] Starting dynamic subtest: rcs0
[387.157180] Dynamic subtest rcs0: SUCCESS (25.963s)
[387.166693] Starting dynamic subtest: bcs0
[394.222782] Dynamic subtest bcs0: SUCCESS (7.062s)
[394.231060] Starting dynamic subtest: vcs0
[401.257708] Dynamic subtest vcs0: SUCCESS (7.030s)
[401.266854] Starting dynamic subtest: vcs1
[408.167319] Dynamic subtest vcs1: SUCCESS (6.907s)
[408.177173] Starting dynamic subtest: vecs0
[415.269880] Dynamic subtest vecs0: SUCCESS (7.099s)
[415.277874] Starting dynamic subtest: ccs0
[429.050869] Dynamic subtest ccs0: SUCCESS (13.775s)
[429.051894] Subtest hang-wait: SUCCESS (67.850s)
[429.206357] [071/132] (601s left) kms_frontbuffer_tracking (fbc-rgb565-draw-pwrite)
[429.310467] Starting subtest: fbc-rgb565-draw-pwrite
[433.951753] Subtest fbc-rgb565-draw-pwrite: SUCCESS (4.640s)
[435.165180] [072/132] (595s left) kms_cursor_crc (cursor-offscreen-128x128)
[435.364521] Starting subtest: cursor-offscreen-128x128
[435.367920] Starting dynamic subtest: pipe-A-eDP-1
[437.335331] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.967s)
[437.366152] Starting dynamic subtest: pipe-D-eDP-1
[439.955895] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.596s)
[439.956711] Subtest cursor-offscreen-128x128: SUCCESS (4.590s)
[440.803976] [073/132] (589s left) gem_ctx_param (invalid-set-no-zeromap)
[440.864549] Starting subtest: invalid-set-no-zeromap
[440.865600] Subtest invalid-set-no-zeromap: SUCCESS (0.000s)
[440.965488] [074/132] (589s left) kms_rotation_crc (primary-yf-tiled-reflect-x-180)
[441.053887] Starting subtest: primary-yf-tiled-reflect-x-180
[441.113274] Subtest primary-yf-tiled-reflect-x-180: SKIP (0.059s)
[441.230801] [075/132] (589s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-pwrite)
[441.336610] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-pwrite
[445.675575] Subtest fbc-1p-primscrn-spr-indfb-draw-pwrite: SUCCESS (4.338s)
[446.902917] [076/132] (583s left) kms_selftest (drm_cmdline_parser)
[446.938462] Starting subtest: drm_cmdline_parser
[446.978505] Starting dynamic subtest: drm_test_cmdline_force_d_only
[447.106968] Dynamic subtest drm_test_cmdline_force_d_only: SUCCESS (0.128s)
[447.115620] Starting dynamic subtest: drm_test_cmdline_force_D_only_dvi
[447.262134] Dynamic subtest drm_test_cmdline_force_D_only_dvi: SUCCESS (0.154s)
[447.271557] Starting dynamic subtest: drm_test_cmdline_force_D_only_hdmi
[447.396853] Dynamic subtest drm_test_cmdline_force_D_only_hdmi: SUCCESS (0.134s)
[447.404599] Starting dynamic subtest: drm_test_cmdline_force_D_only_not_digital
[447.531739] Dynamic subtest drm_test_cmdline_force_D_only_not_digital: SUCCESS (0.134s)
[447.540076] Starting dynamic subtest: drm_test_cmdline_force_e_only
[447.647013] Dynamic subtest drm_test_cmdline_force_e_only: SUCCESS (0.115s)
[447.656293] Starting dynamic subtest: drm_test_cmdline_res
[447.776412] Dynamic subtest drm_test_cmdline_res: SUCCESS (0.129s)
[447.784212] Starting dynamic subtest: drm_test_cmdline_res_vesa
[447.901221] Dynamic subtest drm_test_cmdline_res_vesa: SUCCESS (0.124s)
[447.909771] Starting dynamic subtest: drm_test_cmdline_res_vesa_rblank
[448.038481] Dynamic subtest drm_test_cmdline_res_vesa_rblank: SUCCESS (0.136s)
[448.047039] Starting dynamic subtest: drm_test_cmdline_res_rblank
[448.160197] Dynamic subtest drm_test_cmdline_res_rblank: SUCCESS (0.120s)
[448.169535] Starting dynamic subtest: drm_test_cmdline_res_bpp
[448.302552] Dynamic subtest drm_test_cmdline_res_bpp: SUCCESS (0.143s)
[448.311879] Starting dynamic subtest: drm_test_cmdline_res_refresh
[448.441027] Dynamic subtest drm_test_cmdline_res_refresh: SUCCESS (0.134s)
[448.449968] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh
[448.578265] Dynamic subtest drm_test_cmdline_res_bpp_refresh: SUCCESS (0.137s)
[448.587352] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_interlaced
[448.707994] Dynamic subtest drm_test_cmdline_res_bpp_refresh_interlaced: SUCCESS (0.128s)
[448.716404] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_margins
[448.830745] Dynamic subtest drm_test_cmdline_res_bpp_refresh_margins: SUCCESS (0.121s)
[448.838728] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_force_off
[448.923656] Dynamic subtest drm_test_cmdline_res_bpp_refresh_force_off: SUCCESS (0.094s)
[448.932781] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_force_on
[449.043087] Dynamic subtest drm_test_cmdline_res_bpp_refresh_force_on: SUCCESS (0.118s)
[449.050900] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_force_on_analog
[449.141455] Dynamic subtest drm_test_cmdline_res_bpp_refresh_force_on_analog: SUCCESS (0.097s)
[449.150859] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_force_on_digital
[449.257100] Dynamic subtest drm_test_cmdline_res_bpp_refresh_force_on_digital: SUCCESS (0.114s)
[449.266520] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_interlaced_margins_force_on
[449.383500] Dynamic subtest drm_test_cmdline_res_bpp_refresh_interlaced_margins_force_on: SUCCESS (0.127s)
[449.392593] Starting dynamic subtest: drm_test_cmdline_res_margins_force_on
[449.490651] Dynamic subtest drm_test_cmdline_res_margins_force_on: SUCCESS (0.105s)
[449.500185] Starting dynamic subtest: drm_test_cmdline_res_vesa_margins
[449.626378] Dynamic subtest drm_test_cmdline_res_vesa_margins: SUCCESS (0.134s)
[449.634312] Starting dynamic subtest: drm_test_cmdline_name
[449.748323] Dynamic subtest drm_test_cmdline_name: SUCCESS (0.120s)
[449.757876] Starting dynamic subtest: drm_test_cmdline_name_bpp
[449.869361] Dynamic subtest drm_test_cmdline_name_bpp: SUCCESS (0.122s)
[449.877302] Starting dynamic subtest: drm_test_cmdline_name_option
[450.004105] Dynamic subtest drm_test_cmdline_name_option: SUCCESS (0.131s)
[450.013558] Starting dynamic subtest: drm_test_cmdline_name_bpp_option
[450.132457] Dynamic subtest drm_test_cmdline_name_bpp_option: SUCCESS (0.128s)
[450.140335] Starting dynamic subtest: drm_test_cmdline_rotate_0
[450.246910] Dynamic subtest drm_test_cmdline_rotate_0: SUCCESS (0.113s)
[450.256011] Starting dynamic subtest: drm_test_cmdline_rotate_90
[450.368330] Dynamic subtest drm_test_cmdline_rotate_90: SUCCESS (0.122s)
[450.376277] Starting dynamic subtest: drm_test_cmdline_rotate_180
[450.483109] Dynamic subtest drm_test_cmdline_rotate_180: SUCCESS (0.112s)
[450.491778] Starting dynamic subtest: drm_test_cmdline_rotate_270
[450.614210] Dynamic subtest drm_test_cmdline_rotate_270: SUCCESS (0.131s)
[450.622032] Starting dynamic subtest: drm_test_cmdline_hmirror
[450.741297] Dynamic subtest drm_test_cmdline_hmirror: SUCCESS (0.125s)
[450.750221] Starting dynamic subtest: drm_test_cmdline_vmirror
[450.825993] Dynamic subtest drm_test_cmdline_vmirror: SUCCESS (0.086s)
[450.835074] Starting dynamic subtest: drm_test_cmdline_margin_options
[450.927253] Dynamic subtest drm_test_cmdline_margin_options: SUCCESS (0.098s)
[450.940958] Starting dynamic subtest: drm_test_cmdline_multiple_options
[451.066183] Dynamic subtest drm_test_cmdline_multiple_options: SUCCESS (0.136s)
[451.074892] Starting dynamic subtest: drm_test_cmdline_bpp_extra_and_option
[451.205047] Dynamic subtest drm_test_cmdline_bpp_extra_and_option: SUCCESS (0.138s)
[451.214397] Starting dynamic subtest: drm_test_cmdline_extra_and_option
[451.342899] Dynamic subtest drm_test_cmdline_extra_and_option: SUCCESS (0.137s)
[451.352258] Starting dynamic subtest: drm_test_cmdline_freestanding_options
[451.495274] Dynamic subtest drm_test_cmdline_freestanding_options: SUCCESS (0.151s)
[451.504798] Starting dynamic subtest: drm_test_cmdline_freestanding_force_e_and_options
[451.622633] Dynamic subtest drm_test_cmdline_freestanding_force_e_and_options: SUCCESS (0.128s)
[451.630427] Starting dynamic subtest: drm_test_cmdline_panel_orientation
[451.737869] Dynamic subtest drm_test_cmdline_panel_orientation: SUCCESS (0.115s)
[451.745591] Starting dynamic subtest: drm_test_cmdline_invalid
[451.854840] Dynamic subtest drm_test_cmdline_invalid: SUCCESS (0.116s)
[451.862563] Starting dynamic subtest: drm_test_cmdline_tv_options
[451.960644] Dynamic subtest drm_test_cmdline_tv_options: SUCCESS (0.105s)
[451.961478] Subtest drm_cmdline_parser: SUCCESS (5.020s)
[452.192987] [077/132] (578s left) gem_ctx_param (set-priority-not-supported)
[452.239501] Starting subtest: set-priority-not-supported
[452.241221] Subtest set-priority-not-supported: SKIP (0.000s)
[452.322669] [078/132] (578s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-render)
[452.426306] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-render
[452.428012] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[452.549627] [079/132] (578s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180)
[452.651493] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180
[452.653289] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180: SKIP (0.000s)
[452.792434] [080/132] (578s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-180)
[452.892662] Starting subtest: linear-max-hw-stride-32bpp-rotate-180
[453.971827] Subtest linear-max-hw-stride-32bpp-rotate-180: SUCCESS (1.079s)
[454.134312] [081/132] (576s left) kms_plane_multiple (tiling-4)
[454.233863] Starting subtest: tiling-4
[454.234684] Starting dynamic subtest: pipe-A-eDP-1
[457.921065] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.686s)
[457.930568] Starting dynamic subtest: pipe-B-eDP-1
[462.368739] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.444s)
[462.377085] Starting dynamic subtest: pipe-C-eDP-1
[466.802695] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.431s)
[466.811074] Starting dynamic subtest: pipe-D-eDP-1
[471.137326] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.332s)
[471.138127] Subtest tiling-4: SUCCESS (16.900s)
[471.955301] [082/132] (559s left) kms_ccs (missing-ccs-buffer-y-tiled-gen12-mc-ccs)
[472.045066] Starting subtest: missing-ccs-buffer-y-tiled-gen12-mc-ccs
[472.046220] Starting dynamic subtest: pipe-A-eDP-1
[472.055732] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[472.065691] Starting dynamic subtest: pipe-B-eDP-1
[472.073731] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[472.081956] Starting dynamic subtest: pipe-C-eDP-1
[472.089624] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[472.097282] Starting dynamic subtest: pipe-D-eDP-1
[472.105277] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[472.106856] Subtest missing-ccs-buffer-y-tiled-gen12-mc-ccs: SKIP (0.005s)
[474.229249] [083/132] (556s left) kms_chamelium_hpd (vga-hpd-after-suspend)
[474.615650] Subtest vga-hpd-after-suspend: SKIP (0.000s)
[475.425132] [084/132] (555s left) perf (gen12-group-exclusive-stream-ctx-handle)
[475.561827] Starting subtest: gen12-group-exclusive-stream-ctx-handle
[475.587016] Subtest gen12-group-exclusive-stream-ctx-handle: SUCCESS (0.025s)
[475.623369] [085/132] (555s left) kms_setmode (basic)
[477.568209] Starting subtest: basic
[477.889890] Starting dynamic subtest: pipe-A-eDP-1
[479.976471] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.082s)
[480.326858] Starting dynamic subtest: pipe-B-eDP-1
[482.404581] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.082s)
[482.716169] Subtest basic: SUCCESS (5.153s)
[483.560007] [086/132] (547s left) kms_psr (fbc-psr-primary-render)
[483.666557] Starting subtest: fbc-psr-primary-render
[483.667858] Starting dynamic subtest: eDP-1
[483.669591] Dynamic subtest eDP-1: SKIP (0.000s)
[483.671219] Subtest fbc-psr-primary-render: SKIP (0.001s)
[483.759999] [087/132] (547s left) gem_create (create-valid-nonaligned)
[483.810207] Starting subtest: create-valid-nonaligned
[483.811055] Subtest create-valid-nonaligned: SUCCESS (0.000s)
[484.012869] [088/132] (547s left) kms_atomic_transition (plane-all-transition-fencing)
[484.107328] Starting subtest: plane-all-transition-fencing
[484.108275] Starting dynamic subtest: pipe-A-eDP-1
[486.601183] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.494s)
[486.917747] Starting dynamic subtest: pipe-B-eDP-1
[490.172664] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.263s)
[490.469215] Subtest plane-all-transition-fencing: SUCCESS (6.364s)
[491.317549] [089/132] (539s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-indfb-msflip-blt)
[491.410240] Starting subtest: fbcpsr-2p-scndscrn-indfb-msflip-blt
[491.412054] Subtest fbcpsr-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[491.555064] [090/132] (539s left) kms_lease (lease-unleased-connector)
[491.644283] Starting subtest: lease-unleased-connector
[491.645159] Starting dynamic subtest: pipe-A-eDP-1
[491.651012] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.006s)
[491.659557] Starting dynamic subtest: pipe-B-eDP-1
[491.660445] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.006s)
[491.669011] Starting dynamic subtest: pipe-C-eDP-1
[491.669995] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.006s)
[491.678553] Starting dynamic subtest: pipe-D-eDP-1
[491.679536] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.007s)
[491.680388] Subtest lease-unleased-connector: SUCCESS (0.031s)
[491.804585] [091/132] (539s left) drm_read (short-buffer-nonblock)
[491.946659] Starting subtest: short-buffer-nonblock
[491.947703] Subtest short-buffer-nonblock: SUCCESS (0.000s)
[492.125292] [092/132] (539s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-mmap-wc)
[492.228012] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-mmap-wc
[498.805786] Subtest psr-1p-offscreen-pri-shrfb-draw-mmap-wc: SUCCESS (6.578s)
[498.962259] [093/132] (532s left) kms_ccs (crc-sprite-planes-basic-4-tiled-dg2-rc-ccs)
[499.059626] Starting subtest: crc-sprite-planes-basic-4-tiled-dg2-rc-ccs
[499.060647] Starting dynamic subtest: pipe-A-eDP-1
[499.083475] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[499.103179] Starting dynamic subtest: pipe-B-eDP-1
[499.126990] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[499.136244] Starting dynamic subtest: pipe-C-eDP-1
[499.158149] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[499.166548] Starting dynamic subtest: pipe-D-eDP-1
[499.188904] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[499.190588] Subtest crc-sprite-planes-basic-4-tiled-dg2-rc-ccs: SKIP (0.010s)
[501.316723] [094/132] (530s left) kms_pm_rpm (fences-dpms)
[502.841208] Starting subtest: fences-dpms
[502.843647] Subtest fences-dpms: SKIP (0.000s)
[503.574962] [095/132] (527s left) i915_getparams_basic (basic-subslice-total)
[503.617678] Starting subtest: basic-subslice-total
[503.619607] Subtest basic-subslice-total: SUCCESS (0.000s)
[503.730378] [096/132] (527s left) gem_exec_fence (submit67)
[503.904690] Starting subtest: submit67
[503.906420] Subtest submit67: SKIP (0.000s)
[505.749774] [097/132] (525s left) kms_plane_scaling (planes-unity-scaling-downscale-factor-0-5)
[505.846060] Starting subtest: planes-unity-scaling-downscale-factor-0-5
[505.846902] Starting dynamic subtest: pipe-A
[505.862490] Dynamic subtest pipe-A: SKIP (0.015s)
[505.870621] Starting dynamic subtest: pipe-B
[505.892554] Dynamic subtest pipe-B: SKIP (0.027s)
[505.900937] Starting dynamic subtest: pipe-C
[505.920421] Dynamic subtest pipe-C: SKIP (0.028s)
[505.928666] Starting dynamic subtest: pipe-D
[505.949290] Dynamic subtest pipe-D: SKIP (0.028s)
[505.950933] Subtest planes-unity-scaling-downscale-factor-0-5: SKIP (0.101s)
[506.078079] [098/132] (525s left) sw_sync (sync_busy)
[506.107590] Starting subtest: sync_busy
[506.110086] Subtest sync_busy: SUCCESS (0.002s)
[506.186141] [099/132] (525s left) i915_pm_rpm (sysfs-read)
[507.801860] Starting subtest: sysfs-read
[508.946169] Subtest sysfs-read: SUCCESS (1.143s)
[509.337995] [100/132] (522s left) kms_flip (flip-vs-dpms-on-nop-interruptible)
[509.432171] Starting subtest: flip-vs-dpms-on-nop-interruptible
[509.434886] Starting dynamic subtest: A-eDP1
[511.307344] Dynamic subtest A-eDP1: SUCCESS (1.873s)
[511.317541] Starting dynamic subtest: B-eDP1
[513.131553] Dynamic subtest B-eDP1: SUCCESS (1.821s)
[513.143640] Starting dynamic subtest: C-eDP1
[514.950963] Dynamic subtest C-eDP1: SUCCESS (1.818s)
[514.962076] Starting dynamic subtest: D-eDP1
[516.778285] Dynamic subtest D-eDP1: SUCCESS (1.825s)
[516.783860] Subtest flip-vs-dpms-on-nop-interruptible: SUCCESS (7.352s)
[517.977797] [101/132] (513s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[518.079684] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[518.081412] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[518.212485] [102/132] (513s left) prime_mmap_coherency (write)
[518.280023] Starting subtest: write
[523.664666] Subtest write: SUCCESS (5.382s)
[523.849486] [103/132] (507s left) gem_mmap_wc (read)
[523.910765] Subtest read: SKIP (0.000s)
[523.969654] [104/132] (507s left) sysfs_timeslice_duration (timeout)
[524.020215] Starting subtest: timeout
[524.021199] Starting dynamic subtest: vecs0
[532.593283] Dynamic subtest vecs0: SUCCESS (8.569s)
[532.604414] Starting dynamic subtest: ccs0
[534.099907] Dynamic subtest ccs0: SUCCESS (1.507s)
[534.115675] Starting dynamic subtest: vcs0
[543.597320] Dynamic subtest vcs0: SUCCESS (9.492s)
[543.608123] Starting dynamic subtest: bcs0
[544.429403] Dynamic subtest bcs0: SUCCESS (0.832s)
[544.441454] Starting dynamic subtest: rcs0
[545.914182] Dynamic subtest rcs0: SUCCESS (1.481s)
[545.924388] Starting dynamic subtest: vcs1
[555.625658] Dynamic subtest vcs1: SUCCESS (9.706s)
[555.627297] Subtest timeout: SUCCESS (31.602s)
[555.719635] [105/132] (476s left) gem_softpin (allocator-nopin)
[555.782137] Starting subtest: allocator-nopin
[555.904853] Subtest allocator-nopin: SUCCESS (0.120s)
[556.086912] [106/132] (475s left) gem_mmap_gtt (isolation)
[556.138000] Subtest isolation: SKIP (0.000s)
[556.203520] [107/132] (475s left) kms_flip (flip-vs-absolute-wf_vblank)
[556.302562] Starting subtest: flip-vs-absolute-wf_vblank
[556.304217] Starting dynamic subtest: A-eDP1
[565.208320] Dynamic subtest A-eDP1: SUCCESS (8.903s)
[565.223067] Starting dynamic subtest: B-eDP1
[574.145192] Dynamic subtest B-eDP1: SUCCESS (8.934s)
[574.156139] Starting dynamic subtest: C-eDP1
[583.067554] Dynamic subtest C-eDP1: SUCCESS (8.918s)
[583.077395] Starting dynamic subtest: D-eDP1
[592.004087] Dynamic subtest D-eDP1: SUCCESS (8.934s)
[592.008648] Subtest flip-vs-absolute-wf_vblank: SUCCESS (35.702s)
[593.250605] [108/132] (438s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-pwrite)
[593.357466] Starting subtest: psr-2p-primscrn-spr-indfb-draw-pwrite
[593.359252] Subtest psr-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[593.496026] [109/132] (438s left) syncobj_eventfd (invalid-bad-pad)
[593.557695] Starting subtest: invalid-bad-pad
[593.558708] Subtest invalid-bad-pad: SUCCESS (0.000s)
[593.686025] [110/132] (438s left) kms_big_fb (yf-tiled-32bpp-rotate-180)
[593.809625] Starting subtest: yf-tiled-32bpp-rotate-180
[593.811619] Subtest yf-tiled-32bpp-rotate-180: SKIP (0.000s)
[593.929658] [111/132] (438s left) prime_self_import (export-vs-gem_close-race)
[593.966072] Starting subtest: export-vs-gem_close-race
[599.086854] Subtest export-vs-gem_close-race: SUCCESS (5.118s)
[599.182469] [112/132] (432s left) gem_render_copy (mixed-tiled-to-y-tiled-ccs)
[599.242578] Starting subtest: mixed-tiled-to-y-tiled-ccs
[599.244581] Subtest mixed-tiled-to-y-tiled-ccs: SKIP (0.000s)
[599.447098] [113/132] (432s left) prime_mmap (test_correct)
[599.516124] Starting subtest: test_correct
[599.517051] Starting dynamic subtest: test_correct-smem
[599.550554] Dynamic subtest test_correct-smem: SUCCESS (0.035s)
[599.551690] Subtest test_correct: SUCCESS (0.036s)
[599.669900] [114/132] (432s left) kms_psr2_sf (pr-overlay-plane-update-sf-dmg-area)
[604.724034] Starting subtest: pr-overlay-plane-update-sf-dmg-area
[604.727358] Subtest pr-overlay-plane-update-sf-dmg-area: SKIP (0.002s)
[605.585918] [115/132] (426s left) gem_exec_schedule (u-submit-early-slice)
[605.652479] Starting subtest: u-submit-early-slice
[605.653523] Starting dynamic subtest: rcs0
[605.697659] Dynamic subtest rcs0: SUCCESS (0.045s)
[605.709176] Starting dynamic subtest: bcs0
[605.738241] Dynamic subtest bcs0: SUCCESS (0.040s)
[605.748114] Starting dynamic subtest: vcs0
[605.776094] Dynamic subtest vcs0: SUCCESS (0.037s)
[605.786071] Starting dynamic subtest: vcs1
[605.818609] Dynamic subtest vcs1: SUCCESS (0.042s)
[605.830228] Starting dynamic subtest: vecs0
[605.856049] Dynamic subtest vecs0: SUCCESS (0.037s)
[605.866618] Starting dynamic subtest: ccs0
[605.896256] Dynamic subtest ccs0: SUCCESS (0.039s)
[605.897258] Subtest u-submit-early-slice: SUCCESS (0.244s)
[607.713247] [116/132] (424s left) dumb_buffer (map-valid)
[607.765879] Starting subtest: map-valid
[607.766785] Subtest map-valid: SUCCESS (0.000s)
[607.893111] [117/132] (424s left) kms_atomic_interruptible (legacy-cursor)
[607.988971] Starting subtest: legacy-cursor
[607.989840] Starting dynamic subtest: pipe-A-eDP-1
[615.380194] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.388s)
[615.381167] Subtest legacy-cursor: SUCCESS (7.391s)
[616.249678] [118/132] (415s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-onoff)
[616.344003] Starting subtest: fbc-1p-primscrn-spr-indfb-onoff
[620.337707] Subtest fbc-1p-primscrn-spr-indfb-onoff: SUCCESS (3.993s)
[621.544793] [119/132] (410s left) sysfs_heartbeat_interval (mixed)
[621.597678] Starting subtest: mixed
[621.598744] Starting dynamic subtest: vecs0
[626.852679] Dynamic subtest vecs0: SUCCESS (5.253s)
[626.864462] Starting dynamic subtest: ccs0
[632.104130] Dynamic subtest ccs0: SUCCESS (5.247s)
[632.116612] Starting dynamic subtest: vcs0
[637.357891] Dynamic subtest vcs0: SUCCESS (5.251s)
[637.370682] Starting dynamic subtest: bcs0
[642.588391] Dynamic subtest bcs0: SUCCESS (5.227s)
[642.599362] Starting dynamic subtest: rcs0
[647.811505] Dynamic subtest rcs0: SUCCESS (5.221s)
[647.822298] Starting dynamic subtest: vcs1
[653.061747] Dynamic subtest vcs1: SUCCESS (5.247s)
[653.063214] Subtest mixed: SUCCESS (31.458s)
[653.161054] [120/132] (379s left) gem_exec_reloc (basic-gtt-cpu-noreloc)
[653.227975] Subtest basic-gtt-cpu-noreloc: SKIP (0.000s)
[653.307496] [121/132] (379s left) kms_dsc (dsc-with-output-formats)
[653.408878] Starting subtest: dsc-with-output-formats
[653.417485] Subtest dsc-with-output-formats: SKIP (0.000s)
[653.500644] [122/132] (378s left) kms_ccs (random-ccs-data-yf-tiled-ccs)
[653.594183] Starting subtest: random-ccs-data-yf-tiled-ccs
[653.595072] Starting dynamic subtest: pipe-A-eDP-1
[653.605603] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[653.620058] Starting dynamic subtest: pipe-B-eDP-1
[653.630369] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[653.639704] Starting dynamic subtest: pipe-C-eDP-1
[653.648934] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[653.657204] Starting dynamic subtest: pipe-D-eDP-1
[653.666199] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[653.667785] Subtest random-ccs-data-yf-tiled-ccs: SKIP (0.005s)
[655.825072] [123/132] (376s left) kms_psr (fbc-psr2-cursor-plane-move)
[655.926072] Starting subtest: fbc-psr2-cursor-plane-move
[655.926878] Starting dynamic subtest: eDP-1
[655.928594] Dynamic subtest eDP-1: SKIP (0.000s)
[655.930201] Subtest fbc-psr2-cursor-plane-move: SKIP (0.001s)
[656.018670] [124/132] (376s left) i915_suspend (basic-s2idle-without-i915)
[656.054876] Starting subtest: basic-s2idle-without-i915
[677.036307] Subtest basic-s2idle-without-i915: SUCCESS (5.995s)
[681.177136] [125/132] (355s left) gem_exec_reloc (basic-cpu-gtt)
[681.242299] Subtest basic-cpu-gtt: SKIP (0.000s)
[681.301730] [126/132] (355s left) gem_fenced_exec_thrash (no-spare-fences-busy)
[681.372299] Subtest no-spare-fences-busy: SKIP (0.000s)
[681.466068] [127/132] (355s left) gem_ctx_persistence (userptr)
[681.526956] Starting subtest: userptr
[681.771021] Subtest userptr: SUCCESS (0.243s)
[681.871065] [128/132] (354s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180)
[681.981791] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180
[681.983639] Subtest y-tiled-max-hw-stride-32bpp-rotate-180: SKIP (0.000s)
[682.121144] [129/132] (354s left) kms_cursor_crc (cursor-rapid-movement-512x512)
[682.380729] Starting subtest: cursor-rapid-movement-512x512
[682.382756] Subtest cursor-rapid-movement-512x512: SKIP (0.001s)
[682.590635] [130/132] (354s left) kms_content_protection (atomic-hdcp14)
[682.738515] Starting subtest: atomic-hdcp14
[682.741563] Subtest atomic-hdcp14: SKIP (0.000s)
[682.912735] [131/132] (353s left) syncobj_wait (wait-all-complex)
[682.971096] Starting subtest: wait-all-complex
[683.187682] Subtest wait-all-complex: SUCCESS (0.207s)
[683.282281] [132/132] (353s left) syncobj_timeline (etime-single-wait-all-for-submit-unsubmitted)
[683.326985] Starting subtest: etime-single-wait-all-for-submit-unsubmitted
[683.431188] Subtest etime-single-wait-all-for-submit-unsubmitted: SUCCESS (0.099s)
[683.527429] 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.