[57.522275] Initializing watchdogs
[57.522318]   /dev/watchdog0
[57.535382] [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]
[57.539844] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.542051] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.545324] [001/133] (960s left) syncobj_timeline (invalid-transfer-bad-pad)
[57.623102] Starting subtest: invalid-transfer-bad-pad
[57.625389] Subtest invalid-transfer-bad-pad: SUCCESS (0.000s)
[57.736930] [002/133] (960s left) kms_draw_crc (draw-method-render)
[57.847150] Starting subtest: draw-method-render
[57.849195] Starting dynamic subtest: xrgb8888-untiled
[58.111596] Dynamic subtest xrgb8888-untiled: SUCCESS (0.266s)
[58.119563] Starting dynamic subtest: rgb565-untiled
[58.333871] Dynamic subtest rgb565-untiled: SUCCESS (0.222s)
[58.341113] Starting dynamic subtest: xrgb2101010-untiled
[58.578692] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.244s)
[58.586235] Starting dynamic subtest: xbgr16161616f-untiled
[58.790132] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.211s)
[58.798147] Starting dynamic subtest: xrgb8888-xtiled
[58.912923] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.122s)
[58.921444] Starting dynamic subtest: rgb565-xtiled
[59.024053] Dynamic subtest rgb565-xtiled: SUCCESS (0.110s)
[59.031843] Starting dynamic subtest: xrgb2101010-xtiled
[59.135491] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.111s)
[59.142585] Starting dynamic subtest: xbgr16161616f-xtiled
[59.247115] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.111s)
[59.255864] Starting dynamic subtest: xrgb8888-4tiled
[59.381495] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.133s)
[59.388351] Starting dynamic subtest: rgb565-4tiled
[59.491610] Dynamic subtest rgb565-4tiled: SUCCESS (0.111s)
[59.498011] Starting dynamic subtest: xrgb2101010-4tiled
[59.602738] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.111s)
[59.609890] Starting dynamic subtest: xbgr16161616f-4tiled
[59.714350] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.111s)
[59.716240] Subtest draw-method-render: SUCCESS (1.866s)
[59.859585] [003/133] (958s left) api_intel_allocator (default-alignment)
[59.918712] Starting subtest: default-alignment
[59.924503] Subtest default-alignment: SUCCESS (0.007s)
[60.047233] [004/133] (958s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-onoff)
[60.145945] Starting subtest: fbc-1p-primscrn-cur-indfb-onoff
[64.127410] Subtest fbc-1p-primscrn-cur-indfb-onoff: SUCCESS (3.978s)
[65.291262] [005/133] (952s left) syncobj_timeline (wait-all-for-submit-snapshot)
[65.364079] Starting subtest: wait-all-for-submit-snapshot
[65.445527] Subtest wait-all-for-submit-snapshot: SUCCESS (0.082s)
[65.532332] [006/133] (952s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-onoff)
[65.640609] Starting subtest: psr-2p-primscrn-spr-indfb-onoff
[65.644780] Subtest psr-2p-primscrn-spr-indfb-onoff: SKIP (0.000s)
[65.762160] [007/133] (952s left) kms_plane_scaling (plane-downscale-factor-0-5-with-rotation)
[65.855363] Starting subtest: plane-downscale-factor-0-5-with-rotation
[65.857621] Starting dynamic subtest: pipe-A
[65.875476] Dynamic subtest pipe-A: SKIP (0.016s)
[65.884223] Starting dynamic subtest: pipe-B
[65.916296] Dynamic subtest pipe-B: SKIP (0.040s)
[65.923094] Starting dynamic subtest: pipe-C
[65.949843] Dynamic subtest pipe-C: SKIP (0.035s)
[65.956759] Starting dynamic subtest: pipe-D
[65.982942] Dynamic subtest pipe-D: SKIP (0.033s)
[65.986704] Subtest plane-downscale-factor-0-5-with-rotation: SKIP (0.125s)
[66.096769] [008/133] (952s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0)
[66.208786] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-0
[67.288639] Subtest 4-tiled-max-hw-stride-32bpp-rotate-0: SUCCESS (1.079s)
[67.628717] [009/133] (950s left) i915_selftest (live)
[69.737221] Starting subtest: live
[69.739731] Starting dynamic subtest: sanitycheck
[74.124553] Dynamic subtest sanitycheck: SUCCESS (4.389s)
[74.137556] Starting dynamic subtest: uncore
[78.592404] Dynamic subtest uncore: SUCCESS (4.465s)
[78.600590] Starting dynamic subtest: workarounds
[82.131620] Dynamic subtest workarounds: SUCCESS (3.537s)
[82.139799] Starting dynamic subtest: gt_engines
[86.832075] Dynamic subtest gt_engines: SUCCESS (4.698s)
[86.840972] Starting dynamic subtest: gt_timelines
[102.581619] Dynamic subtest gt_timelines: SUCCESS (15.744s)
[102.590838] Starting dynamic subtest: gt_contexts
[105.407953] Dynamic subtest gt_contexts: SUCCESS (2.825s)
[105.418862] Starting dynamic subtest: gt_lrc
[111.678785] Dynamic subtest gt_lrc: SUCCESS (6.269s)
[111.687548] Starting dynamic subtest: gt_mocs
[114.763021] Dynamic subtest gt_mocs: SUCCESS (3.083s)
[114.771073] Starting dynamic subtest: gt_pm
[119.662522] Dynamic subtest gt_pm: SUCCESS (4.898s)
[119.671585] Starting dynamic subtest: gt_heartbeat
[124.096369] Dynamic subtest gt_heartbeat: SUCCESS (4.433s)
[124.104468] Starting dynamic subtest: gt_tlb
[133.524725] Dynamic subtest gt_tlb: SUCCESS (9.426s)
[133.534096] Starting dynamic subtest: requests
[147.245942] Dynamic subtest requests: SUCCESS (13.719s)
[147.254767] Starting dynamic subtest: migrate
[153.340340] Dynamic subtest migrate: SUCCESS (6.093s)
[153.348958] Starting dynamic subtest: active
[156.174960] Dynamic subtest active: SUCCESS (2.833s)
[156.183652] Starting dynamic subtest: objects
[160.975994] Dynamic subtest objects: SUCCESS (4.800s)
[160.984829] Starting dynamic subtest: mman
[166.023270] Dynamic subtest mman: SUCCESS (5.045s)
[166.031827] Starting dynamic subtest: dmabuf
[170.465463] Dynamic subtest dmabuf: SUCCESS (4.441s)
[170.478611] Starting dynamic subtest: vma
[174.898995] Dynamic subtest vma: SUCCESS (4.431s)
[174.907569] Starting dynamic subtest: coherency
[180.384900] Dynamic subtest coherency: SUCCESS (5.485s)
[180.393919] Starting dynamic subtest: gtt
[192.422345] Dynamic subtest gtt: SUCCESS (12.033s)
[192.430821] Starting dynamic subtest: gem
[198.771086] Dynamic subtest gem: SUCCESS (6.346s)
[198.780578] Starting dynamic subtest: evict
[204.350536] Dynamic subtest evict: SUCCESS (5.577s)
[204.364470] Starting dynamic subtest: hugepages
[213.630753] Dynamic subtest hugepages: SUCCESS (9.278s)
[213.643994] Starting dynamic subtest: gem_contexts
[240.235454] Dynamic subtest gem_contexts: SUCCESS (26.602s)
[240.243883] Starting dynamic subtest: client
[244.671443] Dynamic subtest client: SUCCESS (4.435s)
[244.680560] Starting dynamic subtest: gem_migrate
[249.075057] Dynamic subtest gem_migrate: SUCCESS (4.401s)
[249.086289] Starting dynamic subtest: reset
[252.036520] Dynamic subtest reset: SUCCESS (2.960s)
[252.050659] Starting dynamic subtest: memory_region
[256.530452] Dynamic subtest memory_region: SUCCESS (4.491s)
[256.538769] Starting dynamic subtest: hangcheck
[269.657496] Dynamic subtest hangcheck: SUCCESS (13.124s)
[269.665753] Starting dynamic subtest: execlists
[274.142548] Dynamic subtest execlists: SUCCESS (4.483s)
[274.149439] Starting dynamic subtest: ring_submission
[278.591377] Dynamic subtest ring_submission: SUCCESS (4.446s)
[278.600234] Starting dynamic subtest: perf
[283.056785] Dynamic subtest perf: SUCCESS (4.464s)
[283.070181] Starting dynamic subtest: slpc
[297.275583] Dynamic subtest slpc: SUCCESS (14.217s)
[297.285276] Starting dynamic subtest: guc
[301.266045] Dynamic subtest guc: SUCCESS (3.989s)
[301.274776] Starting dynamic subtest: guc_multi_lrc
[304.070929] Dynamic subtest guc_multi_lrc: SUCCESS (2.803s)
[304.079975] Starting dynamic subtest: guc_hang
[309.130196] Dynamic subtest guc_hang: SUCCESS (5.057s)
[309.139565] Starting dynamic subtest: late_gt_pm
[311.966024] Dynamic subtest late_gt_pm: SUCCESS (2.832s)
[311.968997] Subtest live: SUCCESS (242.214s)
[311.995366] [FACT i915_selftest (live)] deleted: kernel.kmod_is_loaded.i915: true
[311.998428] [010/133] (706s left) kms_psr2_sf (fbc-pr-cursor-plane-update-sf)
[313.567719] Starting subtest: fbc-pr-cursor-plane-update-sf
[313.571783] Subtest fbc-pr-cursor-plane-update-sf: SKIP (0.000s)
[313.657051] [FACT kms_psr2_sf (fbc-pr-cursor-plane-update-sf)] new: kernel.kmod_is_loaded.i915: true
[313.661229] [011/133] (704s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[313.793428] Starting subtest: basic-flip-before-cursor-atomic
[314.730650] Subtest basic-flip-before-cursor-atomic: SUCCESS (0.936s)
[315.616837] [012/133] (702s left) kms_plane_scaling (planes-downscale-factor-0-75)
[315.722298] Starting subtest: planes-downscale-factor-0-75
[315.724554] Starting dynamic subtest: pipe-A
[315.743064] Dynamic subtest pipe-A: SKIP (0.017s)
[315.752716] Starting dynamic subtest: pipe-B
[315.776801] Dynamic subtest pipe-B: SKIP (0.033s)
[315.784286] Starting dynamic subtest: pipe-C
[315.809348] Dynamic subtest pipe-C: SKIP (0.033s)
[315.816278] Starting dynamic subtest: pipe-D
[315.844611] Dynamic subtest pipe-D: SKIP (0.033s)
[315.848752] Subtest planes-downscale-factor-0-75: SKIP (0.118s)
[315.977974] [013/133] (702s left) kms_plane_multiple (tiling-x)
[316.083169] Starting subtest: tiling-x
[316.085520] Starting dynamic subtest: pipe-A-eDP-1
[319.753523] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.671s)
[319.762897] Starting dynamic subtest: pipe-B-eDP-1
[324.182291] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.427s)
[324.189706] Starting dynamic subtest: pipe-C-eDP-1
[328.513283] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.329s)
[328.521405] Starting dynamic subtest: pipe-D-eDP-1
[332.850933] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.338s)
[332.853751] Subtest tiling-x: SUCCESS (16.769s)
[333.668402] [014/133] (684s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-submitted)
[333.731672] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-submitted
[333.834995] Subtest etime-multi-wait-all-for-submit-unsubmitted-submitted: SUCCESS (0.103s)
[333.918137] [015/133] (684s left) kms_flip_scaled_crc (flip-32bpp-yftileccs-to-64bpp-yftile-downscaling)
[334.027238] Starting subtest: flip-32bpp-yftileccs-to-64bpp-yftile-downscaling
[334.031636] Subtest flip-32bpp-yftileccs-to-64bpp-yftile-downscaling: SKIP (0.000s)
[334.106775] [016/133] (684s left) syncobj_timeline (etime-multi-wait-for-submit-submitted)
[334.175798] Starting subtest: etime-multi-wait-for-submit-submitted
[334.284041] Subtest etime-multi-wait-for-submit-submitted: SUCCESS (0.107s)
[334.368600] [017/133] (684s left) gem_userptr_blits (sd-probe)
[334.445827] Starting subtest: sd-probe
[334.447781] Subtest sd-probe: SUCCESS (0.000s)
[334.599649] [018/133] (684s left) gem_ctx_shared (q-independent)
[334.675625] Starting subtest: Q-independent
[334.677954] Starting dynamic subtest: rcs0
[334.734346] Dynamic subtest rcs0: SUCCESS (0.061s)
[334.744741] Starting dynamic subtest: vcs0
[334.771801] Dynamic subtest vcs0: SUCCESS (0.037s)
[334.781732] Starting dynamic subtest: vcs1
[334.808526] Dynamic subtest vcs1: SUCCESS (0.036s)
[334.817378] Starting dynamic subtest: vecs0
[334.845926] Dynamic subtest vecs0: SUCCESS (0.037s)
[334.854873] Starting dynamic subtest: ccs0
[334.884555] Dynamic subtest ccs0: SUCCESS (0.038s)
[334.886996] Subtest Q-independent: SUCCESS (0.213s)
[336.027394] [019/133] (682s left) kms_frontbuffer_tracking (fbc-suspend)
[336.125827] Starting subtest: fbc-suspend
[374.573267] Subtest fbc-suspend: SUCCESS (7.594s)
[375.787916] [020/133] (642s left) kms_big_fb (yf-tiled-64bpp-rotate-270)
[375.899444] Starting subtest: yf-tiled-64bpp-rotate-270
[375.903809] Subtest yf-tiled-64bpp-rotate-270: SKIP (0.000s)
[376.003285] [021/133] (642s left) gem_ctx_param (non-root-set)
[376.063447] Starting subtest: non-root-set
[376.093733] Subtest non-root-set: SUCCESS (0.030s)
[376.202341] [022/133] (642s left) kms_chamelium_color (ctm-max)
[376.288716] Subtest ctm-max: SKIP (0.000s)
[376.329047] [023/133] (642s left) kms_flip (absolute-wf_vblank-interruptible)
[376.430693] Starting subtest: absolute-wf_vblank-interruptible
[376.433013] Starting dynamic subtest: A-eDP1
[385.174466] Dynamic subtest A-eDP1: SUCCESS (8.698s)
[385.184647] Starting dynamic subtest: B-eDP1
[393.926846] Dynamic subtest B-eDP1: SUCCESS (8.733s)
[393.938701] Starting dynamic subtest: C-eDP1
[402.649427] Dynamic subtest C-eDP1: SUCCESS (8.712s)
[402.662505] Starting dynamic subtest: D-eDP1
[411.372902] Dynamic subtest D-eDP1: SUCCESS (8.725s)
[411.375165] Subtest absolute-wf_vblank-interruptible: SUCCESS (34.879s)
[412.606084] [024/133] (606s left) kms_cursor_edge_walk (256x256-left-edge)
[412.711521] Starting subtest: 256x256-left-edge
[412.713795] Starting dynamic subtest: pipe-A-eDP-1
[416.144783] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.433s)
[416.155343] Starting dynamic subtest: pipe-D-eDP-1
[419.258499] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.112s)
[419.260700] Subtest 256x256-left-edge: SUCCESS (6.548s)
[420.088759] [025/133] (598s left) kms_atomic_transition (plane-all-transition-nonblocking-fencing)
[420.182620] Starting subtest: plane-all-transition-nonblocking-fencing
[420.184864] Starting dynamic subtest: pipe-A-eDP-1
[422.677181] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.496s)
[422.998853] Starting dynamic subtest: pipe-B-eDP-1
[426.222704] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.231s)
[426.522116] Subtest plane-all-transition-nonblocking-fencing: SUCCESS (6.340s)
[427.353740] [026/133] (591s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[427.444778] Starting subtest: compare-crc-sanitycheck-nv12
[427.446727] Starting dynamic subtest: pipe-A-eDP-1
[428.126830] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.682s)
[428.135929] Starting dynamic subtest: pipe-B-eDP-1
[429.375808] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.247s)
[429.385570] Starting dynamic subtest: pipe-C-eDP-1
[430.581703] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.204s)
[430.591219] Starting dynamic subtest: pipe-D-eDP-1
[431.836504] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.252s)
[431.839138] Subtest compare-crc-sanitycheck-nv12: SUCCESS (4.392s)
[432.685169] [027/133] (586s left) kms_addfb_basic (bad-pitch-256)
[432.737592] Starting subtest: bad-pitch-256
[432.739479] Subtest bad-pitch-256: SUCCESS (0.000s)
[432.883711] [028/133] (586s left) fbdev (nullptr)
[432.919071] Starting subtest: nullptr
[432.921518] Subtest nullptr: SUCCESS (0.000s)
[433.000883] [029/133] (586s left) kms_cursor_legacy (short-flip-before-cursor-atomic-transitions-varying-size)
[433.128741] Starting subtest: short-flip-before-cursor-atomic-transitions-varying-size
[434.094273] Subtest short-flip-before-cursor-atomic-transitions-varying-size: SUCCESS (0.965s)
[434.939843] [030/133] (584s left) syncobj_wait (invalid-signal-bad-pad)
[435.007682] Starting subtest: invalid-signal-bad-pad
[435.009916] Subtest invalid-signal-bad-pad: SUCCESS (0.000s)
[435.126117] [031/133] (584s left) gem_spin_batch (spin-all-new)
[435.183234] Starting subtest: spin-all-new
[435.198260] Subtest spin-all-new: SUCCESS (0.014s)
[436.774714] [032/133] (582s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[436.873316] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[437.675132] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SUCCESS (0.803s)
[437.871313] [033/133] (581s left) gem_lmem_swapping (heavy-multi)
[437.919365] Subtest heavy-multi: SKIP (0.000s)
[437.968576] [034/133] (581s left) gem_mmap_gtt (isolation)
[438.032315] Subtest isolation: SKIP (0.000s)
[438.097708] [035/133] (581s left) gem_exec_whisper (basic-contexts-forked)
[438.158283] Starting subtest: basic-contexts-forked
[463.334925] Subtest basic-contexts-forked: SUCCESS (25.167s)
[463.525440] [036/133] (555s left) gem_close (basic)
[463.592077] Starting subtest: basic
[463.594473] Subtest basic: SUCCESS (0.000s)
[463.719448] [037/133] (555s left) gen9_exec_parse (bb-start-far)
[463.788562] Subtest bb-start-far: SKIP (0.000s)
[463.867272] [038/133] (555s left) gem_exec_balancer (full-pulse)
[463.939386] Starting subtest: full-pulse
[468.572562] Subtest full-pulse: SUCCESS (4.632s)
[468.911453] [039/133] (550s left) kms_addfb_basic (basic-x-tiled-legacy)
[468.974065] Starting subtest: basic-x-tiled-legacy
[468.978402] Subtest basic-x-tiled-legacy: SKIP (0.000s)
[469.136440] [040/133] (550s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-16bpp-yftile-downscaling)
[469.221773] Starting subtest: flip-64bpp-yftile-to-16bpp-yftile-downscaling
[469.225700] Subtest flip-64bpp-yftile-to-16bpp-yftile-downscaling: SKIP (0.000s)
[469.317972] [041/133] (550s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180)
[469.414373] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180
[470.392709] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180: SUCCESS (0.980s)
[470.551672] [042/133] (549s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-pwrite)
[470.678593] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-pwrite
[470.682314] Subtest fbc-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[470.800409] [043/133] (548s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-plflip-blt)
[470.909625] Starting subtest: psr-1p-primscrn-indfb-plflip-blt
[478.230587] Subtest psr-1p-primscrn-indfb-plflip-blt: SUCCESS (7.324s)
[478.412721] [044/133] (541s left) kms_chamelium_frames (dp-crc-multiple)
[478.802126] Subtest dp-crc-multiple: SKIP (0.000s)
[479.648292] [045/133] (540s left) kms_flip (nonexisting-fb-interruptible)
[479.738476] Starting subtest: nonexisting-fb-interruptible
[479.740768] Starting dynamic subtest: A-eDP1
[480.870108] Dynamic subtest A-eDP1: SUCCESS (1.130s)
[480.880315] Starting dynamic subtest: B-eDP1
[481.994135] Dynamic subtest B-eDP1: SUCCESS (1.122s)
[482.003690] Starting dynamic subtest: C-eDP1
[483.129348] Dynamic subtest C-eDP1: SUCCESS (1.134s)
[483.139751] Starting dynamic subtest: D-eDP1
[484.267599] Dynamic subtest D-eDP1: SUCCESS (1.137s)
[484.269982] Subtest nonexisting-fb-interruptible: SUCCESS (4.532s)
[485.462900] [046/133] (534s left) kms_psr2_sf (psr2-cursor-plane-move-continuous-sf)
[485.564772] Starting subtest: psr2-cursor-plane-move-continuous-sf
[485.567099] Starting dynamic subtest: pipe-A-eDP-1
[495.916926] Dynamic subtest pipe-A-eDP-1: SUCCESS (10.356s)
[495.926553] Starting dynamic subtest: pipe-B-eDP-1
[507.005674] Dynamic subtest pipe-B-eDP-1: SUCCESS (11.086s)
[507.008021] Subtest psr2-cursor-plane-move-continuous-sf: SUCCESS (21.445s)
[507.817692] [047/133] (511s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-shrfb-pgflip-blt)
[507.921124] Starting subtest: fbcpsr-1p-primscrn-shrfb-pgflip-blt
[515.211622] Subtest fbcpsr-1p-primscrn-shrfb-pgflip-blt: SUCCESS (7.290s)
[515.389678] [048/133] (504s left) sysfs_timeslice_duration (invalid)
[515.451890] Starting subtest: invalid
[515.454294] Starting dynamic subtest: vecs0
[515.456683] Dynamic subtest vecs0: SUCCESS (0.000s)
[515.468055] Starting dynamic subtest: ccs0
[515.470341] Dynamic subtest ccs0: SUCCESS (0.000s)
[515.478645] Starting dynamic subtest: vcs0
[515.480839] Dynamic subtest vcs0: SUCCESS (0.000s)
[515.489063] Starting dynamic subtest: bcs0
[515.491336] Dynamic subtest bcs0: SUCCESS (0.000s)
[515.499367] Starting dynamic subtest: rcs0
[515.501766] Dynamic subtest rcs0: SUCCESS (0.000s)
[515.510102] Starting dynamic subtest: vcs1
[515.512337] Dynamic subtest vcs1: SUCCESS (0.000s)
[515.514670] Subtest invalid: SUCCESS (0.005s)
[515.580972] [049/133] (504s left) kms_cursor_legacy (cursora-vs-flipb-legacy)
[515.690787] Starting subtest: cursorA-vs-flipB-legacy
[515.695322] Subtest cursorA-vs-flipB-legacy: SKIP (0.001s)
[515.869863] [050/133] (504s left) kms_chamelium_hpd (dp-hpd-with-enabled-mode)
[516.251217] Subtest dp-hpd-with-enabled-mode: SKIP (0.000s)
[517.039308] [051/133] (502s left) gem_workarounds (reset-context)
[517.109167] Starting subtest: reset-context
[517.290033] Subtest reset-context: SUCCESS (0.178s)
[517.431878] [052/133] (502s left) kms_setmode (clone-exclusive-crtc)
[517.491729] Starting subtest: clone-exclusive-crtc
[517.499033] Subtest clone-exclusive-crtc: SKIP (0.000s)
[517.627266] [053/133] (502s left) gem_mmap_gtt (fault-concurrent)
[517.676746] Subtest fault-concurrent: SKIP (0.000s)
[517.747453] [054/133] (502s left) gem_ctx_persistence (hang)
[517.806100] Starting subtest: hang
[517.810854] Subtest hang: SKIP (0.000s)
[517.961708] [055/133] (502s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-blt)
[518.051756] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-blt
[518.055309] Subtest fbc-2p-scndscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[518.190569] [056/133] (502s left) kms_big_fb (x-tiled-32bpp-rotate-180)
[518.278461] Starting subtest: x-tiled-32bpp-rotate-180
[519.647861] Subtest x-tiled-32bpp-rotate-180: SUCCESS (1.368s)
[519.858006] [057/133] (500s left) kms_atomic (atomic-plane-damage)
[519.956875] Starting subtest: atomic-plane-damage
[520.008797] Starting dynamic subtest: pipe-A-eDP-1
[520.546817] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.540s)
[520.549003] Subtest atomic-plane-damage: SUCCESS (0.589s)
[521.400741] [058/133] (498s left) gem_create (create-clear)
[521.463473] Starting subtest: create-clear
[521.466604] Starting dynamic subtest: smem0
[554.436515] Dynamic subtest smem0: SUCCESS (32.965s)
[554.438812] Subtest create-clear: SUCCESS (32.967s)
[554.638500] [059/133] (465s left) kms_vblank (wait-busy-hang)
[554.740443] Starting subtest: wait-busy-hang
[554.742863] Starting dynamic subtest: pipe-A-eDP-1
[581.498766] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.751s)
[581.513769] Starting dynamic subtest: pipe-D-eDP-1
[606.440480] Dynamic subtest pipe-D-eDP-1: SUCCESS (24.938s)
[606.442725] Subtest wait-busy-hang: SUCCESS (51.694s)
[607.261019] [060/133] (413s left) kms_psr_stress_test (flip-primary-invalidate-overlay)
[607.366140] Starting subtest: flip-primary-invalidate-overlay
[618.986667] Subtest flip-primary-invalidate-overlay: SUCCESS (11.624s)
[619.157582] [061/133] (401s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-wc)
[619.270300] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-wc
[619.273784] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[619.390025] [062/133] (401s left) kms_cursor_legacy (cursora-vs-flipb-atomic-transitions)
[619.508653] Starting subtest: cursorA-vs-flipB-atomic-transitions
[619.512358] Subtest cursorA-vs-flipB-atomic-transitions: SKIP (0.001s)
[619.665561] [063/133] (400s left) kms_ccs (bad-pixel-format-4-tiled-mtl-rc-ccs)
[619.756220] Starting subtest: bad-pixel-format-4-tiled-mtl-rc-ccs
[619.758200] Starting dynamic subtest: pipe-A-eDP-1
[619.775030] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[619.785622] Starting dynamic subtest: pipe-B-eDP-1
[619.802022] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[619.810888] Starting dynamic subtest: pipe-C-eDP-1
[619.826302] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[619.835042] Starting dynamic subtest: pipe-D-eDP-1
[619.850872] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[619.852648] Subtest bad-pixel-format-4-tiled-mtl-rc-ccs: SUCCESS (0.005s)
[622.022330] [064/133] (398s left) kms_cursor_crc (cursor-sliding-128x128)
[622.268489] Starting subtest: cursor-sliding-128x128
[622.273595] Starting dynamic subtest: pipe-A-eDP-1
[625.485794] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.211s)
[625.518775] Starting dynamic subtest: pipe-D-eDP-1
[629.418265] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.913s)
[629.420658] Subtest cursor-sliding-128x128: SUCCESS (7.152s)
[630.299747] [065/133] (390s left) i915_pm_rpm (debugfs-forcewake-user)
[631.932526] Starting subtest: debugfs-forcewake-user
[643.307880] Subtest debugfs-forcewake-user: SUCCESS (11.380s)
[643.716142] [066/133] (376s left) kms_psr (pr-cursor-mmap-cpu)
[643.809651] Starting subtest: pr-cursor-mmap-cpu
[643.814080] Subtest pr-cursor-mmap-cpu: SKIP (0.000s)
[643.893952] [067/133] (376s left) kms_color_pipeline (plane-lut1d-ctm3x4)
[643.998008] Starting subtest: plane-lut1d-ctm3x4
[644.009173] Starting dynamic subtest: pipe-A-eDP-1
[644.699001] Dynamic subtest pipe-A-eDP-1: FAIL (0.309s)
[645.365688] Starting dynamic subtest: pipe-B-eDP-1
[646.168627] Dynamic subtest pipe-B-eDP-1: FAIL (0.298s)
[646.706819] Starting dynamic subtest: pipe-C-eDP-1
[647.530142] Dynamic subtest pipe-C-eDP-1: FAIL (0.246s)
[648.050637] Starting dynamic subtest: pipe-D-eDP-1
[648.898908] Dynamic subtest pipe-D-eDP-1: FAIL (0.268s)
[648.900921] Subtest plane-lut1d-ctm3x4: FAIL (4.603s)
[649.445890] [068/133] (371s left) gem_exec_reloc (basic-write-wc)
[649.500250] Subtest basic-write-wc: SKIP (0.000s)
[649.569393] [069/133] (371s left) kms_getfb (getfb2-handle-protection)
[649.655525] Starting subtest: getfb2-handle-protection
[649.680051] Subtest getfb2-handle-protection: SUCCESS (0.025s)
[649.823025] [070/133] (370s left) kms_flip (modeset-vs-vblank-race-interruptible)
[649.916666] Starting subtest: modeset-vs-vblank-race-interruptible
[649.918945] Starting dynamic subtest: A-eDP1
[654.629839] Dynamic subtest A-eDP1: SUCCESS (4.712s)
[654.645246] Starting dynamic subtest: B-eDP1
[659.249066] Dynamic subtest B-eDP1: SUCCESS (4.616s)
[659.261153] Starting dynamic subtest: C-eDP1
[663.698741] Dynamic subtest C-eDP1: SUCCESS (4.447s)
[663.709251] Starting dynamic subtest: D-eDP1
[668.336699] Dynamic subtest D-eDP1: SUCCESS (4.637s)
[668.339060] Subtest modeset-vs-vblank-race-interruptible: SUCCESS (18.422s)
[669.504370] [071/133] (351s left) gem_pxp (protected-encrypted-src-copy-not-readible)
[669.624487] Starting subtest: protected-encrypted-src-copy-not-readible
[669.739403] Subtest protected-encrypted-src-copy-not-readible: SUCCESS (0.115s)
[669.885679] [072/133] (350s left) kms_cursor_crc (cursor-offscreen-256x256)
[670.124564] Starting subtest: cursor-offscreen-256x256
[670.127007] Starting dynamic subtest: pipe-A-eDP-1
[672.062840] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.936s)
[672.089751] Starting dynamic subtest: pipe-D-eDP-1
[674.704270] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.621s)
[674.706980] Subtest cursor-offscreen-256x256: SUCCESS (4.582s)
[675.580771] [073/133] (345s left) gem_exec_reloc (basic-cpu-active)
[675.648991] Subtest basic-cpu-active: SKIP (0.000s)
[675.730944] [074/133] (345s left) gem_tiled_partial_pwrite_pread (writes-after-reads)
[675.789098] Subtest writes-after-reads: SKIP (0.000s)
[675.864993] [075/133] (345s left) gem_render_copy (yf-tiled-mc-ccs-to-vebox-y-tiled)
[675.916853] Starting subtest: yf-tiled-mc-ccs-to-vebox-y-tiled
[675.920861] Subtest yf-tiled-mc-ccs-to-vebox-y-tiled: SKIP (0.000s)
[676.110819] [076/133] (344s left) gem_exec_balancer (waits)
[676.163199] Starting subtest: waits
[682.658263] Subtest waits: SUCCESS (6.490s)
[682.970192] [077/133] (338s left) gem_partial_pwrite_pread (reads-uncached)
[683.035130] Subtest reads-uncached: SKIP (0.000s)
[683.099856] [078/133] (337s left) kms_big_fb (y-tiled-32bpp-rotate-270)
[683.206607] Starting subtest: y-tiled-32bpp-rotate-270
[683.210830] Subtest y-tiled-32bpp-rotate-270: SKIP (0.000s)
[683.328650] [079/133] (337s left) kms_busy (basic-hang)
[683.427657] Starting subtest: basic-hang
[683.429897] Starting dynamic subtest: flip-pipe-A
[684.003938] Dynamic subtest flip-pipe-A: SUCCESS (0.574s)
[684.013867] Starting dynamic subtest: modeset-pipe-A
[687.617021] Dynamic subtest modeset-pipe-A: SUCCESS (3.614s)
[687.627615] Starting dynamic subtest: flip-pipe-D
[688.815811] Dynamic subtest flip-pipe-D: SUCCESS (1.194s)
[688.825943] Starting dynamic subtest: modeset-pipe-D
[692.259510] Dynamic subtest modeset-pipe-D: SUCCESS (3.442s)
[692.261926] Subtest basic-hang: SUCCESS (8.831s)
[693.072057] [080/133] (328s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-shrfb-pgflip-blt)
[693.169084] Starting subtest: fbc-2p-scndscrn-shrfb-pgflip-blt
[693.173422] Subtest fbc-2p-scndscrn-shrfb-pgflip-blt: SKIP (0.000s)
[693.292596] [081/133] (327s left) kms_pm_dc (dc6-dpms)
[694.388613] Starting subtest: dc6-dpms
[696.440395] Subtest dc6-dpms: SUCCESS (2.051s)
[696.526766] [082/133] (324s left) gem_exec_params (rsvd2-dirt)
[696.592020] Starting subtest: rsvd2-dirt
[696.596767] Subtest rsvd2-dirt: SKIP (0.000s)
[696.716638] [083/133] (324s left) kms_vblank (invalid)
[696.820289] Starting subtest: invalid
[696.822404] Starting dynamic subtest: pipe-A-eDP-1
[697.220171] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.397s)
[697.222624] Subtest invalid: SUCCESS (0.400s)
[698.043231] [084/133] (323s left) kms_frontbuffer_tracking (fbcpsr-1p-pri-indfb-multidraw)
[698.150062] Starting subtest: fbcpsr-1p-pri-indfb-multidraw
[720.668632] Subtest fbcpsr-1p-pri-indfb-multidraw: SUCCESS (22.520s)
[720.808018] [085/133] (300s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite)
[720.914852] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite
[720.918435] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[721.044588] [086/133] (300s left) perf (short-reads)
[721.162891] Starting subtest: short-reads
[721.177034] Subtest short-reads: SUCCESS (0.015s)
[721.216785] [087/133] (300s left) kms_flip (2x-flip-vs-dpms-off-vs-modeset)
[721.319329] Starting subtest: 2x-flip-vs-dpms-off-vs-modeset
[721.328772] Subtest 2x-flip-vs-dpms-off-vs-modeset: SKIP (0.010s)
[721.463683] [088/133] (299s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-cpu)
[721.562473] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-cpu
[721.566041] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[721.692937] [089/133] (299s left) syncobj_timeline (multi-wait-submitted-signaled)
[721.754199] Starting subtest: multi-wait-submitted-signaled
[721.756006] Subtest multi-wait-submitted-signaled: SUCCESS (0.000s)
[721.876840] [090/133] (299s left) gem_render_copy (y-tiled-ccs-to-y-tiled)
[721.938019] Starting subtest: y-tiled-ccs-to-y-tiled
[721.941974] Subtest y-tiled-ccs-to-y-tiled: SKIP (0.000s)
[722.132703] [091/133] (299s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-rc-ccs)
[722.225727] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-rc-ccs
[722.227882] Starting dynamic subtest: pipe-A-eDP-1
[723.089692] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.859s)
[723.099992] Starting dynamic subtest: pipe-B-eDP-1
[724.627776] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.533s)
[724.638748] Starting dynamic subtest: pipe-C-eDP-1
[726.091902] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.462s)
[726.101669] Starting dynamic subtest: pipe-D-eDP-1
[727.643148] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.550s)
[727.645330] Subtest crc-primary-rotation-180-4-tiled-mtl-rc-ccs: SUCCESS (5.410s)
[728.796936] [092/133] (292s left) vgem_basic (sysfs)
[728.921232] Starting subtest: sysfs
[728.923606] Subtest sysfs: SUCCESS (0.000s)
[728.982147] [FACT vgem_basic (sysfs)] new: kernel.kmod_is_loaded.vgem: true
[728.985587] [093/133] (292s left) gem_mmap_gtt (cpuset-big-copy-odd)
[729.041675] Subtest cpuset-big-copy-odd: SKIP (0.000s)
[729.109201] [094/133] (292s left) kms_psr (psr2-sprite-plane-move)
[729.208988] Starting subtest: psr2-sprite-plane-move
[729.211319] Starting dynamic subtest: eDP-1
[730.905007] Dynamic subtest eDP-1: SUCCESS (1.697s)
[730.907234] Subtest psr2-sprite-plane-move: SUCCESS (1.697s)
[731.751031] [095/133] (289s left) kms_psr (fbc-psr-primary-page-flip)
[731.849676] Starting subtest: fbc-psr-primary-page-flip
[731.851885] Starting dynamic subtest: eDP-1
[731.855896] Dynamic subtest eDP-1: SKIP (0.000s)
[731.859949] Subtest fbc-psr-primary-page-flip: SKIP (0.001s)
[731.934463] [096/133] (289s left) gem_exec_schedule (promotion)
[732.000448] Starting subtest: promotion
[732.002735] Starting dynamic subtest: rcs0
[732.095851] Dynamic subtest rcs0: SUCCESS (0.102s)
[732.108203] Starting dynamic subtest: vcs0
[732.179125] Dynamic subtest vcs0: SUCCESS (0.083s)
[732.188373] Starting dynamic subtest: vcs1
[732.277953] Dynamic subtest vcs1: SUCCESS (0.098s)
[732.288063] Starting dynamic subtest: vecs0
[732.365454] Dynamic subtest vecs0: SUCCESS (0.085s)
[732.380826] Starting dynamic subtest: ccs0
[732.470080] Dynamic subtest ccs0: SUCCESS (0.103s)
[732.472631] Subtest promotion: SUCCESS (0.477s)
[733.873908] [097/133] (287s left) gem_exec_schedule (preempt-queue-contexts)
[733.957359] Subtest preempt-queue-contexts: SKIP (0.000s)
[734.255967] [098/133] (287s left) kms_cursor_legacy (forked-move)
[734.369666] Starting subtest: forked-move
[734.371938] Starting dynamic subtest: pipe-A
[739.818491] Dynamic subtest pipe-A: SUCCESS (5.445s)
[739.831007] Starting dynamic subtest: pipe-B
[745.288235] Dynamic subtest pipe-B: SUCCESS (5.466s)
[745.300258] Starting dynamic subtest: pipe-C
[750.754936] Dynamic subtest pipe-C: SUCCESS (5.464s)
[750.766986] Starting dynamic subtest: pipe-D
[756.226466] Dynamic subtest pipe-D: SUCCESS (5.468s)
[756.238611] Starting dynamic subtest: all-pipes
[761.737427] Dynamic subtest all-pipes: SUCCESS (5.507s)
[761.739907] Subtest forked-move: SUCCESS (27.361s)
[761.908177] [099/133] (259s left) gem_ctx_isolation (preservation)
[761.993042] Starting subtest: preservation
[761.995604] Starting dynamic subtest: rcs0
[762.150173] Dynamic subtest rcs0: SUCCESS (0.157s)
[762.162655] Starting dynamic subtest: bcs0
[762.322751] Dynamic subtest bcs0: SUCCESS (0.172s)
[762.335047] Starting dynamic subtest: vcs0
[762.506831] Dynamic subtest vcs0: SUCCESS (0.183s)
[762.519458] Starting dynamic subtest: vcs1
[763.782580] Dynamic subtest vcs1: SUCCESS (1.274s)
[763.795332] Starting dynamic subtest: vecs0
[765.915860] Dynamic subtest vecs0: SUCCESS (2.132s)
[765.928324] Starting dynamic subtest: ccs0
[767.885597] Dynamic subtest ccs0: SUCCESS (1.968s)
[767.889015] Subtest preservation: SUCCESS (5.891s)
[768.173942] [100/133] (253s left) kms_content_protection (dp-mst-type-1)
[768.325656] Starting subtest: dp-mst-type-1
[768.330143] Subtest dp-mst-type-1: SKIP (0.000s)
[768.479143] [101/133] (253s left) kms_flip (blocking-wf_vblank)
[768.575111] Starting subtest: blocking-wf_vblank
[768.577380] Starting dynamic subtest: A-eDP1
[777.490046] Dynamic subtest A-eDP1: SUCCESS (8.915s)
[777.501630] Starting dynamic subtest: B-eDP1
[786.426237] Dynamic subtest B-eDP1: SUCCESS (8.933s)
[786.442523] Starting dynamic subtest: C-eDP1
[795.343814] Dynamic subtest C-eDP1: SUCCESS (8.913s)
[795.354616] Starting dynamic subtest: D-eDP1
[804.266748] Dynamic subtest D-eDP1: SUCCESS (8.921s)
[804.272193] Subtest blocking-wf_vblank: SUCCESS (35.691s)
[805.504335] [102/133] (216s left) i915_getparams_basic (basic-subslice-total)
[805.552517] Starting subtest: basic-subslice-total
[805.556848] Subtest basic-subslice-total: SUCCESS (0.000s)
[805.664467] [103/133] (216s left) kms_psr (fbc-pr-cursor-mmap-gtt)
[805.762896] Starting subtest: fbc-pr-cursor-mmap-gtt
[805.767268] Subtest fbc-pr-cursor-mmap-gtt: SKIP (0.000s)
[805.854410] [104/133] (216s left) gem_mmap_wc (bad-size)
[805.914344] Subtest bad-size: SKIP (0.000s)
[805.981099] [105/133] (216s left) kms_frontbuffer_tracking (fbcpsr-tiling-y)
[806.076233] Starting subtest: fbcpsr-tiling-y
[806.080378] Subtest fbcpsr-tiling-y: SKIP (0.000s)
[806.185128] [106/133] (215s left) kms_frontbuffer_tracking (fbcpsr-indfb-scaledprimary)
[806.279022] Starting subtest: fbcpsr-indfb-scaledprimary
[812.101791] Subtest fbcpsr-indfb-scaledprimary: SUCCESS (5.824s)
[812.237426] [107/133] (209s left) kms_joiner (invalid-modeset-force-ultra-joiner)
[812.340750] Starting subtest: invalid-modeset-force-ultra-joiner
[812.345095] Subtest invalid-modeset-force-ultra-joiner: SKIP (0.000s)
[812.418988] [108/133] (209s left) prime_vgem (basic-fence-blt)
[812.503260] Starting subtest: basic-fence-blt
[812.582557] Subtest basic-fence-blt: SUCCESS (0.079s)
[812.745991] [109/133] (209s left) i915_drm_fdinfo (most-busy-check-all)
[812.785743] Starting subtest: most-busy-check-all
[812.788061] Starting dynamic subtest: rcs0
[812.792732] Dynamic subtest rcs0: SKIP (0.003s)
[812.804594] Starting dynamic subtest: bcs0
[812.809189] Dynamic subtest bcs0: SKIP (0.000s)
[812.818811] Starting dynamic subtest: vcs0
[812.822724] Dynamic subtest vcs0: SKIP (0.000s)
[812.831683] Starting dynamic subtest: vcs1
[812.835768] Dynamic subtest vcs1: SKIP (0.000s)
[812.844853] Starting dynamic subtest: vecs0
[812.848825] Dynamic subtest vecs0: SKIP (0.000s)
[812.857988] Starting dynamic subtest: ccs0
[812.862021] Dynamic subtest ccs0: SKIP (0.000s)
[812.866070] Subtest most-busy-check-all: SKIP (0.010s)
[812.906768] [110/133] (209s left) gem_exec_schedule (fairslice)
[813.021178] Starting subtest: fairslice
[813.023182] Starting dynamic subtest: rcs0
[815.037788] Dynamic subtest rcs0: SUCCESS (2.021s)
[815.049021] Starting dynamic subtest: bcs0
[817.061576] Dynamic subtest bcs0: SUCCESS (2.023s)
[817.073183] Starting dynamic subtest: vcs0
[819.084360] Dynamic subtest vcs0: SUCCESS (2.020s)
[819.094774] Starting dynamic subtest: vcs1
[821.103856] Dynamic subtest vcs1: SUCCESS (2.018s)
[821.113643] Starting dynamic subtest: vecs0
[823.120840] Dynamic subtest vecs0: SUCCESS (2.019s)
[823.133297] Starting dynamic subtest: ccs0
[825.147761] Dynamic subtest ccs0: SUCCESS (2.025s)
[825.149585] Subtest fairslice: SUCCESS (12.131s)
[825.451383] [111/133] (196s left) perf_pmu (event-wait)
[825.493783] Starting subtest: event-wait
[825.495957] Starting dynamic subtest: rcs0
[825.499869] Dynamic subtest rcs0: SKIP (0.000s)
[825.503597] Subtest event-wait: SKIP (0.001s)
[825.610646] [112/133] (196s left) kms_plane (pixel-format-y-tiled-ccs-modifier)
[825.714310] Starting subtest: pixel-format-y-tiled-ccs-modifier
[825.726846] Subtest pixel-format-y-tiled-ccs-modifier: SKIP (0.000s)
[825.798209] [113/133] (196s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-mmap-gtt)
[825.889984] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-mmap-gtt
[825.894001] Subtest fbc-1p-offscreen-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[825.989413] [114/133] (196s left) kms_rotation_crc (primary-y-tiled-reflect-x-0)
[826.080340] Starting subtest: primary-y-tiled-reflect-x-0
[826.140829] Subtest primary-y-tiled-reflect-x-0: SKIP (0.059s)
[826.252833] [115/133] (196s left) kms_properties (invalid-properties-atomic)
[826.344790] Starting subtest: invalid-properties-atomic
[826.640620] Subtest invalid-properties-atomic: SUCCESS (0.295s)
[826.730511] [116/133] (195s left) gem_ctx_create (basic)
[826.795902] Starting subtest: basic
[826.798332] Subtest basic: SUCCESS (0.000s)
[826.988029] [117/133] (195s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-mmap-gtt)
[827.088371] Starting subtest: psr-2p-primscrn-cur-indfb-draw-mmap-gtt
[827.092728] Subtest psr-2p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[827.206156] [118/133] (195s left) kms_flip (2x-busy-flip)
[827.299999] Starting subtest: 2x-busy-flip
[827.311160] Subtest 2x-busy-flip: SKIP (0.011s)
[827.427672] [119/133] (195s left) perf (invalid-oa-exponent)
[827.538084] Starting subtest: invalid-oa-exponent
[827.551834] Subtest invalid-oa-exponent: SUCCESS (0.011s)
[827.591453] [120/133] (195s left) kms_vrr (max-min)
[827.697726] Starting subtest: max-min
[827.703865] Subtest max-min: SKIP (0.000s)
[827.792972] [121/133] (194s left) kms_lease (implicit-plane-lease)
[827.886486] Starting subtest: implicit-plane-lease
[827.888792] Subtest implicit-plane-lease: SUCCESS (0.003s)
[828.009177] [122/133] (194s left) kms_pm_rpm (i2c)
[829.931976] Starting subtest: i2c
[835.204383] Subtest i2c: SUCCESS (5.272s)
[835.304242] [123/133] (187s left) core_getversion (all-cards)
[835.342890] Starting subtest: all-cards
[835.349922] Subtest all-cards: SUCCESS (0.011s)
[835.392406] [124/133] (187s left) kms_flip (nonblocking-read)
[835.488083] Starting subtest: nonblocking-read
[835.490300] Subtest nonblocking-read: SUCCESS (0.000s)
[835.611161] [125/133] (187s left) kms_psr (fbc-pr-primary-render)
[835.706617] Starting subtest: fbc-pr-primary-render
[835.710635] Subtest fbc-pr-primary-render: SKIP (0.000s)
[835.802554] [126/133] (187s left) gem_mmap_wc (coherency)
[835.866866] Subtest coherency: SKIP (0.000s)
[835.936633] [127/133] (187s left) kms_ccs (crc-sprite-planes-basic-4-tiled-lnl-ccs)
[836.052195] Starting subtest: crc-sprite-planes-basic-4-tiled-lnl-ccs
[836.056911] Subtest crc-sprite-planes-basic-4-tiled-lnl-ccs: SKIP (0.000s)
[837.260436] [128/133] (185s left) gem_exec_fence (basic-wait-all)
[837.326112] Starting subtest: basic-wait-all
[837.435561] Subtest basic-wait-all: SUCCESS (0.110s)
[839.854186] [129/133] (183s left) api_intel_bb (full-batch)
[839.926706] Starting subtest: full-batch
[839.939117] Subtest full-batch: SUCCESS (0.012s)
[840.094684] [130/133] (183s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-blt)
[840.204790] Starting subtest: psr-2p-primscrn-pri-indfb-draw-blt
[840.209095] Subtest psr-2p-primscrn-pri-indfb-draw-blt: SKIP (0.000s)
[840.326387] [131/133] (182s left) kms_ccs (crc-primary-basic-4-tiled-mtl-mc-ccs)
[840.416176] Starting subtest: crc-primary-basic-4-tiled-mtl-mc-ccs
[840.418028] Starting dynamic subtest: pipe-A-eDP-1
[842.562068] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.145s)
[842.573653] Starting dynamic subtest: pipe-B-eDP-1
[844.667060] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.103s)
[844.676864] Starting dynamic subtest: pipe-C-eDP-1
[846.657743] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.990s)
[846.667704] Starting dynamic subtest: pipe-D-eDP-1
[848.734657] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.072s)
[848.736899] Subtest crc-primary-basic-4-tiled-mtl-mc-ccs: SUCCESS (8.317s)
[849.967447] [132/133] (173s left) gem_exec_fence (syncobj-signal)
[850.375001] Starting subtest: syncobj-signal
[850.376912] Subtest syncobj-signal: SUCCESS (0.005s)
[851.784323] [133/133] (171s left) syncobj_wait (wait-for-submit-delayed-submit)
[851.857751] Starting subtest: wait-for-submit-delayed-submit
[851.959925] Subtest wait-for-submit-delayed-submit: SUCCESS (0.102s)
[852.049371] Closing watchdogs
results: parsing output: 0/ for test: syncobj_timeline
results: parsing output: 1/ for test: kms_draw_crc
results: parsing output: 2/ for test: api_intel_allocator
results: parsing output: 3/ for test: kms_frontbuffer_tracking
results: parsing output: 4/ for test: syncobj_timeline
results: parsing output: 5/ for test: kms_frontbuffer_tracking
results: parsing output: 6/ for test: kms_plane_scaling
results: parsing output: 7/ for test: kms_big_fb
results: parsing output: 8/ for test: i915_selftest
results: parsing output: 9/ for test: kms_psr2_sf
results: parsing output: 10/ for test: kms_cursor_legacy
results: parsing output: 11/ for test: kms_plane_scaling
results: parsing output: 12/ for test: kms_plane_multiple
results: parsing output: 13/ for test: syncobj_timeline
results: parsing output: 14/ for test: kms_flip_scaled_crc
results: parsing output: 15/ for test: syncobj_timeline
results: parsing output: 16/ for test: gem_userptr_blits
results: parsing output: 17/ for test: gem_ctx_shared
results: parsing output: 18/ for test: kms_frontbuffer_tracking
results: parsing output: 19/ for test: kms_big_fb
results: parsing output: 20/ for test: gem_ctx_param
results: parsing output: 21/ for test: kms_chamelium_color
results: parsing output: 22/ for test: kms_flip
results: parsing output: 23/ for test: kms_cursor_edge_walk
results: parsing output: 24/ for test: kms_atomic_transition
results: parsing output: 25/ for test: kms_pipe_crc_basic
results: parsing output: 26/ for test: kms_addfb_basic
results: parsing output: 27/ for test: fbdev
results: parsing output: 28/ for test: kms_cursor_legacy
results: parsing output: 29/ for test: syncobj_wait
results: parsing output: 30/ for test: gem_spin_batch
results: parsing output: 31/ for test: kms_big_fb
results: parsing output: 32/ for test: gem_lmem_swapping
results: parsing output: 33/ for test: gem_mmap_gtt
results: parsing output: 34/ for test: gem_exec_whisper
results: parsing output: 35/ for test: gem_close
results: parsing output: 36/ for test: gen9_exec_parse
results: parsing output: 37/ for test: gem_exec_balancer
results: parsing output: 38/ for test: kms_addfb_basic
results: parsing output: 39/ for test: kms_flip_scaled_crc
results: parsing output: 40/ for test: kms_big_fb
results: parsing output: 41/ for test: kms_frontbuffer_tracking
results: parsing output: 42/ for test: kms_frontbuffer_tracking
results: parsing output: 43/ for test: kms_chamelium_frames
results: parsing output: 44/ for test: kms_flip
results: parsing output: 45/ for test: kms_psr2_sf
results: parsing output: 46/ for test: kms_frontbuffer_tracking
results: parsing output: 47/ for test: sysfs_timeslice_duration
results: parsing output: 48/ for test: kms_cursor_legacy
results: parsing output: 49/ for test: kms_chamelium_hpd
results: parsing output: 50/ for test: gem_workarounds
results: parsing output: 51/ for test: kms_setmode
results: parsing output: 52/ for test: gem_mmap_gtt
results: parsing output: 53/ for test: gem_ctx_persistence
results: parsing output: 54/ for test: kms_frontbuffer_tracking
results: parsing output: 55/ for test: kms_big_fb
results: parsing output: 56/ for test: kms_atomic
results: parsing output: 57/ for test: gem_create
results: parsing output: 58/ for test: kms_vblank
results: parsing output: 59/ for test: kms_psr_stress_test
results: parsing output: 60/ for test: kms_frontbuffer_tracking
results: parsing output: 61/ for test: kms_cursor_legacy
results: parsing output: 62/ for test: kms_ccs
results: parsing output: 63/ for test: kms_cursor_crc
results: parsing output: 64/ for test: i915_pm_rpm
results: parsing output: 65/ for test: kms_psr
results: parsing output: 66/ for test: kms_color_pipeline
results: parsing output: 67/ for test: gem_exec_reloc
results: parsing output: 68/ for test: kms_getfb
results: parsing output: 69/ for test: kms_flip
results: parsing output: 70/ for test: gem_pxp
results: parsing output: 71/ for test: kms_cursor_crc
results: parsing output: 72/ for test: gem_exec_reloc
results: parsing output: 73/ for test: gem_tiled_partial_pwrite_pread
results: parsing output: 74/ for test: gem_render_copy
results: parsing output: 75/ for test: gem_exec_balancer
results: parsing output: 76/ for test: gem_partial_pwrite_pread
results: parsing output: 77/ for test: kms_big_fb
results: parsing output: 78/ for test: kms_busy
results: parsing output: 79/ for test: kms_frontbuffer_tracking
results: parsing output: 80/ for test: kms_pm_dc
results: parsing output: 81/ for test: gem_exec_params
results: parsing output: 82/ for test: kms_vblank
results: parsing output: 83/ for test: kms_frontbuffer_tracking
results: parsing output: 84/ for test: kms_frontbuffer_tracking
results: parsing output: 85/ for test: perf
results: parsing output: 86/ for test: kms_flip
results: parsing output: 87/ for test: kms_frontbuffer_tracking
results: parsing output: 88/ for test: syncobj_timeline
results: parsing output: 89/ for test: gem_render_copy
results: parsing output: 90/ for test: kms_ccs
results: parsing output: 91/ for test: vgem_basic
results: parsing output: 92/ for test: gem_mmap_gtt
results: parsing output: 93/ for test: kms_psr
results: parsing output: 94/ for test: kms_psr
results: parsing output: 95/ for test: gem_exec_schedule
results: parsing output: 96/ for test: gem_exec_schedule
results: parsing output: 97/ for test: kms_cursor_legacy
results: parsing output: 98/ for test: gem_ctx_isolation
results: parsing output: 99/ for test: kms_content_protection
results: parsing output: 100/ for test: kms_flip
results: parsing output: 101/ for test: i915_getparams_basic
results: parsing output: 102/ for test: kms_psr
results: parsing output: 103/ for test: gem_mmap_wc
results: parsing output: 104/ for test: kms_frontbuffer_tracking
results: parsing output: 105/ for test: kms_frontbuffer_tracking
results: parsing output: 106/ for test: kms_joiner
results: parsing output: 107/ for test: prime_vgem
results: parsing output: 108/ for test: i915_drm_fdinfo
results: parsing output: 109/ for test: gem_exec_schedule
results: parsing output: 110/ for test: perf_pmu
results: parsing output: 111/ for test: kms_plane
results: parsing output: 112/ for test: kms_frontbuffer_tracking
results: parsing output: 113/ for test: kms_rotation_crc
results: parsing output: 114/ for test: kms_properties
results: parsing output: 115/ for test: gem_ctx_create
results: parsing output: 116/ for test: kms_frontbuffer_tracking
results: parsing output: 117/ for test: kms_flip
results: parsing output: 118/ for test: perf
results: parsing output: 119/ for test: kms_vrr
results: parsing output: 120/ for test: kms_lease
results: parsing output: 121/ for test: kms_pm_rpm
results: parsing output: 122/ for test: core_getversion
results: parsing output: 123/ for test: kms_flip
results: parsing output: 124/ for test: kms_psr
results: parsing output: 125/ for test: gem_mmap_wc
results: parsing output: 126/ for test: kms_ccs
results: parsing output: 127/ for test: gem_exec_fence
results: parsing output: 128/ for test: api_intel_bb
results: parsing output: 129/ for test: kms_frontbuffer_tracking
results: parsing output: 130/ for test: kms_ccs
results: parsing output: 131/ for test: gem_exec_fence
results: parsing output: 132/ for test: syncobj_wait
Done.