[57.126524] Initializing watchdogs
[57.126567]   /dev/watchdog0
[57.141492] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics]
[57.146494] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.148698] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.152047] [001/133] (960s left) kms_psr (fbc-psr2-sprite-blt)
[57.264840] Starting subtest: fbc-psr2-sprite-blt
[57.267190] Starting dynamic subtest: eDP-1
[57.271654] Dynamic subtest eDP-1: SKIP (0.000s)
[57.276182] Subtest fbc-psr2-sprite-blt: SKIP (0.000s)
[57.335249] [002/133] (960s left) kms_big_fb (linear-8bpp-rotate-90)
[57.442688] Starting subtest: linear-8bpp-rotate-90
[57.480742] Subtest linear-8bpp-rotate-90: SKIP (0.036s)
[57.586698] [003/133] (960s left) kms_pm_rpm (universal-planes-dpms)
[59.137841] Starting subtest: universal-planes-dpms
[59.140270] Starting dynamic subtest: plane-34
[67.439921] Dynamic subtest plane-34: SUCCESS (8.302s)
[67.449307] Starting dynamic subtest: plane-64
[76.051734] Dynamic subtest plane-64: SUCCESS (8.607s)
[76.061511] Starting dynamic subtest: plane-94
[84.361891] Dynamic subtest plane-94: SUCCESS (8.308s)
[84.371388] Starting dynamic subtest: plane-124
[92.780573] Dynamic subtest plane-124: SUCCESS (8.416s)
[92.789207] Starting dynamic subtest: plane-134
[101.230098] Dynamic subtest plane-134: SUCCESS (8.446s)
[101.240278] Starting dynamic subtest: plane-144
[109.545085] Dynamic subtest plane-144: SUCCESS (8.313s)
[109.548092] Subtest universal-planes-dpms: SUCCESS (50.400s)
[109.919277] [004/133] (907s left) tools_test (tools_test)
[109.972162] Starting subtest: tools_test
[110.017857] Subtest tools_test: SUCCESS (0.060s)
[110.076582] [005/133] (907s left) kms_plane_scaling (plane-upscale-20x20-with-modifiers)
[110.180169] Starting subtest: plane-upscale-20x20-with-modifiers
[110.182392] Starting dynamic subtest: pipe-A
[110.514978] Dynamic subtest pipe-A: SUCCESS (0.337s)
[110.527475] Starting dynamic subtest: pipe-B
[111.908818] Dynamic subtest pipe-B: SUCCESS (1.393s)
[111.919505] Starting dynamic subtest: pipe-C
[113.328806] Dynamic subtest pipe-C: SUCCESS (1.420s)
[113.335750] Starting dynamic subtest: pipe-D
[114.769237] Dynamic subtest pipe-D: SUCCESS (1.439s)
[114.771498] Subtest plane-upscale-20x20-with-modifiers: SUCCESS (4.592s)
[115.945547] [006/133] (901s left) kms_color (invalid-ctm-matrix-sizes)
[116.041879] Starting subtest: invalid-ctm-matrix-sizes
[116.044242] Starting dynamic subtest: pipe-A
[116.046441] Dynamic subtest pipe-A: SUCCESS (0.002s)
[116.054709] Starting dynamic subtest: pipe-B
[116.056874] Dynamic subtest pipe-B: SUCCESS (0.003s)
[116.063691] Starting dynamic subtest: pipe-C
[116.065596] Dynamic subtest pipe-C: SUCCESS (0.003s)
[116.072808] Starting dynamic subtest: pipe-D
[116.074975] Dynamic subtest pipe-D: SUCCESS (0.004s)
[116.077218] Subtest invalid-ctm-matrix-sizes: SUCCESS (0.013s)
[116.126189] [007/133] (901s left) gem_softpin (noreloc-s3)
[116.184076] Starting subtest: noreloc-S3
[136.356704] Subtest noreloc-S3: SUCCESS (5.396s)
[136.489870] [008/133] (881s left) kms_cursor_crc (cursor-size-change)
[136.597134] Starting subtest: cursor-size-change
[136.599162] Starting dynamic subtest: pipe-A-eDP-1
[137.416738] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.804s)
[137.427260] Starting dynamic subtest: pipe-D-eDP-1
[138.955294] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.510s)
[138.957194] Subtest cursor-size-change: SUCCESS (2.316s)
[139.751263] [009/133] (878s left) gem_ctx_exec (basic-nohangcheck)
[139.802017] Starting subtest: basic-nohangcheck
[140.048122] Subtest basic-nohangcheck: SUCCESS (0.244s)
[140.157981] [010/133] (877s left) gem_softpin (allocator-nopin-reserve)
[140.221167] Starting subtest: allocator-nopin-reserve
[140.327362] Subtest allocator-nopin-reserve: SUCCESS (0.103s)
[140.468848] [011/133] (877s left) kms_draw_crc (fill-fb)
[140.564345] Starting subtest: fill-fb
[141.053731] Subtest fill-fb: SUCCESS (0.486s)
[141.145920] [012/133] (876s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-blt)
[141.241504] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-blt
[145.752143] Subtest fbc-1p-primscrn-pri-indfb-draw-blt: SUCCESS (4.476s)
[146.908178] [013/133] (871s left) kms_ccs (crc-primary-suspend-y-tiled-gen12-rc-ccs-cc)
[147.003105] Starting subtest: crc-primary-suspend-y-tiled-gen12-rc-ccs-cc
[147.005440] Starting dynamic subtest: pipe-A-eDP-1
[147.014804] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[147.023741] Starting dynamic subtest: pipe-B-eDP-1
[147.033145] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[147.041670] Starting dynamic subtest: pipe-C-eDP-1
[147.050775] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[147.058495] Starting dynamic subtest: pipe-D-eDP-1
[147.067487] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[147.071980] Subtest crc-primary-suspend-y-tiled-gen12-rc-ccs-cc: SKIP (0.003s)
[149.260392] [014/133] (868s left) gem_ctx_shared (q-smoketest-all)
[149.330965] Starting subtest: Q-smoketest-all
[181.725199] Subtest Q-smoketest-all: SUCCESS (32.312s)
[182.632800] [015/133] (835s left) kms_psr (pr-primary-mmap-gtt)
[182.744657] Starting subtest: pr-primary-mmap-gtt
[182.748984] Subtest pr-primary-mmap-gtt: SKIP (0.000s)
[182.818153] [016/133] (835s left) api_intel_allocator (fork-simple-once)
[182.871208] Starting subtest: fork-simple-once
[182.890300] Subtest fork-simple-once: SUCCESS (0.019s)
[183.008826] [017/133] (835s left) kms_psr (fbc-psr-primary-render)
[183.097008] Starting subtest: fbc-psr-primary-render
[183.099196] Starting dynamic subtest: eDP-1
[183.103202] Dynamic subtest eDP-1: SKIP (0.000s)
[183.107220] Subtest fbc-psr-primary-render: SKIP (0.001s)
[183.166011] [018/133] (835s left) gem_ccs (block-multicopy-compressed)
[183.221665] Starting subtest: block-multicopy-compressed
[183.225831] Subtest block-multicopy-compressed: SKIP (0.000s)
[183.317679] [019/133] (835s left) gem_ctx_create (basic-files)
[183.381237] Starting subtest: basic-files
[185.387763] Subtest basic-files: SUCCESS (2.006s)
[186.662535] [020/133] (831s left) gem_lmem_swapping (heavy-verify-multi)
[186.703131] Subtest heavy-verify-multi: SKIP (0.000s)
[186.745818] [021/133] (831s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-16bpp-yftile-downscaling)
[186.847733] Starting subtest: flip-64bpp-yftile-to-16bpp-yftile-downscaling
[186.851615] Subtest flip-64bpp-yftile-to-16bpp-yftile-downscaling: SKIP (0.000s)
[186.919740] [022/133] (831s left) gem_mmap_wc (pf-nonblock)
[186.970048] Subtest pf-nonblock: SKIP (0.000s)
[187.027850] [023/133] (831s left) kms_vrr (flip-suspend)
[187.121507] Starting subtest: flip-suspend
[187.127685] Subtest flip-suspend: SKIP (0.000s)
[187.190981] [024/133] (831s left) gem_render_copy (y-tiled-to-vebox-y-tiled)
[187.260743] Starting subtest: y-tiled-to-vebox-y-tiled
[187.265159] Subtest y-tiled-to-vebox-y-tiled: SKIP (0.000s)
[187.437987] [025/133] (831s left) syncobj_wait (multi-wait-all-for-submit-unsubmitted-submitted)
[187.493769] Starting subtest: multi-wait-all-for-submit-unsubmitted-submitted
[187.594245] Subtest multi-wait-all-for-submit-unsubmitted-submitted: SUCCESS (0.101s)
[187.684878] [026/133] (830s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180)
[187.802045] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180
[187.806014] Subtest y-tiled-max-hw-stride-32bpp-rotate-180: SKIP (0.000s)
[187.899260] [027/133] (830s left) drm_read (invalid-buffer)
[188.044070] Starting subtest: invalid-buffer
[188.046337] Subtest invalid-buffer: SUCCESS (0.000s)
[188.168879] [028/133] (830s left) gem_wait (write-wait)
[188.228001] Starting subtest: write-wait
[188.230368] Starting dynamic subtest: all-engines
[189.402564] Dynamic subtest all-engines: SUCCESS (1.174s)
[189.418868] Starting dynamic subtest: rcs0
[190.457261] Dynamic subtest rcs0: SUCCESS (1.052s)
[190.473316] Starting dynamic subtest: bcs0
[191.512162] Dynamic subtest bcs0: SUCCESS (1.054s)
[191.528236] Starting dynamic subtest: vcs0
[192.566901] Dynamic subtest vcs0: SUCCESS (1.053s)
[192.582689] Starting dynamic subtest: vcs1
[193.618967] Dynamic subtest vcs1: SUCCESS (1.051s)
[193.633525] Starting dynamic subtest: vecs0
[194.670964] Dynamic subtest vecs0: SUCCESS (1.050s)
[194.686691] Starting dynamic subtest: ccs0
[195.727990] Dynamic subtest ccs0: SUCCESS (1.055s)
[195.730435] Subtest write-wait: SUCCESS (7.499s)
[195.925317] [FACT gem_wait (write-wait)] new: kernel.kmod_is_loaded.vgem: true
[195.928511] [029/133] (822s left) gem_ctx_persistence (legacy-engines-queued)
[195.986847] Starting subtest: legacy-engines-queued
[195.989127] Starting dynamic subtest: render
[196.082351] Dynamic subtest render: SUCCESS (0.095s)
[196.093226] Starting dynamic subtest: bsd1
[197.575451] Dynamic subtest bsd1: SUCCESS (1.489s)
[197.587287] Starting dynamic subtest: bsd2
[199.557797] Dynamic subtest bsd2: SUCCESS (1.979s)
[199.569600] Starting dynamic subtest: blt
[199.622948] Dynamic subtest blt: SUCCESS (0.064s)
[199.634745] Starting dynamic subtest: vebox
[201.542448] Dynamic subtest vebox: SUCCESS (1.916s)
[201.545114] Subtest legacy-engines-queued: SUCCESS (5.554s)
[201.622638] [030/133] (817s left) kms_chamelium_edid (hdmi-edid-change-during-suspend)
[202.020064] Subtest hdmi-edid-change-during-suspend: SKIP (0.000s)
[202.808740] [031/133] (816s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite)
[202.915323] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite
[211.696109] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (8.783s)
[211.810636] [032/133] (807s left) gem_exec_schedule (noreorder)
[211.992631] Starting subtest: noreorder
[211.995034] Starting dynamic subtest: rcs0
[212.045646] Dynamic subtest rcs0: SUCCESS (0.056s)
[212.056727] Starting dynamic subtest: vcs0
[213.581214] Dynamic subtest vcs0: SUCCESS (1.533s)
[213.595140] Starting dynamic subtest: vcs1
[215.516944] Dynamic subtest vcs1: SUCCESS (1.933s)
[215.527442] Starting dynamic subtest: vecs0
[217.623623] Dynamic subtest vecs0: SUCCESS (2.050s)
[217.634242] Starting dynamic subtest: ccs0
[217.645424] Dynamic subtest ccs0: SUCCESS (0.075s)
[217.647714] Subtest noreorder: SUCCESS (5.656s)
[217.847217] [033/133] (801s left) gem_basic (create-close)
[217.902709] Starting subtest: create-close
[217.907120] Subtest create-close: SUCCESS (0.000s)
[217.999235] [034/133] (800s left) perf (buffer-fill)
[218.130769] Starting subtest: buffer-fill
[218.132913] Starting dynamic subtest: 0-rcs0
[220.408533] Dynamic subtest 0-rcs0: SUCCESS (2.275s)
[220.417598] Starting dynamic subtest: 1-vcs0
[224.841579] Dynamic subtest 1-vcs0: SUCCESS (4.432s)
[224.843791] Subtest buffer-fill: SUCCESS (6.709s)
[224.891300] [035/133] (794s left) kms_frontbuffer_tracking (psr-shrfb-scaledprimary)
[225.005457] Starting subtest: psr-shrfb-scaledprimary
[230.741379] Subtest psr-shrfb-scaledprimary: SUCCESS (5.739s)
[230.892791] [036/133] (788s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0)
[230.994538] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0
[232.017190] Subtest x-tiled-max-hw-stride-32bpp-rotate-0: SUCCESS (1.023s)
[232.142168] [037/133] (786s left) kms_async_flips (alternate-sync-async-flip)
[232.238299] Starting subtest: alternate-sync-async-flip
[232.623552] Starting dynamic subtest: pipe-A-eDP-1
[235.380791] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.758s)
[235.771688] Starting dynamic subtest: pipe-B-eDP-1
[238.513831] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.749s)
[238.899167] Starting dynamic subtest: pipe-C-eDP-1
[241.653218] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.761s)
[242.016982] Starting dynamic subtest: pipe-D-eDP-1
[244.782332] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.772s)
[244.784441] Subtest alternate-sync-async-flip: SUCCESS (12.546s)
[245.957349] [038/133] (773s left) perf_pmu (busy-idle-no-semaphores)
[246.011862] Starting subtest: busy-idle-no-semaphores
[246.014204] Starting dynamic subtest: rcs0
[246.661341] Dynamic subtest rcs0: SUCCESS (0.649s)
[246.673149] Starting dynamic subtest: bcs0
[247.318288] Dynamic subtest bcs0: SUCCESS (0.654s)
[247.328678] Starting dynamic subtest: vcs0
[247.970011] Dynamic subtest vcs0: SUCCESS (0.649s)
[247.981765] Starting dynamic subtest: vcs1
[249.170466] Dynamic subtest vcs1: SUCCESS (1.196s)
[249.189979] Starting dynamic subtest: vecs0
[251.217964] Dynamic subtest vecs0: SUCCESS (2.044s)
[251.229721] Starting dynamic subtest: ccs0
[253.202989] Dynamic subtest ccs0: SUCCESS (1.982s)
[253.206179] Subtest busy-idle-no-semaphores: SUCCESS (7.193s)
[253.327881] [039/133] (765s left) sw_sync (alloc_merge_fence)
[253.366105] Starting subtest: alloc_merge_fence
[253.368321] Subtest alloc_merge_fence: SUCCESS (0.000s)
[253.421514] [040/133] (765s left) gem_render_linear_blits (basic)
[253.482674] Starting subtest: basic
[253.515548] Subtest basic: SUCCESS (0.033s)
[253.602140] [041/133] (765s left) kms_dsc (dsc-fractional-bpp)
[253.707769] Starting subtest: dsc-fractional-bpp
[253.720147] Subtest dsc-fractional-bpp: SKIP (0.000s)
[253.792106] [042/133] (765s left) kms_psr2_sf (fbc-pr-cursor-plane-move-continuous-sf)
[253.893004] Starting subtest: fbc-pr-cursor-plane-move-continuous-sf
[253.897150] Subtest fbc-pr-cursor-plane-move-continuous-sf: SKIP (0.000s)
[253.979847] [043/133] (765s left) kms_atomic (atomic-plane-damage)
[254.075311] Starting subtest: atomic-plane-damage
[254.126580] Starting dynamic subtest: pipe-A-eDP-1
[254.669706] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.545s)
[254.671980] Subtest atomic-plane-damage: SUCCESS (0.596s)
[255.488888] [044/133] (763s left) gem_softpin (invalid)
[255.560910] Starting subtest: invalid
[255.569277] Subtest invalid: SUCCESS (0.007s)
[255.693339] [045/133] (763s left) kms_cursor_legacy (cursor-vs-flip-varying-size)
[255.828106] Starting subtest: cursor-vs-flip-varying-size
[286.810591] Subtest cursor-vs-flip-varying-size: SUCCESS (30.981s)
[287.618153] [046/133] (731s left) syncobj_wait (invalid-multi-wait-all-unsubmitted-submitted)
[287.665018] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted
[287.667315] Subtest invalid-multi-wait-all-unsubmitted-submitted: SUCCESS (0.000s)
[287.758155] [047/133] (731s left) syncobj_timeline (multi-wait-available-submitted-signaled)
[287.813511] Starting subtest: multi-wait-available-submitted-signaled
[287.815512] Subtest multi-wait-available-submitted-signaled: SUCCESS (0.000s)
[287.905031] [048/133] (731s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-blt)
[288.003090] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-blt
[294.839208] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-blt: SUCCESS (6.837s)
[294.976125] [049/133] (724s left) kms_plane_scaling (plane-scaler-unity-scaling-with-modifiers)
[295.069318] Starting subtest: plane-scaler-unity-scaling-with-modifiers
[295.071535] Starting dynamic subtest: pipe-A
[295.407021] Dynamic subtest pipe-A: SUCCESS (0.340s)
[295.422131] Starting dynamic subtest: pipe-B
[296.846969] Dynamic subtest pipe-B: SUCCESS (1.438s)
[296.856398] Starting dynamic subtest: pipe-C
[298.267164] Dynamic subtest pipe-C: SUCCESS (1.419s)
[298.275993] Starting dynamic subtest: pipe-D
[299.702977] Dynamic subtest pipe-D: SUCCESS (1.435s)
[299.705087] Subtest plane-scaler-unity-scaling-with-modifiers: SUCCESS (4.637s)
[300.871504] [050/133] (718s left) i915_drm_fdinfo (memory-info-purgeable)
[300.914892] Starting subtest: memory-info-purgeable
[300.917342] Starting dynamic subtest: smem0
[303.517392] Dynamic subtest smem0: SUCCESS (2.603s)
[303.520109] Subtest memory-info-purgeable: SUCCESS (2.604s)
[303.592194] [051/133] (716s left) kms_psr2_sf (psr2-cursor-plane-update-sf)
[303.703540] Starting subtest: psr2-cursor-plane-update-sf
[303.705416] Starting dynamic subtest: pipe-A-eDP-1
[304.276195] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.577s)
[304.285982] Starting dynamic subtest: pipe-B-eDP-1
[305.559401] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.280s)
[305.561603] Subtest psr2-cursor-plane-update-sf: SUCCESS (1.860s)
[306.425422] [052/133] (713s left) gem_request_retire (retire-vma-not-inactive)
[306.497651] Starting subtest: retire-vma-not-inactive
[306.560881] Subtest retire-vma-not-inactive: SUCCESS (0.060s)
[306.682992] [053/133] (713s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted)
[306.739734] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted
[306.842843] Subtest etime-multi-wait-all-for-submit-unsubmitted: SUCCESS (0.103s)
[306.935078] [054/133] (712s left) kms_ccs (ccs-on-another-bo-y-tiled-ccs)
[307.028136] Starting subtest: ccs-on-another-bo-y-tiled-ccs
[307.030417] Starting dynamic subtest: pipe-A-eDP-1
[307.052348] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[307.065336] Starting dynamic subtest: pipe-B-eDP-1
[307.086144] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[307.094454] Starting dynamic subtest: pipe-C-eDP-1
[307.114974] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[307.122874] Starting dynamic subtest: pipe-D-eDP-1
[307.143593] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[307.147593] Subtest ccs-on-another-bo-y-tiled-ccs: SKIP (0.005s)
[309.190404] [055/133] (710s left) kms_chamelium_hpd (dp-hpd-for-each-pipe)
[309.575318] Subtest dp-hpd-for-each-pipe: SKIP (0.000s)
[310.406238] [056/133] (709s left) perf (invalid-remove-userspace-config)
[310.501886] Starting subtest: invalid-remove-userspace-config
[310.513366] Subtest invalid-remove-userspace-config: SUCCESS (0.009s)
[310.558081] [057/133] (709s left) kms_vblank (query-idle)
[310.645073] Starting subtest: query-idle
[310.647225] Starting dynamic subtest: pipe-A-eDP-1
[312.391210] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.745s)
[312.404350] Starting dynamic subtest: pipe-D-eDP-1
[314.873832] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.481s)
[314.876076] Subtest query-idle: SUCCESS (4.230s)
[315.687501] [058/133] (704s left) i915_pm_rpm (gem-idle)
[317.719744] Starting subtest: gem-idle
[323.920016] Subtest gem-idle: SUCCESS (6.195s)
[324.255981] [059/133] (695s left) gem_exec_fence (syncobj-timeline-signal)
[326.007910] Starting subtest: syncobj-timeline-signal
[326.014460] Subtest syncobj-timeline-signal: SUCCESS (0.007s)
[327.650155] [060/133] (692s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-render)
[327.748719] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-render
[335.120854] Subtest psr-1p-primscrn-pri-shrfb-draw-render: SUCCESS (7.374s)
[335.250464] [061/133] (684s left) gen7_exec_parse (chained-batch)
[335.313844] Subtest chained-batch: SKIP (0.000s)
[335.384386] [062/133] (684s left) kms_psr (psr2-no-drrs)
[335.481086] Starting subtest: psr2-no-drrs
[335.483353] Starting dynamic subtest: eDP-1
[337.060300] Dynamic subtest eDP-1: SUCCESS (1.577s)
[337.062525] Subtest psr2-no-drrs: SUCCESS (1.578s)
[337.870207] [063/133] (682s left) kms_lease (lease-invalid-connector)
[337.979230] Starting subtest: lease-invalid-connector
[337.981470] Starting dynamic subtest: pipe-A-eDP-1
[337.983724] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[337.997272] Starting dynamic subtest: pipe-B-eDP-1
[337.999146] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[338.010982] Starting dynamic subtest: pipe-C-eDP-1
[338.013095] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[338.021652] Starting dynamic subtest: pipe-D-eDP-1
[338.023871] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[338.026103] Subtest lease-invalid-connector: SUCCESS (0.005s)
[338.066383] [064/133] (682s left) kms_psr (psr-sprite-render)
[338.162209] Starting subtest: psr-sprite-render
[338.164316] Starting dynamic subtest: eDP-1
[339.873478] Dynamic subtest eDP-1: SUCCESS (1.710s)
[339.875811] Subtest psr-sprite-render: SUCCESS (1.712s)
[340.713599] [065/133] (679s left) kms_frontbuffer_tracking (fbcpsr-farfromfence-mmap-gtt)
[340.816443] Starting subtest: fbcpsr-farfromfence-mmap-gtt
[340.820532] Subtest fbcpsr-farfromfence-mmap-gtt: SKIP (0.000s)
[340.927247] [066/133] (679s left) syncobj_wait (wait-all-for-submit-snapshot)
[340.979585] Starting subtest: wait-all-for-submit-snapshot
[341.059511] Subtest wait-all-for-submit-snapshot: SUCCESS (0.081s)
[341.139926] [067/133] (679s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-pwrite)
[341.247927] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-pwrite
[341.252210] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[341.355800] [068/133] (679s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-mmap-wc)
[341.460516] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-mmap-wc
[345.466315] Subtest fbc-1p-offscreen-pri-indfb-draw-mmap-wc: SUCCESS (4.008s)
[346.613809] [069/133] (673s left) gem_exec_balancer (busy)
[346.690820] Starting subtest: busy
[348.576116] Subtest busy: SUCCESS (1.884s)
[348.883231] [070/133] (671s left) gem_exec_schedule (timeslicing)
[348.952193] Starting subtest: timeslicing
[348.954413] Starting dynamic subtest: rcs0
[348.962718] Dynamic subtest rcs0: SUCCESS (0.021s)
[348.978636] Starting dynamic subtest: bcs0
[348.983292] Dynamic subtest bcs0: SUCCESS (0.020s)
[348.995644] Starting dynamic subtest: vcs0
[349.004557] Dynamic subtest vcs0: SUCCESS (0.021s)
[349.013161] Starting dynamic subtest: vcs1
[349.025130] Dynamic subtest vcs1: SUCCESS (0.020s)
[349.033784] Starting dynamic subtest: vecs0
[349.045125] Dynamic subtest vecs0: SUCCESS (0.019s)
[349.053837] Starting dynamic subtest: ccs0
[349.065625] Dynamic subtest ccs0: SUCCESS (0.020s)
[349.068435] Subtest timeslicing: SUCCESS (0.125s)
[350.610697] [071/133] (669s left) kms_big_fb (4-tiled-64bpp-rotate-90)
[350.715618] Starting subtest: 4-tiled-64bpp-rotate-90
[350.737462] Subtest 4-tiled-64bpp-rotate-90: SKIP (0.021s)
[350.863340] [072/133] (669s left) gem_ctx_param (invalid-param-set)
[350.927373] Starting subtest: invalid-param-set
[350.929818] Subtest invalid-param-set: SUCCESS (0.000s)
[351.015131] [073/133] (669s left) gem_render_copy_redux (normal)
[351.073179] Starting subtest: normal
[351.337077] Subtest normal: SUCCESS (0.264s)
[351.454127] [074/133] (669s left) gem_exec_params (invalid-bsd2-flag-on-blt)
[351.514024] Starting subtest: invalid-bsd2-flag-on-blt
[351.516224] Subtest invalid-bsd2-flag-on-blt: SUCCESS (0.000s)
[351.613604] [075/133] (669s left) kms_flip (2x-wf_vblank-ts-check-interruptible)
[351.702288] Starting subtest: 2x-wf_vblank-ts-check-interruptible
[351.713995] Subtest 2x-wf_vblank-ts-check-interruptible: SKIP (0.011s)
[351.791560] [076/133] (668s left) kms_setmode (basic)
[351.843307] Starting subtest: basic
[352.166232] Starting dynamic subtest: pipe-A-eDP-1
[354.229708] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.059s)
[354.571104] Starting dynamic subtest: pipe-B-eDP-1
[356.659896] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.091s)
[356.985276] Subtest basic: SUCCESS (5.140s)
[357.811255] [077/133] (662s left) kms_selftest (drm_cmdline_parser)
[357.855525] Starting subtest: drm_cmdline_parser
[357.870383] Starting dynamic subtest: drm_test_cmdline_force_d_only
[357.969024] Dynamic subtest drm_test_cmdline_force_d_only: SUCCESS (0.098s)
[357.978739] Starting dynamic subtest: drm_test_cmdline_force_D_only_dvi
[358.085963] Dynamic subtest drm_test_cmdline_force_D_only_dvi: SUCCESS (0.114s)
[358.096164] Starting dynamic subtest: drm_test_cmdline_force_D_only_hdmi
[358.172050] Dynamic subtest drm_test_cmdline_force_D_only_hdmi: SUCCESS (0.087s)
[358.180588] Starting dynamic subtest: drm_test_cmdline_force_D_only_not_digital
[358.273264] Dynamic subtest drm_test_cmdline_force_D_only_not_digital: SUCCESS (0.100s)
[358.281991] Starting dynamic subtest: drm_test_cmdline_force_e_only
[358.381961] Dynamic subtest drm_test_cmdline_force_e_only: SUCCESS (0.109s)
[358.390760] Starting dynamic subtest: drm_test_cmdline_res
[358.480072] Dynamic subtest drm_test_cmdline_res: SUCCESS (0.097s)
[358.489203] Starting dynamic subtest: drm_test_cmdline_res_vesa
[358.568801] Dynamic subtest drm_test_cmdline_res_vesa: SUCCESS (0.088s)
[358.577704] Starting dynamic subtest: drm_test_cmdline_res_vesa_rblank
[358.665819] Dynamic subtest drm_test_cmdline_res_vesa_rblank: SUCCESS (0.095s)
[358.674793] Starting dynamic subtest: drm_test_cmdline_res_rblank
[358.764017] Dynamic subtest drm_test_cmdline_res_rblank: SUCCESS (0.096s)
[358.783233] Starting dynamic subtest: drm_test_cmdline_res_bpp
[358.853537] Dynamic subtest drm_test_cmdline_res_bpp: SUCCESS (0.091s)
[358.864099] Starting dynamic subtest: drm_test_cmdline_res_refresh
[358.947959] Dynamic subtest drm_test_cmdline_res_refresh: SUCCESS (0.092s)
[358.958566] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh
[359.087063] Dynamic subtest drm_test_cmdline_res_bpp_refresh: SUCCESS (0.138s)
[359.095891] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_interlaced
[359.196970] Dynamic subtest drm_test_cmdline_res_bpp_refresh_interlaced: SUCCESS (0.109s)
[359.207155] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_margins
[359.291903] Dynamic subtest drm_test_cmdline_res_bpp_refresh_margins: SUCCESS (0.094s)
[359.302303] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_force_off
[359.385517] Dynamic subtest drm_test_cmdline_res_bpp_refresh_force_off: SUCCESS (0.092s)
[359.395752] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_force_on
[359.473612] Dynamic subtest drm_test_cmdline_res_bpp_refresh_force_on: SUCCESS (0.088s)
[359.482190] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_force_on_analog
[359.577624] Dynamic subtest drm_test_cmdline_res_bpp_refresh_force_on_analog: SUCCESS (0.102s)
[359.586598] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_force_on_digital
[359.679790] Dynamic subtest drm_test_cmdline_res_bpp_refresh_force_on_digital: SUCCESS (0.102s)
[359.689877] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_interlaced_margins_force_on
[359.784571] Dynamic subtest drm_test_cmdline_res_bpp_refresh_interlaced_margins_force_on: SUCCESS (0.104s)
[359.793157] Starting dynamic subtest: drm_test_cmdline_res_margins_force_on
[359.883553] Dynamic subtest drm_test_cmdline_res_margins_force_on: SUCCESS (0.097s)
[359.891819] Starting dynamic subtest: drm_test_cmdline_res_vesa_margins
[359.984807] Dynamic subtest drm_test_cmdline_res_vesa_margins: SUCCESS (0.100s)
[359.993568] Starting dynamic subtest: drm_test_cmdline_name
[360.080003] Dynamic subtest drm_test_cmdline_name: SUCCESS (0.093s)
[360.090114] Starting dynamic subtest: drm_test_cmdline_name_bpp
[360.171121] Dynamic subtest drm_test_cmdline_name_bpp: SUCCESS (0.091s)
[360.180664] Starting dynamic subtest: drm_test_cmdline_name_option
[360.283279] Dynamic subtest drm_test_cmdline_name_option: SUCCESS (0.110s)
[360.291344] Starting dynamic subtest: drm_test_cmdline_name_bpp_option
[360.380605] Dynamic subtest drm_test_cmdline_name_bpp_option: SUCCESS (0.097s)
[360.390588] Starting dynamic subtest: drm_test_cmdline_rotate_0
[360.487585] Dynamic subtest drm_test_cmdline_rotate_0: SUCCESS (0.105s)
[360.497184] Starting dynamic subtest: drm_test_cmdline_rotate_90
[360.586186] Dynamic subtest drm_test_cmdline_rotate_90: SUCCESS (0.098s)
[360.595899] Starting dynamic subtest: drm_test_cmdline_rotate_180
[360.700177] Dynamic subtest drm_test_cmdline_rotate_180: SUCCESS (0.113s)
[360.710723] Starting dynamic subtest: drm_test_cmdline_rotate_270
[360.801848] Dynamic subtest drm_test_cmdline_rotate_270: SUCCESS (0.099s)
[360.812198] Starting dynamic subtest: drm_test_cmdline_hmirror
[360.894667] Dynamic subtest drm_test_cmdline_hmirror: SUCCESS (0.092s)
[360.903848] Starting dynamic subtest: drm_test_cmdline_vmirror
[361.004149] Dynamic subtest drm_test_cmdline_vmirror: SUCCESS (0.109s)
[361.015202] Starting dynamic subtest: drm_test_cmdline_margin_options
[361.115043] Dynamic subtest drm_test_cmdline_margin_options: SUCCESS (0.110s)
[361.125308] Starting dynamic subtest: drm_test_cmdline_multiple_options
[361.212986] Dynamic subtest drm_test_cmdline_multiple_options: SUCCESS (0.097s)
[361.222944] Starting dynamic subtest: drm_test_cmdline_bpp_extra_and_option
[361.309226] Dynamic subtest drm_test_cmdline_bpp_extra_and_option: SUCCESS (0.095s)
[361.319027] Starting dynamic subtest: drm_test_cmdline_extra_and_option
[361.386937] Dynamic subtest drm_test_cmdline_extra_and_option: SUCCESS (0.077s)
[361.396736] Starting dynamic subtest: drm_test_cmdline_freestanding_options
[361.493321] Dynamic subtest drm_test_cmdline_freestanding_options: SUCCESS (0.106s)
[361.503170] Starting dynamic subtest: drm_test_cmdline_freestanding_force_e_and_options
[361.594911] Dynamic subtest drm_test_cmdline_freestanding_force_e_and_options: SUCCESS (0.102s)
[361.603643] Starting dynamic subtest: drm_test_cmdline_panel_orientation
[361.692583] Dynamic subtest drm_test_cmdline_panel_orientation: SUCCESS (0.094s)
[361.701457] Starting dynamic subtest: drm_test_cmdline_invalid
[361.806037] Dynamic subtest drm_test_cmdline_invalid: SUCCESS (0.114s)
[361.814845] Starting dynamic subtest: drm_test_cmdline_tv_options
[361.915814] Dynamic subtest drm_test_cmdline_tv_options: SUCCESS (0.109s)
[361.917708] Subtest drm_cmdline_parser: SUCCESS (4.063s)
[362.092947] [078/133] (658s left) kms_content_protection (dp-mst-lic-type-0)
[362.256613] Starting subtest: dp-mst-lic-type-0
[362.261245] Subtest dp-mst-lic-type-0: SKIP (0.000s)
[362.383013] [079/133] (658s left) kms_flip (2x-flip-vs-suspend)
[362.480400] Starting subtest: 2x-flip-vs-suspend
[362.489508] Subtest 2x-flip-vs-suspend: SKIP (0.011s)
[362.592240] [080/133] (658s left) kms_cursor_legacy (cursorb-vs-flipb-atomic-transitions)
[362.711595] Starting subtest: cursorB-vs-flipB-atomic-transitions
[362.716210] Subtest cursorB-vs-flipB-atomic-transitions: SKIP (0.001s)
[362.856792] [081/133] (658s left) kms_frontbuffer_tracking (psr-2p-scndscrn-shrfb-pgflip-blt)
[362.951819] Starting subtest: psr-2p-scndscrn-shrfb-pgflip-blt
[362.956305] Subtest psr-2p-scndscrn-shrfb-pgflip-blt: SKIP (0.000s)
[363.058004] [082/133] (657s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted)
[363.105960] Starting subtest: invalid-multi-wait-all-unsubmitted
[363.107862] Subtest invalid-multi-wait-all-unsubmitted: SUCCESS (0.000s)
[363.204207] [083/133] (657s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[363.298754] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[363.302420] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[363.391288] [084/133] (657s left) syncobj_timeline (signal-point-0)
[363.442877] Starting subtest: signal-point-0
[363.444557] Subtest signal-point-0: SUCCESS (0.000s)
[363.531432] [085/133] (657s left) sysfs_defaults (readonly)
[363.592342] Starting subtest: readonly
[363.594371] Starting dynamic subtest: vecs0
[363.596237] Dynamic subtest vecs0: SUCCESS (0.000s)
[363.606038] Starting dynamic subtest: ccs0
[363.607814] Dynamic subtest ccs0: SUCCESS (0.000s)
[363.616121] Starting dynamic subtest: vcs0
[363.617857] Dynamic subtest vcs0: SUCCESS (0.000s)
[363.625903] Starting dynamic subtest: bcs0
[363.627522] Dynamic subtest bcs0: SUCCESS (0.000s)
[363.635294] Starting dynamic subtest: rcs0
[363.636945] Dynamic subtest rcs0: SUCCESS (0.000s)
[363.644880] Starting dynamic subtest: vcs1
[363.646474] Dynamic subtest vcs1: SUCCESS (0.000s)
[363.648064] Subtest readonly: SUCCESS (0.006s)
[363.685083] [086/133] (657s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-render)
[363.776916] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-render
[363.780259] Subtest psr-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[363.872276] [087/133] (657s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-blt)
[363.972252] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-blt
[368.461366] Subtest fbc-1p-primscrn-pri-shrfb-draw-blt: SUCCESS (4.492s)
[369.602668] [088/133] (651s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-render)
[369.700687] Starting subtest: psr-1p-offscreen-pri-indfb-draw-render
[376.619707] Subtest psr-1p-offscreen-pri-indfb-draw-render: SUCCESS (6.921s)
[376.757543] [089/133] (644s left) syncobj_timeline (invalid-signal-one-illegal-handle)
[376.814048] Starting subtest: invalid-signal-one-illegal-handle
[376.816379] Subtest invalid-signal-one-illegal-handle: SUCCESS (0.000s)
[376.915314] [090/133] (644s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-indfb-msflip-blt)
[377.012277] Starting subtest: fbcpsr-2p-scndscrn-indfb-msflip-blt
[377.016328] Subtest fbcpsr-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[377.103835] [091/133] (644s left) kms_pm_backlight (fade-with-dpms)
[377.191261] Starting subtest: fade-with-dpms
[379.281934] Starting dynamic subtest: eDP-1
[381.776578] Dynamic subtest eDP-1: SUCCESS (2.495s)
[381.779041] Subtest fade-with-dpms: SUCCESS (4.587s)
[382.585147] [092/133] (638s left) kms_ccs (crc-primary-suspend-4-tiled-mtl-rc-ccs)
[382.690719] Starting subtest: crc-primary-suspend-4-tiled-mtl-rc-ccs
[382.692879] Starting dynamic subtest: pipe-A-eDP-1
[398.467709] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.417s)
[398.477777] Starting dynamic subtest: pipe-B-eDP-1
[415.491244] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.454s)
[415.501422] Starting dynamic subtest: pipe-C-eDP-1
[432.428192] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.394s)
[432.443858] Starting dynamic subtest: pipe-D-eDP-1
[449.443275] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.372s)
[449.445674] Subtest crc-primary-suspend-4-tiled-mtl-rc-ccs: SUCCESS (8.643s)
[450.733505] [093/133] (570s left) i915_power (sanity)
[450.799419] Starting subtest: sanity
[454.853421] Subtest sanity: SUCCESS (3.956s)
[456.494262] [094/133] (565s left) gem_render_copy (x-tiled-to-vebox-yf-tiled)
[456.557746] Starting subtest: x-tiled-to-vebox-yf-tiled
[456.562362] Subtest x-tiled-to-vebox-yf-tiled: SKIP (0.000s)
[456.743989] [095/133] (564s left) dumb_buffer (invalid-bpp)
[456.797722] Starting subtest: invalid-bpp
[456.800041] Subtest invalid-bpp: SUCCESS (0.000s)
[456.889411] [096/133] (564s left) gem_exec_fence (basic-wait)
[457.069894] Starting subtest: basic-wait
[457.072197] Starting dynamic subtest: rcs0
[457.158814] Dynamic subtest rcs0: SUCCESS (0.090s)
[457.170090] Starting dynamic subtest: bcs0
[457.220747] Dynamic subtest bcs0: SUCCESS (0.059s)
[457.231775] Starting dynamic subtest: vcs0
[457.283293] Dynamic subtest vcs0: SUCCESS (0.060s)
[457.294513] Starting dynamic subtest: vcs1
[457.348426] Dynamic subtest vcs1: SUCCESS (0.061s)
[457.358948] Starting dynamic subtest: vecs0
[457.411422] Dynamic subtest vecs0: SUCCESS (0.061s)
[457.422784] Starting dynamic subtest: ccs0
[457.477612] Dynamic subtest ccs0: SUCCESS (0.063s)
[457.480006] Subtest basic-wait: SUCCESS (0.406s)
[459.563258] [097/133] (562s left) i915_module_load (resize-bar)
[459.595966] Starting subtest: resize-bar
[459.603007] Subtest resize-bar: SKIP (0.006s)
[459.651255] [098/133] (562s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-pgflip-blt)
[459.759094] Starting subtest: psr-1p-primscrn-indfb-pgflip-blt
[467.205626] Subtest psr-1p-primscrn-indfb-pgflip-blt: SUCCESS (7.403s)
[467.353682] [099/133] (554s left) gem_partial_pwrite_pread (write-snoop)
[467.423775] Subtest write-snoop: SKIP (0.000s)
[467.503210] [100/133] (554s left) sriov_basic (enable-vfs-bind-unbind-each-numvfs-all)
[467.563327] Starting subtest: enable-vfs-bind-unbind-each-numvfs-all
[467.645566] Subtest enable-vfs-bind-unbind-each-numvfs-all: FAIL (0.029s)
[467.686210] [101/133] (554s left) kms_plane (pixel-format-4-tiled-mtl-rc-ccs-cc-modifier)
[467.789970] Starting subtest: pixel-format-4-tiled-mtl-rc-ccs-cc-modifier
[467.816701] Starting dynamic subtest: pipe-A-plane-0
[468.105761] Dynamic subtest pipe-A-plane-0: SUCCESS (0.288s)
[468.114685] Starting dynamic subtest: pipe-A-plane-3
[468.350514] Dynamic subtest pipe-A-plane-3: SUCCESS (0.242s)
[468.359267] Starting dynamic subtest: pipe-A-plane-5
[468.362931] Dynamic subtest pipe-A-plane-5: SKIP (0.000s)
[469.444199] Starting dynamic subtest: pipe-B-plane-0
[469.749047] Dynamic subtest pipe-B-plane-0: SUCCESS (0.311s)
[469.758549] Starting dynamic subtest: pipe-B-plane-3
[469.983999] Dynamic subtest pipe-B-plane-3: SUCCESS (0.231s)
[469.993406] Starting dynamic subtest: pipe-B-plane-5
[469.997736] Dynamic subtest pipe-B-plane-5: SKIP (0.000s)
[470.296797] Subtest pixel-format-4-tiled-mtl-rc-ccs-cc-modifier: SUCCESS (2.499s)
[471.122000] [102/133] (550s left) gem_exec_flush (basic-uc-prw-default)
[471.185311] Starting subtest: basic-uc-prw-default
[476.683140] Subtest basic-uc-prw-default: SUCCESS (5.469s)
[476.858036] [103/133] (545s left) kms_cursor_crc (cursor-onscreen-256x256)
[477.101027] Starting subtest: cursor-onscreen-256x256
[477.106112] Starting dynamic subtest: pipe-A-eDP-1
[479.217018] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.109s)
[479.251024] Starting dynamic subtest: pipe-D-eDP-1
[482.033267] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.791s)
[482.035655] Subtest cursor-onscreen-256x256: SUCCESS (4.929s)
[482.924864] [104/133] (539s left) gem_exec_reloc (basic-gtt-read)
[482.999474] Subtest basic-gtt-read: SKIP (0.000s)
[483.069379] [105/133] (538s left) kms_plane_lowres (tiling-none)
[483.166655] Starting subtest: tiling-none
[483.168854] Starting dynamic subtest: pipe-A-eDP-1
[483.173201] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[483.461307] Starting dynamic subtest: pipe-B-eDP-1
[483.465787] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[483.474521] Starting dynamic subtest: pipe-C-eDP-1
[483.478829] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[483.487453] Starting dynamic subtest: pipe-D-eDP-1
[483.491705] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[483.495709] Subtest tiling-none: SKIP (0.295s)
[484.330772] [106/133] (537s left) gem_exec_reloc (basic-gtt)
[484.398332] Subtest basic-gtt: SKIP (0.000s)
[484.479851] [107/133] (537s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[484.581846] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-mmap-gtt
[484.585771] Subtest psr-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[484.668716] [108/133] (537s left) kms_cursor_crc (cursor-sliding-64x21)
[484.862416] Starting subtest: cursor-sliding-64x21
[484.915202] Subtest cursor-sliding-64x21: SKIP (0.047s)
[485.189384] [109/133] (536s left) gem_cs_tlb (engines)
[485.248359] Starting subtest: engines
[485.250526] Starting dynamic subtest: rcs0
[487.402420] Dynamic subtest rcs0: SUCCESS (2.152s)
[487.414431] Starting dynamic subtest: bcs0
[489.608199] Dynamic subtest bcs0: SUCCESS (2.153s)
[489.619662] Starting dynamic subtest: vcs0
[491.760276] Dynamic subtest vcs0: SUCCESS (2.151s)
[491.771891] Starting dynamic subtest: vcs1
[493.912805] Dynamic subtest vcs1: SUCCESS (2.152s)
[493.925120] Starting dynamic subtest: vecs0
[496.066101] Dynamic subtest vecs0: SUCCESS (2.151s)
[496.077598] Starting dynamic subtest: ccs0
[498.220509] Dynamic subtest ccs0: SUCCESS (2.152s)
[498.222983] Subtest engines: SUCCESS (12.919s)
[498.285031] [110/133] (523s left) gem_mmap_wc (close)
[498.340988] Subtest close: SKIP (0.000s)
[498.403788] [111/133] (523s left) template (b)
[498.463566] Starting subtest: B
[498.465519] Subtest B: SUCCESS (0.000s)
[498.575001] [112/133] (523s left) kms_big_fb (x-tiled-16bpp-rotate-0)
[498.672391] Starting subtest: x-tiled-16bpp-rotate-0
[499.974182] Subtest x-tiled-16bpp-rotate-0: SUCCESS (1.302s)
[500.124844] [113/133] (522s left) prime_mmap (test_dup)
[500.191234] Starting subtest: test_dup
[500.192844] Starting dynamic subtest: test_dup-smem
[500.223452] Dynamic subtest test_dup-smem: SUCCESS (0.034s)
[500.226258] Subtest test_dup: SUCCESS (0.035s)
[500.302966] [114/133] (522s left) gem_pread (display)
[500.367316] Subtest display: SKIP (0.000s)
[500.454418] [115/133] (521s left) kms_invalid_mode (bad-hsync-start)
[500.552110] Starting subtest: bad-hsync-start
[500.553998] Starting dynamic subtest: pipe-A-eDP-1
[500.840015] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.286s)
[500.849592] Starting dynamic subtest: pipe-B-eDP-1
[500.851737] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[500.860178] Starting dynamic subtest: pipe-C-eDP-1
[500.862337] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[500.870734] Starting dynamic subtest: pipe-D-eDP-1
[500.872984] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[500.875164] Subtest bad-hsync-start: SUCCESS (0.292s)
[501.653575] [116/133] (520s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0)
[501.754844] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0
[501.758931] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0: SKIP (0.000s)
[501.841991] [117/133] (520s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[501.947577] Starting subtest: compare-crc-sanitycheck-nv12
[501.949917] Starting dynamic subtest: pipe-A-eDP-1
[502.643975] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.695s)
[502.654027] Starting dynamic subtest: pipe-B-eDP-1
[503.957745] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.311s)
[503.968896] Starting dynamic subtest: pipe-C-eDP-1
[505.261045] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.303s)
[505.277827] Starting dynamic subtest: pipe-D-eDP-1
[506.484780] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.221s)
[506.487063] Subtest compare-crc-sanitycheck-nv12: SUCCESS (4.536s)
[507.321424] [118/133] (515s left) gem_mmap_gtt (fault-concurrent-y)
[507.376623] Subtest fault-concurrent-Y: SKIP (0.000s)
[507.446022] [119/133] (515s left) gem_mmap_gtt (cpuset-medium-copy-xy)
[507.507691] Subtest cpuset-medium-copy-XY: SKIP (0.000s)
[507.579127] [120/133] (515s left) api_intel_bb (render-ccs)
[507.638123] Starting subtest: render-ccs
[507.999990] Subtest render-ccs: SUCCESS (0.361s)
[508.090542] [121/133] (514s left) kms_ccs (random-ccs-data-y-tiled-gen12-mc-ccs)
[508.188370] Starting subtest: random-ccs-data-y-tiled-gen12-mc-ccs
[508.190510] Starting dynamic subtest: pipe-A-eDP-1
[508.214567] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[508.224610] Starting dynamic subtest: pipe-B-eDP-1
[508.247003] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[508.255503] Starting dynamic subtest: pipe-C-eDP-1
[508.277957] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[508.286017] Starting dynamic subtest: pipe-D-eDP-1
[508.308782] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[508.312981] Subtest random-ccs-data-y-tiled-gen12-mc-ccs: SKIP (0.005s)
[510.464827] [122/133] (512s left) gem_exec_whisper (basic-contexts-all)
[510.534249] Starting subtest: basic-contexts-all
[521.466235] Subtest basic-contexts-all: SUCCESS (10.925s)
[521.652811] [123/133] (501s left) kms_flip (busy-flip)
[521.748867] Starting subtest: busy-flip
[521.751127] Starting dynamic subtest: A-eDP1
[523.471972] Dynamic subtest A-eDP1: SUCCESS (1.721s)
[523.486764] Starting dynamic subtest: B-eDP1
[525.199996] Dynamic subtest B-eDP1: SUCCESS (1.724s)
[525.210976] Starting dynamic subtest: C-eDP1
[526.920123] Dynamic subtest C-eDP1: SUCCESS (1.718s)
[526.929828] Starting dynamic subtest: D-eDP1
[528.562010] Dynamic subtest D-eDP1: SUCCESS (1.639s)
[528.564637] Subtest busy-flip: SUCCESS (6.814s)
[529.736401] [124/133] (493s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-pwrite)
[529.841206] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-pwrite
[534.251058] Subtest fbc-1p-primscrn-cur-indfb-draw-pwrite: SUCCESS (4.411s)
[535.456639] [125/133] (487s left) kms_dp_linktrain_fallback (dsc-fallback)
[535.547696] Starting subtest: dsc-fallback
[535.554019] Subtest dsc-fallback: SKIP (0.000s)
[535.633931] [126/133] (487s left) gem_exec_alignment (single)
[535.688840] Starting subtest: single
[535.693970] Subtest single: SUCCESS (0.002s)
[535.811922] [127/133] (487s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-0)
[535.908525] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-0
[535.912909] Subtest y-tiled-max-hw-stride-32bpp-rotate-0: SKIP (0.000s)
[536.006303] [128/133] (487s left) kms_ccs (bad-pixel-format-y-tiled-gen12-mc-ccs)
[536.117067] Starting subtest: bad-pixel-format-y-tiled-gen12-mc-ccs
[536.119392] Starting dynamic subtest: pipe-A-eDP-1
[536.142440] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[536.154718] Starting dynamic subtest: pipe-B-eDP-1
[536.176420] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[536.185664] Starting dynamic subtest: pipe-C-eDP-1
[536.207032] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[536.216157] Starting dynamic subtest: pipe-D-eDP-1
[536.237809] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[536.241835] Subtest bad-pixel-format-y-tiled-gen12-mc-ccs: SKIP (0.005s)
[538.401662] [129/133] (484s left) kms_psr2_sf (fbc-psr2-overlay-plane-move-continuous-exceed-fully-sf)
[538.513179] Starting subtest: fbc-psr2-overlay-plane-move-continuous-exceed-fully-sf
[538.515489] Starting dynamic subtest: pipe-A-eDP-1
[538.614974] Dynamic subtest pipe-A-eDP-1: SKIP (0.103s)
[538.626191] Starting dynamic subtest: pipe-B-eDP-1
[539.770061] Dynamic subtest pipe-B-eDP-1: SKIP (1.152s)
[539.774345] Subtest fbc-psr2-overlay-plane-move-continuous-exceed-fully-sf: SKIP (1.258s)
[540.956448] [130/133] (482s left) gem_exec_reloc (basic-cpu-wc-noreloc)
[541.014342] Subtest basic-cpu-wc-noreloc: SKIP (0.000s)
[541.087445] [131/133] (482s left) gem_exec_fence (syncobj-channel)
[541.562376] Starting subtest: syncobj-channel
[542.582049] Subtest syncobj-channel: SUCCESS (1.016s)
[543.607874] [132/133] (479s left) kms_cursor_crc (cursor-random-256x256)
[543.852817] Starting subtest: cursor-random-256x256
[543.855225] Starting dynamic subtest: pipe-A-eDP-1
[547.199403] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.343s)
[547.228545] Starting dynamic subtest: pipe-D-eDP-1
[551.205182] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.985s)
[551.207053] Subtest cursor-random-256x256: SUCCESS (7.356s)
[552.069478] [133/133] (471s left) syncobj_wait (reset-signaled)
[552.139075] Starting subtest: reset-signaled
[552.141026] Subtest reset-signaled: SUCCESS (0.000s)
[552.240657] Closing watchdogs
results: parsing output: 0/ for test: kms_psr
results: parsing output: 1/ for test: kms_big_fb
results: parsing output: 2/ for test: kms_pm_rpm
results: parsing output: 3/ for test: tools_test
results: parsing output: 4/ for test: kms_plane_scaling
results: parsing output: 5/ for test: kms_color
results: parsing output: 6/ for test: gem_softpin
results: parsing output: 7/ for test: kms_cursor_crc
results: parsing output: 8/ for test: gem_ctx_exec
results: parsing output: 9/ for test: gem_softpin
results: parsing output: 10/ for test: kms_draw_crc
results: parsing output: 11/ for test: kms_frontbuffer_tracking
results: parsing output: 12/ for test: kms_ccs
results: parsing output: 13/ for test: gem_ctx_shared
results: parsing output: 14/ for test: kms_psr
results: parsing output: 15/ for test: api_intel_allocator
results: parsing output: 16/ for test: kms_psr
results: parsing output: 17/ for test: gem_ccs
results: parsing output: 18/ for test: gem_ctx_create
results: parsing output: 19/ for test: gem_lmem_swapping
results: parsing output: 20/ for test: kms_flip_scaled_crc
results: parsing output: 21/ for test: gem_mmap_wc
results: parsing output: 22/ for test: kms_vrr
results: parsing output: 23/ for test: gem_render_copy
results: parsing output: 24/ for test: syncobj_wait
results: parsing output: 25/ for test: kms_big_fb
results: parsing output: 26/ for test: drm_read
results: parsing output: 27/ for test: gem_wait
results: parsing output: 28/ for test: gem_ctx_persistence
results: parsing output: 29/ for test: kms_chamelium_edid
results: parsing output: 30/ for test: kms_frontbuffer_tracking
results: parsing output: 31/ for test: gem_exec_schedule
results: parsing output: 32/ for test: gem_basic
results: parsing output: 33/ for test: perf
results: parsing output: 34/ for test: kms_frontbuffer_tracking
results: parsing output: 35/ for test: kms_big_fb
results: parsing output: 36/ for test: kms_async_flips
results: parsing output: 37/ for test: perf_pmu
results: parsing output: 38/ for test: sw_sync
results: parsing output: 39/ for test: gem_render_linear_blits
results: parsing output: 40/ for test: kms_dsc
results: parsing output: 41/ for test: kms_psr2_sf
results: parsing output: 42/ for test: kms_atomic
results: parsing output: 43/ for test: gem_softpin
results: parsing output: 44/ for test: kms_cursor_legacy
results: parsing output: 45/ for test: syncobj_wait
results: parsing output: 46/ for test: syncobj_timeline
results: parsing output: 47/ for test: kms_frontbuffer_tracking
results: parsing output: 48/ for test: kms_plane_scaling
results: parsing output: 49/ for test: i915_drm_fdinfo
results: parsing output: 50/ for test: kms_psr2_sf
results: parsing output: 51/ for test: gem_request_retire
results: parsing output: 52/ for test: syncobj_timeline
results: parsing output: 53/ for test: kms_ccs
results: parsing output: 54/ for test: kms_chamelium_hpd
results: parsing output: 55/ for test: perf
results: parsing output: 56/ for test: kms_vblank
results: parsing output: 57/ for test: i915_pm_rpm
results: parsing output: 58/ for test: gem_exec_fence
results: parsing output: 59/ for test: kms_frontbuffer_tracking
results: parsing output: 60/ for test: gen7_exec_parse
results: parsing output: 61/ for test: kms_psr
results: parsing output: 62/ for test: kms_lease
results: parsing output: 63/ for test: kms_psr
results: parsing output: 64/ for test: kms_frontbuffer_tracking
results: parsing output: 65/ for test: syncobj_wait
results: parsing output: 66/ for test: kms_frontbuffer_tracking
results: parsing output: 67/ for test: kms_frontbuffer_tracking
results: parsing output: 68/ for test: gem_exec_balancer
results: parsing output: 69/ for test: gem_exec_schedule
results: parsing output: 70/ for test: kms_big_fb
results: parsing output: 71/ for test: gem_ctx_param
results: parsing output: 72/ for test: gem_render_copy_redux
results: parsing output: 73/ for test: gem_exec_params
results: parsing output: 74/ for test: kms_flip
results: parsing output: 75/ for test: kms_setmode
results: parsing output: 76/ for test: kms_selftest
results: parsing output: 77/ for test: kms_content_protection
results: parsing output: 78/ for test: kms_flip
results: parsing output: 79/ for test: kms_cursor_legacy
results: parsing output: 80/ for test: kms_frontbuffer_tracking
results: parsing output: 81/ for test: syncobj_timeline
results: parsing output: 82/ for test: kms_frontbuffer_tracking
results: parsing output: 83/ for test: syncobj_timeline
results: parsing output: 84/ for test: sysfs_defaults
results: parsing output: 85/ for test: kms_frontbuffer_tracking
results: parsing output: 86/ for test: kms_frontbuffer_tracking
results: parsing output: 87/ for test: kms_frontbuffer_tracking
results: parsing output: 88/ for test: syncobj_timeline
results: parsing output: 89/ for test: kms_frontbuffer_tracking
results: parsing output: 90/ for test: kms_pm_backlight
results: parsing output: 91/ for test: kms_ccs
results: parsing output: 92/ for test: i915_power
results: parsing output: 93/ for test: gem_render_copy
results: parsing output: 94/ for test: dumb_buffer
results: parsing output: 95/ for test: gem_exec_fence
results: parsing output: 96/ for test: i915_module_load
results: parsing output: 97/ for test: kms_frontbuffer_tracking
results: parsing output: 98/ for test: gem_partial_pwrite_pread
results: parsing output: 99/ for test: sriov_basic
results: parsing output: 100/ for test: kms_plane
results: parsing output: 101/ for test: gem_exec_flush
results: parsing output: 102/ for test: kms_cursor_crc
results: parsing output: 103/ for test: gem_exec_reloc
results: parsing output: 104/ for test: kms_plane_lowres
results: parsing output: 105/ for test: gem_exec_reloc
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: kms_cursor_crc
results: parsing output: 108/ for test: gem_cs_tlb
results: parsing output: 109/ for test: gem_mmap_wc
results: parsing output: 110/ for test: template
results: parsing output: 111/ for test: kms_big_fb
results: parsing output: 112/ for test: prime_mmap
results: parsing output: 113/ for test: gem_pread
results: parsing output: 114/ for test: kms_invalid_mode
results: parsing output: 115/ for test: kms_big_fb
results: parsing output: 116/ for test: kms_pipe_crc_basic
results: parsing output: 117/ for test: gem_mmap_gtt
results: parsing output: 118/ for test: gem_mmap_gtt
results: parsing output: 119/ for test: api_intel_bb
results: parsing output: 120/ for test: kms_ccs
results: parsing output: 121/ for test: gem_exec_whisper
results: parsing output: 122/ for test: kms_flip
results: parsing output: 123/ for test: kms_frontbuffer_tracking
results: parsing output: 124/ for test: kms_dp_linktrain_fallback
results: parsing output: 125/ for test: gem_exec_alignment
results: parsing output: 126/ for test: kms_big_fb
results: parsing output: 127/ for test: kms_ccs
results: parsing output: 128/ for test: kms_psr2_sf
results: parsing output: 129/ for test: gem_exec_reloc
results: parsing output: 130/ for test: gem_exec_fence
results: parsing output: 131/ for test: kms_cursor_crc
results: parsing output: 132/ for test: syncobj_wait
Done.