[57.206098] Initializing watchdogs
[57.206190]   /dev/watchdog0
[57.221569] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7dd5 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Graphics]
[57.225571] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.227764] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.231130] [001/133] (960s left) kms_big_fb (linear-64bpp-rotate-180)
[57.345118] Starting subtest: linear-64bpp-rotate-180
[58.988868] Subtest linear-64bpp-rotate-180: SUCCESS (1.648s)
[59.151682] [002/133] (958s left) gem_softpin (evict-active-interruptible)
[59.217711] Starting subtest: evict-active-interruptible
[59.222886] Subtest evict-active-interruptible: SUCCESS (0.005s)
[59.363351] [003/133] (958s left) api_intel_bb (purge-bb)
[59.418971] Starting subtest: purge-bb
[59.423489] Subtest purge-bb: SUCCESS (0.004s)
[59.538054] [004/133] (958s left) gem_softpin (safe-alignment)
[59.598307] Starting subtest: safe-alignment
[59.620056] Subtest safe-alignment: SUCCESS (0.022s)
[61.289991] [005/133] (956s left) gem_exec_fence (submit)
[61.465978] Starting subtest: submit
[61.470555] Subtest submit: SKIP (0.000s)
[63.283715] [FACT gem_exec_fence (submit)] new: kernel.kmod_is_loaded.vgem: true
[63.286927] [006/133] (954s left) syncobj_wait (single-wait-signaled)
[63.332685] Starting subtest: single-wait-signaled
[63.334874] Subtest single-wait-signaled: SUCCESS (0.000s)
[63.435376] [007/133] (954s left) gem_render_copy (y-tiled-to-vebox-y-tiled)
[63.492844] Starting subtest: y-tiled-to-vebox-y-tiled
[63.497020] Subtest y-tiled-to-vebox-y-tiled: SKIP (0.000s)
[63.696823] [008/133] (954s left) kms_cursor_legacy (flip-vs-cursor-legacy)
[63.786386] Starting subtest: flip-vs-cursor-legacy
[71.855068] Subtest flip-vs-cursor-legacy: SUCCESS (8.078s)
[72.213228] [009/133] (945s left) i915_drm_fdinfo (virtual-busy)
[72.257648] Starting subtest: virtual-busy
[72.261876] Subtest virtual-busy: SKIP (0.000s)
[72.355152] [010/133] (945s left) kms_big_fb (4-tiled-8bpp-rotate-270)
[72.638653] Starting subtest: 4-tiled-8bpp-rotate-270
[72.685028] Subtest 4-tiled-8bpp-rotate-270: SKIP (0.042s)
[73.010429] [011/133] (945s left) perf (missing-sample-flags)
[73.153098] Starting subtest: missing-sample-flags
[73.155243] Subtest missing-sample-flags: SUCCESS (0.000s)
[73.201969] [012/133] (945s left) kms_cursor_edge_walk (256x256-left-edge)
[73.348495] Starting subtest: 256x256-left-edge
[73.350790] Starting dynamic subtest: pipe-A-eDP-1
[76.782862] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.436s)
[76.792105] Starting dynamic subtest: pipe-D-eDP-1
[79.891933] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.110s)
[79.894370] Subtest 256x256-left-edge: SUCCESS (6.548s)
[80.711368] [013/133] (937s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-mmap-gtt)
[80.803578] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-mmap-gtt
[80.808123] Subtest fbc-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[80.910028] [014/133] (937s left) kms_pm_rpm (pm-caching)
[82.419328] Starting subtest: pm-caching
[82.424117] Subtest pm-caching: SKIP (0.000s)
[83.148078] [015/133] (935s left) kms_cursor_crc (cursor-sliding-256x256)
[83.384699] Starting subtest: cursor-sliding-256x256
[83.389339] Starting dynamic subtest: pipe-A-eDP-1
[86.704710] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.318s)
[86.733766] Starting dynamic subtest: pipe-D-eDP-1
[90.600723] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.875s)
[90.603064] Subtest cursor-sliding-256x256: SUCCESS (7.218s)
[91.402829] [016/133] (926s left) i915_pciid
[91.574752] [017/133] (926s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-mc-ccs)
[91.644494] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-mc-ccs
[91.646691] Starting dynamic subtest: pipe-A-eDP-1
[91.863965] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.188s)
[91.873611] Starting dynamic subtest: pipe-B-eDP-1
[93.141602] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.276s)
[93.152077] Starting dynamic subtest: pipe-C-eDP-1
[94.403395] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.264s)
[94.410713] Starting dynamic subtest: pipe-D-eDP-1
[95.628022] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.226s)
[95.629856] Subtest crc-sprite-planes-basic-4-tiled-mtl-mc-ccs: SUCCESS (3.956s)
[96.825316] [018/133] (921s left) kms_psr (pr-primary-page-flip)
[96.906378] Starting subtest: pr-primary-page-flip
[96.910272] Subtest pr-primary-page-flip: SKIP (0.000s)
[97.016769] [019/133] (921s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[97.096090] Starting subtest: addfb25-y-tiled-legacy
[97.098087] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[97.195366] [020/133] (921s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-mmap-wc)
[97.273303] Starting subtest: psr-1p-primscrn-spr-indfb-draw-mmap-wc
[103.878433] Subtest psr-1p-primscrn-spr-indfb-draw-mmap-wc: SUCCESS (6.610s)
[103.989533] [021/133] (914s left) kms_rotation_crc (bad-pixel-format)
[104.063189] Starting subtest: bad-pixel-format
[104.067478] Subtest bad-pixel-format: SKIP (0.001s)
[104.134679] [022/133] (914s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-onoff)
[104.215613] Starting subtest: psr-2p-scndscrn-cur-indfb-onoff
[104.220082] Subtest psr-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[104.310407] [023/133] (914s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted)
[104.359216] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted
[104.467059] Subtest etime-multi-wait-all-for-submit-unsubmitted: SUCCESS (0.108s)
[104.559734] [024/133] (914s left) syncobj_timeline (multi-wait-for-submit-signaled)
[104.613288] Starting subtest: multi-wait-for-submit-signaled
[104.615673] Subtest multi-wait-for-submit-signaled: SUCCESS (0.001s)
[104.729676] [025/133] (914s left) kms_flip (2x-flip-vs-rmfb-interruptible)
[104.797586] Starting subtest: 2x-flip-vs-rmfb-interruptible
[104.806831] Subtest 2x-flip-vs-rmfb-interruptible: SKIP (0.008s)
[104.889618] [026/133] (913s left) gem_exec_schedule (implicit-boths)
[104.952606] Starting subtest: implicit-boths
[104.954443] Starting dynamic subtest: rcs0
[105.054534] Dynamic subtest rcs0: SUCCESS (0.112s)
[105.065869] Starting dynamic subtest: vcs0
[105.131983] Dynamic subtest vcs0: SUCCESS (0.077s)
[105.143063] Starting dynamic subtest: vcs1
[105.210664] Dynamic subtest vcs1: SUCCESS (0.078s)
[105.220564] Starting dynamic subtest: vecs0
[105.288038] Dynamic subtest vecs0: SUCCESS (0.077s)
[105.297705] Starting dynamic subtest: ccs0
[105.389864] Dynamic subtest ccs0: SUCCESS (0.101s)
[105.392574] Subtest implicit-boths: SUCCESS (0.448s)
[106.318624] [027/133] (912s left) kms_pipe_b_c_ivb (pipe-b-dpms-off-modeset-pipe-c)
[106.376301] Subtest pipe-B-dpms-off-modeset-pipe-C: SKIP (0.000s)
[106.428796] [028/133] (912s left) kms_flip (2x-flip-vs-wf_vblank-interruptible)
[106.494687] Starting subtest: 2x-flip-vs-wf_vblank-interruptible
[106.504204] Subtest 2x-flip-vs-wf_vblank-interruptible: SKIP (0.007s)
[106.609636] [029/133] (912s left) gem_workarounds (basic-read-fd)
[106.684551] Starting subtest: basic-read-fd
[106.695781] Subtest basic-read-fd: SUCCESS (0.010s)
[106.823120] [030/133] (912s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[106.904793] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[106.909200] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SKIP (0.000s)
[106.998649] [031/133] (911s left) vgem_slow (nohang)
[107.046875] Starting subtest: nohang
[117.148129] Subtest nohang: SUCCESS (10.102s)
[117.202213] [032/133] (901s left) kms_dither (fb-8bpc-vs-panel-6bpc)
[117.293397] Starting subtest: fb-8bpc-vs-panel-6bpc
[117.295761] Starting dynamic subtest: pipe-A-eDP-1
[118.742566] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.450s)
[118.744849] Subtest fb-8bpc-vs-panel-6bpc: SUCCESS (1.451s)
[119.598225] [033/133] (899s left) perf_pmu (interrupts-sync)
[119.640065] Starting subtest: interrupts-sync
[120.714394] Subtest interrupts-sync: SUCCESS (1.073s)
[120.825441] [034/133] (898s left) gem_exec_schedule (preempt-hang)
[121.005696] Starting subtest: preempt-hang
[121.008486] Starting dynamic subtest: rcs0
[121.230431] Dynamic subtest rcs0: SUCCESS (0.224s)
[121.239311] Starting dynamic subtest: bcs0
[121.472141] Dynamic subtest bcs0: SUCCESS (0.241s)
[121.482439] Starting dynamic subtest: vcs0
[121.699546] Dynamic subtest vcs0: SUCCESS (0.227s)
[121.707944] Starting dynamic subtest: vcs1
[121.925409] Dynamic subtest vcs1: SUCCESS (0.226s)
[121.934170] Starting dynamic subtest: vecs0
[122.148844] Dynamic subtest vecs0: SUCCESS (0.223s)
[122.157654] Starting dynamic subtest: ccs0
[122.380664] Dynamic subtest ccs0: SUCCESS (0.233s)
[122.383204] Subtest preempt-hang: SUCCESS (1.377s)
[123.355964] [035/133] (895s left) kms_psr (fbc-psr2-sprite-render)
[123.439124] Starting subtest: fbc-psr2-sprite-render
[123.441085] Starting dynamic subtest: eDP-1
[123.445246] Dynamic subtest eDP-1: SKIP (0.000s)
[123.449452] Subtest fbc-psr2-sprite-render: SKIP (0.001s)
[123.518874] [036/133] (895s left) gem_gtt_cpu_tlb
[123.652882] [037/133] (895s left) vgem_basic (bad-handle)
[123.696450] Starting subtest: bad-handle
[123.698672] Subtest bad-handle: SUCCESS (0.000s)
[123.756258] [038/133] (895s left) gem_wait (write-wait)
[123.829810] Starting subtest: write-wait
[123.832202] Starting dynamic subtest: all-engines
[124.954713] Dynamic subtest all-engines: SUCCESS (1.123s)
[124.973386] Starting dynamic subtest: rcs0
[126.011892] Dynamic subtest rcs0: SUCCESS (1.058s)
[126.030784] Starting dynamic subtest: bcs0
[127.069066] Dynamic subtest bcs0: SUCCESS (1.056s)
[127.085961] Starting dynamic subtest: vcs0
[128.122266] Dynamic subtest vcs0: SUCCESS (1.052s)
[128.138769] Starting dynamic subtest: vcs1
[129.175456] Dynamic subtest vcs1: SUCCESS (1.052s)
[129.192414] Starting dynamic subtest: vecs0
[130.231588] Dynamic subtest vecs0: SUCCESS (1.055s)
[130.248721] Starting dynamic subtest: ccs0
[131.289456] Dynamic subtest ccs0: SUCCESS (1.056s)
[131.291947] Subtest write-wait: SUCCESS (7.460s)
[131.489289] [039/133] (887s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-wc)
[131.562399] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-wc
[131.566312] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[131.650661] [040/133] (887s left) drm_read (empty-block)
[131.783510] Starting subtest: empty-block
[132.781116] Subtest empty-block: SUCCESS (1.000s)
[132.933286] [041/133] (886s left) kms_force_connector_basic (force-connector-state)
[132.989583] Subtest force-connector-state: SKIP (0.000s)
[133.038413] [042/133] (886s left) kms_getfb (getfb-handle-protection)
[133.108970] Starting subtest: getfb-handle-protection
[133.131356] Subtest getfb-handle-protection: SUCCESS (0.025s)
[133.258057] [043/133] (886s left) kms_cursor_legacy (cursora-vs-flipb-toggle)
[133.334413] Starting subtest: cursorA-vs-flipB-toggle
[133.338778] Subtest cursorA-vs-flipB-toggle: SKIP (0.001s)
[133.429028] [044/133] (886s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[133.510697] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[133.514869] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[133.624869] [045/133] (885s left) gem_userptr_blits (sync-unmap-after-close)
[133.702852] Starting subtest: sync-unmap-after-close
[133.711598] Subtest sync-unmap-after-close: SUCCESS (0.009s)
[133.829965] [046/133] (885s left) kms_psr (fbc-pr-sprite-render)
[133.906346] Starting subtest: fbc-pr-sprite-render
[133.909979] Subtest fbc-pr-sprite-render: SKIP (0.000s)
[134.005454] [047/133] (885s left) kms_plane (pixel-format-y-tiled-gen12-rc-ccs-modifier)
[134.078896] Starting subtest: pixel-format-y-tiled-gen12-rc-ccs-modifier
[134.082143] Subtest pixel-format-y-tiled-gen12-rc-ccs-modifier: SKIP (0.000s)
[134.161655] [048/133] (885s left) sw_sync (sync_multi_timeline_wait)
[134.200775] Starting subtest: sync_multi_timeline_wait
[134.202420] Subtest sync_multi_timeline_wait: SUCCESS (0.000s)
[134.248602] [049/133] (885s left) kms_prop_blob (invalid-set-prop-any)
[134.300479] Starting subtest: invalid-set-prop-any
[134.302314] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[134.414212] [050/133] (885s left) kms_cursor_crc (cursor-onscreen-128x128)
[134.589108] Starting subtest: cursor-onscreen-128x128
[134.593524] Starting dynamic subtest: pipe-A-eDP-1
[136.675432] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.081s)
[136.704199] Starting dynamic subtest: pipe-D-eDP-1
[139.472679] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.776s)
[139.474862] Subtest cursor-onscreen-128x128: SUCCESS (4.882s)
[140.263725] [051/133] (879s left) kms_invalid_mode (zero-hdisplay)
[140.342297] Starting subtest: zero-hdisplay
[140.344567] Starting dynamic subtest: pipe-A-eDP-1
[140.640319] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.300s)
[140.649365] Starting dynamic subtest: pipe-B-eDP-1
[140.651559] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[140.659917] Starting dynamic subtest: pipe-C-eDP-1
[140.662211] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[140.670622] Starting dynamic subtest: pipe-D-eDP-1
[140.672883] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[140.675191] Subtest zero-hdisplay: SUCCESS (0.305s)
[141.487133] [052/133] (878s left) kms_getfb (getfb-handle-valid)
[141.558741] Starting subtest: getfb-handle-valid
[141.561266] Subtest getfb-handle-valid: SUCCESS (0.000s)
[141.683916] [053/133] (878s left) gem_exec_reloc (basic-softpin)
[141.745939] Subtest basic-softpin: SKIP (0.000s)
[141.828963] [054/133] (878s left) kms_flip (flip-vs-dpms-off-vs-modeset-interruptible)
[141.899066] Starting subtest: flip-vs-dpms-off-vs-modeset-interruptible
[141.901258] Starting dynamic subtest: A-eDP1
[145.539876] Dynamic subtest A-eDP1: SUCCESS (3.637s)
[145.549935] Starting dynamic subtest: B-eDP1
[148.678070] Dynamic subtest B-eDP1: SUCCESS (3.139s)
[148.688223] Starting dynamic subtest: C-eDP1
[151.772728] Dynamic subtest C-eDP1: SUCCESS (3.093s)
[151.782675] Starting dynamic subtest: D-eDP1
[154.874450] Dynamic subtest D-eDP1: SUCCESS (3.101s)
[154.876867] Subtest flip-vs-dpms-off-vs-modeset-interruptible: SUCCESS (12.977s)
[155.677846] [055/133] (864s left) gem_exec_balancer (bonded-chain)
[155.738513] Starting subtest: bonded-chain
[162.719892] Subtest bonded-chain: SUCCESS (6.980s)
[163.065160] [056/133] (856s left) gem_ctx_persistence (engines-queued)
[163.133389] Starting subtest: engines-queued
[163.135733] Starting dynamic subtest: rcs0
[163.205755] Dynamic subtest rcs0: SUCCESS (0.076s)
[163.216824] Starting dynamic subtest: bcs0
[163.269882] Dynamic subtest bcs0: SUCCESS (0.063s)
[163.280590] Starting dynamic subtest: vcs0
[165.213447] Dynamic subtest vcs0: SUCCESS (1.941s)
[165.224659] Starting dynamic subtest: vcs1
[167.194542] Dynamic subtest vcs1: SUCCESS (1.979s)
[167.204084] Starting dynamic subtest: vecs0
[169.180361] Dynamic subtest vecs0: SUCCESS (1.983s)
[169.190906] Starting dynamic subtest: ccs0
[169.373652] Dynamic subtest ccs0: SUCCESS (0.193s)
[169.376006] Subtest engines-queued: SUCCESS (6.245s)
[169.454613] [057/133] (850s left) kms_plane_scaling (planes-upscale-20x20)
[169.540850] Starting subtest: planes-upscale-20x20
[169.543088] Starting dynamic subtest: pipe-A
[169.618777] Dynamic subtest pipe-A: SUCCESS (0.078s)
[169.627503] Starting dynamic subtest: pipe-B
[170.783829] Dynamic subtest pipe-B: SUCCESS (1.164s)
[170.793482] Starting dynamic subtest: pipe-C
[171.902382] Dynamic subtest pipe-C: SUCCESS (1.118s)
[171.910903] Starting dynamic subtest: pipe-D
[173.032377] Dynamic subtest pipe-D: SUCCESS (1.130s)
[173.034713] Subtest planes-upscale-20x20: SUCCESS (3.492s)
[174.188204] [058/133] (845s left) perf (gen12-oa-tlb-invalidate)
[174.267538] Starting subtest: gen12-oa-tlb-invalidate
[174.269827] Starting dynamic subtest: 0-rcs0
[184.308437] Dynamic subtest 0-rcs0: SUCCESS (10.039s)
[184.318317] Starting dynamic subtest: 1-vcs0
[194.358338] Dynamic subtest 1-vcs0: SUCCESS (10.049s)
[194.360689] Subtest gen12-oa-tlb-invalidate: SUCCESS (20.090s)
[194.415580] [059/133] (825s left) gem_exec_fence (expired-history)
[194.869500] Starting subtest: expired-history
[201.947106] Subtest expired-history: SUCCESS (7.080s)
[204.286500] [060/133] (815s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-onoff)
[204.371048] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-onoff
[211.440309] Subtest fbcpsr-1p-primscrn-spr-indfb-onoff: SUCCESS (7.073s)
[211.600699] [061/133] (808s left) kms_ccs (ccs-on-another-bo-y-tiled-gen12-mc-ccs)
[211.701667] Starting subtest: ccs-on-another-bo-y-tiled-gen12-mc-ccs
[211.703934] Starting dynamic subtest: pipe-A-eDP-1
[211.725735] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[211.736609] Starting dynamic subtest: pipe-B-eDP-1
[211.757546] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[211.766249] Starting dynamic subtest: pipe-C-eDP-1
[211.787090] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[211.795730] Starting dynamic subtest: pipe-D-eDP-1
[211.817481] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[211.821858] Subtest ccs-on-another-bo-y-tiled-gen12-mc-ccs: SKIP (0.010s)
[213.984626] [062/133] (806s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180)
[214.072494] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180
[215.030818] Subtest x-tiled-max-hw-stride-64bpp-rotate-180: SUCCESS (0.959s)
[215.156511] [063/133] (805s left) kms_psr (fbc-pr-primary-blt)
[215.233107] Starting subtest: fbc-pr-primary-blt
[215.237224] Subtest fbc-pr-primary-blt: SKIP (0.000s)
[215.320514] [064/133] (804s left) kms_atomic_interruptible (legacy-setmode)
[215.392050] Starting subtest: legacy-setmode
[215.393926] Starting dynamic subtest: pipe-A-eDP-1
[222.288150] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.894s)
[222.290035] Subtest legacy-setmode: SUCCESS (6.895s)
[223.173968] [065/133] (797s left) kms_plane_scaling (invalid-num-scalers)
[223.252594] Starting subtest: invalid-num-scalers
[223.254821] Starting dynamic subtest: pipe-A-eDP-1-invalid-num-scalers
[223.269839] Dynamic subtest pipe-A-eDP-1-invalid-num-scalers: SUCCESS (0.018s)
[223.279112] Starting dynamic subtest: pipe-B-eDP-1-invalid-num-scalers
[223.302077] Dynamic subtest pipe-B-eDP-1-invalid-num-scalers: SUCCESS (0.030s)
[223.311046] Starting dynamic subtest: pipe-C-eDP-1-invalid-num-scalers
[223.333778] Dynamic subtest pipe-C-eDP-1-invalid-num-scalers: SUCCESS (0.030s)
[223.342630] Starting dynamic subtest: pipe-D-eDP-1-invalid-num-scalers
[223.363625] Dynamic subtest pipe-D-eDP-1-invalid-num-scalers: SUCCESS (0.030s)
[223.365908] Subtest invalid-num-scalers: SUCCESS (0.113s)
[223.458934] [066/133] (796s left) kms_plane_multiple (2x-tiling-4)
[223.533643] Starting subtest: 2x-tiling-4
[223.537886] Subtest 2x-tiling-4: SKIP (0.000s)
[223.617525] [067/133] (796s left) gem_mmap_gtt (hang-busy)
[223.663640] Subtest hang-busy: SKIP (0.000s)
[223.718484] [068/133] (796s left) kms_pm_backlight (brightness-with-dpms)
[223.783990] Starting subtest: brightness-with-dpms
[223.829121] Starting dynamic subtest: eDP-1
[226.260938] Dynamic subtest eDP-1: SUCCESS (2.435s)
[226.263576] Subtest brightness-with-dpms: SUCCESS (2.480s)
[227.157657] [069/133] (793s left) gem_mmap_gtt (cpuset-medium-copy-xy)
[227.212677] Subtest cpuset-medium-copy-XY: SKIP (0.000s)
[227.264744] [070/133] (793s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-pwrite)
[227.341581] Starting subtest: fbcpsr-rgb101010-draw-pwrite
[234.362710] Subtest fbcpsr-rgb101010-draw-pwrite: SUCCESS (7.025s)
[234.489293] [071/133] (786s left) api_intel_allocator (open-vm)
[234.547129] Starting subtest: open-vm
[234.549499] Subtest open-vm: SUCCESS (0.002s)
[234.664555] [072/133] (785s left) sw_sync (alloc_fence_invalid_timeline)
[234.696636] Starting subtest: alloc_fence_invalid_timeline
[234.698550] Subtest alloc_fence_invalid_timeline: SUCCESS (0.000s)
[234.755697] [073/133] (785s left) prime_vgem (basic-fence-flip)
[234.827825] Starting subtest: basic-fence-flip
[235.102420] Subtest basic-fence-flip: SUCCESS (0.274s)
[235.193810] [074/133] (785s left) kms_flip (2x-plain-flip-fb-recreate-interruptible)
[235.264204] Starting subtest: 2x-plain-flip-fb-recreate-interruptible
[235.272561] Subtest 2x-plain-flip-fb-recreate-interruptible: SKIP (0.007s)
[235.357477] [075/133] (785s left) gen9_exec_parse (batch-invalid-length)
[235.421704] Subtest batch-invalid-length: SKIP (0.000s)
[235.493904] [076/133] (785s left) kms_psr (psr2-primary-blt)
[235.575490] Starting subtest: psr2-primary-blt
[235.577807] Starting dynamic subtest: eDP-1
[237.968050] Dynamic subtest eDP-1: FAIL (1.810s)
[237.970406] Subtest psr2-primary-blt: FAIL (1.811s)
[238.010904] [077/133] (782s left) gem_ctx_param (invalid-size-set)
[238.070652] Starting subtest: invalid-size-set
[238.073171] Subtest invalid-size-set: SUCCESS (0.001s)
[238.174476] [078/133] (782s left) i915_pm_rpm (system-suspend-devices)
[240.386548] Starting subtest: system-suspend-devices
[248.174040] Subtest system-suspend-devices: SUCCESS (7.788s)
[248.544801] [079/133] (772s left) gem_caching (read-writes)
[248.631438] Subtest read-writes: SKIP (0.000s)
[248.704963] [080/133] (772s left) kms_ccs (crc-primary-suspend-4-tiled-dg2-rc-ccs-cc)
[248.778522] Starting subtest: crc-primary-suspend-4-tiled-dg2-rc-ccs-cc
[248.780939] Starting dynamic subtest: pipe-A-eDP-1
[248.790382] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[248.802231] Starting dynamic subtest: pipe-B-eDP-1
[248.810566] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[248.820061] Starting dynamic subtest: pipe-C-eDP-1
[248.828842] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[248.837787] Starting dynamic subtest: pipe-D-eDP-1
[248.846629] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[248.851145] Subtest crc-primary-suspend-4-tiled-dg2-rc-ccs-cc: SKIP (0.004s)
[251.019410] [081/133] (769s left) kms_ccs (bad-rotation-90-4-tiled-mtl-rc-ccs-cc)
[251.095185] Starting subtest: bad-rotation-90-4-tiled-mtl-rc-ccs-cc
[251.097465] Starting dynamic subtest: pipe-A-eDP-1
[251.244542] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.138s)
[251.255709] Starting dynamic subtest: pipe-B-eDP-1
[251.275937] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.010s)
[251.285290] Starting dynamic subtest: pipe-C-eDP-1
[251.303436] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.010s)
[251.312966] Starting dynamic subtest: pipe-D-eDP-1
[251.332155] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.011s)
[251.334321] Subtest bad-rotation-90-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.174s)
[253.598163] [082/133] (767s left) gem_exec_balancer (hog)
[253.672496] Starting subtest: hog
[253.677192] Subtest hog: SKIP (0.000s)
[253.971779] [083/133] (767s left) kms_big_fb (y-tiled-64bpp-rotate-180)
[254.069121] Starting subtest: y-tiled-64bpp-rotate-180
[254.073635] Subtest y-tiled-64bpp-rotate-180: SKIP (0.000s)
[254.179900] [084/133] (766s left) syncobj_timeline (invalid-transfer-illegal-handle)
[254.229256] Starting subtest: invalid-transfer-illegal-handle
[254.231147] Subtest invalid-transfer-illegal-handle: SUCCESS (0.000s)
[254.335040] [085/133] (766s left) kms_flip_scaled_crc (flip-32bpp-yftileccs-to-64bpp-yftile-upscaling)
[254.404720] Starting subtest: flip-32bpp-yftileccs-to-64bpp-yftile-upscaling
[254.409597] Subtest flip-32bpp-yftileccs-to-64bpp-yftile-upscaling: SKIP (0.000s)
[254.469016] [086/133] (766s left) gem_exec_schedule (submit-early-slice)
[254.547388] Starting subtest: submit-early-slice
[254.549379] Starting dynamic subtest: rcs0
[254.582266] Dynamic subtest rcs0: SUCCESS (0.042s)
[254.594681] Starting dynamic subtest: bcs0
[254.617044] Dynamic subtest bcs0: SUCCESS (0.034s)
[254.629882] Starting dynamic subtest: vcs0
[254.659480] Dynamic subtest vcs0: SUCCESS (0.042s)
[254.670277] Starting dynamic subtest: vcs1
[254.697572] Dynamic subtest vcs1: SUCCESS (0.037s)
[254.707261] Starting dynamic subtest: vecs0
[254.733334] Dynamic subtest vecs0: SUCCESS (0.035s)
[254.743263] Starting dynamic subtest: ccs0
[254.771799] Dynamic subtest ccs0: SUCCESS (0.038s)
[254.774075] Subtest submit-early-slice: SUCCESS (0.233s)
[256.342123] [087/133] (764s left) kms_addfb_basic (invalid-set-prop)
[256.392879] Starting subtest: invalid-set-prop
[256.395080] Subtest invalid-set-prop: SUCCESS (0.000s)
[256.518264] [088/133] (764s left) kms_chamelium_audio (dp-audio-edid)
[256.911058] Subtest dp-audio-edid: SKIP (0.000s)
[257.708704] [089/133] (763s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-pwrite)
[257.790223] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-pwrite
[264.628862] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-pwrite: SUCCESS (6.842s)
[264.742437] [090/133] (756s left) kms_vblank (wait-forked-hang)
[264.810682] Starting subtest: wait-forked-hang
[264.812628] Starting dynamic subtest: pipe-A-eDP-1
[291.032920] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.220s)
[291.048070] Starting dynamic subtest: pipe-D-eDP-1
[306.043274] Dynamic subtest pipe-D-eDP-1: SUCCESS (15.006s)
[306.045470] Subtest wait-forked-hang: SUCCESS (41.231s)
[306.970332] [091/133] (714s left) kms_lease (page-flip-implicit-plane)
[307.050834] Starting subtest: page-flip-implicit-plane
[307.053202] Starting dynamic subtest: pipe-A-eDP-1
[307.541147] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.487s)
[307.551943] Starting dynamic subtest: pipe-B-eDP-1
[309.393638] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.848s)
[309.404320] Starting dynamic subtest: pipe-C-eDP-1
[310.056644] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.664s)
[310.066887] Starting dynamic subtest: pipe-D-eDP-1
[311.177105] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.118s)
[311.179449] Subtest page-flip-implicit-plane: SUCCESS (4.127s)
[312.024107] [092/133] (709s left) gem_pxp (reject-modify-context-protection-off-3)
[312.151539] Starting subtest: reject-modify-context-protection-off-3
[312.154660] Subtest reject-modify-context-protection-off-3: SUCCESS (0.002s)
[312.276691] [093/133] (709s left) kms_atomic (plane-invalid-params-fence)
[312.356024] Starting subtest: plane-invalid-params-fence
[312.358084] Starting dynamic subtest: pipe-A-eDP-1
[312.715410] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.359s)
[312.717675] Subtest plane-invalid-params-fence: SUCCESS (0.360s)
[313.548841] [094/133] (708s left) kms_content_protection (dp-mst-lic-type-0-hdcp14)
[313.680174] Starting subtest: dp-mst-lic-type-0-hdcp14
[313.684435] Subtest dp-mst-lic-type-0-hdcp14: SKIP (0.000s)
[313.817777] [095/133] (707s left) kms_ccs (bad-aux-stride-yf-tiled-ccs)
[313.895935] Starting subtest: bad-aux-stride-yf-tiled-ccs
[313.898458] Starting dynamic subtest: pipe-A-eDP-1
[313.921909] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[313.934102] Starting dynamic subtest: pipe-B-eDP-1
[313.956034] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[313.965622] Starting dynamic subtest: pipe-C-eDP-1
[313.987808] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[313.997123] Starting dynamic subtest: pipe-D-eDP-1
[314.019972] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[314.024159] Subtest bad-aux-stride-yf-tiled-ccs: SKIP (0.004s)
[316.209023] [096/133] (705s left) i915_suspend (fence-restore-untiled)
[316.263981] Starting subtest: fence-restore-untiled
[316.268420] Subtest fence-restore-untiled: SKIP (0.000s)
[316.386116] [097/133] (705s left) kms_chamelium_hpd (vga-hpd-for-each-pipe)
[316.765247] Subtest vga-hpd-for-each-pipe: SKIP (0.000s)
[317.624739] [098/133] (704s left) gem_mmap_gtt (basic-short)
[317.678224] Subtest basic-short: SKIP (0.000s)
[317.737121] [099/133] (704s left) gem_ppgtt (shrink-vs-evict-any)
[317.792623] Starting subtest: shrink-vs-evict-any
[323.223698] Subtest shrink-vs-evict-any: SUCCESS (5.430s)
[323.309438] [100/133] (698s left) syncobj_wait (invalid-signal-illegal-handle)
[323.373140] Starting subtest: invalid-signal-illegal-handle
[323.375019] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s)
[323.474072] [101/133] (698s left) gem_lmem_swapping (parallel-multi)
[323.519586] Subtest parallel-multi: SKIP (0.000s)
[323.563509] [102/133] (698s left) kms_frontbuffer_tracking (fbcpsr-2p-shrfb-fliptrack-mmap-gtt)
[323.650831] Starting subtest: fbcpsr-2p-shrfb-fliptrack-mmap-gtt
[323.655244] Subtest fbcpsr-2p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[323.741711] [103/133] (698s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-gtt)
[323.828365] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-gtt
[323.832691] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[323.937115] [104/133] (698s left) gem_softpin (invalid)
[323.992326] Starting subtest: invalid
[323.999078] Subtest invalid: SUCCESS (0.005s)
[324.120920] [105/133] (697s left) gem_create (create-ext-cpu-access-sanity-check)
[324.280225] Starting subtest: create-ext-cpu-access-sanity-check
[324.285355] Subtest create-ext-cpu-access-sanity-check: SKIP (0.002s)
[324.362167] [106/133] (697s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-plflip-blt)
[324.441825] Starting subtest: psr-1p-primscrn-indfb-plflip-blt
[331.904262] Subtest psr-1p-primscrn-indfb-plflip-blt: SUCCESS (7.465s)
[332.031663] [107/133] (690s left) kms_color (invalid-gamma-lut-sizes)
[332.107604] Starting subtest: invalid-gamma-lut-sizes
[332.110051] Starting dynamic subtest: pipe-A
[332.412660] Dynamic subtest pipe-A: SUCCESS (0.304s)
[332.427634] Starting dynamic subtest: pipe-B
[332.429991] Dynamic subtest pipe-B: SUCCESS (0.000s)
[332.442750] Starting dynamic subtest: pipe-C
[332.445043] Dynamic subtest pipe-C: SUCCESS (0.000s)
[332.454232] Starting dynamic subtest: pipe-D
[332.456389] Dynamic subtest pipe-D: SUCCESS (0.000s)
[332.458578] Subtest invalid-gamma-lut-sizes: SUCCESS (0.308s)
[333.210993] [108/133] (688s left) device_reset (unbind-reset-rebind)
[333.269678] Starting subtest: unbind-reset-rebind
[338.200457] Subtest unbind-reset-rebind: SUCCESS (4.926s)
[338.244928] [109/133] (683s left) prime_vgem (basic-blt)
[338.332923] Starting subtest: basic-blt
[338.358547] Subtest basic-blt: SUCCESS (0.026s)
[338.479974] [110/133] (683s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-mmap-gtt)
[338.577269] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-mmap-gtt
[338.581291] Subtest fbc-1p-offscreen-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[338.666384] [111/133] (683s left) kms_cursor_legacy (short-flip-before-cursor-atomic-transitions)
[338.776738] Starting subtest: short-flip-before-cursor-atomic-transitions
[339.767207] Subtest short-flip-before-cursor-atomic-transitions: SUCCESS (0.988s)
[340.570694] [112/133] (681s left) perf_pmu (enable-race)
[340.609560] Starting subtest: enable-race
[340.611876] Starting dynamic subtest: rcs0
[353.371616] Dynamic subtest rcs0: SUCCESS (12.762s)
[353.382144] Starting dynamic subtest: bcs0
[366.109578] Dynamic subtest bcs0: SUCCESS (12.735s)
[366.121472] Starting dynamic subtest: vcs0
[381.183851] Dynamic subtest vcs0: SUCCESS (15.070s)
[381.197049] Starting dynamic subtest: vcs1
[397.247470] Dynamic subtest vcs1: SUCCESS (16.061s)
[397.260933] Starting dynamic subtest: vecs0
[413.247609] Dynamic subtest vecs0: SUCCESS (15.997s)
[413.260996] Starting dynamic subtest: ccs0
[425.992519] Dynamic subtest ccs0: SUCCESS (12.743s)
[425.995517] Subtest enable-race: SUCCESS (85.382s)
[426.096975] [113/133] (596s left) gem_basic (multigpu-create-close)
[426.159921] Starting subtest: multigpu-create-close
[426.163983] Subtest multigpu-create-close: SKIP (0.000s)
[426.260491] [114/133] (596s left) kms_chamelium_edid (dp-edid-read)
[426.633982] Subtest dp-edid-read: SKIP (0.000s)
[427.477474] [115/133] (594s left) gem_ctx_persistence (userptr)
[427.530215] Starting subtest: userptr
[427.706892] Subtest userptr: SUCCESS (0.176s)
[427.799066] [116/133] (594s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-shrfb-msflip-blt)
[427.876121] Starting subtest: fbc-2p-scndscrn-shrfb-msflip-blt
[427.880396] Subtest fbc-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[427.960999] [117/133] (594s left) syncobj_timeline (single-wait-all-for-submit-available-signaled)
[428.023452] Starting subtest: single-wait-all-for-submit-available-signaled
[428.025678] Subtest single-wait-all-for-submit-available-signaled: SUCCESS (0.000s)
[428.135233] [118/133] (594s left) kms_sequence (get-forked-busy)
[428.205153] Starting subtest: get-forked-busy
[428.207589] Starting dynamic subtest: pipe-A-eDP-1
[430.150378] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.944s)
[430.161057] Starting dynamic subtest: pipe-B-eDP-1
[432.809285] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.658s)
[432.820839] Starting dynamic subtest: pipe-C-eDP-1
[435.420561] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.611s)
[435.431852] Starting dynamic subtest: pipe-D-eDP-1
[437.971441] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.549s)
[437.973741] Subtest get-forked-busy: SUCCESS (9.767s)
[438.768653] [119/133] (583s left) syncobj_wait (invalid-multi-wait-unsubmitted)
[438.824792] Starting subtest: invalid-multi-wait-unsubmitted
[438.826998] Subtest invalid-multi-wait-unsubmitted: SUCCESS (0.000s)
[438.917409] [120/133] (583s left) kms_cursor_legacy (short-flip-after-cursor-atomic-transitions)
[439.009786] Starting subtest: short-flip-after-cursor-atomic-transitions
[439.967663] Subtest short-flip-after-cursor-atomic-transitions: SUCCESS (0.957s)
[440.800197] [121/133] (581s left) kms_big_fb (x-tiled-16bpp-rotate-0)
[440.873286] Starting subtest: x-tiled-16bpp-rotate-0
[442.063975] Subtest x-tiled-16bpp-rotate-0: SUCCESS (1.190s)
[442.186028] [122/133] (580s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-submitted-signa
[442.242710] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-submitted-signaled
[442.346984] Subtest etime-multi-wait-all-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.103s)
[442.439532] [123/133] (580s left) gem_exec_suspend (basic-s3)
[442.490813] Starting subtest: basic-S3
[442.493235] Starting dynamic subtest: smem
[463.056891] Dynamic subtest smem: SUCCESS (6.731s)
[463.060038] Subtest basic-S3: SUCCESS (6.733s)
[463.243993] [124/133] (559s left) kms_draw_crc (draw-method-mmap-wc)
[463.329726] Starting subtest: draw-method-mmap-wc
[463.332078] Starting dynamic subtest: xrgb8888-untiled
[463.561570] Dynamic subtest xrgb8888-untiled: SUCCESS (0.233s)
[463.571600] Starting dynamic subtest: rgb565-untiled
[463.760803] Dynamic subtest rgb565-untiled: SUCCESS (0.199s)
[463.773010] Starting dynamic subtest: xrgb2101010-untiled
[463.959803] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.199s)
[463.973302] Starting dynamic subtest: xbgr16161616f-untiled
[464.170064] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.209s)
[464.179922] Starting dynamic subtest: xrgb8888-xtiled
[464.291294] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.121s)
[464.303109] Starting dynamic subtest: rgb565-xtiled
[464.413033] Dynamic subtest rgb565-xtiled: SUCCESS (0.121s)
[464.423209] Starting dynamic subtest: xrgb2101010-xtiled
[464.523822] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.110s)
[464.538813] Starting dynamic subtest: xbgr16161616f-xtiled
[464.656630] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.132s)
[464.666720] Starting dynamic subtest: xrgb8888-4tiled
[464.800541] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.144s)
[464.812617] Starting dynamic subtest: rgb565-4tiled
[464.944473] Dynamic subtest rgb565-4tiled: SUCCESS (0.143s)
[464.954645] Starting dynamic subtest: xrgb2101010-4tiled
[465.077342] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.132s)
[465.090113] Starting dynamic subtest: xbgr16161616f-4tiled
[465.243414] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.166s)
[465.245256] Subtest draw-method-mmap-wc: SUCCESS (1.923s)
[465.350891] [125/133] (557s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[465.432745] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu
[465.435798] Subtest fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[465.527049] [126/133] (557s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-cpu)
[465.599852] Starting subtest: psr-rgb565-draw-mmap-cpu
[472.385313] Subtest psr-rgb565-draw-mmap-cpu: SUCCESS (6.803s)
[472.498276] [127/133] (550s left) kms_plane_alpha_blend (constant-alpha-max)
[472.564950] Starting subtest: constant-alpha-max
[472.840943] Starting dynamic subtest: pipe-A-eDP-1
[473.817778] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.976s)
[473.828811] Starting dynamic subtest: pipe-D-eDP-1
[475.408274] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.590s)
[475.410693] Subtest constant-alpha-max: SUCCESS (2.846s)
[476.248864] [128/133] (546s left) kms_psr2_sf (psr2-cursor-plane-update-sf)
[476.331247] Starting subtest: psr2-cursor-plane-update-sf
[476.333573] Starting dynamic subtest: pipe-A-eDP-1
[476.895549] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.567s)
[476.912601] Starting dynamic subtest: pipe-B-eDP-1
[478.222025] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.327s)
[478.224334] Subtest psr2-cursor-plane-update-sf: SUCCESS (1.898s)
[479.089301] [129/133] (543s left) kms_psr (psr-sprite-plane-onoff)
[479.153423] Starting subtest: psr-sprite-plane-onoff
[479.155547] Starting dynamic subtest: eDP-1
[480.934583] Dynamic subtest eDP-1: SUCCESS (1.781s)
[480.936473] Subtest psr-sprite-plane-onoff: SUCCESS (1.782s)
[481.742519] [130/133] (541s left) kms_fbcon_fbt (fbc-suspend)
[481.806847] Starting subtest: fbc-suspend
[531.391919] Subtest fbc-suspend: SUCCESS (19.371s)
[532.729314] [131/133] (490s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-render)
[532.833698] Starting subtest: fbc-rgb101010-draw-render
[537.447891] Subtest fbc-rgb101010-draw-render: SUCCESS (4.625s)
[538.626066] [132/133] (484s left) gem_exec_schedule (wide)
[538.796103] Starting subtest: wide
[538.798680] Starting dynamic subtest: rcs0
[548.745435] Dynamic subtest rcs0: SUCCESS (9.959s)
[548.759074] Starting dynamic subtest: vcs0
[558.918692] Dynamic subtest vcs0: SUCCESS (10.176s)
[558.931809] Starting dynamic subtest: vcs1
[569.336630] Dynamic subtest vcs1: SUCCESS (10.410s)
[569.350485] Starting dynamic subtest: vecs0
[578.847946] Dynamic subtest vecs0: SUCCESS (9.508s)
[578.861250] Starting dynamic subtest: ccs0
[591.462984] Dynamic subtest ccs0: SUCCESS (12.615s)
[591.465156] Subtest wide: SUCCESS (52.677s)
[592.584477] [133/133] (430s left) kms_flip_scaled_crc (flip-64bpp-linear-to-32bpp-linear-downscaling)
[592.671097] Starting subtest: flip-64bpp-linear-to-32bpp-linear-downscaling
[592.673322] Starting dynamic subtest: pipe-A-default-mode
[593.867830] Dynamic subtest pipe-A-default-mode: SKIP (1.194s)
[593.873762] Subtest flip-64bpp-linear-to-32bpp-linear-downscaling: SKIP (1.196s)
[594.023554] Closing watchdogs
results: parsing output: 0/ for test: kms_big_fb
results: parsing output: 1/ for test: gem_softpin
results: parsing output: 2/ for test: api_intel_bb
results: parsing output: 3/ for test: gem_softpin
results: parsing output: 4/ for test: gem_exec_fence
results: parsing output: 5/ for test: syncobj_wait
results: parsing output: 6/ for test: gem_render_copy
results: parsing output: 7/ for test: kms_cursor_legacy
results: parsing output: 8/ for test: i915_drm_fdinfo
results: parsing output: 9/ for test: kms_big_fb
results: parsing output: 10/ for test: perf
results: parsing output: 11/ for test: kms_cursor_edge_walk
results: parsing output: 12/ for test: kms_frontbuffer_tracking
results: parsing output: 13/ for test: kms_pm_rpm
results: parsing output: 14/ for test: kms_cursor_crc
results: parsing output: 15/ for test: i915_pciid
results: parsing output: 16/ for test: kms_ccs
results: parsing output: 17/ for test: kms_psr
results: parsing output: 18/ for test: kms_addfb_basic
results: parsing output: 19/ for test: kms_frontbuffer_tracking
results: parsing output: 20/ for test: kms_rotation_crc
results: parsing output: 21/ for test: kms_frontbuffer_tracking
results: parsing output: 22/ for test: syncobj_timeline
results: parsing output: 23/ for test: syncobj_timeline
results: parsing output: 24/ for test: kms_flip
results: parsing output: 25/ for test: gem_exec_schedule
results: parsing output: 26/ for test: kms_pipe_b_c_ivb
results: parsing output: 27/ for test: kms_flip
results: parsing output: 28/ for test: gem_workarounds
results: parsing output: 29/ for test: kms_big_fb
results: parsing output: 30/ for test: vgem_slow
results: parsing output: 31/ for test: kms_dither
results: parsing output: 32/ for test: perf_pmu
results: parsing output: 33/ for test: gem_exec_schedule
results: parsing output: 34/ for test: kms_psr
results: parsing output: 35/ for test: gem_gtt_cpu_tlb
results: parsing output: 36/ for test: vgem_basic
results: parsing output: 37/ for test: gem_wait
results: parsing output: 38/ for test: kms_frontbuffer_tracking
results: parsing output: 39/ for test: drm_read
results: parsing output: 40/ for test: kms_force_connector_basic
results: parsing output: 41/ for test: kms_getfb
results: parsing output: 42/ for test: kms_cursor_legacy
results: parsing output: 43/ for test: kms_frontbuffer_tracking
results: parsing output: 44/ for test: gem_userptr_blits
results: parsing output: 45/ for test: kms_psr
results: parsing output: 46/ for test: kms_plane
results: parsing output: 47/ for test: sw_sync
results: parsing output: 48/ for test: kms_prop_blob
results: parsing output: 49/ for test: kms_cursor_crc
results: parsing output: 50/ for test: kms_invalid_mode
results: parsing output: 51/ for test: kms_getfb
results: parsing output: 52/ for test: gem_exec_reloc
results: parsing output: 53/ for test: kms_flip
results: parsing output: 54/ for test: gem_exec_balancer
results: parsing output: 55/ for test: gem_ctx_persistence
results: parsing output: 56/ for test: kms_plane_scaling
results: parsing output: 57/ for test: perf
results: parsing output: 58/ for test: gem_exec_fence
results: parsing output: 59/ for test: kms_frontbuffer_tracking
results: parsing output: 60/ for test: kms_ccs
results: parsing output: 61/ for test: kms_big_fb
results: parsing output: 62/ for test: kms_psr
results: parsing output: 63/ for test: kms_atomic_interruptible
results: parsing output: 64/ for test: kms_plane_scaling
results: parsing output: 65/ for test: kms_plane_multiple
results: parsing output: 66/ for test: gem_mmap_gtt
results: parsing output: 67/ for test: kms_pm_backlight
results: parsing output: 68/ for test: gem_mmap_gtt
results: parsing output: 69/ for test: kms_frontbuffer_tracking
results: parsing output: 70/ for test: api_intel_allocator
results: parsing output: 71/ for test: sw_sync
results: parsing output: 72/ for test: prime_vgem
results: parsing output: 73/ for test: kms_flip
results: parsing output: 74/ for test: gen9_exec_parse
results: parsing output: 75/ for test: kms_psr
results: parsing output: 76/ for test: gem_ctx_param
results: parsing output: 77/ for test: i915_pm_rpm
results: parsing output: 78/ for test: gem_caching
results: parsing output: 79/ for test: kms_ccs
results: parsing output: 80/ for test: kms_ccs
results: parsing output: 81/ for test: gem_exec_balancer
results: parsing output: 82/ for test: kms_big_fb
results: parsing output: 83/ for test: syncobj_timeline
results: parsing output: 84/ for test: kms_flip_scaled_crc
results: parsing output: 85/ for test: gem_exec_schedule
results: parsing output: 86/ for test: kms_addfb_basic
results: parsing output: 87/ for test: kms_chamelium_audio
results: parsing output: 88/ for test: kms_frontbuffer_tracking
results: parsing output: 89/ for test: kms_vblank
results: parsing output: 90/ for test: kms_lease
results: parsing output: 91/ for test: gem_pxp
results: parsing output: 92/ for test: kms_atomic
results: parsing output: 93/ for test: kms_content_protection
results: parsing output: 94/ for test: kms_ccs
results: parsing output: 95/ for test: i915_suspend
results: parsing output: 96/ for test: kms_chamelium_hpd
results: parsing output: 97/ for test: gem_mmap_gtt
results: parsing output: 98/ for test: gem_ppgtt
results: parsing output: 99/ for test: syncobj_wait
results: parsing output: 100/ for test: gem_lmem_swapping
results: parsing output: 101/ for test: kms_frontbuffer_tracking
results: parsing output: 102/ for test: kms_frontbuffer_tracking
results: parsing output: 103/ for test: gem_softpin
results: parsing output: 104/ for test: gem_create
results: parsing output: 105/ for test: kms_frontbuffer_tracking
results: parsing output: 106/ for test: kms_color
results: parsing output: 107/ for test: device_reset
results: parsing output: 108/ for test: prime_vgem
results: parsing output: 109/ for test: kms_frontbuffer_tracking
results: parsing output: 110/ for test: kms_cursor_legacy
results: parsing output: 111/ for test: perf_pmu
results: parsing output: 112/ for test: gem_basic
results: parsing output: 113/ for test: kms_chamelium_edid
results: parsing output: 114/ for test: gem_ctx_persistence
results: parsing output: 115/ for test: kms_frontbuffer_tracking
results: parsing output: 116/ for test: syncobj_timeline
results: parsing output: 117/ for test: kms_sequence
results: parsing output: 118/ for test: syncobj_wait
results: parsing output: 119/ for test: kms_cursor_legacy
results: parsing output: 120/ for test: kms_big_fb
results: parsing output: 121/ for test: syncobj_timeline
results: parsing output: 122/ for test: gem_exec_suspend
results: parsing output: 123/ for test: kms_draw_crc
results: parsing output: 124/ for test: kms_frontbuffer_tracking
results: parsing output: 125/ for test: kms_frontbuffer_tracking
results: parsing output: 126/ for test: kms_plane_alpha_blend
results: parsing output: 127/ for test: kms_psr2_sf
results: parsing output: 128/ for test: kms_psr
results: parsing output: 129/ for test: kms_fbcon_fbt
results: parsing output: 130/ for test: kms_frontbuffer_tracking
results: parsing output: 131/ for test: gem_exec_schedule
results: parsing output: 132/ for test: kms_flip_scaled_crc
Done.