[56.959915] Initializing watchdogs
[56.959961]   /dev/watchdog0
[56.974068] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics]
[56.978445] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[56.980903] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[56.984479] [001/155] (960s left) kms_cursor_legacy (cursora-vs-flipa-atomic-transitions-varying-size)
[57.140688] Starting subtest: cursorA-vs-flipA-atomic-transitions-varying-size
[68.378238] Subtest cursorA-vs-flipA-atomic-transitions-varying-size: SUCCESS (11.236s)
[69.227251] [002/155] (948s left) kms_content_protection (dp-mst-type-1-suspend-resume)
[69.377838] Starting subtest: dp-mst-type-1-suspend-resume
[69.382515] Subtest dp-mst-type-1-suspend-resume: SKIP (0.000s)
[69.517489] [003/155] (948s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-pri-indfb-draw-blt)
[69.611392] Starting subtest: hdr-2p-scndscrn-pri-indfb-draw-blt
[69.615446] Subtest hdr-2p-scndscrn-pri-indfb-draw-blt: SKIP (0.000s)
[69.709643] [004/155] (947s left) kms_psr (fbc-psr2-cursor-mmap-cpu)
[69.796209] Starting subtest: fbc-psr2-cursor-mmap-cpu
[69.798087] Starting dynamic subtest: eDP-1
[69.802142] Dynamic subtest eDP-1: SKIP (0.000s)
[69.806240] Subtest fbc-psr2-cursor-mmap-cpu: SKIP (0.001s)
[69.868136] [005/155] (947s left) gem_softpin (full)
[69.927304] Starting subtest: full
[69.935733] Subtest full: SUCCESS (0.008s)
[70.053915] [006/155] (947s left) kms_big_fb (linear-max-hw-stride-64bpp-rotate-0)
[70.158635] Starting subtest: linear-max-hw-stride-64bpp-rotate-0
[71.203063] Subtest linear-max-hw-stride-64bpp-rotate-0: SUCCESS (1.045s)
[71.324643] [007/155] (946s left) syncobj_wait (invalid-single-wait-unsubmitted)
[71.389421] Starting subtest: invalid-single-wait-unsubmitted
[71.391833] Subtest invalid-single-wait-unsubmitted: SUCCESS (0.000s)
[71.496753] [008/155] (946s left) kms_getfb (getfb-handle-protection)
[71.592076] Starting subtest: getfb-handle-protection
[71.615956] Subtest getfb-handle-protection: SUCCESS (0.025s)
[71.753123] [009/155] (946s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-onoff)
[71.845121] Starting subtest: fbc-2p-primscrn-cur-indfb-onoff
[71.848603] Subtest fbc-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[71.937793] [010/155] (945s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-spr-indfb-draw-mmap-cpu)
[72.043231] Starting subtest: fbchdr-2p-primscrn-spr-indfb-draw-mmap-cpu
[72.046685] Subtest fbchdr-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[72.151684] [011/155] (945s left) kms_prop_blob (invalid-set-prop)
[72.257469] Starting subtest: invalid-set-prop
[72.259537] Subtest invalid-set-prop: SUCCESS (0.000s)
[72.430709] [012/155] (945s left) kms_chamelium_hpd (common-hpd-after-suspend)
[72.983325] Subtest common-hpd-after-suspend: SKIP (0.000s)
[73.835171] [013/155] (944s left) kms_pm_rpm (fences)
[75.469276] Starting subtest: fences
[75.474147] Subtest fences: SKIP (0.000s)
[76.099974] [014/155] (941s left) i915_fb_tiling (basic-x-tiling)
[76.156537] Subtest basic-x-tiling: SKIP (0.000s)
[76.223566] [015/155] (941s left) gem_render_copy (y-tiled-to-vebox-x-tiled)
[76.269085] Starting subtest: y-tiled-to-vebox-x-tiled
[76.273597] Subtest y-tiled-to-vebox-x-tiled: SKIP (0.000s)
[76.449461] [016/155] (941s left) kms_frontbuffer_tracking (psr-indfb-scaledprimary)
[76.551990] Starting subtest: psr-indfb-scaledprimary
[82.325058] Subtest psr-indfb-scaledprimary: SUCCESS (5.782s)
[82.435103] [017/155] (935s left) kms_fbcon_fbt (psr-suspend)
[82.488324] Starting subtest: psr-suspend
[132.205459] Subtest psr-suspend: SUCCESS (20.050s)
[132.273915] [018/155] (885s left) gem_userptr_blits (map-fixed-invalidate-busy)
[132.341284] Starting subtest: map-fixed-invalidate-busy
[132.345623] Subtest map-fixed-invalidate-busy: SKIP (0.000s)
[132.447664] [019/155] (885s left) gem_exec_fence (syncobj-timeline-unused-fence)
[132.997214] Starting subtest: syncobj-timeline-unused-fence
[133.002968] Subtest syncobj-timeline-unused-fence: SUCCESS (0.006s)
[134.088465] [FACT gem_exec_fence (syncobj-timeline-unused-fence)] new: kernel.kmod_is_loaded.vgem: true
[134.092121] [020/155] (884s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-cur-indfb-onoff)
[134.196699] Starting subtest: psrhdr-2p-primscrn-cur-indfb-onoff
[134.201196] Subtest psrhdr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[134.326224] [021/155] (883s left) kms_plane_scaling (plane-downscale-factor-0-75-with-pixel-format)
[134.419772] Starting subtest: plane-downscale-factor-0-75-with-pixel-format
[134.422071] Starting dynamic subtest: pipe-A
[135.552066] Dynamic subtest pipe-A: SUCCESS (1.140s)
[135.562175] Starting dynamic subtest: pipe-B
[137.839746] Dynamic subtest pipe-B: SUCCESS (2.297s)
[137.849364] Starting dynamic subtest: pipe-C
[139.014930] Dynamic subtest pipe-C: SUCCESS (1.178s)
[139.024288] Starting dynamic subtest: pipe-D
[140.251475] Dynamic subtest pipe-D: SUCCESS (1.240s)
[140.253735] Subtest plane-downscale-factor-0-75-with-pixel-format: SUCCESS (5.858s)
[141.443937] [022/155] (876s left) kms_frontbuffer_tracking (hdr-2p-pri-indfb-multidraw)
[141.543000] Starting subtest: hdr-2p-pri-indfb-multidraw
[141.547149] Subtest hdr-2p-pri-indfb-multidraw: SKIP (0.000s)
[141.650706] [023/155] (876s left) syncobj_timeline (multi-wait-all-for-submit-available-submitted-signaled)
[141.697848] Starting subtest: multi-wait-all-for-submit-available-submitted-signaled
[141.700199] Subtest multi-wait-all-for-submit-available-submitted-signaled: SUCCESS (0.001s)
[141.796712] [024/155] (876s left) kms_frontbuffer_tracking (fbchdr-1p-offscreen-pri-indfb-draw-pwrite)
[141.893197] Starting subtest: fbchdr-1p-offscreen-pri-indfb-draw-pwrite
[141.897277] Subtest fbchdr-1p-offscreen-pri-indfb-draw-pwrite: SKIP (0.000s)
[141.985996] [025/155] (876s left) sw_sync (timeline_closed_signaled)
[142.019845] Starting subtest: timeline_closed_signaled
[142.021974] Subtest timeline_closed_signaled: SUCCESS (0.000s)
[142.070904] [026/155] (876s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-cur-indfb-draw-render)
[142.172710] Starting subtest: fbchdr-2p-primscrn-cur-indfb-draw-render
[142.176942] Subtest fbchdr-2p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[142.256395] [027/155] (876s left) gem_busy (close-race)
[142.316487] Starting subtest: close-race
[165.979214] Subtest close-race: SUCCESS (23.687s)
[166.357213] [028/155] (852s left) prime_mmap (test_reprime)
[166.453308] Starting subtest: test_reprime
[166.455299] Starting dynamic subtest: test_reprime-smem
[166.483060] Dynamic subtest test_reprime-smem: SUCCESS (0.030s)
[166.485466] Subtest test_reprime: SUCCESS (0.031s)
[166.585614] [029/155] (852s left) kms_frontbuffer_tracking (hdr-2p-primscrn-pri-shrfb-draw-render)
[166.685179] Starting subtest: hdr-2p-primscrn-pri-shrfb-draw-render
[166.689437] Subtest hdr-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[166.820564] [030/155] (851s left) gem_exec_schedule (manyslice)
[166.900593] Starting subtest: manyslice
[166.902512] Starting dynamic subtest: rcs0
[171.498190] Dynamic subtest rcs0: SUCCESS (4.608s)
[171.514996] Starting dynamic subtest: bcs0
[178.118738] Dynamic subtest bcs0: SUCCESS (6.621s)
[178.128952] Starting dynamic subtest: vcs0
[183.694662] Dynamic subtest vcs0: SUCCESS (5.578s)
[183.708164] Starting dynamic subtest: vcs1
[189.783182] Dynamic subtest vcs1: SUCCESS (6.090s)
[189.795902] Starting dynamic subtest: vecs0
[195.868573] Dynamic subtest vecs0: SUCCESS (6.086s)
[195.879339] Starting dynamic subtest: ccs0
[201.516299] Dynamic subtest ccs0: SUCCESS (5.649s)
[201.518966] Subtest manyslice: SUCCESS (34.642s)
[201.822278] [031/155] (816s left) sw_sync (sync_expired_merge)
[201.855931] Starting subtest: sync_expired_merge
[202.928677] Subtest sync_expired_merge: SUCCESS (1.074s)
[202.992917] [032/155] (815s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-render)
[203.098581] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-render
[203.103393] Subtest fbc-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[203.206337] [033/155] (815s left) syncobj_timeline (single-wait-for-submit-signaled)
[203.264146] Starting subtest: single-wait-for-submit-signaled
[203.266371] Subtest single-wait-for-submit-signaled: SUCCESS (0.000s)
[203.363124] [034/155] (815s left) api_intel_bb (reset-bb)
[203.413592] Starting subtest: reset-bb
[203.416040] Subtest reset-bb: SUCCESS (0.004s)
[203.539918] [035/155] (815s left) gem_ctx_param (invalid-ctx-get)
[203.593561] Starting subtest: invalid-ctx-get
[203.595833] Subtest invalid-ctx-get: SUCCESS (0.000s)
[203.676244] [036/155] (815s left) syncobj_basic (bad-fd-to-handle)
[203.726658] Starting subtest: bad-fd-to-handle
[203.728925] Subtest bad-fd-to-handle: SUCCESS (0.000s)
[203.831765] [037/155] (815s left) gem_partial_pwrite_pread (reads)
[203.890297] Subtest reads: SKIP (0.000s)
[203.955733] [038/155] (815s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-spr-indfb-move)
[204.048157] Starting subtest: fbcpsrhdr-2p-primscrn-spr-indfb-move
[204.051704] Subtest fbcpsrhdr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[204.160620] [039/155] (814s left) gem_exec_reloc (basic-gtt)
[204.213545] Subtest basic-gtt: SKIP (0.000s)
[204.275020] [040/155] (814s left) i915_drm_fdinfo (most-busy-check-all)
[204.314857] Starting subtest: most-busy-check-all
[204.316857] Starting dynamic subtest: rcs0
[204.321133] Dynamic subtest rcs0: SKIP (0.004s)
[204.332513] Starting dynamic subtest: bcs0
[204.336033] Dynamic subtest bcs0: SKIP (0.000s)
[204.344715] Starting dynamic subtest: vcs0
[204.348161] Dynamic subtest vcs0: SKIP (0.000s)
[204.356058] Starting dynamic subtest: vcs1
[204.359420] Dynamic subtest vcs1: SKIP (0.000s)
[204.367245] Starting dynamic subtest: vecs0
[204.370703] Dynamic subtest vecs0: SKIP (0.000s)
[204.378579] Starting dynamic subtest: ccs0
[204.381986] Dynamic subtest ccs0: SKIP (0.000s)
[204.385396] Subtest most-busy-check-all: SKIP (0.009s)
[204.420896] [041/155] (814s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-mmap-cpu)
[204.540923] Starting subtest: psr-2p-primscrn-spr-indfb-draw-mmap-cpu
[204.544539] Subtest psr-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[204.650910] [042/155] (814s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-spr-indfb-draw-blt)
[204.756646] Starting subtest: psrhdr-2p-scndscrn-spr-indfb-draw-blt
[204.760188] Subtest psrhdr-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s)
[204.842689] [043/155] (814s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-shrfb-plflip-blt)
[204.934072] Starting subtest: fbcpsrhdr-1p-primscrn-shrfb-plflip-blt
[204.937514] Subtest fbcpsrhdr-1p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[205.038621] [044/155] (814s left) gem_pxp (create-valid-protected-context)
[205.164459] Starting subtest: create-valid-protected-context
[205.166696] Subtest create-valid-protected-context: SUCCESS (0.002s)
[205.269780] [045/155] (813s left) kms_frontbuffer_tracking (psr-slowdraw)
[205.372823] Starting subtest: psr-slowdraw
[230.612823] Subtest psr-slowdraw: SUCCESS (25.245s)
[230.741521] [046/155] (788s left) kms_cursor_legacy (flip-vs-cursor-crc-legacy)
[230.885942] Starting subtest: flip-vs-cursor-crc-legacy
[231.588668] Subtest flip-vs-cursor-crc-legacy: SUCCESS (0.700s)
[232.472787] [047/155] (786s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-move)
[232.577798] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-move
[239.967049] Subtest fbcpsr-1p-primscrn-cur-indfb-move: SUCCESS (7.381s)
[240.124010] [048/155] (779s left) kms_frontbuffer_tracking (fbc-1p-shrfb-fliptrack-mmap-gtt)
[240.232839] Starting subtest: fbc-1p-shrfb-fliptrack-mmap-gtt
[240.236975] Subtest fbc-1p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[240.328004] [049/155] (779s left) gem_render_copy (x-tiled-to-vebox-yf-tiled)
[240.379771] Starting subtest: x-tiled-to-vebox-yf-tiled
[240.384334] Subtest x-tiled-to-vebox-yf-tiled: SKIP (0.000s)
[240.565576] [050/155] (778s left) prime_busy (hang)
[240.629924] Starting subtest: hang
[240.632146] Starting dynamic subtest: rcs0
[266.501555] Dynamic subtest rcs0: SUCCESS (25.854s)
[266.510042] Starting dynamic subtest: bcs0
[273.659697] Dynamic subtest bcs0: SUCCESS (7.156s)
[273.668720] Starting dynamic subtest: vcs0
[280.628689] Dynamic subtest vcs0: SUCCESS (6.968s)
[280.639620] Starting dynamic subtest: vcs1
[287.600238] Dynamic subtest vcs1: SUCCESS (6.973s)
[287.611948] Starting dynamic subtest: vecs0
[294.639764] Dynamic subtest vecs0: SUCCESS (7.038s)
[294.652639] Starting dynamic subtest: ccs0
[308.483045] Dynamic subtest ccs0: SUCCESS (13.836s)
[308.485544] Subtest hang: SUCCESS (67.834s)
[308.594486] [051/155] (710s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[308.699301] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[309.468555] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SUCCESS (0.770s)
[309.628921] [052/155] (709s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-onoff)
[309.725672] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-onoff
[309.730071] Subtest fbcpsr-2p-scndscrn-spr-indfb-onoff: SKIP (0.000s)
[309.825939] [053/155] (709s left) perf_pmu (busy-accuracy-2)
[309.868597] Starting subtest: busy-accuracy-2
[309.870619] Starting dynamic subtest: rcs0
[312.908829] Dynamic subtest rcs0: SUCCESS (3.039s)
[312.920936] Starting dynamic subtest: bcs0
[315.963437] Dynamic subtest bcs0: SUCCESS (3.051s)
[315.977044] Starting dynamic subtest: vcs0
[319.008405] Dynamic subtest vcs0: SUCCESS (3.043s)
[319.019592] Starting dynamic subtest: vcs1
[322.058372] Dynamic subtest vcs1: SUCCESS (3.048s)
[322.070144] Starting dynamic subtest: vecs0
[325.104649] Dynamic subtest vecs0: SUCCESS (3.043s)
[325.116849] Starting dynamic subtest: ccs0
[328.160053] Dynamic subtest ccs0: SUCCESS (3.053s)
[328.162574] Subtest busy-accuracy-2: SUCCESS (18.291s)
[328.276959] [054/155] (691s left) api_intel_allocator (simple-allocator)
[328.332588] Starting subtest: simple-allocator
[328.335453] Starting dynamic subtest: basic
[328.340497] Dynamic subtest basic: SUCCESS (0.007s)
[328.355268] Starting dynamic subtest: parallel-one
[328.515285] Dynamic subtest parallel-one: SUCCESS (0.175s)
[328.527699] Starting dynamic subtest: print
[328.530175] Dynamic subtest print: SUCCESS (0.000s)
[328.539575] Starting dynamic subtest: reuse
[328.541494] Dynamic subtest reuse: SUCCESS (0.000s)
[328.550841] Starting dynamic subtest: reserve
[328.552685] Dynamic subtest reserve: SUCCESS (0.000s)
[328.561529] Starting dynamic subtest: fork-reopen-allocator
[328.563426] Dynamic subtest fork-reopen-allocator: SUCCESS (0.011s)
[328.565338] Subtest simple-allocator: SUCCESS (0.197s)
[328.671946] [055/155] (690s left) kms_psr (fbc-pr-cursor-mmap-gtt)
[328.760831] Starting subtest: fbc-pr-cursor-mmap-gtt
[328.765278] Subtest fbc-pr-cursor-mmap-gtt: SKIP (0.000s)
[328.834683] [056/155] (690s left) fbdev (info)
[328.871676] Starting subtest: info
[328.873763] Subtest info: SUCCESS (0.000s)
[328.934800] [057/155] (690s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-indfb-draw-render)
[329.022667] Starting subtest: psrhdr-2p-scndscrn-pri-indfb-draw-render
[329.027102] Subtest psrhdr-2p-scndscrn-pri-indfb-draw-render: SKIP (0.000s)
[329.133445] [058/155] (690s left) kms_cursor_legacy (short-flip-after-cursor-atomic-transitions)
[329.249422] Starting subtest: short-flip-after-cursor-atomic-transitions
[330.203425] Subtest short-flip-after-cursor-atomic-transitions: SUCCESS (0.952s)
[331.043626] [059/155] (688s left) kms_chamelium_audio (dp-audio)
[331.431126] Subtest dp-audio: SKIP (0.000s)
[332.255027] [060/155] (687s left) kms_ccs (missing-ccs-buffer-y-tiled-gen12-rc-ccs-cc)
[332.340958] Starting subtest: missing-ccs-buffer-y-tiled-gen12-rc-ccs-cc
[332.343362] Starting dynamic subtest: pipe-A-eDP-1
[332.366562] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[332.378529] Starting dynamic subtest: pipe-B-eDP-1
[332.400057] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[332.409656] Starting dynamic subtest: pipe-C-eDP-1
[332.431348] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[332.440097] Starting dynamic subtest: pipe-D-eDP-1
[332.461945] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[332.466328] Subtest missing-ccs-buffer-y-tiled-gen12-rc-ccs-cc: SKIP (0.004s)
[334.647459] [061/155] (685s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[334.757946] Subtest addfb25-x-tiled-legacy: SKIP (0.000s)
[334.858956] [062/155] (685s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-render)
[334.958581] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-render
[338.653687] Subtest fbc-1p-primscrn-cur-indfb-draw-render: SUCCESS (3.703s)
[339.880149] [063/155] (680s left) kms_psr2_sf (fbc-psr2-overlay-plane-move-continuous-sf)
[339.986930] Starting subtest: fbc-psr2-overlay-plane-move-continuous-sf
[339.989210] Starting dynamic subtest: pipe-A-eDP-1
[340.082558] Dynamic subtest pipe-A-eDP-1: SKIP (0.099s)
[340.096830] Starting dynamic subtest: pipe-B-eDP-1
[341.244927] Dynamic subtest pipe-B-eDP-1: SKIP (1.160s)
[341.249512] Subtest fbc-psr2-overlay-plane-move-continuous-sf: SKIP (1.262s)
[342.436031] [064/155] (677s left) kms_cursor_legacy (2x-long-flip-vs-cursor-legacy)
[342.551352] Starting subtest: 2x-long-flip-vs-cursor-legacy
[342.555987] Subtest 2x-long-flip-vs-cursor-legacy: SKIP (0.001s)
[342.697257] [065/155] (677s left) kms_lease (lease-revoke)
[342.785213] Starting subtest: lease-revoke
[342.787551] Starting dynamic subtest: pipe-A-eDP-1
[342.812167] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.028s)
[342.824368] Starting dynamic subtest: pipe-B-eDP-1
[342.844946] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.029s)
[342.855501] Starting dynamic subtest: pipe-C-eDP-1
[342.876207] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.032s)
[342.892668] Starting dynamic subtest: pipe-D-eDP-1
[342.908985] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.028s)
[342.911325] Subtest lease-revoke: SUCCESS (0.124s)
[343.041219] [066/155] (677s left) syncobj_timeline (etime-multi-wait-all-for-submit-submitted)
[343.096043] Starting subtest: etime-multi-wait-all-for-submit-submitted
[343.205398] Subtest etime-multi-wait-all-for-submit-submitted: SUCCESS (0.109s)
[343.280423] [067/155] (676s left) gem_exec_fence (nb-await)
[343.452192] Starting subtest: nb-await
[343.454535] Starting dynamic subtest: rcs0
[343.525285] Dynamic subtest rcs0: SUCCESS (0.072s)
[343.538829] Starting dynamic subtest: bcs0
[343.584635] Dynamic subtest bcs0: SUCCESS (0.057s)
[343.596898] Starting dynamic subtest: vcs0
[343.645597] Dynamic subtest vcs0: SUCCESS (0.059s)
[343.656091] Starting dynamic subtest: vcs1
[343.705408] Dynamic subtest vcs1: SUCCESS (0.058s)
[343.716146] Starting dynamic subtest: vecs0
[343.766552] Dynamic subtest vecs0: SUCCESS (0.058s)
[343.780179] Starting dynamic subtest: ccs0
[343.825920] Dynamic subtest ccs0: SUCCESS (0.056s)
[343.828500] Subtest nb-await: SUCCESS (0.374s)
[346.014319] [068/155] (674s left) kms_plane_scaling (planes-unity-scaling-downscale-factor-0-75)
[346.109783] Starting subtest: planes-unity-scaling-downscale-factor-0-75
[346.112166] Starting dynamic subtest: pipe-A
[346.173295] Dynamic subtest pipe-A: SUCCESS (0.064s)
[346.185859] Starting dynamic subtest: pipe-B
[347.364617] Dynamic subtest pipe-B: SUCCESS (1.190s)
[347.375372] Starting dynamic subtest: pipe-C
[348.478854] Dynamic subtest pipe-C: SUCCESS (1.113s)
[348.488553] Starting dynamic subtest: pipe-D
[349.633442] Dynamic subtest pipe-D: SUCCESS (1.154s)
[349.635795] Subtest planes-unity-scaling-downscale-factor-0-75: SUCCESS (3.525s)
[350.798682] [069/155] (669s left) gem_set_tiling_vs_blt (untiled-to-tiled)
[350.848641] Subtest untiled-to-tiled: SKIP (0.000s)
[350.916528] [070/155] (669s left) gem_wait (write-busy)
[350.971482] Starting subtest: write-busy
[350.973665] Starting dynamic subtest: all-engines
[351.565493] Dynamic subtest all-engines: SUCCESS (0.594s)
[351.583006] Starting dynamic subtest: rcs0
[352.116535] Dynamic subtest rcs0: SUCCESS (0.550s)
[352.135495] Starting dynamic subtest: bcs0
[352.665470] Dynamic subtest bcs0: SUCCESS (0.548s)
[352.685863] Starting dynamic subtest: vcs0
[353.959957] Dynamic subtest vcs0: SUCCESS (1.292s)
[353.976623] Starting dynamic subtest: vcs1
[355.944847] Dynamic subtest vcs1: SUCCESS (1.982s)
[355.962840] Starting dynamic subtest: vecs0
[357.994315] Dynamic subtest vecs0: SUCCESS (2.046s)
[358.013034] Starting dynamic subtest: ccs0
[358.545852] Dynamic subtest ccs0: SUCCESS (0.550s)
[358.548673] Subtest write-busy: SUCCESS (7.574s)
[358.741549] [071/155] (661s left) gem_ctx_param (get-priority-new-ctx)
[358.799489] Starting subtest: get-priority-new-ctx
[358.801844] Subtest get-priority-new-ctx: SUCCESS (0.001s)
[358.925851] [072/155] (661s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[359.037634] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-mmap-gtt
[359.041968] Subtest psr-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[359.137519] [073/155] (661s left) kms_flip (2x-plain-flip-interruptible)
[359.234843] Starting subtest: 2x-plain-flip-interruptible
[359.245734] Subtest 2x-plain-flip-interruptible: SKIP (0.010s)
[359.319147] [074/155] (661s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-spr-indfb-fullscreen)
[359.422005] Starting subtest: hdr-2p-scndscrn-spr-indfb-fullscreen
[359.426044] Subtest hdr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[359.513511] [075/155] (660s left) syncobj_basic (create-signaled)
[359.563676] Starting subtest: create-signaled
[359.566344] Subtest create-signaled: SUCCESS (0.000s)
[359.657237] [076/155] (660s left) gem_exec_reloc (basic-wc-active)
[359.700761] Subtest basic-wc-active: SKIP (0.000s)
[359.771937] [077/155] (660s left) i915_query (test-query-geometry-subslices)
[359.828217] Starting subtest: test-query-geometry-subslices
[359.830318] Subtest test-query-geometry-subslices: SUCCESS (0.000s)
[359.930905] [078/155] (660s left) kms_cursor_legacy (2x-long-nonblocking-modeset-vs-cursor-atomic)
[360.046235] Starting subtest: 2x-long-nonblocking-modeset-vs-cursor-atomic
[360.050016] Subtest 2x-long-nonblocking-modeset-vs-cursor-atomic: SKIP (0.001s)
[360.192608] [079/155] (660s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-cur-indfb-draw-blt)
[360.290147] Starting subtest: fbcpsrhdr-2p-scndscrn-cur-indfb-draw-blt
[360.293702] Subtest fbcpsrhdr-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[360.398539] [080/155] (660s left) kms_frontbuffer_tracking (fbcpsr-farfromfence-mmap-gtt)
[360.498133] Starting subtest: fbcpsr-farfromfence-mmap-gtt
[360.501721] Subtest fbcpsr-farfromfence-mmap-gtt: SKIP (0.000s)
[360.591823] [081/155] (660s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[360.688683] Starting subtest: psr-2p-primscrn-cur-indfb-draw-mmap-cpu
[360.691995] Subtest psr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[360.823717] [082/155] (659s left) syncobj_timeline (host-signal-ordered)
[360.879114] Starting subtest: host-signal-ordered
[360.881094] Subtest host-signal-ordered: SUCCESS (0.000s)
[360.982841] [083/155] (659s left) gem_tiled_fence_blits (basic)
[361.031482] Subtest basic: SKIP (0.000s)
[361.096049] [084/155] (659s left) kms_ccs (bad-rotation-90-4-tiled-dg2-rc-ccs-cc)
[361.186655] Starting subtest: bad-rotation-90-4-tiled-dg2-rc-ccs-cc
[361.188693] Starting dynamic subtest: pipe-A-eDP-1
[361.208227] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[361.219394] Starting dynamic subtest: pipe-B-eDP-1
[361.236959] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[361.246526] Starting dynamic subtest: pipe-C-eDP-1
[361.264468] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[361.273484] Starting dynamic subtest: pipe-D-eDP-1
[361.291631] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[361.295220] Subtest bad-rotation-90-4-tiled-dg2-rc-ccs-cc: SKIP (0.004s)
[363.458505] [085/155] (657s left) kms_big_fb (yf-tiled-addfb-size-offset-overflow)
[363.564547] Starting subtest: yf-tiled-addfb-size-offset-overflow
[363.569013] Subtest yf-tiled-addfb-size-offset-overflow: SKIP (0.000s)
[363.660686] [086/155] (657s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-64bpp-ytile-downscaling)
[363.762015] Starting subtest: flip-32bpp-ytile-to-64bpp-ytile-downscaling
[363.766026] Subtest flip-32bpp-ytile-to-64bpp-ytile-downscaling: SKIP (0.000s)
[363.834578] [087/155] (657s left) kms_frontbuffer_tracking (fbcpsr-2p-rte)
[363.938394] Starting subtest: fbcpsr-2p-rte
[363.942453] Subtest fbcpsr-2p-rte: SKIP (0.000s)
[364.019807] [088/155] (656s left) kms_ccs (ccs-on-another-bo-4-tiled-mtl-rc-ccs)
[364.113300] Starting subtest: ccs-on-another-bo-4-tiled-mtl-rc-ccs
[364.115669] Starting dynamic subtest: pipe-A-eDP-1
[364.136140] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[364.148459] Starting dynamic subtest: pipe-B-eDP-1
[364.167965] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[364.177800] Starting dynamic subtest: pipe-C-eDP-1
[364.196533] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[364.208084] Starting dynamic subtest: pipe-D-eDP-1
[364.227521] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[364.229670] Subtest ccs-on-another-bo-4-tiled-mtl-rc-ccs: SUCCESS (0.008s)
[366.430740] [089/155] (654s left) kms_plane (planar-pixel-format-settings)
[366.527996] Starting subtest: planar-pixel-format-settings
[366.530193] Starting dynamic subtest: nv12-odd-width
[366.549973] Dynamic subtest nv12-odd-width: SUCCESS (0.023s)
[366.560128] Starting dynamic subtest: nv12-odd-height
[366.562395] Dynamic subtest nv12-odd-height: SUCCESS (0.011s)
[366.571498] Starting dynamic subtest: nv12-odd-horizontal-pan
[366.585243] Dynamic subtest nv12-odd-horizontal-pan: SUCCESS (0.021s)
[366.594133] Starting dynamic subtest: p016-odd-vertical-pan
[366.620665] Dynamic subtest p016-odd-vertical-pan: SUCCESS (0.036s)
[366.630696] Starting dynamic subtest: nv12-tile4-src-y
[366.634662] Dynamic subtest nv12-tile4-src-y: SKIP (0.000s)
[366.636536] Subtest planar-pixel-format-settings: SUCCESS (0.095s)
[366.745126] [090/155] (654s left) gem_exec_create (forked)
[366.809586] Starting subtest: forked
[366.811963] Starting dynamic subtest: smem
[388.904788] Dynamic subtest smem: SUCCESS (22.094s)
[388.907632] Subtest forked: SUCCESS (22.096s)
[389.103514] [091/155] (632s left) kms_dp_linktrain_fallback (dp-fallback)
[389.204936] Starting subtest: dp-fallback
[389.211359] Subtest dp-fallback: SKIP (0.000s)
[389.277798] [092/155] (631s left) gem_exec_params (invalid-bsd1-flag-on-render)
[389.337080] Starting subtest: invalid-bsd1-flag-on-render
[389.338973] Subtest invalid-bsd1-flag-on-render: SUCCESS (0.000s)
[389.458218] [093/155] (631s left) sysfs_timeslice_duration (timeout)
[389.511824] Starting subtest: timeout
[389.513713] Starting dynamic subtest: vecs0
[398.955085] Dynamic subtest vecs0: SUCCESS (9.442s)
[398.966639] Starting dynamic subtest: ccs0
[400.216215] Dynamic subtest ccs0: SUCCESS (1.259s)
[400.230644] Starting dynamic subtest: vcs0
[409.963576] Dynamic subtest vcs0: SUCCESS (9.746s)
[409.975707] Starting dynamic subtest: bcs0
[410.556011] Dynamic subtest bcs0: SUCCESS (0.592s)
[410.565911] Starting dynamic subtest: rcs0
[411.790990] Dynamic subtest rcs0: SUCCESS (1.233s)
[411.804449] Starting dynamic subtest: vcs1
[420.967344] Dynamic subtest vcs1: SUCCESS (9.174s)
[420.969791] Subtest timeout: SUCCESS (31.458s)
[421.059343] [094/155] (600s left) kms_cursor_crc (cursor-sliding-64x64)
[421.236771] Starting subtest: cursor-sliding-64x64
[421.242363] Starting dynamic subtest: pipe-A-eDP-1
[427.862717] Dynamic subtest pipe-A-eDP-1: FAIL (6.119s)
[427.874673] Starting dynamic subtest: pipe-D-eDP-1
[436.596927] Dynamic subtest pipe-D-eDP-1: SUCCESS (9.204s)
[436.599192] Subtest cursor-sliding-64x64: FAIL (15.357s)
[437.491656] [095/155] (583s left) gem_ctx_shared (q-out-order)
[437.549320] Starting subtest: Q-out-order
[437.551642] Starting dynamic subtest: rcs0
[437.661065] Dynamic subtest rcs0: SUCCESS (0.108s)
[437.691056] Starting dynamic subtest: vcs0
[437.760069] Dynamic subtest vcs0: SUCCESS (0.101s)
[437.777109] Starting dynamic subtest: vcs1
[437.843514] Dynamic subtest vcs1: SUCCESS (0.082s)
[437.860578] Starting dynamic subtest: vecs0
[437.924051] Dynamic subtest vecs0: SUCCESS (0.079s)
[437.943038] Starting dynamic subtest: ccs0
[438.001689] Dynamic subtest ccs0: SUCCESS (0.077s)
[438.004203] Subtest Q-out-order: SUCCESS (0.452s)
[439.056169] [096/155] (582s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[439.159921] Starting subtest: psrhdr-2p-primscrn-pri-shrfb-draw-mmap-cpu
[439.164287] Subtest psrhdr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[439.256508] [097/155] (582s left) kms_psr (pr-suspend)
[439.342067] Starting subtest: pr-suspend
[439.346060] Subtest pr-suspend: SKIP (0.000s)
[439.419831] [098/155] (581s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180)
[439.507832] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180
[440.492431] Subtest x-tiled-max-hw-stride-32bpp-rotate-180: SUCCESS (0.985s)
[440.603082] [099/155] (580s left) dmabuf (all-tests)
[440.642176] Starting subtest: all-tests
[440.645749] Subtest all-tests: SKIP (0.000s)
[440.700821] [100/155] (580s left) gem_mmap_gtt (ptrace)
[440.773746] Subtest ptrace: SKIP (0.000s)
[440.847709] [101/155] (580s left) kms_cursor_crc (cursor-sliding-256x256)
[441.091092] Starting subtest: cursor-sliding-256x256
[441.092992] Starting dynamic subtest: pipe-A-eDP-1
[444.300926] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.207s)
[444.331443] Starting dynamic subtest: pipe-D-eDP-1
[448.171083] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.846s)
[448.173263] Subtest cursor-sliding-256x256: SUCCESS (7.079s)
[449.069275] [102/155] (572s left) gem_exec_whisper (basic-queues-forked)
[449.136472] Starting subtest: basic-queues-forked
[472.089841] Subtest basic-queues-forked: SUCCESS (22.950s)
[472.277435] [103/155] (549s left) syncobj_timeline (multi-wait-available-unsubmitted-submitted-signaled)
[472.327934] Starting subtest: multi-wait-available-unsubmitted-submitted-signaled
[472.330271] Subtest multi-wait-available-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[472.435704] [104/155] (549s left) kms_frontbuffer_tracking (hdr-1p-primscrn-cur-indfb-draw-render)
[472.533078] Starting subtest: hdr-1p-primscrn-cur-indfb-draw-render
[472.537327] Subtest hdr-1p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[472.631906] [105/155] (549s left) kms_flip (flip-vs-dpms-off-vs-modeset-interruptible)
[472.722384] Starting subtest: flip-vs-dpms-off-vs-modeset-interruptible
[472.724695] Starting dynamic subtest: A-eDP1
[476.289816] Dynamic subtest A-eDP1: SUCCESS (3.566s)
[476.301706] Starting dynamic subtest: B-eDP1
[479.582945] Dynamic subtest B-eDP1: SUCCESS (3.292s)
[479.594590] Starting dynamic subtest: C-eDP1
[482.855740] Dynamic subtest C-eDP1: SUCCESS (3.271s)
[482.866312] Starting dynamic subtest: D-eDP1
[486.151791] Dynamic subtest D-eDP1: SUCCESS (3.294s)
[486.154156] Subtest flip-vs-dpms-off-vs-modeset-interruptible: SUCCESS (13.431s)
[486.953388] [106/155] (534s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-pri-shrfb-draw-mmap-cpu)
[487.051620] Starting subtest: fbchdr-1p-primscrn-pri-shrfb-draw-mmap-cpu
[487.056037] Subtest fbchdr-1p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[487.142254] [107/155] (534s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-spr-indfb-draw-pwrite)
[487.241482] Starting subtest: fbcpsrhdr-2p-scndscrn-spr-indfb-draw-pwrite
[487.245683] Subtest fbcpsrhdr-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[487.348330] [108/155] (534s left) kms_ccs (crc-primary-suspend-4-tiled-lnl-ccs)
[487.447382] Starting subtest: crc-primary-suspend-4-tiled-lnl-ccs
[487.452059] Subtest crc-primary-suspend-4-tiled-lnl-ccs: SKIP (0.000s)
[488.582014] [109/155] (533s left) gen9_exec_parse (secure-batches)
[488.643989] Subtest secure-batches: SKIP (0.000s)
[488.708101] [110/155] (533s left) kms_fb_coherency (memset-crc)
[488.805258] Starting subtest: memset-crc
[488.807509] Starting dynamic subtest: mmap-offset-wc
[489.168378] Dynamic subtest mmap-offset-wc: SUCCESS (0.364s)
[489.473497] Starting dynamic subtest: mmap-offset-uc
[495.576281] Dynamic subtest mmap-offset-uc: SUCCESS (6.113s)
[495.862033] Subtest memset-crc: SUCCESS (7.056s)
[496.737797] [111/155] (525s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-wc)
[496.842067] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-wc
[503.881712] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-wc: SUCCESS (7.045s)
[503.996482] [112/155] (517s left) kms_frontbuffer_tracking (fbcpsrhdr-tiling-y)
[504.094297] Starting subtest: fbcpsrhdr-tiling-y
[504.098536] Subtest fbcpsrhdr-tiling-y: SKIP (0.000s)
[504.202300] [113/155] (517s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-blt)
[504.303134] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-blt
[504.307235] Subtest fbc-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[504.401320] [114/155] (517s left) perf_pmu (gt-awake)
[504.437328] Starting subtest: gt-awake
[512.937899] Subtest gt-awake: SUCCESS (8.501s)
[513.040328] [115/155] (509s left) kms_vblank (wait-busy-hang)
[513.148608] Starting subtest: wait-busy-hang
[513.150541] Starting dynamic subtest: pipe-A-eDP-1
[539.755143] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.605s)
[539.770578] Starting dynamic subtest: pipe-D-eDP-1
[554.794179] Dynamic subtest pipe-D-eDP-1: SUCCESS (15.035s)
[554.796339] Subtest wait-busy-hang: SUCCESS (41.646s)
[555.632812] [116/155] (466s left) gem_exec_schedule (timeslicing)
[555.706405] Starting subtest: timeslicing
[555.708392] Starting dynamic subtest: rcs0
[555.717705] Dynamic subtest rcs0: SUCCESS (0.021s)
[555.728266] Starting dynamic subtest: bcs0
[555.738168] Dynamic subtest bcs0: SUCCESS (0.020s)
[555.748226] Starting dynamic subtest: vcs0
[555.760008] Dynamic subtest vcs0: SUCCESS (0.021s)
[555.770692] Starting dynamic subtest: vcs1
[555.782988] Dynamic subtest vcs1: SUCCESS (0.022s)
[555.792599] Starting dynamic subtest: vecs0
[555.804959] Dynamic subtest vecs0: SUCCESS (0.021s)
[555.814894] Starting dynamic subtest: ccs0
[555.824957] Dynamic subtest ccs0: SUCCESS (0.019s)
[555.827178] Subtest timeslicing: SUCCESS (0.129s)
[557.007741] [117/155] (465s left) gem_exec_balancer (bonded-false-hang)
[557.256383] Starting subtest: bonded-false-hang
[557.261535] Subtest bonded-false-hang: SKIP (0.000s)
[557.359250] [118/155] (464s left) kms_cursor_crc (cursor-dpms)
[557.486422] Starting subtest: cursor-dpms
[557.488614] Starting dynamic subtest: pipe-A-eDP-1
[560.493639] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.005s)
[560.504249] Starting dynamic subtest: pipe-D-eDP-1
[564.185623] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.691s)
[564.188358] Subtest cursor-dpms: SUCCESS (6.699s)
[565.022470] [119/155] (457s left) kms_flip (2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible)
[565.122481] Starting subtest: 2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible
[565.133474] Subtest 2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible: SKIP (0.011s)
[565.222543] [120/155] (457s left) kms_scaling_modes (scaling-mode-full-aspect)
[565.314212] Starting subtest: scaling-mode-full-aspect
[565.316138] Starting dynamic subtest: pipe-A-eDP-1
[565.404733] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.093s)
[565.417756] Starting dynamic subtest: pipe-B-eDP-1
[566.550266] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.144s)
[566.561628] Starting dynamic subtest: pipe-C-eDP-1
[567.669107] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.117s)
[567.681106] Starting dynamic subtest: pipe-D-eDP-1
[568.806194] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.136s)
[568.808497] Subtest scaling-mode-full-aspect: SUCCESS (3.495s)
[569.964703] [121/155] (452s left) kms_cursor_legacy (short-flip-before-cursor-atomic-transitions-varying-size)
[570.074479] Starting subtest: short-flip-before-cursor-atomic-transitions-varying-size
[571.021995] Subtest short-flip-before-cursor-atomic-transitions-varying-size: SUCCESS (0.946s)
[571.886982] [122/155] (450s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-render)
[571.989431] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-render
[571.993688] Subtest fbc-2p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[572.087614] [123/155] (450s left) kms_chamelium_frames (dp-crc-fast)
[572.467403] Subtest dp-crc-fast: SKIP (0.000s)
[573.295980] [124/155] (449s left) gem_exec_store (dword)
[573.351789] Starting subtest: dword
[573.353921] Starting dynamic subtest: rcs0
[573.412746] Dynamic subtest rcs0: SUCCESS (0.061s)
[573.425924] Starting dynamic subtest: bcs0
[573.453445] Dynamic subtest bcs0: SUCCESS (0.039s)
[573.467119] Starting dynamic subtest: vcs0
[573.494353] Dynamic subtest vcs0: SUCCESS (0.039s)
[573.506414] Starting dynamic subtest: vcs1
[573.536007] Dynamic subtest vcs1: SUCCESS (0.040s)
[573.549573] Starting dynamic subtest: vecs0
[573.578174] Dynamic subtest vecs0: SUCCESS (0.040s)
[573.590026] Starting dynamic subtest: ccs0
[573.620624] Dynamic subtest ccs0: SUCCESS (0.040s)
[573.623257] Subtest dword: SUCCESS (0.271s)
[573.808970] [125/155] (448s left) prime_mmap_coherency (write)
[573.867527] Starting subtest: write
[579.245078] Subtest write: SUCCESS (5.376s)
[579.419085] [126/155] (443s left) kms_plane (pixel-format-4-tiled-bmg-ccs-modifier)
[579.520493] Starting subtest: pixel-format-4-tiled-bmg-ccs-modifier
[579.524470] Subtest pixel-format-4-tiled-bmg-ccs-modifier: SKIP (0.000s)
[579.604078] [127/155] (443s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-pwrite)
[579.703026] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-pwrite
[579.707476] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[579.818588] [128/155] (442s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[579.913953] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[579.918504] Subtest y-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SKIP (0.000s)
[580.021774] [129/155] (442s left) kms_big_fb (4-tiled-16bpp-rotate-270)
[580.117494] Starting subtest: 4-tiled-16bpp-rotate-270
[580.137349] Subtest 4-tiled-16bpp-rotate-270: SKIP (0.019s)
[580.240679] [130/155] (442s left) kms_psr (fbc-psr-primary-page-flip)
[580.338560] Starting subtest: fbc-psr-primary-page-flip
[580.340461] Starting dynamic subtest: eDP-1
[580.343819] Dynamic subtest eDP-1: SKIP (0.000s)
[580.347236] Subtest fbc-psr-primary-page-flip: SKIP (0.001s)
[580.411374] [131/155] (442s left) gem_ctx_isolation (preservation-s3)
[580.461730] Starting subtest: preservation-S3
[580.463830] Starting dynamic subtest: rcs0
[597.048821] Dynamic subtest rcs0: SUCCESS (1.325s)
[597.059553] Starting dynamic subtest: bcs0
[613.029829] Dynamic subtest bcs0: SUCCESS (1.302s)
[613.041883] Starting dynamic subtest: vcs0
[629.049337] Dynamic subtest vcs0: SUCCESS (2.971s)
[629.063002] Starting dynamic subtest: vcs1
[647.060655] Dynamic subtest vcs1: SUCCESS (5.039s)
[647.072710] Starting dynamic subtest: vecs0
[665.063448] Dynamic subtest vecs0: SUCCESS (5.009s)
[665.075363] Starting dynamic subtest: ccs0
[683.053638] Dynamic subtest ccs0: SUCCESS (3.149s)
[683.055908] Subtest preservation-S3: SUCCESS (18.802s)
[683.280938] [132/155] (339s left) kms_psr (psr2-primary-page-flip)
[683.379683] Starting subtest: psr2-primary-page-flip
[683.382274] Starting dynamic subtest: eDP-1
[685.085766] Dynamic subtest eDP-1: SUCCESS (1.713s)
[685.088048] Subtest psr2-primary-page-flip: SUCCESS (1.714s)
[685.942951] [133/155] (336s left) kms_color_pipeline (plane-lut1d-ctm3x4)
[686.033926] Starting subtest: plane-lut1d-ctm3x4
[686.168014] Starting dynamic subtest: pipe-A-plane-0
[686.368410] Dynamic subtest pipe-A-plane-0: SUCCESS (0.200s)
[686.379300] Starting dynamic subtest: pipe-A-plane-1
[686.556958] Dynamic subtest pipe-A-plane-1: SUCCESS (0.188s)
[686.567941] Starting dynamic subtest: pipe-A-plane-2
[686.742602] Dynamic subtest pipe-A-plane-2: SUCCESS (0.188s)
[687.924219] Starting dynamic subtest: pipe-B-plane-0
[688.058989] Dynamic subtest pipe-B-plane-0: SUCCESS (0.144s)
[688.069621] Starting dynamic subtest: pipe-B-plane-1
[688.193074] Dynamic subtest pipe-B-plane-1: SUCCESS (0.133s)
[688.203994] Starting dynamic subtest: pipe-B-plane-2
[688.348569] Dynamic subtest pipe-B-plane-2: SUCCESS (0.155s)
[689.551186] Starting dynamic subtest: pipe-C-plane-0
[689.696895] Dynamic subtest pipe-C-plane-0: SUCCESS (0.155s)
[689.708080] Starting dynamic subtest: pipe-C-plane-1
[689.851599] Dynamic subtest pipe-C-plane-1: SUCCESS (0.155s)
[689.862394] Starting dynamic subtest: pipe-C-plane-2
[689.997599] Dynamic subtest pipe-C-plane-2: SUCCESS (0.144s)
[691.191306] Starting dynamic subtest: pipe-D-plane-0
[691.326094] Dynamic subtest pipe-D-plane-0: SUCCESS (0.144s)
[691.342112] Starting dynamic subtest: pipe-D-plane-1
[691.459729] Dynamic subtest pipe-D-plane-1: SUCCESS (0.133s)
[691.471012] Starting dynamic subtest: pipe-D-plane-2
[691.625363] Dynamic subtest pipe-D-plane-2: SUCCESS (0.166s)
[691.913911] Subtest plane-lut1d-ctm3x4: SUCCESS (5.902s)
[692.755625] [134/155] (330s left) gem_lmem_swapping (verify-random)
[692.798860] Subtest verify-random: SKIP (0.000s)
[692.840118] [135/155] (330s left) kms_flip_scaled_crc (flip-32bpp-xtile-to-64bpp-xtile-downscaling)
[692.926615] Starting subtest: flip-32bpp-xtile-to-64bpp-xtile-downscaling
[692.929179] Starting dynamic subtest: pipe-A-default-mode
[694.093686] Dynamic subtest pipe-A-default-mode: SKIP (1.166s)
[694.098727] Subtest flip-32bpp-xtile-to-64bpp-xtile-downscaling: SKIP (1.167s)
[694.261372] [136/155] (328s left) gem_exec_balancer (persistence)
[694.519173] Starting subtest: persistence
[695.417646] Subtest persistence: SUCCESS (0.902s)
[695.542522] [137/155] (327s left) kms_sequence (get-idle)
[695.644629] Starting subtest: get-idle
[695.647070] Starting dynamic subtest: pipe-A-eDP-1
[697.442787] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.802s)
[697.455172] Starting dynamic subtest: pipe-B-eDP-1
[699.923192] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.480s)
[699.934724] Starting dynamic subtest: pipe-C-eDP-1
[702.448740] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.526s)
[702.460234] Starting dynamic subtest: pipe-D-eDP-1
[704.866036] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.422s)
[704.869102] Subtest get-idle: SUCCESS (9.236s)
[705.678258] [138/155] (317s left) kms_frontbuffer_tracking (fbchdr-rgb565-draw-pwrite)
[705.871665] Starting subtest: fbchdr-rgb565-draw-pwrite
[705.877068] Subtest fbchdr-rgb565-draw-pwrite: SKIP (0.000s)
[705.974739] [139/155] (317s left) i915_pm_rc6_residency (rc6-accuracy)
[706.037979] Starting subtest: rc6-accuracy
[706.040339] Starting dynamic subtest: gt0
[709.210445] Dynamic subtest gt0: SUCCESS (3.172s)
[709.221557] Starting dynamic subtest: gt1
[712.383124] Dynamic subtest gt1: SUCCESS (3.171s)
[712.386197] Subtest rc6-accuracy: SUCCESS (6.350s)
[712.461783] [140/155] (310s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[712.562013] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-cpu
[712.567214] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[712.638487] [141/155] (310s left) kms_pipe_crc_basic (read-crc)
[712.726026] Starting subtest: read-crc
[712.729071] Starting dynamic subtest: pipe-A-eDP-1
[713.429184] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.703s)
[713.443581] Starting dynamic subtest: pipe-B-eDP-1
[714.848869] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.416s)
[714.859196] Starting dynamic subtest: pipe-C-eDP-1
[716.182123] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.332s)
[716.193469] Starting dynamic subtest: pipe-D-eDP-1
[717.507457] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.323s)
[717.509807] Subtest read-crc: SUCCESS (4.780s)
[718.327190] [142/155] (304s left) kms_big_fb (y-tiled-32bpp-rotate-90)
[718.427057] Starting subtest: y-tiled-32bpp-rotate-90
[718.431935] Subtest y-tiled-32bpp-rotate-90: SKIP (0.000s)
[718.527314] [143/155] (304s left) gem_create (create-ext-set-pat)
[718.689224] Starting subtest: create-ext-set-pat
[718.691956] Subtest create-ext-set-pat: SUCCESS (0.001s)
[718.790748] [144/155] (304s left) gem_ctx_persistence (smoketest)
[718.855982] Starting subtest: smoketest
[758.821832] Subtest smoketest: SUCCESS (39.972s)
[758.915081] [145/155] (264s left) kms_flip (2x-plain-flip-fb-recreate-interruptible)
[759.010985] Starting subtest: 2x-plain-flip-fb-recreate-interruptible
[759.021784] Subtest 2x-plain-flip-fb-recreate-interruptible: SKIP (0.010s)
[759.109961] [146/155] (264s left) kms_flip (2x-wf_vblank-ts-check)
[759.200848] Starting subtest: 2x-wf_vblank-ts-check
[759.210029] Subtest 2x-wf_vblank-ts-check: SKIP (0.010s)
[759.291011] [147/155] (264s left) gem_workarounds (basic-read)
[759.350637] Starting subtest: basic-read
[759.361096] Subtest basic-read: SUCCESS (0.010s)
[759.473598] [148/155] (264s left) kms_joiner (invalid-modeset-force-ultra-joiner)
[759.564952] Starting subtest: invalid-modeset-force-ultra-joiner
[759.569836] Subtest invalid-modeset-force-ultra-joiner: SKIP (0.000s)
[759.644103] [149/155] (263s left) kms_frontbuffer_tracking (fbchdr-farfromfence-mmap-gtt)
[759.730950] Starting subtest: fbchdr-farfromfence-mmap-gtt
[759.736059] Subtest fbchdr-farfromfence-mmap-gtt: SKIP (0.000s)
[759.834052] [150/155] (263s left) kms_frontbuffer_tracking (psrhdr-1p-offscreen-pri-shrfb-draw-mmap-wc)
[759.929434] Starting subtest: psrhdr-1p-offscreen-pri-shrfb-draw-mmap-wc
[759.933919] Subtest psrhdr-1p-offscreen-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[760.026195] [151/155] (263s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[760.130982] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[760.744200] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SUCCESS (0.615s)
[760.906152] [152/155] (262s left) kms_rotation_crc (exhaust-fences)
[760.993714] Starting subtest: exhaust-fences
[760.998863] Subtest exhaust-fences: SKIP (0.000s)
[761.067711] [153/155] (262s left) gem_eio (throttle)
[761.299099] Starting subtest: throttle
[761.623096] Subtest throttle: SUCCESS (0.324s)
[761.775689] [154/155] (261s left) kms_frontbuffer_tracking (psrhdr-1p-offscreen-pri-indfb-draw-render)
[761.879949] Starting subtest: psrhdr-1p-offscreen-pri-indfb-draw-render
[761.883998] Subtest psrhdr-1p-offscreen-pri-indfb-draw-render: SKIP (0.000s)
[761.971549] [155/155] (261s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-pri-shrfb-draw-blt)
[762.071002] Starting subtest: fbcpsrhdr-1p-primscrn-pri-shrfb-draw-blt
[762.075619] Subtest fbcpsrhdr-1p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[762.178573] Closing watchdogs
results: parsing output: 0/ for test: kms_cursor_legacy
results: parsing output: 1/ for test: kms_content_protection
results: parsing output: 2/ for test: kms_frontbuffer_tracking
results: parsing output: 3/ for test: kms_psr
results: parsing output: 4/ for test: gem_softpin
results: parsing output: 5/ for test: kms_big_fb
results: parsing output: 6/ for test: syncobj_wait
results: parsing output: 7/ for test: kms_getfb
results: parsing output: 8/ for test: kms_frontbuffer_tracking
results: parsing output: 9/ for test: kms_frontbuffer_tracking
results: parsing output: 10/ for test: kms_prop_blob
results: parsing output: 11/ for test: kms_chamelium_hpd
results: parsing output: 12/ for test: kms_pm_rpm
results: parsing output: 13/ for test: i915_fb_tiling
results: parsing output: 14/ for test: gem_render_copy
results: parsing output: 15/ for test: kms_frontbuffer_tracking
results: parsing output: 16/ for test: kms_fbcon_fbt
results: parsing output: 17/ for test: gem_userptr_blits
results: parsing output: 18/ for test: gem_exec_fence
results: parsing output: 19/ for test: kms_frontbuffer_tracking
results: parsing output: 20/ for test: kms_plane_scaling
results: parsing output: 21/ for test: kms_frontbuffer_tracking
results: parsing output: 22/ for test: syncobj_timeline
results: parsing output: 23/ for test: kms_frontbuffer_tracking
results: parsing output: 24/ for test: sw_sync
results: parsing output: 25/ for test: kms_frontbuffer_tracking
results: parsing output: 26/ for test: gem_busy
results: parsing output: 27/ for test: prime_mmap
results: parsing output: 28/ for test: kms_frontbuffer_tracking
results: parsing output: 29/ for test: gem_exec_schedule
results: parsing output: 30/ for test: sw_sync
results: parsing output: 31/ for test: kms_frontbuffer_tracking
results: parsing output: 32/ for test: syncobj_timeline
results: parsing output: 33/ for test: api_intel_bb
results: parsing output: 34/ for test: gem_ctx_param
results: parsing output: 35/ for test: syncobj_basic
results: parsing output: 36/ for test: gem_partial_pwrite_pread
results: parsing output: 37/ for test: kms_frontbuffer_tracking
results: parsing output: 38/ for test: gem_exec_reloc
results: parsing output: 39/ for test: i915_drm_fdinfo
results: parsing output: 40/ for test: kms_frontbuffer_tracking
results: parsing output: 41/ for test: kms_frontbuffer_tracking
results: parsing output: 42/ for test: kms_frontbuffer_tracking
results: parsing output: 43/ for test: gem_pxp
results: parsing output: 44/ for test: kms_frontbuffer_tracking
results: parsing output: 45/ for test: kms_cursor_legacy
results: parsing output: 46/ for test: kms_frontbuffer_tracking
results: parsing output: 47/ for test: kms_frontbuffer_tracking
results: parsing output: 48/ for test: gem_render_copy
results: parsing output: 49/ for test: prime_busy
results: parsing output: 50/ for test: kms_big_fb
results: parsing output: 51/ for test: kms_frontbuffer_tracking
results: parsing output: 52/ for test: perf_pmu
results: parsing output: 53/ for test: api_intel_allocator
results: parsing output: 54/ for test: kms_psr
results: parsing output: 55/ for test: fbdev
results: parsing output: 56/ for test: kms_frontbuffer_tracking
results: parsing output: 57/ for test: kms_cursor_legacy
results: parsing output: 58/ for test: kms_chamelium_audio
results: parsing output: 59/ for test: kms_ccs
results: parsing output: 60/ for test: kms_addfb_basic
results: parsing output: 61/ for test: kms_frontbuffer_tracking
results: parsing output: 62/ for test: kms_psr2_sf
results: parsing output: 63/ for test: kms_cursor_legacy
results: parsing output: 64/ for test: kms_lease
results: parsing output: 65/ for test: syncobj_timeline
results: parsing output: 66/ for test: gem_exec_fence
results: parsing output: 67/ for test: kms_plane_scaling
results: parsing output: 68/ for test: gem_set_tiling_vs_blt
results: parsing output: 69/ for test: gem_wait
results: parsing output: 70/ for test: gem_ctx_param
results: parsing output: 71/ for test: kms_frontbuffer_tracking
results: parsing output: 72/ for test: kms_flip
results: parsing output: 73/ for test: kms_frontbuffer_tracking
results: parsing output: 74/ for test: syncobj_basic
results: parsing output: 75/ for test: gem_exec_reloc
results: parsing output: 76/ for test: i915_query
results: parsing output: 77/ for test: kms_cursor_legacy
results: parsing output: 78/ for test: kms_frontbuffer_tracking
results: parsing output: 79/ for test: kms_frontbuffer_tracking
results: parsing output: 80/ for test: kms_frontbuffer_tracking
results: parsing output: 81/ for test: syncobj_timeline
results: parsing output: 82/ for test: gem_tiled_fence_blits
results: parsing output: 83/ for test: kms_ccs
results: parsing output: 84/ for test: kms_big_fb
results: parsing output: 85/ for test: kms_flip_scaled_crc
results: parsing output: 86/ for test: kms_frontbuffer_tracking
results: parsing output: 87/ for test: kms_ccs
results: parsing output: 88/ for test: kms_plane
results: parsing output: 89/ for test: gem_exec_create
results: parsing output: 90/ for test: kms_dp_linktrain_fallback
results: parsing output: 91/ for test: gem_exec_params
results: parsing output: 92/ for test: sysfs_timeslice_duration
results: parsing output: 93/ for test: kms_cursor_crc
results: parsing output: 94/ for test: gem_ctx_shared
results: parsing output: 95/ for test: kms_frontbuffer_tracking
results: parsing output: 96/ for test: kms_psr
results: parsing output: 97/ for test: kms_big_fb
results: parsing output: 98/ for test: dmabuf
results: parsing output: 99/ for test: gem_mmap_gtt
results: parsing output: 100/ for test: kms_cursor_crc
results: parsing output: 101/ for test: gem_exec_whisper
results: parsing output: 102/ for test: syncobj_timeline
results: parsing output: 103/ for test: kms_frontbuffer_tracking
results: parsing output: 104/ for test: kms_flip
results: parsing output: 105/ for test: kms_frontbuffer_tracking
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: kms_ccs
results: parsing output: 108/ for test: gen9_exec_parse
results: parsing output: 109/ for test: kms_fb_coherency
results: parsing output: 110/ for test: kms_frontbuffer_tracking
results: parsing output: 111/ for test: kms_frontbuffer_tracking
results: parsing output: 112/ for test: kms_frontbuffer_tracking
results: parsing output: 113/ for test: perf_pmu
results: parsing output: 114/ for test: kms_vblank
results: parsing output: 115/ for test: gem_exec_schedule
results: parsing output: 116/ for test: gem_exec_balancer
results: parsing output: 117/ for test: kms_cursor_crc
results: parsing output: 118/ for test: kms_flip
results: parsing output: 119/ for test: kms_scaling_modes
results: parsing output: 120/ for test: kms_cursor_legacy
results: parsing output: 121/ for test: kms_frontbuffer_tracking
results: parsing output: 122/ for test: kms_chamelium_frames
results: parsing output: 123/ for test: gem_exec_store
results: parsing output: 124/ for test: prime_mmap_coherency
results: parsing output: 125/ for test: kms_plane
results: parsing output: 126/ for test: kms_frontbuffer_tracking
results: parsing output: 127/ for test: kms_big_fb
results: parsing output: 128/ for test: kms_big_fb
results: parsing output: 129/ for test: kms_psr
results: parsing output: 130/ for test: gem_ctx_isolation
results: parsing output: 131/ for test: kms_psr
results: parsing output: 132/ for test: kms_color_pipeline
results: parsing output: 133/ for test: gem_lmem_swapping
results: parsing output: 134/ for test: kms_flip_scaled_crc
results: parsing output: 135/ for test: gem_exec_balancer
results: parsing output: 136/ for test: kms_sequence
results: parsing output: 137/ for test: kms_frontbuffer_tracking
results: parsing output: 138/ for test: i915_pm_rc6_residency
results: parsing output: 139/ for test: kms_frontbuffer_tracking
results: parsing output: 140/ for test: kms_pipe_crc_basic
results: parsing output: 141/ for test: kms_big_fb
results: parsing output: 142/ for test: gem_create
results: parsing output: 143/ for test: gem_ctx_persistence
results: parsing output: 144/ for test: kms_flip
results: parsing output: 145/ for test: kms_flip
results: parsing output: 146/ for test: gem_workarounds
results: parsing output: 147/ for test: kms_joiner
results: parsing output: 148/ for test: kms_frontbuffer_tracking
results: parsing output: 149/ for test: kms_frontbuffer_tracking
results: parsing output: 150/ for test: kms_big_fb
results: parsing output: 151/ for test: kms_rotation_crc
results: parsing output: 152/ for test: gem_eio
results: parsing output: 153/ for test: kms_frontbuffer_tracking
results: parsing output: 154/ for test: kms_frontbuffer_tracking
Done.