[56.626975] Initializing watchdogs
[56.627035]   /dev/watchdog0
[56.641340] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics]
[56.645705] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[56.647919] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[56.651452] [001/133] (960s left) core_auth (getclient-simple)
[56.716262] Starting subtest: getclient-simple
[56.732055] Subtest getclient-simple: SUCCESS (0.016s)
[56.834959] [002/133] (960s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-wc)
[56.927571] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-wc
[56.932053] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[57.007194] [003/133] (960s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-indfb-msflip-blt)
[57.107647] Starting subtest: fbcpsr-2p-scndscrn-indfb-msflip-blt
[57.111788] Subtest fbcpsr-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[57.195839] [004/133] (960s left) gen7_exec_parse (batch-without-end)
[57.261666] Subtest batch-without-end: SKIP (0.000s)
[57.324390] [005/133] (959s left) kms_psr (fbc-psr-basic)
[57.410695] Starting subtest: fbc-psr-basic
[57.412772] Starting dynamic subtest: eDP-1
[57.416414] Dynamic subtest eDP-1: SKIP (0.000s)
[57.419934] Subtest fbc-psr-basic: SKIP (0.001s)
[57.481321] [006/133] (959s left) sw_sync (sync_busy)
[57.512590] Starting subtest: sync_busy
[57.514592] Subtest sync_busy: SUCCESS (0.002s)
[57.567496] [007/133] (959s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-onoff)
[57.666427] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-onoff
[57.670169] Subtest fbcpsr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[57.768763] [008/133] (959s left) gem_exec_store (basic)
[57.827114] Starting subtest: basic
[57.921389] Subtest basic: SUCCESS (0.094s)
[58.104214] [009/133] (959s left) gem_create (create-ext-placement-sanity-check)
[58.274620] Starting subtest: create-ext-placement-sanity-check
[58.277232] Subtest create-ext-placement-sanity-check: SUCCESS (0.005s)
[58.361515] [010/133] (959s left) gem_pxp (hw-rejects-pxp-buffer)
[58.486299] Starting subtest: hw-rejects-pxp-buffer
[58.491601] Subtest hw-rejects-pxp-buffer: SKIP (0.000s)
[58.602180] [011/133] (958s left) i915_selftest (perf)
[61.818054] Starting subtest: perf
[61.820429] Starting dynamic subtest: engine_cs
[66.074082] Dynamic subtest engine_cs: SUCCESS (4.256s)
[66.083967] Starting dynamic subtest: request
[71.405996] Dynamic subtest request: SUCCESS (5.331s)
[71.419268] Starting dynamic subtest: migrate
[74.219059] Dynamic subtest migrate: SUCCESS (2.812s)
[74.227901] Starting dynamic subtest: region
[79.913619] Dynamic subtest region: SUCCESS (5.693s)
[79.916071] Subtest perf: SUCCESS (18.096s)
[79.942192] [FACT i915_selftest (perf)] deleted: kernel.kmod_is_loaded.i915: true
[79.945409] [012/133] (937s left) kms_chamelium_hpd (hdmi-hpd-storm-disable)
[81.721153] Subtest hdmi-hpd-storm-disable: SKIP (0.000s)
[82.577092] [FACT kms_chamelium_hpd (hdmi-hpd-storm-disable)] new: kernel.kmod_is_loaded.i915: true
[82.580547] [013/133] (935s left) gem_exec_params (readonly)
[82.640656] Starting subtest: readonly
[82.645475] Subtest readonly: SUCCESS (0.004s)
[82.764831] [014/133] (934s left) kms_universal_plane (universal-plane-sanity)
[82.860861] Starting subtest: universal-plane-sanity
[82.862805] Starting dynamic subtest: pipe-A-eDP-1
[83.472262] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.611s)
[83.482352] Starting dynamic subtest: pipe-B-eDP-1
[84.709732] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.237s)
[84.719294] Starting dynamic subtest: pipe-C-eDP-1
[85.928078] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.215s)
[85.936942] Starting dynamic subtest: pipe-D-eDP-1
[87.160210] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.232s)
[87.162665] Subtest universal-plane-sanity: SUCCESS (4.299s)
[88.019479] [015/133] (929s left) gem_eio (wait-1us)
[88.187022] Starting subtest: wait-1us
[88.381501] Subtest wait-1us: SUCCESS (0.195s)
[88.518214] [016/133] (929s left) perf (invalid-remove-userspace-config)
[88.650663] Starting subtest: invalid-remove-userspace-config
[88.660282] Subtest invalid-remove-userspace-config: SUCCESS (0.010s)
[88.699515] [017/133] (929s left) kms_properties (plane-properties-atomic)
[88.793626] Starting subtest: plane-properties-atomic
[88.795577] Starting dynamic subtest: pipe-A-eDP-1
[89.368182] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.575s)
[89.377669] Starting dynamic subtest: pipe-B-eDP-1
[90.681596] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.312s)
[90.691946] Starting dynamic subtest: pipe-C-eDP-1
[91.922586] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.239s)
[91.932184] Starting dynamic subtest: pipe-D-eDP-1
[93.156561] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.234s)
[93.158610] Subtest plane-properties-atomic: SUCCESS (4.364s)
[93.948023] [018/133] (923s left) gem_unref_active_buffers
[94.138100] [019/133] (923s left) gem_exec_params (invalid-bsd-ring)
[94.193801] Starting subtest: invalid-bsd-ring
[94.196128] Subtest invalid-bsd-ring: SUCCESS (0.000s)
[94.296753] [020/133] (923s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-blt)
[94.395020] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-blt
[94.398874] Subtest fbc-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[94.485998] [021/133] (923s left) gem_lmem_swapping (massive)
[94.533533] Subtest massive: SKIP (0.000s)
[94.580695] [022/133] (923s left) gem_mmap_gtt (basic-small-copy)
[94.642055] Subtest basic-small-copy: SKIP (0.000s)
[94.695522] [023/133] (923s left) kms_ccs (bad-rotation-90-4-tiled-mtl-rc-ccs-cc)
[94.792215] Starting subtest: bad-rotation-90-4-tiled-mtl-rc-ccs-cc
[94.794487] Starting dynamic subtest: pipe-A-eDP-1
[94.945916] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.148s)
[94.955496] Starting dynamic subtest: pipe-B-eDP-1
[94.971158] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.012s)
[94.979591] Starting dynamic subtest: pipe-C-eDP-1
[94.996546] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.013s)
[95.005634] Starting dynamic subtest: pipe-D-eDP-1
[95.021610] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.011s)
[95.023347] Subtest bad-rotation-90-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.188s)
[97.218119] [024/133] (920s left) sysfs_timeslice_duration (invalid)
[97.279043] Starting subtest: invalid
[97.281483] Starting dynamic subtest: vecs0
[97.283679] Dynamic subtest vecs0: SUCCESS (0.000s)
[97.292349] Starting dynamic subtest: ccs0
[97.294461] Dynamic subtest ccs0: SUCCESS (0.000s)
[97.304547] Starting dynamic subtest: vcs0
[97.306741] Dynamic subtest vcs0: SUCCESS (0.000s)
[97.314108] Starting dynamic subtest: bcs0
[97.316277] Dynamic subtest bcs0: SUCCESS (0.000s)
[97.323700] Starting dynamic subtest: rcs0
[97.325888] Dynamic subtest rcs0: SUCCESS (0.001s)
[97.332902] Starting dynamic subtest: vcs1
[97.334749] Dynamic subtest vcs1: SUCCESS (0.001s)
[97.337070] Subtest invalid: SUCCESS (0.007s)
[97.398250] [025/133] (920s left) kms_big_fb (y-tiled-8bpp-rotate-90)
[97.507422] Starting subtest: y-tiled-8bpp-rotate-90
[97.512048] Subtest y-tiled-8bpp-rotate-90: SKIP (0.000s)
[97.606263] [026/133] (920s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-pwrite)
[97.699784] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-pwrite
[97.703983] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[97.789647] [027/133] (920s left) perf_pmu (busy-hang)
[97.834353] Starting subtest: busy-hang
[97.836596] Starting dynamic subtest: rcs0
[99.042763] Dynamic subtest rcs0: SUCCESS (1.207s)
[99.052398] Starting dynamic subtest: bcs0
[100.222102] Dynamic subtest bcs0: SUCCESS (1.179s)
[100.231739] Starting dynamic subtest: vcs0
[102.426075] Dynamic subtest vcs0: SUCCESS (2.205s)
[102.437660] Starting dynamic subtest: vcs1
[104.402876] Dynamic subtest vcs1: SUCCESS (1.976s)
[104.411701] Starting dynamic subtest: vecs0
[106.390139] Dynamic subtest vecs0: SUCCESS (1.986s)
[106.400713] Starting dynamic subtest: ccs0
[107.564661] Dynamic subtest ccs0: SUCCESS (1.173s)
[107.567289] Subtest busy-hang: SUCCESS (9.732s)
[107.678373] [028/133] (910s left) kms_rotation_crc (primary-4-tiled-reflect-x-180)
[107.783878] Starting subtest: primary-4-tiled-reflect-x-180
[114.907603] Subtest primary-4-tiled-reflect-x-180: SUCCESS (7.125s)
[116.078393] [029/133] (902s left) gem_exec_alignment (pi-isolated)
[116.156352] Starting subtest: pi-isolated
[167.698276] Subtest pi-isolated: SUCCESS (51.537s)
[168.663510] [030/133] (849s left) gem_mmap_offset (blt-coherency)
[168.760314] Starting subtest: blt-coherency
[168.773415] Subtest blt-coherency: SUCCESS (0.014s)
[168.913936] [031/133] (849s left) gem_spin_batch (resubmit)
[168.975073] Starting subtest: resubmit
[168.977478] Starting dynamic subtest: rcs0
[168.987451] Dynamic subtest rcs0: SUCCESS (0.012s)
[169.002875] Starting dynamic subtest: bcs0
[169.005877] Dynamic subtest bcs0: SUCCESS (0.005s)
[169.018740] Starting dynamic subtest: vcs0
[169.021192] Dynamic subtest vcs0: SUCCESS (0.005s)
[169.029802] Starting dynamic subtest: vcs1
[169.032075] Dynamic subtest vcs1: SUCCESS (0.006s)
[169.040663] Starting dynamic subtest: vecs0
[169.042837] Dynamic subtest vecs0: SUCCESS (0.004s)
[169.051273] Starting dynamic subtest: ccs0
[169.053467] Dynamic subtest ccs0: SUCCESS (0.004s)
[169.055629] Subtest resubmit: SUCCESS (0.039s)
[169.259233] [032/133] (849s left) syncobj_timeline (wait-all-complex)
[169.333651] Starting subtest: wait-all-complex
[169.548887] Subtest wait-all-complex: SUCCESS (0.213s)
[169.623305] [033/133] (848s left) kms_prop_blob (invalid-get-prop-any)
[169.682910] Starting subtest: invalid-get-prop-any
[169.685186] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[169.779949] [034/133] (848s left) gem_ctx_persistence (engines-hang)
[169.838607] Starting subtest: engines-hang
[169.841231] Starting dynamic subtest: rcs0
[169.983988] Dynamic subtest rcs0: SUCCESS (0.145s)
[169.997606] Starting dynamic subtest: bcs0
[170.090866] Dynamic subtest bcs0: SUCCESS (0.106s)
[170.102894] Starting dynamic subtest: vcs0
[170.196402] Dynamic subtest vcs0: SUCCESS (0.104s)
[170.207061] Starting dynamic subtest: vcs1
[170.290573] Dynamic subtest vcs1: SUCCESS (0.094s)
[170.301572] Starting dynamic subtest: vecs0
[170.390050] Dynamic subtest vecs0: SUCCESS (0.098s)
[170.401490] Starting dynamic subtest: ccs0
[170.471596] Dynamic subtest ccs0: SUCCESS (0.081s)
[170.474060] Subtest engines-hang: SUCCESS (0.634s)
[170.601242] [035/133] (847s left) kms_chamelium_color (ctm-negative)
[170.700688] Subtest ctm-negative: SKIP (0.000s)
[170.754194] [036/133] (847s left) gem_tiled_partial_pwrite_pread (reads)
[170.817071] Subtest reads: SKIP (0.000s)
[170.895780] [037/133] (847s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[171.003389] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[171.006852] Subtest y-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SKIP (0.000s)
[171.100377] [038/133] (847s left) kms_big_fb (yf-tiled-32bpp-rotate-270)
[171.199130] Starting subtest: yf-tiled-32bpp-rotate-270
[171.203604] Subtest yf-tiled-32bpp-rotate-270: SKIP (0.000s)
[171.311271] [039/133] (847s left) gem_bad_reloc (negative-reloc-lut)
[171.371300] Subtest negative-reloc-lut: SKIP (0.000s)
[171.451657] [040/133] (847s left) gem_ctx_param (get-priority-new-ctx)
[171.500279] Starting subtest: get-priority-new-ctx
[171.502173] Subtest get-priority-new-ctx: SUCCESS (0.001s)
[171.611602] [041/133] (847s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-upscaling)
[171.699242] Starting subtest: flip-32bpp-linear-to-64bpp-linear-upscaling
[171.701064] Starting dynamic subtest: pipe-A-default-mode
[174.429739] Dynamic subtest pipe-A-default-mode: SUCCESS (2.729s)
[174.432355] Subtest flip-32bpp-linear-to-64bpp-linear-upscaling: SUCCESS (2.730s)
[175.280671] [042/133] (843s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-msflip-blt)
[175.371559] Starting subtest: psr-1p-primscrn-indfb-msflip-blt
[182.754840] Subtest psr-1p-primscrn-indfb-msflip-blt: SUCCESS (7.386s)
[182.867084] [043/133] (835s left) gem_eio (in-flight-external)
[183.106149] Starting subtest: in-flight-external
[184.992017] Subtest in-flight-external: SUCCESS (1.885s)
[185.151549] [044/133] (833s left) kms_ccs (crc-primary-rotation-180-y-tiled-ccs)
[185.240458] Starting subtest: crc-primary-rotation-180-y-tiled-ccs
[185.242757] Starting dynamic subtest: pipe-A-eDP-1
[185.265178] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[185.275623] Starting dynamic subtest: pipe-B-eDP-1
[185.296263] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[185.304383] Starting dynamic subtest: pipe-C-eDP-1
[185.325152] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[185.333624] Starting dynamic subtest: pipe-D-eDP-1
[185.355844] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[185.360185] Subtest crc-primary-rotation-180-y-tiled-ccs: SKIP (0.005s)
[187.502649] [045/133] (831s left) kms_rotation_crc (sprite-rotation-270)
[187.595486] Starting subtest: sprite-rotation-270
[187.600032] Subtest sprite-rotation-270: SKIP (0.001s)
[187.680730] [046/133] (831s left) kms_frontbuffer_tracking (fbc-tiling-4)
[187.781319] Starting subtest: fbc-tiling-4
[192.032950] Subtest fbc-tiling-4: SUCCESS (4.254s)
[193.233577] [047/133] (825s left) kms_big_fb (linear-64bpp-rotate-90)
[193.329802] Starting subtest: linear-64bpp-rotate-90
[193.349958] Subtest linear-64bpp-rotate-90: SKIP (0.019s)
[193.457464] [048/133] (825s left) kms_plane (pixel-format-4-tiled-mtl-mc-ccs-modifier)
[193.550243] Starting subtest: pixel-format-4-tiled-mtl-mc-ccs-modifier
[193.576392] Starting dynamic subtest: pipe-A-plane-0
[193.998855] Dynamic subtest pipe-A-plane-0: SUCCESS (0.423s)
[194.008873] Starting dynamic subtest: pipe-A-plane-3
[194.264517] Dynamic subtest pipe-A-plane-3: SUCCESS (0.266s)
[194.272915] Starting dynamic subtest: pipe-A-plane-5
[194.276312] Dynamic subtest pipe-A-plane-5: SKIP (0.000s)
[195.394187] Starting dynamic subtest: pipe-B-plane-0
[195.841687] Dynamic subtest pipe-B-plane-0: SUCCESS (0.455s)
[195.850759] Starting dynamic subtest: pipe-B-plane-3
[196.097521] Dynamic subtest pipe-B-plane-3: SUCCESS (0.255s)
[196.106798] Starting dynamic subtest: pipe-B-plane-5
[196.111150] Dynamic subtest pipe-B-plane-5: SKIP (0.000s)
[196.407905] Subtest pixel-format-4-tiled-mtl-mc-ccs-modifier: SUCCESS (2.858s)
[197.260206] [049/133] (821s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0-async-flip)
[197.359048] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-0-async-flip
[198.049785] Subtest 4-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SUCCESS (0.692s)
[198.211083] [050/133] (820s left) gem_exec_params (invalid-bsd2-flag-on-render)
[198.266522] Starting subtest: invalid-bsd2-flag-on-render
[198.268909] Subtest invalid-bsd2-flag-on-render: SUCCESS (0.000s)
[198.369045] [051/133] (820s left) gem_exec_balancer (parallel-ordering)
[198.626294] Starting subtest: parallel-ordering
[199.597445] Subtest parallel-ordering: SUCCESS (0.971s)
[199.703930] [052/133] (819s left) i915_drm_fdinfo (busy-idle-check-all)
[199.753105] Starting subtest: busy-idle-check-all
[199.755534] Starting dynamic subtest: rcs0
[199.760168] Dynamic subtest rcs0: SKIP (0.004s)
[199.770999] Starting dynamic subtest: bcs0
[199.775533] Dynamic subtest bcs0: SKIP (0.000s)
[199.784089] Starting dynamic subtest: vcs0
[199.788055] Dynamic subtest vcs0: SKIP (0.000s)
[199.796305] Starting dynamic subtest: vcs1
[199.800246] Dynamic subtest vcs1: SKIP (0.000s)
[199.808524] Starting dynamic subtest: vecs0
[199.812647] Dynamic subtest vecs0: SKIP (0.000s)
[199.820842] Starting dynamic subtest: ccs0
[199.824706] Dynamic subtest ccs0: SKIP (0.000s)
[199.828571] Subtest busy-idle-check-all: SKIP (0.008s)
[199.865958] [053/133] (819s left) gem_exec_reloc (basic-write-read)
[199.959053] Subtest basic-write-read: SKIP (0.000s)
[200.047114] [054/133] (819s left) dmabuf_sync_file (import-multiple-read-write)
[200.169279] Starting subtest: import-multiple-read-write
[200.171679] Subtest import-multiple-read-write: SUCCESS (0.003s)
[200.232396] [FACT dmabuf_sync_file (import-multiple-read-write)] new: kernel.kmod_is_loaded.vgem: true
[200.235643] [055/133] (819s left) gem_mmap_gtt (hang)
[200.292559] Subtest hang: SKIP (0.000s)
[200.349897] [056/133] (818s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-mmap-wc)
[200.453628] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-mmap-wc
[200.458224] Subtest fbc-2p-scndscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[200.556344] [057/133] (818s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[200.661275] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[200.664733] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[200.749332] [058/133] (818s left) vgem_slow (nohang)
[200.795074] Starting subtest: nohang
[211.060580] Subtest nohang: SUCCESS (10.267s)
[211.133142] [059/133] (808s left) kms_psr2_sf (pr-cursor-plane-move-continuous-exceed-fully-sf)
[211.232648] Starting subtest: pr-cursor-plane-move-continuous-exceed-fully-sf
[211.236768] Subtest pr-cursor-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[211.300109] [060/133] (808s left) kms_cursor_crc (cursor-random-64x64)
[211.473983] Starting subtest: cursor-random-64x64
[211.476359] Starting dynamic subtest: pipe-A-eDP-1
[214.843118] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.367s)
[214.892582] Starting dynamic subtest: pipe-D-eDP-1
[218.846153] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.960s)
[218.848315] Subtest cursor-random-64x64: SUCCESS (7.372s)
[219.715065] [061/133] (799s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-render)
[219.811170] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-render
[219.815304] Subtest fbc-2p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[219.901059] [062/133] (799s left) kms_psr (pr-suspend)
[219.994161] Starting subtest: pr-suspend
[219.998574] Subtest pr-suspend: SKIP (0.000s)
[220.071479] [063/133] (799s left) kms_color (ctm-blue-to-red)
[220.166737] Starting subtest: ctm-blue-to-red
[220.168672] Starting dynamic subtest: pipe-A-eDP-1
[220.869261] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.704s)
[220.878707] Starting dynamic subtest: pipe-B-eDP-1
[222.331361] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.460s)
[222.341425] Starting dynamic subtest: pipe-C-eDP-1
[223.705790] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.375s)
[223.715647] Starting dynamic subtest: pipe-D-eDP-1
[225.034555] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.328s)
[225.036509] Subtest ctm-blue-to-red: SUCCESS (4.869s)
[225.842316] [064/133] (793s left) gem_mmap_gtt (close-race)
[225.908347] Subtest close-race: SKIP (0.000s)
[225.977528] [065/133] (793s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-blt)
[226.081405] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-blt
[232.984503] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-blt: SUCCESS (6.905s)
[233.145605] [066/133] (786s left) kms_dp_linktrain_fallback (dp-fallback)
[233.245232] Starting subtest: dp-fallback
[233.251315] Subtest dp-fallback: SKIP (0.000s)
[233.320370] [067/133] (786s left) kms_addfb_basic (tile-pitch-mismatch)
[233.389190] Starting subtest: tile-pitch-mismatch
[233.393400] Subtest tile-pitch-mismatch: SKIP (0.000s)
[233.530061] [068/133] (786s left) kms_psr (fbc-pr-cursor-blt)
[233.635094] Starting subtest: fbc-pr-cursor-blt
[233.639088] Subtest fbc-pr-cursor-blt: SKIP (0.000s)
[233.707959] [069/133] (786s left) gem_exec_balancer (full)
[233.775827] Starting subtest: full
[238.779078] Subtest full: SUCCESS (5.003s)
[239.090056] [070/133] (780s left) syncobj_timeline (invalid-multi-wait-unsubmitted-signaled)
[239.136632] Starting subtest: invalid-multi-wait-unsubmitted-signaled
[239.138938] Subtest invalid-multi-wait-unsubmitted-signaled: SUCCESS (0.000s)
[239.237541] [071/133] (780s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-pixel-formats)
[239.329179] Starting subtest: plane-scaler-with-clipping-clamping-pixel-formats
[239.331387] Starting dynamic subtest: pipe-A
[239.451793] Dynamic subtest pipe-A: SKIP (0.119s)
[239.463960] Starting dynamic subtest: pipe-B
[240.655681] Dynamic subtest pipe-B: SKIP (1.202s)
[240.670464] Starting dynamic subtest: pipe-C
[241.883953] Dynamic subtest pipe-C: SUCCESS (1.234s)
[241.893339] Starting dynamic subtest: pipe-D
[243.029948] Dynamic subtest pipe-D: SUCCESS (1.145s)
[243.032386] Subtest plane-scaler-with-clipping-clamping-pixel-formats: SUCCESS (3.703s)
[244.212417] [072/133] (775s left) syncobj_timeline (etime-multi-wait-submitted)
[244.269297] Starting subtest: etime-multi-wait-submitted
[244.369742] Subtest etime-multi-wait-submitted: SUCCESS (0.101s)
[244.459065] [073/133] (775s left) kms_getfb (getfb2-handle-protection)
[244.553111] Starting subtest: getfb2-handle-protection
[244.581279] Subtest getfb2-handle-protection: SUCCESS (0.029s)
[244.704083] [074/133] (775s left) i915_hangman (engine-error-state-capture)
[244.768987] Starting subtest: engine-error-state-capture
[244.771412] Starting dynamic subtest: rcs0
[247.860799] Dynamic subtest rcs0: SUCCESS (3.092s)
[247.871733] Starting dynamic subtest: bcs0
[253.878878] Dynamic subtest bcs0: SUCCESS (6.015s)
[253.892654] Starting dynamic subtest: vcs0
[259.829052] Dynamic subtest vcs0: SUCCESS (5.947s)
[259.841063] Starting dynamic subtest: vcs1
[264.821048] Dynamic subtest vcs1: SUCCESS (4.989s)
[264.833207] Starting dynamic subtest: vecs0
[269.813070] Dynamic subtest vecs0: SUCCESS (4.989s)
[269.825809] Starting dynamic subtest: ccs0
[274.869251] Dynamic subtest ccs0: SUCCESS (5.053s)
[274.871999] Subtest engine-error-state-capture: SUCCESS (30.102s)
[274.991591] [075/133] (745s left) kms_async_flips (async-flip-with-page-flip-events-tiled-atomic)
[275.090770] Starting subtest: async-flip-with-page-flip-events-tiled-atomic
[275.463990] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs
[278.279199] Dynamic subtest pipe-A-eDP-1-4-rc-ccs: SUCCESS (2.814s)
[278.294914] Starting dynamic subtest: pipe-A-eDP-1-4
[280.454354] Dynamic subtest pipe-A-eDP-1-4: SUCCESS (2.174s)
[280.465361] Starting dynamic subtest: pipe-A-eDP-1-4
[282.465318] Dynamic subtest pipe-A-eDP-1-4: SUCCESS (2.009s)
[282.477473] Starting dynamic subtest: pipe-A-eDP-1-x
[284.643649] Dynamic subtest pipe-A-eDP-1-x: SUCCESS (2.177s)
[285.021854] Starting dynamic subtest: pipe-B-eDP-1-4-rc-ccs
[287.813532] Dynamic subtest pipe-B-eDP-1-4-rc-ccs: SUCCESS (2.798s)
[287.826166] Starting dynamic subtest: pipe-B-eDP-1-4
[290.001253] Dynamic subtest pipe-B-eDP-1-4: SUCCESS (2.186s)
[290.013358] Starting dynamic subtest: pipe-B-eDP-1-4
[292.010190] Dynamic subtest pipe-B-eDP-1-4: SUCCESS (2.008s)
[292.022293] Starting dynamic subtest: pipe-B-eDP-1-x
[294.200160] Dynamic subtest pipe-B-eDP-1-x: SUCCESS (2.188s)
[294.563232] Starting dynamic subtest: pipe-C-eDP-1-4-rc-ccs
[297.337201] Dynamic subtest pipe-C-eDP-1-4-rc-ccs: SUCCESS (2.780s)
[297.349815] Starting dynamic subtest: pipe-C-eDP-1-4
[299.501372] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.162s)
[299.512456] Starting dynamic subtest: pipe-C-eDP-1-4
[301.511602] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.008s)
[301.525173] Starting dynamic subtest: pipe-C-eDP-1-x
[303.690927] Dynamic subtest pipe-C-eDP-1-x: SUCCESS (2.177s)
[304.088575] Starting dynamic subtest: pipe-D-eDP-1-4-rc-ccs
[306.792682] Dynamic subtest pipe-D-eDP-1-4-rc-ccs: SUCCESS (2.729s)
[306.805117] Starting dynamic subtest: pipe-D-eDP-1-4
[308.967068] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.174s)
[308.978708] Starting dynamic subtest: pipe-D-eDP-1-4
[310.978104] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.010s)
[310.990377] Starting dynamic subtest: pipe-D-eDP-1-x
[313.156464] Dynamic subtest pipe-D-eDP-1-x: SUCCESS (2.176s)
[313.159299] Subtest async-flip-with-page-flip-events-tiled-atomic: SUCCESS (38.064s)
[314.383845] [076/133] (705s left) kms_force_connector_basic (force-connector-state)
[314.470901] Starting subtest: force-connector-state
[315.081776] Subtest force-connector-state: SUCCESS (0.613s)
[316.032700] [077/133] (704s left) gem_exec_fence (syncobj-timeline-invalid-flags)
[316.599062] Starting subtest: syncobj-timeline-invalid-flags
[316.601376] Subtest syncobj-timeline-invalid-flags: SUCCESS (0.000s)
[317.930313] [078/133] (702s left) gem_exec_schedule (submit-early-slice)
[317.999408] Starting subtest: submit-early-slice
[318.001644] Starting dynamic subtest: rcs0
[318.040124] Dynamic subtest rcs0: SUCCESS (0.048s)
[318.052182] Starting dynamic subtest: bcs0
[318.086553] Dynamic subtest bcs0: SUCCESS (0.046s)
[318.097800] Starting dynamic subtest: vcs0
[318.129442] Dynamic subtest vcs0: SUCCESS (0.042s)
[318.140115] Starting dynamic subtest: vcs1
[318.177746] Dynamic subtest vcs1: SUCCESS (0.047s)
[318.190346] Starting dynamic subtest: vecs0
[318.216761] Dynamic subtest vecs0: SUCCESS (0.039s)
[318.227754] Starting dynamic subtest: ccs0
[318.265667] Dynamic subtest ccs0: SUCCESS (0.047s)
[318.268132] Subtest submit-early-slice: SUCCESS (0.274s)
[319.987423] [079/133] (700s left) kms_plane_scaling (planes-downscale-factor-0-75)
[320.085945] Starting subtest: planes-downscale-factor-0-75
[320.088230] Starting dynamic subtest: pipe-A
[320.109156] Dynamic subtest pipe-A: SKIP (0.020s)
[320.118734] Starting dynamic subtest: pipe-B
[320.144996] Dynamic subtest pipe-B: SKIP (0.033s)
[320.154629] Starting dynamic subtest: pipe-C
[320.176801] Dynamic subtest pipe-C: SKIP (0.033s)
[320.185972] Starting dynamic subtest: pipe-D
[320.211468] Dynamic subtest pipe-D: SKIP (0.033s)
[320.215457] Subtest planes-downscale-factor-0-75: SKIP (0.123s)
[320.331632] [080/133] (700s left) api_intel_allocator (standalone)
[320.388405] Starting subtest: standalone
[320.401537] Subtest standalone: SUCCESS (0.016s)
[320.513404] [081/133] (699s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-blt)
[320.612873] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-blt
[320.617169] Subtest psr-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s)
[320.734596] [082/133] (699s left) gem_ctx_persistence (userptr)
[320.790656] Starting subtest: userptr
[320.972731] Subtest userptr: SUCCESS (0.182s)
[321.052070] [083/133] (699s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[321.133508] Starting subtest: compare-crc-sanitycheck-xr24
[321.135145] Starting dynamic subtest: pipe-A-eDP-1
[321.778279] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.645s)
[321.789192] Starting dynamic subtest: pipe-B-eDP-1
[323.069565] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.287s)
[323.079889] Starting dynamic subtest: pipe-C-eDP-1
[324.320424] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.252s)
[324.330713] Starting dynamic subtest: pipe-D-eDP-1
[325.508260] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.187s)
[325.510574] Subtest compare-crc-sanitycheck-xr24: SUCCESS (4.376s)
[326.311901] [084/133] (694s left) kms_cursor_legacy (short-flip-after-cursor-atomic-transitions-varying-size)
[326.426389] Starting subtest: short-flip-after-cursor-atomic-transitions-varying-size
[327.395078] Subtest short-flip-after-cursor-atomic-transitions-varying-size: SUCCESS (0.970s)
[328.212902] [085/133] (692s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-render)
[328.305782] Starting subtest: fbcpsr-rgb101010-draw-render
[334.946676] Subtest fbcpsr-rgb101010-draw-render: SUCCESS (6.644s)
[335.060487] [086/133] (685s left) kms_flip (2x-absolute-wf_vblank)
[335.150910] Starting subtest: 2x-absolute-wf_vblank
[335.161573] Subtest 2x-absolute-wf_vblank: SKIP (0.011s)
[335.244255] [087/133] (685s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-pwrite)
[335.345066] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-pwrite
[335.349149] Subtest psr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[335.445527] [088/133] (685s left) gem_basic (create-fd-close)
[335.501818] Starting subtest: create-fd-close
[335.506094] Subtest create-fd-close: SUCCESS (0.000s)
[335.611014] [089/133] (685s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-move)
[335.710012] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-move
[343.003313] Subtest fbcpsr-1p-primscrn-cur-indfb-move: SUCCESS (7.294s)
[343.140921] [090/133] (677s left) kms_cursor_crc (cursor-offscreen-128x42)
[343.379904] Starting subtest: cursor-offscreen-128x42
[343.429217] Subtest cursor-offscreen-128x42: SKIP (0.043s)
[343.631034] [091/133] (677s left) gem_render_copy (yf-tiled-to-vebox-yf-tiled)
[343.693677] Starting subtest: yf-tiled-to-vebox-yf-tiled
[343.697622] Subtest yf-tiled-to-vebox-yf-tiled: SKIP (0.000s)
[343.892672] [092/133] (676s left) kms_plane_multiple (2x-tiling-x)
[343.993190] Starting subtest: 2x-tiling-x
[343.997226] Subtest 2x-tiling-x: SKIP (0.000s)
[344.064953] [093/133] (676s left) perf_pmu (busy-idle-no-semaphores)
[344.107895] Starting subtest: busy-idle-no-semaphores
[344.110237] Starting dynamic subtest: rcs0
[344.774339] Dynamic subtest rcs0: SUCCESS (0.665s)
[344.786973] Starting dynamic subtest: bcs0
[345.426958] Dynamic subtest bcs0: SUCCESS (0.650s)
[345.439187] Starting dynamic subtest: vcs0
[346.078291] Dynamic subtest vcs0: SUCCESS (0.648s)
[346.088494] Starting dynamic subtest: vcs1
[347.519460] Dynamic subtest vcs1: SUCCESS (1.439s)
[347.531623] Starting dynamic subtest: vecs0
[349.496692] Dynamic subtest vecs0: SUCCESS (1.975s)
[349.508916] Starting dynamic subtest: ccs0
[351.550976] Dynamic subtest ccs0: SUCCESS (2.051s)
[351.553934] Subtest busy-idle-no-semaphores: SUCCESS (7.443s)
[351.680415] [094/133] (669s left) kms_lease (atomic-implicit-crtc)
[351.781035] Starting subtest: atomic-implicit-crtc
[351.783306] Starting dynamic subtest: pipe-A-eDP-1
[351.795085] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[351.805050] Starting dynamic subtest: pipe-B-eDP-1
[351.816485] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.010s)
[351.826205] Starting dynamic subtest: pipe-C-eDP-1
[351.837180] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.010s)
[351.846273] Starting dynamic subtest: pipe-D-eDP-1
[351.857069] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.010s)
[351.858912] Subtest atomic-implicit-crtc: SUCCESS (0.037s)
[351.935897] [095/133] (669s left) kms_cursor_edge_walk (256x256-top-bottom)
[352.024100] Starting subtest: 256x256-top-bottom
[352.026396] Starting dynamic subtest: pipe-A-eDP-1
[355.432015] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.406s)
[355.442858] Starting dynamic subtest: pipe-D-eDP-1
[358.634865] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.202s)
[358.636745] Subtest 256x256-top-bottom: SUCCESS (6.611s)
[359.511509] [096/133] (661s left) syncobj_timeline (multi-wait-for-submit-available-signaled)
[359.568113] Starting subtest: multi-wait-for-submit-available-signaled
[359.570389] Subtest multi-wait-for-submit-available-signaled: SUCCESS (0.000s)
[359.665661] [097/133] (661s left) kms_content_protection (lic-type-0-hdcp14)
[359.819482] Starting subtest: lic-type-0-hdcp14
[359.824083] Subtest lic-type-0-hdcp14: SKIP (0.000s)
[359.953781] [098/133] (661s left) kms_big_fb (yf-tiled-addfb)
[360.053029] Starting subtest: yf-tiled-addfb
[360.057396] Subtest yf-tiled-addfb: SKIP (0.000s)
[360.170468] [099/133] (660s left) kms_pm_rpm (dpms-mode-unset-lpsp)
[362.101558] Starting subtest: dpms-mode-unset-lpsp
[366.136953] Subtest dpms-mode-unset-lpsp: SUCCESS (4.036s)
[366.535963] [100/133] (654s left) kms_cursor_legacy (cursora-vs-flipb-atomic-transitions)
[366.648694] Starting subtest: cursorA-vs-flipB-atomic-transitions
[366.653205] Subtest cursorA-vs-flipB-atomic-transitions: SKIP (0.001s)
[366.797139] [101/133] (654s left) kms_cursor_legacy (2x-long-flip-vs-cursor-atomic)
[366.919435] Starting subtest: 2x-long-flip-vs-cursor-atomic
[366.923882] Subtest 2x-long-flip-vs-cursor-atomic: SKIP (0.001s)
[367.093291] [102/133] (654s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-mmap-wc)
[367.207479] Starting subtest: psr-1p-primscrn-spr-indfb-draw-mmap-wc
[373.720406] Subtest psr-1p-primscrn-spr-indfb-draw-mmap-wc: SUCCESS (6.517s)
[373.846014] [103/133] (647s left) i915_pm_rpm (gem-idle)
[376.075816] Starting subtest: gem-idle
[382.273215] Subtest gem-idle: SUCCESS (6.200s)
[382.608269] [104/133] (638s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-64bpp-ytile-downscaling)
[383.903335] Starting subtest: flip-32bpp-ytile-to-64bpp-ytile-downscaling
[383.907802] Subtest flip-32bpp-ytile-to-64bpp-ytile-downscaling: SKIP (0.000s)
[384.031598] [105/133] (637s left) gem_exec_fence (basic-busy-all)
[384.099303] Starting subtest: basic-busy-all
[384.196835] Subtest basic-busy-all: SUCCESS (0.099s)
[386.019243] [106/133] (635s left) kms_flip (flip-vs-rmfb-interruptible)
[386.121345] Starting subtest: flip-vs-rmfb-interruptible
[386.123667] Starting dynamic subtest: A-eDP1
[394.761967] Dynamic subtest A-eDP1: SUCCESS (8.639s)
[394.773997] Starting dynamic subtest: B-eDP1
[403.364317] Dynamic subtest B-eDP1: SUCCESS (8.599s)
[403.374497] Starting dynamic subtest: C-eDP1
[411.953144] Dynamic subtest C-eDP1: SUCCESS (8.587s)
[411.967883] Starting dynamic subtest: D-eDP1
[420.552575] Dynamic subtest D-eDP1: SUCCESS (8.596s)
[420.556721] Subtest flip-vs-rmfb-interruptible: SUCCESS (34.436s)
[421.803273] [107/133] (599s left) kms_prime (basic-crc-vgem)
[421.903121] Starting subtest: basic-crc-vgem
[421.905382] Starting dynamic subtest: second-to-first
[422.859136] Dynamic subtest second-to-first: SUCCESS (0.960s)
[422.861198] Subtest basic-crc-vgem: SUCCESS (0.961s)
[422.952511] [108/133] (598s left) kms_psr (fbc-psr2-cursor-blt)
[423.061079] Starting subtest: fbc-psr2-cursor-blt
[423.062992] Starting dynamic subtest: eDP-1
[423.066490] Dynamic subtest eDP-1: SKIP (0.000s)
[423.069896] Subtest fbc-psr2-cursor-blt: SKIP (0.001s)
[423.137442] [109/133] (598s left) kms_big_fb (x-tiled-32bpp-rotate-0)
[423.231906] Starting subtest: x-tiled-32bpp-rotate-0
[424.656282] Subtest x-tiled-32bpp-rotate-0: SUCCESS (1.423s)
[424.778665] [110/133] (596s left) kms_psr (psr2-primary-mmap-gtt)
[424.887540] Starting subtest: psr2-primary-mmap-gtt
[424.889383] Starting dynamic subtest: eDP-1
[426.356113] Dynamic subtest eDP-1: SKIP (1.465s)
[426.361172] Subtest psr2-primary-mmap-gtt: SKIP (1.467s)
[426.473740] [111/133] (595s left) kms_vblank (wait-forked-hang)
[426.572065] Starting subtest: wait-forked-hang
[426.574279] Starting dynamic subtest: pipe-A-eDP-1
[452.711656] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.138s)
[452.726365] Starting dynamic subtest: pipe-D-eDP-1
[477.669820] Dynamic subtest pipe-D-eDP-1: SUCCESS (24.955s)
[477.672048] Subtest wait-forked-hang: SUCCESS (51.098s)
[478.494201] [112/133] (543s left) gem_ctx_bad_destroy (invalid-ctx)
[478.547835] Starting subtest: invalid-ctx
[478.550259] Subtest invalid-ctx: SUCCESS (0.000s)
[478.628225] [113/133] (543s left) syncobj_wait (invalid-reset-bad-pad)
[478.680247] Starting subtest: invalid-reset-bad-pad
[478.682573] Subtest invalid-reset-bad-pad: SUCCESS (0.000s)
[478.773597] [114/133] (543s left) kms_setmode (invalid-clone-single-crtc)
[478.823951] Starting subtest: invalid-clone-single-crtc
[478.830394] Subtest invalid-clone-single-crtc: SKIP (0.000s)
[478.920597] [115/133] (542s left) syncobj_wait (invalid-multi-wait-unsubmitted-submitted)
[478.969947] Starting subtest: invalid-multi-wait-unsubmitted-submitted
[478.972269] Subtest invalid-multi-wait-unsubmitted-submitted: SUCCESS (0.000s)
[479.059738] [116/133] (542s left) gem_mmap_wc (write-wc-read-gtt)
[479.109596] Subtest write-wc-read-gtt: SKIP (0.000s)
[479.176640] [117/133] (542s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-mc-ccs)
[479.260238] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-mc-ccs
[479.262574] Starting dynamic subtest: pipe-A-eDP-1
[479.525973] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.242s)
[479.536984] Starting dynamic subtest: pipe-B-eDP-1
[480.879958] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.345s)
[480.891532] Starting dynamic subtest: pipe-C-eDP-1
[482.114851] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.232s)
[482.125225] Starting dynamic subtest: pipe-D-eDP-1
[483.316549] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.203s)
[483.318768] Subtest crc-sprite-planes-basic-4-tiled-mtl-mc-ccs: SUCCESS (4.027s)
[484.497694] [118/133] (537s left) kms_hdr (static-toggle)
[484.586274] Starting subtest: static-toggle
[484.592413] Subtest static-toggle: SKIP (0.000s)
[484.675739] [119/133] (537s left) syncobj_wait (single-wait-all-for-submit-submitted)
[484.724066] Starting subtest: single-wait-all-for-submit-submitted
[484.825229] Subtest single-wait-all-for-submit-submitted: SUCCESS (0.102s)
[484.904145] [120/133] (537s left) kms_async_flips (test-time-stamp)
[485.005640] Starting subtest: test-time-stamp
[485.380849] Starting dynamic subtest: pipe-A-eDP-1
[486.173525] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.790s)
[486.553202] Starting dynamic subtest: pipe-B-eDP-1
[487.365654] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.819s)
[487.745041] Starting dynamic subtest: pipe-C-eDP-1
[488.567553] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.827s)
[488.959761] Starting dynamic subtest: pipe-D-eDP-1
[489.766784] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.828s)
[489.769278] Subtest test-time-stamp: SUCCESS (4.761s)
[491.008340] [121/133] (531s left) gem_exec_schedule (preempt-queue-chain)
[491.090591] Subtest preempt-queue-chain: SKIP (0.000s)
[491.372148] [122/133] (530s left) gem_unfence_active_buffers
[491.518520] [123/133] (530s left) kms_rotation_crc (primary-yf-tiled-reflect-x-90)
[491.614273] Starting subtest: primary-yf-tiled-reflect-x-90
[491.618151] Subtest primary-yf-tiled-reflect-x-90: SKIP (0.001s)
[491.689481] [124/133] (530s left) kms_dsc (dsc-with-formats)
[491.793506] Starting subtest: dsc-with-formats
[491.832968] Subtest dsc-with-formats: SKIP (0.001s)
[491.873061] [125/133] (530s left) gem_render_copy (y-tiled-ccs-to-y-tiled)
[491.934760] Starting subtest: y-tiled-ccs-to-y-tiled
[491.939638] Subtest y-tiled-ccs-to-y-tiled: SKIP (0.000s)
[492.116490] [126/133] (530s left) gem_eio (wait-wedge-immediate)
[492.303620] Starting subtest: wait-wedge-immediate
[493.972260] Subtest wait-wedge-immediate: SUCCESS (1.667s)
[494.115944] [127/133] (528s left) kms_flip (flip-vs-panning-vs-hang)
[494.205618] Starting subtest: flip-vs-panning-vs-hang
[494.207611] Starting dynamic subtest: A-eDP1
[498.249078] Dynamic subtest A-eDP1: SUCCESS (4.046s)
[498.258818] Starting dynamic subtest: D-eDP1
[502.229870] Dynamic subtest D-eDP1: SUCCESS (3.979s)
[502.232281] Subtest flip-vs-panning-vs-hang: SUCCESS (8.030s)
[503.393948] [128/133] (518s left) gem_userptr_blits (sd-probe)
[503.458148] Starting subtest: sd-probe
[503.460123] Subtest sd-probe: SUCCESS (0.000s)
[503.584097] [129/133] (518s left) kms_ccs (ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc)
[503.677101] Starting subtest: ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc
[503.679428] Starting dynamic subtest: pipe-A-eDP-1
[503.699408] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[503.712168] Starting dynamic subtest: pipe-B-eDP-1
[503.731351] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[503.740761] Starting dynamic subtest: pipe-C-eDP-1
[503.760357] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[503.769196] Starting dynamic subtest: pipe-D-eDP-1
[503.787647] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[503.789471] Subtest ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.008s)
[505.886360] [130/133] (516s left) prime_busy (hang)
[505.943453] Starting subtest: hang
[505.945687] Starting dynamic subtest: rcs0
[533.425240] Dynamic subtest rcs0: SUCCESS (27.475s)
[533.436145] Starting dynamic subtest: bcs0
[540.577293] Dynamic subtest bcs0: SUCCESS (7.154s)
[540.589718] Starting dynamic subtest: vcs0
[547.552849] Dynamic subtest vcs0: SUCCESS (6.974s)
[547.564908] Starting dynamic subtest: vcs1
[554.591899] Dynamic subtest vcs1: SUCCESS (7.038s)
[554.604047] Starting dynamic subtest: vecs0
[561.566558] Dynamic subtest vecs0: SUCCESS (6.972s)
[561.578863] Starting dynamic subtest: ccs0
[575.407042] Dynamic subtest ccs0: SUCCESS (13.836s)
[575.409809] Subtest hang: SUCCESS (69.457s)
[575.533816] [131/133] (446s left) kms_flip (wf_vblank-ts-check)
[575.635361] Starting subtest: wf_vblank-ts-check
[575.637276] Starting dynamic subtest: A-eDP1
[584.517892] Dynamic subtest A-eDP1: SUCCESS (8.883s)
[584.528446] Starting dynamic subtest: B-eDP1
[593.400647] Dynamic subtest B-eDP1: SUCCESS (8.880s)
[593.410161] Starting dynamic subtest: C-eDP1
[602.294543] Dynamic subtest C-eDP1: SUCCESS (8.891s)
[602.308395] Starting dynamic subtest: D-eDP1
[611.195173] Dynamic subtest D-eDP1: SUCCESS (8.895s)
[611.200071] Subtest wf_vblank-ts-check: SUCCESS (35.564s)
[612.396203] [132/133] (410s left) syncobj_timeline (etime-multi-wait-all-for-submit-submitted)
[612.450996] Starting subtest: etime-multi-wait-all-for-submit-submitted
[612.553871] Subtest etime-multi-wait-all-for-submit-submitted: SUCCESS (0.103s)
[612.631869] [133/133] (409s left) kms_chamelium_edid (dp-mode-timings)
[613.025711] Subtest dp-mode-timings: SKIP (0.000s)
[613.866694] Closing watchdogs
results: parsing output: 0/ for test: core_auth
results: parsing output: 1/ for test: kms_frontbuffer_tracking
results: parsing output: 2/ for test: kms_frontbuffer_tracking
results: parsing output: 3/ for test: gen7_exec_parse
results: parsing output: 4/ for test: kms_psr
results: parsing output: 5/ for test: sw_sync
results: parsing output: 6/ for test: kms_frontbuffer_tracking
results: parsing output: 7/ for test: gem_exec_store
results: parsing output: 8/ for test: gem_create
results: parsing output: 9/ for test: gem_pxp
results: parsing output: 10/ for test: i915_selftest
results: parsing output: 11/ for test: kms_chamelium_hpd
results: parsing output: 12/ for test: gem_exec_params
results: parsing output: 13/ for test: kms_universal_plane
results: parsing output: 14/ for test: gem_eio
results: parsing output: 15/ for test: perf
results: parsing output: 16/ for test: kms_properties
results: parsing output: 17/ for test: gem_unref_active_buffers
results: parsing output: 18/ for test: gem_exec_params
results: parsing output: 19/ for test: kms_frontbuffer_tracking
results: parsing output: 20/ for test: gem_lmem_swapping
results: parsing output: 21/ for test: gem_mmap_gtt
results: parsing output: 22/ for test: kms_ccs
results: parsing output: 23/ for test: sysfs_timeslice_duration
results: parsing output: 24/ for test: kms_big_fb
results: parsing output: 25/ for test: kms_frontbuffer_tracking
results: parsing output: 26/ for test: perf_pmu
results: parsing output: 27/ for test: kms_rotation_crc
results: parsing output: 28/ for test: gem_exec_alignment
results: parsing output: 29/ for test: gem_mmap_offset
results: parsing output: 30/ for test: gem_spin_batch
results: parsing output: 31/ for test: syncobj_timeline
results: parsing output: 32/ for test: kms_prop_blob
results: parsing output: 33/ for test: gem_ctx_persistence
results: parsing output: 34/ for test: kms_chamelium_color
results: parsing output: 35/ for test: gem_tiled_partial_pwrite_pread
results: parsing output: 36/ for test: kms_big_fb
results: parsing output: 37/ for test: kms_big_fb
results: parsing output: 38/ for test: gem_bad_reloc
results: parsing output: 39/ for test: gem_ctx_param
results: parsing output: 40/ for test: kms_flip_scaled_crc
results: parsing output: 41/ for test: kms_frontbuffer_tracking
results: parsing output: 42/ for test: gem_eio
results: parsing output: 43/ for test: kms_ccs
results: parsing output: 44/ for test: kms_rotation_crc
results: parsing output: 45/ for test: kms_frontbuffer_tracking
results: parsing output: 46/ for test: kms_big_fb
results: parsing output: 47/ for test: kms_plane
results: parsing output: 48/ for test: kms_big_fb
results: parsing output: 49/ for test: gem_exec_params
results: parsing output: 50/ for test: gem_exec_balancer
results: parsing output: 51/ for test: i915_drm_fdinfo
results: parsing output: 52/ for test: gem_exec_reloc
results: parsing output: 53/ for test: dmabuf_sync_file
results: parsing output: 54/ for test: gem_mmap_gtt
results: parsing output: 55/ for test: kms_frontbuffer_tracking
results: parsing output: 56/ for test: kms_frontbuffer_tracking
results: parsing output: 57/ for test: vgem_slow
results: parsing output: 58/ for test: kms_psr2_sf
results: parsing output: 59/ for test: kms_cursor_crc
results: parsing output: 60/ for test: kms_frontbuffer_tracking
results: parsing output: 61/ for test: kms_psr
results: parsing output: 62/ for test: kms_color
results: parsing output: 63/ for test: gem_mmap_gtt
results: parsing output: 64/ for test: kms_frontbuffer_tracking
results: parsing output: 65/ for test: kms_dp_linktrain_fallback
results: parsing output: 66/ for test: kms_addfb_basic
results: parsing output: 67/ for test: kms_psr
results: parsing output: 68/ for test: gem_exec_balancer
results: parsing output: 69/ for test: syncobj_timeline
results: parsing output: 70/ for test: kms_plane_scaling
results: parsing output: 71/ for test: syncobj_timeline
results: parsing output: 72/ for test: kms_getfb
results: parsing output: 73/ for test: i915_hangman
results: parsing output: 74/ for test: kms_async_flips
results: parsing output: 75/ for test: kms_force_connector_basic
results: parsing output: 76/ for test: gem_exec_fence
results: parsing output: 77/ for test: gem_exec_schedule
results: parsing output: 78/ for test: kms_plane_scaling
results: parsing output: 79/ for test: api_intel_allocator
results: parsing output: 80/ for test: kms_frontbuffer_tracking
results: parsing output: 81/ for test: gem_ctx_persistence
results: parsing output: 82/ for test: kms_pipe_crc_basic
results: parsing output: 83/ for test: kms_cursor_legacy
results: parsing output: 84/ for test: kms_frontbuffer_tracking
results: parsing output: 85/ for test: kms_flip
results: parsing output: 86/ for test: kms_frontbuffer_tracking
results: parsing output: 87/ for test: gem_basic
results: parsing output: 88/ for test: kms_frontbuffer_tracking
results: parsing output: 89/ for test: kms_cursor_crc
results: parsing output: 90/ for test: gem_render_copy
results: parsing output: 91/ for test: kms_plane_multiple
results: parsing output: 92/ for test: perf_pmu
results: parsing output: 93/ for test: kms_lease
results: parsing output: 94/ for test: kms_cursor_edge_walk
results: parsing output: 95/ for test: syncobj_timeline
results: parsing output: 96/ for test: kms_content_protection
results: parsing output: 97/ for test: kms_big_fb
results: parsing output: 98/ for test: kms_pm_rpm
results: parsing output: 99/ for test: kms_cursor_legacy
results: parsing output: 100/ for test: kms_cursor_legacy
results: parsing output: 101/ for test: kms_frontbuffer_tracking
results: parsing output: 102/ for test: i915_pm_rpm
results: parsing output: 103/ for test: kms_flip_scaled_crc
results: parsing output: 104/ for test: gem_exec_fence
results: parsing output: 105/ for test: kms_flip
results: parsing output: 106/ for test: kms_prime
results: parsing output: 107/ for test: kms_psr
results: parsing output: 108/ for test: kms_big_fb
results: parsing output: 109/ for test: kms_psr
results: parsing output: 110/ for test: kms_vblank
results: parsing output: 111/ for test: gem_ctx_bad_destroy
results: parsing output: 112/ for test: syncobj_wait
results: parsing output: 113/ for test: kms_setmode
results: parsing output: 114/ for test: syncobj_wait
results: parsing output: 115/ for test: gem_mmap_wc
results: parsing output: 116/ for test: kms_ccs
results: parsing output: 117/ for test: kms_hdr
results: parsing output: 118/ for test: syncobj_wait
results: parsing output: 119/ for test: kms_async_flips
results: parsing output: 120/ for test: gem_exec_schedule
results: parsing output: 121/ for test: gem_unfence_active_buffers
results: parsing output: 122/ for test: kms_rotation_crc
results: parsing output: 123/ for test: kms_dsc
results: parsing output: 124/ for test: gem_render_copy
results: parsing output: 125/ for test: gem_eio
results: parsing output: 126/ for test: kms_flip
results: parsing output: 127/ for test: gem_userptr_blits
results: parsing output: 128/ for test: kms_ccs
results: parsing output: 129/ for test: prime_busy
results: parsing output: 130/ for test: kms_flip
results: parsing output: 131/ for test: syncobj_timeline
results: parsing output: 132/ for test: kms_chamelium_edid
Done.