[63.273785] Initializing watchdogs
[63.273841]   /dev/watchdog0
[63.288602] [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]
[63.293460] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[63.295695] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[63.297897] [001/133] (960s left) i915_drm_fdinfo (busy-idle)
[63.344180] Starting subtest: busy-idle
[63.345279] Starting dynamic subtest: rcs0
[63.363150] Dynamic subtest rcs0: SKIP (0.018s)
[63.371043] Starting dynamic subtest: bcs0
[63.372821] Dynamic subtest bcs0: SKIP (0.000s)
[63.378285] Starting dynamic subtest: vcs0
[63.379918] Dynamic subtest vcs0: SKIP (0.000s)
[63.385098] Starting dynamic subtest: vcs1
[63.386889] Dynamic subtest vcs1: SKIP (0.000s)
[63.392353] Starting dynamic subtest: vecs0
[63.394057] Dynamic subtest vecs0: SKIP (0.000s)
[63.399270] Starting dynamic subtest: ccs0
[63.400905] Dynamic subtest ccs0: SKIP (0.000s)
[63.402805] Subtest busy-idle: SKIP (0.020s)
[63.427965] [002/133] (960s left) kms_hdr (static-toggle-dpms)
[63.530905] Starting subtest: static-toggle-dpms
[63.533397] Subtest static-toggle-dpms: SKIP (0.000s)
[63.606344] [003/133] (960s left) gem_ctx_param (basic-default)
[63.663606] Starting subtest: basic-default
[63.664610] Subtest basic-default: SUCCESS (0.000s)
[63.730795] [004/133] (960s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-wc)
[63.822384] Starting subtest: psr-rgb565-draw-mmap-wc
[70.737546] Subtest psr-rgb565-draw-mmap-wc: SUCCESS (6.924s)
[70.838193] [005/133] (953s left) gem_render_copy (yf-tiled-ccs-to-linear)
[70.886276] Starting subtest: yf-tiled-ccs-to-linear
[70.888342] Subtest yf-tiled-ccs-to-linear: SKIP (0.000s)
[71.063656] [006/133] (952s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-render)
[71.158346] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-render
[78.093363] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-render: SUCCESS (6.927s)
[78.198348] [007/133] (945s left) i915_pm_rps (min-max-config-loaded)
[78.267457] Starting subtest: min-max-config-loaded
[78.269566] Subtest min-max-config-loaded: SKIP (0.001s)
[78.366803] [008/133] (945s left) kms_frontbuffer_tracking (fbcpsr-shrfb-scaledprimary)
[78.452372] Starting subtest: fbcpsr-shrfb-scaledprimary
[84.270912] Subtest fbcpsr-shrfb-scaledprimary: SUCCESS (5.819s)
[84.397741] [009/133] (939s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-onoff)
[84.502197] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-onoff
[91.554966] Subtest fbcpsr-1p-primscrn-cur-indfb-onoff: SUCCESS (7.053s)
[91.694398] [010/133] (932s left) kms_rotation_crc (cursor-rotation-180)
[91.774216] Starting subtest: cursor-rotation-180
[93.282209] Subtest cursor-rotation-180: SUCCESS (1.508s)
[94.450725] [011/133] (929s left) syncobj_wait (reset-multiple-signaled)
[94.504290] Starting subtest: reset-multiple-signaled
[94.505305] Subtest reset-multiple-signaled: SUCCESS (0.000s)
[94.584329] [012/133] (929s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-signaled)
[94.638953] Starting subtest: invalid-multi-wait-all-unsubmitted-signaled
[94.639766] Subtest invalid-multi-wait-all-unsubmitted-signaled: SUCCESS (0.001s)
[94.741108] [013/133] (929s left) kms_psr (psr2-basic)
[94.827215] Starting subtest: psr2-basic
[94.828065] Starting dynamic subtest: eDP-1
[96.428724] Dynamic subtest eDP-1: SUCCESS (1.601s)
[96.429642] Subtest psr2-basic: SUCCESS (1.601s)
[97.225839] [014/133] (926s left) kms_vblank (ts-continuation-modeset)
[97.327185] Starting subtest: ts-continuation-modeset
[97.328069] Starting dynamic subtest: pipe-A-eDP-1
[98.782454] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.455s)
[98.790413] Starting dynamic subtest: pipe-D-eDP-1
[100.945152] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.160s)
[100.946065] Subtest ts-continuation-modeset: SUCCESS (3.618s)
[101.735698] [015/133] (922s left) syncobj_timeline (wait-for-submit-snapshot)
[101.790206] Starting subtest: wait-for-submit-snapshot
[101.873583] Subtest wait-for-submit-snapshot: SUCCESS (0.083s)
[101.939506] [016/133] (922s left) gem_mmap_gtt (cpuset-medium-copy-odd)
[102.006331] Subtest cpuset-medium-copy-odd: SKIP (0.000s)
[102.053877] [017/133] (922s left) gem_ctx_param (invalid-get-ringsize)
[102.097406] Starting subtest: invalid-get-ringsize
[102.098375] Subtest invalid-get-ringsize: SUCCESS (0.001s)
[102.187756] [018/133] (922s left) gem_tiled_fence_blits (normal)
[102.247764] Subtest normal: SKIP (0.000s)
[102.313760] [019/133] (922s left) gem_linear_blits (interruptible)
[102.649880] Starting subtest: interruptible
[107.061456] Subtest interruptible: SUCCESS (4.402s)
[107.178949] [020/133] (917s left) kms_big_fb (x-tiled-64bpp-rotate-270)
[107.337212] Starting subtest: x-tiled-64bpp-rotate-270
[107.356667] Subtest x-tiled-64bpp-rotate-270: SKIP (0.019s)
[107.477895] [021/133] (916s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-modifiers)
[107.568350] Starting subtest: plane-scaler-with-clipping-clamping-modifiers
[107.569332] Starting dynamic subtest: pipe-A
[107.917388] Dynamic subtest pipe-A: SUCCESS (0.351s)
[107.923854] Starting dynamic subtest: pipe-B
[109.343225] Dynamic subtest pipe-B: SUCCESS (1.425s)
[109.350334] Starting dynamic subtest: pipe-C
[110.795342] Dynamic subtest pipe-C: SUCCESS (1.452s)
[110.801921] Starting dynamic subtest: pipe-D
[112.225959] Dynamic subtest pipe-D: SUCCESS (1.430s)
[112.227111] Subtest plane-scaler-with-clipping-clamping-modifiers: SUCCESS (4.660s)
[113.384866] [022/133] (911s left) kms_pm_rpm (system-suspend-modeset)
[115.038068] Starting subtest: system-suspend-modeset
[135.405939] Subtest system-suspend-modeset: SUCCESS (5.242s)
[138.790072] [023/133] (888s left) syncobj_timeline (multi-wait-all-for-submit-available-submitted-signaled)
[138.852810] Starting subtest: multi-wait-all-for-submit-available-submitted-signaled
[138.853867] Subtest multi-wait-all-for-submit-available-submitted-signaled: SUCCESS (0.000s)
[138.949877] [024/133] (888s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling)
[139.047301] Starting subtest: flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling
[139.049531] Subtest flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling: SKIP (0.000s)
[139.126086] [025/133] (888s left) gem_ctx_param (non-root-set)
[139.180098] Starting subtest: non-root-set
[139.207378] Subtest non-root-set: SUCCESS (0.028s)
[139.312879] [026/133] (888s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[139.412418] Starting subtest: psr-2p-primscrn-cur-indfb-draw-mmap-cpu
[139.414174] Subtest psr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[139.510262] [027/133] (888s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[139.605914] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[140.476118] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SUCCESS (0.881s)
[140.637109] [028/133] (887s left) gem_ctx_persistence (file)
[140.697057] Starting subtest: file
[140.824995] Subtest file: SUCCESS (0.129s)
[140.931980] [029/133] (886s left) kms_flip_scaled_crc (flip-32bpp-xtile-to-64bpp-xtile-downscaling)
[141.023545] Starting subtest: flip-32bpp-xtile-to-64bpp-xtile-downscaling
[141.024538] Starting dynamic subtest: pipe-A-default-mode
[142.195208] Dynamic subtest pipe-A-default-mode: SKIP (1.182s)
[142.196956] Subtest flip-32bpp-xtile-to-64bpp-xtile-downscaling: SKIP (1.184s)
[142.347423] [030/133] (885s left) kms_plane_scaling (plane-upscale-factor-0-25-with-pixel-format)
[142.426818] Starting subtest: plane-upscale-factor-0-25-with-pixel-format
[142.427729] Starting dynamic subtest: pipe-A
[143.467026] Dynamic subtest pipe-A: SUCCESS (1.050s)
[143.474289] Starting dynamic subtest: pipe-B
[145.530068] Dynamic subtest pipe-B: SUCCESS (2.078s)
[145.537447] Starting dynamic subtest: pipe-C
[146.724230] Dynamic subtest pipe-C: SUCCESS (1.200s)
[146.734783] Starting dynamic subtest: pipe-D
[147.876036] Dynamic subtest pipe-D: SUCCESS (1.157s)
[147.876925] Subtest plane-upscale-factor-0-25-with-pixel-format: SUCCESS (5.489s)
[149.052005] [031/133] (878s left) kms_flip (flip-vs-dpms-off-vs-modeset-interruptible)
[149.138012] Starting subtest: flip-vs-dpms-off-vs-modeset-interruptible
[149.140175] Starting dynamic subtest: A-eDP1
[152.687809] Dynamic subtest A-eDP1: SUCCESS (3.559s)
[152.695308] Starting dynamic subtest: B-eDP1
[155.886462] Dynamic subtest B-eDP1: SUCCESS (3.205s)
[155.893738] Starting dynamic subtest: C-eDP1
[158.977826] Dynamic subtest C-eDP1: SUCCESS (3.096s)
[158.985644] Starting dynamic subtest: D-eDP1
[162.083895] Dynamic subtest D-eDP1: SUCCESS (3.109s)
[162.085978] Subtest flip-vs-dpms-off-vs-modeset-interruptible: SUCCESS (12.977s)
[162.895439] [032/133] (864s left) gem_exec_parallel (contexts)
[162.961719] Starting subtest: contexts
[162.962759] Starting dynamic subtest: rcs0
[166.101872] Dynamic subtest rcs0: SUCCESS (3.133s)
[166.111794] Starting dynamic subtest: bcs0
[169.748610] Dynamic subtest bcs0: SUCCESS (3.642s)
[169.758006] Starting dynamic subtest: vcs0
[173.527045] Dynamic subtest vcs0: SUCCESS (3.775s)
[173.534421] Starting dynamic subtest: vcs1
[177.587652] Dynamic subtest vcs1: SUCCESS (4.057s)
[177.600260] Starting dynamic subtest: vecs0
[181.581769] Dynamic subtest vecs0: SUCCESS (3.993s)
[181.591533] Starting dynamic subtest: ccs0
[185.255873] Dynamic subtest ccs0: SUCCESS (3.673s)
[185.256993] Subtest contexts: SUCCESS (22.286s)
[185.438303] [033/133] (842s left) gem_exec_alignment (many)
[185.489202] Starting subtest: many
[211.297101] Subtest many: SUCCESS (25.805s)
[213.932762] [034/133] (813s left) kms_psr (fbc-pr-no-drrs)
[214.076867] Starting subtest: fbc-pr-no-drrs
[214.078693] Subtest fbc-pr-no-drrs: SKIP (0.000s)
[214.160305] [035/133] (813s left) kms_vrr (flip-suspend)
[214.241555] Starting subtest: flip-suspend
[214.243893] Subtest flip-suspend: SKIP (0.000s)
[214.306415] [036/133] (813s left) i915_module_load (reload)
[214.341265] Starting subtest: reload
[219.259454] Subtest reload: SUCCESS (4.922s)
[219.293675] [037/133] (808s left) gem_vm_create (invalid-destroy)
[220.955975] Starting subtest: invalid-destroy
[220.957067] Subtest invalid-destroy: SUCCESS (0.002s)
[221.069163] [038/133] (806s left) kms_ccs (crc-primary-suspend-4-tiled-dg2-rc-ccs)
[221.166463] Starting subtest: crc-primary-suspend-4-tiled-dg2-rc-ccs
[221.167236] Starting dynamic subtest: pipe-A-eDP-1
[221.170604] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[221.179521] Starting dynamic subtest: pipe-B-eDP-1
[221.182908] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[221.189815] Starting dynamic subtest: pipe-C-eDP-1
[221.193325] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[221.200538] Starting dynamic subtest: pipe-D-eDP-1
[221.204382] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[221.206152] Subtest crc-primary-suspend-4-tiled-dg2-rc-ccs: SKIP (0.005s)
[223.357553] [039/133] (804s left) kms_dsc (dsc-with-bpc-formats)
[223.449855] Starting subtest: dsc-with-bpc-formats
[223.491867] Subtest dsc-with-bpc-formats: SKIP (0.003s)
[223.519226] [040/133] (804s left) kms_flip (dpms-off-confusion)
[223.603458] Starting subtest: dpms-off-confusion
[223.606980] Starting dynamic subtest: A-eDP1
[232.401098] Dynamic subtest A-eDP1: SUCCESS (8.770s)
[232.410544] Starting dynamic subtest: B-eDP1
[241.046127] Dynamic subtest B-eDP1: SUCCESS (8.639s)
[241.058810] Starting dynamic subtest: C-eDP1
[249.684394] Dynamic subtest C-eDP1: SUCCESS (8.621s)
[249.693871] Starting dynamic subtest: D-eDP1
[258.315808] Dynamic subtest D-eDP1: SUCCESS (8.630s)
[258.316735] Subtest dpms-off-confusion: SUCCESS (34.672s)
[259.458795] [041/133] (768s left) gem_exec_schedule (submit-early-slice)
[259.535144] Starting subtest: submit-early-slice
[259.536007] Starting dynamic subtest: rcs0
[259.573828] Dynamic subtest rcs0: SUCCESS (0.040s)
[259.581455] Starting dynamic subtest: bcs0
[259.615518] Dynamic subtest bcs0: SUCCESS (0.040s)
[259.624873] Starting dynamic subtest: vcs0
[259.650230] Dynamic subtest vcs0: SUCCESS (0.035s)
[259.658242] Starting dynamic subtest: vcs1
[259.694113] Dynamic subtest vcs1: SUCCESS (0.043s)
[259.706013] Starting dynamic subtest: vecs0
[259.733187] Dynamic subtest vecs0: SUCCESS (0.037s)
[259.741383] Starting dynamic subtest: ccs0
[259.768477] Dynamic subtest ccs0: SUCCESS (0.035s)
[259.769303] Subtest submit-early-slice: SUCCESS (0.235s)
[261.118599] [042/133] (766s left) gem_exec_schedule (noreorder)
[261.297052] Starting subtest: noreorder
[261.298099] Starting dynamic subtest: rcs0
[261.368118] Dynamic subtest rcs0: SUCCESS (0.072s)
[261.379304] Starting dynamic subtest: vcs0
[263.026255] Dynamic subtest vcs0: SUCCESS (1.645s)
[263.040704] Starting dynamic subtest: vcs1
[265.013968] Dynamic subtest vcs1: SUCCESS (1.985s)
[265.029442] Starting dynamic subtest: vecs0
[267.036721] Dynamic subtest vecs0: SUCCESS (2.020s)
[267.049095] Starting dynamic subtest: ccs0
[267.108139] Dynamic subtest ccs0: SUCCESS (0.079s)
[267.109763] Subtest noreorder: SUCCESS (5.815s)
[267.286149] [043/133] (760s left) gem_close (many-handles-one-vma)
[267.340388] Starting subtest: many-handles-one-vma
[267.378762] Subtest many-handles-one-vma: SUCCESS (0.038s)
[267.514880] [044/133] (760s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-gtt)
[267.613022] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-gtt
[267.614696] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[267.699755] [045/133] (760s left) kms_plane_alpha_blend (alpha-basic)
[267.776734] Starting subtest: alpha-basic
[268.064637] Starting dynamic subtest: pipe-A-eDP-1
[269.975234] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.909s)
[270.001800] Starting dynamic subtest: pipe-D-eDP-1
[272.653217] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.658s)
[272.654100] Subtest alpha-basic: SUCCESS (4.878s)
[273.509473] [046/133] (754s left) kms_psr (psr2-cursor-plane-move)
[273.593726] Starting subtest: psr2-cursor-plane-move
[273.594696] Starting dynamic subtest: eDP-1
[275.298308] Dynamic subtest eDP-1: SUCCESS (1.704s)
[275.299251] Subtest psr2-cursor-plane-move: SUCCESS (1.705s)
[276.175267] [047/133] (752s left) kms_big_fb (y-tiled-64bpp-rotate-270)
[276.274692] Starting subtest: y-tiled-64bpp-rotate-270
[276.276573] Subtest y-tiled-64bpp-rotate-270: SKIP (0.000s)
[276.367551] [048/133] (751s left) dmabuf_sync_file (export-multiwait)
[276.441817] Starting subtest: export-multiwait
[276.442807] Subtest export-multiwait: SUCCESS (0.001s)
[276.494632] [FACT dmabuf_sync_file (export-multiwait)] new: kernel.kmod_is_loaded.vgem: true
[276.496494] [049/133] (751s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[276.595758] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc
[276.597800] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[276.697358] [050/133] (751s left) gem_mmap_gtt (basic-small-copy)
[276.748223] Subtest basic-small-copy: SKIP (0.000s)
[276.804538] [051/133] (751s left) gem_exec_fence (syncobj-backward-timeline-chain-engines)
[277.336938] Starting subtest: syncobj-backward-timeline-chain-engines
[277.435107] Subtest syncobj-backward-timeline-chain-engines: SUCCESS (0.107s)
[279.110620] [052/133] (749s left) gem_userptr_blits (process-exit-busy)
[279.169714] Starting subtest: process-exit-busy
[279.187423] Subtest process-exit-busy: SUCCESS (0.019s)
[279.310978] [053/133] (749s left) kms_big_fb (linear-64bpp-rotate-90)
[279.409125] Starting subtest: linear-64bpp-rotate-90
[279.428170] Subtest linear-64bpp-rotate-90: SKIP (0.018s)
[279.524917] [054/133] (749s left) kms_busy (extended-modeset-hang-oldfb-with-reset)
[279.613696] Starting subtest: extended-modeset-hang-oldfb-with-reset
[279.614552] Starting dynamic subtest: pipe-A
[291.561005] Dynamic subtest pipe-A: SUCCESS (11.947s)
[291.582644] Starting dynamic subtest: pipe-D
[303.824402] Dynamic subtest pipe-D: SUCCESS (12.263s)
[303.825290] Subtest extended-modeset-hang-oldfb-with-reset: SUCCESS (24.215s)
[304.614754] [055/133] (723s left) gem_readwrite (new-obj)
[304.675517] Subtest new-obj: SKIP (0.000s)
[304.736788] [056/133] (723s left) gem_exec_whisper (basic-forked-all)
[304.801262] Starting subtest: basic-forked-all
[318.146691] Subtest basic-forked-all: SUCCESS (13.347s)
[318.326840] [057/133] (710s left) gem_mmap_offset (mmap-boundaries)
[318.372586] Starting subtest: mmap-boundaries
[318.373420] Starting dynamic subtest: smem0
[318.663107] Dynamic subtest smem0: SUCCESS (0.280s)
[318.664123] Subtest mmap-boundaries: SUCCESS (0.281s)
[318.754330] [058/133] (709s left) kms_plane (pixel-format-4-tiled-modifier-source-clamping)
[318.859186] Starting subtest: pixel-format-4-tiled-modifier-source-clamping
[318.887307] Starting dynamic subtest: pipe-A-plane-0
[319.575747] Dynamic subtest pipe-A-plane-0: SUCCESS (0.689s)
[319.584169] Starting dynamic subtest: pipe-A-plane-3
[320.031137] Dynamic subtest pipe-A-plane-3: SUCCESS (0.455s)
[321.108321] Starting dynamic subtest: pipe-B-plane-0
[321.798327] Dynamic subtest pipe-B-plane-0: SUCCESS (0.700s)
[321.806717] Starting dynamic subtest: pipe-B-plane-3
[322.242786] Dynamic subtest pipe-B-plane-3: SUCCESS (0.444s)
[322.561949] Subtest pixel-format-4-tiled-modifier-source-clamping: SUCCESS (3.703s)
[323.387946] [059/133] (705s left) api_intel_allocator (reopen)
[323.442585] Starting subtest: reopen
[323.446296] Subtest reopen: SUCCESS (0.005s)
[323.543007] [060/133] (705s left) gem_mmap (pf-nonblock)
[323.590231] Subtest pf-nonblock: SKIP (0.000s)
[323.647965] [061/133] (705s left) syncobj_timeline (wait-any-snapshot)
[323.696468] Starting subtest: wait-any-snapshot
[323.777675] Subtest wait-any-snapshot: SUCCESS (0.081s)
[323.851069] [062/133] (704s left) i915_query (query-garbage)
[323.900658] Starting subtest: query-garbage
[323.901567] Subtest query-garbage: SUCCESS (0.000s)
[323.982153] [063/133] (704s left) syncobj_timeline (multi-wait-all-for-submit-signaled)
[324.036705] Starting subtest: multi-wait-all-for-submit-signaled
[324.037749] Subtest multi-wait-all-for-submit-signaled: SUCCESS (0.000s)
[324.145472] [064/133] (704s left) gem_render_copy (y-tiled-mc-ccs-to-y-tiled-ccs)
[324.207895] Starting subtest: y-tiled-mc-ccs-to-y-tiled-ccs
[324.209806] Subtest y-tiled-mc-ccs-to-y-tiled-ccs: SKIP (0.000s)
[324.381807] [065/133] (704s left) kms_flip (2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible)
[324.464580] Starting subtest: 2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible
[324.476030] Subtest 2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible: SKIP (0.011s)
[324.562913] [066/133] (704s left) gem_create (create-valid-nonaligned)
[324.607008] Starting subtest: create-valid-nonaligned
[324.607942] Subtest create-valid-nonaligned: SUCCESS (0.000s)
[324.783131] [067/133] (704s left) gem_barrier_race (remote-request)
[324.850333] Starting subtest: remote-request
[324.851395] Starting dynamic subtest: rcs0
[330.035049] Dynamic subtest rcs0: SUCCESS (5.186s)
[330.038232] Subtest remote-request: SUCCESS (5.188s)
[330.191772] [068/133] (698s left) kms_prop_blob (blob-prop-core)
[330.238667] Starting subtest: blob-prop-core
[330.239569] Subtest blob-prop-core: SUCCESS (0.000s)
[330.331658] [069/133] (698s left) kms_frontbuffer_tracking (fbcpsr-suspend)
[330.425154] Starting subtest: fbcpsr-suspend
[371.490701] Subtest fbcpsr-suspend: SUCCESS (10.784s)
[375.684159] [070/133] (657s left) gem_mmap_gtt (basic-read)
[375.742751] Subtest basic-read: SKIP (0.000s)
[375.803875] [071/133] (657s left) kms_flip (2x-blocking-wf_vblank)
[375.887272] Starting subtest: 2x-blocking-wf_vblank
[375.897656] Subtest 2x-blocking-wf_vblank: SKIP (0.011s)
[375.984056] [072/133] (657s left) kms_psr (psr-sprite-plane-move)
[376.060455] Starting subtest: psr-sprite-plane-move
[376.061293] Starting dynamic subtest: eDP-1
[377.817179] Dynamic subtest eDP-1: SUCCESS (1.777s)
[377.818106] Subtest psr-sprite-plane-move: SUCCESS (1.778s)
[378.627328] [073/133] (654s left) kms_frontbuffer_tracking (psr-2p-primscrn-indfb-msflip-blt)
[378.732497] Starting subtest: psr-2p-primscrn-indfb-msflip-blt
[378.733969] Subtest psr-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[378.831437] [074/133] (654s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-submitted-signa
[378.877795] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-submitted-signaled
[378.993540] Subtest etime-multi-wait-all-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.105s)
[379.055024] [075/133] (654s left) kms_psr (fbc-psr2-cursor-mmap-gtt)
[379.139544] Starting subtest: fbc-psr2-cursor-mmap-gtt
[379.140348] Starting dynamic subtest: eDP-1
[379.141992] Dynamic subtest eDP-1: SKIP (0.000s)
[379.143783] Subtest fbc-psr2-cursor-mmap-gtt: SKIP (0.001s)
[379.214956] [076/133] (654s left) kms_getfb (getfb-handle-protection)
[379.309743] Starting subtest: getfb-handle-protection
[379.324915] Subtest getfb-handle-protection: SUCCESS (0.016s)
[379.433651] [077/133] (653s left) kms_addfb_basic (bad-pitch-128)
[379.476698] Starting subtest: bad-pitch-128
[379.477719] Subtest bad-pitch-128: SUCCESS (0.000s)
[379.612984] [078/133] (653s left) gem_partial_pwrite_pread (writes-after-reads-display)
[379.662979] Subtest writes-after-reads-display: SKIP (0.000s)
[379.725065] [079/133] (653s left) kms_content_protection (atomic)
[379.865630] Starting subtest: atomic
[379.867347] Subtest atomic: SKIP (0.000s)
[379.975793] [080/133] (653s left) kms_cursor_edge_walk (256x256-left-edge)
[380.070636] Starting subtest: 256x256-left-edge
[380.071499] Starting dynamic subtest: pipe-A-eDP-1
[383.544369] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.496s)
[383.553084] Starting dynamic subtest: pipe-D-eDP-1
[386.658338] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.126s)
[386.659081] Subtest 256x256-left-edge: SUCCESS (6.624s)
[387.466980] [081/133] (646s left) kms_pm_rpm (cursor)
[389.492672] Starting subtest: cursor
[389.494840] Subtest cursor: SKIP (0.000s)
[389.852713] [082/133] (643s left) kms_atomic (plane-invalid-params-fence)
[389.944797] Starting subtest: plane-invalid-params-fence
[389.945764] Starting dynamic subtest: pipe-A-eDP-1
[390.310541] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.367s)
[390.311491] Subtest plane-invalid-params-fence: SUCCESS (0.368s)
[391.175501] [083/133] (642s left) kms_async_flips (crc)
[391.273689] Starting subtest: crc
[391.664808] Starting dynamic subtest: pipe-A-eDP-1
[394.619998] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.968s)
[395.002576] Starting dynamic subtest: pipe-B-eDP-1
[397.934228] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.943s)
[398.329748] Starting dynamic subtest: pipe-C-eDP-1
[401.235955] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.917s)
[401.604639] Starting dynamic subtest: pipe-D-eDP-1
[404.463979] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.870s)
[404.464857] Subtest crc: SUCCESS (13.209s)
[405.636517] [084/133] (628s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-render)
[405.729387] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-render
[405.731209] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[405.827763] [085/133] (627s left) kms_plane_cursor (viewport)
[405.908722] Starting subtest: viewport
[405.910551] Starting dynamic subtest: pipe-A-eDP-1-size-64
[408.462047] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (2.554s)
[408.526167] Starting dynamic subtest: pipe-A-eDP-1-size-128
[411.099928] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (2.585s)
[411.160335] Starting dynamic subtest: pipe-A-eDP-1-size-256
[413.733544] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (2.582s)
[414.082655] Starting dynamic subtest: pipe-B-eDP-1-size-64
[417.287377] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (3.215s)
[417.350153] Starting dynamic subtest: pipe-B-eDP-1-size-128
[419.855156] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (2.515s)
[419.917822] Starting dynamic subtest: pipe-B-eDP-1-size-256
[422.513853] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (2.604s)
[422.882866] Starting dynamic subtest: pipe-C-eDP-1-size-64
[425.817018] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (2.950s)
[425.855573] Starting dynamic subtest: pipe-C-eDP-1-size-128
[428.038973] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (2.192s)
[428.078287] Starting dynamic subtest: pipe-C-eDP-1-size-256
[430.260774] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (2.191s)
[430.589667] Starting dynamic subtest: pipe-D-eDP-1-size-64
[433.503669] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (2.923s)
[433.543034] Starting dynamic subtest: pipe-D-eDP-1-size-128
[435.736939] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (2.203s)
[435.776698] Starting dynamic subtest: pipe-D-eDP-1-size-256
[437.991860] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (2.225s)
[438.313425] Subtest viewport: SUCCESS (32.404s)
[439.179667] [086/133] (594s left) i915_pm_rps (thresholds-idle)
[439.236780] Starting subtest: thresholds-idle
[439.238299] Subtest thresholds-idle: SKIP (0.001s)
[439.335021] [087/133] (594s left) kms_addfb_basic (bo-too-small)
[439.418509] Starting subtest: bo-too-small
[439.419426] Subtest bo-too-small: SUCCESS (0.000s)
[439.506937] [088/133] (594s left) gem_exec_whisper (basic-queues-forked)
[439.563802] Starting subtest: basic-queues-forked
[462.606590] Subtest basic-queues-forked: SUCCESS (23.039s)
[462.782360] [089/133] (571s left) perf (invalid-oa-format-id)
[462.885850] Starting subtest: invalid-oa-format-id
[462.899657] Subtest invalid-oa-format-id: SUCCESS (0.014s)
[462.944716] [090/133] (570s left) kms_psr2_sf (psr2-cursor-plane-update-sf)
[463.052103] Starting subtest: psr2-cursor-plane-update-sf
[463.053100] Starting dynamic subtest: pipe-A-eDP-1
[463.622936] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.570s)
[463.633108] Starting dynamic subtest: pipe-B-eDP-1
[464.931024] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.307s)
[464.932018] Subtest psr2-cursor-plane-update-sf: SUCCESS (1.880s)
[465.797935] [091/133] (568s left) gem_exec_reloc (basic-cpu-gtt)
[465.848476] Subtest basic-cpu-gtt: SKIP (0.000s)
[465.917458] [092/133] (568s left) kms_ccs (missing-ccs-buffer-y-tiled-gen12-mc-ccs)
[466.006381] Starting subtest: missing-ccs-buffer-y-tiled-gen12-mc-ccs
[466.007282] Starting dynamic subtest: pipe-A-eDP-1
[466.015853] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[466.026288] Starting dynamic subtest: pipe-B-eDP-1
[466.034455] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[466.043151] Starting dynamic subtest: pipe-C-eDP-1
[466.051063] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[466.059319] Starting dynamic subtest: pipe-D-eDP-1
[466.067693] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[466.069479] Subtest missing-ccs-buffer-y-tiled-gen12-mc-ccs: SKIP (0.005s)
[468.172951] [093/133] (565s left) gem_close_race (multigpu-basic-process)
[468.230961] Starting subtest: multigpu-basic-process
[468.232794] Subtest multigpu-basic-process: SKIP (0.000s)
[468.449944] [094/133] (565s left) gen9_exec_parse (batch-invalid-length)
[468.511475] Subtest batch-invalid-length: SKIP (0.000s)
[468.579615] [095/133] (565s left) gem_exec_fence (syncobj-unused-fence)
[469.036272] Starting subtest: syncobj-unused-fence
[469.039030] Subtest syncobj-unused-fence: SUCCESS (0.005s)
[470.379944] [096/133] (563s left) gem_exec_nop (basic-sequential)
[470.447977] Starting subtest: basic-sequential
[486.461642] Subtest basic-sequential: SUCCESS (15.991s)
[486.662053] [097/133] (547s left) kms_frontbuffer_tracking (fbc-2p-shrfb-fliptrack-mmap-gtt)
[486.749056] Starting subtest: fbc-2p-shrfb-fliptrack-mmap-gtt
[486.751138] Subtest fbc-2p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[486.837105] [098/133] (547s left) kms_big_fb (4-tiled-64bpp-rotate-90)
[486.938319] Starting subtest: 4-tiled-64bpp-rotate-90
[486.960367] Subtest 4-tiled-64bpp-rotate-90: SKIP (0.020s)
[487.070743] [099/133] (547s left) kms_pipe_b_c_ivb (from-pipe-c-to-b-with-3-lanes)
[487.122452] Subtest from-pipe-C-to-B-with-3-lanes: SKIP (0.000s)
[487.177047] [100/133] (547s left) kms_fence_pin_leak
[487.327808] [101/133] (546s left) prime_busy (hang-wait)
[487.375728] Starting subtest: hang-wait
[487.378010] Starting dynamic subtest: rcs0
[514.777174] Dynamic subtest rcs0: SUCCESS (27.395s)
[514.785851] Starting dynamic subtest: bcs0
[521.932578] Dynamic subtest bcs0: SUCCESS (7.155s)
[521.942899] Starting dynamic subtest: vcs0
[528.906689] Dynamic subtest vcs0: SUCCESS (6.970s)
[528.916492] Starting dynamic subtest: vcs1
[535.947004] Dynamic subtest vcs1: SUCCESS (7.039s)
[535.957741] Starting dynamic subtest: vecs0
[542.918828] Dynamic subtest vecs0: SUCCESS (6.968s)
[542.929309] Starting dynamic subtest: ccs0
[556.757358] Dynamic subtest ccs0: SUCCESS (13.836s)
[556.758611] Subtest hang-wait: SUCCESS (69.378s)
[556.880773] [102/133] (477s left) kms_chamelium_hpd (hdmi-hpd-for-each-pipe)
[557.266324] Subtest hdmi-hpd-for-each-pipe: SKIP (0.000s)
[558.075643] [103/133] (476s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-onoff)
[558.165659] Starting subtest: psr-2p-primscrn-spr-indfb-onoff
[558.167376] Subtest psr-2p-primscrn-spr-indfb-onoff: SKIP (0.000s)
[558.255267] [104/133] (476s left) gem_unref_active_buffers
[558.452259] [105/133] (475s left) kms_rmfb (rmfb-ioctl)
[558.533427] Starting subtest: rmfb-ioctl
[558.534197] Starting dynamic subtest: pipe-A-eDP-1
[558.640881] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.107s)
[558.650803] Starting dynamic subtest: pipe-B-eDP-1
[559.868058] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.226s)
[559.878466] Starting dynamic subtest: pipe-C-eDP-1
[560.986572] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.117s)
[561.003832] Starting dynamic subtest: pipe-D-eDP-1
[562.122500] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.134s)
[562.123410] Subtest rmfb-ioctl: SUCCESS (3.590s)
[563.242954] [106/133] (471s left) gem_exec_reloc (basic-cpu-read-noreloc)
[563.293520] Subtest basic-cpu-read-noreloc: SKIP (0.000s)
[563.356273] [107/133] (471s left) kms_cursor_crc (cursor-dpms)
[563.471599] Starting subtest: cursor-dpms
[563.473773] Starting dynamic subtest: pipe-A-eDP-1
[566.567791] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.094s)
[566.576524] Starting dynamic subtest: pipe-D-eDP-1
[570.217270] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.649s)
[570.218136] Subtest cursor-dpms: SUCCESS (6.746s)
[571.057166] [108/133] (463s left) kms_debugfs (display-off-read-all)
[571.149796] Starting subtest: display-off-read-all
[571.438034] Subtest display-off-read-all: SUCCESS (0.290s)
[572.302415] [109/133] (462s left) perf_pmu (most-busy-idle-check-all)
[572.341562] Starting subtest: most-busy-idle-check-all
[572.342476] Starting dynamic subtest: rcs0
[574.339254] Dynamic subtest rcs0: SUCCESS (1.988s)
[574.350620] Starting dynamic subtest: bcs0
[576.323296] Dynamic subtest bcs0: SUCCESS (1.981s)
[576.334517] Starting dynamic subtest: vcs0
[578.306899] Dynamic subtest vcs0: SUCCESS (1.981s)
[578.318056] Starting dynamic subtest: vcs1
[580.354281] Dynamic subtest vcs1: SUCCESS (2.045s)
[580.365884] Starting dynamic subtest: vecs0
[582.339055] Dynamic subtest vecs0: SUCCESS (1.982s)
[582.350490] Starting dynamic subtest: ccs0
[584.323118] Dynamic subtest ccs0: SUCCESS (1.981s)
[584.324363] Subtest most-busy-idle-check-all: SUCCESS (11.976s)
[584.401859] [110/133] (450s left) gem_exec_balancer (waits)
[584.473016] Starting subtest: waits
[591.103666] Subtest waits: SUCCESS (6.585s)
[591.363609] [111/133] (443s left) kms_ccs (crc-primary-basic-4-tiled-mtl-mc-ccs)
[591.458204] Starting subtest: crc-primary-basic-4-tiled-mtl-mc-ccs
[591.459256] Starting dynamic subtest: pipe-A-eDP-1
[593.677523] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.219s)
[593.687564] Starting dynamic subtest: pipe-B-eDP-1
[596.093030] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.415s)
[596.103578] Starting dynamic subtest: pipe-C-eDP-1
[598.290086] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.196s)
[598.301997] Starting dynamic subtest: pipe-D-eDP-1
[600.506191] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.215s)
[600.507193] Subtest crc-primary-basic-4-tiled-mtl-mc-ccs: SUCCESS (9.050s)
[601.713902] [112/133] (432s left) perf (invalid-oa-metric-set-id)
[601.798216] Starting subtest: invalid-oa-metric-set-id
[601.805971] Subtest invalid-oa-metric-set-id: SUCCESS (0.008s)
[601.840397] [113/133] (432s left) kms_fbcon_fbt (fbc)
[601.900124] Starting subtest: fbc
[608.238816] Subtest fbc: SUCCESS (6.339s)
[609.333428] [114/133] (425s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-indfb-msflip-blt)
[609.434411] Starting subtest: fbc-2p-scndscrn-indfb-msflip-blt
[609.436055] Subtest fbc-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[609.525153] [115/133] (425s left) kms_lease (lease-again)
[609.631659] Starting subtest: lease-again
[609.632586] Starting dynamic subtest: pipe-A-eDP-1
[609.635954] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.003s)
[609.645271] Starting dynamic subtest: pipe-B-eDP-1
[609.646334] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.002s)
[609.655588] Starting dynamic subtest: pipe-C-eDP-1
[609.656475] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.002s)
[609.665033] Starting dynamic subtest: pipe-D-eDP-1
[609.665956] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.002s)
[609.666820] Subtest lease-again: SUCCESS (0.013s)
[609.738044] [116/133] (425s left) kms_chamelium_color (ctm-max)
[609.833083] Subtest ctm-max: SKIP (0.000s)
[609.883084] [117/133] (424s left) gem_softpin (zero)
[609.948265] Starting subtest: zero
[609.952251] Subtest zero: SUCCESS (0.004s)
[610.052871] [118/133] (424s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0-async-flip)
[610.152150] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0-async-flip
[610.154314] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SKIP (0.000s)
[610.247749] [119/133] (424s left) perf (missing-sample-flags)
[610.329252] Starting subtest: missing-sample-flags
[610.330362] Subtest missing-sample-flags: SUCCESS (0.000s)
[610.370414] [120/133] (424s left) core_auth (getclient-simple)
[610.401771] Starting subtest: getclient-simple
[610.417930] Subtest getclient-simple: SUCCESS (0.015s)
[610.529077] [121/133] (424s left) kms_color (degamma)
[610.610468] Starting subtest: degamma
[610.611466] Starting dynamic subtest: pipe-A-eDP-1
[611.346257] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.736s)
[611.355204] Starting dynamic subtest: pipe-B-eDP-1
[612.827415] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.480s)
[612.836279] Starting dynamic subtest: pipe-C-eDP-1
[614.154623] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.327s)
[614.163597] Starting dynamic subtest: pipe-D-eDP-1
[615.493827] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.337s)
[615.494718] Subtest degamma: SUCCESS (4.885s)
[616.285491] [122/133] (418s left) kms_frontbuffer_tracking (fbcpsr-2p-pri-indfb-multidraw)
[616.372569] Starting subtest: fbcpsr-2p-pri-indfb-multidraw
[616.374264] Subtest fbcpsr-2p-pri-indfb-multidraw: SKIP (0.000s)
[616.469775] [123/133] (418s left) kms_ccs (bad-rotation-90-4-tiled-bmg-ccs)
[616.566611] Starting subtest: bad-rotation-90-4-tiled-bmg-ccs
[616.568627] Subtest bad-rotation-90-4-tiled-bmg-ccs: SKIP (0.000s)
[617.723880] [124/133] (417s left) gem_ctx_create (basic-files)
[617.781722] Starting subtest: basic-files
[619.789432] Subtest basic-files: SUCCESS (2.008s)
[621.345005] [125/133] (413s left) kms_cursor_crc (cursor-rapid-movement-256x256)
[621.601464] Starting subtest: cursor-rapid-movement-256x256
[621.603695] Starting dynamic subtest: pipe-A-eDP-1
[622.169296] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.565s)
[622.201531] Starting dynamic subtest: pipe-D-eDP-1
[623.433520] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.241s)
[623.434397] Subtest cursor-rapid-movement-256x256: SUCCESS (1.834s)
[624.280363] [126/133] (410s left) kms_chamelium_frames (dp-frame-dump)
[624.659061] Subtest dp-frame-dump: SKIP (0.000s)
[625.488088] [127/133] (409s left) gem_mmap_gtt (medium-copy)
[625.548076] Subtest medium-copy: SKIP (0.000s)
[625.609701] [128/133] (409s left) gem_ctx_param (invalid-param-set)
[625.659819] Starting subtest: invalid-param-set
[625.660756] Subtest invalid-param-set: SUCCESS (0.000s)
[625.728341] [129/133] (409s left) kms_psr (fbc-psr2-sprite-plane-move)
[625.816297] Starting subtest: fbc-psr2-sprite-plane-move
[625.817062] Starting dynamic subtest: eDP-1
[625.818709] Dynamic subtest eDP-1: SKIP (0.000s)
[625.820507] Subtest fbc-psr2-sprite-plane-move: SKIP (0.001s)
[625.902227] [130/133] (409s left) gem_exec_balancer (full-pulse)
[625.974707] Starting subtest: full-pulse
[630.232616] Subtest full-pulse: SUCCESS (4.258s)
[630.570684] [131/133] (404s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-mmap-gtt)
[630.663832] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-mmap-gtt
[630.665460] Subtest fbc-2p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[630.746576] [132/133] (404s left) kms_cursor_legacy (flip-vs-cursor-crc-atomic)
[630.860000] Starting subtest: flip-vs-cursor-crc-atomic
[631.537493] Subtest flip-vs-cursor-crc-atomic: SUCCESS (0.677s)
[632.345153] [133/133] (403s left) kms_vblank (invalid)
[632.439255] Starting subtest: invalid
[632.440100] Starting dynamic subtest: pipe-A-eDP-1
[632.848340] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.407s)
[632.849354] Subtest invalid: SUCCESS (0.408s)
[633.669070] Closing watchdogs
results: parsing output: 0/ for test: i915_drm_fdinfo
results: parsing output: 1/ for test: kms_hdr
results: parsing output: 2/ for test: gem_ctx_param
results: parsing output: 3/ for test: kms_frontbuffer_tracking
results: parsing output: 4/ for test: gem_render_copy
results: parsing output: 5/ for test: kms_frontbuffer_tracking
results: parsing output: 6/ for test: i915_pm_rps
results: parsing output: 7/ for test: kms_frontbuffer_tracking
results: parsing output: 8/ for test: kms_frontbuffer_tracking
results: parsing output: 9/ for test: kms_rotation_crc
results: parsing output: 10/ for test: syncobj_wait
results: parsing output: 11/ for test: syncobj_timeline
results: parsing output: 12/ for test: kms_psr
results: parsing output: 13/ for test: kms_vblank
results: parsing output: 14/ for test: syncobj_timeline
results: parsing output: 15/ for test: gem_mmap_gtt
results: parsing output: 16/ for test: gem_ctx_param
results: parsing output: 17/ for test: gem_tiled_fence_blits
results: parsing output: 18/ for test: gem_linear_blits
results: parsing output: 19/ for test: kms_big_fb
results: parsing output: 20/ for test: kms_plane_scaling
results: parsing output: 21/ for test: kms_pm_rpm
results: parsing output: 22/ for test: syncobj_timeline
results: parsing output: 23/ for test: kms_flip_scaled_crc
results: parsing output: 24/ for test: gem_ctx_param
results: parsing output: 25/ for test: kms_frontbuffer_tracking
results: parsing output: 26/ for test: kms_big_fb
results: parsing output: 27/ for test: gem_ctx_persistence
results: parsing output: 28/ for test: kms_flip_scaled_crc
results: parsing output: 29/ for test: kms_plane_scaling
results: parsing output: 30/ for test: kms_flip
results: parsing output: 31/ for test: gem_exec_parallel
results: parsing output: 32/ for test: gem_exec_alignment
results: parsing output: 33/ for test: kms_psr
results: parsing output: 34/ for test: kms_vrr
results: parsing output: 35/ for test: i915_module_load
results: parsing output: 36/ for test: gem_vm_create
results: parsing output: 37/ for test: kms_ccs
results: parsing output: 38/ for test: kms_dsc
results: parsing output: 39/ for test: kms_flip
results: parsing output: 40/ for test: gem_exec_schedule
results: parsing output: 41/ for test: gem_exec_schedule
results: parsing output: 42/ for test: gem_close
results: parsing output: 43/ for test: kms_frontbuffer_tracking
results: parsing output: 44/ for test: kms_plane_alpha_blend
results: parsing output: 45/ for test: kms_psr
results: parsing output: 46/ for test: kms_big_fb
results: parsing output: 47/ for test: dmabuf_sync_file
results: parsing output: 48/ for test: kms_frontbuffer_tracking
results: parsing output: 49/ for test: gem_mmap_gtt
results: parsing output: 50/ for test: gem_exec_fence
results: parsing output: 51/ for test: gem_userptr_blits
results: parsing output: 52/ for test: kms_big_fb
results: parsing output: 53/ for test: kms_busy
results: parsing output: 54/ for test: gem_readwrite
results: parsing output: 55/ for test: gem_exec_whisper
results: parsing output: 56/ for test: gem_mmap_offset
results: parsing output: 57/ for test: kms_plane
results: parsing output: 58/ for test: api_intel_allocator
results: parsing output: 59/ for test: gem_mmap
results: parsing output: 60/ for test: syncobj_timeline
results: parsing output: 61/ for test: i915_query
results: parsing output: 62/ for test: syncobj_timeline
results: parsing output: 63/ for test: gem_render_copy
results: parsing output: 64/ for test: kms_flip
results: parsing output: 65/ for test: gem_create
results: parsing output: 66/ for test: gem_barrier_race
results: parsing output: 67/ for test: kms_prop_blob
results: parsing output: 68/ for test: kms_frontbuffer_tracking
results: parsing output: 69/ for test: gem_mmap_gtt
results: parsing output: 70/ for test: kms_flip
results: parsing output: 71/ for test: kms_psr
results: parsing output: 72/ for test: kms_frontbuffer_tracking
results: parsing output: 73/ for test: syncobj_timeline
results: parsing output: 74/ for test: kms_psr
results: parsing output: 75/ for test: kms_getfb
results: parsing output: 76/ for test: kms_addfb_basic
results: parsing output: 77/ for test: gem_partial_pwrite_pread
results: parsing output: 78/ for test: kms_content_protection
results: parsing output: 79/ for test: kms_cursor_edge_walk
results: parsing output: 80/ for test: kms_pm_rpm
results: parsing output: 81/ for test: kms_atomic
results: parsing output: 82/ for test: kms_async_flips
results: parsing output: 83/ for test: kms_frontbuffer_tracking
results: parsing output: 84/ for test: kms_plane_cursor
results: parsing output: 85/ for test: i915_pm_rps
results: parsing output: 86/ for test: kms_addfb_basic
results: parsing output: 87/ for test: gem_exec_whisper
results: parsing output: 88/ for test: perf
results: parsing output: 89/ for test: kms_psr2_sf
results: parsing output: 90/ for test: gem_exec_reloc
results: parsing output: 91/ for test: kms_ccs
results: parsing output: 92/ for test: gem_close_race
results: parsing output: 93/ for test: gen9_exec_parse
results: parsing output: 94/ for test: gem_exec_fence
results: parsing output: 95/ for test: gem_exec_nop
results: parsing output: 96/ for test: kms_frontbuffer_tracking
results: parsing output: 97/ for test: kms_big_fb
results: parsing output: 98/ for test: kms_pipe_b_c_ivb
results: parsing output: 99/ for test: kms_fence_pin_leak
results: parsing output: 100/ for test: prime_busy
results: parsing output: 101/ for test: kms_chamelium_hpd
results: parsing output: 102/ for test: kms_frontbuffer_tracking
results: parsing output: 103/ for test: gem_unref_active_buffers
results: parsing output: 104/ for test: kms_rmfb
results: parsing output: 105/ for test: gem_exec_reloc
results: parsing output: 106/ for test: kms_cursor_crc
results: parsing output: 107/ for test: kms_debugfs
results: parsing output: 108/ for test: perf_pmu
results: parsing output: 109/ for test: gem_exec_balancer
results: parsing output: 110/ for test: kms_ccs
results: parsing output: 111/ for test: perf
results: parsing output: 112/ for test: kms_fbcon_fbt
results: parsing output: 113/ for test: kms_frontbuffer_tracking
results: parsing output: 114/ for test: kms_lease
results: parsing output: 115/ for test: kms_chamelium_color
results: parsing output: 116/ for test: gem_softpin
results: parsing output: 117/ for test: kms_big_fb
results: parsing output: 118/ for test: perf
results: parsing output: 119/ for test: core_auth
results: parsing output: 120/ for test: kms_color
results: parsing output: 121/ for test: kms_frontbuffer_tracking
results: parsing output: 122/ for test: kms_ccs
results: parsing output: 123/ for test: gem_ctx_create
results: parsing output: 124/ for test: kms_cursor_crc
results: parsing output: 125/ for test: kms_chamelium_frames
results: parsing output: 126/ for test: gem_mmap_gtt
results: parsing output: 127/ for test: gem_ctx_param
results: parsing output: 128/ for test: kms_psr
results: parsing output: 129/ for test: gem_exec_balancer
results: parsing output: 130/ for test: kms_frontbuffer_tracking
results: parsing output: 131/ for test: kms_cursor_legacy
results: parsing output: 132/ for test: kms_vblank
Done.