[62.196904] Initializing watchdogs
[62.197081]   /dev/watchdog0
[62.210772] [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]
[62.215717] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[62.217868] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[62.221202] [001/131] (960s left) perf_pmu (most-busy-idle-check-all)
[62.271595] Starting subtest: most-busy-idle-check-all
[62.273807] Starting dynamic subtest: rcs0
[63.896257] Dynamic subtest rcs0: SUCCESS (1.626s)
[63.907042] Starting dynamic subtest: bcs0
[65.876693] Dynamic subtest bcs0: SUCCESS (1.985s)
[65.886385] Starting dynamic subtest: vcs0
[67.925662] Dynamic subtest vcs0: SUCCESS (2.052s)
[67.934631] Starting dynamic subtest: vcs1
[69.909509] Dynamic subtest vcs1: SUCCESS (1.986s)
[69.919803] Starting dynamic subtest: vecs0
[71.892035] Dynamic subtest vecs0: SUCCESS (1.984s)
[71.902276] Starting dynamic subtest: ccs0
[73.878815] Dynamic subtest ccs0: SUCCESS (1.986s)
[73.881840] Subtest most-busy-idle-check-all: SUCCESS (11.622s)
[73.986071] [002/131] (948s left) syncobj_wait (multi-wait-submitted)
[74.045644] Starting subtest: multi-wait-submitted
[74.154626] Subtest multi-wait-submitted: SUCCESS (0.106s)
[74.223632] [003/131] (948s left) gem_wait (write-wait)
[74.290149] Starting subtest: write-wait
[74.292573] Starting dynamic subtest: all-engines
[75.438314] Dynamic subtest all-engines: SUCCESS (1.148s)
[75.456468] Starting dynamic subtest: rcs0
[76.497063] Dynamic subtest rcs0: SUCCESS (1.059s)
[76.510068] Starting dynamic subtest: bcs0
[77.555267] Dynamic subtest bcs0: SUCCESS (1.058s)
[77.568867] Starting dynamic subtest: vcs0
[78.608340] Dynamic subtest vcs0: SUCCESS (1.053s)
[78.621931] Starting dynamic subtest: vcs1
[79.662682] Dynamic subtest vcs1: SUCCESS (1.054s)
[79.676103] Starting dynamic subtest: vecs0
[80.716884] Dynamic subtest vecs0: SUCCESS (1.055s)
[80.730104] Starting dynamic subtest: ccs0
[81.774057] Dynamic subtest ccs0: SUCCESS (1.057s)
[81.776828] Subtest write-wait: SUCCESS (7.487s)
[81.960843] [FACT gem_wait (write-wait)] new: kernel.kmod_is_loaded.vgem: true
[81.963919] [004/131] (940s left) kms_plane_lowres (tiling-4)
[82.067092] Starting subtest: tiling-4
[82.069551] Starting dynamic subtest: pipe-A-eDP-1
[82.074362] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[82.373671] Starting dynamic subtest: pipe-B-eDP-1
[82.378630] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[82.389802] Starting dynamic subtest: pipe-C-eDP-1
[82.394656] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[82.402940] Starting dynamic subtest: pipe-D-eDP-1
[82.407444] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[82.411929] Subtest tiling-4: SKIP (0.301s)
[83.162377] [005/131] (939s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-render)
[83.274066] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-render
[89.889867] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-render: SUCCESS (6.617s)
[90.030862] [006/131] (932s left) gem_exec_fence (basic-await)
[90.216622] Starting subtest: basic-await
[90.219793] Starting dynamic subtest: rcs0
[90.293886] Dynamic subtest rcs0: SUCCESS (0.077s)
[90.301541] Starting dynamic subtest: bcs0
[90.366226] Dynamic subtest bcs0: SUCCESS (0.072s)
[90.375320] Starting dynamic subtest: vcs0
[90.437423] Dynamic subtest vcs0: SUCCESS (0.071s)
[90.448241] Starting dynamic subtest: vcs1
[90.510656] Dynamic subtest vcs1: SUCCESS (0.073s)
[90.520728] Starting dynamic subtest: vecs0
[90.581781] Dynamic subtest vecs0: SUCCESS (0.071s)
[90.592211] Starting dynamic subtest: ccs0
[90.652323] Dynamic subtest ccs0: SUCCESS (0.070s)
[90.654901] Subtest basic-await: SUCCESS (0.436s)
[92.032593] [007/131] (930s left) syncobj_timeline (invalid-wait-illegal-handle)
[92.095054] Starting subtest: invalid-wait-illegal-handle
[92.097425] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s)
[92.206186] [008/131] (930s left) gem_ctx_param (invalid-get-ringsize)
[92.263690] Starting subtest: invalid-get-ringsize
[92.266076] Subtest invalid-get-ringsize: SUCCESS (0.001s)
[92.382290] [009/131] (930s left) syncobj_wait (invalid-multi-wait-unsubmitted-submitted-signaled)
[92.441965] Starting subtest: invalid-multi-wait-unsubmitted-submitted-signaled
[92.444123] Subtest invalid-multi-wait-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[92.565899] [010/131] (930s left) kms_dsc (dsc-fractional-bpp)
[92.668068] Starting subtest: dsc-fractional-bpp
[92.681398] Subtest dsc-fractional-bpp: SKIP (0.001s)
[92.736262] [011/131] (930s left) gem_render_copy (yf-tiled-to-vebox-yf-tiled)
[92.797230] Starting subtest: yf-tiled-to-vebox-yf-tiled
[92.801876] Subtest yf-tiled-to-vebox-yf-tiled: SKIP (0.000s)
[92.981667] [012/131] (930s left) gem_exec_schedule (u-submit-late-slice)
[93.051818] Starting subtest: u-submit-late-slice
[93.053864] Starting dynamic subtest: rcs0
[93.081051] Dynamic subtest rcs0: SUCCESS (0.038s)
[93.088894] Starting dynamic subtest: bcs0
[93.108349] Dynamic subtest bcs0: SUCCESS (0.027s)
[93.115907] Starting dynamic subtest: vcs0
[93.139818] Dynamic subtest vcs0: SUCCESS (0.031s)
[93.148271] Starting dynamic subtest: vcs1
[93.169269] Dynamic subtest vcs1: SUCCESS (0.030s)
[93.177469] Starting dynamic subtest: vecs0
[93.197395] Dynamic subtest vecs0: SUCCESS (0.028s)
[93.205430] Starting dynamic subtest: ccs0
[93.228569] Dynamic subtest ccs0: SUCCESS (0.031s)
[93.230883] Subtest u-submit-late-slice: SUCCESS (0.185s)
[95.020457] [013/131] (928s left) kms_psr (psr-primary-mmap-cpu)
[95.115550] Starting subtest: psr-primary-mmap-cpu
[95.117794] Starting dynamic subtest: eDP-1
[96.883376] Dynamic subtest eDP-1: SUCCESS (1.767s)
[96.885677] Subtest psr-primary-mmap-cpu: SUCCESS (1.768s)
[97.747394] [014/131] (925s left) api_intel_bb (object-reloc-purge-cache)
[97.807902] Starting subtest: object-reloc-purge-cache
[97.814287] Subtest object-reloc-purge-cache: SKIP (0.004s)
[97.935753] [015/131] (925s left) gem_create (create-massive)
[97.989899] Starting subtest: create-massive
[97.991969] Subtest create-massive: SUCCESS (0.000s)
[98.170309] [016/131] (925s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0)
[98.270871] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0
[98.275298] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0: SKIP (0.000s)
[98.372232] [017/131] (924s left) kms_ccs (bad-aux-stride-4-tiled-mtl-rc-ccs-cc)
[98.467116] Starting subtest: bad-aux-stride-4-tiled-mtl-rc-ccs-cc
[98.469545] Starting dynamic subtest: pipe-A-eDP-1
[98.490646] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[98.500479] Starting dynamic subtest: pipe-B-eDP-1
[98.520648] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[98.529798] Starting dynamic subtest: pipe-C-eDP-1
[98.549650] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[98.557928] Starting dynamic subtest: pipe-D-eDP-1
[98.577605] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[98.579865] Subtest bad-aux-stride-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.005s)
[100.635189] [018/131] (922s left) kms_big_fb (linear-8bpp-rotate-0)
[100.731389] Starting subtest: linear-8bpp-rotate-0
[101.810513] Subtest linear-8bpp-rotate-0: SUCCESS (1.079s)
[101.964085] [019/131] (921s left) kms_atomic (test-only)
[102.052991] Starting subtest: test-only
[102.101208] Starting dynamic subtest: pipe-A-eDP-1
[104.675971] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.572s)
[104.678043] Subtest test-only: SUCCESS (2.620s)
[105.537791] [020/131] (917s left) gem_mmap_offset (ptrace)
[105.600044] Starting subtest: ptrace
[105.602474] Starting dynamic subtest: smem0-wb
[105.616219] Dynamic subtest smem0-wb: SUCCESS (0.015s)
[105.626163] Starting dynamic subtest: smem0-wc
[105.635402] Dynamic subtest smem0-wc: SUCCESS (0.019s)
[105.642900] Starting dynamic subtest: smem0-uc
[105.655891] Dynamic subtest smem0-uc: SUCCESS (0.020s)
[105.658172] Subtest ptrace: SUCCESS (0.058s)
[105.770660] [021/131] (917s left) perf_pmu (busy-double-start)
[105.807920] Starting subtest: busy-double-start
[105.810265] Starting dynamic subtest: rcs0
[109.446257] Dynamic subtest rcs0: FAIL (3.581s)
[109.455173] Starting dynamic subtest: bcs0
[112.956470] Dynamic subtest bcs0: SUCCESS (3.567s)
[112.965066] Starting dynamic subtest: vcs0
[117.402962] Dynamic subtest vcs0: SUCCESS (4.446s)
[117.412279] Starting dynamic subtest: vcs1
[121.434875] Dynamic subtest vcs1: SUCCESS (4.031s)
[121.444404] Starting dynamic subtest: vecs0
[125.439080] Dynamic subtest vecs0: SUCCESS (4.003s)
[125.448095] Starting dynamic subtest: ccs0
[129.079046] Dynamic subtest ccs0: FAIL (3.567s)
[129.081065] Subtest busy-double-start: FAIL (23.197s)
[129.122120] [022/131] (894s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-fullscreen)
[129.221179] Starting subtest: fbc-1p-primscrn-spr-indfb-fullscreen
[133.275064] Subtest fbc-1p-primscrn-spr-indfb-fullscreen: SUCCESS (4.058s)
[134.452383] [023/131] (889s left) gem_exec_params (rsvd2-dirt)
[134.519517] Starting subtest: rsvd2-dirt
[134.524364] Subtest rsvd2-dirt: SKIP (0.000s)
[134.643412] [024/131] (888s left) api_intel_bb (object-noreloc-purge-cache-simple)
[134.688745] Starting subtest: object-noreloc-purge-cache-simple
[134.693009] Subtest object-noreloc-purge-cache-simple: SUCCESS (0.005s)
[134.810920] [025/131] (888s left) gem_userptr_blits (sync-unmap)
[134.876723] Starting subtest: sync-unmap
[134.889298] Subtest sync-unmap: SUCCESS (0.013s)
[135.020503] [026/131] (888s left) syncobj_timeline (device-signal-unordered)
[135.064424] Starting subtest: device-signal-unordered
[135.066757] Subtest device-signal-unordered: SUCCESS (0.001s)
[135.149357] [027/131] (888s left) gem_ctx_shared (q-smoketest)
[135.212476] Starting subtest: Q-smoketest
[135.214683] Starting dynamic subtest: rcs0
[140.826489] Dynamic subtest rcs0: SUCCESS (5.511s)
[140.835616] Starting dynamic subtest: bcs0
[146.337167] Dynamic subtest bcs0: SUCCESS (5.510s)
[146.346743] Starting dynamic subtest: vcs0
[151.817766] Dynamic subtest vcs0: SUCCESS (5.480s)
[151.828177] Starting dynamic subtest: vcs1
[157.302343] Dynamic subtest vcs1: SUCCESS (5.483s)
[157.311469] Starting dynamic subtest: vecs0
[162.773618] Dynamic subtest vecs0: SUCCESS (5.472s)
[162.788500] Starting dynamic subtest: ccs0
[168.295450] Dynamic subtest ccs0: SUCCESS (5.521s)
[168.298319] Subtest Q-smoketest: SUCCESS (32.978s)
[168.375243] [028/131] (855s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[168.479296] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu
[168.483676] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[168.570637] [029/131] (855s left) gem_userptr_blits (map-fixed-invalidate)
[168.635783] Starting subtest: map-fixed-invalidate
[168.640249] Subtest map-fixed-invalidate: SKIP (0.000s)
[168.766355] [030/131] (855s left) gem_mmap_gtt (big-bo)
[168.824720] Subtest big-bo: SKIP (0.000s)
[168.890286] [031/131] (854s left) sw_sync (sync_busy_fork)
[168.921166] Starting subtest: sync_busy_fork
[169.941405] Subtest sync_busy_fork: SUCCESS (1.010s)
[169.992733] [032/131] (853s left) gem_mmap_wc (coherency)
[170.044133] Subtest coherency: SKIP (0.000s)
[170.110053] [033/131] (853s left) kms_cursor_edge_walk (256x256-left-edge)
[170.197849] Starting subtest: 256x256-left-edge
[170.200096] Starting dynamic subtest: pipe-A-eDP-1
[173.625541] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.426s)
[173.635742] Starting dynamic subtest: pipe-D-eDP-1
[176.739885] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.113s)
[176.742175] Subtest 256x256-left-edge: SUCCESS (6.540s)
[177.541122] [034/131] (846s left) prime_vgem (coherency-blt)
[177.629971] Starting subtest: coherency-blt
[178.690089] Subtest coherency-blt: SUCCESS (1.059s)
[178.820046] [035/131] (845s left) gem_tiled_wc
[178.931664] [036/131] (845s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-pwrite)
[179.033165] Starting subtest: psr-1p-primscrn-cur-indfb-draw-pwrite
[185.747018] Subtest psr-1p-primscrn-cur-indfb-draw-pwrite: SUCCESS (6.717s)
[185.859192] [037/131] (838s left) gem_exec_fence (syncobj-invalid-flags)
[186.293070] Starting subtest: syncobj-invalid-flags
[186.295476] Subtest syncobj-invalid-flags: SUCCESS (0.000s)
[188.005103] [038/131] (836s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling)
[188.098434] Starting subtest: flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling
[188.100952] Starting dynamic subtest: pipe-A-default-mode
[188.402930] Dynamic subtest pipe-A-default-mode: SKIP (0.301s)
[188.407656] Subtest flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling: SKIP (0.302s)
[189.261429] [039/131] (834s left) gem_mmap_gtt (hang-user)
[189.317064] Subtest hang-user: SKIP (0.000s)
[189.375247] [040/131] (834s left) kms_plane_scaling (plane-downscale-factor-0-75-with-pixel-format)
[189.473056] Starting subtest: plane-downscale-factor-0-75-with-pixel-format
[189.475348] Starting dynamic subtest: pipe-A
[190.636457] Dynamic subtest pipe-A: SUCCESS (1.163s)
[190.645144] Starting dynamic subtest: pipe-B
[192.862183] Dynamic subtest pipe-B: SUCCESS (2.225s)
[192.869237] Starting dynamic subtest: pipe-C
[194.066373] Dynamic subtest pipe-C: SUCCESS (1.203s)
[194.075404] Starting dynamic subtest: pipe-D
[195.208632] Dynamic subtest pipe-D: SUCCESS (1.142s)
[195.210991] Subtest plane-downscale-factor-0-75-with-pixel-format: SUCCESS (5.736s)
[196.391145] [041/131] (827s left) kms_chamelium_hpd (dp-hpd)
[196.795643] Subtest dp-hpd: SKIP (0.000s)
[197.600077] [042/131] (826s left) kms_cursor_crc (cursor-rapid-movement-128x42)
[197.818802] Starting subtest: cursor-rapid-movement-128x42
[197.865748] Subtest cursor-rapid-movement-128x42: SKIP (0.040s)
[198.083976] [043/131] (826s left) sw_sync (sync_multi_consumer_producer)
[198.119693] Starting subtest: sync_multi_consumer_producer
[198.416265] Subtest sync_multi_consumer_producer: SUCCESS (0.295s)
[198.477468] [044/131] (825s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc)
[198.579814] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc
[198.584281] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[198.679792] [045/131] (825s left) kms_cursor_crc (cursor-offscreen-max-size)
[198.975618] Starting subtest: cursor-offscreen-max-size
[198.982279] Subtest cursor-offscreen-max-size: SKIP (0.000s)
[199.104197] [046/131] (825s left) kms_big_fb (yf-tiled-64bpp-rotate-90)
[199.199406] Starting subtest: yf-tiled-64bpp-rotate-90
[199.203635] Subtest yf-tiled-64bpp-rotate-90: SKIP (0.000s)
[199.298808] [047/131] (825s left) kms_ccs (crc-primary-basic-4-tiled-dg2-rc-ccs-cc)
[199.392016] Starting subtest: crc-primary-basic-4-tiled-dg2-rc-ccs-cc
[199.394199] Starting dynamic subtest: pipe-A-eDP-1
[199.416604] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[199.426747] Starting dynamic subtest: pipe-B-eDP-1
[199.444046] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[199.450923] Starting dynamic subtest: pipe-C-eDP-1
[199.468243] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[199.475085] Starting dynamic subtest: pipe-D-eDP-1
[199.492904] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[199.496270] Subtest crc-primary-basic-4-tiled-dg2-rc-ccs-cc: SKIP (0.004s)
[201.601903] [048/131] (822s left) i915_pm_sseu (full-enable)
[201.669603] Subtest full-enable: SKIP (0.000s)
[201.770859] [049/131] (822s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[201.852858] Starting subtest: bo-too-small-due-to-tiling
[201.857283] Subtest bo-too-small-due-to-tiling: SKIP (0.000s)
[201.969279] [050/131] (822s left) sysfs_defaults (readonly)
[202.026607] Starting subtest: readonly
[202.029131] Starting dynamic subtest: vecs0
[202.031482] Dynamic subtest vecs0: SUCCESS (0.000s)
[202.040893] Starting dynamic subtest: ccs0
[202.043049] Dynamic subtest ccs0: SUCCESS (0.000s)
[202.050065] Starting dynamic subtest: vcs0
[202.052149] Dynamic subtest vcs0: SUCCESS (0.000s)
[202.059071] Starting dynamic subtest: bcs0
[202.061105] Dynamic subtest bcs0: SUCCESS (0.000s)
[202.067774] Starting dynamic subtest: rcs0
[202.069840] Dynamic subtest rcs0: SUCCESS (0.000s)
[202.077628] Starting dynamic subtest: vcs1
[202.079866] Dynamic subtest vcs1: SUCCESS (0.000s)
[202.081892] Subtest readonly: SUCCESS (0.004s)
[202.133985] [051/131] (822s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180-hflip)
[202.243969] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180-hflip
[202.248214] Subtest y-tiled-max-hw-stride-64bpp-rotate-180-hflip: SKIP (0.000s)
[202.351856] [052/131] (822s left) kms_feature_discovery (psr1)
[202.447316] Starting subtest: psr1
[202.449508] Subtest psr1: SUCCESS (0.000s)
[202.515015] [053/131] (822s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-plflip-blt)
[202.608476] Starting subtest: psr-1p-primscrn-indfb-plflip-blt
[209.992875] Subtest psr-1p-primscrn-indfb-plflip-blt: SUCCESS (7.387s)
[210.125305] [054/131] (814s left) gem_ctx_bad_destroy (invalid-ctx)
[210.183837] Starting subtest: invalid-ctx
[210.186065] Subtest invalid-ctx: SUCCESS (0.000s)
[210.253846] [055/131] (814s left) gem_linear_blits (basic)
[210.602291] Starting subtest: basic
[210.612778] Subtest basic: SUCCESS (0.011s)
[210.765671] [056/131] (814s left) gem_exec_reloc (basic-gtt-wc-noreloc)
[210.882885] Subtest basic-gtt-wc-noreloc: SKIP (0.000s)
[210.979038] [057/131] (813s left) kms_psr2_sf (fbc-pr-overlay-primary-update-sf-dmg-area)
[215.971397] Starting subtest: fbc-pr-overlay-primary-update-sf-dmg-area
[215.976103] Subtest fbc-pr-overlay-primary-update-sf-dmg-area: SKIP (0.002s)
[216.831286] [058/131] (808s left) kms_writeback (writeback-check-output-xrgb2101010)
[216.932091] Subtest writeback-check-output-XRGB2101010: SKIP (0.000s)
[216.975574] [059/131] (807s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[217.079583] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[217.858076] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SUCCESS (0.781s)
[218.029905] [060/131] (806s left) gem_ctx_param (non-root-set)
[218.098548] Starting subtest: non-root-set
[218.120071] Subtest non-root-set: SUCCESS (0.024s)
[218.207371] [061/131] (806s left) kms_psr (fbc-psr-primary-render)
[218.310101] Starting subtest: fbc-psr-primary-render
[218.312305] Starting dynamic subtest: eDP-1
[218.316147] Dynamic subtest eDP-1: SKIP (0.000s)
[218.320160] Subtest fbc-psr-primary-render: SKIP (0.002s)
[218.390589] [062/131] (806s left) syncobj_timeline (multi-wait-available-unsubmitted-submitted)
[218.443732] Starting subtest: multi-wait-available-unsubmitted-submitted
[218.446094] Subtest multi-wait-available-unsubmitted-submitted: SUCCESS (0.000s)
[218.533952] [063/131] (806s left) kms_flip (nonexisting-fb)
[218.644200] Starting subtest: nonexisting-fb
[218.646523] Starting dynamic subtest: A-eDP1
[219.823592] Dynamic subtest A-eDP1: SUCCESS (1.179s)
[219.831463] Starting dynamic subtest: B-eDP1
[220.957357] Dynamic subtest B-eDP1: SUCCESS (1.132s)
[220.966917] Starting dynamic subtest: C-eDP1
[222.076594] Dynamic subtest C-eDP1: SUCCESS (1.119s)
[222.085651] Starting dynamic subtest: D-eDP1
[223.209339] Dynamic subtest D-eDP1: SUCCESS (1.130s)
[223.211781] Subtest nonexisting-fb: SUCCESS (4.569s)
[224.380122] [064/131] (800s left) gem_spin_batch (resubmit-new-all)
[224.444206] Starting subtest: resubmit-new-all
[224.446471] Starting dynamic subtest: rcs0
[224.458166] Dynamic subtest rcs0: SUCCESS (0.014s)
[224.467208] Starting dynamic subtest: bcs0
[224.469302] Dynamic subtest bcs0: SUCCESS (0.010s)
[224.478291] Starting dynamic subtest: vcs0
[224.480483] Dynamic subtest vcs0: SUCCESS (0.008s)
[224.488193] Starting dynamic subtest: vcs1
[224.490425] Dynamic subtest vcs1: SUCCESS (0.012s)
[224.498577] Starting dynamic subtest: vecs0
[224.500801] Dynamic subtest vecs0: SUCCESS (0.009s)
[224.509815] Starting dynamic subtest: ccs0
[224.512581] Dynamic subtest ccs0: SUCCESS (0.009s)
[224.515044] Subtest resubmit-new-all: SUCCESS (0.065s)
[225.913751] [065/131] (799s left) syncobj_timeline (etime-single-wait-all-for-submit-submitted)
[225.962945] Starting subtest: etime-single-wait-all-for-submit-submitted
[226.073688] Subtest etime-single-wait-all-for-submit-submitted: SUCCESS (0.108s)
[226.146604] [066/131] (799s left) gem_exec_whisper (basic-fds-forked)
[226.204840] Starting subtest: basic-fds-forked
[251.106833] Subtest basic-fds-forked: SUCCESS (24.845s)
[251.235391] [067/131] (773s left) drm_mm (drm_mm)
[251.276180] Starting subtest: drm_mm
[251.308990] Starting dynamic subtest: drm_test_mm_init
[251.461049] Dynamic subtest drm_test_mm_init: SUCCESS (0.154s)
[251.470204] Starting dynamic subtest: drm_test_mm_debug
[251.605908] Dynamic subtest drm_test_mm_debug: SUCCESS (0.143s)
[251.615588] Starting dynamic subtest: drm_test_mm_align32
[251.765480] Dynamic subtest drm_test_mm_align32: SUCCESS (0.160s)
[251.773136] Starting dynamic subtest: drm_test_mm_align64
[251.908061] Dynamic subtest drm_test_mm_align64: SUCCESS (0.141s)
[251.917069] Starting dynamic subtest: drm_test_mm_lowest
[252.032726] Dynamic subtest drm_test_mm_lowest: SUCCESS (0.123s)
[252.041783] Starting dynamic subtest: drm_test_mm_highest
[252.157569] Dynamic subtest drm_test_mm_highest: SUCCESS (0.125s)
[252.159713] Subtest drm_mm: SUCCESS (0.882s)
[252.354329] [068/131] (772s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-render)
[252.459783] Starting subtest: psr-2p-primscrn-cur-indfb-draw-render
[252.464128] Subtest psr-2p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[252.571213] [069/131] (772s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-wc)
[252.669359] Starting subtest: fbcpsr-rgb565-draw-mmap-wc
[259.404531] Subtest fbcpsr-rgb565-draw-mmap-wc: SUCCESS (6.737s)
[259.511877] [070/131] (765s left) kms_psr2_su (page_flip-p010)
[259.567781] Subtest page_flip-P010: SKIP (0.000s)
[259.628254] [071/131] (765s left) kms_frontbuffer_tracking (fbc-rgb565-draw-blt)
[259.731591] Starting subtest: fbc-rgb565-draw-blt
[263.919126] Subtest fbc-rgb565-draw-blt: SUCCESS (4.191s)
[265.111049] [072/131] (760s left) syncobj_timeline (single-wait-available-signaled)
[265.162839] Starting subtest: single-wait-available-signaled
[265.165214] Subtest single-wait-available-signaled: SUCCESS (0.000s)
[265.285041] [073/131] (760s left) perf (missing-sample-flags)
[265.418626] Starting subtest: missing-sample-flags
[265.420737] Subtest missing-sample-flags: SUCCESS (0.000s)
[265.460009] [074/131] (760s left) kms_rotation_crc (primary-y-tiled-reflect-x-270)
[265.552268] Starting subtest: primary-y-tiled-reflect-x-270
[265.556735] Subtest primary-y-tiled-reflect-x-270: SKIP (0.002s)
[265.625184] [075/131] (759s left) gem_lmem_swapping (random-engines)
[265.671112] Subtest random-engines: SKIP (0.000s)
[265.711642] [076/131] (759s left) i915_query (test-query-geometry-subslices)
[265.766783] Starting subtest: test-query-geometry-subslices
[265.769056] Subtest test-query-geometry-subslices: SUCCESS (0.000s)
[265.875684] [077/131] (759s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[265.966667] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[265.970821] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[266.056676] [078/131] (759s left) kms_ccs (crc-primary-suspend-4-tiled-mtl-rc-ccs)
[266.149778] Starting subtest: crc-primary-suspend-4-tiled-mtl-rc-ccs
[266.151865] Starting dynamic subtest: pipe-A-eDP-1
[283.193436] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.783s)
[283.203591] Starting dynamic subtest: pipe-B-eDP-1
[300.840529] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.357s)
[300.850711] Starting dynamic subtest: pipe-C-eDP-1
[317.798887] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.325s)
[317.807358] Starting dynamic subtest: pipe-D-eDP-1
[334.790634] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.248s)
[334.792833] Subtest crc-primary-suspend-4-tiled-mtl-rc-ccs: SUCCESS (8.716s)
[335.986119] [079/131] (689s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-mc-ccs)
[336.085535] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-mc-ccs
[336.087860] Starting dynamic subtest: pipe-A-eDP-1
[336.110851] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[336.120335] Starting dynamic subtest: pipe-B-eDP-1
[336.141757] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[336.149825] Starting dynamic subtest: pipe-C-eDP-1
[336.171139] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[336.178931] Starting dynamic subtest: pipe-D-eDP-1
[336.200345] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[336.204404] Subtest crc-primary-rotation-180-4-tiled-dg2-mc-ccs: SKIP (0.005s)
[338.308660] [080/131] (687s left) syncobj_wait (multi-wait-all-submitted)
[338.362388] Starting subtest: multi-wait-all-submitted
[338.467676] Subtest multi-wait-all-submitted: SUCCESS (0.106s)
[338.540449] [081/131] (687s left) kms_content_protection (legacy)
[338.691628] Starting subtest: legacy
[342.498812] Subtest legacy: SKIP (3.834s)
[343.697651] [082/131] (682s left) kms_atomic_transition (plane-use-after-nonblocking-unbind)
[343.796506] Starting subtest: plane-use-after-nonblocking-unbind
[343.798833] Starting dynamic subtest: pipe-A-eDP-1
[343.868601] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.074s)
[344.173763] Starting dynamic subtest: pipe-B-eDP-1
[344.999811] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.840s)
[345.288781] Subtest plane-use-after-nonblocking-unbind: SUCCESS (1.498s)
[346.149456] [083/131] (679s left) gem_create (create-ext-cpu-access-big)
[346.325998] Starting subtest: create-ext-cpu-access-big
[346.331055] Subtest create-ext-cpu-access-big: SKIP (0.001s)
[346.398069] [084/131] (679s left) gem_exec_balancer (busy)
[346.473326] Starting subtest: busy
[347.912355] Subtest busy: SUCCESS (1.442s)
[348.242683] [085/131] (677s left) kms_psr (fbc-psr2-sprite-render)
[348.340630] Starting subtest: fbc-psr2-sprite-render
[348.342987] Starting dynamic subtest: eDP-1
[348.347497] Dynamic subtest eDP-1: SKIP (0.000s)
[348.351955] Subtest fbc-psr2-sprite-render: SKIP (0.001s)
[348.413298] [086/131] (677s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-onoff)
[348.528542] Starting subtest: psr-2p-scndscrn-cur-indfb-onoff
[348.532553] Subtest psr-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[348.650124] [087/131] (677s left) kms_flip (flip-vs-expired-vblank)
[348.752493] Starting subtest: flip-vs-expired-vblank
[348.754826] Starting dynamic subtest: A-eDP1
[357.488891] Dynamic subtest A-eDP1: SUCCESS (8.716s)
[357.499761] Starting dynamic subtest: B-eDP1
[366.194434] Dynamic subtest B-eDP1: SUCCESS (8.713s)
[366.204862] Starting dynamic subtest: C-eDP1
[374.917371] Dynamic subtest C-eDP1: SUCCESS (8.702s)
[374.931354] Starting dynamic subtest: D-eDP1
[383.546106] Dynamic subtest D-eDP1: SUCCESS (8.626s)
[383.548833] Subtest flip-vs-expired-vblank: SUCCESS (34.766s)
[384.666018] [088/131] (641s left) gem_userptr_blits (readonly-pwrite-unsync)
[384.730862] Subtest readonly-pwrite-unsync: SKIP (0.000s)
[384.842931] [089/131] (641s left) gem_pwrite (basic-self)
[384.897358] Subtest basic-self: SKIP (0.000s)
[384.959082] [090/131] (641s left) kms_lease (lease-again)
[385.065342] Starting subtest: lease-again
[385.067547] Starting dynamic subtest: pipe-A-eDP-1
[385.069808] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.003s)
[385.079625] Starting dynamic subtest: pipe-B-eDP-1
[385.081511] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.002s)
[385.089801] Starting dynamic subtest: pipe-C-eDP-1
[385.091602] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.002s)
[385.100435] Starting dynamic subtest: pipe-D-eDP-1
[385.102447] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.002s)
[385.104408] Subtest lease-again: SUCCESS (0.013s)
[385.187312] [091/131] (640s left) syncobj_wait (invalid-wait-bad-flags)
[385.232357] Starting subtest: invalid-wait-bad-flags
[385.234349] Subtest invalid-wait-bad-flags: SUCCESS (0.000s)
[385.336034] [092/131] (640s left) gem_exec_params (invalid-bsd2-flag-on-render)
[385.392626] Starting subtest: invalid-bsd2-flag-on-render
[385.395029] Subtest invalid-bsd2-flag-on-render: SUCCESS (0.000s)
[385.503451] [093/131] (640s left) gem_partial_pwrite_pread (write-snoop)
[385.570877] Subtest write-snoop: SKIP (0.000s)
[385.659319] [094/131] (640s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[385.749480] Starting subtest: nonblocking-crc-frame-sequence
[385.751621] Starting dynamic subtest: pipe-A-eDP-1
[386.488142] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.735s)
[386.497187] Starting dynamic subtest: pipe-B-eDP-1
[387.861536] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.372s)
[387.871217] Starting dynamic subtest: pipe-C-eDP-1
[389.264318] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.403s)
[389.274475] Starting dynamic subtest: pipe-D-eDP-1
[390.590005] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.325s)
[390.592238] Subtest nonblocking-crc-frame-sequence: SUCCESS (4.840s)
[391.399112] [095/131] (634s left) kms_pm_dc (dc5-retention-flops)
[392.499102] Starting subtest: dc5-retention-flops
[392.503501] Subtest dc5-retention-flops: SKIP (0.000s)
[392.566832] [096/131] (633s left) kms_chamelium_frames (dp-crc-multiple)
[392.948512] Subtest dp-crc-multiple: SKIP (0.000s)
[393.817821] [097/131] (632s left) kms_flip (flip-vs-rmfb)
[393.915622] Starting subtest: flip-vs-rmfb
[393.917968] Starting dynamic subtest: A-eDP1
[402.633017] Dynamic subtest A-eDP1: SUCCESS (8.719s)
[402.641493] Starting dynamic subtest: B-eDP1
[411.259854] Dynamic subtest B-eDP1: SUCCESS (8.628s)
[411.271243] Starting dynamic subtest: C-eDP1
[419.911079] Dynamic subtest C-eDP1: SUCCESS (8.651s)
[419.920186] Starting dynamic subtest: D-eDP1
[428.561333] Dynamic subtest D-eDP1: SUCCESS (8.652s)
[428.563264] Subtest flip-vs-rmfb: SUCCESS (34.659s)
[429.798995] [098/131] (596s left) kms_panel_fitting (legacy)
[429.905191] Starting subtest: legacy
[429.907624] Starting dynamic subtest: pipe-A-eDP-1
[430.437938] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.532s)
[430.465036] Starting dynamic subtest: pipe-B-eDP-1
[431.997117] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.538s)
[432.025560] Starting dynamic subtest: pipe-C-eDP-1
[433.562350] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.542s)
[433.587901] Starting dynamic subtest: pipe-D-eDP-1
[435.098148] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.515s)
[435.100462] Subtest legacy: SUCCESS (5.190s)
[436.338750] [099/131] (590s left) kms_color (legacy-gamma)
[436.423450] Starting subtest: legacy-gamma
[436.425799] Starting dynamic subtest: pipe-A-eDP-1
[437.113080] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.688s)
[437.123182] Starting dynamic subtest: pipe-B-eDP-1
[438.526853] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.411s)
[438.535359] Starting dynamic subtest: pipe-C-eDP-1
[439.823061] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.290s)
[439.833421] Starting dynamic subtest: pipe-D-eDP-1
[441.146185] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.325s)
[441.148955] Subtest legacy-gamma: SUCCESS (4.718s)
[441.974196] [100/131] (584s left) kms_invalid_mode (bad-hsync-end)
[442.081745] Starting subtest: bad-hsync-end
[442.084084] Starting dynamic subtest: pipe-A-eDP-1
[442.366777] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.286s)
[442.375570] Starting dynamic subtest: pipe-B-eDP-1
[442.377502] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[442.385430] Starting dynamic subtest: pipe-C-eDP-1
[442.387436] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[442.395700] Starting dynamic subtest: pipe-D-eDP-1
[442.398007] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[442.400309] Subtest bad-hsync-end: SUCCESS (0.291s)
[443.202527] [101/131] (583s left) kms_scaling_modes (scaling-mode-center)
[443.298733] Starting subtest: scaling-mode-center
[443.301120] Starting dynamic subtest: pipe-A-eDP-1
[443.392987] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.097s)
[443.401886] Starting dynamic subtest: pipe-B-eDP-1
[444.525598] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.131s)
[444.534225] Starting dynamic subtest: pipe-C-eDP-1
[445.628797] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.102s)
[445.640320] Starting dynamic subtest: pipe-D-eDP-1
[446.768642] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.137s)
[446.771008] Subtest scaling-mode-center: SUCCESS (3.472s)
[447.936090] [102/131] (578s left) kms_cursor_legacy (cursorb-vs-flipa-atomic-transitions)
[448.061911] Starting subtest: cursorB-vs-flipA-atomic-transitions
[448.066497] Subtest cursorB-vs-flipA-atomic-transitions: SKIP (0.001s)
[448.205015] [103/131] (578s left) kms_ccs (missing-ccs-buffer-y-tiled-ccs)
[448.295396] Starting subtest: missing-ccs-buffer-y-tiled-ccs
[448.297636] Starting dynamic subtest: pipe-A-eDP-1
[448.320185] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[448.330541] Starting dynamic subtest: pipe-B-eDP-1
[448.352052] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[448.361031] Starting dynamic subtest: pipe-C-eDP-1
[448.383361] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[448.393425] Starting dynamic subtest: pipe-D-eDP-1
[448.415462] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[448.419481] Subtest missing-ccs-buffer-y-tiled-ccs: SKIP (0.004s)
[450.516301] [104/131] (576s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-blt)
[450.609583] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-blt
[450.613924] Subtest fbc-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[450.718410] [105/131] (575s left) gem_exec_schedule (preempt-queue-contexts)
[450.790675] Subtest preempt-queue-contexts: SKIP (0.000s)
[451.079203] [106/131] (575s left) kms_getfb (getfb2-handle-protection)
[451.169502] Starting subtest: getfb2-handle-protection
[451.190698] Subtest getfb2-handle-protection: SUCCESS (0.023s)
[451.327191] [107/131] (575s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[451.414034] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu
[451.418498] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[451.534706] [108/131] (575s left) gem_exec_reloc (basic-wc-read)
[451.600307] Subtest basic-wc-read: SKIP (0.000s)
[451.689438] [109/131] (575s left) kms_psr2_sf (pr-primary-plane-update-sf-dmg-area-big-fb)
[456.701905] Starting subtest: pr-primary-plane-update-sf-dmg-area-big-fb
[456.706823] Subtest pr-primary-plane-update-sf-dmg-area-big-fb: SKIP (0.002s)
[457.539462] [110/131] (569s left) kms_flip (flip-vs-absolute-wf_vblank-interruptible)
[457.645061] Starting subtest: flip-vs-absolute-wf_vblank-interruptible
[457.647400] Starting dynamic subtest: A-eDP1
[466.579397] Dynamic subtest A-eDP1: SUCCESS (8.934s)
[466.590361] Starting dynamic subtest: B-eDP1
[475.517083] Dynamic subtest B-eDP1: SUCCESS (8.935s)
[475.527464] Starting dynamic subtest: C-eDP1
[484.487577] Dynamic subtest C-eDP1: SUCCESS (8.924s)
[484.499769] Starting dynamic subtest: D-eDP1
[493.416530] Dynamic subtest D-eDP1: SUCCESS (8.927s)
[493.418602] Subtest flip-vs-absolute-wf_vblank-interruptible: SUCCESS (35.731s)
[494.595591] [111/131] (532s left) i915_pm_rc6_residency (media-rc6-accuracy)
[494.653449] Starting subtest: media-rc6-accuracy
[494.657961] Subtest media-rc6-accuracy: SKIP (0.000s)
[494.761532] [112/131] (532s left) kms_plane_scaling (plane-upscale-factor-0-25-with-modifiers)
[494.856012] Starting subtest: plane-upscale-factor-0-25-with-modifiers
[494.858593] Starting dynamic subtest: pipe-A
[495.193635] Dynamic subtest pipe-A: SUCCESS (0.338s)
[495.204702] Starting dynamic subtest: pipe-B
[496.571403] Dynamic subtest pipe-B: SUCCESS (1.377s)
[496.580894] Starting dynamic subtest: pipe-C
[498.005163] Dynamic subtest pipe-C: SUCCESS (1.434s)
[498.013961] Starting dynamic subtest: pipe-D
[499.437462] Dynamic subtest pipe-D: SUCCESS (1.431s)
[499.439745] Subtest plane-upscale-factor-0-25-with-modifiers: SUCCESS (4.583s)
[500.612179] [113/131] (526s left) gem_userptr_blits (unsync-unmap-after-close)
[500.681810] Subtest unsync-unmap-after-close: SKIP (0.000s)
[500.811653] [114/131] (526s left) gen9_exec_parse (unaligned-access)
[500.863851] Subtest unaligned-access: SKIP (0.000s)
[500.930550] [115/131] (526s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[501.018638] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[501.797771] Subtest x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SUCCESS (0.781s)
[501.967410] [116/131] (525s left) kms_async_flips (crc)
[502.070264] Starting subtest: crc
[502.443456] Starting dynamic subtest: pipe-A-eDP-1
[505.344310] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.870s)
[505.690858] Starting dynamic subtest: pipe-B-eDP-1
[508.592953] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.896s)
[508.964715] Starting dynamic subtest: pipe-C-eDP-1
[511.911803] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.921s)
[512.235267] Starting dynamic subtest: pipe-D-eDP-1
[515.128898] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.875s)
[515.131143] Subtest crc: SUCCESS (13.030s)
[516.262096] [117/131] (510s left) perf (enable-disable)
[516.339444] Starting subtest: enable-disable
[516.341888] Starting dynamic subtest: 0-rcs0
[518.633924] Dynamic subtest 0-rcs0: SUCCESS (2.240s)
[518.645119] Starting dynamic subtest: 1-vcs0
[523.045873] Dynamic subtest 1-vcs0: SUCCESS (4.465s)
[523.048526] Subtest enable-disable: SUCCESS (6.706s)
[523.089228] [118/131] (504s left) perf (non-system-wide-paranoid)
[523.164722] Starting subtest: non-system-wide-paranoid
[523.190341] Subtest non-system-wide-paranoid: SUCCESS (0.026s)
[523.232946] [119/131] (503s left) kms_sequence (get-idle)
[523.327427] Starting subtest: get-idle
[523.329719] Starting dynamic subtest: pipe-A-eDP-1
[525.133456] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.805s)
[525.147543] Starting dynamic subtest: pipe-B-eDP-1
[527.628789] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.495s)
[527.638964] Starting dynamic subtest: pipe-C-eDP-1
[530.060115] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.429s)
[530.070381] Starting dynamic subtest: pipe-D-eDP-1
[532.489954] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.430s)
[532.492160] Subtest get-idle: SUCCESS (9.164s)
[533.296057] [120/131] (493s left) kms_frontbuffer_tracking (fbc-indfb-scaledprimary)
[533.400125] Starting subtest: fbc-indfb-scaledprimary
[538.480836] Subtest fbc-indfb-scaledprimary: SUCCESS (5.086s)
[539.596460] [121/131] (487s left) kms_frontbuffer_tracking (fbc-1p-shrfb-fliptrack-mmap-gtt)
[539.709673] Starting subtest: fbc-1p-shrfb-fliptrack-mmap-gtt
[539.714020] Subtest fbc-1p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[539.813605] [122/131] (487s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-fullscreen)
[539.914904] Starting subtest: fbc-2p-primscrn-spr-indfb-fullscreen
[539.919584] Subtest fbc-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[540.006191] [123/131] (487s left) kms_pm_rpm (pm-tiling)
[542.043367] Starting subtest: pm-tiling
[542.048839] Subtest pm-tiling: SKIP (0.000s)
[542.411823] [124/131] (484s left) gem_exec_params (invalid-flag)
[542.477401] Starting subtest: invalid-flag
[542.479890] Subtest invalid-flag: SUCCESS (0.000s)
[542.582254] [125/131] (484s left) gem_exec_create (forked)
[542.637732] Starting subtest: forked
[542.640103] Starting dynamic subtest: smem
[563.895497] Dynamic subtest smem: SUCCESS (21.257s)
[563.898519] Subtest forked: SUCCESS (21.258s)
[564.077342] [126/131] (463s left) gem_softpin (allocator-basic-reserve)
[564.152847] Starting subtest: allocator-basic-reserve
[564.221827] Subtest allocator-basic-reserve: SUCCESS (0.069s)
[564.355399] [127/131] (463s left) gem_exec_balancer (invalid-bonds)
[564.419076] Starting subtest: invalid-bonds
[564.423630] Subtest invalid-bonds: SKIP (0.001s)
[564.734136] [128/131] (462s left) gem_exec_schedule (pi-common)
[564.917879] Starting subtest: pi-common
[564.920515] Starting dynamic subtest: rcs0
[565.002025] Dynamic subtest rcs0: SUCCESS (0.088s)
[565.015383] Starting dynamic subtest: bcs0
[565.094658] Dynamic subtest bcs0: SUCCESS (0.092s)
[565.107104] Starting dynamic subtest: vcs0
[565.180565] Dynamic subtest vcs0: SUCCESS (0.084s)
[565.195854] Starting dynamic subtest: vcs1
[565.259201] Dynamic subtest vcs1: SUCCESS (0.078s)
[565.272980] Starting dynamic subtest: vecs0
[565.336710] Dynamic subtest vecs0: SUCCESS (0.077s)
[565.346970] Starting dynamic subtest: ccs0
[565.416806] Dynamic subtest ccs0: SUCCESS (0.079s)
[565.419905] Subtest pi-common: SUCCESS (0.504s)
[565.666405] [129/131] (461s left) gem_exec_reloc (basic-cpu-read)
[565.726977] Subtest basic-cpu-read: SKIP (0.000s)
[565.792992] [130/131] (461s left) i915_drm_fdinfo (virtual-busy-idle)
[565.834207] Starting subtest: virtual-busy-idle
[565.838584] Subtest virtual-busy-idle: SKIP (0.000s)
[565.891021] [131/131] (461s left) kms_psr (pr-sprite-render)
[565.985516] Starting subtest: pr-sprite-render
[565.989914] Subtest pr-sprite-render: SKIP (0.000s)
[566.049202] Closing watchdogs
results: parsing output: 0/ for test: perf_pmu
results: parsing output: 1/ for test: syncobj_wait
results: parsing output: 2/ for test: gem_wait
results: parsing output: 3/ for test: kms_plane_lowres
results: parsing output: 4/ for test: kms_frontbuffer_tracking
results: parsing output: 5/ for test: gem_exec_fence
results: parsing output: 6/ for test: syncobj_timeline
results: parsing output: 7/ for test: gem_ctx_param
results: parsing output: 8/ for test: syncobj_wait
results: parsing output: 9/ for test: kms_dsc
results: parsing output: 10/ for test: gem_render_copy
results: parsing output: 11/ for test: gem_exec_schedule
results: parsing output: 12/ for test: kms_psr
results: parsing output: 13/ for test: api_intel_bb
results: parsing output: 14/ for test: gem_create
results: parsing output: 15/ for test: kms_big_fb
results: parsing output: 16/ for test: kms_ccs
results: parsing output: 17/ for test: kms_big_fb
results: parsing output: 18/ for test: kms_atomic
results: parsing output: 19/ for test: gem_mmap_offset
results: parsing output: 20/ for test: perf_pmu
results: parsing output: 21/ for test: kms_frontbuffer_tracking
results: parsing output: 22/ for test: gem_exec_params
results: parsing output: 23/ for test: api_intel_bb
results: parsing output: 24/ for test: gem_userptr_blits
results: parsing output: 25/ for test: syncobj_timeline
results: parsing output: 26/ for test: gem_ctx_shared
results: parsing output: 27/ for test: kms_frontbuffer_tracking
results: parsing output: 28/ for test: gem_userptr_blits
results: parsing output: 29/ for test: gem_mmap_gtt
results: parsing output: 30/ for test: sw_sync
results: parsing output: 31/ for test: gem_mmap_wc
results: parsing output: 32/ for test: kms_cursor_edge_walk
results: parsing output: 33/ for test: prime_vgem
results: parsing output: 34/ for test: gem_tiled_wc
results: parsing output: 35/ for test: kms_frontbuffer_tracking
results: parsing output: 36/ for test: gem_exec_fence
results: parsing output: 37/ for test: kms_flip_scaled_crc
results: parsing output: 38/ for test: gem_mmap_gtt
results: parsing output: 39/ for test: kms_plane_scaling
results: parsing output: 40/ for test: kms_chamelium_hpd
results: parsing output: 41/ for test: kms_cursor_crc
results: parsing output: 42/ for test: sw_sync
results: parsing output: 43/ for test: kms_frontbuffer_tracking
results: parsing output: 44/ for test: kms_cursor_crc
results: parsing output: 45/ for test: kms_big_fb
results: parsing output: 46/ for test: kms_ccs
results: parsing output: 47/ for test: i915_pm_sseu
results: parsing output: 48/ for test: kms_addfb_basic
results: parsing output: 49/ for test: sysfs_defaults
results: parsing output: 50/ for test: kms_big_fb
results: parsing output: 51/ for test: kms_feature_discovery
results: parsing output: 52/ for test: kms_frontbuffer_tracking
results: parsing output: 53/ for test: gem_ctx_bad_destroy
results: parsing output: 54/ for test: gem_linear_blits
results: parsing output: 55/ for test: gem_exec_reloc
results: parsing output: 56/ for test: kms_psr2_sf
results: parsing output: 57/ for test: kms_writeback
results: parsing output: 58/ for test: kms_big_fb
results: parsing output: 59/ for test: gem_ctx_param
results: parsing output: 60/ for test: kms_psr
results: parsing output: 61/ for test: syncobj_timeline
results: parsing output: 62/ for test: kms_flip
results: parsing output: 63/ for test: gem_spin_batch
results: parsing output: 64/ for test: syncobj_timeline
results: parsing output: 65/ for test: gem_exec_whisper
results: parsing output: 66/ for test: drm_mm
results: parsing output: 67/ for test: kms_frontbuffer_tracking
results: parsing output: 68/ for test: kms_frontbuffer_tracking
results: parsing output: 69/ for test: kms_psr2_su
results: parsing output: 70/ for test: kms_frontbuffer_tracking
results: parsing output: 71/ for test: syncobj_timeline
results: parsing output: 72/ for test: perf
results: parsing output: 73/ for test: kms_rotation_crc
results: parsing output: 74/ for test: gem_lmem_swapping
results: parsing output: 75/ for test: i915_query
results: parsing output: 76/ for test: kms_frontbuffer_tracking
results: parsing output: 77/ for test: kms_ccs
results: parsing output: 78/ for test: kms_ccs
results: parsing output: 79/ for test: syncobj_wait
results: parsing output: 80/ for test: kms_content_protection
results: parsing output: 81/ for test: kms_atomic_transition
results: parsing output: 82/ for test: gem_create
results: parsing output: 83/ for test: gem_exec_balancer
results: parsing output: 84/ for test: kms_psr
results: parsing output: 85/ for test: kms_frontbuffer_tracking
results: parsing output: 86/ for test: kms_flip
results: parsing output: 87/ for test: gem_userptr_blits
results: parsing output: 88/ for test: gem_pwrite
results: parsing output: 89/ for test: kms_lease
results: parsing output: 90/ for test: syncobj_wait
results: parsing output: 91/ for test: gem_exec_params
results: parsing output: 92/ for test: gem_partial_pwrite_pread
results: parsing output: 93/ for test: kms_pipe_crc_basic
results: parsing output: 94/ for test: kms_pm_dc
results: parsing output: 95/ for test: kms_chamelium_frames
results: parsing output: 96/ for test: kms_flip
results: parsing output: 97/ for test: kms_panel_fitting
results: parsing output: 98/ for test: kms_color
results: parsing output: 99/ for test: kms_invalid_mode
results: parsing output: 100/ for test: kms_scaling_modes
results: parsing output: 101/ for test: kms_cursor_legacy
results: parsing output: 102/ for test: kms_ccs
results: parsing output: 103/ for test: kms_frontbuffer_tracking
results: parsing output: 104/ for test: gem_exec_schedule
results: parsing output: 105/ for test: kms_getfb
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: gem_exec_reloc
results: parsing output: 108/ for test: kms_psr2_sf
results: parsing output: 109/ for test: kms_flip
results: parsing output: 110/ for test: i915_pm_rc6_residency
results: parsing output: 111/ for test: kms_plane_scaling
results: parsing output: 112/ for test: gem_userptr_blits
results: parsing output: 113/ for test: gen9_exec_parse
results: parsing output: 114/ for test: kms_big_fb
results: parsing output: 115/ for test: kms_async_flips
results: parsing output: 116/ for test: perf
results: parsing output: 117/ for test: perf
results: parsing output: 118/ for test: kms_sequence
results: parsing output: 119/ for test: kms_frontbuffer_tracking
results: parsing output: 120/ for test: kms_frontbuffer_tracking
results: parsing output: 121/ for test: kms_frontbuffer_tracking
results: parsing output: 122/ for test: kms_pm_rpm
results: parsing output: 123/ for test: gem_exec_params
results: parsing output: 124/ for test: gem_exec_create
results: parsing output: 125/ for test: gem_softpin
results: parsing output: 126/ for test: gem_exec_balancer
results: parsing output: 127/ for test: gem_exec_schedule
results: parsing output: 128/ for test: gem_exec_reloc
results: parsing output: 129/ for test: i915_drm_fdinfo
results: parsing output: 130/ for test: kms_psr
Done.