[62.305720] Initializing watchdogs
[62.305768]   /dev/watchdog0
[62.325050] [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]
[62.329462] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[62.331924] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[62.335340] [001/133] (960s left) gem_ctx_isolation (clean)
[62.421672] Starting subtest: clean
[62.424156] Starting dynamic subtest: rcs0
[62.481840] Dynamic subtest rcs0: SUCCESS (0.059s)
[62.488931] Starting dynamic subtest: bcs0
[62.558845] Dynamic subtest bcs0: SUCCESS (0.077s)
[62.567199] Starting dynamic subtest: vcs0
[62.632706] Dynamic subtest vcs0: SUCCESS (0.073s)
[62.642137] Starting dynamic subtest: vcs1
[63.920802] Dynamic subtest vcs1: SUCCESS (1.288s)
[63.929825] Starting dynamic subtest: vecs0
[65.898629] Dynamic subtest vecs0: SUCCESS (1.978s)
[65.907787] Starting dynamic subtest: ccs0
[68.080583] Dynamic subtest ccs0: SUCCESS (2.182s)
[68.083166] Subtest clean: SUCCESS (5.659s)
[68.319588] [002/133] (954s left) kms_pipe_stress (stress-xrgb8888-4tiled)
[68.428201] Starting subtest: stress-xrgb8888-4tiled
[74.379651] Subtest stress-xrgb8888-4tiled: SUCCESS (5.952s)
[74.427707] [003/133] (948s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-blt)
[74.527804] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-blt
[74.531314] Subtest fbc-2p-scndscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[74.626438] [004/133] (948s left) kms_flip (2x-flip-vs-fences-interruptible)
[74.723526] Starting subtest: 2x-flip-vs-fences-interruptible
[74.727055] Subtest 2x-flip-vs-fences-interruptible: SKIP (0.000s)
[74.823890] [005/133] (948s left) kms_cursor_crc (cursor-rapid-movement-512x170)
[75.089096] Starting subtest: cursor-rapid-movement-512x170
[75.093057] Subtest cursor-rapid-movement-512x170: SKIP (0.001s)
[75.233028] [006/133] (947s left) kms_chamelium_hpd (dp-hpd-for-each-pipe)
[75.628688] Subtest dp-hpd-for-each-pipe: SKIP (0.000s)
[76.430877] [007/133] (946s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-render)
[76.528652] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-render
[76.533082] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[76.608477] [008/133] (946s left) gem_partial_pwrite_pread (writes-after-reads-snoop)
[76.677740] Subtest writes-after-reads-snoop: SKIP (0.000s)
[76.740041] [009/133] (946s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-mmap-gtt)
[76.833075] Starting subtest: psr-1p-primscrn-spr-indfb-draw-mmap-gtt
[76.837220] Subtest psr-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[76.922496] [010/133] (946s left) gem_pxp (verify-pxp-stale-buf-execution)
[77.049998] Starting subtest: verify-pxp-stale-buf-execution
[78.113579] Subtest verify-pxp-stale-buf-execution: SUCCESS (1.065s)
[78.222510] [011/133] (944s left) kms_psr2_su (frontbuffer-xrgb8888)
[78.280196] Subtest frontbuffer-XRGB8888: SKIP (0.000s)
[78.333312] [012/133] (944s left) kms_busy (extended-modeset-hang-newfb-with-reset)
[78.438580] Starting subtest: extended-modeset-hang-newfb-with-reset
[78.440913] Starting dynamic subtest: pipe-A
[93.863430] Dynamic subtest pipe-A: SUCCESS (15.425s)
[93.873198] Starting dynamic subtest: pipe-D
[109.728541] Dynamic subtest pipe-D: SUCCESS (15.862s)
[109.730975] Subtest extended-modeset-hang-newfb-with-reset: SUCCESS (31.291s)
[110.531831] [013/133] (912s left) kms_dp_linktrain_fallback (dsc-fallback)
[110.635063] Starting subtest: dsc-fallback
[110.641630] Subtest dsc-fallback: SKIP (0.000s)
[110.703720] [014/133] (912s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-16bpp-4tile-upscaling)
[110.795052] Starting subtest: flip-64bpp-4tile-to-16bpp-4tile-upscaling
[110.797292] Starting dynamic subtest: pipe-A-default-mode
[113.553218] Dynamic subtest pipe-A-default-mode: SUCCESS (2.755s)
[113.555355] Subtest flip-64bpp-4tile-to-16bpp-4tile-upscaling: SUCCESS (2.756s)
[114.425289] [015/133] (908s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[114.527434] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[114.531931] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[114.621608] [016/133] (908s left) kms_content_protection (legacy)
[114.774387] Starting subtest: legacy
[114.779075] Subtest legacy: SKIP (0.000s)
[114.905698] [017/133] (908s left) perf (gen12-group-exclusive-stream-ctx-handle)
[115.032811] Starting subtest: gen12-group-exclusive-stream-ctx-handle
[115.057847] Subtest gen12-group-exclusive-stream-ctx-handle: SUCCESS (0.025s)
[115.104392] [018/133] (908s left) kms_flip (2x-wf_vblank-ts-check-interruptible)
[116.901972] Starting subtest: 2x-wf_vblank-ts-check-interruptible
[116.912784] Subtest 2x-wf_vblank-ts-check-interruptible: SKIP (0.011s)
[116.987722] [019/133] (906s left) kms_force_connector_basic (force-connector-state)
[117.067776] Starting subtest: force-connector-state
[117.687431] Subtest force-connector-state: SUCCESS (0.622s)
[118.596460] [020/133] (904s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-render)
[118.687660] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-render
[125.840665] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-render: SUCCESS (7.155s)
[125.976254] [021/133] (897s left) prime_vgem (basic-read)
[126.124813] Starting subtest: basic-read
[126.131971] Subtest basic-read: SKIP (0.005s)
[126.237226] [FACT prime_vgem (basic-read)] new: kernel.kmod_is_loaded.vgem: true
[126.240269] [022/133] (897s left) gem_ctx_persistence (saturated-hostile)
[126.302037] Starting subtest: saturated-hostile
[126.304541] Starting dynamic subtest: rcs0
[127.133511] Dynamic subtest rcs0: SUCCESS (0.831s)
[127.142636] Starting dynamic subtest: bcs0
[127.919276] Dynamic subtest bcs0: SUCCESS (0.785s)
[127.927626] Starting dynamic subtest: vcs0
[128.721876] Dynamic subtest vcs0: SUCCESS (0.801s)
[128.732663] Starting dynamic subtest: vcs1
[129.523727] Dynamic subtest vcs1: SUCCESS (0.800s)
[129.534252] Starting dynamic subtest: vecs0
[130.309614] Dynamic subtest vecs0: SUCCESS (0.785s)
[130.320284] Starting dynamic subtest: ccs0
[131.124620] Dynamic subtest ccs0: SUCCESS (0.813s)
[131.127538] Subtest saturated-hostile: SUCCESS (4.823s)
[131.204677] [023/133] (892s left) kms_big_fb (linear-16bpp-rotate-270)
[131.308988] Starting subtest: linear-16bpp-rotate-270
[131.329261] Subtest linear-16bpp-rotate-270: SKIP (0.020s)
[131.440346] [024/133] (892s left) kms_joiner (basic-max-non-joiner)
[131.538383] Starting subtest: basic-max-non-joiner
[131.542482] Subtest basic-max-non-joiner: SKIP (0.000s)
[131.607714] [025/133] (892s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-wc)
[131.704973] Starting subtest: fbcpsr-rgb565-draw-mmap-wc
[138.531384] Subtest fbcpsr-rgb565-draw-mmap-wc: SUCCESS (6.829s)
[138.662385] [026/133] (885s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-signaled)
[138.724045] Starting subtest: invalid-multi-wait-all-unsubmitted-signaled
[138.726365] Subtest invalid-multi-wait-all-unsubmitted-signaled: SUCCESS (0.001s)
[138.833287] [027/133] (885s left) kms_prime (d3hot)
[138.919472] Subtest D3hot: SKIP (0.000s)
[139.026514] [028/133] (884s left) gem_exec_params (invalid-bsd2-flag-on-vebox)
[139.082591] Starting subtest: invalid-bsd2-flag-on-vebox
[139.084941] Subtest invalid-bsd2-flag-on-vebox: SUCCESS (0.000s)
[139.182720] [029/133] (884s left) kms_cursor_legacy (flip-vs-cursor-legacy)
[139.297383] Starting subtest: flip-vs-cursor-legacy
[146.448510] Subtest flip-vs-cursor-legacy: SUCCESS (7.151s)
[147.272716] [030/133] (876s left) gem_exec_schedule (in-order)
[147.348655] Starting subtest: in-order
[147.350885] Starting dynamic subtest: rcs0
[147.448444] Dynamic subtest rcs0: SUCCESS (0.106s)
[147.457644] Starting dynamic subtest: vcs0
[147.533870] Dynamic subtest vcs0: SUCCESS (0.085s)
[147.544055] Starting dynamic subtest: vcs1
[147.617194] Dynamic subtest vcs1: SUCCESS (0.083s)
[147.625287] Starting dynamic subtest: vecs0
[147.715746] Dynamic subtest vecs0: SUCCESS (0.096s)
[147.727160] Starting dynamic subtest: ccs0
[147.810249] Dynamic subtest ccs0: SUCCESS (0.094s)
[147.812873] Subtest in-order: SUCCESS (0.469s)
[148.979431] [031/133] (875s left) api_intel_bb (intel-bb-blit-x)
[149.041880] Starting subtest: intel-bb-blit-x
[153.039260] Subtest intel-bb-blit-x: SUCCESS (3.996s)
[153.145647] [032/133] (870s left) kms_chamelium_frames (dp-crc-single)
[153.553537] Subtest dp-crc-single: SKIP (0.000s)
[154.340339] [033/133] (869s left) kms_plane (pixel-format-4-tiled-mtl-rc-ccs-cc-modifier)
[154.439377] Starting subtest: pixel-format-4-tiled-mtl-rc-ccs-cc-modifier
[154.465287] Starting dynamic subtest: pipe-A-plane-0
[154.754149] Dynamic subtest pipe-A-plane-0: SUCCESS (0.289s)
[154.762541] Starting dynamic subtest: pipe-A-plane-3
[154.998452] Dynamic subtest pipe-A-plane-3: SUCCESS (0.243s)
[155.006428] Starting dynamic subtest: pipe-A-plane-5
[155.010573] Dynamic subtest pipe-A-plane-5: SKIP (0.000s)
[156.125689] Starting dynamic subtest: pipe-B-plane-0
[156.414786] Dynamic subtest pipe-B-plane-0: SUCCESS (0.300s)
[156.423166] Starting dynamic subtest: pipe-B-plane-3
[156.647727] Dynamic subtest pipe-B-plane-3: SUCCESS (0.232s)
[156.656700] Starting dynamic subtest: pipe-B-plane-5
[156.660893] Dynamic subtest pipe-B-plane-5: SKIP (0.000s)
[156.965017] Subtest pixel-format-4-tiled-mtl-rc-ccs-cc-modifier: SUCCESS (2.527s)
[157.768251] [034/133] (866s left) gem_mmap_gtt (basic-write-gtt)
[157.831178] Subtest basic-write-gtt: SKIP (0.000s)
[157.889352] [035/133] (866s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-gtt)
[157.983054] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-gtt
[157.987530] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[158.066414] [036/133] (866s left) kms_vblank (query-busy)
[158.153841] Starting subtest: query-busy
[158.155706] Starting dynamic subtest: pipe-A-eDP-1
[160.028161] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.875s)
[160.041337] Starting dynamic subtest: pipe-D-eDP-1
[162.591234] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.559s)
[162.593076] Subtest query-busy: SUCCESS (4.437s)
[163.463028] [037/133] (860s left) syncobj_wait (invalid-multi-wait-unsubmitted)
[163.508894] Starting subtest: invalid-multi-wait-unsubmitted
[163.511206] Subtest invalid-multi-wait-unsubmitted: SUCCESS (0.000s)
[163.617869] [038/133] (860s left) prime_mmap_coherency (write)
[163.668426] Starting subtest: write
[169.044260] Subtest write: SUCCESS (5.373s)
[169.190360] [039/133] (855s left) kms_pm_dc (dc5-dpms-negative)
[170.293100] Starting subtest: dc5-dpms-negative
[170.297623] Subtest dc5-dpms-negative: SKIP (0.000s)
[170.367316] [040/133] (854s left) kms_big_fb (y-tiled-64bpp-rotate-0)
[170.489678] Starting subtest: y-tiled-64bpp-rotate-0
[170.494201] Subtest y-tiled-64bpp-rotate-0: SKIP (0.000s)
[170.586639] [041/133] (853s left) gen9_exec_parse (unaligned-access)
[170.649523] Subtest unaligned-access: SKIP (0.000s)
[170.711472] [042/133] (853s left) kms_cursor_crc (cursor-offscreen-256x256)
[170.976589] Starting subtest: cursor-offscreen-256x256
[170.981317] Starting dynamic subtest: pipe-A-eDP-1
[172.900540] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.915s)
[172.927301] Starting dynamic subtest: pipe-D-eDP-1
[175.558503] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.637s)
[175.560661] Subtest cursor-offscreen-256x256: SUCCESS (4.579s)
[176.360354] [043/133] (848s left) gem_close_race (multigpu-basic-threads)
[176.433440] Starting subtest: multigpu-basic-threads
[176.437822] Subtest multigpu-basic-threads: SKIP (0.000s)
[176.652865] [044/133] (847s left) kms_addfb_basic (too-high)
[176.740322] Starting subtest: too-high
[176.742675] Subtest too-high: SUCCESS (0.001s)
[176.847944] [045/133] (847s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip)
[176.947075] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip
[177.649442] Subtest 4-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip: SUCCESS (0.704s)
[177.803431] [046/133] (846s left) gem_spin_batch (engines)
[177.871399] Starting subtest: engines
[177.873778] Starting dynamic subtest: rcs0
[181.111300] Dynamic subtest rcs0: SUCCESS (3.235s)
[181.122026] Starting dynamic subtest: bcs0
[184.350035] Dynamic subtest bcs0: SUCCESS (3.236s)
[184.360437] Starting dynamic subtest: vcs0
[187.588636] Dynamic subtest vcs0: SUCCESS (3.236s)
[187.598852] Starting dynamic subtest: vcs1
[190.822893] Dynamic subtest vcs1: SUCCESS (3.232s)
[190.833027] Starting dynamic subtest: vecs0
[194.062337] Dynamic subtest vecs0: SUCCESS (3.236s)
[194.073074] Starting dynamic subtest: ccs0
[197.300855] Dynamic subtest ccs0: SUCCESS (3.235s)
[197.303307] Subtest engines: SUCCESS (19.426s)
[197.526715] [047/133] (827s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-mmap-gtt)
[197.623287] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-mmap-gtt
[197.627579] Subtest fbc-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[197.705234] [048/133] (827s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180-hflip)
[197.805241] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180-hflip
[197.809576] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180-hflip: SKIP (0.000s)
[197.896160] [049/133] (826s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-mmap-gtt)
[198.001093] Starting subtest: psr-1p-primscrn-pri-indfb-draw-mmap-gtt
[198.005290] Subtest psr-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[198.112396] [050/133] (826s left) gem_exec_whisper (basic-contexts)
[198.183103] Starting subtest: basic-contexts
[204.275264] Subtest basic-contexts: SUCCESS (6.092s)
[204.469274] [051/133] (820s left) perf_pmu (most-busy-check-all)
[204.513655] Starting subtest: most-busy-check-all
[204.515904] Starting dynamic subtest: rcs0
[205.910443] Dynamic subtest rcs0: SUCCESS (1.396s)
[205.921606] Starting dynamic subtest: bcs0
[207.893960] Dynamic subtest bcs0: SUCCESS (1.980s)
[207.904927] Starting dynamic subtest: vcs0
[209.877955] Dynamic subtest vcs0: SUCCESS (1.981s)
[209.888588] Starting dynamic subtest: vcs1
[211.925766] Dynamic subtest vcs1: SUCCESS (2.045s)
[211.935777] Starting dynamic subtest: vecs0
[213.909754] Dynamic subtest vecs0: SUCCESS (1.982s)
[213.919701] Starting dynamic subtest: ccs0
[215.894026] Dynamic subtest ccs0: SUCCESS (1.982s)
[215.896772] Subtest most-busy-check-all: SUCCESS (11.382s)
[216.035940] [052/133] (808s left) kms_psr2_sf (pr-overlay-plane-update-sf-dmg-area)
[216.144562] Starting subtest: pr-overlay-plane-update-sf-dmg-area
[216.148964] Subtest pr-overlay-plane-update-sf-dmg-area: SKIP (0.000s)
[216.251272] [053/133] (808s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-render)
[216.352622] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-render
[216.356809] Subtest psr-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[216.463867] [054/133] (808s left) prime_self_import (basic-llseek-bad)
[216.506093] Starting subtest: basic-llseek-bad
[216.524404] Subtest basic-llseek-bad: SUCCESS (0.017s)
[216.618913] [055/133] (808s left) gem_exec_schedule (u-lateslice)
[216.693929] Starting subtest: u-lateslice
[216.696084] Starting dynamic subtest: rcs0
[216.703758] Dynamic subtest rcs0: SUCCESS (0.020s)
[216.714816] Starting dynamic subtest: bcs0
[216.721034] Dynamic subtest bcs0: SUCCESS (0.017s)
[216.729296] Starting dynamic subtest: vcs0
[216.738752] Dynamic subtest vcs0: SUCCESS (0.017s)
[216.747431] Starting dynamic subtest: vcs1
[216.760967] Dynamic subtest vcs1: SUCCESS (0.021s)
[216.776120] Starting dynamic subtest: vecs0
[216.780502] Dynamic subtest vecs0: SUCCESS (0.018s)
[216.788968] Starting dynamic subtest: ccs0
[216.795532] Dynamic subtest ccs0: SUCCESS (0.016s)
[216.797829] Subtest u-lateslice: SUCCESS (0.113s)
[218.028567] [056/133] (807s left) kms_atomic_transition (plane-all-transition-nonblocking)
[218.121803] Starting subtest: plane-all-transition-nonblocking
[218.124276] Starting dynamic subtest: pipe-A-eDP-1
[220.614560] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.495s)
[220.936721] Starting dynamic subtest: pipe-B-eDP-1
[224.150555] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.228s)
[224.461168] Subtest plane-all-transition-nonblocking: SUCCESS (6.338s)
[225.256161] [057/133] (799s left) gem_mmap_gtt (flink-race)
[225.312409] Subtest flink-race: SKIP (0.000s)
[225.375383] [058/133] (799s left) syncobj_timeline (multi-wait-for-submit-available-unsubmitted-submitted)
[225.422908] Starting subtest: multi-wait-for-submit-available-unsubmitted-submitted
[225.425113] Subtest multi-wait-for-submit-available-unsubmitted-submitted: SUCCESS (0.000s)
[225.518940] [059/133] (799s left) vgem_basic (second-client)
[225.568733] Starting subtest: second-client
[225.591125] Subtest second-client: SUCCESS (0.022s)
[225.650979] [060/133] (799s left) kms_vblank (query-forked-busy-hang)
[225.745296] Starting subtest: query-forked-busy-hang
[225.747165] Starting dynamic subtest: pipe-A-eDP-1
[253.674521] Dynamic subtest pipe-A-eDP-1: SUCCESS (27.927s)
[253.688621] Starting dynamic subtest: pipe-D-eDP-1
[278.673433] Dynamic subtest pipe-D-eDP-1: SUCCESS (24.996s)
[278.675891] Subtest query-forked-busy-hang: SUCCESS (52.927s)
[279.558701] [061/133] (745s left) vgem_slow (nohang)
[279.601127] Starting subtest: nohang
[289.908079] Subtest nohang: SUCCESS (10.306s)
[289.974628] [062/133] (735s left) kms_plane (pixel-format-4-tiled-dg2-rc-ccs-cc-modifier)
[290.062274] Starting subtest: pixel-format-4-tiled-dg2-rc-ccs-cc-modifier
[290.066669] Subtest pixel-format-4-tiled-dg2-rc-ccs-cc-modifier: SKIP (0.000s)
[290.153129] [063/133] (735s left) gem_exercise_blt (fast-copy)
[290.210756] Starting subtest: fast-copy
[290.212991] Starting dynamic subtest: linear-smem-smem
[290.234807] Dynamic subtest linear-smem-smem: SUCCESS (0.023s)
[290.245101] Starting dynamic subtest: xmajor-smem-smem
[290.268283] Dynamic subtest xmajor-smem-smem: SUCCESS (0.033s)
[290.276677] Starting dynamic subtest: tile4-smem-smem
[290.286941] Dynamic subtest tile4-smem-smem: SUCCESS (0.018s)
[290.298121] Starting dynamic subtest: tile64-smem-smem
[290.304625] Dynamic subtest tile64-smem-smem: SUCCESS (0.017s)
[290.306958] Subtest fast-copy: SUCCESS (0.094s)
[290.451110] [064/133] (734s left) gem_workarounds (basic-read-context)
[290.526085] Starting subtest: basic-read-context
[290.536345] Subtest basic-read-context: SUCCESS (0.009s)
[290.669409] [065/133] (734s left) kms_psr (psr-cursor-plane-onoff)
[290.762272] Starting subtest: psr-cursor-plane-onoff
[290.764634] Starting dynamic subtest: eDP-1
[292.515410] Dynamic subtest eDP-1: SUCCESS (1.753s)
[292.517823] Subtest psr-cursor-plane-onoff: SUCCESS (1.754s)
[293.349236] [066/133] (732s left) kms_psr (pr-basic)
[293.447057] Starting subtest: pr-basic
[293.451301] Subtest pr-basic: SKIP (0.000s)
[293.520353] [067/133] (732s left) syncobj_eventfd (binary-wait-before-signal)
[293.570436] Starting subtest: binary-wait-before-signal
[293.598776] Subtest binary-wait-before-signal: SUCCESS (0.030s)
[293.678232] [068/133] (731s left) i915_pm_rpm (system-suspend-execbuf)
[295.220741] Starting subtest: system-suspend-execbuf
[352.769094] Subtest system-suspend-execbuf: SUCCESS (42.838s)
[353.161291] [069/133] (672s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-pixel-formats)
[353.253621] Starting subtest: plane-scaler-with-clipping-clamping-pixel-formats
[353.255802] Starting dynamic subtest: pipe-A
[353.365277] Dynamic subtest pipe-A: SKIP (0.108s)
[353.375025] Starting dynamic subtest: pipe-B
[354.619744] Dynamic subtest pipe-B: SKIP (1.253s)
[354.629901] Starting dynamic subtest: pipe-C
[355.793227] Dynamic subtest pipe-C: SUCCESS (1.180s)
[355.803020] Starting dynamic subtest: pipe-D
[356.985232] Dynamic subtest pipe-D: SUCCESS (1.191s)
[356.987745] Subtest plane-scaler-with-clipping-clamping-pixel-formats: SUCCESS (3.735s)
[358.145915] [070/133] (667s left) syncobj_wait (invalid-signal-zero-handles)
[358.199917] Starting subtest: invalid-signal-zero-handles
[358.202354] Subtest invalid-signal-zero-handles: SUCCESS (0.000s)
[358.304076] [071/133] (667s left) kms_prop_blob (invalid-set-prop-any)
[358.373341] Starting subtest: invalid-set-prop-any
[358.375565] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[358.491451] [072/133] (667s left) kms_psr (psr-primary-render)
[358.574353] Starting subtest: psr-primary-render
[358.576537] Starting dynamic subtest: eDP-1
[360.328458] Dynamic subtest eDP-1: SUCCESS (1.755s)
[360.330789] Subtest psr-primary-render: SUCCESS (1.756s)
[361.158591] [073/133] (664s left) gem_softpin (evict-active)
[361.226688] Starting subtest: evict-active
[361.232765] Subtest evict-active: SUCCESS (0.006s)
[361.369678] [074/133] (664s left) tools_test (sysfs_l3_parity)
[361.428057] Starting subtest: sysfs_l3_parity
[361.499256] Subtest sysfs_l3_parity: SKIP (0.083s)
[361.567730] [075/133] (664s left) kms_ccs (crc-primary-basic-4-tiled-mtl-mc-ccs)
[361.674217] Starting subtest: crc-primary-basic-4-tiled-mtl-mc-ccs
[361.676105] Starting dynamic subtest: pipe-A-eDP-1
[363.877496] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.204s)
[363.886997] Starting dynamic subtest: pipe-B-eDP-1
[366.333727] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.455s)
[366.342786] Starting dynamic subtest: pipe-C-eDP-1
[368.483426] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.149s)
[368.493154] Starting dynamic subtest: pipe-D-eDP-1
[370.658902] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.175s)
[370.660784] Subtest crc-primary-basic-4-tiled-mtl-mc-ccs: SUCCESS (8.988s)
[371.858070] [076/133] (654s left) kms_frontbuffer_tracking (psr-rgb565-draw-pwrite)
[371.968351] Starting subtest: psr-rgb565-draw-pwrite
[378.933090] Subtest psr-rgb565-draw-pwrite: SUCCESS (6.967s)
[379.059785] [077/133] (646s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[379.154443] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu
[379.158652] Subtest fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[379.238425] [078/133] (646s left) gem_ccs (block-multicopy-inplace)
[379.301386] Starting subtest: block-multicopy-inplace
[379.305575] Subtest block-multicopy-inplace: SKIP (0.000s)
[379.414201] [079/133] (646s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-wc)
[379.509591] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-wc
[379.513769] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[379.606780] [080/133] (646s left) gem_wait (await)
[379.663070] Starting subtest: await
[379.665484] Starting dynamic subtest: all-engines
[380.771147] Dynamic subtest all-engines: SUCCESS (1.107s)
[380.790079] Starting dynamic subtest: rcs0
[381.830824] Dynamic subtest rcs0: SUCCESS (1.058s)
[381.849894] Starting dynamic subtest: bcs0
[382.892338] Dynamic subtest bcs0: SUCCESS (1.059s)
[382.911146] Starting dynamic subtest: vcs0
[383.947534] Dynamic subtest vcs0: SUCCESS (1.053s)
[383.965615] Starting dynamic subtest: vcs1
[385.002214] Dynamic subtest vcs1: SUCCESS (1.052s)
[385.019750] Starting dynamic subtest: vecs0
[386.056518] Dynamic subtest vecs0: SUCCESS (1.052s)
[386.075034] Starting dynamic subtest: ccs0
[387.114617] Dynamic subtest ccs0: SUCCESS (1.056s)
[387.117336] Subtest await: SUCCESS (7.452s)
[387.319260] [081/133] (638s left) kms_psr (fbc-psr-sprite-mmap-cpu)
[387.432381] Starting subtest: fbc-psr-sprite-mmap-cpu
[387.434692] Starting dynamic subtest: eDP-1
[387.439119] Dynamic subtest eDP-1: SKIP (0.000s)
[387.443154] Subtest fbc-psr-sprite-mmap-cpu: SKIP (0.003s)
[387.529635] [082/133] (638s left) syncobj_wait (invalid-reset-one-illegal-handle)
[387.592728] Starting subtest: invalid-reset-one-illegal-handle
[387.595093] Subtest invalid-reset-one-illegal-handle: SUCCESS (0.000s)
[387.709306] [083/133] (638s left) gem_ctx_param (get-priority-new-ctx)
[387.766956] Starting subtest: get-priority-new-ctx
[387.769215] Subtest get-priority-new-ctx: SUCCESS (0.001s)
[387.863462] [084/133] (638s left) gem_userptr_blits (coherency-unsync)
[387.924024] Subtest coherency-unsync: SKIP (0.000s)
[388.058540] [085/133] (638s left) perf (unprivileged-single-ctx-counters)
[388.143201] Starting subtest: unprivileged-single-ctx-counters
[388.147851] Subtest unprivileged-single-ctx-counters: SKIP (0.000s)
[388.188136] [086/133] (638s left) kms_frontbuffer_tracking (psr-2p-scndscrn-shrfb-msflip-blt)
[388.274098] Starting subtest: psr-2p-scndscrn-shrfb-msflip-blt
[388.277464] Subtest psr-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[388.372412] [087/133] (638s left) kms_plane_scaling (planes-downscale-factor-0-5-upscale-20x20)
[388.472198] Starting subtest: planes-downscale-factor-0-5-upscale-20x20
[388.474158] Starting dynamic subtest: pipe-A
[388.494930] Dynamic subtest pipe-A: SKIP (0.019s)
[388.504351] Starting dynamic subtest: pipe-B
[388.526091] Dynamic subtest pipe-B: SKIP (0.032s)
[388.535965] Starting dynamic subtest: pipe-C
[388.558729] Dynamic subtest pipe-C: SKIP (0.032s)
[388.567757] Starting dynamic subtest: pipe-D
[388.593106] Dynamic subtest pipe-D: SKIP (0.033s)
[388.596912] Subtest planes-downscale-factor-0-5-upscale-20x20: SKIP (0.119s)
[388.700471] [088/133] (637s left) kms_atomic_transition (modeset-transition)
[388.806735] Starting subtest: modeset-transition
[388.808626] Starting dynamic subtest: 1x-outputs
[399.707195] Dynamic subtest 1x-outputs: SUCCESS (10.898s)
[399.709971] Subtest modeset-transition: SUCCESS (10.900s)
[400.567266] [089/133] (625s left) gem_mmap_wc (write-prefaulted)
[400.636502] Subtest write-prefaulted: SKIP (0.000s)
[400.697571] [090/133] (625s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[400.797560] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-cpu
[400.801460] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[400.894936] [091/133] (625s left) kms_getfb (getfb-handle-protection)
[400.986546] Starting subtest: getfb-handle-protection
[401.010731] Subtest getfb-handle-protection: SUCCESS (0.025s)
[401.128575] [092/133] (625s left) kms_addfb_basic (basic-y-tiled-legacy)
[401.183642] Starting subtest: basic-y-tiled-legacy
[401.188164] Subtest basic-y-tiled-legacy: SKIP (0.000s)
[401.310738] [093/133] (625s left) i915_query (query-garbage)
[401.360966] Starting subtest: query-garbage
[401.363112] Subtest query-garbage: SUCCESS (0.000s)
[401.453700] [094/133] (625s left) syncobj_timeline (invalid-query-bad-pad)
[401.513427] Starting subtest: invalid-query-bad-pad
[401.515443] Subtest invalid-query-bad-pad: SUCCESS (0.000s)
[401.605509] [095/133] (625s left) kms_color (ctm-signed)
[401.695169] Starting subtest: ctm-signed
[401.696981] Starting dynamic subtest: pipe-A-eDP-1
[403.813484] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.113s)
[403.823019] Starting dynamic subtest: pipe-B-eDP-1
[406.604568] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.790s)
[406.614759] Starting dynamic subtest: pipe-C-eDP-1
[409.349707] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.747s)
[409.362602] Starting dynamic subtest: pipe-D-eDP-1
[412.057383] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.706s)
[412.059617] Subtest ctm-signed: SUCCESS (10.361s)
[412.885088] [096/133] (613s left) i915_query (engine-info-invalid)
[412.952445] Starting subtest: engine-info-invalid
[412.954832] Subtest engine-info-invalid: SUCCESS (0.000s)
[413.042443] [097/133] (613s left) gem_mmap_wc (write-read)
[413.096170] Subtest write-read: SKIP (0.000s)
[413.154395] [098/133] (613s left) gem_partial_pwrite_pread (reads-uncached)
[413.219290] Subtest reads-uncached: SKIP (0.000s)
[413.309491] [099/133] (613s left) gem_eio (in-flight-1us)
[413.514076] Starting subtest: in-flight-1us
[422.184791] Subtest in-flight-1us: SUCCESS (8.669s)
[422.306822] [100/133] (604s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-64bpp-4tile-upscaling)
[422.393391] Starting subtest: flip-32bpp-4tile-to-64bpp-4tile-upscaling
[422.395784] Starting dynamic subtest: pipe-A-default-mode
[425.139200] Dynamic subtest pipe-A-default-mode: SUCCESS (2.744s)
[425.141356] Subtest flip-32bpp-4tile-to-64bpp-4tile-upscaling: SUCCESS (2.746s)
[425.941614] [101/133] (601s left) gem_exec_fence (submit67)
[426.123499] Starting subtest: submit67
[426.127723] Subtest submit67: SKIP (0.000s)
[427.713754] [102/133] (599s left) kms_legacy_colorkey (basic)
[427.807196] Starting subtest: basic
[427.809013] Starting dynamic subtest: pipe-A
[427.851449] Dynamic subtest pipe-A: SUCCESS (0.045s)
[427.867017] Starting dynamic subtest: pipe-B
[427.869371] Dynamic subtest pipe-B: SUCCESS (0.001s)
[427.878296] Starting dynamic subtest: pipe-C
[427.880202] Dynamic subtest pipe-C: SUCCESS (0.000s)
[427.888987] Starting dynamic subtest: pipe-D
[427.890970] Dynamic subtest pipe-D: SUCCESS (0.000s)
[427.892920] Subtest basic: SUCCESS (0.051s)
[427.944444] [103/133] (599s left) kms_big_fb (x-tiled-64bpp-rotate-180)
[428.054098] Starting subtest: x-tiled-64bpp-rotate-180
[429.624147] Subtest x-tiled-64bpp-rotate-180: SUCCESS (1.570s)
[429.801380] [104/133] (597s left) gen9_exec_parse (batch-invalid-length)
[429.878651] Subtest batch-invalid-length: SKIP (0.000s)
[429.943654] [105/133] (597s left) kms_cursor_edge_walk (256x256-top-edge)
[430.045609] Starting subtest: 256x256-top-edge
[430.047624] Starting dynamic subtest: pipe-A-eDP-1
[433.484374] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.438s)
[433.493921] Starting dynamic subtest: pipe-D-eDP-1
[436.674871] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.191s)
[436.677167] Subtest 256x256-top-edge: SUCCESS (6.631s)
[437.540601] [106/133] (589s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-pwrite)
[437.655275] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-pwrite
[444.691441] Subtest fbc-1p-offscreen-pri-shrfb-draw-pwrite: SUCCESS (7.039s)
[445.867573] [107/133] (581s left) kms_cursor_legacy (2x-long-nonblocking-modeset-vs-cursor-atomic)
[445.996411] Starting subtest: 2x-long-nonblocking-modeset-vs-cursor-atomic
[446.001007] Subtest 2x-long-nonblocking-modeset-vs-cursor-atomic: SKIP (0.001s)
[446.144382] [108/133] (581s left) gem_ctx_sseu (mmap-args)
[446.203754] Subtest mmap-args: SKIP (0.000s)
[446.266097] [109/133] (581s left) kms_cursor_legacy (cursorb-vs-flipb-varying-size)
[446.390929] Starting subtest: cursorB-vs-flipB-varying-size
[446.395212] Subtest cursorB-vs-flipB-varying-size: SKIP (0.001s)
[446.535455] [110/133] (580s left) kms_big_fb (yf-tiled-32bpp-rotate-270)
[446.648585] Starting subtest: yf-tiled-32bpp-rotate-270
[446.652723] Subtest yf-tiled-32bpp-rotate-270: SKIP (0.000s)
[446.754571] [111/133] (580s left) kms_atomic_interruptible (legacy-cursor)
[446.844066] Starting subtest: legacy-cursor
[446.845963] Starting dynamic subtest: pipe-A-eDP-1
[454.209208] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.360s)
[454.211821] Subtest legacy-cursor: SUCCESS (7.362s)
[455.043440] [112/133] (572s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc)
[455.155111] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc
[455.159573] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[455.265806] [113/133] (572s left) api_intel_allocator (simple-allocator)
[455.320339] Starting subtest: simple-allocator
[455.322591] Starting dynamic subtest: basic
[455.324904] Dynamic subtest basic: SUCCESS (0.004s)
[455.334070] Starting dynamic subtest: parallel-one
[455.530778] Dynamic subtest parallel-one: SUCCESS (0.206s)
[455.540682] Starting dynamic subtest: print
[455.542969] Dynamic subtest print: SUCCESS (0.000s)
[455.552233] Starting dynamic subtest: reuse
[455.554169] Dynamic subtest reuse: SUCCESS (0.000s)
[455.562930] Starting dynamic subtest: reserve
[455.564919] Dynamic subtest reserve: SUCCESS (0.000s)
[455.573673] Starting dynamic subtest: fork-reopen-allocator
[455.575652] Dynamic subtest fork-reopen-allocator: SUCCESS (0.013s)
[455.577624] Subtest simple-allocator: SUCCESS (0.228s)
[455.669654] [114/133] (571s left) kms_psr (fbc-pr-suspend)
[455.773227] Starting subtest: fbc-pr-suspend
[455.777295] Subtest fbc-pr-suspend: SKIP (0.000s)
[455.845921] [115/133] (571s left) kms_ccs (bad-aux-stride-y-tiled-gen12-rc-ccs)
[455.938106] Starting subtest: bad-aux-stride-y-tiled-gen12-rc-ccs
[455.940465] Starting dynamic subtest: pipe-A-eDP-1
[455.963553] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[455.975517] Starting dynamic subtest: pipe-B-eDP-1
[455.997154] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[456.005967] Starting dynamic subtest: pipe-C-eDP-1
[456.027332] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[456.036180] Starting dynamic subtest: pipe-D-eDP-1
[456.057847] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[456.062329] Subtest bad-aux-stride-y-tiled-gen12-rc-ccs: SKIP (0.005s)
[458.140698] [116/133] (569s left) gem_exec_schedule (implicit-boths)
[458.205730] Starting subtest: implicit-boths
[458.208068] Starting dynamic subtest: rcs0
[458.308983] Dynamic subtest rcs0: SUCCESS (0.113s)
[458.322134] Starting dynamic subtest: vcs0
[458.404404] Dynamic subtest vcs0: SUCCESS (0.094s)
[458.416594] Starting dynamic subtest: vcs1
[458.487875] Dynamic subtest vcs1: SUCCESS (0.082s)
[458.500804] Starting dynamic subtest: vecs0
[458.574468] Dynamic subtest vecs0: SUCCESS (0.086s)
[458.586914] Starting dynamic subtest: ccs0
[458.683459] Dynamic subtest ccs0: SUCCESS (0.108s)
[458.686337] Subtest implicit-boths: SUCCESS (0.489s)
[459.684512] [117/133] (568s left) kms_ccs (random-ccs-data-y-tiled-gen12-rc-ccs)
[459.785280] Starting subtest: random-ccs-data-y-tiled-gen12-rc-ccs
[459.787468] Starting dynamic subtest: pipe-A-eDP-1
[459.812078] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[459.822610] Starting dynamic subtest: pipe-B-eDP-1
[459.845837] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[459.854845] Starting dynamic subtest: pipe-C-eDP-1
[459.878531] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[459.888838] Starting dynamic subtest: pipe-D-eDP-1
[459.912339] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[459.916691] Subtest random-ccs-data-y-tiled-gen12-rc-ccs: SKIP (0.005s)
[462.038329] [118/133] (565s left) gem_exec_flush (basic-wb-ro-before-default)
[462.106567] Starting subtest: basic-wb-ro-before-default
[467.600915] Subtest basic-wb-ro-before-default: SUCCESS (5.495s)
[467.795589] [119/133] (560s left) sysfs_preempt_timeout (invalid)
[467.867925] Starting subtest: invalid
[467.870333] Starting dynamic subtest: vecs0
[467.872927] Dynamic subtest vecs0: SUCCESS (0.000s)
[467.884551] Starting dynamic subtest: ccs0
[467.886446] Dynamic subtest ccs0: SUCCESS (0.000s)
[467.900479] Starting dynamic subtest: vcs0
[467.902674] Dynamic subtest vcs0: SUCCESS (0.000s)
[467.912248] Starting dynamic subtest: bcs0
[467.914113] Dynamic subtest bcs0: SUCCESS (0.000s)
[467.923520] Starting dynamic subtest: rcs0
[467.925633] Dynamic subtest rcs0: SUCCESS (0.000s)
[467.934774] Starting dynamic subtest: vcs1
[467.936682] Dynamic subtest vcs1: SUCCESS (0.000s)
[467.938598] Subtest invalid: SUCCESS (0.006s)
[467.977369] [120/133] (559s left) gem_exec_balancer (parallel-bb-first)
[468.252177] Starting subtest: parallel-bb-first
[469.194451] Subtest parallel-bb-first: SUCCESS (0.945s)
[469.687210] [121/133] (558s left) kms_ccs (random-ccs-data-4-tiled-bmg-ccs)
[469.777840] Starting subtest: random-ccs-data-4-tiled-bmg-ccs
[469.782163] Subtest random-ccs-data-4-tiled-bmg-ccs: SKIP (0.000s)
[470.932545] [122/133] (557s left) i915_hangman (hangcheck-unterminated)
[471.000486] Starting subtest: hangcheck-unterminated
[482.594177] Subtest hangcheck-unterminated: SUCCESS (11.593s)
[482.719398] [123/133] (545s left) syncobj_timeline (single-wait-all-for-submit-signaled)
[482.779394] Starting subtest: single-wait-all-for-submit-signaled
[482.782283] Subtest single-wait-all-for-submit-signaled: SUCCESS (0.000s)
[482.899690] [124/133] (545s left) kms_draw_crc (draw-method-blt)
[482.993416] Starting subtest: draw-method-blt
[482.995257] Starting dynamic subtest: xrgb8888-untiled
[483.246845] Dynamic subtest xrgb8888-untiled: SUCCESS (0.255s)
[483.259549] Starting dynamic subtest: rgb565-untiled
[483.493497] Dynamic subtest rgb565-untiled: SUCCESS (0.242s)
[483.503364] Starting dynamic subtest: xrgb2101010-untiled
[483.713181] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.220s)
[483.723139] Starting dynamic subtest: xbgr16161616f-untiled
[483.935215] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.221s)
[483.945483] Starting dynamic subtest: xrgb8888-xtiled
[484.047800] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.110s)
[484.057169] Starting dynamic subtest: rgb565-xtiled
[484.168425] Dynamic subtest rgb565-xtiled: SUCCESS (0.122s)
[484.178562] Starting dynamic subtest: xrgb2101010-xtiled
[484.279624] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.110s)
[484.291234] Starting dynamic subtest: xbgr16161616f-xtiled
[484.390954] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.110s)
[484.400989] Starting dynamic subtest: xrgb8888-4tiled
[484.513031] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.121s)
[484.527438] Starting dynamic subtest: rgb565-4tiled
[484.636206] Dynamic subtest rgb565-4tiled: SUCCESS (0.121s)
[484.645369] Starting dynamic subtest: xrgb2101010-4tiled
[484.746382] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.110s)
[484.758051] Starting dynamic subtest: xbgr16161616f-4tiled
[484.869106] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.120s)
[484.871097] Subtest draw-method-blt: SUCCESS (1.878s)
[485.000069] [125/133] (543s left) gem_softpin (32b-excludes-last-page)
[485.058707] Starting subtest: 32b-excludes-last-page
[485.063564] Subtest 32b-excludes-last-page: SUCCESS (0.004s)
[485.192328] [126/133] (542s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[485.287979] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-mmap-gtt
[485.291326] Subtest fbc-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[485.391538] [127/133] (542s left) prime_vgem (shrink)
[485.452996] Starting subtest: shrink
[485.469466] Subtest shrink: SUCCESS (0.017s)
[485.582116] [128/133] (542s left) core_setmaster (master-drop-set-shared-fd)
[485.663837] Starting subtest: master-drop-set-shared-fd
[485.685639] Subtest master-drop-set-shared-fd: SUCCESS (0.022s)
[485.725365] [129/133] (542s left) gem_exec_fence (basic-wait)
[485.899034] Starting subtest: basic-wait
[485.901395] Starting dynamic subtest: rcs0
[485.992011] Dynamic subtest rcs0: SUCCESS (0.092s)
[486.002880] Starting dynamic subtest: bcs0
[486.060283] Dynamic subtest bcs0: SUCCESS (0.066s)
[486.070348] Starting dynamic subtest: vcs0
[486.124487] Dynamic subtest vcs0: SUCCESS (0.063s)
[486.139501] Starting dynamic subtest: vcs1
[486.190311] Dynamic subtest vcs1: SUCCESS (0.063s)
[486.202083] Starting dynamic subtest: vecs0
[486.241863] Dynamic subtest vecs0: SUCCESS (0.050s)
[486.252693] Starting dynamic subtest: ccs0
[486.302053] Dynamic subtest ccs0: SUCCESS (0.059s)
[486.305185] Subtest basic-wait: SUCCESS (0.403s)
[487.709049] [130/133] (540s left) gem_eio (throttle)
[487.953314] Starting subtest: throttle
[488.288701] Subtest throttle: SUCCESS (0.334s)
[488.409091] [131/133] (539s left) gem_lmem_swapping (parallel-random-verify)
[488.455477] Subtest parallel-random-verify: SKIP (0.000s)
[488.508591] [132/133] (539s left) kms_ccs (crc-primary-rotation-180-4-tiled-lnl-ccs)
[488.610610] Starting subtest: crc-primary-rotation-180-4-tiled-lnl-ccs
[488.615247] Subtest crc-primary-rotation-180-4-tiled-lnl-ccs: SKIP (0.000s)
[489.749494] [133/133] (538s left) gem_close_race (basic-process)
[489.820467] Starting subtest: basic-process
[489.870777] Subtest basic-process: SUCCESS (0.053s)
[490.068283] Closing watchdogs
results: parsing output: 0/ for test: gem_ctx_isolation
results: parsing output: 1/ for test: kms_pipe_stress
results: parsing output: 2/ for test: kms_frontbuffer_tracking
results: parsing output: 3/ for test: kms_flip
results: parsing output: 4/ for test: kms_cursor_crc
results: parsing output: 5/ for test: kms_chamelium_hpd
results: parsing output: 6/ for test: kms_frontbuffer_tracking
results: parsing output: 7/ for test: gem_partial_pwrite_pread
results: parsing output: 8/ for test: kms_frontbuffer_tracking
results: parsing output: 9/ for test: gem_pxp
results: parsing output: 10/ for test: kms_psr2_su
results: parsing output: 11/ for test: kms_busy
results: parsing output: 12/ for test: kms_dp_linktrain_fallback
results: parsing output: 13/ for test: kms_flip_scaled_crc
results: parsing output: 14/ for test: kms_frontbuffer_tracking
results: parsing output: 15/ for test: kms_content_protection
results: parsing output: 16/ for test: perf
results: parsing output: 17/ for test: kms_flip
results: parsing output: 18/ for test: kms_force_connector_basic
results: parsing output: 19/ for test: kms_frontbuffer_tracking
results: parsing output: 20/ for test: prime_vgem
results: parsing output: 21/ for test: gem_ctx_persistence
results: parsing output: 22/ for test: kms_big_fb
results: parsing output: 23/ for test: kms_joiner
results: parsing output: 24/ for test: kms_frontbuffer_tracking
results: parsing output: 25/ for test: syncobj_timeline
results: parsing output: 26/ for test: kms_prime
results: parsing output: 27/ for test: gem_exec_params
results: parsing output: 28/ for test: kms_cursor_legacy
results: parsing output: 29/ for test: gem_exec_schedule
results: parsing output: 30/ for test: api_intel_bb
results: parsing output: 31/ for test: kms_chamelium_frames
results: parsing output: 32/ for test: kms_plane
results: parsing output: 33/ for test: gem_mmap_gtt
results: parsing output: 34/ for test: kms_frontbuffer_tracking
results: parsing output: 35/ for test: kms_vblank
results: parsing output: 36/ for test: syncobj_wait
results: parsing output: 37/ for test: prime_mmap_coherency
results: parsing output: 38/ for test: kms_pm_dc
results: parsing output: 39/ for test: kms_big_fb
results: parsing output: 40/ for test: gen9_exec_parse
results: parsing output: 41/ for test: kms_cursor_crc
results: parsing output: 42/ for test: gem_close_race
results: parsing output: 43/ for test: kms_addfb_basic
results: parsing output: 44/ for test: kms_big_fb
results: parsing output: 45/ for test: gem_spin_batch
results: parsing output: 46/ for test: kms_frontbuffer_tracking
results: parsing output: 47/ for test: kms_big_fb
results: parsing output: 48/ for test: kms_frontbuffer_tracking
results: parsing output: 49/ for test: gem_exec_whisper
results: parsing output: 50/ for test: perf_pmu
results: parsing output: 51/ for test: kms_psr2_sf
results: parsing output: 52/ for test: kms_frontbuffer_tracking
results: parsing output: 53/ for test: prime_self_import
results: parsing output: 54/ for test: gem_exec_schedule
results: parsing output: 55/ for test: kms_atomic_transition
results: parsing output: 56/ for test: gem_mmap_gtt
results: parsing output: 57/ for test: syncobj_timeline
results: parsing output: 58/ for test: vgem_basic
results: parsing output: 59/ for test: kms_vblank
results: parsing output: 60/ for test: vgem_slow
results: parsing output: 61/ for test: kms_plane
results: parsing output: 62/ for test: gem_exercise_blt
results: parsing output: 63/ for test: gem_workarounds
results: parsing output: 64/ for test: kms_psr
results: parsing output: 65/ for test: kms_psr
results: parsing output: 66/ for test: syncobj_eventfd
results: parsing output: 67/ for test: i915_pm_rpm
results: parsing output: 68/ for test: kms_plane_scaling
results: parsing output: 69/ for test: syncobj_wait
results: parsing output: 70/ for test: kms_prop_blob
results: parsing output: 71/ for test: kms_psr
results: parsing output: 72/ for test: gem_softpin
results: parsing output: 73/ for test: tools_test
results: parsing output: 74/ for test: kms_ccs
results: parsing output: 75/ for test: kms_frontbuffer_tracking
results: parsing output: 76/ for test: kms_frontbuffer_tracking
results: parsing output: 77/ for test: gem_ccs
results: parsing output: 78/ for test: kms_frontbuffer_tracking
results: parsing output: 79/ for test: gem_wait
results: parsing output: 80/ for test: kms_psr
results: parsing output: 81/ for test: syncobj_wait
results: parsing output: 82/ for test: gem_ctx_param
results: parsing output: 83/ for test: gem_userptr_blits
results: parsing output: 84/ for test: perf
results: parsing output: 85/ for test: kms_frontbuffer_tracking
results: parsing output: 86/ for test: kms_plane_scaling
results: parsing output: 87/ for test: kms_atomic_transition
results: parsing output: 88/ for test: gem_mmap_wc
results: parsing output: 89/ for test: kms_frontbuffer_tracking
results: parsing output: 90/ for test: kms_getfb
results: parsing output: 91/ for test: kms_addfb_basic
results: parsing output: 92/ for test: i915_query
results: parsing output: 93/ for test: syncobj_timeline
results: parsing output: 94/ for test: kms_color
results: parsing output: 95/ for test: i915_query
results: parsing output: 96/ for test: gem_mmap_wc
results: parsing output: 97/ for test: gem_partial_pwrite_pread
results: parsing output: 98/ for test: gem_eio
results: parsing output: 99/ for test: kms_flip_scaled_crc
results: parsing output: 100/ for test: gem_exec_fence
results: parsing output: 101/ for test: kms_legacy_colorkey
results: parsing output: 102/ for test: kms_big_fb
results: parsing output: 103/ for test: gen9_exec_parse
results: parsing output: 104/ for test: kms_cursor_edge_walk
results: parsing output: 105/ for test: kms_frontbuffer_tracking
results: parsing output: 106/ for test: kms_cursor_legacy
results: parsing output: 107/ for test: gem_ctx_sseu
results: parsing output: 108/ for test: kms_cursor_legacy
results: parsing output: 109/ for test: kms_big_fb
results: parsing output: 110/ for test: kms_atomic_interruptible
results: parsing output: 111/ for test: kms_frontbuffer_tracking
results: parsing output: 112/ for test: api_intel_allocator
results: parsing output: 113/ for test: kms_psr
results: parsing output: 114/ for test: kms_ccs
results: parsing output: 115/ for test: gem_exec_schedule
results: parsing output: 116/ for test: kms_ccs
results: parsing output: 117/ for test: gem_exec_flush
results: parsing output: 118/ for test: sysfs_preempt_timeout
results: parsing output: 119/ for test: gem_exec_balancer
results: parsing output: 120/ for test: kms_ccs
results: parsing output: 121/ for test: i915_hangman
results: parsing output: 122/ for test: syncobj_timeline
results: parsing output: 123/ for test: kms_draw_crc
results: parsing output: 124/ for test: gem_softpin
results: parsing output: 125/ for test: kms_frontbuffer_tracking
results: parsing output: 126/ for test: prime_vgem
results: parsing output: 127/ for test: core_setmaster
results: parsing output: 128/ for test: gem_exec_fence
results: parsing output: 129/ for test: gem_eio
results: parsing output: 130/ for test: gem_lmem_swapping
results: parsing output: 131/ for test: kms_ccs
results: parsing output: 132/ for test: gem_close_race
Done.