[56.731394] Initializing watchdogs
[56.731445]   /dev/watchdog0
[56.744754] [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.748823] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[56.751275] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[56.754656] [001/130] (960s left) gem_ctx_param (non-root-set)
[56.879786] Starting subtest: non-root-set
[56.899419] Subtest non-root-set: SUCCESS (0.020s)
[56.988362] [002/130] (960s left) kms_vblank (crtc-id)
[57.084915] Starting subtest: crtc-id
[57.087344] Starting dynamic subtest: pipe-A-eDP-1
[57.551781] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.462s)
[57.562968] Starting dynamic subtest: pipe-D-eDP-1
[58.628465] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.077s)
[58.630694] Subtest crtc-id: SUCCESS (1.542s)
[59.457118] [003/130] (957s left) gem_ctx_param (root-set)
[59.511413] Starting subtest: root-set
[59.513906] Subtest root-set: SUCCESS (0.001s)
[59.620658] [004/130] (957s left) dmabuf_sync_file (import-multiple-read-write)
[59.689680] Starting subtest: import-multiple-read-write
[59.692187] Subtest import-multiple-read-write: SUCCESS (0.002s)
[59.738234] [FACT dmabuf_sync_file (import-multiple-read-write)] new: kernel.kmod_is_loaded.vgem: true
[59.741291] [005/130] (957s left) syncobj_wait (wait-all-for-submit-snapshot)
[59.789616] Starting subtest: wait-all-for-submit-snapshot
[59.869253] Subtest wait-all-for-submit-snapshot: SUCCESS (0.081s)
[59.934534] [006/130] (957s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted)
[59.992947] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted
[60.097237] Subtest etime-multi-wait-all-for-submit-unsubmitted: SUCCESS (0.104s)
[60.166427] [007/130] (957s left) kms_flip (2x-flip-vs-dpms-off-vs-modeset-interruptible)
[60.269051] Starting subtest: 2x-flip-vs-dpms-off-vs-modeset-interruptible
[60.282499] Subtest 2x-flip-vs-dpms-off-vs-modeset-interruptible: SKIP (0.011s)
[60.349062] [008/130] (957s left) api_intel_bb (offset-control)
[60.408126] Starting subtest: offset-control
[60.415402] Subtest offset-control: SUCCESS (0.008s)
[60.542300] [009/130] (956s left) kms_big_fb (linear-8bpp-rotate-0)
[60.648416] Starting subtest: linear-8bpp-rotate-0
[61.849932] Subtest linear-8bpp-rotate-0: SUCCESS (1.202s)
[61.987626] [010/130] (955s left) i915_pm_rpm (sysfs-read)
[64.022791] Starting subtest: sysfs-read
[65.183618] Subtest sysfs-read: SUCCESS (1.158s)
[65.530497] [011/130] (952s left) gem_softpin (noreloc-interruptible)
[65.602506] Starting subtest: noreloc-interruptible
[70.998717] Subtest noreloc-interruptible: SUCCESS (5.394s)
[71.319458] [012/130] (946s left) gem_exec_schedule (fairslice)
[71.427083] Starting subtest: fairslice
[71.429485] Starting dynamic subtest: rcs0
[73.462442] Dynamic subtest rcs0: SUCCESS (2.032s)
[73.469581] Starting dynamic subtest: bcs0
[75.481068] Dynamic subtest bcs0: SUCCESS (2.019s)
[75.490275] Starting dynamic subtest: vcs0
[77.502452] Dynamic subtest vcs0: SUCCESS (2.020s)
[77.510140] Starting dynamic subtest: vcs1
[79.520973] Dynamic subtest vcs1: SUCCESS (2.017s)
[79.527933] Starting dynamic subtest: vecs0
[81.538651] Dynamic subtest vecs0: SUCCESS (2.020s)
[81.547879] Starting dynamic subtest: ccs0
[83.559778] Dynamic subtest ccs0: SUCCESS (2.021s)
[83.562100] Subtest fairslice: SUCCESS (12.131s)
[83.852741] [013/130] (933s left) kms_prop_blob (invalid-get-prop)
[83.913099] Starting subtest: invalid-get-prop
[83.915106] Subtest invalid-get-prop: SUCCESS (0.000s)
[84.024319] [014/130] (933s left) gem_ctx_create (basic)
[84.088639] Starting subtest: basic
[84.091078] Subtest basic: SUCCESS (0.000s)
[84.265172] [015/130] (933s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-indfb-draw-render)
[84.377836] Starting subtest: fbc-1p-offscren-pri-indfb-draw-render
[87.565295] Subtest fbc-1p-offscren-pri-indfb-draw-render: SUCCESS (3.192s)
[87.716577] [016/130] (930s left) gem_eio (in-flight-immediate)
[87.933567] Starting subtest: in-flight-immediate
[96.711302] Subtest in-flight-immediate: SUCCESS (8.777s)
[96.883748] [017/130] (920s left) syncobj_timeline (multi-wait-available-submitted-signaled)
[96.934864] Starting subtest: multi-wait-available-submitted-signaled
[96.936791] Subtest multi-wait-available-submitted-signaled: SUCCESS (0.000s)
[97.023114] [018/130] (920s left) kms_frontbuffer_tracking (psr-2p-primscrn-shrfb-msflip-blt)
[97.129256] Starting subtest: psr-2p-primscrn-shrfb-msflip-blt
[97.133591] Subtest psr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[97.212881] [019/130] (920s left) gem_readwrite (read-write)
[97.265633] Subtest read-write: SKIP (0.000s)
[97.315061] [020/130] (920s left) gem_ctx_persistence (many-contexts)
[97.373797] Starting subtest: many-contexts
[142.580969] Subtest many-contexts: SUCCESS (45.207s)
[142.655048] [021/130] (875s left) kms_flip (blocking-absolute-wf_vblank)
[142.757220] Starting subtest: blocking-absolute-wf_vblank
[142.759563] Starting dynamic subtest: A-eDP1
[151.491890] Dynamic subtest A-eDP1: SUCCESS (8.734s)
[151.499552] Starting dynamic subtest: B-eDP1
[160.221486] Dynamic subtest B-eDP1: SUCCESS (8.728s)
[160.229729] Starting dynamic subtest: C-eDP1
[168.939245] Dynamic subtest C-eDP1: SUCCESS (8.717s)
[168.947909] Starting dynamic subtest: D-eDP1
[177.667850] Dynamic subtest D-eDP1: SUCCESS (8.728s)
[177.672811] Subtest blocking-absolute-wf_vblank: SUCCESS (34.915s)
[178.882680] [022/130] (839s left) gem_ccs (block-copy-uncompressed)
[178.947927] Starting subtest: block-copy-uncompressed
[178.950922] Starting dynamic subtest: linear-uncompressed-compfmt0-smem-smem
[178.970227] Dynamic subtest linear-uncompressed-compfmt0-smem-smem: SUCCESS (0.022s)
[178.982788] Starting dynamic subtest: xmajor-uncompressed-compfmt0-smem-smem
[178.988535] Dynamic subtest xmajor-uncompressed-compfmt0-smem-smem: SUCCESS (0.018s)
[178.997664] Starting dynamic subtest: tile4-uncompressed-compfmt0-smem-smem
[179.006925] Dynamic subtest tile4-uncompressed-compfmt0-smem-smem: SUCCESS (0.018s)
[179.014044] Starting dynamic subtest: tile64-uncompressed-compfmt0-smem-smem
[179.026064] Dynamic subtest tile64-uncompressed-compfmt0-smem-smem: SUCCESS (0.019s)
[179.028449] Subtest block-copy-uncompressed: SUCCESS (0.079s)
[179.168930] [023/130] (838s left) kms_cursor_crc (cursor-random-128x42)
[179.373089] Starting subtest: cursor-random-128x42
[179.412121] Subtest cursor-random-128x42: SKIP (0.033s)
[179.617647] [024/130] (838s left) kms_getfb (getfb2-handle-not-fb)
[179.674063] Starting subtest: getfb2-handle-not-fb
[179.716768] Subtest getfb2-handle-not-fb: SUCCESS (0.044s)
[179.831240] [025/130] (838s left) prime_vgem (shrink)
[179.911848] Starting subtest: shrink
[179.931350] Subtest shrink: SUCCESS (0.018s)
[180.049798] [026/130] (838s left) kms_psr (pr-cursor-mmap-cpu)
[180.141590] Starting subtest: pr-cursor-mmap-cpu
[180.145661] Subtest pr-cursor-mmap-cpu: SKIP (0.000s)
[180.211932] [027/130] (837s left) gem_exec_reloc (basic-wc-read-active)
[180.277245] Subtest basic-wc-read-active: SKIP (0.000s)
[180.329252] [028/130] (837s left) gem_exec_suspend (basic-s0)
[180.394717] Starting subtest: basic-S0
[180.396639] Starting dynamic subtest: smem
[200.317285] Dynamic subtest smem: SUCCESS (6.523s)
[200.320492] Subtest basic-S0: SUCCESS (6.524s)
[200.501671] [029/130] (831s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[200.616432] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu
[200.620896] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[200.731067] [030/130] (830s left) kms_flip (bo-too-big)
[200.838873] Starting subtest: bo-too-big
[200.841019] Starting dynamic subtest: A-eDP1
[202.633660] Dynamic subtest A-eDP1: SUCCESS (1.789s)
[202.643242] Starting dynamic subtest: B-eDP1
[204.366299] Dynamic subtest B-eDP1: SUCCESS (1.729s)
[204.374351] Starting dynamic subtest: C-eDP1
[206.099203] Dynamic subtest C-eDP1: SUCCESS (1.729s)
[206.107827] Starting dynamic subtest: D-eDP1
[207.829551] Dynamic subtest D-eDP1: SUCCESS (1.727s)
[207.832384] Subtest bo-too-big: SUCCESS (6.980s)
[208.986514] [031/130] (822s left) gem_readwrite (beyond-eob)
[209.053224] Subtest beyond-EOB: SKIP (0.000s)
[209.105652] [032/130] (822s left) kms_chamelium_color (degamma)
[209.215212] Subtest degamma: SKIP (0.000s)
[209.255192] [033/130] (822s left) kms_psr2_sf (fbc-pr-overlay-plane-move-continuous-sf)
[214.275000] Starting subtest: fbc-pr-overlay-plane-move-continuous-sf
[214.279668] Subtest fbc-pr-overlay-plane-move-continuous-sf: SKIP (0.002s)
[215.102560] [034/130] (816s left) kms_psr (fbc-psr-dpms)
[215.207890] Starting subtest: fbc-psr-dpms
[215.210122] Starting dynamic subtest: eDP-1
[215.214251] Dynamic subtest eDP-1: SKIP (0.000s)
[215.218299] Subtest fbc-psr-dpms: SKIP (0.000s)
[215.276012] [035/130] (816s left) gem_partial_pwrite_pread (writes-after-reads-uncached)
[215.341201] Subtest writes-after-reads-uncached: SKIP (0.000s)
[215.427624] [036/130] (816s left) kms_addfb_basic (basic-x-tiled-legacy)
[215.489990] Starting subtest: basic-x-tiled-legacy
[215.494742] Subtest basic-x-tiled-legacy: SKIP (0.000s)
[215.623592] [037/130] (816s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-fullscreen)
[215.729500] Starting subtest: fbc-2p-primscrn-spr-indfb-fullscreen
[215.734055] Subtest fbc-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[215.836226] [038/130] (816s left) fbdev (eof)
[215.878082] Starting subtest: eof
[215.880471] Subtest eof: SUCCESS (0.000s)
[215.932057] [039/130] (816s left) kms_scaling_modes (scaling-mode-none)
[216.029747] Starting subtest: scaling-mode-none
[216.032153] Starting dynamic subtest: pipe-A-eDP-1
[216.142677] Dynamic subtest pipe-A-eDP-1: SKIP (0.110s)
[216.152808] Starting dynamic subtest: pipe-B-eDP-1
[216.195495] Dynamic subtest pipe-B-eDP-1: SKIP (0.051s)
[216.203774] Starting dynamic subtest: pipe-C-eDP-1
[216.241532] Dynamic subtest pipe-C-eDP-1: SKIP (0.045s)
[216.249529] Starting dynamic subtest: pipe-D-eDP-1
[216.288818] Dynamic subtest pipe-D-eDP-1: SKIP (0.047s)
[216.292506] Subtest scaling-mode-none: SKIP (0.257s)
[216.420402] [040/130] (815s left) kms_properties (invalid-properties-legacy)
[216.514486] Starting subtest: invalid-properties-legacy
[216.625748] Subtest invalid-properties-legacy: SUCCESS (0.109s)
[216.692174] [041/130] (815s left) gem_render_copy (y-tiled-mc-ccs-to-vebox-yf-tiled)
[216.758964] Starting subtest: y-tiled-mc-ccs-to-vebox-yf-tiled
[216.763830] Subtest y-tiled-mc-ccs-to-vebox-yf-tiled: SKIP (0.000s)
[216.939442] [042/130] (815s left) gem_mmap_wc (pf-nonblock)
[217.002047] Subtest pf-nonblock: SKIP (0.000s)
[217.061601] [043/130] (815s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[217.171686] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[217.176028] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[217.282448] [044/130] (814s left) gem_pxp (reject-modify-context-protection-off-3)
[217.420768] Starting subtest: reject-modify-context-protection-off-3
[217.422985] Subtest reject-modify-context-protection-off-3: SUCCESS (0.002s)
[217.519502] [045/130] (814s left) kms_ccs (crc-primary-basic-4-tiled-mtl-mc-ccs)
[217.614576] Starting subtest: crc-primary-basic-4-tiled-mtl-mc-ccs
[217.617032] Starting dynamic subtest: pipe-A-eDP-1
[219.731888] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.114s)
[219.740767] Starting dynamic subtest: pipe-B-eDP-1
[221.753175] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.019s)
[221.761652] Starting dynamic subtest: pipe-C-eDP-1
[223.744599] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.988s)
[223.753254] Starting dynamic subtest: pipe-D-eDP-1
[225.774355] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.028s)
[225.776728] Subtest crc-primary-basic-4-tiled-mtl-mc-ccs: SUCCESS (8.153s)
[227.023000] [046/130] (805s left) kms_chamelium_hpd (vga-hpd-fast)
[227.411647] Subtest vga-hpd-fast: SKIP (0.000s)
[228.229682] [047/130] (804s left) gem_flink_basic (basic)
[228.288978] Starting subtest: basic
[228.291380] Subtest basic: SUCCESS (0.000s)
[228.396245] [048/130] (803s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0)
[228.497033] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0
[229.628431] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0: SUCCESS (1.134s)
[229.747274] [049/130] (802s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-blt)
[229.849966] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-blt
[229.853890] Subtest fbc-2p-scndscrn-pri-indfb-draw-blt: SKIP (0.000s)
[229.964120] [050/130] (802s left) kms_plane (odd-panning)
[230.064499] Starting subtest: odd-panning
[230.066547] Starting dynamic subtest: pipe-A-eDP-1
[230.417113] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.350s)
[230.419454] Subtest odd-panning: SUCCESS (0.351s)
[231.268599] [051/130] (801s left) kms_cursor_legacy (cursorb-vs-flipa-atomic-transitions-varying-size)
[231.388104] Starting subtest: cursorB-vs-flipA-atomic-transitions-varying-size
[231.392423] Subtest cursorB-vs-flipA-atomic-transitions-varying-size: SKIP (0.001s)
[231.516727] [052/130] (800s left) kms_psr2_sf (psr2-overlay-plane-move-continuous-exceed-sf)
[236.518422] Starting subtest: psr2-overlay-plane-move-continuous-exceed-sf
[236.520760] Starting dynamic subtest: pipe-A-eDP-1
[248.552468] Dynamic subtest pipe-A-eDP-1: SUCCESS (12.041s)
[248.561735] Starting dynamic subtest: pipe-B-eDP-1
[260.567988] Dynamic subtest pipe-B-eDP-1: SUCCESS (12.015s)
[260.570334] Subtest psr2-overlay-plane-move-continuous-exceed-sf: SUCCESS (24.061s)
[261.414756] [053/130] (771s left) kms_plane_alpha_blend (constant-alpha-max)
[261.518182] Starting subtest: constant-alpha-max
[261.935063] Starting dynamic subtest: pipe-A-eDP-1
[262.867971] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.931s)
[262.877769] Starting dynamic subtest: pipe-D-eDP-1
[264.501835] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.633s)
[264.504136] Subtest constant-alpha-max: SUCCESS (2.983s)
[265.365636] [054/130] (767s left) kms_getfb (getfb-handle-closed)
[265.421543] Starting subtest: getfb-handle-closed
[265.423929] Subtest getfb-handle-closed: SUCCESS (0.000s)
[265.535576] [055/130] (767s left) syncobj_wait (wait-all-for-submit-delayed-submit)
[265.597910] Starting subtest: wait-all-for-submit-delayed-submit
[265.702028] Subtest wait-all-for-submit-delayed-submit: SUCCESS (0.103s)
[265.773584] [056/130] (766s left) gem_lmem_swapping (smem-oom)
[265.817194] Subtest smem-oom: SKIP (0.000s)
[265.856556] [057/130] (766s left) gem_mmap_gtt (hang-user)
[265.915045] Subtest hang-user: SKIP (0.000s)
[265.966056] [058/130] (766s left) kms_big_fb (4-tiled-32bpp-rotate-270)
[266.064373] Starting subtest: 4-tiled-32bpp-rotate-270
[266.084072] Subtest 4-tiled-32bpp-rotate-270: SKIP (0.019s)
[266.226662] [059/130] (766s left) kms_frontbuffer_tracking (psr-rgb101010-draw-render)
[266.332487] Starting subtest: psr-rgb101010-draw-render
[271.417981] Subtest psr-rgb101010-draw-render: SUCCESS (5.086s)
[271.548013] [060/130] (761s left) kms_addfb_basic (legacy-format)
[271.609338] Starting subtest: legacy-format
[271.628712] Subtest legacy-format: SUCCESS (0.019s)
[271.744162] [061/130] (761s left) gem_reg_read (timestamp-monotonic)
[271.801741] Starting subtest: timestamp-monotonic
[275.824198] Subtest timestamp-monotonic: SUCCESS (4.021s)
[275.893121] [062/130] (756s left) kms_busy (extended-pageflip-hang-newfb)
[275.993606] Starting subtest: extended-pageflip-hang-newfb
[275.995983] Starting dynamic subtest: pipe-A
[302.088208] Dynamic subtest pipe-A: SUCCESS (26.090s)
[302.097364] Starting dynamic subtest: pipe-D
[317.066718] Dynamic subtest pipe-D: SUCCESS (14.975s)
[317.068962] Subtest extended-pageflip-hang-newfb: SUCCESS (41.070s)
[317.858093] [063/130] (714s left) kms_chamelium_color (ctm-0-25)
[317.956041] Subtest ctm-0-25: SKIP (0.000s)
[317.995954] [064/130] (714s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling)
[318.098186] Starting subtest: flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling
[318.100533] Starting dynamic subtest: pipe-A-default-mode
[318.407491] Dynamic subtest pipe-A-default-mode: SKIP (0.306s)
[318.412264] Subtest flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling: SKIP (0.307s)
[319.176268] [065/130] (713s left) perf (non-zero-reason)
[319.287603] Starting subtest: non-zero-reason
[319.289758] Starting dynamic subtest: 0-rcs0
[324.796186] Dynamic subtest 0-rcs0: SUCCESS (5.508s)
[324.807045] Starting dynamic subtest: 1-vcs1
[328.762338] Dynamic subtest 1-vcs1: SUCCESS (3.965s)
[328.765486] Subtest non-zero-reason: SUCCESS (9.474s)
[328.806069] [066/130] (704s left) kms_cursor_legacy (flip-vs-cursor-legacy)
[328.937191] Starting subtest: flip-vs-cursor-legacy
[335.791020] Subtest flip-vs-cursor-legacy: SUCCESS (6.852s)
[336.605253] [067/130] (696s left) api_intel_allocator (fork-simple-stress-signal)
[336.667754] Starting subtest: fork-simple-stress-signal
[342.081060] Subtest fork-simple-stress-signal: SUCCESS (5.412s)
[342.198597] [068/130] (690s left) gem_pipe_control_store_loop (reused-buffer)
[342.268420] Starting subtest: reused-buffer
[344.426894] Subtest reused-buffer: SUCCESS (2.156s)
[344.548950] [069/130] (688s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-indfb-draw-mmap-wc)
[344.667552] Starting subtest: fbc-1p-offscren-pri-indfb-draw-mmap-wc
[347.706708] Subtest fbc-1p-offscren-pri-indfb-draw-mmap-wc: SUCCESS (3.043s)
[347.826293] [070/130] (685s left) gem_sync (basic-store-all)
[347.902378] Starting subtest: basic-store-all
[350.312259] Subtest basic-store-all: SUCCESS (2.419s)
[350.491727] [071/130] (682s left) gen9_exec_parse (batch-without-end)
[350.556586] Subtest batch-without-end: SKIP (0.000s)
[350.624610] [072/130] (682s left) kms_cursor_legacy (torture-bo)
[350.755407] Starting subtest: torture-bo
[350.757290] Starting dynamic subtest: pipe-A
[357.150067] Dynamic subtest pipe-A: SUCCESS (6.390s)
[357.161862] Starting dynamic subtest: pipe-B
[363.579970] Dynamic subtest pipe-B: SUCCESS (6.428s)
[363.591865] Starting dynamic subtest: pipe-C
[369.900582] Dynamic subtest pipe-C: SUCCESS (6.318s)
[369.912029] Starting dynamic subtest: pipe-D
[376.413878] Dynamic subtest pipe-D: SUCCESS (6.510s)
[376.425520] Starting dynamic subtest: all-pipes
[382.803883] Dynamic subtest all-pipes: SUCCESS (6.386s)
[382.806150] Subtest torture-bo: SUCCESS (32.041s)
[382.939567] [073/130] (650s left) kms_draw_crc (draw-method-blt)
[383.041413] Starting subtest: draw-method-blt
[383.043682] Starting dynamic subtest: xrgb8888-untiled
[383.256740] Dynamic subtest xrgb8888-untiled: SUCCESS (0.217s)
[383.265671] Starting dynamic subtest: rgb565-untiled
[383.467514] Dynamic subtest rgb565-untiled: SUCCESS (0.210s)
[383.476007] Starting dynamic subtest: xrgb2101010-untiled
[383.678544] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.210s)
[383.686864] Starting dynamic subtest: xbgr16161616f-untiled
[383.911509] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.233s)
[383.919769] Starting dynamic subtest: xrgb8888-xtiled
[384.011664] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.100s)
[384.019743] Starting dynamic subtest: rgb565-xtiled
[384.111746] Dynamic subtest rgb565-xtiled: SUCCESS (0.099s)
[384.119683] Starting dynamic subtest: xrgb2101010-xtiled
[384.211603] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.099s)
[384.219545] Starting dynamic subtest: xbgr16161616f-xtiled
[384.344903] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.133s)
[384.358883] Starting dynamic subtest: xrgb8888-4tiled
[384.455959] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.109s)
[384.463841] Starting dynamic subtest: rgb565-4tiled
[384.556111] Dynamic subtest rgb565-4tiled: SUCCESS (0.099s)
[384.564656] Starting dynamic subtest: xrgb2101010-4tiled
[384.656118] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.100s)
[384.664012] Starting dynamic subtest: xbgr16161616f-4tiled
[384.767360] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.111s)
[384.769409] Subtest draw-method-blt: SUCCESS (1.729s)
[384.899617] [074/130] (648s left) kms_vblank (ts-continuation-idle)
[385.013720] Starting subtest: ts-continuation-idle
[385.015729] Starting dynamic subtest: pipe-A-eDP-1
[385.426294] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.408s)
[385.441684] Starting dynamic subtest: pipe-D-eDP-1
[386.508055] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.081s)
[386.510455] Subtest ts-continuation-idle: SUCCESS (1.492s)
[387.376698] [075/130] (645s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[387.473720] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu
[387.478048] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[387.585700] [076/130] (645s left) gem_eio (unwedge-stress)
[387.826652] Starting subtest: unwedge-stress
[430.410047] Subtest unwedge-stress: SUCCESS (42.576s)
[432.352970] [077/130] (601s left) kms_cursor_crc (cursor-rapid-movement-64x21)
[432.523933] Starting subtest: cursor-rapid-movement-64x21
[432.565959] Subtest cursor-rapid-movement-64x21: SKIP (0.036s)
[432.800817] [078/130] (600s left) gem_exec_schedule (preempt-queue-contexts-chain)
[432.881169] Subtest preempt-queue-contexts-chain: SKIP (0.000s)
[433.164092] [079/130] (600s left) kms_psr (psr-cursor-mmap-gtt)
[433.272202] Starting subtest: psr-cursor-mmap-gtt
[433.274436] Starting dynamic subtest: eDP-1
[434.966983] Dynamic subtest eDP-1: SUCCESS (1.693s)
[434.969413] Subtest psr-cursor-mmap-gtt: SUCCESS (1.695s)
[435.824017] [080/130] (597s left) kms_vrr (flip-basic)
[435.919932] Starting subtest: flip-basic
[435.926553] Subtest flip-basic: SKIP (0.000s)
[435.991081] [081/130] (597s left) kms_lease (possible-crtcs-filtering)
[436.094874] Starting subtest: possible-crtcs-filtering
[436.102108] Subtest possible-crtcs-filtering: SUCCESS (0.005s)
[436.202567] [082/130] (597s left) syncobj_timeline (wait-zero-handles)
[436.249689] Starting subtest: wait-zero-handles
[436.251885] Subtest wait-zero-handles: SUCCESS (0.000s)
[436.335316] [083/130] (597s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-move)
[436.431404] Starting subtest: psr-2p-scndscrn-cur-indfb-move
[436.435428] Subtest psr-2p-scndscrn-cur-indfb-move: SKIP (0.000s)
[436.529017] [084/130] (597s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-64bpp-yftile-downscaling)
[436.630000] Starting subtest: flip-32bpp-yftile-to-64bpp-yftile-downscaling
[436.632211] Starting dynamic subtest: pipe-A-default-mode
[436.916015] Dynamic subtest pipe-A-default-mode: SKIP (0.281s)
[436.920770] Subtest flip-32bpp-yftile-to-64bpp-yftile-downscaling: SKIP (0.283s)
[437.744423] [085/130] (595s left) kms_rmfb (rmfb-ioctl)
[437.840881] Starting subtest: rmfb-ioctl
[437.843132] Starting dynamic subtest: pipe-A-eDP-1
[437.954745] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.113s)
[437.964926] Starting dynamic subtest: pipe-B-eDP-1
[439.101539] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.144s)
[439.115460] Starting dynamic subtest: pipe-C-eDP-1
[440.326398] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.222s)
[440.336828] Starting dynamic subtest: pipe-D-eDP-1
[441.448344] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.121s)
[441.450827] Subtest rmfb-ioctl: SUCCESS (3.608s)
[442.658088] [086/130] (591s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-indfb-draw-mmap-cpu)
[442.783378] Starting subtest: fbcpsr-1p-offscren-pri-indfb-draw-mmap-cpu
[447.450339] Subtest fbcpsr-1p-offscren-pri-indfb-draw-mmap-cpu: SUCCESS (4.672s)
[447.592257] [087/130] (586s left) syncobj_wait (single-wait-all-submitted)
[447.650914] Starting subtest: single-wait-all-submitted
[447.751440] Subtest single-wait-all-submitted: SUCCESS (0.101s)
[447.823451] [088/130] (585s left) kms_frontbuffer_tracking (basic)
[447.935574] Starting subtest: basic
[451.127075] Subtest basic: SUCCESS (3.198s)
[451.249401] [089/130] (582s left) kms_flip (modeset-vs-vblank-race)
[451.354130] Starting subtest: modeset-vs-vblank-race
[451.356068] Starting dynamic subtest: A-eDP1
[456.095001] Dynamic subtest A-eDP1: SUCCESS (4.739s)
[456.105558] Starting dynamic subtest: B-eDP1
[460.797006] Dynamic subtest B-eDP1: SUCCESS (4.700s)
[460.807400] Starting dynamic subtest: C-eDP1
[465.387696] Dynamic subtest C-eDP1: SUCCESS (4.589s)
[465.397720] Starting dynamic subtest: D-eDP1
[469.900215] Dynamic subtest D-eDP1: SUCCESS (4.512s)
[469.905105] Subtest modeset-vs-vblank-race: SUCCESS (18.550s)
[471.150477] [090/130] (562s left) gem_exec_whisper (basic-fds)
[471.222242] Starting subtest: basic-fds
[478.241937] Subtest basic-fds: SUCCESS (7.017s)
[478.425131] [091/130] (555s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180-hflip)
[478.537253] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180-hflip
[479.747009] Subtest x-tiled-max-hw-stride-64bpp-rotate-180-hflip: SUCCESS (1.211s)
[479.920327] [092/130] (553s left) gem_wait (wait)
[479.980109] Starting subtest: wait
[479.982457] Starting dynamic subtest: all-engines
[481.339431] Dynamic subtest all-engines: SUCCESS (1.357s)
[481.351561] Starting dynamic subtest: rcs0
[482.384642] Dynamic subtest rcs0: SUCCESS (1.044s)
[482.396038] Starting dynamic subtest: bcs0
[483.432805] Dynamic subtest bcs0: SUCCESS (1.046s)
[483.442981] Starting dynamic subtest: vcs0
[484.478187] Dynamic subtest vcs0: SUCCESS (1.044s)
[484.489882] Starting dynamic subtest: vcs1
[485.522749] Dynamic subtest vcs1: SUCCESS (1.042s)
[485.533391] Starting dynamic subtest: vecs0
[486.567582] Dynamic subtest vecs0: SUCCESS (1.043s)
[486.578099] Starting dynamic subtest: ccs0
[487.614187] Dynamic subtest ccs0: SUCCESS (1.045s)
[487.616745] Subtest wait: SUCCESS (7.634s)
[487.814393] [093/130] (546s left) syncobj_timeline (etime-single-wait-submitted)
[487.875343] Starting subtest: etime-single-wait-submitted
[487.983643] Subtest etime-single-wait-submitted: SUCCESS (0.108s)
[488.057996] [094/130] (545s left) kms_frontbuffer_tracking (psr-2p-primscrn-indfb-msflip-blt)
[488.163639] Starting subtest: psr-2p-primscrn-indfb-msflip-blt
[488.167656] Subtest psr-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[488.276636] [095/130] (545s left) vgem_basic (bad-flag)
[488.325130] Starting subtest: bad-flag
[488.327345] Subtest bad-flag: SUCCESS (0.000s)
[488.377129] [096/130] (545s left) gem_exec_fence (syncobj-signal)
[488.818698] Starting subtest: syncobj-signal
[488.824789] Subtest syncobj-signal: SUCCESS (0.004s)
[490.371307] [097/130] (543s left) kms_plane_scaling (planes-upscale-20x20-downscale-factor-0-75)
[490.468852] Starting subtest: planes-upscale-20x20-downscale-factor-0-75
[490.470972] Starting dynamic subtest: pipe-A
[490.487947] Dynamic subtest pipe-A: SKIP (0.015s)
[490.496742] Starting dynamic subtest: pipe-B
[490.518275] Dynamic subtest pipe-B: SKIP (0.026s)
[490.528169] Starting dynamic subtest: pipe-C
[490.542821] Dynamic subtest pipe-C: SKIP (0.026s)
[490.551317] Starting dynamic subtest: pipe-D
[490.569302] Dynamic subtest pipe-D: SKIP (0.026s)
[490.573155] Subtest planes-upscale-20x20-downscale-factor-0-75: SKIP (0.096s)
[490.669349] [098/130] (543s left) kms_ccs (missing-ccs-buffer-4-tiled-mtl-rc-ccs)
[490.763955] Starting subtest: missing-ccs-buffer-4-tiled-mtl-rc-ccs
[490.766384] Starting dynamic subtest: pipe-A-eDP-1
[490.786863] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[490.797773] Starting dynamic subtest: pipe-B-eDP-1
[490.817363] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[490.825449] Starting dynamic subtest: pipe-C-eDP-1
[490.845761] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[490.854977] Starting dynamic subtest: pipe-D-eDP-1
[490.875325] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[490.877660] Subtest missing-ccs-buffer-4-tiled-mtl-rc-ccs: SUCCESS (0.005s)
[492.891448] [099/130] (541s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[492.995867] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[493.000218] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SKIP (0.000s)
[493.106250] [100/130] (541s left) gem_workarounds (reset-context)
[493.164693] Starting subtest: reset-context
[493.299767] Subtest reset-context: SUCCESS (0.135s)
[493.452524] [101/130] (540s left) gem_exec_reloc (basic-cpu-gtt-noreloc)
[493.517550] Subtest basic-cpu-gtt-noreloc: SKIP (0.000s)
[493.596106] [102/130] (540s left) gem_pxp (verify-pxp-stale-buf-optout-execution)
[493.658419] Starting subtest: verify-pxp-stale-buf-optout-execution
[494.719085] Subtest verify-pxp-stale-buf-optout-execution: SUCCESS (1.059s)
[494.855680] [103/130] (539s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-pwrite)
[494.970431] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-pwrite
[501.560896] Subtest psr-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (6.593s)
[501.659477] [104/130] (532s left) kms_plane_scaling (planes-downscale-factor-0-25-unity-scaling)
[501.765859] Starting subtest: planes-downscale-factor-0-25-unity-scaling
[501.767971] Starting dynamic subtest: pipe-A
[501.781962] Dynamic subtest pipe-A: SKIP (0.011s)
[501.791370] Starting dynamic subtest: pipe-B
[501.806116] Dynamic subtest pipe-B: SKIP (0.022s)
[501.815000] Starting dynamic subtest: pipe-C
[501.826735] Dynamic subtest pipe-C: SKIP (0.021s)
[501.835213] Starting dynamic subtest: pipe-D
[501.850180] Dynamic subtest pipe-D: SKIP (0.021s)
[501.854622] Subtest planes-downscale-factor-0-25-unity-scaling: SKIP (0.078s)
[501.964719] [105/130] (532s left) gem_exec_balancer (semaphore)
[502.032198] Starting subtest: semaphore
[503.360863] Subtest semaphore: SUCCESS (1.327s)
[503.666262] [106/130] (530s left) gem_exec_endless (dispatch)
[503.733446] Starting subtest: dispatch
[503.735920] Starting dynamic subtest: rcs0
[503.743362] Dynamic subtest rcs0: SUCCESS (0.005s)
[503.754548] Starting dynamic subtest: bcs0
[503.759139] Dynamic subtest bcs0: SUCCESS (0.004s)
[503.771089] Starting dynamic subtest: vcs0
[503.775367] Dynamic subtest vcs0: SUCCESS (0.004s)
[503.784002] Starting dynamic subtest: vcs1
[503.788278] Dynamic subtest vcs1: SUCCESS (0.004s)
[503.796535] Starting dynamic subtest: vecs0
[503.800969] Dynamic subtest vecs0: SUCCESS (0.004s)
[503.809419] Starting dynamic subtest: ccs0
[503.813863] Dynamic subtest ccs0: SUCCESS (0.004s)
[503.816042] Subtest dispatch: SUCCESS (0.029s)
[505.371945] [107/130] (529s left) kms_tv_load_detect (load-detect)
[505.420009] Subtest load-detect: SKIP (0.000s)
[505.478874] [108/130] (528s left) kms_ccs (crc-primary-suspend-4-tiled-dg2-rc-ccs)
[505.577918] Starting subtest: crc-primary-suspend-4-tiled-dg2-rc-ccs
[505.580249] Starting dynamic subtest: pipe-A-eDP-1
[505.589462] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[505.599507] Starting dynamic subtest: pipe-B-eDP-1
[505.608729] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[505.617754] Starting dynamic subtest: pipe-C-eDP-1
[505.626665] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[505.635422] Starting dynamic subtest: pipe-D-eDP-1
[505.644379] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[505.648955] Subtest crc-primary-suspend-4-tiled-dg2-rc-ccs: SKIP (0.004s)
[507.736792] [109/130] (526s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-move)
[507.842365] Starting subtest: fbc-1p-primscrn-cur-indfb-move
[510.929135] Subtest fbc-1p-primscrn-cur-indfb-move: SUCCESS (3.090s)
[511.091921] [110/130] (523s left) gem_ctx_engines (independent)
[511.164563] Starting subtest: independent
[511.166418] Starting dynamic subtest: rcs0
[511.292254] Dynamic subtest rcs0: SUCCESS (0.128s)
[511.303744] Starting dynamic subtest: bcs0
[511.423099] Dynamic subtest bcs0: SUCCESS (0.130s)
[511.433954] Starting dynamic subtest: vcs0
[511.549995] Dynamic subtest vcs0: SUCCESS (0.126s)
[511.558287] Starting dynamic subtest: vcs1
[511.691856] Dynamic subtest vcs1: SUCCESS (0.141s)
[511.706776] Starting dynamic subtest: vecs0
[511.825685] Dynamic subtest vecs0: SUCCESS (0.133s)
[511.837342] Starting dynamic subtest: ccs0
[511.978383] Dynamic subtest ccs0: SUCCESS (0.151s)
[511.990158] Starting dynamic subtest: all-engines
[512.535070] Dynamic subtest all-engines: SUCCESS (0.557s)
[512.537527] Subtest independent: SUCCESS (1.373s)
[513.492411] [111/130] (521s left) syncobj_timeline (multi-wait-for-submit-submitted-signaled)
[513.550723] Starting subtest: multi-wait-for-submit-submitted-signaled
[513.553692] Subtest multi-wait-for-submit-submitted-signaled: SUCCESS (0.001s)
[513.658262] [112/130] (520s left) kms_invalid_mode (bad-hsync-end)
[513.762765] Starting subtest: bad-hsync-end
[513.765033] Starting dynamic subtest: pipe-A-eDP-1
[514.042768] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.280s)
[514.051578] Starting dynamic subtest: pipe-B-eDP-1
[514.053753] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[514.062238] Starting dynamic subtest: pipe-C-eDP-1
[514.064443] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[514.072904] Starting dynamic subtest: pipe-D-eDP-1
[514.074870] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[514.076713] Subtest bad-hsync-end: SUCCESS (0.285s)
[514.888880] [113/130] (519s left) perf_pmu (render-node-busy)
[514.976725] Starting subtest: render-node-busy
[514.979173] Starting dynamic subtest: rcs0
[515.572187] Dynamic subtest rcs0: SUCCESS (0.597s)
[515.585039] Starting dynamic subtest: bcs0
[516.137410] Dynamic subtest bcs0: SUCCESS (0.562s)
[516.149147] Starting dynamic subtest: vcs0
[517.307506] Dynamic subtest vcs0: SUCCESS (1.167s)
[517.318022] Starting dynamic subtest: vcs1
[519.291383] Dynamic subtest vcs1: SUCCESS (1.982s)
[519.303010] Starting dynamic subtest: vecs0
[521.339863] Dynamic subtest vecs0: SUCCESS (2.046s)
[521.349854] Starting dynamic subtest: ccs0
[521.913529] Dynamic subtest ccs0: SUCCESS (0.573s)
[521.916292] Subtest render-node-busy: SUCCESS (6.940s)
[521.987180] [114/130] (512s left) kms_cursor_crc (cursor-offscreen-32x10)
[522.136561] Starting subtest: cursor-offscreen-32x10
[522.177313] Subtest cursor-offscreen-32x10: SKIP (0.033s)
[522.441461] [115/130] (512s left) kms_big_fb (yf-tiled-16bpp-rotate-180)
[522.555207] Starting subtest: yf-tiled-16bpp-rotate-180
[522.559280] Subtest yf-tiled-16bpp-rotate-180: SKIP (0.000s)
[522.648912] [116/130] (512s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[522.759224] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[522.763221] Subtest y-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[522.850766] [117/130] (511s left) gem_ctx_persistence (file)
[522.914005] Starting subtest: file
[523.063058] Subtest file: SUCCESS (0.149s)
[523.204272] [118/130] (511s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-mmap-cpu)
[523.305003] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-mmap-cpu
[528.173731] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-mmap-cpu: SUCCESS (4.870s)
[528.306046] [119/130] (506s left) i915_module_load (resize-bar)
[528.344391] Starting subtest: resize-bar
[528.350994] Subtest resize-bar: SKIP (0.006s)
[528.392410] [120/130] (506s left) kms_chamelium_frames (hdmi-crc-nonplanar-formats)
[528.785883] Subtest hdmi-crc-nonplanar-formats: SKIP (0.000s)
[529.543499] [121/130] (505s left) kms_frontbuffer_tracking (fbcpsr-farfromfence-mmap-gtt)
[529.660424] Starting subtest: fbcpsr-farfromfence-mmap-gtt
[529.664788] Subtest fbcpsr-farfromfence-mmap-gtt: SKIP (0.000s)
[529.761369] [122/130] (505s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-pwrite)
[529.870619] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-pwrite
[534.589063] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-pwrite: SUCCESS (4.722s)
[534.710773] [123/130] (500s left) gem_spin_batch (spin-all-new)
[534.785402] Starting subtest: spin-all-new
[534.799682] Subtest spin-all-new: SUCCESS (0.012s)
[536.352610] [124/130] (498s left) gem_mmap_gtt (basic)
[536.415542] Subtest basic: SKIP (0.000s)
[536.465973] [125/130] (498s left) kms_psr (fbc-pr-primary-page-flip)
[536.567083] Starting subtest: fbc-pr-primary-page-flip
[536.573167] Subtest fbc-pr-primary-page-flip: SKIP (0.000s)
[536.630825] [126/130] (498s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[536.750175] Starting subtest: basic-flip-before-cursor-legacy
[537.652542] Subtest basic-flip-before-cursor-legacy: SUCCESS (0.904s)
[538.452821] [127/130] (496s left) kms_pm_lpsp (screens-disabled)
[539.558750] Starting subtest: screens-disabled
[539.563104] Subtest screens-disabled: SKIP (0.000s)
[539.625994] [128/130] (495s left) kms_frontbuffer_tracking (psr-1p-offscren-pri-shrfb-draw-blt)
[539.724181] Starting subtest: psr-1p-offscren-pri-shrfb-draw-blt
[544.554999] Subtest psr-1p-offscren-pri-shrfb-draw-blt: SUCCESS (4.832s)
[544.698251] [129/130] (490s left) kms_psr (pr-sprite-blt)
[544.797577] Starting subtest: pr-sprite-blt
[544.801837] Subtest pr-sprite-blt: SKIP (0.000s)
[544.865741] [130/130] (490s left) gem_mmap_wc (write-cpu-read-wc)
[544.934548] Subtest write-cpu-read-wc: SKIP (0.000s)
[544.996409] Closing watchdogs
Done.