[47.026476] Initializing watchdogs
[47.026925]   /dev/watchdog0
[47.057561] [001/141] (960s left) i915_pm_rps (waitboost)
[47.529810] Starting subtest: waitboost
[47.619476] Subtest waitboost: FAIL (0.069s)
[47.713824] [002/141] (959s left) kms_cursor_crc (cursor-offscreen-128x128)
[48.624130] Starting subtest: cursor-offscreen-128x128
[48.708469] Starting dynamic subtest: pipe-A-eDP-1
[51.834546] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.126s)
[52.012929] Starting dynamic subtest: pipe-D-eDP-1
[56.140302] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.157s)
[56.142639] Subtest cursor-offscreen-128x128: SUCCESS (7.516s)
[57.253723] [003/141] (950s left) syncobj_timeline (device-submit-unordered)
[57.638046] Starting subtest: device-submit-unordered
[57.640820] Subtest device-submit-unordered: SUCCESS (0.002s)
[57.770732] [004/141] (949s left) kms_ccs (pipe-a-bad-rotation-90-4_tiled_dg2_rc_ccs)
[58.589542] Starting subtest: pipe-A-bad-rotation-90-4_tiled_dg2_rc_ccs
[58.592698] Subtest pipe-A-bad-rotation-90-4_tiled_dg2_rc_ccs: SKIP (0.000s)
[59.715205] [005/141] (948s left) syncobj_timeline (single-wait-all-available-submitted)
[60.043573] Starting subtest: single-wait-all-available-submitted
[60.046198] Subtest single-wait-all-available-submitted: SUCCESS (0.002s)
[60.172381] [006/141] (947s left) kms_ccs (pipe-a-bad-aux-stride-y_tiled_ccs)
[61.163226] Starting subtest: pipe-A-bad-aux-stride-y_tiled_ccs
[61.167184] Subtest pipe-A-bad-aux-stride-y_tiled_ccs: SKIP (0.000s)
[62.438233] [007/141] (945s left) kms_cursor_legacy (2x-flip-vs-cursor-legacy)
[63.014904] Starting subtest: 2x-flip-vs-cursor-legacy
[63.030688] Subtest 2x-flip-vs-cursor-legacy: SKIP (0.016s)
[63.340966] [008/141] (944s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-onoff)
[63.822231] Starting subtest: psr-2p-primscrn-cur-indfb-onoff
[63.826641] Subtest psr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[63.971148] [009/141] (944s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-cpu)
[64.461208] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-cpu
[79.587076] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-cpu: SUCCESS (15.131s)
[79.835288] [010/141] (928s left) kms_chamelium_hpd (vga-hpd-fast)
[80.721791] Subtest vga-hpd-fast: SKIP (0.000s)
[81.582334] [011/141] (926s left) kms_frontbuffer_tracking (fbc-rgb565-draw-pwrite)
[82.081176] Starting subtest: fbc-rgb565-draw-pwrite
[96.520943] Subtest fbc-rgb565-draw-pwrite: SUCCESS (14.441s)
[96.825615] [012/141] (911s left) kms_concurrent (pipe-a)
[97.309600] Starting subtest: pipe-A
[97.317649] Starting dynamic subtest: eDP-1
[98.371715] Dynamic subtest eDP-1: SUCCESS (1.052s)
[98.374179] Subtest pipe-A: SUCCESS (1.061s)
[99.307651] [013/141] (909s left) kms_cursor_legacy (cursora-vs-flipb-atomic)
[99.872114] Starting subtest: cursorA-vs-flipB-atomic
[99.881130] Subtest cursorA-vs-flipB-atomic: SKIP (0.008s)
[100.184552] [014/141] (908s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[100.684621] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu
[100.689143] Subtest fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[100.842165] [015/141] (907s left) kms_vblank (pipe-a-ts-continuation-idle-hang)
[101.311132] Starting subtest: pipe-A-ts-continuation-idle-hang
[115.602567] Subtest pipe-A-ts-continuation-idle-hang: SUCCESS (14.292s)
[116.576421] [016/141] (891s left) kms_ccs (pipe-c-crc-primary-rotation-180-4_tiled_dg2_rc_ccs)
[117.994372] Starting subtest: pipe-C-crc-primary-rotation-180-4_tiled_dg2_rc_ccs
[117.999122] Subtest pipe-C-crc-primary-rotation-180-4_tiled_dg2_rc_ccs: SKIP (0.002s)
[118.826102] [017/141] (889s left) kms_plane (plane-position-covered)
[119.305137] Starting subtest: plane-position-covered
[119.310180] Starting dynamic subtest: pipe-A-planes
[127.364735] Dynamic subtest pipe-A-planes: SUCCESS (8.055s)
[127.400664] Starting dynamic subtest: pipe-B-planes
[136.089699] Dynamic subtest pipe-B-planes: SUCCESS (8.716s)
[136.092278] Subtest plane-position-covered: SUCCESS (16.789s)
[136.939712] [018/141] (871s left) kms_big_fb (yf-tiled-64bpp-rotate-180)
[137.489580] Starting subtest: yf-tiled-64bpp-rotate-180
[137.494851] Subtest yf-tiled-64bpp-rotate-180: SKIP (0.000s)
[137.627765] [019/141] (871s left) prime_busy (after-wait)
[138.016547] Starting subtest: after-wait
[138.047958] Starting dynamic subtest: rcs0
[138.073848] Dynamic subtest rcs0: SUCCESS (0.026s)
[138.108695] Starting dynamic subtest: bcs0
[138.120751] Dynamic subtest bcs0: SUCCESS (0.044s)
[138.154819] Starting dynamic subtest: vcs0
[138.167822] Dynamic subtest vcs0: SUCCESS (0.044s)
[138.198191] Starting dynamic subtest: vcs1
[138.204394] Dynamic subtest vcs1: SUCCESS (0.031s)
[138.234060] Starting dynamic subtest: vecs0
[138.241559] Dynamic subtest vecs0: SUCCESS (0.035s)
[138.272184] Starting dynamic subtest: ccs0
[138.274516] Dynamic subtest ccs0: SUCCESS (0.025s)
[138.397279] Subtest after-wait: SUCCESS (0.378s)
[138.529465] [020/141] (870s left) api_intel_bb (blit-reloc-keep-cache)
[138.902625] Starting subtest: blit-reloc-keep-cache
[138.907371] Subtest blit-reloc-keep-cache: SKIP (0.003s)
[139.063167] [021/141] (869s left) kms_plane_scaling (planes-downscale-factor-0-5)
[139.544925] Starting subtest: planes-downscale-factor-0-5
[139.552320] Starting dynamic subtest: pipe-A-eDP-1
[139.603661] Dynamic subtest pipe-A-eDP-1: SKIP (0.045s)
[139.638218] Starting dynamic subtest: pipe-B-eDP-1
[139.763524] Dynamic subtest pipe-B-eDP-1: SKIP (0.151s)
[139.797684] Starting dynamic subtest: pipe-C-eDP-1
[139.918349] Dynamic subtest pipe-C-eDP-1: SKIP (0.149s)
[139.954229] Starting dynamic subtest: pipe-D-eDP-1
[140.080795] Dynamic subtest pipe-D-eDP-1: SKIP (0.151s)
[140.085713] Subtest planes-downscale-factor-0-5: SKIP (0.538s)
[140.234592] [022/141] (868s left) kms_frontbuffer_tracking (fbcpsr-2p-indfb-fliptrack-mmap-gtt)
[140.732699] Starting subtest: fbcpsr-2p-indfb-fliptrack-mmap-gtt
[140.737150] Subtest fbcpsr-2p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[140.891846] [023/141] (868s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-pwrite)
[141.393423] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-pwrite
[141.398242] Subtest fbc-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[141.557503] [024/141] (867s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[142.052658] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt
[142.057677] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[142.217231] [025/141] (866s left) kms_frontbuffer_tracking (fbc-1p-indfb-fliptrack-mmap-gtt)
[142.716591] Starting subtest: fbc-1p-indfb-fliptrack-mmap-gtt
[142.721690] Subtest fbc-1p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[142.861654] [026/141] (866s left) vc4/vc4_tiling (set-bad-flags)
[143.327612] Subtest set-bad-flags: SKIP (0.000s)
[143.441702] [027/141] (865s left) kms_invalid_mode (bad-hsync-end)
[143.919120] Starting subtest: bad-hsync-end
[143.925576] Starting dynamic subtest: eDP-1-pipe-A
[144.339190] Dynamic subtest eDP-1-pipe-A: SUCCESS (0.413s)
[144.375387] Starting dynamic subtest: eDP-1-pipe-B
[144.390431] Dynamic subtest eDP-1-pipe-B: SUCCESS (0.041s)
[144.425211] Starting dynamic subtest: eDP-1-pipe-C
[144.439721] Dynamic subtest eDP-1-pipe-C: SUCCESS (0.040s)
[144.471589] Starting dynamic subtest: eDP-1-pipe-D
[144.486244] Dynamic subtest eDP-1-pipe-D: SUCCESS (0.041s)
[144.488981] Subtest bad-hsync-end: SUCCESS (0.569s)
[145.208160] [028/141] (864s left) kms_flip (absolute-wf_vblank)
[145.598562] Starting subtest: absolute-wf_vblank
[145.626503] Starting dynamic subtest: A-eDP1
[154.937560] Dynamic subtest A-eDP1: SUCCESS (9.311s)
[154.967411] Starting dynamic subtest: B-eDP1
[164.090592] Dynamic subtest B-eDP1: SUCCESS (9.149s)
[164.135250] Starting dynamic subtest: C-eDP1
[173.239713] Dynamic subtest C-eDP1: SUCCESS (9.145s)
[173.268699] Starting dynamic subtest: D-eDP1
[182.378647] Dynamic subtest D-eDP1: SUCCESS (9.133s)
[182.404642] Subtest absolute-wf_vblank: SUCCESS (36.808s)
[183.998131] [029/141] (825s left) kms_ccs (pipe-d-crc-primary-rotation-180-4_tiled_mtl_rc_ccs)
[185.700973] Starting subtest: pipe-D-crc-primary-rotation-180-4_tiled_mtl_rc_ccs
[186.970231] Subtest pipe-D-crc-primary-rotation-180-4_tiled_mtl_rc_ccs: SUCCESS (1.269s)
[188.382226] [030/141] (821s left) kms_universal_plane (universal-plane-pipe-d-sanity)
[188.868155] Starting subtest: universal-plane-pipe-D-sanity
[191.055260] Subtest universal-plane-pipe-D-sanity: SUCCESS (2.188s)
[191.988834] [031/141] (817s left) kms_ccs (pipe-a-crc-primary-basic-yf_tiled_ccs)
[192.903308] Starting subtest: pipe-A-crc-primary-basic-yf_tiled_ccs
[192.907820] Subtest pipe-A-crc-primary-basic-yf_tiled_ccs: SKIP (0.002s)
[194.343702] [032/141] (815s left) gem_exec_reloc (basic-write-cpu-active)
[194.739565] Subtest basic-write-cpu-active: SKIP (0.000s)
[194.865429] [033/141] (814s left) gem_exec_reloc (basic-wc-active)
[195.265676] Subtest basic-wc-active: SKIP (0.000s)
[195.400404] [034/141] (814s left) gem_create (create-massive)
[195.775841] Starting subtest: create-massive
[195.778303] Subtest create-massive: SUCCESS (0.000s)
[196.064548] [035/141] (813s left) gem_ctx_shared (exec-shared-gtt)
[196.488994] Starting subtest: exec-shared-gtt
[196.490760] Starting dynamic subtest: rcs0
[196.528540] Dynamic subtest rcs0: SUCCESS (0.038s)
[196.573645] Starting dynamic subtest: bcs0
[196.576199] Dynamic subtest bcs0: SUCCESS (0.027s)
[196.614411] Starting dynamic subtest: vcs0
[196.616848] Dynamic subtest vcs0: SUCCESS (0.028s)
[196.649120] Starting dynamic subtest: vcs1
[196.651574] Dynamic subtest vcs1: SUCCESS (0.020s)
[196.689659] Starting dynamic subtest: vecs0
[196.692181] Dynamic subtest vecs0: SUCCESS (0.020s)
[196.724663] Starting dynamic subtest: ccs0
[196.727202] Dynamic subtest ccs0: SUCCESS (0.027s)
[196.729625] Subtest exec-shared-gtt: SUCCESS (0.181s)
[198.711610] [036/141] (811s left) sw_sync (sync_expired_merge)
[199.076703] Starting subtest: sync_expired_merge
[200.153096] Subtest sync_expired_merge: SUCCESS (1.075s)
[200.294107] [037/141] (809s left) i915_getparams_basic (basic-subslice-total)
[200.666360] Starting subtest: basic-subslice-total
[200.671879] Subtest basic-subslice-total: SUCCESS (0.000s)
[200.834353] [038/141] (809s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-blt)
[201.342674] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-blt
[201.347286] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[201.517258] [039/141] (808s left) gem_exec_balancer (smoke)
[201.937708] Starting subtest: smoke
[227.440971] Subtest smoke: SUCCESS (25.500s)
[229.033255] [040/141] (781s left) gem_mmap_offset (bad-extensions)
[229.414619] Starting subtest: bad-extensions
[229.417472] Subtest bad-extensions: SUCCESS (0.001s)
[229.606254] [041/141] (780s left) fbdev (write)
[229.997459] Starting subtest: write
[233.686131] Subtest write: SUCCESS (3.686s)
[233.877126] [042/141] (776s left) kms_flip (dpms-vs-vblank-race)
[234.274245] Starting subtest: dpms-vs-vblank-race
[234.303126] Starting dynamic subtest: A-eDP1
[239.134415] Dynamic subtest A-eDP1: SUCCESS (4.828s)
[239.160542] Starting dynamic subtest: B-eDP1
[243.150908] Dynamic subtest B-eDP1: SUCCESS (4.013s)
[243.178501] Starting dynamic subtest: C-eDP1
[247.182479] Dynamic subtest C-eDP1: SUCCESS (4.026s)
[247.215447] Starting dynamic subtest: D-eDP1
[251.156675] Dynamic subtest D-eDP1: SUCCESS (3.968s)
[251.180834] Subtest dpms-vs-vblank-race: SUCCESS (16.909s)
[252.091432] [043/141] (758s left) gem_fence_thrash (bo-write-verify-y)
[252.459462] Starting subtest: bo-write-verify-y
[252.474621] Subtest bo-write-verify-y: SKIP (0.012s)
[252.587954] [044/141] (757s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-pwrite)
[253.090080] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-pwrite
[275.037349] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-pwrite: SUCCESS (21.948s)
[275.311475] [045/141] (735s left) kms_cursor_crc (cursor-sliding-128x128)
[276.201074] Starting subtest: cursor-sliding-128x128
[276.233602] Starting dynamic subtest: pipe-A-eDP-1
[282.226141] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.992s)
[282.408830] Starting dynamic subtest: pipe-D-eDP-1
[289.233649] Dynamic subtest pipe-D-eDP-1: SUCCESS (6.854s)
[289.236350] Subtest cursor-sliding-128x128: SUCCESS (13.029s)
[290.199298] [046/141] (720s left) kms_vblank (pipe-b-wait-forked-hang)
[290.682322] Starting subtest: pipe-B-wait-forked-hang
[306.890059] Subtest pipe-B-wait-forked-hang: SUCCESS (16.210s)
[307.835610] [047/141] (702s left) syncobj_wait (reset-signaled)
[308.213692] Starting subtest: reset-signaled
[308.216406] Subtest reset-signaled: SUCCESS (0.001s)
[308.349928] [048/141] (702s left) perf_pmu (cpu-hotplug)
[308.737199] Starting subtest: cpu-hotplug
[308.750430] Subtest cpu-hotplug: SKIP (0.012s)
[308.905886] [049/141] (702s left) gem_exec_whisper (basic-queues-priority-all)
[309.329587] Starting subtest: basic-queues-priority-all
[345.967900] Subtest basic-queues-priority-all: SUCCESS (36.632s)
[346.233374] [050/141] (664s left) v3d/v3d_wait_bo (map-bo-0ns)
[346.696883] Subtest map-bo-0ns: SKIP (0.000s)
[346.836089] [051/141] (664s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-mmap-cpu)
[347.346259] Starting subtest: psr-1p-primscrn-cur-indfb-draw-mmap-cpu
[357.512558] Subtest psr-1p-primscrn-cur-indfb-draw-mmap-cpu: SUCCESS (10.169s)
[357.772712] [052/141] (653s left) kms_big_fb (linear-32bpp-rotate-90)
[358.316960] Starting subtest: linear-32bpp-rotate-90
[358.376078] Subtest linear-32bpp-rotate-90: SKIP (0.058s)
[358.556791] [053/141] (652s left) kms_psr2_su (page_flip-xrgb8888)
[358.747566] Subtest page_flip-XRGB8888: SKIP (0.000s)
[358.831621] [054/141] (652s left) kms_psr2_sf (plane-move-sf-dmg-area)
[366.238783] Starting subtest: plane-move-sf-dmg-area
[366.245453] Starting dynamic subtest: pipe-A-eDP-1
[372.099559] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.852s)
[372.143966] Starting dynamic subtest: pipe-B-eDP-1
[377.772141] Dynamic subtest pipe-B-eDP-1: SUCCESS (5.661s)
[377.774550] Subtest plane-move-sf-dmg-area: SUCCESS (11.534s)
[378.748915] [055/141] (632s left) gem_exec_reloc (basic-cpu)
[379.136699] Subtest basic-cpu: SKIP (0.000s)
[379.274059] [056/141] (632s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-shrfb-msflip-blt)
[379.778232] Starting subtest: fbcpsr-2p-scndscrn-shrfb-msflip-blt
[379.782630] Subtest fbcpsr-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[379.936388] [057/141] (631s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytileccs-downscaling)
[380.423639] Starting subtest: flip-32bpp-ytile-to-32bpp-ytileccs-downscaling
[380.433596] Starting dynamic subtest: pipe-A-default-mode
[380.838339] Dynamic subtest pipe-A-default-mode: SKIP (0.401s)
[380.842869] Subtest flip-32bpp-ytile-to-32bpp-ytileccs-downscaling: SKIP (0.415s)
[381.775419] [058/141] (629s left) i915_pm_rpm (gem-evict-pwrite)
[387.979098] Starting subtest: gem-evict-pwrite
[387.983402] Subtest gem-evict-pwrite: SKIP (0.000s)
[388.832961] [059/141] (622s left) gem_ctx_param (invalid-param-set)
[389.202364] Starting subtest: invalid-param-set
[389.204712] Subtest invalid-param-set: SUCCESS (0.000s)
[389.337601] [060/141] (622s left) kms_ccs (pipe-c-bad-rotation-90-yf_tiled_ccs)
[390.728099] Starting subtest: pipe-C-bad-rotation-90-yf_tiled_ccs
[390.732418] Subtest pipe-C-bad-rotation-90-yf_tiled_ccs: SKIP (0.000s)
[391.753904] [061/141] (620s left) perf_pmu (idle-no-semaphores)
[392.147435] Starting subtest: idle-no-semaphores
[392.150220] Starting dynamic subtest: rcs0
[392.700741] Dynamic subtest rcs0: SUCCESS (0.548s)
[392.740442] Starting dynamic subtest: bcs0
[393.222292] Dynamic subtest bcs0: SUCCESS (0.518s)
[393.261593] Starting dynamic subtest: vcs0
[393.746461] Dynamic subtest vcs0: SUCCESS (0.519s)
[393.785349] Starting dynamic subtest: vcs1
[394.263392] Dynamic subtest vcs1: SUCCESS (0.512s)
[394.302134] Starting dynamic subtest: vecs0
[394.779414] Dynamic subtest vecs0: SUCCESS (0.512s)
[394.818311] Starting dynamic subtest: ccs0
[395.300335] Dynamic subtest ccs0: SUCCESS (0.516s)
[395.302690] Subtest idle-no-semaphores: SUCCESS (3.153s)
[395.485731] [062/141] (616s left) gem_exec_reloc (basic-gtt-cpu)
[395.880567] Subtest basic-gtt-cpu: SKIP (0.000s)
[396.019606] [063/141] (615s left) gem_exec_balancer (hang)
[397.272209] Starting subtest: hang
[406.448108] Subtest hang: SUCCESS (9.184s)
[406.653650] [064/141] (605s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-0)
[407.205876] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-0
[407.210335] Subtest y-tiled-max-hw-stride-32bpp-rotate-0: SKIP (0.000s)
[407.350438] [065/141] (604s left) kms_multipipe_modeset (basic-max-pipe-crc-check)
[407.838064] Starting subtest: basic-max-pipe-crc-check
[407.842581] Subtest basic-max-pipe-crc-check: SKIP (0.000s)
[407.953709] [066/141] (604s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0-async-flip)
[408.514122] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0-async-flip
[408.518771] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SKIP (0.000s)
[408.661797] [067/141] (603s left) gem_exec_fence (invalid-timeline-fence-array)
[410.905110] Starting subtest: invalid-timeline-fence-array
[410.927304] Subtest invalid-timeline-fence-array: SUCCESS (0.020s)
[411.726711] [068/141] (600s left) kms_chamelium_frames (dp-crc-multiple)
[412.634606] Subtest dp-crc-multiple: SKIP (0.000s)
[413.500414] [069/141] (598s left) gem_fenced_exec_thrash (2-spare-fences)
[413.897109] Subtest 2-spare-fences: SKIP (0.000s)
[414.011492] [070/141] (598s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[414.563780] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[417.997962] Subtest x-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SUCCESS (3.432s)
[418.602211] [071/141] (594s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-cpu)
[419.140415] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-cpu
[419.145150] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[419.303868] [072/141] (593s left) kms_psr (sprite_mmap_cpu)
[419.785206] Starting subtest: sprite_mmap_cpu
[422.895133] Subtest sprite_mmap_cpu: SUCCESS (3.109s)
[423.831255] [073/141] (588s left) kms_frontbuffer_tracking (psr-2p-scndscrn-indfb-msflip-blt)
[424.343869] Starting subtest: psr-2p-scndscrn-indfb-msflip-blt
[424.348753] Subtest psr-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[424.520483] [074/141] (588s left) kms_cursor_crc (cursor-sliding-max-size)
[425.826911] Starting subtest: cursor-sliding-max-size
[425.834473] Subtest cursor-sliding-max-size: SKIP (0.000s)
[426.102486] [075/141] (586s left) kms_addfb_basic (clobberred-modifier)
[426.402303] Starting subtest: clobberred-modifier
[426.406057] Subtest clobberred-modifier: SKIP (0.000s)
[426.698070] [076/141] (586s left) api_intel_bb (reset-bb)
[427.055578] Starting subtest: reset-bb
[427.067492] Subtest reset-bb: SUCCESS (0.014s)
[427.249409] [077/141] (585s left) kms_vblank (pipe-d-ts-continuation-modeset-rpm)
[427.713506] Starting subtest: pipe-D-ts-continuation-modeset-rpm
[432.492585] Subtest pipe-D-ts-continuation-modeset-rpm: SUCCESS (4.781s)
[433.434824] [078/141] (579s left) kms_plane_lowres (tiling-y)
[433.907583] Starting subtest: tiling-y
[433.912330] Subtest tiling-y: SKIP (0.000s)
[434.050788] [079/141] (579s left) kms_vblank (pipe-c-wait-forked-busy)
[434.519746] Starting subtest: pipe-C-wait-forked-busy
[438.598671] Subtest pipe-C-wait-forked-busy: SUCCESS (4.081s)
[439.547465] [080/141] (573s left) api_intel_allocator (open-vm)
[439.928551] Starting subtest: open-vm
[439.934789] Subtest open-vm: SUCCESS (0.008s)
[440.110833] [081/141] (573s left) sysfs_timeslice_duration (timeout)
[440.481433] Starting subtest: timeout
[440.484332] Starting dynamic subtest: vecs0
[450.524882] Dynamic subtest vecs0: SUCCESS (10.038s)
[450.567122] Starting dynamic subtest: ccs0
[453.790110] Dynamic subtest ccs0: SUCCESS (3.263s)
[453.839848] Starting dynamic subtest: vcs0
[463.515418] Dynamic subtest vcs0: SUCCESS (9.720s)
[463.535333] Starting dynamic subtest: bcs0
[467.665624] Dynamic subtest bcs0: SUCCESS (4.149s)
[467.688263] Starting dynamic subtest: rcs0
[471.798313] Dynamic subtest rcs0: SUCCESS (4.128s)
[471.827462] Starting dynamic subtest: vcs1
[481.499519] Dynamic subtest vcs1: SUCCESS (9.695s)
[481.502510] Subtest timeout: SUCCESS (41.017s)
[481.649253] [082/141] (531s left) kms_ccs (pipe-d-ccs-on-another-bo-y_tiled_gen12_mc_ccs)
[483.326220] Starting subtest: pipe-D-ccs-on-another-bo-y_tiled_gen12_mc_ccs
[483.330547] Subtest pipe-D-ccs-on-another-bo-y_tiled_gen12_mc_ccs: SKIP (0.000s)
[483.960774] [083/141] (529s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-shrfb-plflip-blt)
[484.462833] Starting subtest: fbcpsr-2p-primscrn-shrfb-plflip-blt
[484.467507] Subtest fbcpsr-2p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[484.629457] [084/141] (529s left) kms_vblank (pipe-b-wait-idle)
[485.118178] Starting subtest: pipe-B-wait-idle
[488.228124] Subtest pipe-B-wait-idle: SUCCESS (3.110s)
[489.181383] [085/141] (524s left) gem_madvise (dontneed-before-mmap)
[489.543102] Starting subtest: dontneed-before-mmap
[489.572889] Subtest dontneed-before-mmap: SUCCESS (0.029s)
[489.735844] [086/141] (524s left) prime_mmap (test_errors)
[490.096120] Starting subtest: test_errors
[490.098622] Starting dynamic subtest: test_errors-smem
[491.683771] Dynamic subtest test_errors-smem: SUCCESS (1.583s)
[491.686717] Subtest test_errors: SUCCESS (1.588s)
[491.830354] [087/141] (522s left) kms_ccs (pipe-d-bad-pixel-format-4_tiled_mtl_rc_ccs)
[493.455107] Starting subtest: pipe-D-bad-pixel-format-4_tiled_mtl_rc_ccs
[493.457772] Subtest pipe-D-bad-pixel-format-4_tiled_mtl_rc_ccs: SUCCESS (0.001s)
[494.264868] [088/141] (519s left) kms_addfb_basic (size-max)
[494.761575] Starting subtest: size-max
[494.764365] Subtest size-max: SUCCESS (0.000s)
[494.931708] [089/141] (519s left) kms_setmode (invalid-clone-exclusive-crtc)
[495.317868] Starting subtest: invalid-clone-exclusive-crtc
[495.325209] Subtest invalid-clone-exclusive-crtc: SKIP (0.003s)
[495.461502] [090/141] (518s left) syncobj_timeline (invalid-query-one-illegal-handle)
[495.838158] Starting subtest: invalid-query-one-illegal-handle
[495.840537] Subtest invalid-query-one-illegal-handle: SUCCESS (0.002s)
[495.987448] [091/141] (518s left) kms_atomic_transition (modeset-transition-nonblocking)
[496.478418] Starting subtest: modeset-transition-nonblocking
[496.480803] Starting dynamic subtest: 1x-outputs
[531.439890] Dynamic subtest 1x-outputs: SUCCESS (34.960s)
[531.958172] Subtest modeset-transition-nonblocking: SUCCESS (35.482s)
[532.853895] [092/141] (481s left) kms_draw_crc (draw-method-mmap-cpu)
[533.329852] Starting subtest: draw-method-mmap-cpu
[533.332391] Starting dynamic subtest: xrgb8888-untiled
[534.015384] Dynamic subtest xrgb8888-untiled: SUCCESS (0.683s)
[534.058682] Starting dynamic subtest: rgb565-untiled
[534.649245] Dynamic subtest rgb565-untiled: SUCCESS (0.629s)
[534.704748] Starting dynamic subtest: xrgb2101010-untiled
[535.327415] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.674s)
[535.375672] Starting dynamic subtest: xrgb8888-xtiled
[535.838194] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.506s)
[535.885397] Starting dynamic subtest: rgb565-xtiled
[536.293434] Dynamic subtest rgb565-xtiled: SUCCESS (0.448s)
[536.344163] Starting dynamic subtest: xrgb2101010-xtiled
[536.727408] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.429s)
[536.770250] Starting dynamic subtest: xrgb8888-4tiled
[537.237101] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.506s)
[537.278788] Starting dynamic subtest: rgb565-4tiled
[537.771775] Dynamic subtest rgb565-4tiled: SUCCESS (0.532s)
[537.818690] Starting dynamic subtest: xrgb2101010-4tiled
[538.260229] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.484s)
[538.262588] Subtest draw-method-mmap-cpu: SUCCESS (4.933s)
[538.453456] [093/141] (475s left) gem_exec_schedule (u-semaphore-user)
[538.901094] Starting subtest: u-semaphore-user
[538.958882] Subtest u-semaphore-user: SUCCESS (0.057s)
[540.687787] [094/141] (473s left) kms_selftest (drm_format_helper)
[541.053158] Starting subtest: drm_format_helper
[541.063113] Subtest drm_format_helper: SKIP (0.008s)
[541.192725] [095/141] (473s left) kms_addfb_basic (master-rmfb)
[541.570629] Starting subtest: master-rmfb
[541.573298] Subtest master-rmfb: SUCCESS (0.004s)
[541.863858] [096/141] (472s left) kms_panel_fitting (legacy)
[542.347551] Starting subtest: legacy
[542.350105] Starting dynamic subtest: pipe-A-eDP-1
[544.310199] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.960s)
[544.410279] Starting dynamic subtest: pipe-B-eDP-1
[547.132695] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.759s)
[547.225289] Starting dynamic subtest: pipe-C-eDP-1
[550.008212] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.808s)
[550.095063] Starting dynamic subtest: pipe-D-eDP-1
[552.839303] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.786s)
[552.841567] Subtest legacy: SUCCESS (10.494s)
[554.308731] [097/141] (460s left) gem_exec_params (invalid-bsd-ring)
[554.727901] Starting subtest: invalid-bsd-ring
[554.730868] Subtest invalid-bsd-ring: SUCCESS (0.001s)
[554.902624] [098/141] (459s left) i915_hangman (gt-error-state-capture)
[555.358696] Starting subtest: gt-error-state-capture
[555.365560] Starting dynamic subtest: rcs0
[566.533092] Dynamic subtest rcs0: SUCCESS (11.168s)
[566.581151] Starting dynamic subtest: bcs0
[578.499401] Dynamic subtest bcs0: SUCCESS (11.963s)
[578.543454] Starting dynamic subtest: vcs0
[584.515959] Dynamic subtest vcs0: SUCCESS (6.012s)
[584.559952] Starting dynamic subtest: vcs1
[590.531633] Dynamic subtest vcs1: SUCCESS (6.011s)
[590.579765] Starting dynamic subtest: vecs0
[596.547972] Dynamic subtest vecs0: SUCCESS (6.011s)
[596.592123] Starting dynamic subtest: ccs0
[608.516205] Dynamic subtest ccs0: SUCCESS (11.964s)
[608.518784] Subtest gt-error-state-capture: SUCCESS (53.157s)
[608.717498] [099/141] (406s left) gem_exec_schedule (promotion)
[609.154932] Starting subtest: promotion
[609.157855] Starting dynamic subtest: rcs0
[609.638853] Dynamic subtest rcs0: SUCCESS (0.479s)
[609.688385] Starting dynamic subtest: bcs0
[610.019566] Dynamic subtest bcs0: SUCCESS (0.376s)
[610.067975] Starting dynamic subtest: vcs0
[610.342077] Dynamic subtest vcs0: SUCCESS (0.318s)
[610.379411] Starting dynamic subtest: vcs1
[610.635335] Dynamic subtest vcs1: SUCCESS (0.288s)
[610.686504] Starting dynamic subtest: vecs0
[610.850428] Dynamic subtest vecs0: SUCCESS (0.212s)
[610.900258] Starting dynamic subtest: ccs0
[611.178261] Dynamic subtest ccs0: SUCCESS (0.322s)
[611.181169] Subtest promotion: SUCCESS (2.021s)
[612.764561] [100/141] (402s left) gem_pread (exhaustion)
[613.152152] Subtest exhaustion: SKIP (0.000s)
[613.300470] [101/141] (401s left) gem_spin_batch (spin-all-new)
[613.732350] Starting subtest: spin-all-new
[613.809211] Subtest spin-all-new: SUCCESS (0.077s)
[615.683823] [102/141] (399s left) kms_frontbuffer_tracking (psr-2p-primscrn-shrfb-pgflip-blt)
[616.196939] Starting subtest: psr-2p-primscrn-shrfb-pgflip-blt
[616.201884] Subtest psr-2p-primscrn-shrfb-pgflip-blt: SKIP (0.000s)
[616.382244] [103/141] (398s left) gem_media_fill (media-fill)
[616.758611] Subtest media-fill: SKIP (0.000s)
[616.950443] [104/141] (398s left) gem_mmap_wc (read-write-distinct)
[617.331804] Subtest read-write-distinct: SKIP (0.000s)
[617.460979] [105/141] (397s left) kms_ccs (pipe-c-crc-primary-rotation-180-y_tiled_gen12_rc_ccs_cc)
[618.853683] Starting subtest: pipe-C-crc-primary-rotation-180-y_tiled_gen12_rc_ccs_cc
[618.858847] Subtest pipe-C-crc-primary-rotation-180-y_tiled_gen12_rc_ccs_cc: SKIP (0.002s)
[619.812797] [106/141] (395s left) kms_flip (plain-flip-fb-recreate-interruptible)
[620.242149] Starting subtest: plain-flip-fb-recreate-interruptible
[620.277456] Starting dynamic subtest: A-eDP1
[629.684309] Dynamic subtest A-eDP1: SUCCESS (9.410s)
[629.704481] Starting dynamic subtest: B-eDP1
[638.750638] Dynamic subtest B-eDP1: SUCCESS (9.062s)
[638.797718] Starting dynamic subtest: C-eDP1
[647.977589] Dynamic subtest C-eDP1: SUCCESS (9.221s)
[648.035749] Starting dynamic subtest: D-eDP1
[657.218072] Dynamic subtest D-eDP1: SUCCESS (9.235s)
[657.245609] Subtest plain-flip-fb-recreate-interruptible: SUCCESS (37.003s)
[658.760441] [107/141] (356s left) vgem_basic (dmabuf-mmap)
[659.140821] Starting subtest: dmabuf-mmap
[659.195547] Subtest dmabuf-mmap: SUCCESS (0.055s)
[659.328169] [108/141] (356s left) gem_exec_flush (basic-uc-set-default)
[659.752370] Starting subtest: basic-uc-set-default
[665.639446] Subtest basic-uc-set-default: SUCCESS (5.886s)
[665.907783] [109/141] (349s left) gem_exec_schedule (independent)
[666.344221] Starting subtest: independent
[666.346173] Starting dynamic subtest: rcs0
[667.533752] Dynamic subtest rcs0: SUCCESS (1.189s)
[667.579573] Starting dynamic subtest: bcs0
[669.517591] Dynamic subtest bcs0: SUCCESS (1.979s)
[669.557725] Starting dynamic subtest: vcs0
[671.603176] Dynamic subtest vcs0: SUCCESS (2.082s)
[671.648193] Starting dynamic subtest: vcs1
[673.601367] Dynamic subtest vcs1: SUCCESS (1.994s)
[673.649237] Starting dynamic subtest: vecs0
[675.577814] Dynamic subtest vecs0: SUCCESS (1.973s)
[675.622510] Starting dynamic subtest: ccs0
[677.514386] Dynamic subtest ccs0: SUCCESS (1.930s)
[677.516872] Subtest independent: SUCCESS (11.173s)
[677.980321] [110/141] (337s left) drm_fdinfo (virtual-busy)
[678.384496] Starting subtest: virtual-busy
[678.390167] Subtest virtual-busy: SKIP (0.001s)
[678.544567] [111/141] (337s left) v3d/v3d_submit_csd (bad-perfmon)
[679.033048] Subtest bad-perfmon: SKIP (0.000s)
[679.164522] [112/141] (336s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0-async-flip)
[679.722808] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-0-async-flip
[683.452592] Subtest 4-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SUCCESS (3.727s)
[683.924886] [113/141] (332s left) syncobj_timeline (invalid-multi-wait-available-unsubmitted-signaled)
[684.302613] Starting subtest: invalid-multi-wait-available-unsubmitted-signaled
[684.305193] Subtest invalid-multi-wait-available-unsubmitted-signaled: SUCCESS (0.002s)
[684.451364] [114/141] (331s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[685.015145] Starting subtest: basic-flip-before-cursor-varying-size
[686.415238] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.396s)
[687.367270] [115/141] (328s left) kms_cdclk (mode-transition)
[687.829967] Starting subtest: mode-transition
[687.832159] Starting dynamic subtest: pipe-A-eDP-1
[688.210860] Dynamic subtest pipe-A-eDP-1: SKIP (0.378s)
[688.252048] Starting dynamic subtest: pipe-B-eDP-1
[688.256146] Dynamic subtest pipe-B-eDP-1: SKIP (0.022s)
[688.292109] Starting dynamic subtest: pipe-C-eDP-1
[688.296214] Dynamic subtest pipe-C-eDP-1: SKIP (0.022s)
[688.339972] Starting dynamic subtest: pipe-D-eDP-1
[688.345399] Dynamic subtest pipe-D-eDP-1: SKIP (0.022s)
[688.350176] Subtest mode-transition: SKIP (0.464s)
[689.170572] [116/141] (327s left) gem_ctx_persistence (legacy-engines-mixed-process)
[689.565444] Starting subtest: legacy-engines-mixed-process
[689.572600] Starting dynamic subtest: render
[691.573402] Dynamic subtest render: SUCCESS (2.000s)
[691.620517] Starting dynamic subtest: bsd1
[711.683612] Dynamic subtest bsd1: SUCCESS (20.110s)
[711.735505] Starting dynamic subtest: bsd2
[731.794341] Dynamic subtest bsd2: SUCCESS (20.105s)
[731.847049] Starting dynamic subtest: blt
[733.556762] Dynamic subtest blt: SUCCESS (1.758s)
[733.609729] Starting dynamic subtest: vebox
[753.673061] Dynamic subtest vebox: SUCCESS (20.107s)
[753.675491] Subtest legacy-engines-mixed-process: SUCCESS (64.105s)
[753.857569] [117/141] (262s left) gem_partial_pwrite_pread (write-uncached)
[754.270389] Subtest write-uncached: SKIP (0.000s)
[754.426567] [118/141] (262s left) kms_ccs (pipe-b-random-ccs-data-y_tiled_gen12_rc_ccs_cc)
[755.644412] Starting subtest: pipe-B-random-ccs-data-y_tiled_gen12_rc_ccs_cc
[755.650780] Subtest pipe-B-random-ccs-data-y_tiled_gen12_rc_ccs_cc: SKIP (0.000s)
[756.825179] [119/141] (259s left) kms_ccs (pipe-b-ccs-on-another-bo-y_tiled_gen12_mc_ccs)
[758.076206] Starting subtest: pipe-B-ccs-on-another-bo-y_tiled_gen12_mc_ccs
[758.080786] Subtest pipe-B-ccs-on-another-bo-y_tiled_gen12_mc_ccs: SKIP (0.000s)
[759.186965] [120/141] (257s left) gem_softpin (allocator-nopin)
[759.592164] Starting subtest: allocator-nopin
[760.156945] Subtest allocator-nopin: SUCCESS (0.567s)
[760.393810] [121/141] (256s left) kms_ccs (pipe-a-crc-sprite-planes-basic-yf_tiled_ccs)
[761.480515] Starting subtest: pipe-A-crc-sprite-planes-basic-yf_tiled_ccs
[761.496045] Subtest pipe-A-crc-sprite-planes-basic-yf_tiled_ccs: SKIP (0.013s)
[762.828151] [122/141] (254s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-render)
[763.324981] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-render
[763.329575] Subtest fbc-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[763.513200] [123/141] (253s left) i915_pm_rc6_residency (rc6-fence)
[763.878073] Starting subtest: rc6-fence
[787.564554] Subtest rc6-fence: SUCCESS (23.688s)
[788.995549] [124/141] (228s left) syncobj_wait (reset-multiple-signaled)
[789.381873] Starting subtest: reset-multiple-signaled
[789.384946] Subtest reset-multiple-signaled: SUCCESS (0.003s)
[789.571115] [125/141] (227s left) kms_ccs (pipe-b-bad-pixel-format-4_tiled_mtl_rc_ccs_cc)
[790.709403] Starting subtest: pipe-B-bad-pixel-format-4_tiled_mtl_rc_ccs_cc
[790.712129] Subtest pipe-B-bad-pixel-format-4_tiled_mtl_rc_ccs_cc: SUCCESS (0.002s)
[792.010539] [126/141] (225s left) kms_vblank (pipe-b-ts-continuation-modeset-hang)
[792.495884] Starting subtest: pipe-B-ts-continuation-modeset-hang
[807.880218] Subtest pipe-B-ts-continuation-modeset-hang: SUCCESS (15.386s)
[808.829085] [127/141] (208s left) kms_universal_plane (disable-primary-vs-flip-pipe-c)
[809.305266] Starting subtest: disable-primary-vs-flip-pipe-C
[812.778974] Subtest disable-primary-vs-flip-pipe-C: SUCCESS (3.476s)
[813.709469] [128/141] (203s left) gem_exec_fence (syncobj-invalid-flags)
[815.755690] Starting subtest: syncobj-invalid-flags
[815.758205] Subtest syncobj-invalid-flags: SUCCESS (0.002s)
[816.700862] [129/141] (200s left) kms_ccs (pipe-b-crc-primary-rotation-180-4_tiled_mtl_rc_ccs_cc)
[817.891658] Starting subtest: pipe-B-crc-primary-rotation-180-4_tiled_mtl_rc_ccs_cc
[818.977240] Subtest pipe-B-crc-primary-rotation-180-4_tiled_mtl_rc_ccs_cc: SUCCESS (1.085s)
[820.494938] [130/141] (197s left) gem_pipe_control_store_loop (reused-buffer)
[820.898293] Starting subtest: reused-buffer
[823.064933] Subtest reused-buffer: SUCCESS (2.163s)
[823.238304] [131/141] (194s left) v3d/v3d_submit_cl (bad-multisync-extension)
[823.745144] Subtest bad-multisync-extension: SKIP (0.000s)
[823.889915] [132/141] (193s left) gem_exec_reloc (basic-wc-cpu-noreloc)
[824.299641] Subtest basic-wc-cpu-noreloc: SKIP (0.000s)
[824.446222] [133/141] (193s left) gem_userptr_blits (input-checking)
[824.842147] Starting subtest: input-checking
[824.844698] Subtest input-checking: SUCCESS (0.000s)
[825.152881] [134/141] (192s left) gem_exec_whisper (basic-contexts)
[825.572685] Starting subtest: basic-contexts
[843.543135] Subtest basic-contexts: SUCCESS (17.964s)
[844.180879] [135/141] (173s left) gem_ctx_engines (invalid-engines)
[844.618896] Starting subtest: invalid-engines
[844.652322] Subtest invalid-engines: SUCCESS (0.033s)
[844.995475] [136/141] (173s left) kms_chamelium_edid (dp-edid-stress-resolution-non-4k)
[845.887763] Subtest dp-edid-stress-resolution-non-4k: SKIP (0.000s)
[846.759225] [137/141] (171s left) gem_vm_create (isolation)
[847.153702] Starting subtest: isolation
[847.179680] Subtest isolation: SUCCESS (0.030s)
[847.373210] [138/141] (170s left) kms_cursor_crc (cursor-offscreen-32x10)
[848.075312] Starting subtest: cursor-offscreen-32x10
[848.386780] Subtest cursor-offscreen-32x10: SKIP (0.303s)
[849.298314] [139/141] (169s left) gem_render_copy (yf-tiled-ccs-to-yf-tiled)
[849.715662] Starting subtest: yf-tiled-ccs-to-yf-tiled
[849.719595] Subtest yf-tiled-ccs-to-yf-tiled: SKIP (0.000s)
[849.990655] [140/141] (168s left) syncobj_timeline (signal-array)
[850.264207] Starting subtest: signal-array
[850.266986] Subtest signal-array: SUCCESS (0.001s)
[850.416249] [141/141] (168s left) gem_exec_reloc (basic-wc-gtt-noreloc)
[850.804582] Subtest basic-wc-gtt-noreloc: SKIP (0.000s)
[850.950762] Closing watchdogs
Done.