[63.516931] Initializing watchdogs
[63.516984]   /dev/watchdog0
[63.531713] [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]
[63.535998] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[63.538256] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[63.541638] [001/133] (960s left) kms_plane (pixel-format-y-tiled-modifier-source-clamping)
[63.662876] Starting subtest: pixel-format-y-tiled-modifier-source-clamping
[63.667359] Subtest pixel-format-y-tiled-modifier-source-clamping: SKIP (0.000s)
[63.752787] [002/133] (960s left) gem_mmap_gtt (fault-concurrent-y)
[63.812209] Subtest fault-concurrent-Y: SKIP (0.000s)
[63.873506] [003/133] (960s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-mmap-gtt)
[63.967156] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-mmap-gtt
[63.971320] Subtest fbc-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[64.059959] [004/133] (960s left) kms_chamelium_hpd (dp-hpd-storm-disable)
[64.441348] Subtest dp-hpd-storm-disable: SKIP (0.000s)
[65.217718] [005/133] (958s left) kms_atomic_transition (plane-all-modeset-transition-fencing-internal-panels)
[65.320542] Starting subtest: plane-all-modeset-transition-fencing-internal-panels
[65.322769] Starting dynamic subtest: pipe-A-eDP-1
[66.689539] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.374s)
[66.965996] Starting dynamic subtest: pipe-B-eDP-1
[68.013384] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.057s)
[68.292624] Subtest plane-all-modeset-transition-fencing-internal-panels: SUCCESS (2.980s)
[69.139556] [006/133] (955s left) gem_mmap_wc (read)
[69.196805] Subtest read: SKIP (0.000s)
[69.250503] [007/133] (955s left) kms_dsc (dsc-basic)
[69.336669] Starting subtest: dsc-basic
[69.349326] Subtest dsc-basic: SKIP (0.000s)
[69.404540] [008/133] (954s left) kms_addfb_basic (unused-offsets)
[69.457714] Starting subtest: unused-offsets
[69.459778] Subtest unused-offsets: SUCCESS (0.000s)
[69.601139] [009/133] (954s left) gem_pxp (create-valid-protected-context)
[69.726686] Starting subtest: create-valid-protected-context
[69.729365] Subtest create-valid-protected-context: SUCCESS (0.002s)
[69.831497] [010/133] (954s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-rc-ccs)
[69.918625] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-rc-ccs
[69.920519] Starting dynamic subtest: pipe-A-eDP-1
[70.802116] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.879s)
[70.811151] Starting dynamic subtest: pipe-B-eDP-1
[72.389263] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.585s)
[72.399280] Starting dynamic subtest: pipe-C-eDP-1
[73.842659] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.456s)
[73.852805] Starting dynamic subtest: pipe-D-eDP-1
[75.388218] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.547s)
[75.390222] Subtest crc-primary-rotation-180-4-tiled-mtl-rc-ccs: SUCCESS (5.470s)
[76.548272] [011/133] (947s left) kms_frontbuffer_tracking (fbcpsr-stridechange)
[76.651621] Starting subtest: fbcpsr-stridechange
[82.842850] Subtest fbcpsr-stridechange: SUCCESS (6.199s)
[82.976267] [012/133] (941s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-msflip-blt)
[83.080627] Starting subtest: fbcpsr-2p-primscrn-indfb-msflip-blt
[83.084977] Subtest fbcpsr-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[83.186051] [013/133] (941s left) kms_psr2_sf (psr2-plane-move-sf-dmg-area)
[83.288971] Starting subtest: psr2-plane-move-sf-dmg-area
[83.291277] Starting dynamic subtest: pipe-A-eDP-1
[87.818780] Dynamic subtest pipe-A-eDP-1: SUCCESS (4.535s)
[87.829736] Starting dynamic subtest: pipe-B-eDP-1
[93.473901] Dynamic subtest pipe-B-eDP-1: SUCCESS (5.654s)
[93.476340] Subtest psr2-plane-move-sf-dmg-area: SUCCESS (10.191s)
[94.350303] [014/133] (930s left) kms_cursor_edge_walk (64x64-top-edge)
[94.454249] Starting subtest: 64x64-top-edge
[94.456647] Starting dynamic subtest: pipe-A-eDP-1
[97.896931] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.443s)
[97.907035] Starting dynamic subtest: pipe-D-eDP-1
[101.035489] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.137s)
[101.037801] Subtest 64x64-top-edge: SUCCESS (6.582s)
[101.837578] [015/133] (922s left) vgem_basic (dmabuf-mmap)
[101.915160] Starting subtest: dmabuf-mmap
[101.921814] Subtest dmabuf-mmap: SUCCESS (0.009s)
[101.981773] [FACT vgem_basic (dmabuf-mmap)] new: kernel.kmod_is_loaded.vgem: true
[101.986283] [016/133] (922s left) kms_plane_scaling (planes-downscale-factor-0-75-upscale-factor-0-25)
[102.073200] Starting subtest: planes-downscale-factor-0-75-upscale-factor-0-25
[102.075781] Starting dynamic subtest: pipe-A
[102.137119] Dynamic subtest pipe-A: SUCCESS (0.065s)
[102.144881] Starting dynamic subtest: pipe-B
[103.263401] Dynamic subtest pipe-B: SUCCESS (1.125s)
[103.271926] Starting dynamic subtest: pipe-C
[104.376798] Dynamic subtest pipe-C: SUCCESS (1.112s)
[104.386256] Starting dynamic subtest: pipe-D
[105.518323] Dynamic subtest pipe-D: SUCCESS (1.141s)
[105.520698] Subtest planes-downscale-factor-0-75-upscale-factor-0-25: SUCCESS (3.447s)
[106.663128] [017/133] (918s left) kms_ccs (bad-rotation-90-yf-tiled-ccs)
[106.756017] Starting subtest: bad-rotation-90-yf-tiled-ccs
[106.758316] Starting dynamic subtest: pipe-A-eDP-1
[106.781144] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[106.791100] Starting dynamic subtest: pipe-B-eDP-1
[106.812796] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[106.820087] Starting dynamic subtest: pipe-C-eDP-1
[106.841812] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[106.849519] Starting dynamic subtest: pipe-D-eDP-1
[106.871581] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[106.875656] Subtest bad-rotation-90-yf-tiled-ccs: SKIP (0.003s)
[109.034253] [018/133] (915s left) gem_ctx_exec (basic)
[109.098654] Starting subtest: basic
[109.108353] Subtest basic: SUCCESS (0.009s)
[109.246692] [019/133] (915s left) gem_exec_schedule (pi-shared-iova)
[109.422715] Starting subtest: pi-shared-iova
[109.425246] Starting dynamic subtest: rcs0
[109.493502] Dynamic subtest rcs0: SUCCESS (0.075s)
[109.505871] Starting dynamic subtest: vcs0
[109.544752] Dynamic subtest vcs0: SUCCESS (0.051s)
[109.556522] Starting dynamic subtest: vcs1
[109.595246] Dynamic subtest vcs1: SUCCESS (0.050s)
[109.604791] Starting dynamic subtest: vecs0
[109.641563] Dynamic subtest vecs0: SUCCESS (0.046s)
[109.653896] Starting dynamic subtest: ccs0
[109.703658] Dynamic subtest ccs0: SUCCESS (0.061s)
[109.706116] Subtest pi-shared-iova: SUCCESS (0.285s)
[111.398130] [020/133] (913s left) device_reset (unbind-reset-rebind)
[111.449474] Starting subtest: unbind-reset-rebind
[116.407524] Subtest unbind-reset-rebind: SUCCESS (4.954s)
[116.450656] [021/133] (908s left) syncobj_timeline (wait-all-snapshot)
[116.512931] Starting subtest: wait-all-snapshot
[116.596083] Subtest wait-all-snapshot: SUCCESS (0.083s)
[116.671326] [022/133] (908s left) gem_mmap_gtt (medium-copy-xy)
[116.722475] Subtest medium-copy-XY: SKIP (0.000s)
[116.781284] [023/133] (908s left) syncobj_timeline (multi-wait-for-submit-available-submitted-signaled)
[116.826335] Starting subtest: multi-wait-for-submit-available-submitted-signaled
[116.828747] Subtest multi-wait-for-submit-available-submitted-signaled: SUCCESS (0.000s)
[116.909587] [024/133] (908s left) perf_pmu (busy-check-all)
[116.955090] Starting subtest: busy-check-all
[116.957075] Starting dynamic subtest: rcs0
[117.515923] Dynamic subtest rcs0: SUCCESS (0.563s)
[117.528843] Starting dynamic subtest: bcs0
[118.068292] Dynamic subtest bcs0: SUCCESS (0.551s)
[118.080687] Starting dynamic subtest: vcs0
[119.301294] Dynamic subtest vcs0: SUCCESS (1.230s)
[119.313691] Starting dynamic subtest: vcs1
[121.285707] Dynamic subtest vcs1: SUCCESS (1.982s)
[121.296728] Starting dynamic subtest: vecs0
[123.269075] Dynamic subtest vecs0: SUCCESS (1.981s)
[123.281069] Starting dynamic subtest: ccs0
[123.822341] Dynamic subtest ccs0: SUCCESS (0.553s)
[123.824864] Subtest busy-check-all: SUCCESS (6.871s)
[123.932671] [025/133] (901s left) syncobj_timeline (invalid-query-illegal-handle)
[123.988341] Starting subtest: invalid-query-illegal-handle
[123.990591] Subtest invalid-query-illegal-handle: SUCCESS (0.000s)
[124.079589] [026/133] (900s left) kms_cursor_legacy (cursora-vs-flipa-atomic-transitions)
[124.212123] Starting subtest: cursorA-vs-flipA-atomic-transitions
[135.404739] Subtest cursorA-vs-flipA-atomic-transitions: SUCCESS (11.192s)
[136.196223] [027/133] (888s left) gem_tiled_swapping (non-threaded)
[136.269731] Subtest non-threaded: SKIP (0.000s)
[136.343256] [028/133] (888s left) kms_plane_multiple (tiling-x)
[136.436219] Starting subtest: tiling-x
[136.438506] Starting dynamic subtest: pipe-A-eDP-1
[140.236374] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.797s)
[140.246441] Starting dynamic subtest: pipe-B-eDP-1
[144.589341] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.353s)
[144.599677] Starting dynamic subtest: pipe-C-eDP-1
[148.930238] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.341s)
[148.940688] Starting dynamic subtest: pipe-D-eDP-1
[153.279629] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.346s)
[153.282180] Subtest tiling-x: SUCCESS (16.842s)
[154.084353] [029/133] (871s left) kms_big_fb (yf-tiled-16bpp-rotate-270)
[154.195671] Starting subtest: yf-tiled-16bpp-rotate-270
[154.200071] Subtest yf-tiled-16bpp-rotate-270: SKIP (0.000s)
[154.296269] [030/133] (870s left) kms_psr (pr-cursor-mmap-cpu)
[154.388473] Starting subtest: pr-cursor-mmap-cpu
[154.392754] Subtest pr-cursor-mmap-cpu: SKIP (0.000s)
[154.462452] [031/133] (870s left) gem_ctx_param (invalid-get-ringsize)
[154.512891] Starting subtest: invalid-get-ringsize
[154.515216] Subtest invalid-get-ringsize: SUCCESS (0.002s)
[154.628243] [032/133] (870s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-wc)
[154.725682] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-wc
[161.317730] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-wc: SUCCESS (6.593s)
[161.453544] [033/133] (863s left) gem_exec_whisper (basic-fds-all)
[161.515059] Starting subtest: basic-fds-all
[172.785842] Subtest basic-fds-all: SUCCESS (11.266s)
[172.967569] [034/133] (852s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite)
[173.084486] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite
[173.088864] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[173.200409] [035/133] (852s left) syncobj_wait (invalid-multi-wait-unsubmitted)
[173.246304] Starting subtest: invalid-multi-wait-unsubmitted
[173.248153] Subtest invalid-multi-wait-unsubmitted: SUCCESS (0.000s)
[173.332267] [036/133] (852s left) gem_mmap_offset (close-race)
[173.387491] Starting subtest: close-race
[193.488256] Subtest close-race: SUCCESS (20.100s)
[193.635224] [037/133] (831s left) kms_psr (psr-cursor-render)
[193.724958] Starting subtest: psr-cursor-render
[193.727255] Starting dynamic subtest: eDP-1
[195.406252] Dynamic subtest eDP-1: SUCCESS (1.680s)
[195.408585] Subtest psr-cursor-render: SUCCESS (1.681s)
[196.276415] [038/133] (829s left) perf_pmu (interrupts)
[196.318351] Starting subtest: interrupts
[197.412156] Subtest interrupts: SUCCESS (1.092s)
[197.521672] [039/133] (828s left) api_intel_allocator (alloc-simple)
[197.576753] Starting subtest: alloc-simple
[197.578914] Subtest alloc-simple: SUCCESS (0.004s)
[197.715170] [040/133] (827s left) kms_psr (psr-primary-blt)
[197.806784] Starting subtest: psr-primary-blt
[197.808999] Starting dynamic subtest: eDP-1
[199.568086] Dynamic subtest eDP-1: SUCCESS (1.758s)
[199.570020] Subtest psr-primary-blt: SUCCESS (1.759s)
[200.448371] [041/133] (825s left) kms_cursor_crc (cursor-random-512x170)
[200.749285] Starting subtest: cursor-random-512x170
[200.753722] Subtest cursor-random-512x170: SKIP (0.001s)
[200.913334] [042/133] (824s left) syncobj_timeline (etime-multi-wait-all-available-unsubmitted-signaled)
[200.965959] Starting subtest: etime-multi-wait-all-available-unsubmitted-signaled
[201.073385] Subtest etime-multi-wait-all-available-unsubmitted-signaled: SUCCESS (0.106s)
[201.148413] [043/133] (824s left) i915_pm_rpm (gem-execbuf-stress-pc8)
[203.484586] Starting subtest: gem-execbuf-stress-pc8
[203.535228] Subtest gem-execbuf-stress-pc8: SKIP (0.053s)
[203.865411] [044/133] (821s left) gem_reg_read (bad-register)
[203.921094] Starting subtest: bad-register
[203.923282] Subtest bad-register: SUCCESS (0.000s)
[204.016601] [045/133] (821s left) gem_mmap_gtt (basic-small-copy-odd)
[204.079579] Subtest basic-small-copy-odd: SKIP (0.000s)
[204.137414] [046/133] (821s left) i915_hangman (gt-engine-hang)
[204.193179] Starting subtest: gt-engine-hang
[204.195090] Starting dynamic subtest: rcs0
[205.285746] Dynamic subtest rcs0: SUCCESS (1.091s)
[205.297609] Starting dynamic subtest: bcs0
[207.267198] Dynamic subtest bcs0: SUCCESS (1.980s)
[207.279636] Starting dynamic subtest: vcs0
[209.251671] Dynamic subtest vcs0: SUCCESS (1.981s)
[209.265573] Starting dynamic subtest: vcs1
[211.236021] Dynamic subtest vcs1: SUCCESS (1.981s)
[211.249703] Starting dynamic subtest: vecs0
[213.283378] Dynamic subtest vecs0: SUCCESS (2.044s)
[213.298967] Starting dynamic subtest: ccs0
[215.267928] Dynamic subtest ccs0: SUCCESS (1.981s)
[215.270489] Subtest gt-engine-hang: SUCCESS (11.076s)
[215.389349] [047/133] (810s left) fbdev (pan)
[215.425773] Starting subtest: pan
[215.447971] Subtest pan: SUCCESS (0.026s)
[215.518067] [048/133] (810s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0-hflip)
[215.621270] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0-hflip
[216.720087] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0-hflip: FAIL (0.500s)
[216.763820] [049/133] (809s left) prime_self_import (basic-with_one_bo)
[216.796920] Starting subtest: basic-with_one_bo
[216.821792] Subtest basic-with_one_bo: SUCCESS (0.024s)
[216.949935] [050/133] (809s left) kms_flip (2x-flip-vs-modeset-vs-hang)
[217.033856] Starting subtest: 2x-flip-vs-modeset-vs-hang
[217.037766] Subtest 2x-flip-vs-modeset-vs-hang: SKIP (0.002s)
[217.164840] [051/133] (808s left) kms_flip (2x-flip-vs-suspend)
[217.251924] Starting subtest: 2x-flip-vs-suspend
[217.255599] Subtest 2x-flip-vs-suspend: SKIP (0.002s)
[217.362810] [052/133] (808s left) i915_query (query-garbage)
[217.419613] Starting subtest: query-garbage
[217.421288] Subtest query-garbage: SUCCESS (0.000s)
[217.512434] [053/133] (808s left) kms_pm_dc (dc5-retention-flops)
[218.602664] Starting subtest: dc5-retention-flops
[218.606855] Subtest dc5-retention-flops: SKIP (0.000s)
[218.677091] [054/133] (807s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0-async-flip)
[218.774793] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0-async-flip
[218.779150] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SKIP (0.000s)
[218.889238] [055/133] (807s left) kms_atomic_interruptible (legacy-dpms)
[218.989037] Starting subtest: legacy-dpms
[218.991123] Starting dynamic subtest: pipe-A-eDP-1
[226.420412] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.429s)
[226.422600] Subtest legacy-dpms: SUCCESS (7.431s)
[227.255698] [056/133] (799s left) gem_basic (create-close)
[227.314774] Starting subtest: create-close
[227.319079] Subtest create-close: SUCCESS (0.000s)
[227.429134] [057/133] (798s left) sysfs_timeslice_duration (idempotent)
[227.491883] Starting subtest: idempotent
[227.494151] Starting dynamic subtest: vecs0
[227.496260] Dynamic subtest vecs0: SUCCESS (0.001s)
[227.505778] Starting dynamic subtest: ccs0
[227.507889] Dynamic subtest ccs0: SUCCESS (0.000s)
[227.517087] Starting dynamic subtest: vcs0
[227.519218] Dynamic subtest vcs0: SUCCESS (0.000s)
[227.528500] Starting dynamic subtest: bcs0
[227.530699] Dynamic subtest bcs0: SUCCESS (0.000s)
[227.539755] Starting dynamic subtest: rcs0
[227.541610] Dynamic subtest rcs0: SUCCESS (0.000s)
[227.550743] Starting dynamic subtest: vcs1
[227.553053] Dynamic subtest vcs1: SUCCESS (0.000s)
[227.555259] Subtest idempotent: SUCCESS (0.007s)
[227.593644] [058/133] (798s left) kms_cursor_crc (cursor-offscreen-128x128)
[227.797170] Starting subtest: cursor-offscreen-128x128
[227.799394] Starting dynamic subtest: pipe-A-eDP-1
[229.737548] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.938s)
[229.771088] Starting dynamic subtest: pipe-D-eDP-1
[232.346984] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.583s)
[232.349665] Subtest cursor-offscreen-128x128: SUCCESS (4.550s)
[233.198795] [059/133] (793s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-mmap-gtt)
[233.290741] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-mmap-gtt
[233.295101] Subtest fbc-1p-offscreen-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[233.384065] [060/133] (793s left) gem_pxp (regular-baseline-src-copy-readible)
[233.498179] Starting subtest: regular-baseline-src-copy-readible
[233.557521] Subtest regular-baseline-src-copy-readible: SUCCESS (0.061s)
[233.691139] [061/133] (792s left) kms_psr2_sf (fbc-pr-overlay-plane-move-continuous-exceed-fully-sf)
[233.795266] Starting subtest: fbc-pr-overlay-plane-move-continuous-exceed-fully-sf
[233.799192] Subtest fbc-pr-overlay-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[233.875559] [062/133] (792s left) gem_exec_schedule (fairslice)
[233.937276] Starting subtest: fairslice
[233.939270] Starting dynamic subtest: rcs0
[235.951688] Dynamic subtest rcs0: SUCCESS (2.020s)
[235.963155] Starting dynamic subtest: bcs0
[237.972792] Dynamic subtest bcs0: SUCCESS (2.020s)
[237.985858] Starting dynamic subtest: vcs0
[239.993599] Dynamic subtest vcs0: SUCCESS (2.022s)
[240.006225] Starting dynamic subtest: vcs1
[242.012682] Dynamic subtest vcs1: SUCCESS (2.018s)
[242.025381] Starting dynamic subtest: vecs0
[244.028673] Dynamic subtest vecs0: SUCCESS (2.015s)
[244.042620] Starting dynamic subtest: ccs0
[246.047487] Dynamic subtest ccs0: SUCCESS (2.018s)
[246.049723] Subtest fairslice: SUCCESS (12.119s)
[246.349462] [063/133] (780s left) kms_frontbuffer_tracking (psr-2p-rte)
[246.455205] Starting subtest: psr-2p-rte
[246.459489] Subtest psr-2p-rte: SKIP (0.000s)
[246.555441] [064/133] (780s left) kms_color_pipeline (plane-lut1d-pre-ctm3x4)
[246.652348] Starting subtest: plane-lut1d-pre-ctm3x4
[246.799091] Starting dynamic subtest: pipe-A-plane-0
[246.954492] Dynamic subtest pipe-A-plane-0: SUCCESS (0.155s)
[246.965107] Starting dynamic subtest: pipe-A-plane-1
[247.119819] Dynamic subtest pipe-A-plane-1: SUCCESS (0.166s)
[247.135810] Starting dynamic subtest: pipe-A-plane-2
[247.253678] Dynamic subtest pipe-A-plane-2: SUCCESS (0.133s)
[248.507796] Starting dynamic subtest: pipe-B-plane-0
[248.661861] Dynamic subtest pipe-B-plane-0: SUCCESS (0.166s)
[248.675233] Starting dynamic subtest: pipe-B-plane-1
[248.850483] Dynamic subtest pipe-B-plane-1: SUCCESS (0.188s)
[248.860700] Starting dynamic subtest: pipe-B-plane-2
[249.005748] Dynamic subtest pipe-B-plane-2: SUCCESS (0.155s)
[250.245581] Starting dynamic subtest: pipe-C-plane-0
[250.392634] Dynamic subtest pipe-C-plane-0: SUCCESS (0.155s)
[250.404150] Starting dynamic subtest: pipe-C-plane-1
[250.537192] Dynamic subtest pipe-C-plane-1: SUCCESS (0.144s)
[250.550258] Starting dynamic subtest: pipe-C-plane-2
[250.681851] Dynamic subtest pipe-C-plane-2: SUCCESS (0.144s)
[251.884837] Starting dynamic subtest: pipe-D-plane-0
[252.026414] Dynamic subtest pipe-D-plane-0: SUCCESS (0.154s)
[252.038088] Starting dynamic subtest: pipe-D-plane-1
[252.159685] Dynamic subtest pipe-D-plane-1: SUCCESS (0.133s)
[252.170308] Starting dynamic subtest: pipe-D-plane-2
[252.293296] Dynamic subtest pipe-D-plane-2: SUCCESS (0.133s)
[252.585737] Subtest plane-lut1d-pre-ctm3x4: SUCCESS (5.933s)
[253.416195] [065/133] (773s left) gem_exec_balancer (semaphore)
[253.470131] Starting subtest: semaphore
[255.301045] Subtest semaphore: SUCCESS (1.829s)
[255.632880] [066/133] (771s left) kms_content_protection (dp-mst-type-0)
[255.781165] Starting subtest: dp-mst-type-0
[255.785593] Subtest dp-mst-type-0: SKIP (0.000s)
[255.905827] [067/133] (770s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-render)
[256.003625] Starting subtest: psr-2p-primscrn-pri-indfb-draw-render
[256.007779] Subtest psr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[256.109175] [068/133] (770s left) kms_cursor_legacy (2x-flip-vs-cursor-atomic)
[256.226672] Starting subtest: 2x-flip-vs-cursor-atomic
[256.231575] Subtest 2x-flip-vs-cursor-atomic: SKIP (0.001s)
[256.377413] [069/133] (770s left) kms_lease (lease-invalid-crtc)
[256.470991] Starting subtest: lease-invalid-crtc
[256.473334] Starting dynamic subtest: pipe-A-eDP-1
[256.475466] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[256.486292] Starting dynamic subtest: pipe-B-eDP-1
[256.488492] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[256.498093] Starting dynamic subtest: pipe-C-eDP-1
[256.500395] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[256.509730] Starting dynamic subtest: pipe-D-eDP-1
[256.511801] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[256.513939] Subtest lease-invalid-crtc: SUCCESS (0.005s)
[256.552322] [070/133] (770s left) kms_cursor_legacy (cursor-vs-flip-atomic-transitions)
[256.680675] Starting subtest: cursor-vs-flip-atomic-transitions
[286.969732] Subtest cursor-vs-flip-atomic-transitions: SUCCESS (30.286s)
[287.841889] [071/133] (739s left) gem_render_copy (yf-tiled-to-vebox-y-tiled)
[287.912806] Starting subtest: yf-tiled-to-vebox-y-tiled
[287.916949] Subtest yf-tiled-to-vebox-y-tiled: SKIP (0.000s)
[288.094499] [072/133] (738s left) kms_flip_scaled_crc (flip-64bpp-linear-to-32bpp-linear-downscaling)
[288.183736] Starting subtest: flip-64bpp-linear-to-32bpp-linear-downscaling
[288.186102] Starting dynamic subtest: pipe-A-default-mode
[289.298930] Dynamic subtest pipe-A-default-mode: SKIP (1.114s)
[289.303182] Subtest flip-64bpp-linear-to-32bpp-linear-downscaling: SKIP (1.116s)
[289.456973] [073/133] (737s left) syncobj_timeline (etime-single-wait-available-unsubmitted)
[289.504485] Starting subtest: etime-single-wait-available-unsubmitted
[289.609257] Subtest etime-single-wait-available-unsubmitted: SUCCESS (0.104s)
[289.699464] [074/133] (737s left) kms_chamelium_color (ctm-0-50)
[289.785934] Subtest ctm-0-50: SKIP (0.000s)
[289.834406] [075/133] (737s left) kms_invalid_mode (overflow-vrefresh)
[289.932519] Starting subtest: overflow-vrefresh
[289.934783] Starting dynamic subtest: pipe-A-eDP-1
[290.219170] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.287s)
[290.231842] Starting dynamic subtest: pipe-B-eDP-1
[290.234180] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[290.244063] Starting dynamic subtest: pipe-C-eDP-1
[290.246378] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[290.256909] Starting dynamic subtest: pipe-D-eDP-1
[290.259238] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[290.261446] Subtest overflow-vrefresh: SUCCESS (0.293s)
[291.012906] [076/133] (736s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-move)
[291.117696] Starting subtest: psr-1p-primscrn-spr-indfb-move
[298.489221] Subtest psr-1p-primscrn-spr-indfb-move: SUCCESS (7.374s)
[298.628092] [077/133] (728s left) gem_create (hog-create)
[298.688815] Starting subtest: hog-create
[298.690943] Starting dynamic subtest: smem0
[332.239761] Dynamic subtest smem0: SUCCESS (33.549s)
[332.242531] Subtest hog-create: SUCCESS (33.552s)
[332.429200] [078/133] (694s left) gem_unref_active_buffers
[332.646965] [079/133] (694s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0)
[332.753689] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0
[332.758042] Subtest y-tiled-max-hw-stride-64bpp-rotate-0: SKIP (0.000s)
[332.883229] [080/133] (694s left) kms_big_fb (y-tiled-addfb-size-overflow)
[332.971897] Starting subtest: y-tiled-addfb-size-overflow
[332.976441] Subtest y-tiled-addfb-size-overflow: SKIP (0.000s)
[333.079578] [081/133] (694s left) gem_exec_reloc (basic-write-cpu-active)
[333.135149] Subtest basic-write-cpu-active: SKIP (0.000s)
[333.209987] [082/133] (694s left) kms_pm_rpm (legacy-planes-dpms)
[335.435887] Starting subtest: legacy-planes-dpms
[335.437931] Starting dynamic subtest: plane-65
[343.865355] Dynamic subtest plane-65: SUCCESS (8.431s)
[343.880511] Starting dynamic subtest: plane-95
[352.192661] Dynamic subtest plane-95: SUCCESS (8.325s)
[352.205029] Starting dynamic subtest: plane-125
[360.806860] Dynamic subtest plane-125: SUCCESS (8.610s)
[360.827049] Starting dynamic subtest: plane-135
[369.140110] Dynamic subtest plane-135: SUCCESS (8.330s)
[369.142971] Subtest legacy-planes-dpms: SUCCESS (33.708s)
[369.537339] [083/133] (657s left) kms_pipe_crc_basic (disable-crc-after-crtc)
[369.626260] Starting subtest: disable-crc-after-crtc
[369.628614] Starting dynamic subtest: pipe-A-eDP-1
[371.270234] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.644s)
[371.281071] Starting dynamic subtest: pipe-B-eDP-1
[373.638836] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.368s)
[373.650823] Starting dynamic subtest: pipe-C-eDP-1
[375.866500] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.226s)
[375.877913] Starting dynamic subtest: pipe-D-eDP-1
[378.159449] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.291s)
[378.161770] Subtest disable-crc-after-crtc: SUCCESS (8.535s)
[378.977521] [084/133] (648s left) prime_udl
[379.065790] [085/133] (648s left) kms_frontbuffer_tracking (fbcpsr-modesetfrombusy)
[379.167474] Starting subtest: fbcpsr-modesetfrombusy
[385.444495] Subtest fbcpsr-modesetfrombusy: SUCCESS (6.280s)
[385.624475] [086/133] (641s left) kms_big_fb (x-tiled-8bpp-rotate-270)
[385.729204] Starting subtest: x-tiled-8bpp-rotate-270
[385.754853] Subtest x-tiled-8bpp-rotate-270: SKIP (0.023s)
[385.865646] [087/133] (641s left) gem_exec_fence (syncobj-repeat)
[386.353871] Starting subtest: syncobj-repeat
[386.537579] Subtest syncobj-repeat: SUCCESS (0.182s)
[387.360668] [088/133] (640s left) gem_softpin (zero)
[387.414795] Starting subtest: zero
[387.420144] Subtest zero: SUCCESS (0.004s)
[387.526764] [089/133] (640s left) gem_ctx_persistence (heartbeat-hostile)
[387.592095] Starting subtest: heartbeat-hostile
[387.596623] Subtest heartbeat-hostile: SKIP (0.000s)
[387.693299] [090/133] (640s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-render)
[387.797225] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-render
[387.801474] Subtest fbc-2p-scndscrn-pri-indfb-draw-render: SKIP (0.000s)
[387.910772] [091/133] (639s left) kms_chamelium_frames (hdmi-crc-multiple)
[388.291605] Subtest hdmi-crc-multiple: SKIP (0.000s)
[389.121217] [092/133] (638s left) kms_async_flips (async-flip-with-page-flip-events-linear)
[389.215072] Starting subtest: async-flip-with-page-flip-events-linear
[389.591602] Starting dynamic subtest: pipe-A-eDP-1
[392.328315] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.736s)
[392.703700] Starting dynamic subtest: pipe-B-eDP-1
[395.454956] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.760s)
[395.816662] Starting dynamic subtest: pipe-C-eDP-1
[398.582450] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.772s)
[398.965730] Starting dynamic subtest: pipe-D-eDP-1
[401.711289] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.756s)
[401.714272] Subtest async-flip-with-page-flip-events-linear: SUCCESS (12.497s)
[402.897033] [093/133] (624s left) kms_psr2_sf (pr-cursor-plane-move-continuous-exceed-sf)
[403.002646] Starting subtest: pr-cursor-plane-move-continuous-exceed-sf
[403.006914] Subtest pr-cursor-plane-move-continuous-exceed-sf: SKIP (0.000s)
[403.069946] [094/133] (624s left) gem_exec_params (secure-non-master)
[403.131264] Starting subtest: secure-non-master
[403.135972] Subtest secure-non-master: SKIP (0.000s)
[403.229980] [095/133] (624s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-shrfb-plflip-blt)
[403.319177] Starting subtest: fbcpsr-2p-primscrn-shrfb-plflip-blt
[403.323358] Subtest fbcpsr-2p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[403.413896] [096/133] (624s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-render)
[403.507761] Starting subtest: fbc-rgb101010-draw-render
[408.119081] Subtest fbc-rgb101010-draw-render: SUCCESS (4.614s)
[409.318807] [097/133] (618s left) api_intel_bb (reset-flags)
[409.370086] Starting subtest: reset-flags
[409.376953] Subtest reset-flags: SUCCESS (0.007s)
[409.493518] [098/133] (618s left) syncobj_wait (single-wait-submitted)
[409.546425] Starting subtest: single-wait-submitted
[409.657668] Subtest single-wait-submitted: SUCCESS (0.108s)
[409.740109] [099/133] (618s left) i915_query (engine-info)
[409.793529] Starting subtest: engine-info
[409.798585] Subtest engine-info: SUCCESS (0.005s)
[409.916925] [100/133] (618s left) perf_pmu (all-busy-check-all)
[409.954954] Starting subtest: all-busy-check-all
[411.332832] Subtest all-busy-check-all: SUCCESS (1.377s)
[411.417206] [101/133] (616s left) syncobj_basic (bad-flags-fd-to-handle)
[411.463959] Starting subtest: bad-flags-fd-to-handle
[411.466226] Subtest bad-flags-fd-to-handle: SUCCESS (0.000s)
[411.588715] [102/133] (616s left) gem_exec_schedule (u-submit-golden-slice)
[411.663110] Starting subtest: u-submit-golden-slice
[411.664897] Starting dynamic subtest: rcs0
[411.691045] Dynamic subtest rcs0: SUCCESS (0.034s)
[411.701469] Starting dynamic subtest: bcs0
[411.719086] Dynamic subtest bcs0: SUCCESS (0.027s)
[411.730673] Starting dynamic subtest: vcs0
[411.750314] Dynamic subtest vcs0: SUCCESS (0.032s)
[411.760808] Starting dynamic subtest: vcs1
[411.785265] Dynamic subtest vcs1: SUCCESS (0.033s)
[411.804570] Starting dynamic subtest: vecs0
[411.824990] Dynamic subtest vecs0: SUCCESS (0.039s)
[411.835470] Starting dynamic subtest: ccs0
[411.891351] Dynamic subtest ccs0: SUCCESS (0.066s)
[411.893912] Subtest u-submit-golden-slice: SUCCESS (0.235s)
[413.441866] [103/133] (614s left) i915_drm_fdinfo (memory-info-shared)
[413.481039] Starting subtest: memory-info-shared
[413.483326] Starting dynamic subtest: smem0
[413.551463] Dynamic subtest smem0: SUCCESS (0.070s)
[413.554046] Subtest memory-info-shared: SUCCESS (0.071s)
[413.626336] [104/133] (614s left) gem_mmap_wc (write-read-distinct)
[413.683230] Subtest write-read-distinct: SKIP (0.000s)
[413.745392] [105/133] (614s left) kms_selftest (drm_damage_helper)
[413.782834] Starting subtest: drm_damage_helper
[413.792280] Starting dynamic subtest: drm_test_damage_iter_no_damage
[413.895271] Dynamic subtest drm_test_damage_iter_no_damage: SUCCESS (0.101s)
[413.907545] Starting dynamic subtest: drm_test_damage_iter_no_damage_fractional_src
[413.989263] Dynamic subtest drm_test_damage_iter_no_damage_fractional_src: SUCCESS (0.094s)
[414.000969] Starting dynamic subtest: drm_test_damage_iter_no_damage_src_moved
[414.092183] Dynamic subtest drm_test_damage_iter_no_damage_src_moved: SUCCESS (0.102s)
[414.104561] Starting dynamic subtest: drm_test_damage_iter_no_damage_fractional_src_moved
[414.184891] Dynamic subtest drm_test_damage_iter_no_damage_fractional_src_moved: SUCCESS (0.091s)
[414.194598] Starting dynamic subtest: drm_test_damage_iter_no_damage_not_visible
[414.274314] Dynamic subtest drm_test_damage_iter_no_damage_not_visible: SUCCESS (0.089s)
[414.283733] Starting dynamic subtest: drm_test_damage_iter_no_damage_no_crtc
[414.384897] Dynamic subtest drm_test_damage_iter_no_damage_no_crtc: SUCCESS (0.110s)
[414.393983] Starting dynamic subtest: drm_test_damage_iter_no_damage_no_fb
[414.487826] Dynamic subtest drm_test_damage_iter_no_damage_no_fb: SUCCESS (0.102s)
[414.496838] Starting dynamic subtest: drm_test_damage_iter_simple_damage
[414.589984] Dynamic subtest drm_test_damage_iter_simple_damage: SUCCESS (0.100s)
[414.601146] Starting dynamic subtest: drm_test_damage_iter_single_damage
[414.691927] Dynamic subtest drm_test_damage_iter_single_damage: SUCCESS (0.101s)
[414.702515] Starting dynamic subtest: drm_test_damage_iter_single_damage_intersect_src
[414.793989] Dynamic subtest drm_test_damage_iter_single_damage_intersect_src: SUCCESS (0.100s)
[414.803276] Starting dynamic subtest: drm_test_damage_iter_single_damage_outside_src
[414.901904] Dynamic subtest drm_test_damage_iter_single_damage_outside_src: SUCCESS (0.107s)
[414.913449] Starting dynamic subtest: drm_test_damage_iter_single_damage_fractional_src
[415.005350] Dynamic subtest drm_test_damage_iter_single_damage_fractional_src: SUCCESS (0.103s)
[415.016563] Starting dynamic subtest: drm_test_damage_iter_single_damage_intersect_fractional_src
[415.108442] Dynamic subtest drm_test_damage_iter_single_damage_intersect_fractional_src: SUCCESS (0.102s)
[415.119851] Starting dynamic subtest: drm_test_damage_iter_single_damage_outside_fractional_src
[415.201477] Dynamic subtest drm_test_damage_iter_single_damage_outside_fractional_src: SUCCESS (0.091s)
[415.213049] Starting dynamic subtest: drm_test_damage_iter_single_damage_src_moved
[415.308600] Dynamic subtest drm_test_damage_iter_single_damage_src_moved: SUCCESS (0.107s)
[415.319479] Starting dynamic subtest: drm_test_damage_iter_single_damage_fractional_src_moved
[415.413436] Dynamic subtest drm_test_damage_iter_single_damage_fractional_src_moved: SUCCESS (0.103s)
[415.425086] Starting dynamic subtest: drm_test_damage_iter_damage
[415.531438] Dynamic subtest drm_test_damage_iter_damage: SUCCESS (0.115s)
[415.542619] Starting dynamic subtest: drm_test_damage_iter_damage_one_intersect
[415.630237] Dynamic subtest drm_test_damage_iter_damage_one_intersect: SUCCESS (0.100s)
[415.641702] Starting dynamic subtest: drm_test_damage_iter_damage_one_outside
[415.717203] Dynamic subtest drm_test_damage_iter_damage_one_outside: SUCCESS (0.085s)
[415.728698] Starting dynamic subtest: drm_test_damage_iter_damage_src_moved
[415.823631] Dynamic subtest drm_test_damage_iter_damage_src_moved: SUCCESS (0.106s)
[415.833370] Starting dynamic subtest: drm_test_damage_iter_damage_not_visible
[415.922926] Dynamic subtest drm_test_damage_iter_damage_not_visible: SUCCESS (0.098s)
[415.925124] Subtest drm_damage_helper: SUCCESS (2.142s)
[416.077374] [106/133] (612s left) prime_mmap (test_correct_cpu_write)
[416.138909] Starting subtest: test_correct_cpu_write
[416.141195] Starting dynamic subtest: test_correct_cpu_write-smem
[416.143449] Dynamic subtest test_correct_cpu_write-smem: SUCCESS (0.000s)
[416.145624] Subtest test_correct_cpu_write: SUCCESS (0.001s)
[416.253261] [107/133] (612s left) gen9_exec_parse (secure-batches)
[416.301734] Subtest secure-batches: SKIP (0.000s)
[416.392817] [108/133] (612s left) kms_atomic (plane-immutable-zpos)
[416.494198] Starting subtest: plane-immutable-zpos
[416.496522] Starting dynamic subtest: pipe-A-eDP-1
[417.136984] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.644s)
[417.139479] Subtest plane-immutable-zpos: SUCCESS (0.645s)
[417.947327] [109/133] (610s left) gem_exec_nop (basic-parallel)
[418.031776] Starting subtest: basic-parallel
[434.245636] Subtest basic-parallel: SUCCESS (16.223s)
[434.477918] [110/133] (594s left) kms_psr (fbc-psr-sprite-render)
[434.579911] Starting subtest: fbc-psr-sprite-render
[434.582242] Starting dynamic subtest: eDP-1
[434.586585] Dynamic subtest eDP-1: SKIP (0.000s)
[434.590990] Subtest fbc-psr-sprite-render: SKIP (0.001s)
[434.652473] [111/133] (593s left) gem_fenced_exec_thrash (2-spare-fences)
[434.710996] Subtest 2-spare-fences: SKIP (0.000s)
[434.772926] [112/133] (593s left) perf_pmu (idle)
[434.808132] Starting subtest: idle
[434.810601] Starting dynamic subtest: rcs0
[435.354766] Dynamic subtest rcs0: SUCCESS (0.548s)
[435.368243] Starting dynamic subtest: bcs0
[435.873986] Dynamic subtest bcs0: SUCCESS (0.516s)
[435.885706] Starting dynamic subtest: vcs0
[436.403921] Dynamic subtest vcs0: SUCCESS (0.527s)
[436.415803] Starting dynamic subtest: vcs1
[436.929757] Dynamic subtest vcs1: SUCCESS (0.520s)
[436.941911] Starting dynamic subtest: vecs0
[437.444246] Dynamic subtest vecs0: SUCCESS (0.516s)
[437.458061] Starting dynamic subtest: ccs0
[437.959095] Dynamic subtest ccs0: SUCCESS (0.512s)
[437.962241] Subtest idle: SUCCESS (3.154s)
[438.073127] [113/133] (590s left) kms_vblank (wait-busy-hang)
[438.177195] Starting subtest: wait-busy-hang
[438.179435] Starting dynamic subtest: pipe-A-eDP-1
[465.032575] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.852s)
[465.050577] Starting dynamic subtest: pipe-D-eDP-1
[490.068504] Dynamic subtest pipe-D-eDP-1: SUCCESS (25.035s)
[490.070755] Subtest wait-busy-hang: SUCCESS (51.891s)
[490.929500] [114/133] (537s left) gem_exec_balancer (noheartbeat)
[491.184681] Starting subtest: noheartbeat
[491.189273] Subtest noheartbeat: SKIP (0.000s)
[491.281372] [115/133] (537s left) kms_hdr (brightness-with-hdr)
[491.367352] Starting subtest: brightness-with-hdr
[491.373657] Subtest brightness-with-hdr: SKIP (0.000s)
[491.443709] [116/133] (537s left) kms_flip (blocking-wf_vblank)
[491.532597] Starting subtest: blocking-wf_vblank
[491.534529] Starting dynamic subtest: A-eDP1
[500.466649] Dynamic subtest A-eDP1: SUCCESS (8.935s)
[500.481302] Starting dynamic subtest: B-eDP1
[509.400827] Dynamic subtest B-eDP1: SUCCESS (8.931s)
[509.414187] Starting dynamic subtest: C-eDP1
[518.320533] Dynamic subtest C-eDP1: SUCCESS (8.915s)
[518.331940] Starting dynamic subtest: D-eDP1
[527.246053] Dynamic subtest D-eDP1: SUCCESS (8.926s)
[527.251125] Subtest blocking-wf_vblank: SUCCESS (35.721s)
[528.481822] [117/133] (500s left) kms_plane (pixel-format-linear-modifier-source-clamping)
[528.577204] Starting subtest: pixel-format-linear-modifier-source-clamping
[528.602887] Starting dynamic subtest: pipe-A-plane-0
[529.191580] Dynamic subtest pipe-A-plane-0: SUCCESS (0.589s)
[529.202941] Starting dynamic subtest: pipe-A-plane-3
[529.602287] Dynamic subtest pipe-A-plane-3: SUCCESS (0.410s)
[530.685602] Starting dynamic subtest: pipe-B-plane-0
[531.265811] Dynamic subtest pipe-B-plane-0: SUCCESS (0.589s)
[531.276758] Starting dynamic subtest: pipe-B-plane-3
[531.700075] Dynamic subtest pipe-B-plane-3: SUCCESS (0.433s)
[532.008795] Subtest pixel-format-linear-modifier-source-clamping: SUCCESS (3.433s)
[532.852470] [118/133] (496s left) gem_lmem_swapping (heavy-verify-multi)
[532.904204] Subtest heavy-verify-multi: SKIP (0.000s)
[532.955207] [119/133] (495s left) kms_frontbuffer_tracking (fbc-1p-primscrn-shrfb-pgflip-blt)
[533.048256] Starting subtest: fbc-1p-primscrn-shrfb-pgflip-blt
[537.584688] Subtest fbc-1p-primscrn-shrfb-pgflip-blt: SUCCESS (4.540s)
[538.760810] [120/133] (490s left) kms_big_fb (4-tiled-addfb-size-overflow)
[538.853646] Starting subtest: 4-tiled-addfb-size-overflow
[538.855946] Subtest 4-tiled-addfb-size-overflow: SUCCESS (0.000s)
[538.960190] [121/133] (490s left) gem_vm_create (invalid-create)
[539.018741] Starting subtest: invalid-create
[539.021055] Subtest invalid-create: SUCCESS (0.002s)
[539.131042] [122/133] (489s left) kms_ccs (random-ccs-data-4-tiled-dg2-rc-ccs)
[539.228375] Starting subtest: random-ccs-data-4-tiled-dg2-rc-ccs
[539.230655] Starting dynamic subtest: pipe-A-eDP-1
[539.255557] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[539.266936] Starting dynamic subtest: pipe-B-eDP-1
[539.290842] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[539.300207] Starting dynamic subtest: pipe-C-eDP-1
[539.324061] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[539.333337] Starting dynamic subtest: pipe-D-eDP-1
[539.358433] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[539.362934] Subtest random-ccs-data-4-tiled-dg2-rc-ccs: SKIP (0.004s)
[541.519167] [123/133] (487s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-wc)
[541.615708] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-wc
[548.678268] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-wc: SUCCESS (7.066s)
[548.803841] [124/133] (480s left) kms_psr (fbc-psr2-cursor-mmap-cpu)
[548.893126] Starting subtest: fbc-psr2-cursor-mmap-cpu
[548.895304] Starting dynamic subtest: eDP-1
[548.899195] Dynamic subtest eDP-1: SKIP (0.000s)
[548.903173] Subtest fbc-psr2-cursor-mmap-cpu: SKIP (0.001s)
[548.992771] [125/133] (480s left) syncobj_basic (bad-create-flags)
[549.051243] Starting subtest: bad-create-flags
[549.053729] Subtest bad-create-flags: SUCCESS (0.000s)
[549.157475] [126/133] (480s left) perf (enable-disable)
[549.286080] Starting subtest: enable-disable
[549.287984] Starting dynamic subtest: 0-rcs0
[551.531080] Dynamic subtest 0-rcs0: SUCCESS (2.246s)
[551.542802] Starting dynamic subtest: 1-vecs0
[555.977864] Dynamic subtest 1-vecs0: SUCCESS (4.445s)
[555.980303] Subtest enable-disable: SUCCESS (6.694s)
[556.028502] [127/133] (473s left) gem_ctx_shared (q-smoketest)
[556.097279] Starting subtest: Q-smoketest
[556.099572] Starting dynamic subtest: rcs0
[561.643486] Dynamic subtest rcs0: SUCCESS (5.491s)
[561.656615] Starting dynamic subtest: bcs0
[567.165470] Dynamic subtest bcs0: SUCCESS (5.520s)
[567.178175] Starting dynamic subtest: vcs0
[572.642499] Dynamic subtest vcs0: SUCCESS (5.480s)
[572.654104] Starting dynamic subtest: vcs1
[578.120616] Dynamic subtest vcs1: SUCCESS (5.478s)
[578.132685] Starting dynamic subtest: vecs0
[583.607496] Dynamic subtest vecs0: SUCCESS (5.485s)
[583.618892] Starting dynamic subtest: ccs0
[589.091060] Dynamic subtest ccs0: SUCCESS (5.483s)
[589.093410] Subtest Q-smoketest: SUCCESS (32.945s)
[589.231639] [128/133] (440s left) gem_exec_flush (basic-wb-rw-before-default)
[589.299055] Starting subtest: basic-wb-rw-before-default
[594.753573] Subtest basic-wb-rw-before-default: SUCCESS (5.457s)
[594.960673] [129/133] (434s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-wc)
[595.057834] Starting subtest: psr-rgb565-draw-mmap-wc
[601.823331] Subtest psr-rgb565-draw-mmap-wc: SUCCESS (6.767s)
[601.945871] [130/133] (427s left) kms_ccs (crc-primary-basic-yf-tiled-ccs)
[602.034084] Starting subtest: crc-primary-basic-yf-tiled-ccs
[602.036361] Starting dynamic subtest: pipe-A-eDP-1
[602.059599] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[602.071531] Starting dynamic subtest: pipe-B-eDP-1
[602.092542] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[602.102527] Starting dynamic subtest: pipe-C-eDP-1
[602.123549] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[602.133210] Starting dynamic subtest: pipe-D-eDP-1
[602.156375] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[602.160747] Subtest crc-primary-basic-yf-tiled-ccs: SKIP (0.006s)
[604.309589] [131/133] (425s left) kms_cursor_legacy (forked-move)
[604.432155] Starting subtest: forked-move
[604.434335] Starting dynamic subtest: pipe-A
[609.898057] Dynamic subtest pipe-A: SUCCESS (5.462s)
[609.911479] Starting dynamic subtest: pipe-B
[615.377673] Dynamic subtest pipe-B: SUCCESS (5.477s)
[615.392658] Starting dynamic subtest: pipe-C
[620.855401] Dynamic subtest pipe-C: SUCCESS (5.475s)
[620.874095] Starting dynamic subtest: pipe-D
[626.338327] Dynamic subtest pipe-D: SUCCESS (5.480s)
[626.353802] Starting dynamic subtest: all-pipes
[631.837751] Dynamic subtest all-pipes: SUCCESS (5.496s)
[631.840241] Subtest forked-move: SUCCESS (27.402s)
[631.987433] [132/133] (397s left) prime_vgem (fence-wait)
[632.083365] Starting subtest: fence-wait
[632.085673] Starting dynamic subtest: rcs0
[633.151384] Dynamic subtest rcs0: SUCCESS (1.064s)
[633.164047] Starting dynamic subtest: bcs0
[634.212289] Dynamic subtest bcs0: SUCCESS (1.061s)
[634.222748] Starting dynamic subtest: vcs0
[635.256302] Dynamic subtest vcs0: SUCCESS (1.043s)
[635.268720] Starting dynamic subtest: vcs1
[636.296133] Dynamic subtest vcs1: SUCCESS (1.039s)
[636.308864] Starting dynamic subtest: vecs0
[637.337098] Dynamic subtest vecs0: SUCCESS (1.038s)
[637.349724] Starting dynamic subtest: ccs0
[638.382348] Dynamic subtest ccs0: SUCCESS (1.042s)
[638.385301] Subtest fence-wait: SUCCESS (6.298s)
[638.540540] [133/133] (390s left) kms_flip (2x-absolute-wf_vblank-interruptible)
[638.643048] Starting subtest: 2x-absolute-wf_vblank-interruptible
[638.655205] Subtest 2x-absolute-wf_vblank-interruptible: SKIP (0.011s)
[638.772540] Closing watchdogs
results: parsing output: 0/ for test: kms_plane
results: parsing output: 1/ for test: gem_mmap_gtt
results: parsing output: 2/ for test: kms_frontbuffer_tracking
results: parsing output: 3/ for test: kms_chamelium_hpd
results: parsing output: 4/ for test: kms_atomic_transition
results: parsing output: 5/ for test: gem_mmap_wc
results: parsing output: 6/ for test: kms_dsc
results: parsing output: 7/ for test: kms_addfb_basic
results: parsing output: 8/ for test: gem_pxp
results: parsing output: 9/ for test: kms_ccs
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_psr2_sf
results: parsing output: 13/ for test: kms_cursor_edge_walk
results: parsing output: 14/ for test: vgem_basic
results: parsing output: 15/ for test: kms_plane_scaling
results: parsing output: 16/ for test: kms_ccs
results: parsing output: 17/ for test: gem_ctx_exec
results: parsing output: 18/ for test: gem_exec_schedule
results: parsing output: 19/ for test: device_reset
results: parsing output: 20/ for test: syncobj_timeline
results: parsing output: 21/ for test: gem_mmap_gtt
results: parsing output: 22/ for test: syncobj_timeline
results: parsing output: 23/ for test: perf_pmu
results: parsing output: 24/ for test: syncobj_timeline
results: parsing output: 25/ for test: kms_cursor_legacy
results: parsing output: 26/ for test: gem_tiled_swapping
results: parsing output: 27/ for test: kms_plane_multiple
results: parsing output: 28/ for test: kms_big_fb
results: parsing output: 29/ for test: kms_psr
results: parsing output: 30/ for test: gem_ctx_param
results: parsing output: 31/ for test: kms_frontbuffer_tracking
results: parsing output: 32/ for test: gem_exec_whisper
results: parsing output: 33/ for test: kms_frontbuffer_tracking
results: parsing output: 34/ for test: syncobj_wait
results: parsing output: 35/ for test: gem_mmap_offset
results: parsing output: 36/ for test: kms_psr
results: parsing output: 37/ for test: perf_pmu
results: parsing output: 38/ for test: api_intel_allocator
results: parsing output: 39/ for test: kms_psr
results: parsing output: 40/ for test: kms_cursor_crc
results: parsing output: 41/ for test: syncobj_timeline
results: parsing output: 42/ for test: i915_pm_rpm
results: parsing output: 43/ for test: gem_reg_read
results: parsing output: 44/ for test: gem_mmap_gtt
results: parsing output: 45/ for test: i915_hangman
results: parsing output: 46/ for test: fbdev
results: parsing output: 47/ for test: kms_big_fb
results: parsing output: 48/ for test: prime_self_import
results: parsing output: 49/ for test: kms_flip
results: parsing output: 50/ for test: kms_flip
results: parsing output: 51/ for test: i915_query
results: parsing output: 52/ for test: kms_pm_dc
results: parsing output: 53/ for test: kms_big_fb
results: parsing output: 54/ for test: kms_atomic_interruptible
results: parsing output: 55/ for test: gem_basic
results: parsing output: 56/ for test: sysfs_timeslice_duration
results: parsing output: 57/ for test: kms_cursor_crc
results: parsing output: 58/ for test: kms_frontbuffer_tracking
results: parsing output: 59/ for test: gem_pxp
results: parsing output: 60/ for test: kms_psr2_sf
results: parsing output: 61/ for test: gem_exec_schedule
results: parsing output: 62/ for test: kms_frontbuffer_tracking
results: parsing output: 63/ for test: kms_color_pipeline
results: parsing output: 64/ for test: gem_exec_balancer
results: parsing output: 65/ for test: kms_content_protection
results: parsing output: 66/ for test: kms_frontbuffer_tracking
results: parsing output: 67/ for test: kms_cursor_legacy
results: parsing output: 68/ for test: kms_lease
results: parsing output: 69/ for test: kms_cursor_legacy
results: parsing output: 70/ for test: gem_render_copy
results: parsing output: 71/ for test: kms_flip_scaled_crc
results: parsing output: 72/ for test: syncobj_timeline
results: parsing output: 73/ for test: kms_chamelium_color
results: parsing output: 74/ for test: kms_invalid_mode
results: parsing output: 75/ for test: kms_frontbuffer_tracking
results: parsing output: 76/ for test: gem_create
results: parsing output: 77/ for test: gem_unref_active_buffers
results: parsing output: 78/ for test: kms_big_fb
results: parsing output: 79/ for test: kms_big_fb
results: parsing output: 80/ for test: gem_exec_reloc
results: parsing output: 81/ for test: kms_pm_rpm
results: parsing output: 82/ for test: kms_pipe_crc_basic
results: parsing output: 83/ for test: prime_udl
results: parsing output: 84/ for test: kms_frontbuffer_tracking
results: parsing output: 85/ for test: kms_big_fb
results: parsing output: 86/ for test: gem_exec_fence
results: parsing output: 87/ for test: gem_softpin
results: parsing output: 88/ for test: gem_ctx_persistence
results: parsing output: 89/ for test: kms_frontbuffer_tracking
results: parsing output: 90/ for test: kms_chamelium_frames
results: parsing output: 91/ for test: kms_async_flips
results: parsing output: 92/ for test: kms_psr2_sf
results: parsing output: 93/ for test: gem_exec_params
results: parsing output: 94/ for test: kms_frontbuffer_tracking
results: parsing output: 95/ for test: kms_frontbuffer_tracking
results: parsing output: 96/ for test: api_intel_bb
results: parsing output: 97/ for test: syncobj_wait
results: parsing output: 98/ for test: i915_query
results: parsing output: 99/ for test: perf_pmu
results: parsing output: 100/ for test: syncobj_basic
results: parsing output: 101/ for test: gem_exec_schedule
results: parsing output: 102/ for test: i915_drm_fdinfo
results: parsing output: 103/ for test: gem_mmap_wc
results: parsing output: 104/ for test: kms_selftest
results: parsing output: 105/ for test: prime_mmap
results: parsing output: 106/ for test: gen9_exec_parse
results: parsing output: 107/ for test: kms_atomic
results: parsing output: 108/ for test: gem_exec_nop
results: parsing output: 109/ for test: kms_psr
results: parsing output: 110/ for test: gem_fenced_exec_thrash
results: parsing output: 111/ for test: perf_pmu
results: parsing output: 112/ for test: kms_vblank
results: parsing output: 113/ for test: gem_exec_balancer
results: parsing output: 114/ for test: kms_hdr
results: parsing output: 115/ for test: kms_flip
results: parsing output: 116/ for test: kms_plane
results: parsing output: 117/ for test: gem_lmem_swapping
results: parsing output: 118/ for test: kms_frontbuffer_tracking
results: parsing output: 119/ for test: kms_big_fb
results: parsing output: 120/ for test: gem_vm_create
results: parsing output: 121/ for test: kms_ccs
results: parsing output: 122/ for test: kms_frontbuffer_tracking
results: parsing output: 123/ for test: kms_psr
results: parsing output: 124/ for test: syncobj_basic
results: parsing output: 125/ for test: perf
results: parsing output: 126/ for test: gem_ctx_shared
results: parsing output: 127/ for test: gem_exec_flush
results: parsing output: 128/ for test: kms_frontbuffer_tracking
results: parsing output: 129/ for test: kms_ccs
results: parsing output: 130/ for test: kms_cursor_legacy
results: parsing output: 131/ for test: prime_vgem
results: parsing output: 132/ for test: kms_flip
Done.