[56.657590] Initializing watchdogs
[56.657639]   /dev/watchdog0
[56.670874] [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]
[56.674874] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[56.677045] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[56.680193] [001/128] (960s left) kms_cursor_legacy (flip-vs-cursor-crc-legacy)
[56.860225] Starting subtest: flip-vs-cursor-crc-legacy
[57.517974] Subtest flip-vs-cursor-crc-legacy: SUCCESS (0.657s)
[58.366871] [002/128] (958s left) perf_pmu (rc6-suspend)
[58.407745] Starting subtest: rc6-suspend
[80.911853] Subtest rc6-suspend: SUCCESS (7.526s)
[81.804146] [003/128] (950s left) perf_pmu (busy-double-start)
[81.844588] Starting subtest: busy-double-start
[81.846991] Starting dynamic subtest: rcs0
[85.487686] Dynamic subtest rcs0: FAIL (3.577s)
[85.496643] Starting dynamic subtest: bcs0
[88.993453] Dynamic subtest bcs0: SUCCESS (3.568s)
[89.002823] Starting dynamic subtest: vcs0
[93.442404] Dynamic subtest vcs0: SUCCESS (4.447s)
[93.450763] Starting dynamic subtest: vcs1
[97.488602] Dynamic subtest vcs1: SUCCESS (4.045s)
[97.499363] Starting dynamic subtest: vecs0
[101.468437] Dynamic subtest vecs0: SUCCESS (3.979s)
[101.477670] Starting dynamic subtest: ccs0
[105.102863] Dynamic subtest ccs0: FAIL (3.564s)
[105.105092] Subtest busy-double-start: FAIL (23.182s)
[105.136011] [004/128] (927s left) kms_big_fb (yf-tiled-32bpp-rotate-0)
[105.242002] Starting subtest: yf-tiled-32bpp-rotate-0
[105.246278] Subtest yf-tiled-32bpp-rotate-0: SKIP (0.000s)
[105.333748] [005/128] (926s left) kms_flip (basic-plain-flip)
[105.429818] Starting subtest: basic-plain-flip
[105.432118] Starting dynamic subtest: A-eDP1
[107.084500] Dynamic subtest A-eDP1: SUCCESS (1.655s)
[107.093367] Starting dynamic subtest: B-eDP1
[108.816145] Dynamic subtest B-eDP1: SUCCESS (1.733s)
[108.825135] Starting dynamic subtest: C-eDP1
[110.532415] Dynamic subtest C-eDP1: SUCCESS (1.717s)
[110.540499] Starting dynamic subtest: D-eDP1
[112.263376] Dynamic subtest D-eDP1: SUCCESS (1.732s)
[112.268494] Subtest basic-plain-flip: SUCCESS (6.845s)
[113.505496] [006/128] (918s left) kms_big_fb (x-tiled-addfb-size-overflow)
[113.613826] Starting subtest: x-tiled-addfb-size-overflow
[113.616153] Subtest x-tiled-addfb-size-overflow: SUCCESS (0.000s)
[113.693191] [007/128] (918s left) kms_chamelium_frames (hdmi-crc-fast)
[114.077677] Subtest hdmi-crc-fast: SKIP (0.000s)
[114.901030] [008/128] (917s left) prime_self_import (basic-with_fd_dup)
[114.935370] Starting subtest: basic-with_fd_dup
[114.960244] Subtest basic-with_fd_dup: SUCCESS (0.026s)
[115.058708] [009/128] (917s left) gem_exec_flush (basic-uc-set-default)
[115.133801] Starting subtest: basic-uc-set-default
[120.602037] Subtest basic-uc-set-default: SUCCESS (5.473s)
[120.804540] [010/128] (911s left) gen7_exec_parse (basic-allocation)
[120.868670] Subtest basic-allocation: SKIP (0.000s)
[120.978146] [011/128] (911s left) gem_spin_batch (legacy-resubmit-new)
[121.038829] Starting subtest: legacy-resubmit-new
[121.041137] Starting dynamic subtest: default
[121.052478] Dynamic subtest default: SUCCESS (0.013s)
[121.063966] Starting dynamic subtest: render
[121.066560] Dynamic subtest render: SUCCESS (0.004s)
[121.075571] Starting dynamic subtest: bsd
[121.077954] Dynamic subtest bsd: SUCCESS (0.006s)
[121.085958] Starting dynamic subtest: bsd1
[121.088791] Dynamic subtest bsd1: SUCCESS (0.006s)
[121.096684] Starting dynamic subtest: bsd2
[121.098636] Dynamic subtest bsd2: SUCCESS (0.006s)
[121.105201] Starting dynamic subtest: blt
[121.107014] Dynamic subtest blt: SUCCESS (0.010s)
[121.113687] Starting dynamic subtest: vebox
[121.115486] Dynamic subtest vebox: SUCCESS (0.006s)
[121.117322] Subtest legacy-resubmit-new: SUCCESS (0.054s)
[122.954752] [012/128] (909s left) gem_lmem_swapping (heavy-verify-random-ccs)
[122.995309] Subtest heavy-verify-random-ccs: SKIP (0.000s)
[123.026540] [013/128] (909s left) gem_mmap_gtt (basic-small-bo-tiledx)
[123.085580] Subtest basic-small-bo-tiledX: SKIP (0.000s)
[123.135015] [014/128] (909s left) gem_render_copy_redux (normal)
[123.192214] Starting subtest: normal
[123.660405] Subtest normal: SUCCESS (0.468s)
[123.790016] [015/128] (908s left) gem_pread (self)
[123.850777] Subtest self: SKIP (0.000s)
[123.906973] [016/128] (908s left) kms_cdclk (mode-transition-all-outputs)
[124.005878] Starting subtest: mode-transition-all-outputs
[124.293675] Subtest mode-transition-all-outputs: SKIP (0.283s)
[125.139965] [017/128] (907s left) gem_exec_reloc (basic-write-read-active)
[125.211036] Subtest basic-write-read-active: SKIP (0.000s)
[125.278397] [018/128] (907s left) gem_exec_big (single)
[125.331604] Starting subtest: single
[133.441665] Subtest single: SUCCESS (8.111s)
[134.168195] [019/128] (898s left) gem_exec_params (sol-reset-invalid)
[134.280731] Starting subtest: sol-reset-invalid
[134.287297] Subtest sol-reset-invalid: SUCCESS (0.006s)
[135.973971] [020/128] (896s left) gem_mmap_offset (perf)
[136.045530] Starting subtest: perf
[136.047544] Starting dynamic subtest: smem0
[136.374391] Dynamic subtest smem0: SUCCESS (0.327s)
[136.377105] Subtest perf: SUCCESS (0.327s)
[136.500437] [021/128] (896s left) gem_mmap_gtt (fault-concurrent-x)
[136.560146] Subtest fault-concurrent-X: SKIP (0.000s)
[136.615371] [022/128] (896s left) kms_plane_scaling (planes-downscale-factor-0-25-upscale-20x20)
[136.710764] Starting subtest: planes-downscale-factor-0-25-upscale-20x20
[136.713058] Starting dynamic subtest: pipe-A
[136.727928] Dynamic subtest pipe-A: SKIP (0.012s)
[136.737008] Starting dynamic subtest: pipe-B
[136.752744] Dynamic subtest pipe-B: SKIP (0.022s)
[136.762007] Starting dynamic subtest: pipe-C
[136.794759] Dynamic subtest pipe-C: SKIP (0.023s)
[136.801883] Starting dynamic subtest: pipe-D
[136.810379] Dynamic subtest pipe-D: SKIP (0.023s)
[136.814430] Subtest planes-downscale-factor-0-25-upscale-20x20: SKIP (0.081s)
[136.899824] [023/128] (896s left) gem_exec_fence (basic-wait-all)
[136.965616] Starting subtest: basic-wait-all
[137.060040] Subtest basic-wait-all: SUCCESS (0.097s)
[139.013161] [FACT gem_exec_fence (basic-wait-all)] new: kernel.kmod_is_loaded.vgem: true
[139.016141] [024/128] (893s left) gem_pwrite_snooped
[139.143877] [025/128] (893s left) kms_psr (fbc-psr-primary-mmap-cpu)
[139.253540] Starting subtest: fbc-psr-primary-mmap-cpu
[139.255557] Starting dynamic subtest: eDP-1
[139.259693] Dynamic subtest eDP-1: SKIP (0.000s)
[139.263878] Subtest fbc-psr-primary-mmap-cpu: SKIP (0.000s)
[139.317730] [026/128] (893s left) gem_softpin (allocator-nopin)
[139.379194] Starting subtest: allocator-nopin
[139.463498] Subtest allocator-nopin: SUCCESS (0.085s)
[139.587828] [027/128] (893s left) kms_prime (d3hot)
[141.612650] Subtest D3hot: SKIP (0.000s)
[141.685406] [FACT kms_prime (d3hot)] new: kernel.kmod_is_loaded.amdgpu: true
[141.686060] [FACT kms_prime (d3hot)] new: kernel.kmod_is_loaded.xe: true
[141.688804] [028/128] (891s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling)
[141.784426] Starting subtest: flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling
[141.786672] Starting dynamic subtest: pipe-A-default-mode
[142.072336] Dynamic subtest pipe-A-default-mode: SKIP (0.283s)
[142.076989] Subtest flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling: SKIP (0.284s)
[142.857823] [029/128] (890s left) gem_render_copy (y-tiled-ccs-to-y-tiled)
[142.923535] Starting subtest: y-tiled-ccs-to-y-tiled
[142.928339] Subtest y-tiled-ccs-to-y-tiled: SKIP (0.000s)
[143.095979] [030/128] (890s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-submitted)
[143.158579] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted
[143.160965] Subtest invalid-multi-wait-all-unsubmitted-submitted: SUCCESS (0.000s)
[143.254797] [031/128] (889s left) kms_psr (psr-suspend)
[143.352564] Starting subtest: psr-suspend
[143.354809] Starting dynamic subtest: eDP-1
[161.142400] Dynamic subtest eDP-1: SUCCESS (2.875s)
[161.145192] Subtest psr-suspend: SUCCESS (2.876s)
[161.993818] [032/128] (886s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-shrfb-draw-mmap-cpu)
[162.105963] Starting subtest: fbc-1p-offscren-pri-shrfb-draw-mmap-cpu
[165.166601] Subtest fbc-1p-offscren-pri-shrfb-draw-mmap-cpu: SUCCESS (3.063s)
[165.283077] [033/128] (882s left) kms_chamelium_edid (dp-edid-read)
[165.679024] Subtest dp-edid-read: SKIP (0.000s)
[166.523929] [034/128] (881s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling)
[166.628577] Starting subtest: flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling
[166.631049] Starting dynamic subtest: pipe-A-default-mode
[166.932554] Dynamic subtest pipe-A-default-mode: SKIP (0.300s)
[166.937367] Subtest flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling: SKIP (0.301s)
[167.767295] [035/128] (880s left) gem_ctx_exec (basic-nohangcheck)
[167.828661] Starting subtest: basic-nohangcheck
[168.058072] Subtest basic-nohangcheck: SUCCESS (0.231s)
[168.187876] [036/128] (880s left) kms_cursor_legacy (cursorb-vs-flipa-toggle)
[168.313008] Starting subtest: cursorB-vs-flipA-toggle
[168.317249] Subtest cursorB-vs-flipA-toggle: SKIP (0.001s)
[168.402890] [037/128] (879s left) gem_ctx_persistence (heartbeat-hostile)
[168.470898] Starting subtest: heartbeat-hostile
[168.475697] Subtest heartbeat-hostile: SKIP (0.000s)
[168.575104] [038/128] (879s left) syncobj_wait (reset-signaled)
[168.631639] Starting subtest: reset-signaled
[168.633649] Subtest reset-signaled: SUCCESS (0.000s)
[168.732776] [039/128] (879s left) kms_psr2_sf (pr-primary-plane-update-sf-dmg-area-big-fb)
[173.824219] Starting subtest: pr-primary-plane-update-sf-dmg-area-big-fb
[173.829693] Subtest pr-primary-plane-update-sf-dmg-area-big-fb: SKIP (0.002s)
[174.673142] [040/128] (873s left) kms_cursor_crc (cursor-random-128x42)
[174.877229] Starting subtest: cursor-random-128x42
[174.916204] Subtest cursor-random-128x42: SKIP (0.034s)
[175.125668] [041/128] (873s left) kms_dirtyfb (default-dirtyfb-ioctl)
[175.220686] Starting subtest: default-dirtyfb-ioctl
[175.222658] Starting dynamic subtest: A-eDP-1
[176.848510] Dynamic subtest A-eDP-1: SUCCESS (1.629s)
[176.851198] Subtest default-dirtyfb-ioctl: SUCCESS (1.630s)
[177.707539] [042/128] (870s left) prime_vgem (fence-read-hang)
[177.783524] Subtest fence-read-hang: SKIP (0.000s)
[177.882289] [043/128] (870s left) kms_psr (fbc-psr2-dpms)
[177.986498] Starting subtest: fbc-psr2-dpms
[177.988740] Starting dynamic subtest: eDP-1
[177.992655] Dynamic subtest eDP-1: SKIP (0.000s)
[177.996535] Subtest fbc-psr2-dpms: SKIP (0.000s)
[178.054945] [044/128] (870s left) gem_pipe_control_store_loop (reused-buffer)
[178.129565] Starting subtest: reused-buffer
[180.284424] Subtest reused-buffer: SUCCESS (2.154s)
[180.418710] [045/128] (868s left) syncobj_timeline (wait-any-complex)
[180.479311] Starting subtest: wait-any-complex
[180.541808] Subtest wait-any-complex: SUCCESS (0.062s)
[180.617782] [046/128] (867s left) perf_pmu (all-busy-idle-check-all)
[180.664090] Starting subtest: all-busy-idle-check-all
[181.802409] Subtest all-busy-idle-check-all: SUCCESS (1.136s)
[181.897798] [047/128] (866s left) kms_ccs (missing-ccs-buffer-y-tiled-ccs)
[182.005913] Starting subtest: missing-ccs-buffer-y-tiled-ccs
[182.008149] Starting dynamic subtest: pipe-A-eDP-1
[182.030246] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[182.039091] Starting dynamic subtest: pipe-B-eDP-1
[182.060444] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[182.069873] Starting dynamic subtest: pipe-C-eDP-1
[182.091256] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[182.099150] Starting dynamic subtest: pipe-D-eDP-1
[182.120946] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[182.125316] Subtest missing-ccs-buffer-y-tiled-ccs: SKIP (0.003s)
[184.152754] [048/128] (864s left) kms_atomic_transition (modeset-transition)
[184.259880] Starting subtest: modeset-transition
[184.262126] Starting dynamic subtest: 1x-outputs
[194.756368] Dynamic subtest 1x-outputs: SUCCESS (10.495s)
[195.032066] Subtest modeset-transition: SUCCESS (10.770s)
[195.878868] [049/128] (852s left) kms_plane (plane-panning-top-left)
[195.988656] Starting subtest: plane-panning-top-left
[195.991148] Starting dynamic subtest: pipe-A
[197.617303] Dynamic subtest pipe-A: SUCCESS (1.627s)
[197.628426] Starting dynamic subtest: pipe-B
[199.975800] Dynamic subtest pipe-B: SUCCESS (2.356s)
[199.977915] Subtest plane-panning-top-left: SUCCESS (3.985s)
[200.781756] [050/128] (847s left) gem_exec_fence (syncobj-timeline-invalid-wait)
[201.355840] Starting subtest: syncobj-timeline-invalid-wait
[201.358916] Subtest syncobj-timeline-invalid-wait: SUCCESS (0.004s)
[202.904962] [051/128] (845s left) kms_cursor_crc (cursor-offscreen-512x170)
[203.156341] Starting subtest: cursor-offscreen-512x170
[203.161334] Subtest cursor-offscreen-512x170: SKIP (0.001s)
[203.306042] [052/128] (845s left) kms_joiner (basic-big-joiner)
[203.418440] Starting subtest: basic-big-joiner
[203.423109] Subtest basic-big-joiner: SKIP (0.000s)
[203.475713] [053/128] (845s left) gem_ctx_param (invalid-ctx-set)
[203.534084] Starting subtest: invalid-ctx-set
[203.536312] Subtest invalid-ctx-set: SUCCESS (0.001s)
[203.631913] [054/128] (845s left) kms_flip (flip-vs-dpms-off-vs-modeset-interruptible)
[203.734318] Starting subtest: flip-vs-dpms-off-vs-modeset-interruptible
[203.736696] Starting dynamic subtest: A-eDP1
[207.172120] Dynamic subtest A-eDP1: SUCCESS (3.436s)
[207.182767] Starting dynamic subtest: B-eDP1
[210.354834] Dynamic subtest B-eDP1: SUCCESS (3.182s)
[210.364584] Starting dynamic subtest: C-eDP1
[213.420644] Dynamic subtest C-eDP1: SUCCESS (3.065s)
[213.430460] Starting dynamic subtest: D-eDP1
[216.494444] Dynamic subtest D-eDP1: SUCCESS (3.073s)
[216.497171] Subtest flip-vs-dpms-off-vs-modeset-interruptible: SUCCESS (12.761s)
[217.291700] [055/128] (831s left) gem_create (create-clear)
[217.355088] Starting subtest: create-clear
[217.357538] Starting dynamic subtest: smem0
[251.905093] Dynamic subtest smem0: SUCCESS (34.548s)
[251.907586] Subtest create-clear: SUCCESS (34.549s)
[252.195635] [056/128] (796s left) kms_flip (dpms-off-confusion)
[252.366717] Starting subtest: dpms-off-confusion
[252.369034] Starting dynamic subtest: A-eDP1
[261.097777] Dynamic subtest A-eDP1: SUCCESS (8.731s)
[261.107570] Starting dynamic subtest: B-eDP1
[269.822173] Dynamic subtest B-eDP1: SUCCESS (8.724s)
[269.832290] Starting dynamic subtest: C-eDP1
[278.533904] Dynamic subtest C-eDP1: SUCCESS (8.711s)
[278.542262] Starting dynamic subtest: D-eDP1
[287.245162] Dynamic subtest D-eDP1: SUCCESS (8.710s)
[287.249927] Subtest dpms-off-confusion: SUCCESS (34.885s)
[288.501267] [057/128] (760s left) kms_getfb (getfb2-handle-protection)
[288.556137] Starting subtest: getfb2-handle-protection
[288.580651] Subtest getfb2-handle-protection: SUCCESS (0.025s)
[288.688734] [058/128] (760s left) kms_big_fb (linear-max-hw-stride-64bpp-rotate-180)
[288.795116] Starting subtest: linear-max-hw-stride-64bpp-rotate-180
[289.903454] Subtest linear-max-hw-stride-64bpp-rotate-180: SUCCESS (1.110s)
[290.033826] [059/128] (759s left) gem_create (create-invalid-size)
[290.091998] Starting subtest: create-invalid-size
[290.094316] Subtest create-invalid-size: SUCCESS (0.000s)
[290.276547] [060/128] (758s left) gem_userptr_blits (access-control)
[290.363630] Starting subtest: access-control
[290.368261] Subtest access-control: SKIP (0.000s)
[290.481895] [061/128] (758s left) syncobj_wait (multi-wait-signaled)
[290.534889] Starting subtest: multi-wait-signaled
[290.537307] Subtest multi-wait-signaled: SUCCESS (0.000s)
[290.618003] [062/128] (758s left) syncobj_basic (test-valid-cycle)
[290.671266] Starting subtest: test-valid-cycle
[290.673346] Subtest test-valid-cycle: SUCCESS (0.000s)
[290.769360] [063/128] (758s left) kms_ccs (bad-rotation-90-4-tiled-lnl-ccs)
[290.865577] Starting subtest: bad-rotation-90-4-tiled-lnl-ccs
[290.870000] Subtest bad-rotation-90-4-tiled-lnl-ccs: SKIP (0.000s)
[292.022091] [064/128] (757s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-16bpp-xtile-downscaling)
[292.116775] Starting subtest: flip-64bpp-xtile-to-16bpp-xtile-downscaling
[292.119221] Starting dynamic subtest: pipe-A-default-mode
[293.267660] Dynamic subtest pipe-A-default-mode: SKIP (1.146s)
[293.272430] Subtest flip-64bpp-xtile-to-16bpp-xtile-downscaling: SKIP (1.147s)
[293.426367] [065/128] (755s left) api_intel_bb (intel-bb-blit-x)
[293.475873] Starting subtest: intel-bb-blit-x
[297.076283] Subtest intel-bb-blit-x: SUCCESS (3.601s)
[297.215937] [066/128] (752s left) kms_fbcon_fbt (fbc)
[297.274549] Starting subtest: fbc
[302.510339] Subtest fbc: SUCCESS (5.235s)
[302.606785] [067/128] (746s left) syncobj_eventfd (timeline-wait)
[302.664870] Starting subtest: timeline-wait
[302.672960] Subtest timeline-wait: SUCCESS (0.010s)
[302.757498] [068/128] (746s left) kms_lease (lease-uevent)
[302.851531] Starting subtest: lease-uevent
[303.866431] Subtest lease-uevent: SUCCESS (1.013s)
[303.967835] [069/128] (745s left) kms_ccs (bad-aux-stride-y-tiled-gen12-rc-ccs-cc)
[304.065295] Starting subtest: bad-aux-stride-y-tiled-gen12-rc-ccs-cc
[304.067737] Starting dynamic subtest: pipe-A-eDP-1
[304.092042] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[304.102868] Starting dynamic subtest: pipe-B-eDP-1
[304.125206] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[304.133480] Starting dynamic subtest: pipe-C-eDP-1
[304.155156] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[304.163128] Starting dynamic subtest: pipe-D-eDP-1
[304.185973] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[304.190528] Subtest bad-aux-stride-y-tiled-gen12-rc-ccs-cc: SKIP (0.003s)
[306.241576] [070/128] (743s left) kms_draw_crc (draw-method-mmap-gtt)
[306.343215] Starting subtest: draw-method-mmap-gtt
[306.347431] Subtest draw-method-mmap-gtt: SKIP (0.000s)
[306.443554] [071/128] (743s left) kms_plane_scaling (planes-upscale-20x20-downscale-factor-0-5)
[306.536559] Starting subtest: planes-upscale-20x20-downscale-factor-0-5
[306.538890] Starting dynamic subtest: pipe-A
[306.549825] Dynamic subtest pipe-A: SKIP (0.004s)
[306.560235] Starting dynamic subtest: pipe-B
[306.569656] Dynamic subtest pipe-B: SKIP (0.015s)
[306.578552] Starting dynamic subtest: pipe-C
[306.587123] Dynamic subtest pipe-C: SKIP (0.018s)
[306.595633] Starting dynamic subtest: pipe-D
[306.604179] Dynamic subtest pipe-D: SKIP (0.016s)
[306.608121] Subtest planes-upscale-20x20-downscale-factor-0-5: SKIP (0.055s)
[306.708254] [072/128] (742s left) i915_pm_rpm (debugfs-read)
[308.964012] Starting subtest: debugfs-read
[310.134805] Subtest debugfs-read: SUCCESS (1.169s)
[310.485963] [073/128] (739s left) i915_suspend (basic-s2idle-without-i915)
[310.524527] Starting subtest: basic-s2idle-without-i915
[330.990983] Subtest basic-s2idle-without-i915: SUCCESS (5.620s)
[331.077983] [074/128] (733s left) kms_cursor_legacy (short-flip-after-cursor-atomic-transitions-varying-size)
[331.202700] Starting subtest: short-flip-after-cursor-atomic-transitions-varying-size
[332.185122] Subtest short-flip-after-cursor-atomic-transitions-varying-size: SUCCESS (0.981s)
[333.020355] [075/128] (731s left) i915_pm_rpm (system-suspend-devices)
[334.761419] Starting subtest: system-suspend-devices
[342.330649] Subtest system-suspend-devices: SUCCESS (7.563s)
[342.679401] [076/128] (721s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-pwrite)
[342.783114] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-pwrite
[342.787251] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[342.897868] [077/128] (721s left) gem_exec_whisper (basic-queues-forked-all)
[342.963913] Starting subtest: basic-queues-forked-all
[362.825106] Subtest basic-queues-forked-all: SUCCESS (19.856s)
[363.004545] [078/128] (701s left) syncobj_timeline (single-wait-all-for-submit-available-signaled)
[363.064184] Starting subtest: single-wait-all-for-submit-available-signaled
[363.066836] Subtest single-wait-all-for-submit-available-signaled: SUCCESS (0.000s)
[363.168634] [079/128] (701s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180)
[363.272376] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180
[364.260904] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180: SUCCESS (0.990s)
[364.413198] [080/128] (700s left) kms_psr2_sf (fbc-psr2-overlay-plane-update-sf-dmg-area)
[369.414099] Starting subtest: fbc-psr2-overlay-plane-update-sf-dmg-area
[369.416528] Starting dynamic subtest: pipe-A-eDP-1
[370.221810] Dynamic subtest pipe-A-eDP-1: SKIP (0.802s)
[370.232140] Starting dynamic subtest: pipe-B-eDP-1
[371.342765] Dynamic subtest pipe-B-eDP-1: SKIP (1.119s)
[371.347377] Subtest fbc-psr2-overlay-plane-update-sf-dmg-area: SKIP (1.926s)
[372.518155] [081/128] (692s left) kms_frontbuffer_tracking (fbcpsr-2p-pri-indfb-multidraw)
[372.619833] Starting subtest: fbcpsr-2p-pri-indfb-multidraw
[372.623860] Subtest fbcpsr-2p-pri-indfb-multidraw: SKIP (0.000s)
[372.733139] [082/128] (692s left) gem_userptr_blits (coherency-sync)
[372.795511] Starting subtest: coherency-sync
[372.973211] Subtest coherency-sync: SKIP (0.143s)
[373.079083] [083/128] (691s left) gem_render_copy (yf-tiled-ccs-to-x-tiled)
[373.196565] Starting subtest: yf-tiled-ccs-to-x-tiled
[373.201072] Subtest yf-tiled-ccs-to-x-tiled: SKIP (0.000s)
[373.375444] [084/128] (691s left) gem_exec_reloc (basic-wc-read-active)
[373.453150] Subtest basic-wc-read-active: SKIP (0.000s)
[373.519860] [085/128] (691s left) prime_mmap (test_refcounting)
[373.591243] Starting subtest: test_refcounting
[373.593676] Starting dynamic subtest: test_refcounting-smem
[373.624944] Dynamic subtest test_refcounting-smem: SUCCESS (0.034s)
[373.627424] Subtest test_refcounting: SUCCESS (0.035s)
[373.718261] [086/128] (691s left) kms_flip (plain-flip-fb-recreate)
[373.829852] Starting subtest: plain-flip-fb-recreate
[373.832124] Starting dynamic subtest: A-eDP1
[382.808338] Dynamic subtest A-eDP1: SUCCESS (8.978s)
[382.819055] Starting dynamic subtest: B-eDP1
[391.736135] Dynamic subtest B-eDP1: SUCCESS (8.926s)
[391.747679] Starting dynamic subtest: C-eDP1
[400.656192] Dynamic subtest C-eDP1: SUCCESS (8.918s)
[400.664393] Starting dynamic subtest: D-eDP1
[409.478472] Dynamic subtest D-eDP1: SUCCESS (8.821s)
[409.480536] Subtest plain-flip-fb-recreate: SUCCESS (35.652s)
[410.647101] [087/128] (654s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-fullscreen)
[410.756701] Starting subtest: psr-1p-primscrn-spr-indfb-fullscreen
[415.292550] Subtest psr-1p-primscrn-spr-indfb-fullscreen: SUCCESS (4.539s)
[415.409875] [088/128] (649s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-blt)
[415.522018] Starting subtest: fbcpsr-rgb101010-draw-blt
[420.183349] Subtest fbcpsr-rgb101010-draw-blt: SUCCESS (4.665s)
[420.302511] [089/128] (644s left) kms_vblank (wait-idle)
[420.406726] Starting subtest: wait-idle
[420.409055] Starting dynamic subtest: pipe-A-eDP-1
[422.183349] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.774s)
[422.197900] Starting dynamic subtest: pipe-D-eDP-1
[424.592465] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.406s)
[424.594836] Subtest wait-idle: SUCCESS (4.184s)
[425.407688] [090/128] (639s left) kms_frontbuffer_tracking (psr-1p-offscren-pri-shrfb-draw-mmap-cpu)
[425.510237] Starting subtest: psr-1p-offscren-pri-shrfb-draw-mmap-cpu
[430.033655] Subtest psr-1p-offscren-pri-shrfb-draw-mmap-cpu: SUCCESS (4.526s)
[430.173409] [091/128] (634s left) kms_pm_rpm (basic-rte)
[430.212068] Starting subtest: basic-rte
[433.161492] Subtest basic-rte: SUCCESS (2.951s)
[434.639305] [092/128] (630s left) gem_exec_schedule (preempt-queue-contexts)
[434.735182] Subtest preempt-queue-contexts: SKIP (0.000s)
[435.016653] [093/128] (630s left) template (b)
[435.069140] Starting subtest: B
[435.071531] Subtest B: SUCCESS (0.000s)
[435.162963] [094/128] (629s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[435.268654] Starting subtest: psr-2p-primscrn-spr-indfb-draw-mmap-gtt
[435.272931] Subtest psr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[435.360348] [095/128] (629s left) drm_fdinfo (basics)
[435.405205] Starting subtest: basics
[435.407466] Subtest basics: SUCCESS (0.000s)
[435.472586] [096/128] (629s left) syncobj_timeline (etime-multi-wait-available-unsubmitted)
[435.532103] Starting subtest: etime-multi-wait-available-unsubmitted
[435.638516] Subtest etime-multi-wait-available-unsubmitted: SUCCESS (0.104s)
[435.708019] [097/128] (629s left) kms_scaling_modes (scaling-mode-full-aspect)
[435.816665] Starting subtest: scaling-mode-full-aspect
[435.819169] Starting dynamic subtest: pipe-A-eDP-1
[435.932187] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.115s)
[435.943674] Starting dynamic subtest: pipe-B-eDP-1
[437.153289] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.220s)
[437.163054] Starting dynamic subtest: pipe-C-eDP-1
[438.354312] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.200s)
[438.362999] Starting dynamic subtest: pipe-D-eDP-1
[439.477927] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.122s)
[439.480330] Subtest scaling-mode-full-aspect: SUCCESS (3.661s)
[440.635026] [098/128] (624s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-shrfb-draw-mmap-cpu)
[440.739545] Starting subtest: fbcpsr-1p-offscren-pri-shrfb-draw-mmap-cpu
[445.246888] Subtest fbcpsr-1p-offscren-pri-shrfb-draw-mmap-cpu: SUCCESS (4.510s)
[445.367939] [099/128] (619s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-blt)
[445.476104] Starting subtest: psr-1p-primscrn-spr-indfb-draw-blt
[450.099519] Subtest psr-1p-primscrn-spr-indfb-draw-blt: SUCCESS (4.626s)
[450.249491] [100/128] (615s left) syncobj_timeline (multi-wait-all-available-submitted-signaled)
[450.305162] Starting subtest: multi-wait-all-available-submitted-signaled
[450.307712] Subtest multi-wait-all-available-submitted-signaled: SUCCESS (0.000s)
[450.415551] [101/128] (614s left) kms_psr (psr2-cursor-mmap-cpu)
[450.512763] Starting subtest: psr2-cursor-mmap-cpu
[450.515092] Starting dynamic subtest: eDP-1
[452.201038] Dynamic subtest eDP-1: SUCCESS (1.686s)
[452.203507] Subtest psr2-cursor-mmap-cpu: SUCCESS (1.687s)
[452.981946] [102/128] (612s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-pwrite)
[453.088632] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-pwrite
[453.093097] Subtest fbc-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[453.199268] [103/128] (612s left) fbdev (pan)
[453.232534] Starting subtest: pan
[453.264615] Subtest pan: SUCCESS (0.032s)
[453.331785] [104/128] (612s left) gem_exec_balancer (persistence)
[453.581764] Starting subtest: persistence
[454.318203] Subtest persistence: SUCCESS (0.737s)
[454.431434] [105/128] (611s left) kms_psr (fbc-pr-sprite-mmap-gtt)
[454.531512] Starting subtest: fbc-pr-sprite-mmap-gtt
[454.535728] Subtest fbc-pr-sprite-mmap-gtt: SKIP (0.000s)
[454.598876] [106/128] (610s left) gen9_exec_parse (bb-oversize)
[454.657835] Subtest bb-oversize: SKIP (0.000s)
[454.735111] [107/128] (610s left) core_hotunplug (unbind-rebind)
[454.842334] Starting subtest: unbind-rebind
[460.922122] Subtest unbind-rebind: SUCCESS (6.079s)
[460.955489] [108/128] (604s left) kms_plane_alpha_blend (alpha-7efc)
[462.712850] Starting subtest: alpha-7efc
[463.143144] Starting dynamic subtest: pipe-A-eDP-1
[463.780399] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.635s)
[463.806133] Starting dynamic subtest: pipe-D-eDP-1
[465.101924] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.302s)
[465.104430] Subtest alpha-7efc: SUCCESS (2.388s)
[465.957063] [109/128] (599s left) kms_cursor_crc (cursor-sliding-32x10)
[466.100191] Starting subtest: cursor-sliding-32x10
[466.136779] Subtest cursor-sliding-32x10: SKIP (0.032s)
[466.398391] [110/128] (599s left) kms_ccs (crc-primary-suspend-4-tiled-dg2-mc-ccs)
[466.496567] Starting subtest: crc-primary-suspend-4-tiled-dg2-mc-ccs
[466.498889] Starting dynamic subtest: pipe-A-eDP-1
[466.507980] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[466.517576] Starting dynamic subtest: pipe-B-eDP-1
[466.526394] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[466.536132] Starting dynamic subtest: pipe-C-eDP-1
[466.544430] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[466.552713] Starting dynamic subtest: pipe-D-eDP-1
[466.560600] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[466.564585] Subtest crc-primary-suspend-4-tiled-dg2-mc-ccs: SKIP (0.004s)
[468.601078] [111/128] (597s left) kms_color (invalid-degamma-lut-sizes)
[468.701713] Starting subtest: invalid-degamma-lut-sizes
[468.704136] Starting dynamic subtest: pipe-A
[468.984647] Dynamic subtest pipe-A: SUCCESS (0.282s)
[468.993967] Starting dynamic subtest: pipe-B
[468.996193] Dynamic subtest pipe-B: SUCCESS (0.001s)
[469.005536] Starting dynamic subtest: pipe-C
[469.007943] Dynamic subtest pipe-C: SUCCESS (0.000s)
[469.018001] Starting dynamic subtest: pipe-D
[469.019976] Dynamic subtest pipe-D: SUCCESS (0.000s)
[469.021875] Subtest invalid-degamma-lut-sizes: SUCCESS (0.286s)
[469.843020] [112/128] (595s left) kms_flip (nonexisting-fb)
[469.949753] Starting subtest: nonexisting-fb
[469.952107] Starting dynamic subtest: A-eDP1
[471.116783] Dynamic subtest A-eDP1: SUCCESS (1.166s)
[471.125975] Starting dynamic subtest: B-eDP1
[472.342555] Dynamic subtest B-eDP1: SUCCESS (1.224s)
[472.353473] Starting dynamic subtest: C-eDP1
[473.568515] Dynamic subtest C-eDP1: SUCCESS (1.225s)
[473.579985] Starting dynamic subtest: D-eDP1
[474.792429] Dynamic subtest D-eDP1: SUCCESS (1.223s)
[474.794891] Subtest nonexisting-fb: SUCCESS (4.846s)
[475.956686] [113/128] (589s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-indfb-msflip-blt)
[476.065648] Starting subtest: fbcpsr-2p-scndscrn-indfb-msflip-blt
[476.069713] Subtest fbcpsr-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[476.165527] [114/128] (589s left) gem_render_copy_redux (interruptible)
[476.229498] Starting subtest: interruptible
[476.584030] Subtest interruptible: SUCCESS (0.354s)
[476.723248] [115/128] (589s left) gem_ctx_persistence (processes)
[476.786189] Starting subtest: processes
[478.672750] Subtest processes: SUCCESS (1.886s)
[478.748995] [116/128] (587s left) gem_exec_schedule (fairslice-all)
[478.825920] Starting subtest: fairslice-all
[480.903023] Subtest fairslice-all: SUCCESS (2.079s)
[481.201173] [117/128] (584s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-shrfb-pgflip-blt)
[481.311073] Starting subtest: fbc-2p-scndscrn-shrfb-pgflip-blt
[481.315254] Subtest fbc-2p-scndscrn-shrfb-pgflip-blt: SKIP (0.000s)
[481.398069] [118/128] (584s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[481.506078] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[481.510256] Subtest y-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SKIP (0.000s)
[481.617986] [119/128] (584s left) kms_async_flips (alternate-sync-async-flip)
[481.717518] Starting subtest: alternate-sync-async-flip
[482.076111] Starting dynamic subtest: pipe-A-eDP-1
[484.850408] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.776s)
[485.236171] Starting dynamic subtest: pipe-B-eDP-1
[487.979745] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.754s)
[488.354396] Starting dynamic subtest: pipe-C-eDP-1
[491.109375] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.764s)
[491.473590] Starting dynamic subtest: pipe-D-eDP-1
[494.229519] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.766s)
[494.231665] Subtest alternate-sync-async-flip: SUCCESS (12.514s)
[495.388594] [120/128] (570s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-pwrite)
[495.507145] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-pwrite
[498.935012] Subtest fbc-1p-primscrn-spr-indfb-draw-pwrite: SUCCESS (3.432s)
[499.067772] [121/128] (567s left) gem_exec_suspend (basic-s3-devices)
[499.130192] Starting subtest: basic-S3-devices
[499.132033] Starting dynamic subtest: smem
[509.673799] Dynamic subtest smem: SUCCESS (10.540s)
[509.676468] Subtest basic-S3-devices: SUCCESS (10.541s)
[509.855291] [122/128] (556s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-blt)
[509.969792] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-blt
[509.973819] Subtest fbc-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s)
[510.085814] [123/128] (556s left) kms_vblank (ts-continuation-modeset-rpm)
[510.182266] Starting subtest: ts-continuation-modeset-rpm
[510.184652] Starting dynamic subtest: pipe-A-eDP-1
[512.707079] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.520s)
[512.723076] Starting dynamic subtest: pipe-D-eDP-1
[514.948170] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.240s)
[514.950652] Subtest ts-continuation-modeset-rpm: SUCCESS (4.764s)
[515.734347] [124/128] (550s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-blt)
[515.835875] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-blt
[515.840212] Subtest psr-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s)
[515.953631] [125/128] (550s left) kms_big_fb (y-tiled-16bpp-rotate-0)
[516.058950] Starting subtest: y-tiled-16bpp-rotate-0
[516.063008] Subtest y-tiled-16bpp-rotate-0: SKIP (0.000s)
[516.168540] [126/128] (550s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-wc)
[516.279236] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-wc
[516.283842] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[516.381009] [127/128] (549s left) gem_exec_schedule (semaphore-resolve)
[516.466420] Starting subtest: semaphore-resolve
[516.501152] Subtest semaphore-resolve: SUCCESS (0.042s)
[516.852786] [128/128] (549s left) sw_sync (sync_merge)
[516.887326] Starting subtest: sync_merge
[516.889871] Subtest sync_merge: SUCCESS (0.000s)
[516.938502] Closing watchdogs
Done.